May 20, 2011 2:46:10 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /usr/java6/bin/java -cp /usr/opt/home/glassfish3/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:+HeapDumpOnOutOfMemoryError -XX:NewRatio=2 -XX:+PrintGCDateStamps -XX:+DisableExplicitGC -XX:+PrintClassHistogram -XX:+PrintGCDetails -XX:MaxPermSize=192m -Xss128k -Xverbosegclog:/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/logs/gc.log -Xmx768m -server -javaagent:/usr/opt/home/glassfish3/glassfish/lib/monitor/flashlight-agent.jar -Dfelix.fileinstall.poll=5000 -Dcom.sun.aas.instanceRoot=/usr/opt/home/glassfish3/glassfish/nodes/node3/in3 -Dfelix.fileinstall.bundles.startTransient=true -Dfelix.fileinstall.disableConfigSave=false -Dorg.glassfish.additionalOSGiBundlesToStart=org.apache.felix.shell,org.apache.felix.gogo.runtime,org.apache.felix.gogo.shell,org.apache.felix.gogo.command,org.apache.felix.fileinstall -Dosgi.shell.telnet.port=26666 -Dcom.sun.enterprise.config.config_environment_factory_class=com.sun.enterprise.config.serverbeans.AppserverConfigEnvironmentFactory -DANTLR_USE_DIRECT_CLASS_LOADING=true -Dosgi.shell.telnet.maxconn=1 -Djava.security.auth.login.config=/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/config/login.conf -Dfelix.fileinstall.dir=/usr/opt/home/glassfish3/glassfish/modules/autostart/ -Djavax.net.ssl.keyStore=/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/config/keystore.jks -Dosgi.shell.telnet.ip=127.0.0.1 -Dgosh.args=--noshutdown -c noop=true -Dcom.sun.aas.installRoot=/usr/opt/home/glassfish3/glassfish -Djavax.net.ssl.trustStore=/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/config/cacerts.jks -Djava.security.policy=/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/config/server.policy -Djava.endorsed.dirs=/usr/opt/home/glassfish3/glassfish/modules/endorsed:/usr/opt/home/glassfish3/glassfish/lib/endorsed -Djava.awt.headless=true -Dfelix.fileinstall.bundles.new.start=true -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver -Dfelix.fileinstall.log.level=3 -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as -Djava.ext.dirs=/usr/java6/lib/ext:/usr/java6/jre/lib/ext:/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/lib/ext -Djava.library.path=/usr/opt/home/glassfish3/glassfish/lib:/usr/java6/jre/lib/ppc:/usr/java6/jre/lib/ppc/j9vm:/usr/java6/lib/ppc:/usr/lib com.sun.enterprise.glassfish.bootstrap.ASMain -asadmin-classpath /usr/opt/home/glassfish3/glassfish/modules/admin-cli.jar -verbose false -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -debug false -type INSTANCE -asadmin-args --host,,,aixas2,,,--port,,,4848,,,--secure=false,,,--terse=false,,,--echo=false,,,--interactive=false,,,start-local-instance,,,--verbose=false,,,--debug=false,,,--node,,,node3,,,in3 -instancedir /usr/opt/home/glassfish3/glassfish/nodes/node3/in3 -instancename in3 -read-stdin true -upgrade false May 20, 2011 2:46:11 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 59 msec. [#|2011-05-20T14:46:23.644-0700|INFO|null|null|_ThreadID=1;_ThreadName=Thread-8;|Running GlassFish Version: GlassFish Server Open Source Edition 3.1.1-SNAPSHOT (build 5)|#] [#|2011-05-20T14:46:23.646-0700|WARNING|null|null|_ThreadID=1;_ThreadName=Thread-8;|Record begin marker is not a proper value so using default.|#] [#|2011-05-20T14:46:23.646-0700|WARNING|null|null|_ThreadID=1;_ThreadName=Thread-8;|Record end marker is not a proper value so using default.|#] [#|2011-05-20T14:46:23.646-0700|WARNING|null|null|_ThreadID=1;_ThreadName=Thread-8;|Log Format field separator is not a character so using default.|#] [#|2011-05-20T14:46:24.068-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|GMS1095: member: in3 is joining group: c1 ...|#] [#|2011-05-20T14:46:24.119-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|instantiated following NetworkManager implementation:com.sun.enterprise.mgmt.transport.grizzly.GrizzlyNetworkManager|#] [#|2011-05-20T14:46:24.124-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|GMS MAX_MESSAGE_LENGTH=4196352|#] [#|2011-05-20T14:46:24.126-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;| GrizzlyNetworkManager Configuration BIND_INTERFACE_ADDRESS:null NetworkInterfaceName:null TCPSTARTPORT..TCPENDPORT:9090..9200 MULTICAST_ADDRESS:MULTICAST_PORT:228.9.204.35:15724 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 |#] [#|2011-05-20T14:46:24.169-0700|INFO|glassfish3.1|grizzly|_ThreadID=1;_ThreadName=Thread-8;|GRIZZLY0001: Starting Grizzly Framework 1.9.35 - 5/20/11 2:46 PM|#] [#|2011-05-20T14:46:24.243-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|Grizzly controller listening on /0:0:0:0:0:0:0:0:9142. Controller started in 86 ms|#] [#|2011-05-20T14:46:24.280-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|Set via default minimum: MulticastSocket.getTimeToLive()=4|#] [#|2011-05-20T14:46:24.284-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|MulticastSocket configuration: local socket address: 0.0.0.0/0.0.0.0:15724 network interface: name:null index: -1 addresses: 0.0.0.0/0.0.0.0; multicast address:/228.9.204.35 timeToLive=4|#] [#|2011-05-20T14:46:24.313-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|HealthMonitor: heartBeatTimeout(ms)=2000 maxMissedBeats=3 failureDetectionTCPTimeout(ms)=10000 failureDetectionTCPPort=9000|#] [#|2011-05-20T14:46:24.316-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|starting cluster c1 for member:in3|#] [#|2011-05-20T14:46:24.317-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|GMS1061: MasterNode message listener is registered for member: in3 group: c1|#] [#|2011-05-20T14:46:24.328-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1092: GMS View Change Received for group: c1 : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: in3, MemberType: CORE, Address: 10.133.169.48:9142:228.9.204.35:15724:c1:in3 |#] [#|2011-05-20T14:46:24.330-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1016: Analyzing new membership snapshot received as part of event: MASTER_CHANGE_EVENT for member: in3 of group: c1|#] [#|2011-05-20T14:46:24.335-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1093: adding GroupLeadershipNotification signal leadermember: in3 of group: c1|#] [#|2011-05-20T14:46:24.325-0700|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=0;_ThreadName=Thread-8;|GMSAD1005: Member in3 joined group c1|#] [#|2011-05-20T14:46:24.337-0700|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=0;_ThreadName=Thread-8;|GMSAD1004: Started GMS for instance in3 in group c1|#] [#|2011-05-20T14:46:24.442-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=3;_ThreadName=Thread-8;|MySystemAdvertisement(summary): GMSMember name: in3 group: c1 memberType: CORE startTime: May 20, 2011 2:46:24 PM PDT ID:10.133.169.48:9142:228.9.204.35:15724:c1:in3 TCP uri(s):[tcp://10.133.169.48:4000]|#] [#|2011-05-20T14:46:24.444-0700|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=3;_ThreadName=Thread-8;|MySystemAdvertisement(dump)=PEERID[10.133.169.48:9142:228.9.204.35:15724:c1:in3] CUSTOMTAGS[START_TIME[2:46:24 PM PDT on May 20, 2011], GROUP_NAME:c1, MEMBER_TYPE:CORE], ENDPONTADDRESSES[[tcp://10.133.169.48:4000]], NAME:in3, OSNAME:AIX, OSVERSION:6.1, OSARCH:ppc, HWARCH:ppc, HWVENDOR:IBM Corporation|#] [#|2011-05-20T14:46:24.471-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1092: GMS View Change Received for group: c1 : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: in1, MemberType: CORE, Address: 10.133.169.43:9101:228.9.204.35:15724:c1:in1 2: MemberId: in2, MemberType: CORE, Address: 10.133.169.42:9097:228.9.204.35:15724:c1:in2 3: MemberId: in3, MemberType: CORE, Address: 10.133.169.48:9142:228.9.204.35:15724:c1:in3 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.43:9098:228.9.204.35:15724:c1:server |#] [#|2011-05-20T14:46:24.474-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1016: Analyzing new membership snapshot received as part of event: MASTER_CHANGE_EVENT for member: server of group: c1|#] [#|2011-05-20T14:46:24.476-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1093: adding GroupLeadershipNotification signal leadermember: server of group: c1|#] [#|2011-05-20T14:46:24.478-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1024: Adding Join member: in1 group: c1 StartupState: GROUP_STARTUP |#] [#|2011-05-20T14:46:24.480-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1024: Adding Join member: in2 group: c1 StartupState: GROUP_STARTUP |#] [#|2011-05-20T14:46:24.483-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1092: GMS View Change Received for group: c1 : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: in1, MemberType: CORE, Address: 10.133.169.43:9101:228.9.204.35:15724:c1:in1 2: MemberId: in2, MemberType: CORE, Address: 10.133.169.42:9097:228.9.204.35:15724:c1:in2 3: MemberId: in3, MemberType: CORE, Address: 10.133.169.48:9142:228.9.204.35:15724:c1:in3 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.43:9098:228.9.204.35:15724:c1:server |#] [#|2011-05-20T14:46:24.484-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1016: Analyzing new membership snapshot received as part of event: ADD_EVENT for member: in3 of group: c1|#] [#|2011-05-20T14:46:24.485-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1024: Adding Join member: in3 group: c1 StartupState: GROUP_STARTUP |#] [#|2011-05-20T14:46:24.839-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=4;_ThreadName=Thread-8;|Grizzly Framework 1.9.35 started in: 137ms - bound to [0.0.0.0:18080]|#] [#|2011-05-20T14:46:24.861-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=5;_ThreadName=Thread-8;|Grizzly Framework 1.9.35 started in: 13ms - bound to [0.0.0.0:18181]|#] [#|2011-05-20T14:46:24.922-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=6;_ThreadName=Thread-8;|Grizzly Framework 1.9.35 started in: 6ms - bound to [0.0.0.0:14848]|#] [#|2011-05-20T14:46:24.960-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=7;_ThreadName=Thread-8;|Grizzly Framework 1.9.35 started in: 13ms - bound to [0.0.0.0:18686]|#] [#|2011-05-20T14:46:25.598-0700|INFO|glassfish3.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=0;_ThreadName=Thread-8;|JTS5014: Recoverable JTS instance, serverId = [100]|#] [#|2011-05-20T14:46:26.094-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:18080]|#] [#|2011-05-20T14:46:26.105-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:18181]|#] [#|2011-05-20T14:46:26.118-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:14848]|#] [#|2011-05-20T14:46:26.180-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0171: Created virtual server [server]|#] [#|2011-05-20T14:46:26.186-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0171: Created virtual server [__asadmin]|#] [#|2011-05-20T14:46:27.373-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0172: Virtual server [server] loaded default web module []|#] [#|2011-05-20T14:46:28.300-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=0;_ThreadName=Thread-8;|core.start_container_done|#] [#|2011-05-20T14:46:28.310-0700|INFO|glassfish3.1|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=0;_ThreadName=Thread-8;|Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry|#] [#|2011-05-20T14:46:29.731-0700|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=7;_ThreadName=Thread-8;|ADDRESSLIST in setJmsServiceProvider: mq://aixas9:18686/,mq://aixas2:18686/,mq://aixas1:18686/|#] [#|2011-05-20T14:46:29.738-0700|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=7;_ThreadName=Thread-8;|JMS Service Connection URL is : mq://aixas9:18686/,mq://aixas2:18686/,mq://aixas1:18686/|#] [#|2011-05-20T14:46:30.052-0700|INFO|glassfish3.1|org.hibernate.validator.util.Version|_ThreadID=7;_ThreadName=Thread-8;|Hibernate Validator 4.1.0.Final|#] [#|2011-05-20T14:46:30.083-0700|INFO|glassfish3.1|org.hibernate.validator.engine.resolver.DefaultTraversableResolver|_ThreadID=7;_ThreadName=Thread-8;|Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.|#] [#|2011-05-20T14:46:30.285-0700|INFO|glassfish3.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=7;_ThreadName=Thread-8;|MQJMSRA_RA1101: GlassFish MQ JMS Resource Adapter: Version: 4.5.1 (Build 1-c) Compile: Tue May 10 19:30:04 PDT 2011|#] [#|2011-05-20T14:46:30.387-0700|INFO|glassfish3.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=7;_ThreadName=Thread-8;|MQJMSRA_RA1101: GlassFish MQ JMS Resource Adapter starting: broker is EMBEDDED, connection mode is TCP|#] [#|2011-05-20T14:46:30.464-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=0;_ThreadName=Thread-8;|SEC1002: Security Manager is OFF.|#] [#|2011-05-20T14:46:31.034-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=0;_ThreadName=Thread-8;|SEC1010: Entering Security Startup Service|#] [#|2011-05-20T14:46:31.043-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=0;_ThreadName=Thread-8;|SEC1143: Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper.|#] [#|2011-05-20T14:46:31.148-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=0;_ThreadName=Thread-8;|SEC1115: Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#] [#|2011-05-20T14:46:31.161-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=0;_ThreadName=Thread-8;|SEC1115: Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.|#] [#|2011-05-20T14:46:31.179-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security.auth.realm|_ThreadID=0;_ThreadName=Thread-8;|SEC1115: Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created.|#] [#|2011-05-20T14:46:31.201-0700|INFO|glassfish3.1|javax.enterprise.system.core.security.com.sun.enterprise.security|_ThreadID=0;_ThreadName=Thread-8;|SEC1011: Security Service(s) Started Successfully|#] [#|2011-05-20T14:46:35.291-0700|INFO|glassfish3.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=7;_ThreadName=Thread-8;|MQJMSRA_RA1101: GlassFish MQ JMS Resource Adapter Started:EMBEDDED|#] [#|2011-05-20T14:46:38.186-0700|INFO|glassfish3.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=0;_ThreadName=Thread-8;|JTS5014: Recoverable JTS instance, serverId = [13700]|#] [#|2011-05-20T14:46:48.391-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers.builder|_ThreadID=0;_ThreadName=Thread-8;|TopLevel AvailabilityService.getAvailabilityEnabled => true|#] [#|2011-05-20T14:46:48.398-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers.builder|_ThreadID=0;_ThreadName=Thread-8;|TopLevel EjbAvailabilityService.getAvailabilityEnabled => true|#] [#|2011-05-20T14:46:48.399-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers.builder|_ThreadID=0;_ThreadName=Thread-8;|**Global AvailabilityEnabled => true; isAppHAEnabled: true|#] [#|2011-05-20T14:46:48.400-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers.builder|_ThreadID=0;_ThreadName=Thread-8;|StatefulContainerBuilder AvailabilityEnabled for this app => true|#] [#|2011-05-20T14:46:48.403-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers.builder|_ThreadID=0;_ThreadName=Thread-8;|StatefulContainerBuilder.buildStoreManager() storeName: SFSB-85584551391461377-BackingStore|#] [#|2011-05-20T14:46:48.424-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|**GroupServiceProvider:: REGISTERED member event listeners for => |#] [#|2011-05-20T14:46:48.460-0700|WARNING|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers.builder|_ThreadID=0;_ThreadName=Thread-8;|StatefulContainerbuilder instantiated store: org.shoal.adapter.store.StoreableReplicatedBackingStore@31c231c2; ha-enabled: true ==> BackingStoreConfiguration{clusterName='c1', instanceName='in3', storeName='SFSB-85584551391461377-BackingStore', shortUniqueName='85584551391461377', storeType='replicated', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/session-store/SFSB-85584551391461377', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={key.mapper=org.shoal.ha.store.GlassFishKeyMapper@77a077a0, value.class.is.thread.safe=true, start.gms=false, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.ScrambledKeyGenerator@3b563b56, async.replication=true, local.caching=true}}|#] [#|2011-05-20T14:46:48.704-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers|_ThreadID=0;_ThreadName=Thread-8;|Portable JNDI names for EJB SFSB : [java:global/richAccess/richAccessSfsb/SFSB, java:global/richAccess/richAccessSfsb/SFSB!samples.rmiiiopclient.ejb.SFSBRemoteHomeRef]|#] [#|2011-05-20T14:46:48.705-0700|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers|_ThreadID=0;_ThreadName=Thread-8;|Glassfish-specific (Non-portable) JNDI names for EJB SFSB : [ejb/SFSB]|#] [#|2011-05-20T14:46:48.930-0700|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-8;|WEB0671: Loading application [richAccess#richAccess.war] at [/richAccess]|#] [#|2011-05-20T14:46:48.934-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=0;_ThreadName=Thread-8;|CORE10010: Loading application richAccess done in 20,558 ms|#] [#|2011-05-20T14:46:48.942-0700|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=0;_ThreadName=Thread-8;|GlassFish Server Open Source Edition 3.1.1-SNAPSHOT (5) startup time : Felix (11,642ms), startup services(25,626ms), total(37,268ms)|#] [#|2011-05-20T14:46:48.948-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-8;|GMS1099: GMS:Reporting Joined and Ready state to group: c1|#] [#|2011-05-20T14:46:48.998-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1092: GMS View Change Received for group: c1 : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: in1, MemberType: CORE, Address: 10.133.169.43:9101:228.9.204.35:15724:c1:in1 2: MemberId: in2, MemberType: CORE, Address: 10.133.169.42:9097:228.9.204.35:15724:c1:in2 3: MemberId: in3, MemberType: CORE, Address: 10.133.169.48:9142:228.9.204.35:15724:c1:in3 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.43:9098:228.9.204.35:15724:c1:server |#] [#|2011-05-20T14:46:48.999-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1016: Analyzing new membership snapshot received as part of event: JOINED_AND_READY_EVENT for member: in3 of group: c1|#] [#|2011-05-20T14:46:49.000-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1025: Adding Joined And Ready member: in3 group: c1 StartupState: GROUP_STARTUP |#] [#|2011-05-20T14:46:49.051-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=9;_ThreadName=Thread-8;|**VIEW: prevViewId: 0; curViewID: 1; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@63e063e [current: in1, in3] [previous: ]|#] [#|2011-05-20T14:46:49.052-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=9;_ThreadName=Thread-8;|**********************************************************************|#] [#|2011-05-20T14:46:49.030-0700|INFO|glassfish3.1|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=8;_ThreadName=Thread-8;|Recovery of Inbound Transactions started.|#] [#|2011-05-20T14:46:49.550-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=10;_ThreadName=Thread-8;|/usr/opt/home/glassfish3/glassfish/nodes/node3/in3/autodeploy/bundles does not exist, please create it.|#] [#|2011-05-20T14:46:49.590-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-cdi.jar|#] [#|2011-05-20T14:46:49.614-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2011-05-20T14:46:49.622-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-jta.jar|#] [#|2011-05-20T14:46:49.643-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=12;_ThreadName=Thread-8;|____________________________ Welcome to Apache Felix Gogo |#] [#|2011-05-20T14:46:49.689-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/org.apache.felix.eventadmin.jar|#] [#|2011-05-20T14:46:49.816-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2011-05-20T14:46:49.843-0700|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=8;_ThreadName=Thread-8;|Addresslist mq://aixas9:18686/|#] [#|2011-05-20T14:46:49.856-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-ee-resources.jar|#] [#|2011-05-20T14:46:49.863-0700|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=8;_ThreadName=Thread-8;|Addresslist mq://aixas2:18686/|#] [#|2011-05-20T14:46:49.867-0700|INFO|glassfish3.1|javax.enterprise.resource.jms.com.sun.enterprise.connectors.jms.system|_ThreadID=8;_ThreadName=Thread-8;|Addresslist mq://aixas1:18686/|#] [#|2011-05-20T14:46:49.869-0700|INFO|glassfish3.1|javax.enterprise.resource.resourceadapter.com.sun.enterprise.resource.recovery|_ThreadID=8;_ThreadName=Thread-8;|JMS resource recovery has created CFs = 3|#] [#|2011-05-20T14:46:49.991-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1092: GMS View Change Received for group: c1 : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: in1, MemberType: CORE, Address: 10.133.169.43:9101:228.9.204.35:15724:c1:in1 2: MemberId: in2, MemberType: CORE, Address: 10.133.169.42:9097:228.9.204.35:15724:c1:in2 3: MemberId: in3, MemberType: CORE, Address: 10.133.169.48:9142:228.9.204.35:15724:c1:in3 4: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.43:9098:228.9.204.35:15724:c1:server |#] [#|2011-05-20T14:46:49.993-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1016: Analyzing new membership snapshot received as part of event: JOINED_AND_READY_EVENT for member: in2 of group: c1|#] [#|2011-05-20T14:46:49.994-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-8;|GMS1025: Adding Joined And Ready member: in2 group: c1 StartupState: GROUP_STARTUP |#] [#|2011-05-20T14:46:49.998-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=13;_ThreadName=Thread-8;|**VIEW: prevViewId: 1; curViewID: 2; signal: com.sun.enterprise.ee.cms.impl.common.JoinedAndReadyNotificationSignalImpl@6e666e66 [current: in1, in2, in3] [previous: ]|#] [#|2011-05-20T14:46:49.999-0700|INFO|glassfish3.1|ShoalLogger|_ThreadID=13;_ThreadName=Thread-8;|**********************************************************************|#] [#|2011-05-20T14:46:50.025-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-jpa.jar|#] [#|2011-05-20T14:46:50.041-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-jdbc.jar|#] [#|2011-05-20T14:46:50.072-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2011-05-20T14:46:50.099-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/osgi-ejb-container.jar|#] [#|2011-05-20T14:46:50.097-0700|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=14;_ThreadName=Thread-8;|JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://aixas9.us.oracle.com:17676/jndi/rmi://aixas9.us.oracle.com:17676/jmxrmi|#] [#|2011-05-20T14:46:51.397-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Started bundle: file:/usr/opt/home/glassfish3/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2011-05-20T14:46:56.422-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Installed /usr/opt/home/glassfish3/glassfish/modules/autostart/org.apache.felix.fileinstall-autodeploy-bundles.cfg|#] [#|2011-05-20T14:46:56.430-0700|INFO|glassfish3.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-8;|Installed /usr/opt/home/glassfish3/glassfish/modules/autostart/org.apache.felix.webconsole.internal.servlet.OsgiManager.cfg|#] [#|2011-05-20T15:17:31.746-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-22T01:32:09.341-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-22T12:13:41.469-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.NullPointerException at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:610) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-22T14:42:10.072-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.NullPointerException at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:610) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-22T15:49:12.261-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-22T20:09:56.090-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-22T22:30:42.227-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-23T01:33:17.474-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-23T09:57:14.472-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.NullPointerException at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:610) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#] [#|2011-05-23T10:03:44.571-0700|SEVERE|glassfish3.1|org.apache.catalina.core.ContainerBase|_ThreadID=16;_ThreadName=Thread-8;|Exception invoking periodic operation: java.lang.ArrayIndexOutOfBoundsException at java.util.AbstractCollection.toArray(AbstractCollection.java:353) at org.apache.catalina.session.ManagerBase.findSessions(ManagerBase.java:907) at org.apache.catalina.session.PersistentManagerBase.processExpires(PersistentManagerBase.java:602) at org.apache.catalina.session.PersistentManagerBase.backgroundProcess(PersistentManagerBase.java:255) at org.apache.catalina.core.StandardContext.backgroundProcess(StandardContext.java:5783) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1791) at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1780) at java.lang.Thread.run(Thread.java:736) |#]