hostname% hostname% asadmin start-domain --upgrade domain1 Aug 28, 2009 11:47:56 AM com.sun.enterprise.universal.xml.MiniXmlParser read WARNING: XML file was parsed twice because the configs element appears before the servers element. To prevent this waste of time, move the servers element before the configs element. Aug 28, 2009 11:47:56 AM com.sun.common.util.logging.LoggingConfigImpl openPropFile WARNING: /Users/bobby/servers/glassfishv3/glassfish/domains/domain1/config/logging.properties not found, will create one with properties given. Waiting for DAS to start. Error starting domain: domain1. The server exited prematurely with exit code 0. Before it died, it produced the following output: Welcome to Felix. ================= Aug 28, 2009 11:47:57 AM com.sun.enterprise.glassfish.bootstrap.ASMain main INFO: Launching GlassFish on Felix platform Aug 28, 2009 11:48:03 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.common.glassfish-mbeanserver [154] Aug 28, 2009 11:48:03 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.core.kernel [55] Aug 28, 2009 11:48:03 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.common.util [67] Aug 28, 2009 11:48:03 AM OSGiModuleImpl start INFO: Started bundle com.sun.enterprise.config [170] Aug 28, 2009 11:48:04 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.connectors.internal-api [50] Aug 28, 2009 11:48:04 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.common.internal-api [199] Aug 28, 2009 11:48:04 AM org.glassfish.config.support.DomainXml$DomainXmlReader parse2ndTime INFO: Forced to parse file:/Users/bobby/servers/glassfishv3/glassfish/domains/domain1/config/domain.xml twice because we didn't see before Aug 28, 2009 11:48:04 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.transaction.jta [66] Aug 28, 2009 11:48:04 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.admin.config-api [95] Aug 28, 2009 11:48:04 AM org.hibernate.validation.util.Version INFO: Hibernate Validator null Aug 28, 2009 11:48:04 AM org.hibernate.validation.engine.resolver.DefaultTraversableResolver detectJPA INFO: Instantiated an instance of org.hibernate.validation.engine.resolver.JPATraversableResolver. Aug 28, 2009 11:48:04 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.connectors.admin [181] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle com.sun.grizzly.grizzly-config [138] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.common.glassfish-api [81] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.jdbc.admin [184] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.admin.server-mgmt [192] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.ejb.ejb-container [44] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.admin.monitoring-core [157] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.web.cli [97] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.branding [183] Aug 28, 2009 11:48:05 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.deployment.common [51] Aug 28, 2009 11:48:06 AM com.sun.enterprise.v3.admin.IdmService setFromStdin INFO: Reading the master password from stdin> Aug 28, 2009 11:48:06 AM INFO: com.sun.enterprise.naming.util.NamingActivator@7c20db7f called Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.common.glassfish-naming [89] Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.common.container-common [18] Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.flashlight.framework [179] Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.registration.glassfish-registration [168] Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.orb.connector [143] Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.jms.core [16] Aug 28, 2009 11:48:06 AM org.glassfish.admin.mbeanserver.JMXStartupService$JMXConnectorsStarterThread run INFO: JMXStartupService: JMX authenticator is com.sun.enterprise.container.common.GenericAdminAuthenticator@53fa3fe4 Aug 28, 2009 11:48:06 AM WebExtender run INFO: Waiting for Server to start Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.web.osgi-web-container [161] Aug 28, 2009 11:48:06 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.admin.rest-service [188] Aug 28, 2009 11:48:07 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.deployment.autodeploy [136] Aug 28, 2009 11:48:07 AM org.glassfish.admin.mbeanserver.JMXStartupService$JMXConnectorsStarterThread startConnector INFO: JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://dhcp-ubur02-71-236.East.Sun.COM:8686/jndi/rmi://dhcp-ubur02-71-236.East.Sun.COM:8686/jmxrmi Aug 28, 2009 11:48:07 AM OSGiModuleImpl start INFO: Started bundle org.glassfish.transaction.jts [74] Aug 28, 2009 11:48:07 AM com.sun.enterprise.v3.services.impl.GrizzlyProxy start INFO: Listening on port 8080 Aug 28, 2009 11:48:07 AM com.sun.grizzly.config.GrizzlyEmbeddedHttps configureProtocol INFO: Perform lazy SSL initialization for the listener 'http-listener-2' Aug 28, 2009 11:48:07 AM com.sun.enterprise.v3.services.impl.GrizzlyProxy start INFO: Listening on port 8181 Aug 28, 2009 11:48:07 AM com.sun.grizzly.Controller start INFO: Starting Grizzly Framework 1.9.18-M2 - Fri Aug 28 11:48:07 EDT 2009 Aug 28, 2009 11:48:07 AM com.sun.grizzly.Controller start INFO: Starting Grizzly Framework 1.9.18-M2 - Fri Aug 28 11:48:07 EDT 2009 Aug 28, 2009 11:48:07 AM com.sun.enterprise.v3.services.impl.GrizzlyProxy start INFO: Listening on port 4848 Aug 28, 2009 11:48:07 AM com.sun.grizzly.Controller start INFO: Starting Grizzly Framework 1.9.18-M2 - Fri Aug 28 11:48:07 EDT 2009 Aug 28, 2009 11:48:08 AM com.sun.enterprise.v3.server.AppServerStartup run INFO: GlassFish v3 startup time : Felix(8805ms) startup services(2896ms) total(11701ms) Aug 28, 2009 11:48:08 AM WebExtender event INFO: Received Server Started Event Aug 28, 2009 11:48:08 AM AppServerStartup run INFO: [Thread[GlassFish Kernel Main Thread,5,main]] started Aug 28, 2009 11:48:08 AM com.sun.enterprise.v3.server.UpgradeStartup start INFO: Exiting after upgrade Aug 28, 2009 11:48:11 AM com.sun.enterprise.v3.admin.StopDomainCommand execute INFO: Server shutdown initiated Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.poll (ms) 5000 Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.dir /Users/bobby/servers/glassfishv3/glassfish/modules/autostart Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.debug 1 Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.bundles.new.start true Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.poll (ms) 5000 Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.dir /Users/bobby/servers/glassfishv3/glassfish/domains/domain1/autodeploy-bundles Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.debug 1 Aug 28, 2009 11:48:12 AM INFO: felix.fileinstall.bundles.new.start true Aug 28, 2009 11:48:13 AM com.sun.grizzly.tcp.http11.GrizzlyAdapter service SEVERE: service exception java.lang.RuntimeException: ClientAbortException: java.nio.channels.ClosedChannelException at com.sun.enterprise.v3.admin.AdminAdapter.service(AdminAdapter.java:216) at com.sun.grizzly.tcp.http11.GrizzlyAdapter.service(GrizzlyAdapter.java:166) at com.sun.enterprise.v3.server.HK2Dispatcher.dispath(HK2Dispatcher.java:100) at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:211) at com.sun.grizzly.http.ProcessorTask.invokeAdapter(ProcessorTask.java:752) at com.sun.grizzly.http.ProcessorTask.doProcess(ProcessorTask.java:660) at com.sun.grizzly.http.ProcessorTask.process(ProcessorTask.java:911) at com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:164) at com.sun.grizzly.DefaultProtocolChain.executeProtocolFilter(DefaultProtocolChain.java:135) at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:102) at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:88) at com.sun.grizzly.http.HttpProtocolChain.execute(HttpProtocolChain.java:76) at com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:53) at com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:57) at com.sun.grizzly.NIOContext.execute(NIOContext.java:510) at com.sun.grizzly.SelectorHandlerRunner.handleSelectedKey(SelectorHandlerRunner.java:357) at com.sun.grizzly.SelectorHandlerRunner.handleSelectedKeys(SelectorHandlerRunner.java:257) at com.sun.grizzly.SelectorHandlerRunner.doSelect(SelectorHandlerRunner.java:194) at com.sun.grizzly.SelectorHandlerRunner.run(SelectorHandlerRunner.java:129) at com.sun.grizzly.util.FixedThreadPool$BasicWorker.dowork(FixedThreadPool.java:379) at com.sun.grizzly.util.FixedThreadPool$BasicWorker.run(FixedThreadPool.java:360) at java.lang.Thread.run(Thread.java:637) Caused by: ClientAbortException: java.nio.channels.ClosedChannelException at com.sun.grizzly.tcp.http11.GrizzlyOutputBuffer.doFlush(GrizzlyOutputBuffer.java:436) at com.sun.grizzly.tcp.http11.GrizzlyOutputBuffer.flush(GrizzlyOutputBuffer.java:402) at com.sun.grizzly.tcp.http11.GrizzlyOutputStream.flush(GrizzlyOutputStream.java:138) at com.sun.enterprise.v3.admin.AdminAdapter.service(AdminAdapter.java:213) ... 21 more Caused by: java.nio.channels.ClosedChannelException at sun.nio.ch.SocketChannelImpl.ensureWriteOpen(SocketChannelImpl.java:126) at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:324) at com.sun.grizzly.util.OutputWriter.flushChannel(OutputWriter.java:106) at com.sun.grizzly.util.OutputWriter.flushChannel(OutputWriter.java:74) at com.sun.grizzly.http.SocketChannelOutputBuffer.flushChannel(SocketChannelOutputBuffer.java:321) at com.sun.grizzly.http.SocketChannelOutputBuffer.flushBuffer(SocketChannelOutputBuffer.java:386) at com.sun.grizzly.http.SocketChannelOutputBuffer.flush(SocketChannelOutputBuffer.java:364) at com.sun.grizzly.http.ProcessorTask.action(ProcessorTask.java:1127) at com.sun.grizzly.tcp.Response.action(Response.java:260) at com.sun.grizzly.tcp.http11.GrizzlyOutputBuffer.doFlush(GrizzlyOutputBuffer.java:431) ... 24 more Aug 28, 2009 11:48:13 AM org.glassfish.admin.mbeanserver.JMXStartupService shutdown INFO: JMXStartupService: shutting down AMX and JMX Aug 28, 2009 11:48:13 AM org.glassfish.admin.mbeanserver.JMXStartupService$JMXConnectorsStarterThread shutdown INFO: JMXStartupService: Stopped JMXConnectorServer: service:jmx:rmi://dhcp-ubur02-71-236.East.Sun.COM:8686/jndi/rmi://dhcp-ubur02-71-236.East.Sun.COM:8686/jmxrmi Aug 28, 2009 11:48:13 AM AppServerStartup run INFO: [Thread[GlassFish Kernel Main Thread,5,main]] exiting Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.transaction.jta [66] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.registration.glassfish-registration [168] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.orb.connector [143] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.connectors.admin [181] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.admin.config-api [95] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.common.glassfish-naming [89] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.common.glassfish-mbeanserver [154] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.connectors.internal-api [50] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.jdbc.admin [184] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.jms.core [16] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.core.kernel [55] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.admin.server-mgmt [192] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.common.internal-api [199] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.ejb.ejb-container [44] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.web.osgi-web-container [161] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.common.util [67] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = com.sun.grizzly.grizzly-config [138] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.common.glassfish-api [81] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.flashlight.framework [179] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.common.container-common [18] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = com.sun.enterprise.config [170] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.admin.rest-service [188] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.deployment.autodeploy [136] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.admin.monitoring-core [157] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.branding [183] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.web.cli [97] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.deployment.common [51] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = org.glassfish.transaction.jts [74] Aug 28, 2009 11:48:13 AM OSGiModuleImpl detach INFO: Stopped bundle = com.sun.enterprise.osgi-adapter [131] Command start-domain failed.