Nov 19, 2010 4:34:43 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /export/ha/jdk/bin/java -cp /export/appcvs/glassfish/glassfish3/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:MaxPermSize=192m -XX:NewRatio=2 -Xmx512m -javaagent:/export/appcvs/glassfish/glassfish3/glassfish/lib/monitor/btrace-agent.jar=unsafe=true,noServer=true -server -Dosgi.shell.telnet.maxconn=1 -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver -Dfelix.fileinstall.dir=/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/ -Djavax.net.ssl.keyStore=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/config/keystore.jks -Dosgi.shell.telnet.port=26668 -Djava.security.policy=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/config/server.policy -Dfelix.fileinstall.log.level=3 -Dfelix.fileinstall.poll=5000 -Dcom.sun.aas.instanceRoot=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103 -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=/export/appcvs/glassfish/glassfish3/glassfish/modules/endorsed:/export/appcvs/glassfish/glassfish3/glassfish/lib/endorsed -Dcom.sun.aas.installRoot=/export/appcvs/glassfish/glassfish3/glassfish -Dfelix.fileinstall.bundles.startTransient=true -Djava.ext.dirs=/export/ha/jdk/lib/ext:/export/ha/jdk/jre/lib/ext:/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/lib/ext -Dfelix.fileinstall.bundles.new.start=true -Djavax.net.ssl.trustStore=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/config/cacerts.jks -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as -Djava.security.auth.login.config=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/config/login.conf -DANTLR_USE_DIRECT_CLASS_LOADING=true -Dgosh.args=--noshutdown -c noop=true -Djava.library.path=/export/appcvs/glassfish/glassfish3/glassfish/lib:/export/ha/jdk/jre/lib/sparc/server:/export/ha/jdk/jre/lib/sparc:/export/ha/jdk/lib/sparc:/export/ha/jdk/jre/lib/sparc/client:/usr/jdk/packages/lib/sparc:/lib:/usr/lib com.sun.enterprise.glassfish.bootstrap.ASMain -asadmin-args --host,,,localhost,,,--port,,,4848,,,--secure=false,,,--terse=false,,,--echo=false,,,--interactive=false,,,start-local-instance,,,--verbose=false,,,--debug=false,,,--node,,,agent1,,,instance103 -instancename instance103 -verbose false -debug false -asadmin-classpath /export/appcvs/glassfish/glassfish3/glassfish/modules/admin-cli.jar -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -upgrade false -type INSTANCE -instancedir /export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103 -read-stdin true Nov 19, 2010 4:34:43 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 84 msec. Nov 19, 2010 4:35:04 PM null INFO: Running GlassFish Version: GlassFish Server Open Source Edition 3.1-b30 (build 30) [#|2010-11-19T16:35:04.877-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.server.logging|_ThreadID=16;_ThreadName=Thread-1;|This message was emitted before the logging service started:Total time to parse domain.xml: 722 milliseconds|#] [#|2010-11-19T16:35:05.959-0800|INFO|glassfish3.1|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=16;_ThreadName=Thread-1;|Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry|#] [#|2010-11-19T16:35:05.965-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy|_ThreadID=16;_ThreadName=Thread-1;|Registered SHOAL BackingStore Proxy with persistence-type = replicated|#] [#|2010-11-19T16:35:05.974-0800|INFO|glassfish3.1|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=16;_ThreadName=Thread-1;|Registered org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2 for persistence-type = replication in BackingStoreFactoryRegistry|#] [#|2010-11-19T16:35:05.975-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=16;_ThreadName=Thread-1;|Registered ReplicationStoreProxy with persistence-type = replication|#] [#|2010-11-19T16:35:06.149-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|clusterName=st-cluster clusterConfig=GlassFishConfigBean.com.sun.enterprise.config.serverbeans.Config|#] [#|2010-11-19T16:35:06.275-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|processing cluster property name=GMS_LISTENER_PORT value= ${GMS_LISTENER_PORT-st-cluster}|#] [#|2010-11-19T16:35:06.275-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|skipping cluster property name=GMS_LISTENER_PORT since value is unresolved symbolic token=${GMS_LISTENER_PORT-st-cluster}|#] [#|2010-11-19T16:35:06.277-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|Printing all GMS properties: |#] [#|2010-11-19T16:35:06.519-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1095: Member instance103 is joining group st-cluster ...|#] [#|2010-11-19T16:35:06.712-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|instantiated following NetworkManager implementation:com.sun.enterprise.mgmt.transport.grizzly.GrizzlyNetworkManager|#] [#|2010-11-19T16:35:06.723-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS MAX_MESSAGE_LENGTH=4196352|#] [#|2010-11-19T16:35:06.727-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;| GrizzlyNetworkManager Configuration BIND_INTERFACE_ADDRESS:null NetworkInterfaceName:null TCPSTARTPORT..TCPENDPORT:9090..9120 MULTICAST_ADDRESS:MULTICAST_PORT:228.9.44.27:13887 MULTICAST_PACKET_SIZE:65536 MULTICAST_TIME_TO_LIVE: default FAILURE_DETECT_TCP_RETRANSMIT_TIMEOUT(ms):10000 ThreadPool CORE_POOLSIZE:20 MAX_POOLSIZE:50 POOL_QUEUE_SIZE:4096 KEEP_ALIVE_TIME(ms):60000 HIGH_WATER_MARK:1024 NUMBER_TO_RECLAIM:10 MAX_PARALLEL:15 START_TIMEOUT(ms):15000 WRITE_TIMEOUT(ms):10000 MAX_WRITE_SELECTOR_POOL_SIZE:30 VIRTUAL_MULTICAST_URI_LIST:null |#] [#|2010-11-19T16:35:06.962-0800|INFO|glassfish3.1|grizzly|_ThreadID=16;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.22 - 11/19/10 4:35 PM|#] [#|2010-11-19T16:35:07.119-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Grizzly controller listening on /0.0.0.0:9119. Controller started in 200 ms|#] [#|2010-11-19T16:35:07.146-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Set via default minimum: MulticastSocket.getTimeToLive()=4|#] [#|2010-11-19T16:35:07.149-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MulticastSocket configuration: local socket address: 0.0.0.0/0.0.0.0:13887 network interface: name:e1000g0 (e1000g0) index: 2 addresses: /10.133.169.53; multicast address:/228.9.44.27 timeToLive=4|#] [#|2010-11-19T16:35:07.226-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|HealthMonitor: heartBeatTimeout(ms)=2000 maxMissedBeats=3 failureDetectionTCPTimeout(ms)=10000 failureDetectionTCPPort=9000|#] [#|2010-11-19T16:35:07.233-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|starting cluster st-cluster for member:instance103|#] [#|2010-11-19T16:35:07.234-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1061: MasterNode message listener is registered for member: instance103 group: st-cluster|#] [#|2010-11-19T16:35:07.257-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1005: Member instance103 joined group st-cluster|#] [#|2010-11-19T16:35:07.257-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1004: Started GMS for instance instance103 in group st-cluster|#] [#|2010-11-19T16:35:07.258-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 |#] [#|2010-11-19T16:35:07.259-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : MASTER_CHANGE_EVENT for Member: instance103 of Group: st-cluster|#] [#|2010-11-19T16:35:07.267-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: instance103 of group: st-cluster|#] [#|2010-11-19T16:35:07.374-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(summary): GMSMember name: instance103 group: st-cluster memberType: CORE startTime: Nov 19, 2010 4:35:06 PM PST ID:10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 TCP uri(s):[tcp://10.133.169.53:4000]|#] [#|2010-11-19T16:35:07.377-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(dump)=PEERID[10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103] CUSTOMTAGS[GROUP_NAME:st-cluster, MEMBER_TYPE:CORE, START_TIME[4:35:06 PM PST on Nov 19, 2010]], ENDPONTADDRESSES[[tcp://10.133.169.53:4000]], NAME:instance103, OSNAME:SunOS, OSVERSION:5.10, OSARCH:sparc, HWARCH:sparc, HWVENDOR:Sun Microsystems Inc.|#] [#|2010-11-19T16:35:07.414-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: instance101, MemberType: CORE, Address: 10.133.169.53:9115:228.9.44.27:13887:st-cluster:instance101 2: MemberId: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102 3: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server |#] [#|2010-11-19T16:35:07.414-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : MASTER_CHANGE_EVENT for Member: server of Group: st-cluster|#] [#|2010-11-19T16:35:07.414-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: server of group: st-cluster|#] [#|2010-11-19T16:35:07.418-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: instance101 group: st-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-19T16:35:07.423-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: instance102 group: st-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-19T16:35:07.426-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: instance101, MemberType: CORE, Address: 10.133.169.53:9115:228.9.44.27:13887:st-cluster:instance101 2: MemberId: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102 3: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server |#] [#|2010-11-19T16:35:07.426-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: instance103 of Group: st-cluster|#] [#|2010-11-19T16:35:07.428-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: instance103 group: st-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-19T16:35:09.540-0800|INFO|glassfish3.1|javax.enterprise.resource.jta.com.sun.enterprise.transaction|_ThreadID=16;_ThreadName=Thread-1;|DTX5019: Using [com.sun.enterprise.transaction.jts.JavaEETransactionManagerJTSDelegate] as the delegate|#] [#|2010-11-19T16:35:10.797-0800|INFO|glassfish3.1|com.sun.grizzly.config.GrizzlyServiceListener|_ThreadID=16;_ThreadName=Thread-1;|Perform lazy SSL initialization for the listener 'http-listener-2'|#] [#|2010-11-19T16:35:10.905-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 78ms - bound to [0.0.0.0:28183]|#] [#|2010-11-19T16:35:10.905-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 28ms - bound to [0.0.0.0:24850]|#] [#|2010-11-19T16:35:10.905-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 303ms - bound to [0.0.0.0:28082]|#] [#|2010-11-19T16:35:11.032-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 30ms - bound to [127.0.0.1:27678]|#] [#|2010-11-19T16:35:11.433-0800|INFO|glassfish3.1|org.hibernate.validator.util.Version|_ThreadID=16;_ThreadName=Thread-1;|Hibernate Validator null|#] [#|2010-11-19T16:35:11.466-0800|INFO|glassfish3.1|org.hibernate.validator.engine.resolver.DefaultTraversableResolver|_ThreadID=16;_ThreadName=Thread-1;|Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.|#] [#|2010-11-19T16:35:12.913-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:28082]|#] [#|2010-11-19T16:35:12.953-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:28183]|#] [#|2010-11-19T16:35:12.989-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:24850]|#] [#|2010-11-19T16:35:13.182-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0171: Created virtual server [server]|#] [#|2010-11-19T16:35:13.182-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [server]|#] [#|2010-11-19T16:35:13.198-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-19T16:35:13.198-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-19T16:35:13.198-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-19T16:35:16.125-0800|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-19T16:35:16.125-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0172: Virtual server [server] loaded default web module []|#] [#|2010-11-19T16:35:16.125-0800|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-19T16:35:19.678-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=16;_ThreadName=Thread-1;|core.start_container_done|#] [#|2010-11-19T16:35:22.013-0800|SEVERE|glassfish3.1|javax.enterprise.system.tools.deployment.org.glassfish.deployment.common|_ThreadID=16;_ThreadName=Thread-1;|DPL8006: get/add descriptor failure : module-name TO SLSBJar|#] [#|2010-11-19T16:35:22.066-0800|SEVERE|glassfish3.1|javax.enterprise.system.tools.deployment.org.glassfish.deployment.common|_ThreadID=16;_ThreadName=Thread-1;|DPL8007: Unsupported deployment descriptors element min-beans-in-pool value 0|#] [#|2010-11-19T16:35:22.067-0800|SEVERE|glassfish3.1|javax.enterprise.system.tools.deployment.org.glassfish.deployment.common|_ThreadID=16;_ThreadName=Thread-1;|DPL8007: Unsupported deployment descriptors element initial-beans-in-pool value 0|#] [#|2010-11-19T16:35:22.068-0800|SEVERE|glassfish3.1|javax.enterprise.system.tools.deployment.org.glassfish.deployment.common|_ThreadID=16;_ThreadName=Thread-1;|DPL8007: Unsupported deployment descriptors element bean-increment-count value 60|#] [#|2010-11-19T16:35:22.071-0800|SEVERE|glassfish3.1|javax.enterprise.system.tools.deployment.org.glassfish.deployment.common|_ThreadID=16;_ThreadName=Thread-1;|DPL8007: Unsupported deployment descriptors element idle-in-pool-timeout-in-seconds value 900|#] [#|2010-11-19T16:35:23.111-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1002: Security Manager is OFF.|#] [#|2010-11-19T16:35:23.765-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1010: Entering Security Startup Service|#] [#|2010-11-19T16:35:23.784-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1143: Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper.|#] [#|2010-11-19T16:35:24.039-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=16;_ThreadName=Thread-1;|SEC1115: Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#] [#|2010-11-19T16:35:24.044-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=16;_ThreadName=Thread-1;|SEC1115: Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#] [#|2010-11-19T16:35:24.078-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=16;_ThreadName=Thread-1;|SEC1115: Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created.|#] [#|2010-11-19T16:35:24.123-0800|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1011: Security Service(s) Started Successfully|#] [#|2010-11-19T16:35:39.069-0800|INFO|glassfish3.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=16;_ThreadName=Thread-1;|JTS5014: Recoverable JTS instance, serverId = [23702]|#] [#|2010-11-19T16:35:41.798-0800|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers|_ThreadID=16;_ThreadName=Thread-1;|Portable JNDI names for EJB SLSB : [java:global/SLSB/SLSBJar/SLSB!SLSBLocalHomeRef, java:global/SLSB/SLSBJar/SLSB!SLSBRemoteHomeRef]|#] [#|2010-11-19T16:35:41.812-0800|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers|_ThreadID=16;_ThreadName=Thread-1;|Glassfish-specific (Non-portable) JNDI names for EJB SLSB : [ejb/SLSB]|#] [#|2010-11-19T16:35:42.331-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=16;_ThreadName=Thread-1;|ReplicationWebEventPersistentManager created|#] [#|2010-11-19T16:35:42.401-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=16;_ThreadName=Thread-1;|Create backing store invoked with persistence type replicated and store name /SLSBRefFO|#] [#|2010-11-19T16:35:42.405-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=16;_ThreadName=Thread-1;|About to create backing store BackingStoreConfiguration{clusterName='st-cluster', instanceName='instance103', storeName='/SLSBRefFO', 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.CompositeMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={async.replication=true}}|#] [#|2010-11-19T16:35:42.467-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Entered GlassFish2ShoalBackingStoreFactory.createBackingStore...|#] [#|2010-11-19T16:35:42.476-0800|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper created for: myName: instance103; groupName: st-cluster|#] [#|2010-11-19T16:35:42.520-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|** SET ASYNC REPLICATUION: false|#] [#|2010-11-19T16:35:42.555-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:: GMS module for group st-cluster should have been started by now GMS: com.sun.enterprise.ee.cms.impl.common.GroupManagementServiceImpl@44e76c|#] [#|2010-11-19T16:35:42.557-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:: REGISTERED member event listeners for => |#] [#|2010-11-19T16:35:42.560-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-11-19T16:35:42.579-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**DataStorecontext.setDoSyncReplication = false|#] [#|2010-11-19T16:35:42.588-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Created ExecutorService with: core=16; max=16; keepAlive=300; maxLimit=32768|#] [#|2010-11-19T16:35:42.594-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-11-19T16:35:42.594-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-11-19T16:35:42.598-0800|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|ReplicationFramework initialized. Configuration: DataStoreConfigurator{instanceName='instance103', groupName='st-cluster', storeName='/SLSBRefFO', keyClazz=class java.lang.String, valueClazz=class org.glassfish.web.ha.session.management.CompositeMetadata, keyMapper=org.shoal.ha.store.GlassFishKeyMapper@42058f, dataStoreKeyHelper=org.shoal.ha.cache.api.ObjectKeyHelper@7b28dc, startGMS=false, cacheLocally= false, clazzLoader=96.0, dataStoreEntryHelper=org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper@1b7c463, doSynchronousReplication=false, objectInputOutputStreamFactory=org.shoal.ha.cache.api.DefaultObjectInputOutputStreamFactory@1be645d}|#] [#|2010-11-19T16:35:43.630-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: instance101, MemberType: CORE, Address: 10.133.169.53:9115:228.9.44.27:13887:st-cluster:instance101 2: MemberId: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102 3: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server |#] [#|2010-11-19T16:35:43.630-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : JOINED_AND_READY_EVENT for Member: instance101 of Group: st-cluster|#] [#|2010-11-19T16:35:43.631-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : instance101 Group: :st-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-19T16:35:43.647-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: instance101; previousView ReplicaChoices[instance101]: instance101 |#] [#|2010-11-19T16:35:43.662-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: instance101; previousView ReplicaChoices[instance101]: instance101 |#] [#|2010-11-19T16:35:43.663-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 1; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@90c1ee [current: instance101] [previous: ]|#] [#|2010-11-19T16:35:43.663-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-11-19T16:35:43.713-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: instance101, MemberType: CORE, Address: 10.133.169.53:9115:228.9.44.27:13887:st-cluster:instance101 2: MemberId: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102 3: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server |#] [#|2010-11-19T16:35:43.713-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : JOINED_AND_READY_EVENT for Member: instance102 of Group: st-cluster|#] [#|2010-11-19T16:35:43.714-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : instance102 Group: :st-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-19T16:35:43.730-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: instance101 : instance102; previousView instance101 ReplicaChoices[instance101]: instance101 ReplicaChoices[instance102]: instance102 |#] [#|2010-11-19T16:35:43.733-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: instance101 : instance102; previousView instance101 ReplicaChoices[instance101]: instance101 ReplicaChoices[instance102]: instance102 |#] [#|2010-11-19T16:35:43.734-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 1; curViewID: 2; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@c82d1f [current: instance101, instance102] [previous: instance101]|#] [#|2010-11-19T16:35:43.734-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-11-19T16:35:45.153-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0671: Loading application [SLSB#SLSBWar.war] at [SLSBRefFO]|#] [#|2010-11-19T16:35:45.153-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0671: Loading application [SLSB#SLSBWar.war] at [SLSBRefFO]|#] [#|2010-11-19T16:35:45.153-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0671: Loading application [SLSB#SLSBWar.war] at [SLSBRefFO]|#] [#|2010-11-19T16:35:45.159-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|CORE10010: Loading application SLSB done in 25,200 ms|#] [#|2010-11-19T16:35:45.163-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|GlassFish Server Open Source Edition 3.1-b30 (30) startup time : Felix (19,467ms), startup services(41,696ms), total(61,163ms)|#] [#|2010-11-19T16:35:45.169-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1099: GMS:Reporting Joined and Ready state to group st-cluster|#] [#|2010-11-19T16:35:45.170-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1040: Calling reportMyState() with READY...|#] [#|2010-11-19T16:35:45.223-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: instance101, MemberType: CORE, Address: 10.133.169.53:9115:228.9.44.27:13887:st-cluster:instance101 2: MemberId: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102 3: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server |#] [#|2010-11-19T16:35:45.223-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : JOINED_AND_READY_EVENT for Member: instance103 of Group: st-cluster|#] [#|2010-11-19T16:35:45.223-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : instance103 Group: :st-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-19T16:35:45.232-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: instance101 : instance102; previousView ReplicaChoices[instance101]: instance101 ReplicaChoices[instance102]: instance102 |#] [#|2010-11-19T16:35:45.235-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: true) currentView: instance101 : instance102; previousView ReplicaChoices[instance101]: instance101 ReplicaChoices[instance102]: instance102 |#] [#|2010-11-19T16:35:45.236-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 2; curViewID: 3; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@1620d83 [current: instance101, instance102, instance103] [previous: ]|#] [#|2010-11-19T16:35:45.236-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-11-19T16:35:45.767-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|____________________________ Welcome to Apache Felix Gogo |#] [#|2010-11-19T16:35:45.848-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/autodeploy/bundles does not exist, please create it.|#] [#|2010-11-19T16:35:45.965-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-19T16:35:45.966-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-19T16:35:45.967-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-19T16:35:45.968-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-19T16:35:45.985-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|Binding RMI port to *:28688|#] [#|2010-11-19T16:35:46.062-0800|INFO|glassfish3.1|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=16;_ThreadName=Thread-1;|Recovery of Inbound Transactions started.|#] [#|2010-11-19T16:35:46.118-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-19T16:35:46.119-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/org.apache.felix.eventadmin.jar|#] [#|2010-11-19T16:35:46.119-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-19T16:35:46.120-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-19T16:35:46.121-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-19T16:35:46.164-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-cdi.jar|#] [#|2010-11-19T16:35:46.672-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|[Thread[GlassFish Kernel Main Thread,5,main]] started|#] [#|2010-11-19T16:35:47.358-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-jpa.jar|#] [#|2010-11-19T16:35:47.701-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://hat2k1:28688/jndi/rmi://hat2k1:28688/jmxrmi|#] [#|2010-11-19T16:35:47.703-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|connection OBJ Name while creation = jmxremote:type=jmx-connector-server,protocol=rmi_jrmp,name=system|#] [#|2010-11-19T16:35:47.739-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Registered the connector :: jmxremote:type=jmx-connector-server,protocol=rmi_jrmp,name=system|#] [#|2010-11-19T16:35:47.769-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|[Fatal Error] servicetag-registry.xml:1:1: Premature end of file. |#] [#|2010-11-19T16:35:47.830-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Premature end of file.|#] [#|2010-11-19T16:35:50.369-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2010-11-19T16:35:50.393-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|ExtenderManager starting|#] [#|2010-11-19T16:35:50.406-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijpa.JPAExtender@ded763|#] [#|2010-11-19T16:35:50.407-0800|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=16;_ThreadName=Thread-1;| JPAExtender started|#] [#|2010-11-19T16:35:50.414-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijavaeebase.JavaEEExtender@1ef8774|#] [#|2010-11-19T16:35:50.437-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2010-11-19T16:35:50.547-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiweb.WebExtender@628e1|#] [#|2010-11-19T16:35:50.595-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2010-11-19T16:35:50.633-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijdbc.JDBCExtender@15e4373|#] [#|2010-11-19T16:35:50.650-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-jdbc.jar|#] [#|2010-11-19T16:35:50.684-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijta.JTAExtender@1fd1c60|#] [#|2010-11-19T16:35:50.727-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-jta.jar|#] [#|2010-11-19T16:35:50.829-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2010-11-19T16:35:50.870-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiejb.EJBExtender@154614c|#] [#|2010-11-19T16:35:50.895-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-ejb-container.jar|#] [#|2010-11-19T16:35:50.930-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgi.ee.resources.ResourcesExtender@10f7c0d|#] [#|2010-11-19T16:35:50.972-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/osgi-ee-resources.jar|#] [#|2010-11-19T16:35:56.038-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/org.apache.felix.fileinstall-autodeploy-bundles.cfg|#] [#|2010-11-19T16:35:56.042-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance103/autodeploy/bundles does not exist, please create it.|#] [#|2010-11-19T16:35:56.084-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/appcvs/glassfish/glassfish3/glassfish/modules/autostart/org.apache.felix.webconsole.internal.servlet.OsgiManager.cfg|#] [#|2010-11-19T16:36:43.478-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:36:43.488-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:36:43.528-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredCommand:39(30000) from instance101|#] [#|2010-11-19T16:36:43.553-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:36:43.553-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance102|#] [#|2010-11-19T16:36:43.555-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredCommand:39(30000) from instance102|#] [#|2010-11-19T16:36:43.754-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 539|#] [#|2010-11-19T16:36:43.774-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance102; size: 539|#] [#|2010-11-19T16:36:45.046-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 559|#] [#|2010-11-19T16:36:45.067-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance102; size: 559|#] [#|2010-11-19T16:36:45.226-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:36:45.226-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance102|#] [#|2010-11-19T16:36:45.229-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredResultCommand:44(result=0) from instance102|#] [#|2010-11-19T16:36:45.230-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance103For tokenId = 1 received remove_expired_response value=0|#] [#|2010-11-19T16:36:45.248-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:36:45.248-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:36:45.251-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredResultCommand:44(result=0) from instance101|#] [#|2010-11-19T16:36:45.251-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance103For tokenId = 1 received remove_expired_response value=0|#] [#|2010-11-19T16:37:03.826-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:37:03.826-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance102|#] [#|2010-11-19T16:37:03.833-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received StoreableLoadRequestCommand{key=6ad349c11ec4e798542caf3d5765, minimumRequiredVersion=-9223372036854775808, resp=null, tokenId=4, originatingInstance='instance102'} from instance102|#] [#|2010-11-19T16:37:03.835-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=execute;|instance103StoreableLoadRequestCommand:69 received load_request command for 6ad349c11ec4e798542caf3d5765from instance102|#] [#|2010-11-19T16:37:03.836-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=execute;|instance103StoreableLoadRequestCommand:69 will send 6ad349c11ec4e798542caf3d5765 => null to instance102|#] [#|2010-11-19T16:37:03.843-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_response|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadResponseCommand;MethodName=writeObject;|instance103StoreableLoadResponseCommand:72 sending load_response command for 6ad349c11ec4e798542caf3d5765 to instance102; null|#] [#|2010-11-19T16:37:03.966-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance102; size: 651|#] [#|2010-11-19T16:37:43.960-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:37:43.960-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance102|#] [#|2010-11-19T16:37:43.962-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredCommand:39(30000) from instance102|#] [#|2010-11-19T16:37:43.962-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:37:43.964-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:37:43.966-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredCommand:39(30000) from instance101|#] [#|2010-11-19T16:37:44.153-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 539|#] [#|2010-11-19T16:37:44.173-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance102; size: 539|#] [#|2010-11-19T16:37:45.447-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 559|#] [#|2010-11-19T16:37:45.465-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance102; size: 559|#] [#|2010-11-19T16:37:45.625-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:37:45.625-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance102|#] [#|2010-11-19T16:37:45.629-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredResultCommand:44(result=0) from instance102|#] [#|2010-11-19T16:37:45.629-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance103For tokenId = 2 received remove_expired_response value=0|#] [#|2010-11-19T16:37:45.645-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:37:45.645-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:37:45.648-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredResultCommand:44(result=0) from instance101|#] [#|2010-11-19T16:37:45.648-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance103For tokenId = 2 received remove_expired_response value=0|#] [#|2010-11-19T16:37:49.666-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group st-cluster : Members in view for PEER_STOP_EVENT(before change analysis) are : 1: MemberId: instance101, MemberType: CORE, Address: 10.133.169.53:9115:228.9.44.27:13887:st-cluster:instance101 2: MemberId: instance103, MemberType: CORE, Address: 10.133.169.53:9119:228.9.44.27:13887:st-cluster:instance103 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server |#] [#|2010-11-19T16:37:49.666-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : PEER_STOP_EVENT for Member: instance102 of Group: st-cluster|#] [#|2010-11-19T16:37:49.667-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1017: Received PlannedShutdownEvent Announcement from Instance instance102 with Shutdown type =INSTANCE_SHUTDOWN of Group: st-cluster|#] [#|2010-11-19T16:37:49.673-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1008: Sending PlannedShutdownSignals to registered Actions for shutdownType INSTANCE_SHUTDOWN Member: instance102...|#] [#|2010-11-19T16:37:49.677-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: false) currentView: instance101; previousView instance101 : instance103 ReplicaChoices[instance101]: instance101 |#] [#|2010-11-19T16:37:49.679-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance103].onViewChange (isJoin: false) currentView: instance101; previousView instance101 : instance103 ReplicaChoices[instance101]: instance101 |#] [#|2010-11-19T16:37:49.681-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 3; curViewID: 4; signal: com.sun.enterprise.ee.cms.impl.common.PlannedShutdownSignalImpl@12c45ff [current: instance101, instance103] [previous: instance101, instance102, instance103]|#] [#|2010-11-19T16:37:49.681-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-11-19T16:38:06.761-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:38:06.761-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:38:06.763-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received StoreableLoadRequestCommand{key=6b682e213313b1b46de0517f9fb8, minimumRequiredVersion=-9223372036854775808, resp=null, tokenId=3, originatingInstance='instance101'} from instance101|#] [#|2010-11-19T16:38:06.766-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=execute;|instance103StoreableLoadRequestCommand:69 received load_request command for 6b682e213313b1b46de0517f9fb8from instance101|#] [#|2010-11-19T16:38:06.766-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=execute;|instance103StoreableLoadRequestCommand:69 will send 6b682e213313b1b46de0517f9fb8 => null to instance101|#] [#|2010-11-19T16:38:06.767-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_response|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadResponseCommand;MethodName=writeObject;|instance103StoreableLoadResponseCommand:72 sending load_response command for 6b682e213313b1b46de0517f9fb8 to instance101; null|#] [#|2010-11-19T16:38:06.945-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 651|#] [#|2010-11-19T16:38:07.144-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:38:07.144-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:38:07.147-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received StoreableLoadRequestCommand{key=6b682e213313b1b46de0517f9fb8, minimumRequiredVersion=-9223372036854775808, resp=null, tokenId=4, originatingInstance='instance101'} from instance101|#] [#|2010-11-19T16:38:07.148-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=execute;|instance103StoreableLoadRequestCommand:69 received load_request command for 6b682e213313b1b46de0517f9fb8from instance101|#] [#|2010-11-19T16:38:07.148-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=execute;|instance103StoreableLoadRequestCommand:69 will send 6b682e213313b1b46de0517f9fb8 => null to instance101|#] [#|2010-11-19T16:38:07.150-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_response|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadResponseCommand;MethodName=writeObject;|instance103StoreableLoadResponseCommand:72 sending load_response command for 6b682e213313b1b46de0517f9fb8 to instance101; null|#] [#|2010-11-19T16:38:07.345-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 651|#] [#|2010-11-19T16:38:09.448-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:38:09.448-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:38:09.471-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received StoreableSaveCommand:68(6b77a5e1c76cab4e70be6dad5776) from instance101|#] [#|2010-11-19T16:38:44.352-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:38:44.352-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:38:44.353-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredCommand:39(30000) from instance101|#] [#|2010-11-19T16:38:44.357-0800|WARNING|glassfish3.1|org.shoal.ha.cache.store|_ThreadID=16;_ThreadName=Thread-1;|**Removing expired entry: org.glassfish.web.ha.session.management.CompositeMetadata@1b28af8|#] [#|2010-11-19T16:38:44.358-0800|WARNING|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;|ReplicaStore removing (idle) key: 6b77a5e1c76cab4e70be6dad5776|#] [#|2010-11-19T16:38:44.557-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 539|#] [#|2010-11-19T16:38:45.845-0800|FINE|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.TransmitInterceptor;MethodName=onTransmit;|/SLSBRefFO: TransmitInterceptor./SLSBRefFO:onTransmit() Sent ReplicationFramePayloadCommand: contains 1 commands to instance101; size: 559|#] [#|2010-11-19T16:38:46.043-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.command.CommandManager;MethodName=handleMessage;|/SLSBRefFO RECEIVED ReplicationFramePayloadCommand: contains 1 commands|#] [#|2010-11-19T16:38:46.043-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received ReplicationFramePayloadCommand: contains 1 commands from instance101|#] [#|2010-11-19T16:38:46.046-0800|FINE|glassfish3.1|org.shoal.ha.cache.command|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor;MethodName=onReceive;|/SLSBRefFO: Received RemoveExpiredResultCommand:44(result=0) from instance101|#] [#|2010-11-19T16:38:46.046-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance103For tokenId = 3 received remove_expired_response value=0|#]