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

        craigday created issue -
        Hong Zhang made changes -
        Field Original Value New Value
        Assignee Hong Zhang [ hzhang_jn ] Jagadish [ jr158900 ]
        Jagadish made changes -
        Assignee Jagadish [ jr158900 ] naman_mehta [ naman_mehta ]
        naman_mehta made changes -
        Attachment testcase.tar [ 51368 ]
        naman_mehta made changes -
        Status Open [ 1 ] Closed [ 6 ]
        Fix Version/s 4.0_b61 [ 15650 ]
        Resolution Fixed [ 1 ]

          People

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

            Dates

            • Created:
              Updated:
              Resolved: