Aug 27, 2010 11:48:45 AM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /System/Library/Frameworks/JavaVM.framework/Versions/1.6/Home/bin/java -cp /Users/emvy/workspace/glassfish-3.1/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:MaxPermSize=192m -XX:NewRatio=2 -Xmx512m -client -javaagent:/Users/emvy/workspace/glassfish-3.1/glassfish/lib/monitor/btrace-agent.jar=unsafe=true,noServer=true -Dosgi.shell.telnet.maxconn=1 -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver -Dfelix.fileinstall.dir=/Users/emvy/workspace/glassfish-3.1/glassfish/modules/autostart/ -Djavax.net.ssl.keyStore=/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/config/keystore.jks -Dosgi.shell.telnet.port=6666 -Djava.security.policy=/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/config/server.policy -Dfelix.fileinstall.log.level=3 -Dfelix.fileinstall.poll=5000 -Dcom.sun.aas.instanceRoot=/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain -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=/Users/emvy/workspace/glassfish-3.1/glassfish/modules/endorsed:/Users/emvy/workspace/glassfish-3.1/glassfish/lib/endorsed -Dcom.sun.aas.installRoot=/Users/emvy/workspace/glassfish-3.1/glassfish -Dfelix.fileinstall.bundles.startTransient=true -Djava.ext.dirs=/System/Library/Frameworks/JavaVM.framework/Versions/1.6/Home/lib/ext:/System/Library/Frameworks/JavaVM.framework/Versions/1.6/Home/jre/lib/ext:/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/lib/ext -Dfelix.fileinstall.bundles.new.start=true -Djavax.net.ssl.trustStore=/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/config/cacerts.jks -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as -Djava.security.auth.login.config=/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/config/login.conf -DANTLR_USE_DIRECT_CLASS_LOADING=true -Dorg.glassfish.web.rfc2109_cookie_names_enforced=false -Djava.library.path=/Users/emvy/workspace/glassfish-3.1/glassfish/lib:/Users/emvy/workspace/glassfish-3.1/glassfish/bin:/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java com.sun.enterprise.glassfish.bootstrap.ASMain -domainname st-domain -asadmin-args start-domain,,,st-domain -instancename server -verbose false -debug false -asadmin-classpath /Users/emvy/workspace/glassfish-3.1/glassfish/modules/admin-cli.jar -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -upgrade false -type DAS -domaindir /Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain -read-stdin true Aug 27, 2010 11:48:46 AM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 51 msec. [#|2010-08-27T11:48:54.000+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Running GlassFish Version: GlassFish Server Open Source Edition 3.1-b17 (build 17)|#] [#|2010-08-27T11:48:54.009+0530|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: 213 milliseconds|#] [#|2010-08-27T11:48:54.157+0530|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-08-27T11:48:54.158+0530|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy|_ThreadID=16;_ThreadName=Thread-1;|Registered SHOAL BackingStore Proxy with persistence-type = replicated|#] [#|2010-08-27T11:48:54.161+0530|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-08-27T11:48:54.162+0530|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=16;_ThreadName=Thread-1;|Registered ReplicationStoreProxy with persistence-type = replication|#] [#|2010-08-27T11:48:54.517+0530|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|Inside GMSService.postConstruct :|#] [#|2010-08-27T11:48:54.541+0530|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-08-27T11:48:54.560+0530|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-08-27T11:48:54.561+0530|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-08-27T11:48:54.565+0530|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|Printing all GMS properties : MULTICASTPORT = 12118 FAILURE_DETECTION_RETRIES = 3 MEMBER_TYPE = SPECTATOR MULTICASTADDRESS = 228.9.68.223 FAILURE_VERIFICATION_TIMEOUT = 1500 DISCOVERY_TIMEOUT = 5000 FAILURE_DETECTION_TCP_RETRANSMIT_TIMEOUT = 10000 MAX_MESSAGE_LENGTH = 4196352 FAILURE_DETECTION_TIMEOUT = 2000 IS_BOOTSTRAPPING_NODE = true |#] [#|2010-08-27T11:48:54.649+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|instantiated following NetworkManager implementation:com.sun.enterprise.mgmt.transport.grizzly.GrizzlyNetworkManager|#] [#|2010-08-27T11:48:54.652+0530|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS MAX_MESSAGE_LENGTH=4196352|#] [#|2010-08-27T11:48:54.661+0530|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;| GrizzlyNetworkManager Configuration BIND_INTERFACE_ADDRESS:null NetworkInterfaceName:null TCPSTARTPORT..TCPENDPORT:9090..9120 tcpPort:9090 MULTICAST_ADDRESS:MULTICAST_PORT:228.9.68.223:12118 MULTICAST_PACKET_SIZE:65536 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:1 START_TIMEOUT(ms):15000 WRITE_TIMEOUT(ms):10000 MAX_WRITE_SELECTOR_POOL_SIZE:30 VIRTUAL_MULTICAST_URI_LIST:null |#] [#|2010-08-27T11:48:54.738+0530|INFO|glassfish3.1|grizzly|_ThreadID=16;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.19 - 8/27/10 11:48 AM|#] [#|2010-08-27T11:48:54.764+0530|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Grizzly controller started and is ready in 43 ms|#] [#|2010-08-27T11:48:54.791+0530|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|HealthMonitor: heartBeatTimeout(ms)=2000 maxMissedBeats=3 failureDetectionTCPTimeout(ms)=10000 failureDetectionTCPPort=9000|#] [#|2010-08-27T11:48:54.793+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|starting cluster st-cluster for member:server|#] [#|2010-08-27T11:48:54.793+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MasterNode message listener is registered for member: server group:st-cluster|#] [#|2010-08-27T11:48:54.800+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS View Change Received for group st-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: server, MemberType: SPECTATOR, Address: 192.168.1.3:9090:12118:st-cluster:server |#] [#|2010-08-27T11:48:54.801+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Analyzing new membership snapshot received as part of event : MASTER_CHANGE_EVENT for Member: server of Group: st-cluster|#] [#|2010-08-27T11:48:54.804+0530|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|member server joined group st-cluster|#] [#|2010-08-27T11:48:54.804+0530|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|gmsservice.started |#] [#|2010-08-27T11:48:54.808+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|adding GroupLeadershipNotification signal leaderMember: server of group: st-cluster|#] [#|2010-08-27T11:48:54.844+0530|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(summary): GMSMember name: server group: st-cluster memberType: SPECTATOR startTime: Aug 27, 2010 11:48:54 AM IST ID:192.168.1.3:9090:12118:st-cluster:server TCP uri(s):[tcp://192.168.1.3:4000, tcp://[fe80:0:0:0:21c:42ff:fe00:1%8]:4000, tcp://[fe80:0:0:0:21c:42ff:fe00:0%7]:4000, tcp://[fe80:0:0:0:0:0:0:1%1]:4000]|#] [#|2010-08-27T11:48:54.845+0530|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(dump)=PEERID[192.168.1.3:9090:12118:st-cluster:server] CUSTOMTAGS[GROUP_NAME:st-cluster, MEMBER_TYPE:SPECTATOR, START_TIME[11:48:54 AM IST on Aug 27, 2010]], ENDPONTADDRESSES[[tcp://192.168.1.3:4000, tcp://[fe80:0:0:0:21c:42ff:fe00:1%8]:4000, tcp://[fe80:0:0:0:21c:42ff:fe00:0%7]:4000, tcp://[fe80:0:0:0:0:0:0:1%1]:4000]], NAME:server, OSNAME:Mac OS X, OSVERSION:10.5.8, OSARCH:x86_64, HWARCH:x86_64, HWVENDOR:Apple Inc.|#] [#|2010-08-27T11:48:55.431+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.19 started in: 75ms - bound to [0.0.0.0:8080]|#] [#|2010-08-27T11:48:55.456+0530|INFO|glassfish3.1|com.sun.grizzly.config.GrizzlyServiceListener|_ThreadID=16;_ThreadName=Thread-1;|Perform lazy SSL initialization for the listener 'http-listener-2'|#] [#|2010-08-27T11:48:55.473+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.19 started in: 12ms - bound to [0.0.0.0:8181]|#] [#|2010-08-27T11:48:55.479+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.19 started in: 2ms - bound to [0.0.0.0:4848]|#] [#|2010-08-27T11:48:55.498+0530|INFO|glassfish3.1|com.sun.grizzly.config.GrizzlyServiceListener|_ThreadID=16;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.19 - 8/27/10 11:48 AM|#] [#|2010-08-27T11:48:55.499+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.19 started in: 9ms - bound to [0.0.0.0:3700]|#] [#|2010-08-27T11:48:55.518+0530|INFO|glassfish3.1|com.sun.grizzly.config.GrizzlyServiceListener|_ThreadID=16;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.19 - 8/27/10 11:48 AM|#] [#|2010-08-27T11:48:55.519+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.19 started in: 15ms - bound to [localhost:7676]|#] [#|2010-08-27T11:48:55.873+0530|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-08-27T11:48:59.819+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Assuming Master Node designation member:server for group:st-cluster|#] [#|2010-08-27T11:48:59.819+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Announcing Master Node designation for member: server of group: st-cluster. Local view contains 1 entries|#] [#|2010-08-27T11:48:59.821+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS View Change Received for group st-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: server, MemberType: SPECTATOR, Address: 192.168.1.3:9090:12118:st-cluster:server |#] [#|2010-08-27T11:48:59.821+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Analyzing new membership snapshot received as part of event : MASTER_CHANGE_EVENT for Member: server of Group: st-cluster|#] [#|2010-08-27T11:48:59.821+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|adding GroupLeadershipNotification signal leaderMember: server of group: st-cluster|#] [#|2010-08-27T11:49:03.162+0530|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1002: Security Manager is OFF.|#] [#|2010-08-27T11:49:03.468+0530|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1010: Entering Security Startup Service|#] [#|2010-08-27T11:49:03.503+0530|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-08-27T11:49:03.701+0530|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-08-27T11:49:03.703+0530|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-08-27T11:49:03.745+0530|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-08-27T11:49:03.815+0530|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=16;_ThreadName=Thread-1;|SEC1011: Security Service(s) Started Successfully|#] [#|2010-08-27T11:49:04.862+0530|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:8080]|#] [#|2010-08-27T11:49:04.921+0530|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:8181]|#] [#|2010-08-27T11:49:04.933+0530|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:4848]|#] [#|2010-08-27T11:49:04.994+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0171: Created virtual server [server]|#] [#|2010-08-27T11:49:04.998+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-08-27T11:49:06.221+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|PersistenceStrategyBuilderFactory>>createPersistenceStrategyBuilder: CandidateBuilderClassName = class com.sun.enterprise.web.MemoryStrategyBuilder|#] [#|2010-08-27T11:49:06.265+0530|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-08-27T11:49:12.268+0530|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00018: Webservice Endpoint deployed MessageWS listening at address at http://varun-rupelas-macbook.local:8080/reliable-messaging-1/MessageWSService|#] [#|2010-08-27T11:49:13.177+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|PersistenceStrategyBuilderFactory>>createPersistenceStrategyBuilder: CandidateBuilderClassName = class com.sun.enterprise.web.MemoryStrategyBuilder|#] [#|2010-08-27T11:49:14.942+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0671: Loading application [reliable-messaging-1] at [/reliable-messaging-1]|#] [#|2010-08-27T11:49:14.946+0530|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|CORE10010: Loading application reliable-messaging-1 done in 19,281 ms|#] [#|2010-08-27T11:49:15.392+0530|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00018: Webservice Endpoint deployed MessageWSService listening at address at http://varun-rupelas-macbook.local:8080/reliable-messaging-server/MessageWSServiceService|#] [#|2010-08-27T11:49:15.395+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|PersistenceStrategyBuilderFactory>>createPersistenceStrategyBuilder: CandidateBuilderClassName = class com.sun.enterprise.web.MemoryStrategyBuilder|#] [#|2010-08-27T11:49:15.609+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0671: Loading application [reliable-messaging-server] at [/reliable-messaging-server]|#] [#|2010-08-27T11:49:15.611+0530|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|CORE10010: Loading application reliable-messaging-server done in 649 ms|#] [#|2010-08-27T11:49:16.047+0530|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00018: Webservice Endpoint deployed RMService listening at address at http://varun-rupelas-macbook.local:8080/rm-service/RMServiceService|#] [#|2010-08-27T11:49:16.052+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|PersistenceStrategyBuilderFactory>>createPersistenceStrategyBuilder: CandidateBuilderClassName = class com.sun.enterprise.web.MemoryStrategyBuilder|#] [#|2010-08-27T11:49:16.184+0530|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=16;_ThreadName=Thread-1;|WEB0671: Loading application [rm-service] at [/rm-service]|#] [#|2010-08-27T11:49:16.188+0530|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|CORE10010: Loading application rm-service done in 575 ms|#] [#|2010-08-27T11:49:16.191+0530|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|GlassFish Server Open Source Edition 3.1-b17 (17) startup time : Felix(6444ms) startup services(23249ms) total(29693ms)|#] [#|2010-08-27T11:49:16.193+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS:Reporting Joined and Ready state to group st-cluster|#] [#|2010-08-27T11:49:16.203+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS View Change Received for group st-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: server, MemberType: SPECTATOR, Address: 192.168.1.3:9090:12118:st-cluster:server |#] [#|2010-08-27T11:49:16.203+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Analyzing new membership snapshot received as part of event : JOINED_AND_READY_EVENT for Member: server of Group: st-cluster|#] [#|2010-08-27T11:49:16.204+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Calling reportMyState() with READY...|#] [#|2010-08-27T11:49:16.661+0530|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-08-27T11:49:16.692+0530|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|Binding RMI port to *:8686|#] [#|2010-08-27T11:49:16.818+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/autodeploy/bundles does not exist, please create it.|#] [#|2010-08-27T11:49:16.931+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/Users/emvy/workspace/glassfish-3.1/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2010-08-27T11:49:17.067+0530|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://192.168.1.3:8686/jndi/rmi://192.168.1.3:8686/jmxrmi|#] [#|2010-08-27T11:49:17.071+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/Users/emvy/workspace/glassfish-3.1/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2010-08-27T11:49:17.800+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/Users/emvy/workspace/glassfish-3.1/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2010-08-27T11:49:17.843+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/Users/emvy/workspace/glassfish-3.1/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2010-08-27T11:49:22.859+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /Users/emvy/workspace/glassfish-3.1/glassfish/modules/autostart/org.apache.felix.fileinstall-autodeploy-bundles.cfg|#] [#|2010-08-27T11:49:22.876+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/Users/emvy/workspace/glassfish-3.1/glassfish/domains/st-domain/autodeploy/bundles does not exist, please create it.|#] [#|2010-08-27T11:49:29.372+0530|INFO|glassfish3.1|com.sun.metro.policy|_ThreadID=16;_ThreadName=Thread-1;|WSP5018: Loaded WSIT configuration from file: jndi:/server/reliable-messaging-server/WEB-INF/wsit-first.MessageWSService.xml.|#] [#|2010-08-27T11:49:32.429+0530|INFO|glassfish3.1|com.sun.xml.ws.monitoring|_ThreadID=16;_ThreadName=Thread-1;|Metro monitoring rootname successfully set to: amx:pp=/mon/server-mon[server],type=WSEndpoint,name=/reliable-messaging-server-MessageWSServiceService-MessageWSServicePort|#] [#|2010-08-27T11:49:38.901+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Entered GlassFish2ShoalBackingStoreFactory.createBackingStore...|#] [#|2010-08-27T11:49:38.904+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper created for: myName: server; groupName: st-cluster|#] [#|2010-08-27T11:49:38.916+0530|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@48bcff11|#] [#|2010-08-27T11:49:38.917+0530|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:: REGISTERED member event listeners for => |#] [#|2010-08-27T11:49:38.917+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers: previous viewID: -100; current viewID: 0; null|#] [#|2010-08-27T11:49:38.918+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.918+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: -100; curViewID: 0; signal: null [current: ] [previous: ]|#] [#|2010-08-27T11:49:38.918+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-08-27T11:49:38.926+0530|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|ASync replication enabled...|#] [#|2010-08-27T11:49:38.926+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers: previous viewID: 0; current viewID: 0; null|#] [#|2010-08-27T11:49:38.927+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers. Entered ELSE 2|#] [#|2010-08-27T11:49:38.928+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.928+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.928+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 0; signal: null [current: ] [previous: ]|#] [#|2010-08-27T11:49:38.928+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-08-27T11:49:38.930+0530|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|Created ReplicatedDataStore with config: DataStoreConfigurator{instanceName='server', groupName='st-cluster', storeName='{http://first/}MessageWSServiceService::{http://first/}MessageWSServicePort_BOUND_SEQUENCE_BS', keyClazz=class java.lang.String, valueClazz=class java.lang.String, keyMapper=org.shoal.ha.store.GlassFishKeyMapper@33c12f86, dataStoreKeyHelper=org.shoal.ha.cache.api.ObjectKeyHelper@501720f5, startGMS=false, cacheLocally= false, clazzLoader=null, dataStoreEntryHelper=org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper@1e74dde7, doASyncReplication=true, objectInputOutputStreamFactory=org.shoal.ha.cache.api.DefaultObjectInputOutputStreamFactory@a947850}|#] [#|2010-08-27T11:49:38.930+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|GlassFish2ShoalBackingStoreFactory:: CREATED an instance of: org.shoal.adapter.store.ReplicatedBackingStore|#] [#|2010-08-27T11:49:38.933+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Entered GlassFish2ShoalBackingStoreFactory.createBackingStore...|#] [#|2010-08-27T11:49:38.933+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper created for: myName: server; groupName: st-cluster|#] [#|2010-08-27T11:49:38.933+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers: previous viewID: 0; current viewID: 0; null|#] [#|2010-08-27T11:49:38.933+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers. Entered ELSE 2|#] [#|2010-08-27T11:49:38.934+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.934+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.934+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.934+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 0; signal: null [current: ] [previous: ]|#] [#|2010-08-27T11:49:38.935+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-08-27T11:49:38.937+0530|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|ASync replication enabled...|#] [#|2010-08-27T11:49:38.937+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers: previous viewID: 0; current viewID: 0; null|#] [#|2010-08-27T11:49:38.938+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers. Entered ELSE 2|#] [#|2010-08-27T11:49:38.938+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.938+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.938+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.938+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:38.939+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 0; signal: null [current: ] [previous: ]|#] [#|2010-08-27T11:49:38.939+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-08-27T11:49:38.939+0530|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|Created ReplicatedDataStore with config: DataStoreConfigurator{instanceName='server', groupName='st-cluster', storeName='{http://first/}MessageWSServiceService::{http://first/}MessageWSServicePort_SEQUENCE_DATA_BS', keyClazz=class java.lang.String, valueClazz=class com.sun.xml.ws.rx.rm.runtime.sequence.invm.SequenceDataPojo, keyMapper=org.shoal.ha.store.GlassFishKeyMapper@3e48dea8, dataStoreKeyHelper=org.shoal.ha.cache.api.ObjectKeyHelper@2212078a, startGMS=false, cacheLocally= false, clazzLoader=16.0, dataStoreEntryHelper=org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper@4dad067f, doASyncReplication=true, objectInputOutputStreamFactory=org.shoal.ha.cache.api.DefaultObjectInputOutputStreamFactory@1a85a3b0}|#] [#|2010-08-27T11:49:38.940+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|GlassFish2ShoalBackingStoreFactory:: CREATED an instance of: org.shoal.adapter.store.ReplicatedBackingStore|#] [#|2010-08-27T11:49:39.712+0530|INFO|glassfish3.1|com.sun.xml.ws.api.message.Packet|_ThreadID=16;_ThreadName=Thread-1;|Cannot resolve wsdl operation that this Packet is targeted for.|#] [#|2010-08-27T11:49:39.724+0530|INFO|glassfish3.1|com.sun.xml.ws.api.message.Packet|_ThreadID=16;_ThreadName=Thread-1;|Cannot resolve wsdl operation that this Packet is targeted for.|#] [#|2010-08-27T11:49:39.725+0530|INFO|glassfish3.1|com.sun.xml.ws.api.message.Packet|_ThreadID=16;_ThreadName=Thread-1;|Cannot resolve wsdl operation that this Packet is targeted for.|#] [#|2010-08-27T11:49:39.735+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|***** => load(uuid:36e0d96e-bbd5-40f6-b8e3-6dcf686f14a9, null)|#] [#|2010-08-27T11:49:39.852+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Wrote 1 commands; totalBytes: 101|#] [#|2010-08-27T11:49:39.857+0530|INFO|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;|{http://first/}MessageWSServiceService::{http://first/}MessageWSServicePort_SEQUENCE_DATA_BS: Sent ReplicationFramePayloadCommand: contains 1 commands to ALL MEMBERS ; size: 102|#] [#|2010-08-27T11:49:42.740+0530|WARNING|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;|LoadRequestCommand timed out while waiting for result java.util.concurrent.TimeoutException|#] [#|2010-08-27T11:49:42.905+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Entered GlassFish2ShoalBackingStoreFactory.createBackingStore...|#] [#|2010-08-27T11:49:42.907+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper created for: myName: server; groupName: st-cluster|#] [#|2010-08-27T11:49:42.908+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers: previous viewID: 0; current viewID: 0; null|#] [#|2010-08-27T11:49:42.931+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers. Entered ELSE 2|#] [#|2010-08-27T11:49:42.943+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.948+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.948+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.948+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.949+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.949+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 0; signal: null [current: ] [previous: ]|#] [#|2010-08-27T11:49:42.949+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-08-27T11:49:42.950+0530|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|ASync replication enabled...|#] [#|2010-08-27T11:49:42.950+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers: previous viewID: 0; current viewID: 0; null|#] [#|2010-08-27T11:49:42.950+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**GroupServiceProvider:checkAndNotifyAboutCurrentAndPreviousMembers. Entered ELSE 2|#] [#|2010-08-27T11:49:42.950+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.950+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.951+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.951+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.951+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.951+0530|INFO|glassfish3.1|org.shoal.ha.cache.mapper|_ThreadID=16;_ThreadName=Thread-1;|DefaultKeyMapper[server].onViewChange (isJoin: true) currentView: ; previousView |#] [#|2010-08-27T11:49:42.952+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**VIEW: prevViewId: 0; curViewID: 0; signal: null [current: ] [previous: ]|#] [#|2010-08-27T11:49:42.952+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|**********************************************************************|#] [#|2010-08-27T11:49:42.952+0530|INFO|glassfish3.1|org.shoal.ha.cache.config|_ThreadID=16;_ThreadName=Thread-1;|Created ReplicatedDataStore with config: DataStoreConfigurator{instanceName='server', groupName='st-cluster', storeName='uuid:5bf5dd39-91e7-42fa-9dbb-957bc64653c8_UNACKED_MESSAGES_BS', keyClazz=class java.lang.String, valueClazz=class com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceData$UnackedMessageReplicationManager$ApplicationMessageState, keyMapper=org.shoal.ha.store.GlassFishKeyMapper@56dacb7, dataStoreKeyHelper=org.shoal.ha.cache.api.ObjectKeyHelper@63662529, startGMS=false, cacheLocally= false, clazzLoader=16.0, dataStoreEntryHelper=org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper@4711d9ba, doASyncReplication=true, objectInputOutputStreamFactory=org.shoal.ha.cache.api.DefaultObjectInputOutputStreamFactory@64ed66c9}|#] [#|2010-08-27T11:49:42.952+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|GlassFish2ShoalBackingStoreFactory:: CREATED an instance of: org.shoal.adapter.store.ReplicatedBackingStore|#] [#|2010-08-27T11:49:42.960+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|***** => load(uuid:5bf5dd39-91e7-42fa-9dbb-957bc64653c8, null)|#] [#|2010-08-27T11:49:43.048+0530|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Wrote 1 commands; totalBytes: 101|#] [#|2010-08-27T11:49:43.049+0530|INFO|glassfish3.1|org.shoal.ha.cache.interceptor.transmit|_ThreadID=16;_ThreadName=Thread-1;|{http://first/}MessageWSServiceService::{http://first/}MessageWSServicePort_SEQUENCE_DATA_BS: Sent ReplicationFramePayloadCommand: contains 1 commands to ALL MEMBERS ; size: 102|#] [#|2010-08-27T11:49:45.961+0530|WARNING|glassfish3.1|org.shoal.ha.cache.command.load_request|_ThreadID=16;_ThreadName=Thread-1;|LoadRequestCommand timed out while waiting for result java.util.concurrent.TimeoutException|#] [#|2010-08-27T11:49:45.973+0530|SEVERE|glassfish3.1|com.sun.metro.assembler|_ThreadID=16;_ThreadName=Thread-1;| org.glassfish.ha.store.api.BackingStoreException: Error during save: uuid:5bf5dd39-91e7-42fa-9dbb-957bc64653c8 at org.shoal.adapter.store.ReplicatedBackingStore.save(ReplicatedBackingStore.java:165) at com.sun.xml.ws.assembler.dev.HighAvailabilityProvider.saveTo(HighAvailabilityProvider.java:297) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.save(InVmSequenceManager.java:437) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.save(InVmSequenceManager.java:69) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.HighlyAvailableMap.put(HighlyAvailableMap.java:163) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.registerSequence(InVmSequenceManager.java:363) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.createInboundSequence(InVmSequenceManager.java:228) at com.sun.xml.ws.rx.rm.runtime.ServerTube.handleCreateSequenceAction(ServerTube.java:358) at com.sun.xml.ws.rx.rm.runtime.ServerTube.processProtocolMessage(ServerTube.java:285) at com.sun.xml.ws.rx.rm.runtime.ServerTube.processRequest(ServerTube.java:164) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:637) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:596) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:581) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:478) at com.sun.xml.ws.api.pipe.helper.AbstractTubeImpl.process(AbstractTubeImpl.java:112) at com.sun.enterprise.security.webservices.CommonServerSecurityPipe.processRequest(CommonServerSecurityPipe.java:199) at com.sun.enterprise.security.webservices.CommonServerSecurityPipe.process(CommonServerSecurityPipe.java:131) at com.sun.xml.ws.api.pipe.helper.PipeAdapter.processRequest(PipeAdapter.java:115) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:637) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:596) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:581) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:478) at com.sun.xml.ws.server.WSEndpointImpl$2.process(WSEndpointImpl.java:310) at com.sun.xml.ws.transport.http.HttpAdapter$HttpToolkit.handle(HttpAdapter.java:554) at com.sun.xml.ws.transport.http.HttpAdapter.handle(HttpAdapter.java:252) at com.sun.xml.ws.transport.http.servlet.ServletAdapter.handle(ServletAdapter.java:144) at org.glassfish.webservices.JAXWSServlet.doPost(JAXWSServlet.java:155) at javax.servlet.http.HttpServlet.service(HttpServlet.java:755) at javax.servlet.http.HttpServlet.service(HttpServlet.java:848) at org.apache.catalina.core.StandardWrapper.service(StandardWrapper.java:1522) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:281) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175) at org.apache.catalina.core.StandardPipeline.doInvoke(StandardPipeline.java:655) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:595) at com.sun.enterprise.web.WebPipeline.invoke(WebPipeline.java:98) at com.sun.enterprise.web.PESessionLockingStandardPipeline.invoke(PESessionLockingStandardPipeline.java:91) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:162) at org.apache.catalina.connector.CoyoteAdapter.doService(CoyoteAdapter.java:325) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:226) at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:170) at com.sun.grizzly.http.ProcessorTask.invokeAdapter(ProcessorTask.java:824) at com.sun.grizzly.http.ProcessorTask.doProcess(ProcessorTask.java:721) at com.sun.grizzly.http.ProcessorTask.process(ProcessorTask.java:1014) at com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:220) at com.sun.grizzly.DefaultProtocolChain.executeProtocolFilter(DefaultProtocolChain.java:135) at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:102) at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:88) at com.sun.grizzly.http.HttpProtocolChain.execute(HttpProtocolChain.java:76) at com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:53) at com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:57) at com.sun.grizzly.ContextTask.run(ContextTask.java:69) at com.sun.grizzly.util.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:530) at com.sun.grizzly.util.AbstractThreadPool$Worker.run(AbstractThreadPool.java:511) at java.lang.Thread.run(Thread.java:637) Caused by: org.shoal.ha.cache.api.DataStoreException: Error during writeCommandPayload at org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor.onTransmit(CommandHandlerInterceptor.java:63) at org.shoal.ha.cache.impl.command.CommandManager.executeCommand(CommandManager.java:117) at org.shoal.ha.cache.impl.command.CommandManager.execute(CommandManager.java:109) at org.shoal.ha.cache.impl.store.ReplicatedDataStore.put(ReplicatedDataStore.java:149) at org.shoal.ha.cache.impl.store.ReplicatedDataStore.put(ReplicatedDataStore.java:63) at org.shoal.adapter.store.ReplicatedBackingStore.save(ReplicatedBackingStore.java:163) ... 53 more Caused by: java.io.NotSerializableException: org.shoal.adapter.store.ReplicatedBackingStore at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1156) at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1509) at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1474) at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1392) at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1150) at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:326) at org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper.writeObject(DefaultDataStoreEntryHelper.java:78) at org.shoal.adapter.store.commands.SaveCommand.writeCommandPayload(SaveCommand.java:95) at org.shoal.ha.cache.impl.command.Command.prepareTransmit(Command.java:102) at org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor.onTransmit(CommandHandlerInterceptor.java:61) ... 58 more |#] [#|2010-08-27T11:49:45.986+0530|SEVERE|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.webservices|_ThreadID=16;_ThreadName=Thread-1;|ws.error_next_pipe com.sun.xml.ws.assembler.dev.HighAvailabilityProviderException: at com.sun.xml.ws.assembler.dev.HighAvailabilityProvider.saveTo(HighAvailabilityProvider.java:299) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.save(InVmSequenceManager.java:437) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.save(InVmSequenceManager.java:69) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.HighlyAvailableMap.put(HighlyAvailableMap.java:163) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.registerSequence(InVmSequenceManager.java:363) at com.sun.xml.ws.rx.rm.runtime.sequence.invm.InVmSequenceManager.createInboundSequence(InVmSequenceManager.java:228) at com.sun.xml.ws.rx.rm.runtime.ServerTube.handleCreateSequenceAction(ServerTube.java:358) at com.sun.xml.ws.rx.rm.runtime.ServerTube.processProtocolMessage(ServerTube.java:285) at com.sun.xml.ws.rx.rm.runtime.ServerTube.processRequest(ServerTube.java:164) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:637) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:596) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:581) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:478) at com.sun.xml.ws.api.pipe.helper.AbstractTubeImpl.process(AbstractTubeImpl.java:112) at com.sun.enterprise.security.webservices.CommonServerSecurityPipe.processRequest(CommonServerSecurityPipe.java:199) at com.sun.enterprise.security.webservices.CommonServerSecurityPipe.process(CommonServerSecurityPipe.java:131) at com.sun.xml.ws.api.pipe.helper.PipeAdapter.processRequest(PipeAdapter.java:115) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:637) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:596) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:581) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:478) at com.sun.xml.ws.server.WSEndpointImpl$2.process(WSEndpointImpl.java:310) at com.sun.xml.ws.transport.http.HttpAdapter$HttpToolkit.handle(HttpAdapter.java:554) at com.sun.xml.ws.transport.http.HttpAdapter.handle(HttpAdapter.java:252) at com.sun.xml.ws.transport.http.servlet.ServletAdapter.handle(ServletAdapter.java:144) at org.glassfish.webservices.JAXWSServlet.doPost(JAXWSServlet.java:155) at javax.servlet.http.HttpServlet.service(HttpServlet.java:755) at javax.servlet.http.HttpServlet.service(HttpServlet.java:848) at org.apache.catalina.core.StandardWrapper.service(StandardWrapper.java:1522) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:281) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175) at org.apache.catalina.core.StandardPipeline.doInvoke(StandardPipeline.java:655) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:595) at com.sun.enterprise.web.WebPipeline.invoke(WebPipeline.java:98) at com.sun.enterprise.web.PESessionLockingStandardPipeline.invoke(PESessionLockingStandardPipeline.java:91) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:162) at org.apache.catalina.connector.CoyoteAdapter.doService(CoyoteAdapter.java:325) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:226) at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:170) at com.sun.grizzly.http.ProcessorTask.invokeAdapter(ProcessorTask.java:824) at com.sun.grizzly.http.ProcessorTask.doProcess(ProcessorTask.java:721) at com.sun.grizzly.http.ProcessorTask.process(ProcessorTask.java:1014) at com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:220) at com.sun.grizzly.DefaultProtocolChain.executeProtocolFilter(DefaultProtocolChain.java:135) at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:102) at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:88) at com.sun.grizzly.http.HttpProtocolChain.execute(HttpProtocolChain.java:76) at com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:53) at com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:57) at com.sun.grizzly.ContextTask.run(ContextTask.java:69) at com.sun.grizzly.util.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:530) at com.sun.grizzly.util.AbstractThreadPool$Worker.run(AbstractThreadPool.java:511) at java.lang.Thread.run(Thread.java:637) Caused by: org.glassfish.ha.store.api.BackingStoreException: Error during save: uuid:5bf5dd39-91e7-42fa-9dbb-957bc64653c8 at org.shoal.adapter.store.ReplicatedBackingStore.save(ReplicatedBackingStore.java:165) at com.sun.xml.ws.assembler.dev.HighAvailabilityProvider.saveTo(HighAvailabilityProvider.java:297) ... 52 more Caused by: org.shoal.ha.cache.api.DataStoreException: Error during writeCommandPayload at org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor.onTransmit(CommandHandlerInterceptor.java:63) at org.shoal.ha.cache.impl.command.CommandManager.executeCommand(CommandManager.java:117) at org.shoal.ha.cache.impl.command.CommandManager.execute(CommandManager.java:109) at org.shoal.ha.cache.impl.store.ReplicatedDataStore.put(ReplicatedDataStore.java:149) at org.shoal.ha.cache.impl.store.ReplicatedDataStore.put(ReplicatedDataStore.java:63) at org.shoal.adapter.store.ReplicatedBackingStore.save(ReplicatedBackingStore.java:163) ... 53 more Caused by: java.io.NotSerializableException: org.shoal.adapter.store.ReplicatedBackingStore at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1156) at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1509) at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1474) at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1392) at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1150) at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:326) at org.shoal.ha.cache.impl.util.DefaultDataStoreEntryHelper.writeObject(DefaultDataStoreEntryHelper.java:78) at org.shoal.adapter.store.commands.SaveCommand.writeCommandPayload(SaveCommand.java:95) at org.shoal.ha.cache.impl.command.Command.prepareTransmit(Command.java:102) at org.shoal.ha.cache.impl.interceptor.CommandHandlerInterceptor.onTransmit(CommandHandlerInterceptor.java:61) ... 58 more |#]