bhavani@sholay: ~/tmp/embedded/issue_16911$ java -cp /space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/lib/embedded/glassfish-embedded-static-shell.jar:. Test Jun 27, 2011 11:23:34 PM org.glassfish.ha.store.spi.BackingStoreFactoryRegistry register INFO: Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry Jun 27, 2011 11:23:34 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy$2$1 onReady INFO: Grizzly Framework 1.9.36 started in: 111ms - bound to [0.0.0.0:8080] Jun 27, 2011 11:23:34 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy$2$1 onReady INFO: Grizzly Framework 1.9.36 started in: 49ms - bound to [0.0.0.0:7676] Jun 27, 2011 11:23:34 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy$2$1 onReady INFO: Grizzly Framework 1.9.36 started in: 79ms - bound to [0.0.0.0:4848] Jun 27, 2011 11:23:34 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy$2$1 onReady INFO: Grizzly Framework 1.9.36 started in: 62ms - bound to [0.0.0.0:3700] Jun 27, 2011 11:23:34 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy$2$1 onReady INFO: Grizzly Framework 1.9.36 started in: 90ms - bound to [0.0.0.0:8181] Jun 27, 2011 11:23:34 PM com.sun.enterprise.v3.server.AppServerStartup run INFO: GlassFish Server Open Source Edition 3.1.1-SNAPSHOT (bhavani-private) startup time : Embedded (578ms), startup services(579ms), total(1,157ms) Jun 27, 2011 11:23:35 PM org.glassfish.admin.mbeanserver.JMXStartupService$JMXConnectorsStarterThread startConnector INFO: JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://sholay.india.sun.com:8686/jndi/rmi://sholay.india.sun.com:8686/jmxrmi Jun 27, 2011 11:23:35 PM org.hibernate.validator.util.Version INFO: Hibernate Validator 4.1.0.Final Jun 27, 2011 11:23:35 PM org.hibernate.validator.engine.resolver.DefaultTraversableResolver detectJPA INFO: Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. Jun 27, 2011 11:23:35 PM com.sun.enterprise.security.SecurityLifecycle INFO: SEC1002: Security Manager is OFF. Jun 27, 2011 11:23:35 PM com.sun.enterprise.security.SecurityLifecycle onInitialization INFO: SEC1010: Entering Security Startup Service Jun 27, 2011 11:23:35 PM com.sun.enterprise.security.PolicyLoader loadPolicy INFO: SEC1143: Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper. Jun 27, 2011 11:23:36 PM com.sun.enterprise.security.auth.realm.Realm doInstantiate INFO: SEC1115: Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created. Jun 27, 2011 11:23:36 PM com.sun.enterprise.security.auth.realm.Realm doInstantiate INFO: SEC1115: Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created. Jun 27, 2011 11:23:36 PM com.sun.enterprise.security.auth.realm.Realm doInstantiate INFO: SEC1115: Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created. Jun 27, 2011 11:23:36 PM com.sun.enterprise.security.SecurityLifecycle onInitialization INFO: SEC1011: Security Service(s) Started Successfully Jun 27, 2011 11:23:36 PM com.sun.ejb.containers.BaseContainer initializeHome INFO: Portable JNDI names for EJB SampleEjb : [java:global/remoteejb/SampleEjb!org.glassfish.tests.embedded.ejb.remoteejb.SampleEjb, java:global/remoteejb/SampleEjb] Jun 27, 2011 11:23:38 PM com.sun.jts.CosTransactions.DefaultTransactionService setServerName INFO: JTS5014: Recoverable JTS instance, serverId = [3700] Jun 27, 2011 11:23:39 PM com.sun.ejb.containers.BaseContainer initializeHome INFO: Portable JNDI names for EJB RemoteEJB : [java:global/remoteejb/RemoteEJB, java:global/remoteejb/RemoteEJB!org.glassfish.tests.embedded.ejb.remoteejb.RemoteEJBInf] Jun 27, 2011 11:23:39 PM com.sun.ejb.containers.BaseContainer initializeHome INFO: Glassfish-specific (Non-portable) JNDI names for EJB RemoteEJB : [org.glassfish.tests.embedded.ejb.remoteejb.RemoteEJBInf#org.glassfish.tests.embedded.ejb.remoteejb.RemoteEJBInf, org.glassfish.tests.embedded.ejb.remoteejb.RemoteEJBInf] Jun 27, 2011 11:23:39 PM com.sun.ejb.containers.EjbContainerUtilImpl deployEJBTimerService INFO: Loading EJBTimerService. Please wait. Jun 27, 2011 11:23:39 PM com.sun.enterprise.web.WebContainer createHttpListener INFO: WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:8080] Jun 27, 2011 11:23:39 PM com.sun.enterprise.web.WebContainer createHttpListener INFO: WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:8181] Jun 27, 2011 11:23:39 PM com.sun.enterprise.web.WebContainer createHttpListener INFO: WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:4848] Jun 27, 2011 11:23:39 PM com.sun.enterprise.web.WebContainer createHosts INFO: WEB0171: Created virtual server [server] Jun 27, 2011 11:23:39 PM com.sun.enterprise.web.WebContainer createHosts INFO: WEB0171: Created virtual server [__asadmin] Jun 27, 2011 11:23:40 PM com.sun.enterprise.web.WebContainer loadSystemDefaultWebModules INFO: WEB0172: Virtual server [server] loaded default web module [] Jun 27, 2011 11:23:42 PM org.hibernate.validator.engine.resolver.DefaultTraversableResolver detectJPA INFO: Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. classLoader = WebappClassLoader (delegate=true; repositories=WEB-INF/classes/) SharedSecrets.getJavaNetAccess()=java.net.URLClassLoader$7@196b73e Jun 27, 2011 11:23:43 PM org.hibernate.validator.engine.resolver.DefaultTraversableResolver detectJPA INFO: Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. Jun 27, 2011 11:23:43 PM org.eclipse.persistence.session.file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App INFO: EclipseLink, version: Eclipse Persistence Services - 2.3.0.v20110604-r9504 Jun 27, 2011 11:23:50 PM org.eclipse.persistence.session.file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App INFO: file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App login successful Jun 27, 2011 11:23:50 PM org.eclipse.persistence.session.file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App.server WARNING: Multiple [2] JMX MBeanServer instances exist, we will use the server at index [0] : [com.sun.enterprise.v3.admin.DynamicInterceptor@135b1f3]. Jun 27, 2011 11:23:50 PM org.eclipse.persistence.session.file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App.server WARNING: JMX MBeanServer in use: [com.sun.enterprise.v3.admin.DynamicInterceptor@135b1f3] from index [0] Jun 27, 2011 11:23:50 PM org.eclipse.persistence.session.file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App.server WARNING: JMX MBeanServer in use: [com.sun.jmx.mbeanserver.JmxMBeanServer@f50903] from index [1] Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.TimerBeanContainer INFO: [TimerBeanContainer] Created TimerBeanContainer: TimerBean Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.BaseContainer initializeHome INFO: Portable JNDI names for EJB TimerBean : [java:global/ejb-timer-service-app/TimerBean, java:global/ejb-timer-service-app/TimerBean!com.sun.ejb.containers.TimerLocal] Jun 27, 2011 11:23:51 PM com.sun.enterprise.web.WebApplication start INFO: WEB0671: Loading application [ejb-timer-service-app] at [/ejb-timer-service-app] Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.EjbContainerUtilImpl deployEJBTimerService INFO: EJB5109:EJB Timer Service started successfully for data source [jdbc/__TimerPool] Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.EjbContainerUtilImpl getEJBTimerService INFO: Setting DBReadBeforeTimeout to false Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.EjbContainerUtilImpl getEJBTimerService INFO: ==> Restoring Timers ... Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.EJBTimerService restoreEJBTimers INFO: There are no EJB Timers owned by this server Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.EjbContainerUtilImpl getEJBTimerService INFO: <== ... Timers Restored. Jun 27, 2011 11:23:51 PM com.sun.ejb.containers.BaseContainer initializeHome INFO: Portable JNDI names for EJB TimerEjb : [java:global/remoteejb/TimerEjb!org.glassfish.tests.embedded.ejb.remoteejb.TimerEjb, java:global/remoteejb/TimerEjb] Jun 27, 2011 11:23:52 PM org.glassfish.deployment.admin.DeployCommand execute INFO: remoteejb was successfully deployed in 16,829 milliseconds. PlainTextActionReporterSUCCESSDescription: deploy AdminCommandApplication deployed with name remoteejb. [name=remoteejb GlassFish started [ com.sun.enterprise.glassfish.bootstrap.StaticGlassFishRuntime$1@1c7b77e] Jun 27, 2011 11:23:52 PM com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator handleFullLogging WARNING: IOP02310830: ReferenceFactoryManager destroy failed org.omg.CORBA.OBJ_ADAPTER: WARNING: IOP02310830: ReferenceFactoryManager destroy failed vmcid: OMG minor code: 830 completed: No at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27) at java.lang.reflect.Constructor.newInstance(Constructor.java:513) at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:248) at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:95) at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.handleFullLogging(WrapperGenerator.java:387) at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.access$400(WrapperGenerator.java:107) at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator$2.invoke(WrapperGenerator.java:511) at com.sun.corba.ee.spi.orbutil.proxy.CompositeInvocationHandlerImpl.invoke(CompositeInvocationHandlerImpl.java:99) at $Proxy145.rfmDestroyFailed(Unknown Source) at com.sun.corba.ee.impl.oa.rfm.ReferenceFactoryManagerImpl.destroy(ReferenceFactoryManagerImpl.java:575) at com.sun.corba.ee.impl.oa.rfm.ReferenceFactoryImpl.destroy(ReferenceFactoryImpl.java:66) at org.glassfish.enterprise.iiop.impl.POARemoteReferenceFactory.destroy(POARemoteReferenceFactory.java:554) at com.sun.ejb.containers.BaseContainer.doContainerCleanup(BaseContainer.java:4356) at com.sun.ejb.containers.BaseContainer.onShutdown(BaseContainer.java:4237) at org.glassfish.ejb.startup.EjbApplication.stop(EjbApplication.java:307) at org.glassfish.internal.data.EngineRef.stop(EngineRef.java:169) at org.glassfish.internal.data.ModuleInfo.stop(ModuleInfo.java:302) at org.glassfish.internal.data.ApplicationInfo.stop(ApplicationInfo.java:322) at com.sun.enterprise.v3.server.ApplicationLifecycle.unload(ApplicationLifecycle.java:999) at com.sun.enterprise.v3.server.ApplicationLifecycle.disable(ApplicationLifecycle.java:1971) at com.sun.enterprise.v3.server.ApplicationLoaderService.stopApplication(ApplicationLoaderService.java:454) at com.sun.enterprise.v3.server.ApplicationLoaderService.preDestroy(ApplicationLoaderService.java:422) at com.sun.hk2.component.AbstractCreatorInhabitantImpl.dispose(AbstractCreatorInhabitantImpl.java:83) at com.sun.hk2.component.SingletonInhabitant.release(SingletonInhabitant.java:81) at com.sun.hk2.component.EventPublishingInhabitant.release(EventPublishingInhabitant.java:108) at com.sun.hk2.component.LazyInhabitant.release(LazyInhabitant.java:133) at com.sun.enterprise.v3.server.AppServerStartup.stop(AppServerStartup.java:425) at com.sun.enterprise.glassfish.bootstrap.GlassFishImpl.stop(GlassFishImpl.java:88) at com.sun.enterprise.glassfish.bootstrap.GlassFishImpl.dispose(GlassFishImpl.java:97) at com.sun.enterprise.glassfish.bootstrap.StaticGlassFishRuntime$1.dispose(StaticGlassFishRuntime.java:111) at Test.main(Test.java:71) Caused by: java.lang.NullPointerException at com.sun.corba.ee.impl.orb.ORBImpl.isDuringDispatch(ORBImpl.java:1433) at com.sun.corba.ee.impl.oa.poa.POAImpl.destroy(POAImpl.java:1152) at com.sun.corba.ee.impl.oa.rfm.ReferenceFactoryManagerImpl.destroy(ReferenceFactoryManagerImpl.java:573) ... 21 more Jun 27, 2011 11:23:52 PM org.glassfish.enterprise.iiop.impl.POARemoteReferenceFactory destroy SEVERE: Exception during POARemoteRefFactory::destroy() org.omg.CORBA.OBJ_ADAPTER: WARNING: IOP02310830: ReferenceFactoryManager destroy failed vmcid: OMG minor code: 830 completed: No at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27) at java.lang.reflect.Constructor.newInstance(Constructor.java:513) at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:248) at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:95) at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.handleFullLogging(WrapperGenerator.java:387) at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.access$400(WrapperGenerator.java:107) at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator$2.invoke(WrapperGenerator.java:511) at com.sun.corba.ee.spi.orbutil.proxy.CompositeInvocationHandlerImpl.invoke(CompositeInvocationHandlerImpl.java:99) at $Proxy145.rfmDestroyFailed(Unknown Source) at com.sun.corba.ee.impl.oa.rfm.ReferenceFactoryManagerImpl.destroy(ReferenceFactoryManagerImpl.java:575) at com.sun.corba.ee.impl.oa.rfm.ReferenceFactoryImpl.destroy(ReferenceFactoryImpl.java:66) at org.glassfish.enterprise.iiop.impl.POARemoteReferenceFactory.destroy(POARemoteReferenceFactory.java:554) at com.sun.ejb.containers.BaseContainer.doContainerCleanup(BaseContainer.java:4356) at com.sun.ejb.containers.BaseContainer.onShutdown(BaseContainer.java:4237) at org.glassfish.ejb.startup.EjbApplication.stop(EjbApplication.java:307) at org.glassfish.internal.data.EngineRef.stop(EngineRef.java:169) at org.glassfish.internal.data.ModuleInfo.stop(ModuleInfo.java:302) at org.glassfish.internal.data.ApplicationInfo.stop(ApplicationInfo.java:322) at com.sun.enterprise.v3.server.ApplicationLifecycle.unload(ApplicationLifecycle.java:999) at com.sun.enterprise.v3.server.ApplicationLifecycle.disable(ApplicationLifecycle.java:1971) at com.sun.enterprise.v3.server.ApplicationLoaderService.stopApplication(ApplicationLoaderService.java:454) at com.sun.enterprise.v3.server.ApplicationLoaderService.preDestroy(ApplicationLoaderService.java:422) at com.sun.hk2.component.AbstractCreatorInhabitantImpl.dispose(AbstractCreatorInhabitantImpl.java:83) at com.sun.hk2.component.SingletonInhabitant.release(SingletonInhabitant.java:81) at com.sun.hk2.component.EventPublishingInhabitant.release(EventPublishingInhabitant.java:108) at com.sun.hk2.component.LazyInhabitant.release(LazyInhabitant.java:133) at com.sun.enterprise.v3.server.AppServerStartup.stop(AppServerStartup.java:425) at com.sun.enterprise.glassfish.bootstrap.GlassFishImpl.stop(GlassFishImpl.java:88) at com.sun.enterprise.glassfish.bootstrap.GlassFishImpl.dispose(GlassFishImpl.java:97) at com.sun.enterprise.glassfish.bootstrap.StaticGlassFishRuntime$1.dispose(StaticGlassFishRuntime.java:111) at Test.main(Test.java:71) Caused by: java.lang.NullPointerException at com.sun.corba.ee.impl.orb.ORBImpl.isDuringDispatch(ORBImpl.java:1433) at com.sun.corba.ee.impl.oa.poa.POAImpl.destroy(POAImpl.java:1152) at com.sun.corba.ee.impl.oa.rfm.ReferenceFactoryManagerImpl.destroy(ReferenceFactoryManagerImpl.java:573) ... 21 more Jun 27, 2011 11:23:52 PM com.sun.ejb.containers.TimerBeanContainer doConcreteContainerShutdown INFO: [TimerBeanContainer] Shutdown() called.... Jun 27, 2011 11:23:52 PM com.sun.ejb.containers.EJBTimerService onShutdown WARNING: EJB5121:EJB Timer Service shutdown info [__timer_service_shutdown__.dat] not found or is corrupted. Shutdown time unknown. classLoader = WebappClassLoader (delegate=true; repositories=WEB-INF/classes/) SharedSecrets.getJavaNetAccess()=java.net.URLClassLoader$7@196b73e Jun 27, 2011 11:23:52 PM org.eclipse.persistence.session.file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App INFO: file:/space/bhavani/ws/glassfish/3.1.1/image/glassfish3/glassfish/domains/domain1/applications/ejb-timer-service-app/WEB-INF/classes/___EJB__Timer__App logout successful Jun 27, 2011 11:23:52 PM com.sun.enterprise.connectors.service.ResourceAdapterAdminServiceImpl sendStopToResourceAdapter INFO: RAR7094: __xa_jdbc_ra shutdown successful. Jun 27, 2011 11:23:55 PM org.glassfish.admin.mbeanserver.JMXStartupService$JMXConnectorsStarterThread shutdown INFO: JMXStartupService: Stopped JMXConnectorServer: null Jun 27, 2011 11:23:55 PM org.glassfish.admin.mbeanserver.JMXStartupService shutdown INFO: JMXStartupService and JMXConnectors have been shut down. Jun 27, 2011 11:23:55 PM com.sun.enterprise.v3.server.AppServerStartup stop INFO: Shutdown procedure finished Jun 27, 2011 11:23:55 PM AppServerStartup run INFO: [Thread[GlassFish Kernel Main Thread,5,main]] exiting GlassFish disposed Sleeping forever. Check 'netstat -an | grep 3700' now