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/instance102/config/keystore.jks
-Dosgi.shell.telnet.port=26667
-Djava.security.policy=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance102/config/server.policy
-Dfelix.fileinstall.log.level=3
-Dfelix.fileinstall.poll=5000
-Dcom.sun.aas.instanceRoot=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance102
-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/instance102/lib/ext
-Dfelix.fileinstall.bundles.new.start=true
-Djavax.net.ssl.trustStore=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance102/config/cacerts.jks
-Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as
-Djava.security.auth.login.config=/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance102/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,,,instance102
-instancename
instance102
-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/instance102
-read-stdin
true
Nov 19, 2010 4:34:43 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info
INFO: Successfully launched in 93 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.344-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: 707 milliseconds|#]

[#|2010-11-19T16:35:05.474-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.482-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.487-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.488-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:05.656-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:05.787-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:05.787-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:05.788-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|Printing all GMS properties: |#]

[#|2010-11-19T16:35:05.965-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1095: Member instance102 is joining group st-cluster ...|#]

[#|2010-11-19T16:35:06.157-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.169-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS MAX_MESSAGE_LENGTH=4196352|#]

[#|2010-11-19T16:35:06.173-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.385-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:06.579-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Grizzly controller listening on /0.0.0.0:9102. Controller started in 244 ms|#]

[#|2010-11-19T16:35:06.607-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Set via default minimum: MulticastSocket.getTimeToLive()=4|#]

[#|2010-11-19T16:35:06.610-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:06.689-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:06.697-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|starting cluster st-cluster for member:instance102|#]

[#|2010-11-19T16:35:06.697-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1061: MasterNode message listener is registered for member: instance102 group: st-cluster|#]

[#|2010-11-19T16:35:06.720-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1005: Member instance102 joined group st-cluster|#]

[#|2010-11-19T16:35:06.720-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1004: Started GMS for instance instance102 in group st-cluster|#]

[#|2010-11-19T16:35:06.722-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: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102
|#]

[#|2010-11-19T16:35:06.723-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: instance102 of Group: st-cluster|#]

[#|2010-11-19T16:35:06.732-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: instance102 of group: st-cluster|#]

[#|2010-11-19T16:35:06.817-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(summary): GMSMember name: instance102  group: st-cluster memberType: CORE startTime: Nov 19, 2010 4:35:05 PM PST ID:10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102 TCP uri(s):[tcp://10.133.169.53:4000]|#]

[#|2010-11-19T16:35:06.821-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(dump)=PEERID[10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102]
CUSTOMTAGS[GROUP_NAME:st-cluster, MEMBER_TYPE:CORE, START_TIME[4:35:05 PM PST on Nov 19, 2010]], ENDPONTADDRESSES[[tcp://10.133.169.53:4000]], NAME:instance102, OSNAME:SunOS, OSVERSION:5.10, OSARCH:sparc, HWARCH:sparc, HWVENDOR:Sun Microsystems Inc.|#]

[#|2010-11-19T16:35:06.847-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: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102
2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server
|#]

[#|2010-11-19T16:35:06.849-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:06.849-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: server of group: st-cluster|#]

[#|2010-11-19T16:35:06.852-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: instance102, MemberType: CORE, Address: 10.133.169.53:9102:228.9.44.27:13887:st-cluster:instance102
2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server
|#]

[#|2010-11-19T16:35:06.852-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: instance102 of Group: st-cluster|#]

[#|2010-11-19T16:35:06.856-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:06.881-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: server, MemberType: SPECTATOR, Address: 10.133.169.53:9114:228.9.44.27:13887:st-cluster:server
|#]

[#|2010-11-19T16:35:06.882-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: instance101 of Group: st-cluster|#]

[#|2010-11-19T16:35:06.883-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;|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.425-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.425-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.140-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.440-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.564-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 322ms - bound to [0.0.0.0:28081]|#]

[#|2010-11-19T16:35:10.564-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 39ms - bound to [0.0.0.0:24849]|#]

[#|2010-11-19T16:35:10.564-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 92ms - bound to [0.0.0.0:28182]|#]

[#|2010-11-19T16:35:10.674-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 30ms - bound to [127.0.0.1:27677]|#]

[#|2010-11-19T16:35:11.089-0800|INFO|glassfish3.1|org.hibernate.validator.util.Version|_ThreadID=16;_ThreadName=Thread-1;|Hibernate Validator null|#]

[#|2010-11-19T16:35:11.122-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.352-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:28081]|#]

[#|2010-11-19T16:35:12.387-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:28182]|#]

[#|2010-11-19T16:35:12.424-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:24849]|#]

[#|2010-11-19T16:35:12.608-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:12.608-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:12.625-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:12.625-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:12.625-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:15.511-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:15.511-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:15.511-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:18.295-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:20.756-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:20.805-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:20.806-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:20.807-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:20.809-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:22.045-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:22.473-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:22.490-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:22.785-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:22.790-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:22.844-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:22.892-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:37.589-0800|INFO|glassfish3.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=16;_ThreadName=Thread-1;|JTS5014: Recoverable JTS instance, serverId = [23701]|#]

[#|2010-11-19T16:35:40.372-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:40.383-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:40.864-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:40.924-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:40.929-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='instance102', 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:40.992-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Entered GlassFish2ShoalBackingStoreFactory.createBackingStore...|#]

[#|2010-11-19T16:35:41.004-0800|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper created for: myName: instance102; groupName: st-cluster|#]

[#|2010-11-19T16:35:41.046-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**  SET  ASYNC REPLICATUION: false|#]

[#|2010-11-19T16:35:41.085-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@5876fe|#]

[#|2010-11-19T16:35:41.086-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:: REGISTERED member event listeners for <group, instance> => <st-cluster, instance102>|#]

[#|2010-11-19T16:35:41.090-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: ; previousView 
|#]

[#|2010-11-19T16:35:41.110-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**DataStorecontext.setDoSyncReplication = false|#]

[#|2010-11-19T16:35:41.119-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:41.125-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: ; previousView 
|#]

[#|2010-11-19T16:35:41.126-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: ; previousView 
|#]

[#|2010-11-19T16:35:41.130-0800|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|ReplicationFramework initialized. Configuration: DataStoreConfigurator{instanceName='instance102', 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@1b5302d, dataStoreKeyHelper=org.shoal.ha.cache.api.ObjectKeyHelper@6de78a, startGMS=false, cacheLocally= false, clazzLoader=96.0, dataStoreEntryHelper=org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper@17c3925, doSynchronousReplication=false, objectInputOutputStreamFactory=org.shoal.ha.cache.api.DefaultObjectInputOutputStreamFactory@8bda08}|#]

[#|2010-11-19T16:35:43.627-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.628-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.629-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.642-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: instance101; previousView 
	ReplicaChoices[instance101]: instance101
|#]

[#|2010-11-19T16:35:43.646-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: instance101; previousView 
	ReplicaChoices[instance101]: instance101
|#]

[#|2010-11-19T16:35:43.648-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 1; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@1ec7c56 [current: instance101]  [previous: ]|#]

[#|2010-11-19T16:35:43.648-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#]

[#|2010-11-19T16:35:43.667-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:43.667-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:43.667-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:43.672-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,117 ms|#]

[#|2010-11-19T16:35:43.677-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 (18,914ms), startup services(40,666ms), total(59,580ms)|#]

[#|2010-11-19T16:35:43.684-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:43.684-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1040: Calling reportMyState() with READY...|#]

[#|2010-11-19T16:35:43.715-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.715-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.716-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.719-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: instance101; previousView instance101
	ReplicaChoices[instance101]: instance101
|#]

[#|2010-11-19T16:35:43.722-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: instance101; previousView instance101
	ReplicaChoices[instance101]: instance101
|#]

[#|2010-11-19T16:35:43.722-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 1; curViewID: 2; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@505fd8 [current: instance101, instance102]  [previous: instance101]|#]

[#|2010-11-19T16:35:43.722-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#]

[#|2010-11-19T16:35:44.252-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|____________________________
Welcome to Apache Felix Gogo

|#]

[#|2010-11-19T16:35:44.381-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance102/autodeploy/bundles does not exist, please create it.|#]

[#|2010-11-19T16:35:44.504-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#]

[#|2010-11-19T16:35:44.505-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#]

[#|2010-11-19T16:35:44.506-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#]

[#|2010-11-19T16:35:44.507-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#]

[#|2010-11-19T16:35:44.524-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|Binding RMI port to *:28687|#]

[#|2010-11-19T16:35:44.610-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:44.664-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#]

[#|2010-11-19T16:35:44.665-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#]

[#|2010-11-19T16:35:44.666-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:44.667-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#]

[#|2010-11-19T16:35:44.668-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#]

[#|2010-11-19T16:35:44.690-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.584-0800|INFO|glassfish3.1|ShoalLogger.mcast|_ThreadID=16;_ThreadName=Thread-1;8;|GMS1113: identified missed MasterViewChangeEvents from master:server latestMasterViewSeqID=8 missed masterViewSeqId=[8] lastCheckedMasterViewID:8|#]

[#|2010-11-19T16:35:46.594-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:46.595-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:46.595-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:46.601-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: instance101 : instance103; previousView 
	ReplicaChoices[instance101]: instance101
	ReplicaChoices[instance103]: instance103
|#]

[#|2010-11-19T16:35:46.604-0800|FINE|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.ha.mapper.DefaultKeyMapper;MethodName=printMemberStates;|DefaultKeyMapper[instance102].onViewChange (isJoin: true) currentView: instance101 : instance103; previousView 
	ReplicaChoices[instance101]: instance101
	ReplicaChoices[instance103]: instance103
|#]

[#|2010-11-19T16:35:46.604-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 2; curViewID: 3; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@1818dbc [current: instance101, instance102, instance103]  [previous: ]|#]

[#|2010-11-19T16:35:46.604-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#]

[#|2010-11-19T16:35:46.715-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.219-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.661-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:28687/jndi/rmi://hat2k1:28687/jmxrmi|#]

[#|2010-11-19T16:35:47.663-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.669-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:50.249-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.271-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|ExtenderManager starting|#]

[#|2010-11-19T16:35:50.281-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijpa.JPAExtender@1eb536|#]

[#|2010-11-19T16:35:50.283-0800|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=16;_ThreadName=Thread-1;| JPAExtender started|#]

[#|2010-11-19T16:35:50.294-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijavaeebase.JavaEEExtender@1ad453f|#]

[#|2010-11-19T16:35:50.318-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.441-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiweb.WebExtender@1e3606d|#]

[#|2010-11-19T16:35:50.498-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.544-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijdbc.JDBCExtender@1b1407a|#]

[#|2010-11-19T16:35:50.563-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.605-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijta.JTAExtender@de756|#]

[#|2010-11-19T16:35:50.658-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.759-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.801-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiejb.EJBExtender@131d024|#]

[#|2010-11-19T16:35:50.830-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.869-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgi.ee.resources.ResourcesExtender@1636e34|#]

[#|2010-11-19T16:35:50.910-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:55.970-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:55.973-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/appcvs/glassfish/glassfish3/glassfish/nodes/agent1/instance102/autodeploy/bundles does not exist, please create it.|#]

[#|2010-11-19T16:35:55.991-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.485-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.486-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.493-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.531-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 2 commands to instance101; size: 807|#]

[#|2010-11-19T16:36:43.542-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 instance103; size: 559|#]

[#|2010-11-19T16:36:43.746-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.746-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.748-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:43.750-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance102For tokenId = 1 received remove_expired_response value=0|#]

[#|2010-11-19T16:36:43.784-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.784-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 instance103|#]

[#|2010-11-19T16:36:43.787-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 instance103|#]

[#|2010-11-19T16:36:43.787-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance102For tokenId = 1 received remove_expired_response value=0|#]

[#|2010-11-19T16:36:45.078-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.078-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 instance103|#]

[#|2010-11-19T16:36:45.080-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 instance103|#]

[#|2010-11-19T16:36:45.216-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 instance103; size: 539|#]

[#|2010-11-19T16:37:02.892-0800|FINE|glassfish3.1|org.shoal.ha.cache.store|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=load;|StoreableReplicatedBackingStore.load(6ad349c11ec4e798542caf3d5765, null)|#]

[#|2010-11-19T16:37:02.897-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=doLoad;|StoreableReplicatedBackingStore.doLoad(6ad349c11ec4e798542caf3d5765, -9223372036854775808); ReplicaChoices: instance101|#]

[#|2010-11-19T16:37:02.898-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=doLoad;|StoreableReplicatedBackingStore: For Key=6ad349c11ec4e798542caf3d5765; Trying to load from Replica[0]: instance101|#]

[#|2010-11-19T16:37:02.906-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=writeObject;|instance102StoreableLoadRequestCommand:69 sending load_request command for 6ad349c11ec4e798542caf3d5765to instance101|#]

[#|2010-11-19T16:37:03.016-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: 773|#]

[#|2010-11-19T16:37:03.246-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.246-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:03.252-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 StoreableLoadResponseCommand:72(6ad349c11ec4e798542caf3d5765) from instance101|#]

[#|2010-11-19T16:37:03.254-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_response|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadResponseCommand;MethodName=execute;|instance102 received load_response key=6ad349c11ec4e798542caf3d5765; value=null; from instance101|#]

[#|2010-11-19T16:37:03.255-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=doLoad;|StoreableReplicatedBackingStore.doLoad(6ad349c11ec4e798542caf3d5765) Performing broadcast load|#]

[#|2010-11-19T16:37:03.258-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=doLoad;|*StoreableReplicatedBackingStore: For Key=6ad349c11ec4e798542caf3d5765; Trying to load from instance101|#]

[#|2010-11-19T16:37:03.259-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=writeObject;|instance102StoreableLoadRequestCommand:69 sending load_request command for 6ad349c11ec4e798542caf3d5765to instance101|#]

[#|2010-11-19T16:37:03.415-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: 773|#]

[#|2010-11-19T16:37:03.653-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.653-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:03.656-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 StoreableLoadResponseCommand:72(6ad349c11ec4e798542caf3d5765) from instance101|#]

[#|2010-11-19T16:37:03.656-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_response|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadResponseCommand;MethodName=execute;|instance102 received load_response key=6ad349c11ec4e798542caf3d5765; value=null; from instance101|#]

[#|2010-11-19T16:37:03.657-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=doLoad;|*StoreableReplicatedBackingStore: For Key=6ad349c11ec4e798542caf3d5765; Trying to load from instance103|#]

[#|2010-11-19T16:37:03.661-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadRequestCommand;MethodName=writeObject;|instance102StoreableLoadRequestCommand:69 sending load_request command for 6ad349c11ec4e798542caf3d5765to instance103|#]

[#|2010-11-19T16:37:03.816-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 instance103; size: 773|#]

[#|2010-11-19T16:37:03.976-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.976-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 instance103|#]

[#|2010-11-19T16:37:03.979-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 StoreableLoadResponseCommand:72(6ad349c11ec4e798542caf3d5765) from instance103|#]

[#|2010-11-19T16:37:03.979-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.load_response|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.StoreableLoadResponseCommand;MethodName=execute;|instance102 received load_response key=6ad349c11ec4e798542caf3d5765; value=null; from instance103|#]

[#|2010-11-19T16:37:03.980-0800|FINE|glassfish3.1|org.shoal.ha.cache.store|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=doLoad;|StoreableReplicatedBackingStore.load(6ad349c11ec4e798542caf3d5765) Final result: null|#]

[#|2010-11-19T16:37:05.902-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=16;_ThreadName=Thread-1;|ModifiedAttributeHASession>>setAttribute name=jreplicaLocation attributeState=null|#]

[#|2010-11-19T16:37:06.585-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.org.glassfish.web.ha.session.management|_ThreadID=16;_ThreadName=Thread-1;|ModifiedAttributeHASession>>setAttribute name=remoteobject attributeState=null|#]

[#|2010-11-19T16:37:06.588-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|ReplicationAttributeStore>>save: replicator: org.shoal.adapter.store.StoreableReplicatedBackingStore@45801a|#]

[#|2010-11-19T16:37:06.900-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Calling save with composite metadata|#]

[#|2010-11-19T16:37:06.916-0800|FINE|glassfish3.1|org.shoal.ha.cache.store|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=save;|/SLSBRefFOsave(6b682e213313b1b46de0517f9fb8). Saved to instance101|#]

[#|2010-11-19T16:37:07.116-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: 2483|#]

[#|2010-11-19T16:37:43.941-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:43.949-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 instance103; size: 559|#]

[#|2010-11-19T16:37:43.957-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.957-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.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 RemoveExpiredCommand:39(30000) from instance101|#]

[#|2010-11-19T16:37:44.116-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.147-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:44.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 ReplicationFramePayloadCommand: contains 1 commands from instance101|#]

[#|2010-11-19T16:37:44.150-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:44.150-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance102For tokenId = 5 received remove_expired_response value=0|#]

[#|2010-11-19T16:37:44.183-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:44.183-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 instance103|#]

[#|2010-11-19T16:37:44.185-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 instance103|#]

[#|2010-11-19T16:37:44.185-0800|FINE|glassfish3.1|org.shoal.ha.cache.command.remove|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.commands.RemoveExpiredResultCommand;MethodName=execute;|instance102For tokenId = 5 received remove_expired_response value=0|#]

[#|2010-11-19T16:37:45.474-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.474-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 instance103|#]

[#|2010-11-19T16:37:45.476-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 instance103|#]

[#|2010-11-19T16:37:45.615-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 instance103; size: 539|#]

[#|2010-11-19T16:37:49.617-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=16;_ThreadName=Thread-1;|Server shutdown initiated|#]

[#|2010-11-19T16:37:49.645-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1008: GMSAdapter for member: instance102 group: st-cluster received GlassfishEventType: prepare_shutdown|#]

[#|2010-11-19T16:37:49.646-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1096: Member instance102 is leaving group st-cluster|#]

[#|2010-11-19T16:37:49.647-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1010: Leaving GMS group st-cluster with shutdown type set to InstanceShutdown|#]

[#|2010-11-19T16:37:49.654-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1065: Completed processing outstanding master node messages for member:instance102 group:st-cluster outstandingMessages to process:0|#]

[#|2010-11-19T16:37:49.659-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1110: Thread IP Multicast Listener for /228.9.44.27:13887 has completed.|#]

[#|2010-11-19T16:37:49.659-0800|INFO|glassfish3.1|ShoalLogger.monitor|_ThreadID=16;_ThreadName=Thread-1;|BlockingIOMulicastSender monitoring stats: received: 338 core poolsize:10 largest pool size:10 task count:339 max queue size:0 rejected execution:0|#]

[#|2010-11-19T16:37:50.673-0800|INFO|glassfish3.1|ShoalLogger.monitor|_ThreadID=16;_ThreadName=Thread-1;|GMS1115: router signal queue high water mark:0 signal queue capacity:600|#]

[#|2010-11-19T16:37:50.673-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1091: View Window event processing thread for group st-cluster terminated normally|#]

[#|2010-11-19T16:37:50.673-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1107: SignalHandler task named GMS SignalHandler for Group-st-cluster thread exiting|#]

[#|2010-11-19T16:37:50.673-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1088: MessageWindow thread for group st-cluster terminated due to shutdown notification|#]

[#|2010-11-19T16:37:50.688-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgi.ee.resources.ResourcesExtender@1636e34|#]

[#|2010-11-19T16:37:50.689-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgiejb.EJBExtender@131d024|#]

[#|2010-11-19T16:37:50.705-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijpa.JPAExtender@1eb536|#]

[#|2010-11-19T16:37:50.705-0800|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=16;_ThreadName=Thread-1;| JPAExtender stopped|#]

[#|2010-11-19T16:37:50.707-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijta.JTAExtender@de756|#]

[#|2010-11-19T16:37:50.707-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijavaeebase.JavaEEExtender@1ad453f|#]

[#|2010-11-19T16:37:50.715-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijdbc.JDBCExtender@1b1407a|#]

[#|2010-11-19T16:37:50.721-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgiweb.WebExtender@1e3606d|#]

[#|2010-11-19T16:37:50.899-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|in do save|#]

[#|2010-11-19T16:37:50.900-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Calling save on backing store|#]

[#|2010-11-19T16:37:50.902-0800|FINE|glassfish3.1|org.shoal.ha.cache.store|_ThreadID=16;_ThreadName=Thread-1;ClassName=org.shoal.adapter.store.StoreableReplicatedBackingStore;MethodName=save;|/SLSBRefFOsave(6b682e213313b1b46de0517f9fb8). Saved to instance101|#]

[#|2010-11-19T16:37:51.013-0800|WARNING|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GroupCommunicationProvider.sendMessage(target=instance101): unable to send message: missing mapping from member identifier to network peerid|#]

[#|2010-11-19T16:37:51.016-0800|WARNING|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Error during groupHandle.sendMessage(instance101) failed because instance101 is not alive?|#]

[#|2010-11-19T16:37:51.016-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: 856|#]

[#|2010-11-19T16:37:52.821-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy|_ThreadID=16;_ThreadName=Thread-1;|Unregistered SHOAL BackingStore Proxy with persistence-type = replicated|#]

[#|2010-11-19T16:37:52.823-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=16;_ThreadName=Thread-1;|Unregistered ReplicationStoreProxy with persistence-type = replication|#]

[#|2010-11-19T16:37:52.845-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|connection OBJ Name = jmxremote:type=jmx-connector-server,protocol=rmi_jrmp,name=system|#]

[#|2010-11-19T16:37:52.846-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|JMXStartupService: Stopped JMXConnectorServer: null|#]

[#|2010-11-19T16:37:52.848-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|JMXStartupService and JMXConnectors have been shut down.|#]

[#|2010-11-19T16:37:52.849-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|Shutdown procedure finished|#]