Nov 29, 2010 12:32:16 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /files/jdk/jdk1.6/bin/java -cp /sun/glassfishv2.1.1/glassfish3/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:MaxPermSize=192m -XX:NewRatio=2 -XX:+LogVMOutput -XX:LogFile=/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/logs/jvm.log -Xmx512m -client -javaagent:/sun/glassfishv2.1.1/glassfish3/glassfish/lib/monitor/btrace-agent.jar=unsafe=true,noServer=true -Dosgi.shell.telnet.maxconn=1 -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver -Dfelix.fileinstall.dir=/sun/glassfishv2.1.1/glassfish3/glassfish/modules/autostart/ -Djavax.net.ssl.keyStore=/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/config/keystore.jks -Dosgi.shell.telnet.port=6666 -Djava.security.policy=/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/config/server.policy -Dcom.sun.appserv.pluggable.features=com.sun.enterprise.ee.server.pluggable.EEPluggableFeatureImpl -Dfelix.fileinstall.poll=5000 -Dcom.sun.aas.instanceRoot=/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1 -Dcom.sun.enterprise.config.config_environment_factory_class=com.sun.enterprise.config.serverbeans.AppserverConfigEnvironmentFactory -Dosgi.shell.telnet.ip=127.0.0.1 -Djava.endorsed.dirs=/sun/glassfishv2.1.1/glassfish3/glassfish/lib/endorsed -Dcom.sun.aas.installRoot=/sun/glassfishv2.1.1/glassfish3/glassfish -Djava.ext.dirs=/files/jdk/jdk1.6/lib/ext:/files/jdk/jdk1.6/jre/lib/ext:/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/lib/ext:/sun/glassfishv2.1.1/glassfish3/javadb/lib:/sun/glassfishv2.1.1/glassfish3/glassfish/lib/jdbcdrivers -Dfelix.fileinstall.bundles.new.start=true -Djavax.net.ssl.trustStore=/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/config/cacerts.jks -Djava.security.auth.login.config=/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/config/login.conf -DANTLR_USE_DIRECT_CLASS_LOADING=true -Dfelix.fileinstall.debug=1 -Dorg.glassfish.web.rfc2109_cookie_names_enforced=false -Djava.library.path=/sun/glassfishv2.1.1/glassfish3/glassfish/lib:/sun/glassfishv2.1.1/glassfish3/glassfish/domains/domain1/config:/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java com.sun.enterprise.glassfish.bootstrap.ASMain -asadmin-args --host,,,localhost,,,--port,,,4848,,,--user,,,admin,,,--secure=false,,,--terse=false,,,--echo=false,,,--interactive=false,,,start-local-instance,,,--verbose=false,,,--debug=false,,,--node,,,na1,,,ins1 -instancename ins1 -verbose false -debug false -asadmin-classpath /sun/glassfishv2.1.1/glassfish3/glassfish/modules/admin-cli.jar -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -upgrade false -type INSTANCE -instancedir /sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1 -read-stdin true Nov 29, 2010 12:32:16 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 51 msec. Nov 29, 2010 12:32:30 PM null INFO: Running GlassFish Version: GlassFish Server Open Source Edition 3.1-SNAPSHOT (build adf-private) [#|2010-11-29T12:32:30.669-0500|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|This message was emitted before the logging service started:Total time to parse domain.xml: 401 milliseconds|#] [#|2010-11-29T12:32:33.017-0500|INFO|glassfish3.1|javax.enterprise.resource.jta.com.sun.enterprise.transaction|_ThreadID=17;_ThreadName=Thread-1;|DTX5019: Using [com.sun.enterprise.transaction.jts.JavaEETransactionManagerJTSDelegate] as the delegate|#] [#|2010-11-29T12:32:33.741-0500|INFO|glassfish3.1|com.sun.grizzly.config.GrizzlyServiceListener|_ThreadID=17;_ThreadName=Thread-1;|Perform lazy SSL initialization for the listener 'http-listener-2'|#] [#|2010-11-29T12:32:33.746-0500|INFO|glassfish3.1|null|_ThreadID=17;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 105ms - bound to [0.0.0.0:38080]|#] [#|2010-11-29T12:32:33.813-0500|INFO|glassfish3.1|null|_ThreadID=17;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 39ms - bound to [0.0.0.0:38181]|#] [#|2010-11-29T12:32:33.829-0500|INFO|glassfish3.1|null|_ThreadID=17;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 6ms - bound to [0.0.0.0:34848]|#] [#|2010-11-29T12:32:34.066-0500|INFO|glassfish3.1|org.hibernate.validator.util.Version|_ThreadID=17;_ThreadName=Thread-1;|Hibernate Validator 4.1.0.Final|#] [#|2010-11-29T12:32:34.076-0500|INFO|glassfish3.1|org.hibernate.validator.engine.resolver.DefaultTraversableResolver|_ThreadID=17;_ThreadName=Thread-1;|Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.|#] [#|2010-11-29T12:32:34.732-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:38080]|#] [#|2010-11-29T12:32:34.818-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:38181]|#] [#|2010-11-29T12:32:34.824-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:34848]|#] [#|2010-11-29T12:32:34.881-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0171: Created virtual server [server]|#] [#|2010-11-29T12:32:34.881-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [server]|#] [#|2010-11-29T12:32:34.885-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-29T12:32:34.885-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-29T12:32:34.885-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-29T12:32:36.428-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0172: Virtual server [server] loaded default web module []|#] [#|2010-11-29T12:32:36.428-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0172: Virtual server [server] loaded default web module []|#] [#|2010-11-29T12:32:36.428-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0172: Virtual server [server] loaded default web module []|#] [#|2010-11-29T12:32:40.816-0500|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=17;_ThreadName=Thread-1;|core.start_container_done|#] [#|2010-11-29T12:32:41.590-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1095: Member ins1 is joining group cluster1 ...|#] [#|2010-11-29T12:32:41.859-0500|INFO|glassfish3.1|com.sun.grizzly.config.GrizzlyServiceListener|_ThreadID=17;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.22 - 11/29/10 12:32 PM|#] [#|2010-11-29T12:32:41.913-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|starting cluster cluster1 for member:ins1|#] [#|2010-11-29T12:32:41.914-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1061: MasterNode message listener is registered for member: ins1 group: cluster1|#] [#|2010-11-29T12:32:41.925-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group cluster1 : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: ins1, MemberType: CORE, Address: 172.16.189.1:9115:228.8.7.9:12952:cluster1:ins1 |#] [#|2010-11-29T12:32:41.926-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : MASTER_CHANGE_EVENT for Member: ins1 of Group: cluster1|#] [#|2010-11-29T12:32:41.941-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: ins1 of group: cluster1|#] [#|2010-11-29T12:32:41.976-0500|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=17;_ThreadName=Thread-1;|GMSAD1005: Member ins1 joined group cluster1|#] [#|2010-11-29T12:32:41.978-0500|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=17;_ThreadName=Thread-1;|GMSAD1004: Started GMS for instance ins1 in group cluster1|#] [#|2010-11-29T12:32:41.983-0500|INFO|glassfish3.1|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=17;_ThreadName=Thread-1;|Registered org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2 for persistence-type = replication in BackingStoreFactoryRegistry|#] [#|2010-11-29T12:32:41.983-0500|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=17;_ThreadName=Thread-1;|Registered ReplicationStoreProxy with persistence-type = replication|#] [#|2010-11-29T12:32:41.985-0500|INFO|glassfish3.1|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=17;_ThreadName=Thread-1;|Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry|#] [#|2010-11-29T12:32:41.985-0500|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy|_ThreadID=17;_ThreadName=Thread-1;|Registered SHOAL BackingStore Proxy with persistence-type = replicated|#] [#|2010-11-29T12:32:42.020-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group cluster1 : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: ins1, MemberType: CORE, Address: 172.16.189.1:9115:228.8.7.9:12952:cluster1:ins1 2: MemberId: server, MemberType: SPECTATOR, Address: 172.16.189.1:9094:228.8.7.9:12952:cluster1:server |#] [#|2010-11-29T12:32:42.021-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : MASTER_CHANGE_EVENT for Member: server of Group: cluster1|#] [#|2010-11-29T12:32:42.021-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: server of group: cluster1|#] [#|2010-11-29T12:32:42.025-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group cluster1 : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: ins1, MemberType: CORE, Address: 172.16.189.1:9115:228.8.7.9:12952:cluster1:ins1 2: MemberId: server, MemberType: SPECTATOR, Address: 172.16.189.1:9094:228.8.7.9:12952:cluster1:server |#] [#|2010-11-29T12:32:42.025-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: ins1 of Group: cluster1|#] [#|2010-11-29T12:32:42.025-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1024: Adding Join member: ins1 group: cluster1 StartupState:GROUP_STARTUP |#] [#|2010-11-29T12:32:42.341-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group cluster1 : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: ins1, MemberType: CORE, Address: 172.16.189.1:9115:228.8.7.9:12952:cluster1:ins1 2: MemberId: ins2, MemberType: CORE, Address: 172.16.189.1:9116:228.8.7.9:12952:cluster1:ins2 3: MemberId: server, MemberType: SPECTATOR, Address: 172.16.189.1:9094:228.8.7.9:12952:cluster1:server |#] [#|2010-11-29T12:32:42.341-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: ins2 of Group: cluster1|#] [#|2010-11-29T12:32:42.341-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1024: Adding Join member: ins2 group: cluster1 StartupState:GROUP_STARTUP |#] [#|2010-11-29T12:32:42.864-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=17;_ThreadName=Thread-1;|SEC1002: Security Manager is OFF.|#] [#|2010-11-29T12:32:42.988-0500|WARNING|glassfish3.1|javax.enterprise.system.core.security|_ThreadID=17;_ThreadName=Thread-1;|Can not find resource bundle for this logger. class name that failed: com.sun.enterprise.security.ssl.impl.SecuritySupportImpl|#] [#|2010-11-29T12:32:42.992-0500|WARNING|glassfish3.1|javax.enterprise.system.core.security|_ThreadID=17;_ThreadName=Thread-1;|Can not find resource bundle for this logger. class name that failed: com.sun.enterprise.security.ssl.impl.SecuritySupportImpl|#] [#|2010-11-29T12:32:42.955-0500|SEVERE|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.ssl.impl|_ThreadID=17;_ThreadName=Thread-1;|java_security.expired_certificate|#] [#|2010-11-29T12:32:42.992-0500|WARNING|glassfish3.1|javax.enterprise.system.core.security|_ThreadID=17;_ThreadName=Thread-1;|Can not find resource bundle for this logger. class name that failed: com.sun.enterprise.security.ssl.impl.SecuritySupportImpl|#] [#|2010-11-29T12:32:43.041-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=17;_ThreadName=Thread-1;|SEC1010: Entering Security Startup Service|#] [#|2010-11-29T12:32:43.049-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=17;_ThreadName=Thread-1;|SEC1143: Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper.|#] [#|2010-11-29T12:32:43.347-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=17;_ThreadName=Thread-1;|SEC1115: Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#] [#|2010-11-29T12:32:43.362-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=17;_ThreadName=Thread-1;|SEC1115: Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#] [#|2010-11-29T12:32:43.551-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=17;_ThreadName=Thread-1;|SEC1115: Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created.|#] [#|2010-11-29T12:32:43.659-0500|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=17;_ThreadName=Thread-1;|SEC1011: Security Service(s) Started Successfully|#] [#|2010-11-29T12:32:44.667-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0129: Invalid Session Management Configuration for non-distributable app [HelloWorldWebApp] - defaulting to memory: persistence-type = [replicated] / persistenceFrequency = [web-method] / persistenceScope = [session]|#] [#|2010-11-29T12:32:44.667-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0129: Invalid Session Management Configuration for non-distributable app [HelloWorldWebApp] - defaulting to memory: persistence-type = [replicated] / persistenceFrequency = [web-method] / persistenceScope = [session]|#] [#|2010-11-29T12:32:44.667-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0129: Invalid Session Management Configuration for non-distributable app [HelloWorldWebApp] - defaulting to memory: persistence-type = [replicated] / persistenceFrequency = [web-method] / persistenceScope = [session]|#] [#|2010-11-29T12:32:44.911-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0671: Loading application [HelloWorldWebApp] at [/hello]|#] [#|2010-11-29T12:32:44.911-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0671: Loading application [HelloWorldWebApp] at [/hello]|#] [#|2010-11-29T12:32:44.911-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0671: Loading application [HelloWorldWebApp] at [/hello]|#] [#|2010-11-29T12:32:44.914-0500|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=17;_ThreadName=Thread-1;|CORE10010: Loading application HelloWorldWebApp done in 2,688 ms|#] [#|2010-11-29T12:32:45.771-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=17;_ThreadName=Thread-1;|ReplicationWebEventPersistentManager created|#] [#|2010-11-29T12:32:45.787-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=17;_ThreadName=Thread-1;|Create backing store invoked with persistence type replicated and store name /clusterjsp|#] [#|2010-11-29T12:32:45.789-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=17;_ThreadName=Thread-1;|About to create backing store BackingStoreConfiguration{clusterName='cluster1', instanceName='ins1', storeName='/clusterjsp', shortUniqueName='null', storeType='replicated', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='null', keyClazz=class java.lang.String, valueClazz=class org.glassfish.web.ha.session.management.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={async.replication=true}}|#] [#|2010-11-29T12:32:45.847-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|Entered GlassFish2ShoalBackingStoreFactory.createBackingStore...|#] [#|2010-11-29T12:32:45.854-0500|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=17;_ThreadName=Thread-1;|DefaultKeyMapper created for: myName: ins1; groupName: cluster1|#] [#|2010-11-29T12:32:45.863-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|** SET ASYNC REPLICATUION: false|#] [#|2010-11-29T12:32:45.867-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|**GroupServiceProvider:: GMS module for group cluster1 should have been started by now GMS: com.sun.enterprise.ee.cms.impl.common.GroupManagementServiceImpl@264ab70a|#] [#|2010-11-29T12:32:45.868-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|**GroupServiceProvider:: REGISTERED member event listeners for => |#] [#|2010-11-29T12:32:45.876-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|Created ExecutorService with: core=16; max=16; keepAlive=300; maxLimit=32768|#] [#|2010-11-29T12:32:45.882-0500|INFO|glassfish3.1|org.shoal.ha.cache.store|_ThreadID=17;_ThreadName=Thread-1;|Created ReplicatedDataStore with config: DataStoreConfigurator{instanceName='ins1', groupName='cluster1', storeName='/clusterjsp', keyClazz=class java.lang.String, valueClazz=class org.glassfish.web.ha.session.management.SimpleMetadata, keyMapper=org.shoal.ha.store.GlassFishKeyMapper@4368cef6, dataStoreKeyHelper=org.shoal.ha.cache.api.ObjectKeyHelper@15eb9b0d, startGMS=false, cacheLocally= false, clazzLoader=178.0, dataStoreEntryHelper=org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper@1f107db9, doSynchronousReplication=false, objectInputOutputStreamFactory=org.shoal.ha.cache.api.DefaultObjectInputOutputStreamFactory@73ae0c9d}|#] [#|2010-11-29T12:32:45.883-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|GlassFish2ShoalBackingStoreFactory:: CREATED an instance of: org.shoal.adapter.store.ReplicatedBackingStore|#] [#|2010-11-29T12:32:46.046-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0671: Loading application [clusterjsp#clusterjsp.war] at [/clusterjsp]|#] [#|2010-11-29T12:32:46.046-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=17;_ThreadName=Thread-1;|WEB0671: Loading application [clusterjsp#clusterjsp.war] at [/clusterjsp]|#] [#|2010-11-29T12:32:46.046-0500|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0671: Loading application [clusterjsp#clusterjsp.war] at [/clusterjsp]|#] [#|2010-11-29T12:32:46.047-0500|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=17;_ThreadName=Thread-1;|CORE10010: Loading application clusterjsp done in 1,131 ms|#] [#|2010-11-29T12:32:46.055-0500|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=17;_ThreadName=Thread-1;|GlassFish Server Open Source Edition 3.1-SNAPSHOT (adf-private) startup time : Felix (11,788ms), startup services(16,696ms), total(28,484ms)|#] [#|2010-11-29T12:32:46.082-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1099: GMS:Reporting Joined and Ready state to group cluster1|#] [#|2010-11-29T12:32:46.082-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1040: Calling reportMyState() with READY...|#] [#|2010-11-29T12:32:46.123-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group cluster1 : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: ins1, MemberType: CORE, Address: 172.16.189.1:9115:228.8.7.9:12952:cluster1:ins1 2: MemberId: ins2, MemberType: CORE, Address: 172.16.189.1:9116:228.8.7.9:12952:cluster1:ins2 3: MemberId: server, MemberType: SPECTATOR, Address: 172.16.189.1:9094:228.8.7.9:12952:cluster1:server |#] [#|2010-11-29T12:32:46.123-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : JOINED_AND_READY_EVENT for Member: ins1 of Group: cluster1|#] [#|2010-11-29T12:32:46.123-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : ins1 Group: :cluster1 StartupState:GROUP_STARTUP |#] [#|2010-11-29T12:32:46.134-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 1; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@4dad4b69 [current: ins1] [previous: ]|#] [#|2010-11-29T12:32:46.134-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-11-29T12:32:46.197-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|Failed to start: org.apache.felix.shell.remote|#] [#|2010-11-29T12:32:46.201-0500|SEVERE|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|org.osgi.framework.BundleException: Activator start error in bundle org.apache.felix.shell.remote [42]. at org.apache.felix.framework.Felix.activateBundle(Felix.java:1869) at org.apache.felix.framework.Felix.startBundle(Felix.java:1739) at org.apache.felix.framework.BundleImpl.start(BundleImpl.java:922) at org.glassfish.kernel.GlassFishActivator.startBundle(GlassFishActivator.java:240) at org.glassfish.kernel.GlassFishActivator.startPostStartupBundles(GlassFishActivator.java:217) at org.glassfish.kernel.GlassFishActivator.access$300(GlassFishActivator.java:73) at org.glassfish.kernel.GlassFishActivator$2.run(GlassFishActivator.java:285) Caused by: java.net.BindException: Address already in use at java.net.PlainSocketImpl.socketBind(Native Method) at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365) at java.net.ServerSocket.bind(ServerSocket.java:319) at java.net.ServerSocket.(ServerSocket.java:185) at org.apache.felix.shell.remote.Listener$Acceptor.(Listener.java:117) at org.apache.felix.shell.remote.Listener.(Listener.java:59) at org.apache.felix.shell.remote.Activator.start(Activator.java:36) at org.apache.felix.framework.util.SecureAction.startActivator(SecureAction.java:633) at org.apache.felix.framework.Felix.activateBundle(Felix.java:1822) ... 6 more |#] [#|2010-11-29T12:32:46.303-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|____________________________ Welcome to Apache Felix Gogo |#] [#|2010-11-29T12:32:46.432-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group cluster1 : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: ins1, MemberType: CORE, Address: 172.16.189.1:9115:228.8.7.9:12952:cluster1:ins1 2: MemberId: ins2, MemberType: CORE, Address: 172.16.189.1:9116:228.8.7.9:12952:cluster1:ins2 3: MemberId: server, MemberType: SPECTATOR, Address: 172.16.189.1:9094:228.8.7.9:12952:cluster1:server |#] [#|2010-11-29T12:32:46.432-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : JOINED_AND_READY_EVENT for Member: ins2 of Group: cluster1|#] [#|2010-11-29T12:32:46.432-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : ins2 Group: :cluster1 StartupState:GROUP_STARTUP |#] [#|2010-11-29T12:32:46.462-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|**VIEW: prevViewId: 1; curViewID: 2; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@4cc3507d [current: ins1, ins2] [previous: ]|#] [#|2010-11-29T12:32:46.462-0500|INFO|glassfish3.1|ShoalLogger|_ThreadID=17;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-11-29T12:32:47.023-0500|INFO|glassfish3.1|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=17;_ThreadName=Thread-1;|Recovery of Inbound Transactions started.|#] [#|2010-11-29T12:32:50.785-0500|INFO|glassfish3.1|org.hibernate.validator.engine.resolver.DefaultTraversableResolver|_ThreadID=17;_ThreadName=Thread-1;|Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.|#] [#|2010-11-29T12:32:51.824-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|/sun/glassfishv2.1.1/glassfish3/glassfish/nodes/na1/ins1/autodeploy/bundles does not exist, please create it.|#] [#|2010-11-29T12:32:54.512-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-29T12:32:54.512-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-29T12:32:54.513-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-29T12:32:54.513-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-29T12:32:54.629-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-29T12:32:54.629-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-29T12:32:54.629-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-29T12:32:54.630-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-29T12:32:55.475-0500|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=17;_ThreadName=Thread-1;| JPAExtender started|#] [#|2010-11-29T12:32:56.925-0500|INFO|glassfish3.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=17;_ThreadName=Thread-1;|JTS5014: Recoverable JTS instance, serverId = [100]|#] [#|2010-11-29T12:32:57.715-0500|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=17;_ThreadName=Thread-1;|addresslist.setjmsservice.provider|#] [#|2010-11-29T12:32:57.733-0500|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=17;_ThreadName=Thread-1;|jms.connection.url|#] [#|2010-11-29T12:32:57.776-0500|INFO|glassfish3.1|org.hibernate.validator.engine.resolver.DefaultTraversableResolver|_ThreadID=17;_ThreadName=Thread-1;|Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.|#] [#|2010-11-29T12:32:57.970-0500|INFO|glassfish3.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=17;_ThreadName=Thread-1;|MQJMSRA_RA1101: SJSMQ JMS Resource Adapter starting: broker is LOCAL, connection mode is TCP|#] [#|2010-11-29T12:33:00.242-0500|WARNING|glassfish3.1|javax.jms|_ThreadID=17;_ThreadName=Thread-1;|[C4003]: Error occurred on connection creation [localhost:37676]. - cause: java.net.ConnectException: Connection refused|#] [#|2010-11-29T12:33:02.520-0500|INFO|glassfish3.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=17;_ThreadName=Thread-1;|MQJMSRA_RA1101: SJSMQ JMSRA Started:LOCAL|#] [#|2010-11-29T12:33:02.604-0500|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=17;_ThreadName=Thread-1;|[Thread[GlassFish Kernel Main Thread,5,main]] started|#] [#|2010-11-29T12:33:02.617-0500|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=17;_ThreadName=Thread-1;|Binding RMI port to *:38686|#] [#|2010-11-29T12:33:02.860-0500|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=17;_ThreadName=Thread-1;|JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://dhcp-burlington9-3rd-a-east-10-152-22-229.usdhcp.oraclecorp.com:38686/jndi/rmi://dhcp-burlington9-3rd-a-east-10-152-22-229.usdhcp.oraclecorp.com:38686/jmxrmi|#] [#|2010-11-29T13:18:26.030-0500|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=17;_ThreadName=Thread-1;|Add to session: foo = bar|#]