glassfish
  1. glassfish
  2. GLASSFISH-19161

Webapp with application scoped resources fails to startup when node is restarted

    Details

    • Type: Bug Bug
    • Status: Closed
    • Priority: Critical Critical
    • Resolution: Fixed
    • Affects Version/s: 3.1.2
    • Fix Version/s: 4.0_b61
    • Component/s: deployment
    • Labels:
      None
    • Environment:

      Linux lwlserver 3.2.0-29-generic #46-Ubuntu SMP Fri Jul 27 17:03:23 UTC 2012 x86_64 x86_64 x86_64 GNU/Linux, GlassFish Server Open Source Edition 3.1.2.2 (build 5), JRE version 1.7.0_04

      Description

      Steps to reproduce:

      ----------------------------------------
      STEP 1: Create and start a new local instance:
      ----------------------------------------

      apps@lwlserver:~$ asadmin create-local-instance testcase
      Rendezvoused with DAS on localhost:4848.
      Using DAS host localhost and port 4848 from existing das.properties for node
      localhost-domain1. To use a different DAS, create a new node using create-node-ssh or
      create-node-config. Create the instance with the new node and correct
      host and port:
      asadmin --host das_host --port das_port create-local-instance --node node_name instance_name.
      Port Assignments for server instance testcase:
      JMX_SYSTEM_CONNECTOR_PORT=28692
      JMS_PROVIDER_PORT=27682
      HTTP_LISTENER_PORT=28086
      ASADMIN_LISTENER_PORT=24854
      JAVA_DEBUGGER_PORT=29015
      IIOP_SSL_LISTENER_PORT=23826
      IIOP_LISTENER_PORT=23706
      OSGI_SHELL_TELNET_PORT=26672
      HTTP_SSL_LISTENER_PORT=28187
      IIOP_SSL_MUTUALAUTH_PORT=23926
      Command create-local-instance executed successfully.

      apps@lwlserver:~$ asadmin start-instance testcase
      Waiting for testcase to start ......
      Successfully started the instance: testcase
      instance Location: /home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase
      Log File: /home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/logs/server.log
      Admin Port: 24854
      Command start-local-instance executed successfully.
      The instance, testcase, was started on host localhost
      Command start-instance executed successfully.

      ----------------------------------------
      STEP 2: Deploy the attached testcase:
      ----------------------------------------

      apps@lwlserver:~$ asadmin deploy --target testcase testcase.war
      Application deployed with name testcase.
      Command deploy executed successfully.

      ----------------------------------------
      STEP 3: Observe server.log for successful deploy:
      ----------------------------------------

      Oct 16, 2012 2:25:02 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info
      INFO: JVM invocation command line:
      -XX:+UnlockDiagnosticVMOptions
      -XX:PermSize=64m
      -XX:MaxPermSize=192m
      -XX:NewRatio=2
      -Xmx512m
      -javaagent:/home/apps/glassfish3122/glassfish/lib/monitor/flashlight-agent.jar
      -server
      -Dosgi.shell.telnet.maxconn=1
      -Dfelix.fileinstall.disableConfigSave=false
      -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver
      -Dfelix.fileinstall.dir=/home/apps/glassfish3122/glassfish/modules/autostart/
      -Djavax.net.ssl.keyStore=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/keystore.jks
      -Dosgi.shell.telnet.port=26672
      -Djava.security.policy=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/server.policy
      -Djava.awt.headless=true
      -Dfelix.fileinstall.log.level=3
      -Dfelix.fileinstall.poll=5000
      -Dcom.sun.aas.instanceRoot=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase
      -Dosgi.shell.telnet.ip=127.0.0.1
      -Dcom.sun.enterprise.config.config_environment_factory_class=com.sun.enterprise.config.serverbeans.AppserverConfigEnvironmentFactory
      -Djava.endorsed.dirs=/home/apps/glassfish3122/glassfish/modules/endorsed:/home/apps/glassfish3122/glassfish/lib/endorsed
      -Dcom.sun.aas.installRoot=/home/apps/glassfish3122/glassfish
      -Dfelix.fileinstall.bundles.startTransient=true
      -Djava.ext.dirs=/home/apps/jdk1.7.0_04/lib/ext:/home/apps/jdk1.7.0_04/jre/lib/ext:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/lib/ext
      -Dfelix.fileinstall.bundles.new.start=true
      -Djavax.net.ssl.trustStore=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/cacerts.jks
      -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as
      -Djava.security.auth.login.config=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/login.conf
      -DANTLR_USE_DIRECT_CLASS_LOADING=true
      Dgosh.args=-noshutdown -c noop=true
      Oct 16, 2012 2:25:02 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info
      INFO: Successfully launched in 3 msec.
      [#|2012-10-16T14:25:05.002+0800|INFO|glassfish3.1.2|com.sun.enterprise.server.logging.GFFileHandler|_ThreadID=1;_ThreadName=Thread-2;|Running GlassFish Version: GlassFish Server Open Source Edition 3.1.2.2 (build 5)|#]

      [#|2012-10-16T14:25:05.172+0800|INFO|glassfish3.1.2|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=1;_ThreadName=Thread-2;|Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry|#]

      [#|2012-10-16T14:25:05.560+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=1;_ThreadName=Thread-2;|JTS5014: Recoverable JTS instance, serverId = [100]|#]

      [#|2012-10-16T14:25:05.853+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=23;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 18ms - bound to [0.0.0.0:28187]|#]

      [#|2012-10-16T14:25:05.853+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=20;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 45ms - bound to [0.0.0.0:28086]|#]

      [#|2012-10-16T14:25:05.890+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=27;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 4ms - bound to [0.0.0.0:24854]|#]

      [#|2012-10-16T14:25:05.915+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=35;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 10ms - bound to [0.0.0.0:27682]|#]

      [#|2012-10-16T14:25:06.299+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:28086]|#]

      [#|2012-10-16T14:25:06.313+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:28187]|#]

      [#|2012-10-16T14:25:06.324+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:24854]|#]

      [#|2012-10-16T14:25:06.390+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0171: Created virtual server [server]|#]

      [#|2012-10-16T14:25:06.396+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0171: Created virtual server [__asadmin]|#]

      [#|2012-10-16T14:25:06.836+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0172: Virtual server [server] loaded default web module []|#]

      [#|2012-10-16T14:25:07.844+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=1;_ThreadName=Thread-2;|core.start_container_done|#]

      [#|2012-10-16T14:25:07.928+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=1;_ThreadName=Thread-2;|GlassFish Server Open Source Edition 3.1.2.2 (5) startup time : Felix (2,434ms), startup services(3,246ms), total(5,680ms)|#]

      [#|2012-10-16T14:25:08.167+0800|INFO|glassfish3.1.2|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=44;_ThreadName=Thread-2;|Recovery of Inbound Transactions started.|#]

      [#|2012-10-16T14:25:08.177+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=44;_ThreadName=Thread-2;|JTS5014: Recoverable JTS instance, serverId = [100]|#]

      [#|2012-10-16T14:25:08.936+0800|INFO|glassfish3.1.2|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=12;_ThreadName=Thread-2;|/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/autodeploy/bundles does not exist, please create it.|#]

      [#|2012-10-16T14:25:09.077+0800|INFO|glassfish3.1.2|javax.enterprise.system.jmx.org.glassfish.admin.mbeanserver|_ThreadID=48;_ThreadName=Thread-2;|JMX005: JMXStartupService had Started JMXConnector on JMXService URL service:jmx:rmi://lwlserver:28692/jndi/rmi://lwlserver:28692/jmxrmi|#]

      [#|2012-10-16T14:27:14.283+0800|INFO|glassfish3.1.2|org.hibernate.validator.internal.util.Version|_ThreadID=28;_ThreadName=Thread-2;|HV000001: Hibernate Validator 4.3.0.Final|#]

      [#|2012-10-16T14:27:14.454+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=28;_ThreadName=Thread-2;|SEC1002: Security Manager is OFF.|#]

      [#|2012-10-16T14:27:14.477+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=28;_ThreadName=Thread-2;|SEC1010: Entering Security Startup Service|#]

      [#|2012-10-16T14:27:14.481+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=28;_ThreadName=Thread-2;|SEC1143: Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper.|#]

      [#|2012-10-16T14:27:14.523+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=28;_ThreadName=Thread-2;|SEC1115: Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#]

      [#|2012-10-16T14:27:14.524+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=28;_ThreadName=Thread-2;|SEC1115: Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#]

      [#|2012-10-16T14:27:14.533+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=28;_ThreadName=Thread-2;|SEC1115: Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created.|#]

      [#|2012-10-16T14:27:14.545+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=28;_ThreadName=Thread-2;|SEC1011: Security Service(s) Started Successfully|#]

      [#|2012-10-16T14:27:16.091+0800|INFO|glassfish3.1.2|org.eclipse.persistence.session.file:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/applications/testcase/WEB-INF/classes/_testcase|_ThreadID=28;_ThreadName=Thread-2;|EclipseLink, version: Eclipse Persistence Services - 2.3.2.v20111125-r10461|#]

      [#|2012-10-16T14:27:24.273+0800|INFO|glassfish3.1.2|org.eclipse.persistence.session.file:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/applications/testcase/WEB-INF/classes/_testcase|_ThreadID=28;_ThreadName=Thread-2;|file:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/applications/testcase/WEB-INF/classes/_testcase login successful|#]

      [#|2012-10-16T14:27:24.274+0800|WARNING|glassfish3.1.2|org.eclipse.persistence.session.file:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/applications/testcase/WEB-INF/classes/_testcase.server|_ThreadID=28;_ThreadName=Thread-2;|Multiple [2] JMX MBeanServer instances exist, we will use the server at index [0] : [com.sun.jmx.mbeanserver.JmxMBeanServer@43a6a5f9].|#]

      [#|2012-10-16T14:27:24.274+0800|WARNING|glassfish3.1.2|org.eclipse.persistence.session.file:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/applications/testcase/WEB-INF/classes/_testcase.server|_ThreadID=28;_ThreadName=Thread-2;|JMX MBeanServer in use: [com.sun.jmx.mbeanserver.JmxMBeanServer@43a6a5f9] from index [0] |#]

      [#|2012-10-16T14:27:24.275+0800|WARNING|glassfish3.1.2|org.eclipse.persistence.session.file:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/applications/testcase/WEB-INF/classes/_testcase.server|_ThreadID=28;_ThreadName=Thread-2;|JMX MBeanServer in use: [com.sun.jmx.mbeanserver.JmxMBeanServer@50d2ffa8] from index [1] |#]

      [#|2012-10-16T14:27:24.295+0800|WARNING|glassfish3.1.2|org.eclipse.persistence.default|_ThreadID=28;_ThreadName=Thread-2;|The collection of metamodel types is empty. Model classes may not have been found during entity search for Java SE and some Java EE container managed persistence units. Please verify that your entity classes are referenced in persistence.xml using either <class> elements or a global <exclude-unlisted-classes>false</exclude-unlisted-classes> element|#]

      [#|2012-10-16T14:27:24.393+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=28;_ThreadName=Thread-2;|WEB0671: Loading application [testcase] at [/testcase]|#]

      [#|2012-10-16T14:27:24.520+0800|INFO|glassfish3.1.2|javax.enterprise.system.tools.admin.org.glassfish.deployment.admin|_ThreadID=28;_ThreadName=Thread-2;|testcase was successfully deployed in 10,310 milliseconds.|#]

      ----------------------------------------
      STEP 4: Restart instance:
      ----------------------------------------

      apps@lwlserver:~$ asadmin restart-instance testcase
      testcase was restarted.
      Command restart-instance executed successfully.
      apps@lwlserver:~$

      ----------------------------------------
      STEP 5: Observe server.log for failed startup:
      ----------------------------------------

      [#|2012-10-16T14:27:49.989+0800|INFO|glassfish3.1.2|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=96;_ThreadName=Thread-2;|Server restart initiated|#]

      [#|2012-10-16T14:27:49.997+0800|WARNING|glassfish3.1.2|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=96;_ThreadName=Thread-2;|There should be only 1 primordial module but 0 primordial modules were found.|#]

      Oct 16, 2012 2:27:51 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info
      INFO: JVM invocation command line:
      -XX:+UnlockDiagnosticVMOptions
      -XX:PermSize=64m
      -XX:MaxPermSize=192m
      -XX:NewRatio=2
      -Xmx512m
      -javaagent:/home/apps/glassfish3122/glassfish/lib/monitor/flashlight-agent.jar
      -server
      -Dosgi.shell.telnet.maxconn=1
      -Dfelix.fileinstall.disableConfigSave=false
      -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver
      -Dfelix.fileinstall.dir=/home/apps/glassfish3122/glassfish/modules/autostart/
      -Djavax.net.ssl.keyStore=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/keystore.jks
      -Dosgi.shell.telnet.port=26672
      -Djava.security.policy=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/server.policy
      -Djava.awt.headless=true
      -Dfelix.fileinstall.log.level=3
      -Dfelix.fileinstall.poll=5000
      -Dcom.sun.aas.instanceRoot=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase
      -Dosgi.shell.telnet.ip=127.0.0.1
      -Dcom.sun.enterprise.config.config_environment_factory_class=com.sun.enterprise.config.serverbeans.AppserverConfigEnvironmentFactory
      -Djava.endorsed.dirs=/home/apps/glassfish3122/glassfish/modules/endorsed:/home/apps/glassfish3122/glassfish/lib/endorsed
      -Dcom.sun.aas.installRoot=/home/apps/glassfish3122/glassfish
      -Dfelix.fileinstall.bundles.startTransient=true
      -Djava.ext.dirs=/home/apps/jdk1.7.0_04/lib/ext:/home/apps/jdk1.7.0_04/jre/lib/ext:/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/lib/ext
      -Dfelix.fileinstall.bundles.new.start=true
      -Djavax.net.ssl.trustStore=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/cacerts.jks
      -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as
      -Djava.security.auth.login.config=/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/config/login.conf
      -DANTLR_USE_DIRECT_CLASS_LOADING=true
      Dgosh.args=-noshutdown -c noop=true
      Oct 16, 2012 2:27:51 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info
      INFO: Successfully launched in 3 msec.
      [#|2012-10-16T14:27:54.391+0800|INFO|glassfish3.1.2|com.sun.enterprise.server.logging.GFFileHandler|_ThreadID=1;_ThreadName=Thread-2;|Running GlassFish Version: GlassFish Server Open Source Edition 3.1.2.2 (build 5)|#]

      [#|2012-10-16T14:27:54.543+0800|INFO|glassfish3.1.2|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=1;_ThreadName=Thread-2;|Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry|#]

      [#|2012-10-16T14:27:54.919+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=1;_ThreadName=Thread-2;|JTS5014: Recoverable JTS instance, serverId = [100]|#]

      [#|2012-10-16T14:27:55.229+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=23;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 56ms - bound to [0.0.0.0:28086]|#]

      [#|2012-10-16T14:27:55.229+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=22;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 31ms - bound to [0.0.0.0:28187]|#]

      [#|2012-10-16T14:27:55.247+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=27;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 5ms - bound to [0.0.0.0:24854]|#]

      [#|2012-10-16T14:27:55.273+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=35;_ThreadName=Thread-2;|Grizzly Framework 1.9.50 started in: 11ms - bound to [0.0.0.0:27682]|#]

      [#|2012-10-16T14:27:55.662+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:28086]|#]

      [#|2012-10-16T14:27:55.675+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:28187]|#]

      [#|2012-10-16T14:27:55.686+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:24854]|#]

      [#|2012-10-16T14:27:55.751+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0171: Created virtual server [server]|#]

      [#|2012-10-16T14:27:55.757+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0171: Created virtual server [__asadmin]|#]

      [#|2012-10-16T14:27:56.237+0800|INFO|glassfish3.1.2|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=Thread-2;|WEB0172: Virtual server [server] loaded default web module []|#]

      [#|2012-10-16T14:27:57.663+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=1;_ThreadName=Thread-2;|core.start_container_done|#]

      [#|2012-10-16T14:27:57.976+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=1;_ThreadName=Thread-2;|SEC1002: Security Manager is OFF.|#]

      [#|2012-10-16T14:27:58.000+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=1;_ThreadName=Thread-2;|SEC1010: Entering Security Startup Service|#]

      [#|2012-10-16T14:27:58.004+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=1;_ThreadName=Thread-2;|SEC1143: Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper.|#]

      [#|2012-10-16T14:27:58.135+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=1;_ThreadName=Thread-2;|SEC1115: Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#]

      [#|2012-10-16T14:27:58.136+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=1;_ThreadName=Thread-2;|SEC1115: Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#]

      [#|2012-10-16T14:27:58.186+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=1;_ThreadName=Thread-2;|SEC1115: Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created.|#]

      [#|2012-10-16T14:27:58.238+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=1;_ThreadName=Thread-2;|SEC1011: Security Service(s) Started Successfully|#]

      [#|2012-10-16T14:27:58.764+0800|SEVERE|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=1;_ThreadName=Thread-2;|Exception while invoking class org.glassfish.persistence.jpa.JPADeployer prepare method|#]

      [#|2012-10-16T14:27:58.765+0800|SEVERE|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=1;_ThreadName=Thread-2;|Exception while preparing the app|#]

      [#|2012-10-16T14:27:58.765+0800|SEVERE|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=1;_ThreadName=Thread-2;|javax.naming.NameNotFoundException: No object bound to name java:app/jdbc/TestDB
      java.lang.RuntimeException: javax.naming.NameNotFoundException: No object bound to name java:app/jdbc/TestDB
      at org.glassfish.persistence.jpa.PersistenceUnitInfoImpl.<init>(PersistenceUnitInfoImpl.java:111)
      at org.glassfish.persistence.jpa.PersistenceUnitLoader.loadPU(PersistenceUnitLoader.java:155)
      at org.glassfish.persistence.jpa.PersistenceUnitLoader.<init>(PersistenceUnitLoader.java:120)
      at org.glassfish.persistence.jpa.JPADeployer$1.visitPUD(JPADeployer.java:224)
      at org.glassfish.persistence.jpa.JPADeployer$PersistenceUnitDescriptorIterator.iteratePUDs(JPADeployer.java:495)
      at org.glassfish.persistence.jpa.JPADeployer.createEMFs(JPADeployer.java:233)
      at org.glassfish.persistence.jpa.JPADeployer.prepare(JPADeployer.java:168)
      at com.sun.enterprise.v3.server.ApplicationLifecycle.prepareModule(ApplicationLifecycle.java:871)
      at com.sun.enterprise.v3.server.ApplicationLifecycle.deploy(ApplicationLifecycle.java:410)
      at com.sun.enterprise.v3.server.ApplicationLoaderService.processApplication(ApplicationLoaderService.java:375)
      at com.sun.enterprise.v3.server.ApplicationLoaderService.postConstruct(ApplicationLoaderService.java:219)
      at com.sun.hk2.component.AbstractCreatorImpl.inject(AbstractCreatorImpl.java:131)
      at com.sun.hk2.component.ConstructorCreator.initialize(ConstructorCreator.java:91)
      at com.sun.hk2.component.AbstractCreatorImpl.get(AbstractCreatorImpl.java:82)
      at com.sun.hk2.component.SingletonInhabitant.get(SingletonInhabitant.java:67)
      at com.sun.hk2.component.EventPublishingInhabitant.get(EventPublishingInhabitant.java:139)
      at com.sun.hk2.component.AbstractInhabitantImpl.get(AbstractInhabitantImpl.java:78)
      at com.sun.enterprise.v3.server.AppServerStartup.run(AppServerStartup.java:253)
      at com.sun.enterprise.v3.server.AppServerStartup.doStart(AppServerStartup.java:145)
      at com.sun.enterprise.v3.server.AppServerStartup.start(AppServerStartup.java:136)
      at com.sun.enterprise.glassfish.bootstrap.GlassFishImpl.start(GlassFishImpl.java:79)
      at com.sun.enterprise.glassfish.bootstrap.GlassFishDecorator.start(GlassFishDecorator.java:63)
      at com.sun.enterprise.glassfish.bootstrap.osgi.OSGiGlassFishImpl.start(OSGiGlassFishImpl.java:69)
      at com.sun.enterprise.glassfish.bootstrap.GlassFishMain$Launcher.launch(GlassFishMain.java:117)
      at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.lang.reflect.Method.invoke(Method.java:601)
      at com.sun.enterprise.glassfish.bootstrap.GlassFishMain.main(GlassFishMain.java:97)
      at com.sun.enterprise.glassfish.bootstrap.ASMain.main(ASMain.java:55)
      Caused by: javax.naming.NameNotFoundException: No object bound to name java:app/jdbc/TestDB
      at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookupFromNamespace(GlassfishNamingManagerImpl.java:394)
      at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookupFromAppNamespace(GlassfishNamingManagerImpl.java:420)
      at com.sun.appserv.connectors.internal.api.ResourceNamingService.lookup(ResourceNamingService.java:209)
      at com.sun.enterprise.connectors.service.ConnectorResourceAdminServiceImpl.lookup(ConnectorResourceAdminServiceImpl.java:227)
      at com.sun.enterprise.connectors.ConnectorRuntime.lookupPMResource(ConnectorRuntime.java:463)
      at org.glassfish.persistence.common.PersistenceHelper.lookupPMResource(PersistenceHelper.java:63)
      at org.glassfish.persistence.jpa.ProviderContainerContractInfoBase.lookupDataSource(ProviderContainerContractInfoBase.java:71)
      at org.glassfish.persistence.jpa.PersistenceUnitInfoImpl.<init>(PersistenceUnitInfoImpl.java:108)
      ... 29 more

      #]

      [#|2012-10-16T14:27:58.975+0800|SEVERE|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=1;_ThreadName=Thread-2;|Exception while preparing the app|#]

      [#|2012-10-16T14:27:58.980+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=1;_ThreadName=Thread-2;|GlassFish Server Open Source Edition 3.1.2.2 (5) startup time : Felix (2,432ms), startup services(4,926ms), total(7,358ms)|#]

      [#|2012-10-16T14:27:59.290+0800|SEVERE|glassfish3.1.2|javax.enterprise.resource.resourceadapter.com.sun.enterprise.resource.recovery|_ThreadID=44;_ThreadName=Thread-2;|RAR7109: Error while loading jdbc resources during recovery : java:app/jdbc/TestDB|#]

      [#|2012-10-16T14:27:59.291+0800|INFO|glassfish3.1.2|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=44;_ThreadName=Thread-2;|Recovery of Inbound Transactions started.|#]

      [#|2012-10-16T14:27:59.293+0800|WARNING|glassfish3.1.2|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=44;_ThreadName=Thread-2;|RAR8505: Application [ testcase ] does not seem to have started. Skipping Inbound Recovery for the application.|#]

      [#|2012-10-16T14:27:59.299+0800|INFO|glassfish3.1.2|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=44;_ThreadName=Thread-2;|JTS5014: Recoverable JTS instance, serverId = [100]|#]

      [#|2012-10-16T14:27:59.780+0800|INFO|glassfish3.1.2|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=12;_ThreadName=Thread-2;|/home/apps/glassfish3122/glassfish/nodes/localhost-domain1/testcase/autodeploy/bundles does not exist, please create it.|#]

      [#|2012-10-16T14:27:59.863+0800|INFO|glassfish3.1.2|javax.enterprise.system.jmx.org.glassfish.admin.mbeanserver|_ThreadID=49;_ThreadName=Thread-2;|JMX005: JMXStartupService had Started JMXConnector on JMXService URL service:jmx:rmi://lwlserver:28692/jndi/rmi://lwlserver:28692/jmxrmi|#]

      1. testcase.tar
        40 kB
        naman_mehta
      2. testcase.tar
        11 kB
        craigday

        Activity

        Hide
        craigday added a comment -
        Show
        craigday added a comment - Also, issue briefly discussed (with another report of the issue) at http://www.java.net/forum/topic/glassfish/glassfish/application-scoped-resources-and-persistencexml-do-not-work-together-what-i-did-wrong
        Hide
        Hong Zhang added a comment -

        Assign to Jagadish for initial evaluation.

        (BTW: I feel we should have a resource category or something similar in the JIRA..)

        Show
        Hong Zhang added a comment - Assign to Jagadish for initial evaluation. (BTW: I feel we should have a resource category or something similar in the JIRA..)
        Hide
        Jagadish added a comment -

        Transferring to Naman for investigation.

        Show
        Jagadish added a comment - Transferring to Naman for investigation.
        Hide
        craigday added a comment -

        Any update/progress on this issue?

        Show
        craigday added a comment - Any update/progress on this issue?
        Hide
        craigday added a comment -

        Any update/progress on this issue?

        Show
        craigday added a comment - Any update/progress on this issue?
        Hide
        naman_mehta added a comment -

        I will look into this and update you on the same.

        Show
        naman_mehta added a comment - I will look into this and update you on the same.
        Hide
        naman_mehta added a comment -

        To fix the same we are calling 'ApplicationScopedResourcesManager' service to run during start up to load all application scoped resources. Attaching new testcase to verify the same.

        Current attached testcase is not working as it's throwing derby error 'https://issues.apache.org/jira/browse/DERBY-5526' on restart.

        Show
        naman_mehta added a comment - To fix the same we are calling 'ApplicationScopedResourcesManager' service to run during start up to load all application scoped resources. Attaching new testcase to verify the same. Current attached testcase is not working as it's throwing derby error 'https://issues.apache.org/jira/browse/DERBY-5526' on restart.
        Hide
        naman_mehta added a comment -

        Attached new testcase to verify this bug.

        Show
        naman_mehta added a comment - Attached new testcase to verify this bug.
        Hide
        naman_mehta added a comment -

        Made required changes and run all required tests.

        Sending kernel/src/main/java/com/sun/enterprise/v3/server/ApplicationLoaderService.java
        Transmitting file data .
        Committed revision 56746.

        Show
        naman_mehta added a comment - Made required changes and run all required tests. Sending kernel/src/main/java/com/sun/enterprise/v3/server/ApplicationLoaderService.java Transmitting file data . Committed revision 56746.

          People

          • Assignee:
            naman_mehta
            Reporter:
            craigday
          • Votes:
            0 Vote for this issue
            Watchers:
            5 Start watching this issue

            Dates

            • Created:
              Updated:
              Resolved: