Nov 17, 2010 12:20:55 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /export/sonia/jdk1.6.0_18/bin/java -cp /export/sonia/v3/glassfish3/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:MaxPermSize=192m -XX:NewRatio=2 -Xmx512m -javaagent:/export/sonia/v3/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/sonia/v3/glassfish3/glassfish/modules/autostart/ -Djavax.net.ssl.keyStore=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/keystore.jks -Dosgi.shell.telnet.port=26666 -Djava.security.policy=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/server.policy -Dfelix.fileinstall.log.level=3 -Dfelix.fileinstall.poll=5000 -Dcom.sun.aas.instanceRoot=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1 -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/sonia/v3/glassfish3/glassfish/modules/endorsed:/export/sonia/v3/glassfish3/glassfish/lib/endorsed -Dcom.sun.aas.installRoot=/export/sonia/v3/glassfish3/glassfish -Dfelix.fileinstall.bundles.startTransient=true -Djava.ext.dirs=/export/sonia/jdk1.6.0_18/lib/ext:/export/sonia/jdk1.6.0_18/jre/lib/ext:/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/lib/ext -Dfelix.fileinstall.bundles.new.start=true -Djavax.net.ssl.trustStore=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/cacerts.jks -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as -Djava.security.auth.login.config=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/login.conf -DANTLR_USE_DIRECT_CLASS_LOADING=true -Dgosh.args=--noshutdown -c noop=true -Djava.library.path=/export/sonia/v3/glassfish3/glassfish/lib:/export/sonia/jdk1.6.0_18/jre/lib/sparc/server:/export/sonia/jdk1.6.0_18/jre/lib/sparc:/export/sonia/jdk1.6.0_18/lib/sparc:/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,,,clustered_instance_1 -instancename clustered_instance_1 -verbose false -debug false -asadmin-classpath /export/sonia/v3/glassfish3/glassfish/modules/admin-cli.jar -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -upgrade false -type INSTANCE -instancedir /export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1 -read-stdin true Nov 17, 2010 12:20:55 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 57 msec. [#|2010-11-17T12:21:19.423-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Running GlassFish Version: GlassFish Server Open Source Edition 3.1-b30 (build 30)|#] [#|2010-11-17T12:21:19.434-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: 478 milliseconds|#] [#|2010-11-17T12:21:20.510-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-17T12:21:20.512-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-17T12:21:20.517-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-17T12:21:20.518-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=16;_ThreadName=Thread-1;|Registered ReplicationStoreProxy with persistence-type = replication|#] [#|2010-11-17T12:21:20.678-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|clusterName=sqe-cluster clusterConfig=GlassFishConfigBean.com.sun.enterprise.config.serverbeans.Config|#] [#|2010-11-17T12:21:20.842-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-sqe-cluster}|#] [#|2010-11-17T12:21:20.843-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-sqe-cluster}|#] [#|2010-11-17T12:21:20.844-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|Printing all GMS properties: |#] [#|2010-11-17T12:21:20.939-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1095: Member clustered_instance_1 is joining group sqe-cluster ...|#] [#|2010-11-17T12:21:21.062-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|instantiated following NetworkManager implementation:com.sun.enterprise.mgmt.transport.grizzly.GrizzlyNetworkManager|#] [#|2010-11-17T12:21:21.068-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS MAX_MESSAGE_LENGTH=4196352|#] [#|2010-11-17T12:21:21.074-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.63.212:4847 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-17T12:21:21.255-0800|INFO|glassfish3.1|grizzly|_ThreadID=16;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.22 - 11/17/10 12:21 PM|#] [#|2010-11-17T12:21:21.348-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Grizzly controller listening on /0.0.0.0:9103. Controller started in 127 ms|#] [#|2010-11-17T12:21:21.365-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Set via default minimum: MulticastSocket.getTimeToLive()=4|#] [#|2010-11-17T12:21:21.368-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MulticastSocket configuration: local socket address: 0.0.0.0/0.0.0.0:4847 network interface: name:bge0 (bge0) index: 2 addresses: /10.133.185.9; multicast address:/228.9.63.212 timeToLive=4|#] [#|2010-11-17T12:21:21.407-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|HealthMonitor: heartBeatTimeout(ms)=2000 maxMissedBeats=3 failureDetectionTCPTimeout(ms)=10000 failureDetectionTCPPort=9000|#] [#|2010-11-17T12:21:21.411-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|starting cluster sqe-cluster for member:clustered_instance_1|#] [#|2010-11-17T12:21:21.412-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1061: MasterNode message listener is registered for member: clustered_instance_1 group: sqe-cluster|#] [#|2010-11-17T12:21:21.418-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1005: Member clustered_instance_1 joined group sqe-cluster|#] [#|2010-11-17T12:21:21.419-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1004: Started GMS for instance clustered_instance_1 in group sqe-cluster|#] [#|2010-11-17T12:21:21.486-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 |#] [#|2010-11-17T12:21:21.487-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: clustered_instance_1 of Group: sqe-cluster|#] [#|2010-11-17T12:21:21.492-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: clustered_instance_1 of group: sqe-cluster|#] [#|2010-11-17T12:21:21.547-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(summary): GMSMember name: clustered_instance_1 group: sqe-cluster memberType: CORE startTime: Nov 17, 2010 12:21:20 PM PST ID:10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 TCP uri(s):[tcp://10.133.185.9:4000]|#] [#|2010-11-17T12:21:21.550-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(dump)=PEERID[10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1] CUSTOMTAGS[GROUP_NAME:sqe-cluster, MEMBER_TYPE:CORE, START_TIME[12:21:20 PM PST on Nov 17, 2010]], ENDPONTADDRESSES[[tcp://10.133.185.9:4000]], NAME:clustered_instance_1, OSNAME:SunOS, OSVERSION:5.10, OSARCH:sparc, HWARCH:sparc, HWVENDOR:Sun Microsystems Inc.|#] [#|2010-11-17T12:21:23.256-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9101:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:21:23.257-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: sqe-cluster|#] [#|2010-11-17T12:21:23.257-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: server of group: sqe-cluster|#] [#|2010-11-17T12:21:23.258-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9101:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:21:23.258-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: clustered_instance_1 of Group: sqe-cluster|#] [#|2010-11-17T12:21:23.269-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: clustered_instance_1 group: sqe-cluster StartupState:INSTANCE_STARTUP |#] [#|2010-11-17T12:21:23.301-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-17T12:21:24.000-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-17T12:21:24.048-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 171ms - bound to [0.0.0.0:38080]|#] [#|2010-11-17T12:21:24.154-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 134ms - bound to [0.0.0.0:38181]|#] [#|2010-11-17T12:21:24.143-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 83ms - bound to [0.0.0.0:34848]|#] [#|2010-11-17T12:21:24.172-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 50ms - bound to [127.0.0.1:38686]|#] [#|2010-11-17T12:21:24.340-0800|INFO|glassfish3.1|org.hibernate.validator.util.Version|_ThreadID=16;_ThreadName=Thread-1;|Hibernate Validator null|#] [#|2010-11-17T12:21:24.357-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-17T12:21:25.154-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:38080]|#] [#|2010-11-17T12:21:25.221-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:38181]|#] [#|2010-11-17T12:21:25.246-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:34848]|#] [#|2010-11-17T12:21:25.331-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [server]|#] [#|2010-11-17T12:21:25.331-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-17T12:21:25.348-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-17T12:21:25.348-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-17T12:21:25.348-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-17T12:21:26.864-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-17T12:21:26.864-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-17T12:21:26.864-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-17T12:21:28.755-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-17T12:21:28.852-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 (22,587ms), startup services(10,474ms), total(33,061ms)|#] [#|2010-11-17T12:21:28.862-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1099: GMS:Reporting Joined and Ready state to group sqe-cluster|#] [#|2010-11-17T12:21:28.863-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1040: Calling reportMyState() with READY...|#] [#|2010-11-17T12:21:28.910-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9101:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:21:28.910-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: clustered_instance_1 of Group: sqe-cluster|#] [#|2010-11-17T12:21:28.911-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : clustered_instance_1 Group: :sqe-cluster StartupState:INSTANCE_STARTUP |#] [#|2010-11-17T12:21:29.230-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|____________________________ Welcome to Apache Felix Gogo |#] [#|2010-11-17T12:21:29.703-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|Binding RMI port to *:37676|#] [#|2010-11-17T12:21:32.206-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://jed-asqe-2:37676/jndi/rmi://jed-asqe-2:37676/jmxrmi|#] [#|2010-11-17T12:21:32.208-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-17T12:21:32.211-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-17T12:21:34.399-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-17T12:21:34.521-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ee-resources.jar|#] [#|2010-11-17T12:21:34.631-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2010-11-17T12:21:34.666-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jpa.jar|#] [#|2010-11-17T12:21:34.678-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-cdi.jar|#] [#|2010-11-17T12:21:34.708-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.fileinstall-autodeploy-bundles.cfg|#] [#|2010-11-17T12:21:34.716-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/autodeploy/bundles does not exist, please create it.|#] [#|2010-11-17T12:21:34.728-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jta.jar|#] [#|2010-11-17T12:21:34.751-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.webconsole.internal.servlet.OsgiManager.cfg|#] [#|2010-11-17T12:21:34.768-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2010-11-17T12:21:34.839-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2010-11-17T12:21:34.849-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jdbc.jar|#] [#|2010-11-17T12:21:34.862-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ejb-container.jar|#] [#|2010-11-17T12:21:34.972-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2010-11-17T12:21:35.529-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.eventadmin.jar|#] [#|2010-11-17T12:21:35.549-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ee-resources.jar|#] [#|2010-11-17T12:21:35.816-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2010-11-17T12:21:36.155-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jpa.jar|#] [#|2010-11-17T12:21:36.161-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-cdi.jar|#] [#|2010-11-17T12:21:36.175-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jta.jar|#] [#|2010-11-17T12:21:36.377-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2010-11-17T12:21:36.387-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|ExtenderManager starting|#] [#|2010-11-17T12:21:36.392-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijta.JTAExtender@1134043|#] [#|2010-11-17T12:21:36.552-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijpa.JPAExtender@1b0a038|#] [#|2010-11-17T12:21:36.554-0800|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=16;_ThreadName=Thread-1;| JPAExtender started|#] [#|2010-11-17T12:21:36.556-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiweb.WebExtender@1fa269b|#] [#|2010-11-17T12:21:36.573-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgi.ee.resources.ResourcesExtender@8fb561|#] [#|2010-11-17T12:21:36.908-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijavaeebase.JavaEEExtender@13aa4e|#] [#|2010-11-17T12:21:36.919-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2010-11-17T12:21:36.976-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijdbc.JDBCExtender@17a8369|#] [#|2010-11-17T12:21:36.983-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jdbc.jar|#] [#|2010-11-17T12:21:36.999-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiejb.EJBExtender@1d8e081|#] [#|2010-11-17T12:21:37.183-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ejb-container.jar|#] [#|2010-11-17T12:21:40.437-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2010-11-17T12:21:40.462-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-17T12:21:40.463-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-17T12:21:40.463-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-17T12:21:40.464-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-17T12:21:40.530-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.eventadmin.jar|#] [#|2010-11-17T12:21:40.532-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ee-resources.jar|#] [#|2010-11-17T12:21:40.532-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2010-11-17T12:21:40.533-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jpa.jar|#] [#|2010-11-17T12:21:40.534-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-cdi.jar|#] [#|2010-11-17T12:21:40.534-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jta.jar|#] [#|2010-11-17T12:21:40.535-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2010-11-17T12:21:40.535-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2010-11-17T12:21:40.536-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jdbc.jar|#] [#|2010-11-17T12:21:40.536-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ejb-container.jar|#] [#|2010-11-17T12:21:40.537-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2010-11-17T12:21:40.537-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.eventadmin.jar|#] [#|2010-11-17T12:21:40.630-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-17T12:21:40.631-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-17T12:21:40.631-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-17T12:21:40.632-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-17T12:22:11.335-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: clustered_instance_2, MemberType: CORE, Address: 10.133.185.9:9099:228.9.63.212:4847:sqe-cluster:clustered_instance_2 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9101:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:22:11.336-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: clustered_instance_2 of Group: sqe-cluster|#] [#|2010-11-17T12:22:11.337-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: clustered_instance_2 group: sqe-cluster StartupState:INSTANCE_STARTUP |#] [#|2010-11-17T12:22:18.261-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9103:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: clustered_instance_2, MemberType: CORE, Address: 10.133.185.9:9099:228.9.63.212:4847:sqe-cluster:clustered_instance_2 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9101:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:22:18.262-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: clustered_instance_2 of Group: sqe-cluster|#] [#|2010-11-17T12:22:18.262-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : clustered_instance_2 Group: :sqe-cluster StartupState:INSTANCE_STARTUP |#] [#|2010-11-17T12:26:29.715-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1015: Received Group Shutting down message from member : server of Group: sqe-cluster|#] [#|2010-11-17T12:26:29.843-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-17T12:26:29.862-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1008: GMSAdapter for member: clustered_instance_1 group: sqe-cluster received GlassfishEventType: prepare_shutdown|#] [#|2010-11-17T12:26:29.863-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1096: Member clustered_instance_1 is leaving group sqe-cluster|#] [#|2010-11-17T12:26:29.864-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1009: Leaving GMS group sqe-cluster with shutdown type set to GroupShutdown|#] [#|2010-11-17T12:26:29.872-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1110: Thread IP Multicast Listener for /228.9.63.212:4847 has completed.|#] [#|2010-11-17T12:26:29.874-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1065: Completed processing outstanding master node messages for member:clustered_instance_1 group:sqe-cluster outstandingMessages to process:0|#] [#|2010-11-17T12:26:29.886-0800|INFO|glassfish3.1|ShoalLogger.monitor|_ThreadID=16;_ThreadName=Thread-1;|BlockingIOMulicastSender monitoring stats: received: 447 core poolsize:10 largest pool size:10 task count:447 max queue size:0 rejected execution:0|#] [#|2010-11-17T12:26:30.824-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-17T12:26:30.827-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1091: View Window event processing thread for group sqe-cluster terminated normally|#] [#|2010-11-17T12:26:30.830-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1088: MessageWindow thread for group sqe-cluster terminated due to shutdown notification|#] [#|2010-11-17T12:26:30.831-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1107: SignalHandler task named GMS SignalHandler for Group-sqe-cluster thread exiting|#] [#|2010-11-17T12:26:30.833-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijta.JTAExtender@1134043|#] [#|2010-11-17T12:26:30.834-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijdbc.JDBCExtender@17a8369|#] [#|2010-11-17T12:26:30.840-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijpa.JPAExtender@1b0a038|#] [#|2010-11-17T12:26:30.841-0800|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=16;_ThreadName=Thread-1;| JPAExtender stopped|#] [#|2010-11-17T12:26:30.841-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgiejb.EJBExtender@1d8e081|#] [#|2010-11-17T12:26:30.848-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgiweb.WebExtender@1fa269b|#] [#|2010-11-17T12:26:30.862-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgijavaeebase.JavaEEExtender@13aa4e|#] [#|2010-11-17T12:26:30.879-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Stopping extender called org.glassfish.osgi.ee.resources.ResourcesExtender@8fb561|#] [#|2010-11-17T12:26:33.058-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-17T12:26:33.059-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=16;_ThreadName=Thread-1;|Unregistered ReplicationStoreProxy with persistence-type = replication|#] [#|2010-11-17T12:26:33.067-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-17T12:26:33.068-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|JMXStartupService: Stopped JMXConnectorServer: null|#] [#|2010-11-17T12:26:33.070-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-17T12:26:33.071-0800|INFO|glassfish3.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=16;_ThreadName=Thread-1;|Shutdown procedure finished|#] Nov 17, 2010 12:27:20 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /export/sonia/jdk1.6.0_18/bin/java -cp /export/sonia/v3/glassfish3/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:MaxPermSize=192m -XX:NewRatio=2 -Xmx512m -javaagent:/export/sonia/v3/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/sonia/v3/glassfish3/glassfish/modules/autostart/ -Djavax.net.ssl.keyStore=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/keystore.jks -Dosgi.shell.telnet.port=26666 -Djava.security.policy=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/server.policy -Dfelix.fileinstall.log.level=3 -Dfelix.fileinstall.poll=5000 -Dcom.sun.aas.instanceRoot=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1 -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/sonia/v3/glassfish3/glassfish/modules/endorsed:/export/sonia/v3/glassfish3/glassfish/lib/endorsed -Dcom.sun.aas.installRoot=/export/sonia/v3/glassfish3/glassfish -Dfelix.fileinstall.bundles.startTransient=true -Djava.ext.dirs=/export/sonia/jdk1.6.0_18/lib/ext:/export/sonia/jdk1.6.0_18/jre/lib/ext:/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/lib/ext -Dfelix.fileinstall.bundles.new.start=true -Djavax.net.ssl.trustStore=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/cacerts.jks -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as -Djava.security.auth.login.config=/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/config/login.conf -DANTLR_USE_DIRECT_CLASS_LOADING=true -Dgosh.args=--noshutdown -c noop=true -Djava.library.path=/export/sonia/v3/glassfish3/glassfish/lib:/export/sonia/jdk1.6.0_18/jre/lib/sparc/server:/export/sonia/jdk1.6.0_18/jre/lib/sparc:/export/sonia/jdk1.6.0_18/lib/sparc:/export/sonia/jdk1.6.0_18/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,,,localhost,,,clustered_instance_1 -instancename clustered_instance_1 -verbose false -debug false -asadmin-classpath /export/sonia/v3/glassfish3/glassfish/modules/admin-cli.jar -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -upgrade false -type INSTANCE -instancedir /export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1 -read-stdin true Nov 17, 2010 12:27:21 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 347 msec. [#|2010-11-17T12:27:41.837-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Running GlassFish Version: GlassFish Server Open Source Edition 3.1-b30 (build 30)|#] [#|2010-11-17T12:27:41.845-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: 534 milliseconds|#] [#|2010-11-17T12:27:43.142-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-17T12:27:43.143-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-17T12:27:43.178-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-17T12:27:43.179-0800|INFO|glassfish3.1|org.glassfish.ha.store.adapter.cache.ReplicationStoreProxy2|_ThreadID=16;_ThreadName=Thread-1;|Registered ReplicationStoreProxy with persistence-type = replication|#] [#|2010-11-17T12:27:43.388-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|clusterName=sqe-cluster clusterConfig=GlassFishConfigBean.com.sun.enterprise.config.serverbeans.Config|#] [#|2010-11-17T12:27:43.457-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-sqe-cluster}|#] [#|2010-11-17T12:27:43.458-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-sqe-cluster}|#] [#|2010-11-17T12:27:43.459-0800|CONFIG|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|Printing all GMS properties: |#] [#|2010-11-17T12:27:43.538-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1095: Member clustered_instance_1 is joining group sqe-cluster ...|#] [#|2010-11-17T12:27:43.887-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|instantiated following NetworkManager implementation:com.sun.enterprise.mgmt.transport.grizzly.GrizzlyNetworkManager|#] [#|2010-11-17T12:27:43.893-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS MAX_MESSAGE_LENGTH=4196352|#] [#|2010-11-17T12:27:44.051-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.63.212:4847 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-17T12:27:44.238-0800|INFO|glassfish3.1|grizzly|_ThreadID=16;_ThreadName=Thread-1;|GRIZZLY0001: Starting Grizzly Framework 1.9.22 - 11/17/10 12:27 PM|#] [#|2010-11-17T12:27:44.347-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Grizzly controller listening on /0.0.0.0:9111. Controller started in 216 ms|#] [#|2010-11-17T12:27:44.362-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|Set via default minimum: MulticastSocket.getTimeToLive()=4|#] [#|2010-11-17T12:27:44.364-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MulticastSocket configuration: local socket address: 0.0.0.0/0.0.0.0:4847 network interface: name:bge0 (bge0) index: 2 addresses: /10.133.185.9; multicast address:/228.9.63.212 timeToLive=4|#] [#|2010-11-17T12:27:44.459-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|HealthMonitor: heartBeatTimeout(ms)=2000 maxMissedBeats=3 failureDetectionTCPTimeout(ms)=10000 failureDetectionTCPPort=9000|#] [#|2010-11-17T12:27:44.462-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|starting cluster sqe-cluster for member:clustered_instance_1|#] [#|2010-11-17T12:27:44.463-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1061: MasterNode message listener is registered for member: clustered_instance_1 group: sqe-cluster|#] [#|2010-11-17T12:27:44.474-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1005: Member clustered_instance_1 joined group sqe-cluster|#] [#|2010-11-17T12:27:44.474-0800|INFO|glassfish3.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=16;_ThreadName=Thread-1;|GMSAD1004: Started GMS for instance clustered_instance_1 in group sqe-cluster|#] [#|2010-11-17T12:27:44.696-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1 |#] [#|2010-11-17T12:27:44.697-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: clustered_instance_1 of Group: sqe-cluster|#] [#|2010-11-17T12:27:44.717-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: clustered_instance_1 of group: sqe-cluster|#] [#|2010-11-17T12:27:44.791-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: clustered_instance_2, MemberType: CORE, Address: 10.133.185.9:9110:228.9.63.212:4847:sqe-cluster:clustered_instance_2 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9106:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:27:44.792-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: sqe-cluster|#] [#|2010-11-17T12:27:44.792-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1093: adding GroupLeadershipNotification signal leaderMember: server of group: sqe-cluster|#] [#|2010-11-17T12:27:45.001-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: clustered_instance_2 group: sqe-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-17T12:27:45.003-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: clustered_instance_2, MemberType: CORE, Address: 10.133.185.9:9110:228.9.63.212:4847:sqe-cluster:clustered_instance_2 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9106:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:27:45.003-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1016: Analyzing new membership snapshot received as part of event : ADD_EVENT for Member: clustered_instance_1 of Group: sqe-cluster|#] [#|2010-11-17T12:27:45.004-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1024: Adding Join member: clustered_instance_1 group: sqe-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-17T12:27:45.082-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(summary): GMSMember name: clustered_instance_1 group: sqe-cluster memberType: CORE startTime: Nov 17, 2010 12:27:43 PM PST ID:10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1 TCP uri(s):[tcp://10.133.185.9:4000]|#] [#|2010-11-17T12:27:45.084-0800|CONFIG|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|MySystemAdvertisement(dump)=PEERID[10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1] CUSTOMTAGS[GROUP_NAME:sqe-cluster, MEMBER_TYPE:CORE, START_TIME[12:27:43 PM PST on Nov 17, 2010]], ENDPONTADDRESSES[[tcp://10.133.185.9:4000]], NAME:clustered_instance_1, OSNAME:SunOS, OSVERSION:5.10, OSARCH:sparc, HWARCH:sparc, HWVENDOR:Sun Microsystems Inc.|#] [#|2010-11-17T12:27:46.997-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-17T12:27:48.448-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-17T12:27:48.878-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 406ms - bound to [0.0.0.0:38181]|#] [#|2010-11-17T12:27:48.905-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 693ms - bound to [0.0.0.0:38080]|#] [#|2010-11-17T12:27:48.902-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 408ms - bound to [0.0.0.0:34848]|#] [#|2010-11-17T12:27:48.966-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Grizzly Framework 1.9.22 started in: 225ms - bound to [127.0.0.1:38686]|#] [#|2010-11-17T12:27:49.792-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:38080]|#] [#|2010-11-17T12:27:49.864-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:38181]|#] [#|2010-11-17T12:27:50.011-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:34848]|#] [#|2010-11-17T12:27:50.114-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-17T12:27:50.114-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [server]|#] [#|2010-11-17T12:27:50.122-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-17T12:27:50.122-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-17T12:27:50.122-0800|INFO|glassfish3.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=1;_ThreadName=main;|WEB0171: Created virtual server [__asadmin]|#] [#|2010-11-17T12:27:52.956-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-17T12:27:52.956-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-17T12:27:52.956-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-17T12:27:57.991-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-17T12:27:58.241-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,052ms), startup services(18,698ms), total(36,750ms)|#] [#|2010-11-17T12:27:58.246-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1099: GMS:Reporting Joined and Ready state to group sqe-cluster|#] [#|2010-11-17T12:27:58.246-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1040: Calling reportMyState() with READY...|#] [#|2010-11-17T12:27:58.463-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: clustered_instance_2, MemberType: CORE, Address: 10.133.185.9:9110:228.9.63.212:4847:sqe-cluster:clustered_instance_2 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9106:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:27:58.464-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: clustered_instance_1 of Group: sqe-cluster|#] [#|2010-11-17T12:27:58.464-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : clustered_instance_1 Group: :sqe-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-17T12:27:58.976-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/autodeploy/bundles does not exist, please create it.|#] [#|2010-11-17T12:27:59.504-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1092: GMS View Change Received for group sqe-cluster : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: clustered_instance_1, MemberType: CORE, Address: 10.133.185.9:9111:228.9.63.212:4847:sqe-cluster:clustered_instance_1 2: MemberId: clustered_instance_2, MemberType: CORE, Address: 10.133.185.9:9110:228.9.63.212:4847:sqe-cluster:clustered_instance_2 3: MemberId: server, MemberType: SPECTATOR, Address: 10.133.185.9:9106:228.9.63.212:4847:sqe-cluster:server |#] [#|2010-11-17T12:27:59.505-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: clustered_instance_2 of Group: sqe-cluster|#] [#|2010-11-17T12:27:59.505-0800|INFO|glassfish3.1|ShoalLogger|_ThreadID=16;_ThreadName=Thread-1;|GMS1025: Adding Joined And Ready member : clustered_instance_2 Group: :sqe-cluster StartupState:GROUP_STARTUP |#] [#|2010-11-17T12:27:59.663-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.scr.jar|#] [#|2010-11-17T12:27:59.668-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|____________________________ Welcome to Apache Felix Gogo |#] [#|2010-11-17T12:27:59.726-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ee-resources.jar|#] [#|2010-11-17T12:27:59.853-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jpa.jar|#] [#|2010-11-17T12:27:59.880-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-cdi.jar|#] [#|2010-11-17T12:27:59.949-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-web-container.jar|#] [#|2010-11-17T12:27:59.969-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|ExtenderManager starting|#] [#|2010-11-17T12:28:00.001-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijpa.JPAExtender@d27bb0|#] [#|2010-11-17T12:28:00.002-0800|INFO|glassfish3.1|org.glassfish.osgijpa|_ThreadID=16;_ThreadName=Thread-1;| JPAExtender started|#] [#|2010-11-17T12:28:00.003-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiweb.WebExtender@b679e1|#] [#|2010-11-17T12:28:00.075-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgi.ee.resources.ResourcesExtender@15b6c19|#] [#|2010-11-17T12:28:00.285-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijavaeebase.JavaEEExtender@1c142c2|#] [#|2010-11-17T12:28:00.302-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-javaee-base.jar|#] [#|2010-11-17T12:28:00.327-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijdbc.JDBCExtender@d1fa4|#] [#|2010-11-17T12:28:00.333-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jdbc.jar|#] [#|2010-11-17T12:28:00.470-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgijta.JTAExtender@1b8424e|#] [#|2010-11-17T12:28:00.621-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-jta.jar|#] [#|2010-11-17T12:28:00.677-0800|INFO|glassfish3.1|org.glassfish.osgijavaeebase|_ThreadID=16;_ThreadName=Thread-1;|Starting extender called org.glassfish.osgiejb.EJBExtender@b4855e|#] [#|2010-11-17T12:28:00.681-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=16;_ThreadName=Thread-1;|Binding RMI port to *:37676|#] [#|2010-11-17T12:28:00.910-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/osgi-ejb-container.jar|#] [#|2010-11-17T12:28:03.915-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://jed-asqe-2:37676/jndi/rmi://jed-asqe-2:37676/jmxrmi|#] [#|2010-11-17T12:28:03.916-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-17T12:28:03.919-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-17T12:28:05.489-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-17T12:28:09.010-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.bundlerepository.jar|#] [#|2010-11-17T12:28:09.025-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-17T12:28:09.025-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-17T12:28:09.026-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-17T12:28:09.026-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-17T12:28:09.078-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Started bundle: file:/export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.eventadmin.jar|#] [#|2010-11-17T12:28:09.106-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.CacheSize=30|#] [#|2010-11-17T12:28:09.107-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.ThreadPoolSize=20|#] [#|2010-11-17T12:28:09.107-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.Timeout=5000|#] [#|2010-11-17T12:28:09.108-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|DEBUG: EventAdmin: org.apache.felix.eventadmin.RequireTopic=true|#] [#|2010-11-17T12:28:14.158-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.fileinstall-autodeploy-bundles.cfg|#] [#|2010-11-17T12:28:14.161-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|/export/sonia/v3/glassfish3/glassfish/nodes/localhost/clustered_instance_1/autodeploy/bundles does not exist, please create it.|#] [#|2010-11-17T12:28:14.173-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Installed /export/sonia/v3/glassfish3/glassfish/modules/autostart/org.apache.felix.webconsole.internal.servlet.OsgiManager.cfg|#] [#|2010-11-17T12:30:56.682-0800|INFO|glassfish3.1|org.hibernate.validator.util.Version|_ThreadID=16;_ThreadName=Thread-1;|Hibernate Validator null|#] [#|2010-11-17T12:30:56.809-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-17T12:31:02.121-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-17T12:31:03.831-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-17T12:31:03.841-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-17T12:31:04.405-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-17T12:31:04.409-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-17T12:31:04.426-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-17T12:31:04.957-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-17T12:31:19.340-0800|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices.metroglue|_ThreadID=16;_ThreadName=Thread-1;|WS10010: Web service endpoint deployment events listener registered successfully.|#] [#|2010-11-17T12:31:19.351-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|initHaEnvironment is called: sqe-cluster clustered_instance_1|#] [#|2010-11-17T12:31:19.352-0800|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices.metroglue|_ThreadID=16;_ThreadName=Thread-1;|WS10020: High availability environment configuration injected into Metro high availability provider.|#] [#|2010-11-17T12:31:31.894-0800|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00018: Webservice Endpoint deployed EJB listening at address at StateTaxIFPort|#] [#|2010-11-17T12:31:31.896-0800|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00018: Webservice Endpoint deployed EJB listening at address at FedTaxIFPort|#] [#|2010-11-17T12:31:36.140-0800|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00019: EJB Endpoint deployed wss-ejbtaxcalApp listening at address at http://jed-asqe-2:38080/statetaxejb|#] [#|2010-11-17T12:31:36.148-0800|INFO|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;|WS00019: EJB Endpoint deployed wss-ejbtaxcalApp listening at address at http://jed-asqe-2:38080/fedtaxejb|#] [#|2010-11-17T12:31:36.513-0800|INFO|glassfish3.1|javax.enterprise.system.container.appclient.org.glassfish.appclient.server.core|_ThreadID=16;_ThreadName=Thread-1;|ACDEPL103: Java Web Start services started for the app client wss-ejbtaxcalApp/wss-ejbtaxcal-client.jar (contextRoot: /wss-ejbtaxcalApp/wss-ejbtaxcal-client)|#] [#|2010-11-17T12:31:36.862-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.deployment.admin|_ThreadID=16;_ThreadName=Thread-1;|wss-ejbtaxcalApp was successfully deployed in 41,528 milliseconds.|#] [#|2010-11-17T12:31:55.711-0800|INFO|glassfish3.1|com.sun.xml.wss.logging.impl.filter|_ThreadID=16;_ThreadName=Thread-1;|==== Received Message Start ==== 2010-11-17T20:31:52Z 2010-11-17T20:36:52Z MIIClDCCAf2gAwIBAgIETOPL0DANBgkqhkiG9w0BAQUFADB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwHhcNMTAxMTE3MTIzNDI0WhcNMjAxMTE0MTIzNDI0WjB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwgZ8wDQYJKoZIhvcNAQEBBQADgY0AMIGJAoGBAN2QUZU96AGdf1TXCbve9N2kbpqHGLnHYijPF4JczBjuPN6AfcBOLygiEqMTFpVIfZ2ugiiVWioqqer8OExrHM8oj3HUWsVOhDqbHAbKE5qpW5pg01pc/632a66e6JMYkrqoBbaf+EoaIYM0gkoRGMfLLqcsMwnLvuv8Z3bw+aTBAgMBAAGjITAfMB0GA1UdDgQWBBSz6f0Y8tnMM+k5YN+cNUUxFfsikDANBgkqhkiG9w0BAQUFAAOBgQBk73L56BRJvRGH5OMUzBs/V/UHyMGktTcf73vKCRqwFmQDzegmbIEHQ14qXrI4IUT8ttzt0OKFK9q9pAFqpXtbXZJpKYOq8qYr8Z+KbAYLct/Kqr6fH0VVFNVobztzLVpk47gcTEZC/gqW7Fkveg4iXn+lMQa/DiNcjjpE9tKLZA== W8V7A0LYKQHMuM61FU28WvsYIqk= L+v+xIXCvGN15mbTD9dnStW0J6Y= kXLXodgaAL8fXuYKDeGtWFuzVBQN5fbVeBWScR+lG5ECY6hquGW6t6Emp+YTslvkXJG60p3IIf0c GtNLbk4wlPeQXFl4NmCYJR/Yl7d2tJl9bucJG81qU0q0dz9Vqk4oNFm/xjpvx3Bdz7RcmOlUnnaf dteV34dzM8ru0UZu9wU= 85000.0 5000.0 ==== Received Message End ==== |#] [#|2010-11-17T12:31:57.216-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|In StateTaxEjb::ejbCreate !!|#] [#|2010-11-17T12:31:57.508-0800|INFO|glassfish3.1|com.sun.xml.wss.logging.impl.filter|_ThreadID=16;_ThreadName=Thread-1;|==== Sending Message Start ==== 2010-11-17T20:31:57Z 2010-11-17T20:36:57Z MIIClDCCAf2gAwIBAgIETOPL0DANBgkqhkiG9w0BAQUFADB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwHhcNMTAxMTE3MTIzNDI0WhcNMjAxMTE0MTIzNDI0WjB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwgZ8wDQYJKoZIhvcNAQEBBQADgY0AMIGJAoGBAN2QUZU96AGdf1TXCbve9N2kbpqHGLnHYijPF4JczBjuPN6AfcBOLygiEqMTFpVIfZ2ugiiVWioqqer8OExrHM8oj3HUWsVOhDqbHAbKE5qpW5pg01pc/632a66e6JMYkrqoBbaf+EoaIYM0gkoRGMfLLqcsMwnLvuv8Z3bw+aTBAgMBAAGjITAfMB0GA1UdDgQWBBSz6f0Y8tnMM+k5YN+cNUUxFfsikDANBgkqhkiG9w0BAQUFAAOBgQBk73L56BRJvRGH5OMUzBs/V/UHyMGktTcf73vKCRqwFmQDzegmbIEHQ14qXrI4IUT8ttzt0OKFK9q9pAFqpXtbXZJpKYOq8qYr8Z+KbAYLct/Kqr6fH0VVFNVobztzLVpk47gcTEZC/gqW7Fkveg4iXn+lMQa/DiNcjjpE9tKLZA== BdHCZYetDbgQVLiBK40rILtN7bU= NoWE3ZHzf8CdNqlKBjv7TKWNfGo= WHcbn008K1haTQDd3IxHlup/wiymh6LtJ8gOjKfhl7u3aTkxT8/N+Wv0aZqoh0fHowDqZ7oXcudv ow/sqo3xoIQ7qzjoP6w9R+i+UXivYkbz3sAAPRf5ZrpB4T6cauUx7lT4A49p2Ymj9061evSR4Qzv jiJZWvyOM5k0O4CXEI4= 24000.0 ==== Sending Message End ==== |#] [#|2010-11-17T12:31:58.016-0800|INFO|glassfish3.1|com.sun.xml.wss.logging.impl.filter|_ThreadID=16;_ThreadName=Thread-1;|==== Received Message Start ==== 2010-11-17T20:31:57Z 2010-11-17T20:36:57Z MIIClDCCAf2gAwIBAgIETOPL0DANBgkqhkiG9w0BAQUFADB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwHhcNMTAxMTE3MTIzNDI0WhcNMjAxMTE0MTIzNDI0WjB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwgZ8wDQYJKoZIhvcNAQEBBQADgY0AMIGJAoGBAN2QUZU96AGdf1TXCbve9N2kbpqHGLnHYijPF4JczBjuPN6AfcBOLygiEqMTFpVIfZ2ugiiVWioqqer8OExrHM8oj3HUWsVOhDqbHAbKE5qpW5pg01pc/632a66e6JMYkrqoBbaf+EoaIYM0gkoRGMfLLqcsMwnLvuv8Z3bw+aTBAgMBAAGjITAfMB0GA1UdDgQWBBSz6f0Y8tnMM+k5YN+cNUUxFfsikDANBgkqhkiG9w0BAQUFAAOBgQBk73L56BRJvRGH5OMUzBs/V/UHyMGktTcf73vKCRqwFmQDzegmbIEHQ14qXrI4IUT8ttzt0OKFK9q9pAFqpXtbXZJpKYOq8qYr8Z+KbAYLct/Kqr6fH0VVFNVobztzLVpk47gcTEZC/gqW7Fkveg4iXn+lMQa/DiNcjjpE9tKLZA== t7FRbV9NMK4zxpCH/Vn2x8gnUFc= pbcvWJfPtAXk5xPd7bCFx/VlrA4= ehLAfxaoeRQHPZOwAnZd+8SlHwXkqG+m3ls2gCGyiGxsA++aEr1AdnxihBD9nccylpGynqW5KHnD Urxg8qA4QDCaHAMvDEnJZytHrxUfwrkAg27Q7vdnnTafN3sfRfFrVq9ZBQKwsL9/I4U6/rOXRwlN Y/hfMRWwHPyoUemlnOE= 97000.0 7000.0 ==== Received Message End ==== |#] [#|2010-11-17T12:31:58.078-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|In FedTaxEjb::ejbCreate !!|#] [#|2010-11-17T12:31:58.197-0800|INFO|glassfish3.1|com.sun.xml.wss.logging.impl.filter|_ThreadID=16;_ThreadName=Thread-1;|==== Sending Message Start ==== 2010-11-17T20:31:58Z 2010-11-17T20:36:58Z MIIClDCCAf2gAwIBAgIETOPL0DANBgkqhkiG9w0BAQUFADB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwHhcNMTAxMTE3MTIzNDI0WhcNMjAxMTE0MTIzNDI0WjB9MQswCQYDVQQGEwJVUzETMBEGA1UECBMKQ2FsaWZvcm5pYTEUMBIGA1UEBxMLU2FudGEgQ2xhcmExGzAZBgNVBAoTEk9yYWNsZSBDb3Jwb3JhdGlvbjESMBAGA1UECxMJR2xhc3NGaXNoMRIwEAYDVQQDEwlsb2NhbGhvc3QwgZ8wDQYJKoZIhvcNAQEBBQADgY0AMIGJAoGBAN2QUZU96AGdf1TXCbve9N2kbpqHGLnHYijPF4JczBjuPN6AfcBOLygiEqMTFpVIfZ2ugiiVWioqqer8OExrHM8oj3HUWsVOhDqbHAbKE5qpW5pg01pc/632a66e6JMYkrqoBbaf+EoaIYM0gkoRGMfLLqcsMwnLvuv8Z3bw+aTBAgMBAAGjITAfMB0GA1UdDgQWBBSz6f0Y8tnMM+k5YN+cNUUxFfsikDANBgkqhkiG9w0BAQUFAAOBgQBk73L56BRJvRGH5OMUzBs/V/UHyMGktTcf73vKCRqwFmQDzegmbIEHQ14qXrI4IUT8ttzt0OKFK9q9pAFqpXtbXZJpKYOq8qYr8Z+KbAYLct/Kqr6fH0VVFNVobztzLVpk47gcTEZC/gqW7Fkveg4iXn+lMQa/DiNcjjpE9tKLZA== wvq9Z7oJT7zesq2eILycxvHadiU= 9ChDPoPPwtfLbl+0j70M1YJC2O4= dKq2enPTjX0htEkBKjj8RGNt8U5CT2bmXpPLozHjqsYO5++2RXEQEgNz1C9Q1+U337979IOJpwOd p316DrGGB4WF+++0vqbEHp04QYRVm/YIikQzIU3C4VrcDId685ZE9KX3iWUQrnLGf+50VWOPxT0x Ah7mi+IRtOKuIVY8shI= 18000.0 ==== Sending Message End ==== |#] [#|2010-11-17T12:34:12.352-0800|INFO|glassfish3.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=16;_ThreadName=Thread-1;|JTS5014: Recoverable JTS instance, serverId = [33700]|#] [#|2010-11-17T12:34:15.044-0800|INFO|glassfish3.1|javax.enterprise.system.container.ejb.com.sun.ejb.containers|_ThreadID=16;_ThreadName=Thread-1;|Portable JNDI names for EJB ClientBean : [java:global/wss-ejbws-ejbcApp/wss-ejbws-ejbc-ejb/ClientBean, java:global/wss-ejbws-ejbcApp/wss-ejbws-ejbc-ejb/ClientBean!com.sun.appserv.sqe.security.wss.ejbws.taxcal.ejbclient.ClientRemoteHome]|#] [#|2010-11-17T12:34:15.049-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 ClientBean : [ejb/ClientBean]|#] [#|2010-11-17T12:34:15.305-0800|INFO|glassfish3.1|javax.enterprise.system.container.appclient.org.glassfish.appclient.server.core|_ThreadID=16;_ThreadName=Thread-1;|ACDEPL103: Java Web Start services started for the app client wss-ejbws-ejbcApp/wss-ejbws-ejbc-client.jar (contextRoot: /wss-ejbws-ejbcApp/wss-ejbws-ejbc-client)|#] [#|2010-11-17T12:34:15.412-0800|INFO|glassfish3.1|javax.enterprise.system.tools.admin.org.glassfish.deployment.admin|_ThreadID=16;_ThreadName=Thread-1;|wss-ejbws-ejbcApp was successfully deployed in 24,435 milliseconds.|#] [#|2010-11-17T12:35:15.556-0800|INFO|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|In ClientBean::ejbCreate !!|#] [#|2010-11-17T12:35:16.422-0800|WARNING|glassfish3.1|javax.enterprise.webservices.org.glassfish.webservices|_ThreadID=16;_ThreadName=Thread-1;| javax.xml.ws.WebServiceException: null is not a valid service at com.sun.xml.ws.client.WSServiceDelegate.(WSServiceDelegate.java:198) at com.sun.xml.ws.client.WSServiceDelegate.(WSServiceDelegate.java:186) at com.sun.xml.ws.spi.ProviderImpl.createServiceDelegate(ProviderImpl.java:111) at javax.xml.ws.Service.(Service.java:57) at javax.xml.ws.Service.create(Service.java:687) at org.glassfish.webservices.WebServiceReferenceManagerImpl.resolveWSReference(WebServiceReferenceManagerImpl.java:193) at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$WebServiceRefProxy.create(ComponentEnvManagerImpl.java:986) at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:771) at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:740) at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:166) at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:538) at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:491) at javax.naming.InitialContext.lookup(InitialContext.java:392) at javax.naming.InitialContext.lookup(InitialContext.java:392) at com.sun.appserv.sqe.security.wss.ejbws.taxcal.ejbclient.ClientBean.callFedTaxService(ClientBean.java:48) at com.sun.appserv.sqe.security.wss.ejbws.taxcal.ejbclient.ClientBean.getFedTax(ClientBean.java:32) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.glassfish.ejb.security.application.EJBSecurityManager.runMethod(EJBSecurityManager.java:1052) at org.glassfish.ejb.security.application.EJBSecurityManager.invoke(EJBSecurityManager.java:1124) at com.sun.ejb.containers.BaseContainer.invokeBeanMethod(BaseContainer.java:5367) at com.sun.ejb.EjbInvocation.invokeBeanMethod(EjbInvocation.java:619) at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:801) at com.sun.ejb.EjbInvocation.proceed(EjbInvocation.java:571) at com.sun.ejb.containers.interceptors.SystemInterceptorProxy.doAround(SystemInterceptorProxy.java:162) at com.sun.ejb.containers.interceptors.SystemInterceptorProxy.aroundInvoke(SystemInterceptorProxy.java:144) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at com.sun.ejb.containers.interceptors.AroundInvokeInterceptor.intercept(InterceptorManager.java:862) at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:801) at com.sun.ejb.containers.interceptors.InterceptorManager.intercept(InterceptorManager.java:371) at com.sun.ejb.containers.BaseContainer.__intercept(BaseContainer.java:5339) at com.sun.ejb.containers.BaseContainer.intercept(BaseContainer.java:5327) at com.sun.ejb.containers.EJBObjectInvocationHandler.invoke(EJBObjectInvocationHandler.java:206) at com.sun.ejb.containers.EJBObjectInvocationHandler.invoke(EJBObjectInvocationHandler.java:123) at $Proxy183.getFedTax(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:144) at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:174) at com.sun.corba.ee.impl.protocol.CorbaServerRequestDispatcherImpl.dispatchToServant(CorbaServerRequestDispatcherImpl.java:528) at com.sun.corba.ee.impl.protocol.CorbaServerRequestDispatcherImpl.dispatch(CorbaServerRequestDispatcherImpl.java:199) at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleRequestRequest(CorbaMessageMediatorImpl.java:1624) at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleRequest(CorbaMessageMediatorImpl.java:1486) at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleInput(CorbaMessageMediatorImpl.java:990) at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:214) at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleRequest(CorbaMessageMediatorImpl.java:742) at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.dispatch(CorbaMessageMediatorImpl.java:539) at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.doWork(CorbaMessageMediatorImpl.java:2324) at com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:496) at com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:537) |#] [#|2010-11-17T12:35:16.445-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|javax.naming.NamingException: Lookup failed for 'java:comp/env/service/TaxCalEjbService' in SerialContext[targetHost=null,targetPort=null [Root exception is javax.naming.NamingException [Root exception is javax.xml.ws.WebServiceException: null is not a valid service]]|#] [#|2010-11-17T12:35:16.446-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:561)|#] [#|2010-11-17T12:35:16.446-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:491)|#] [#|2010-11-17T12:35:16.447-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at javax.naming.InitialContext.lookup(InitialContext.java:392)|#] [#|2010-11-17T12:35:16.447-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at javax.naming.InitialContext.lookup(InitialContext.java:392)|#] [#|2010-11-17T12:35:16.447-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.appserv.sqe.security.wss.ejbws.taxcal.ejbclient.ClientBean.callFedTaxService(ClientBean.java:48)|#] [#|2010-11-17T12:35:16.448-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.appserv.sqe.security.wss.ejbws.taxcal.ejbclient.ClientBean.getFedTax(ClientBean.java:32)|#] [#|2010-11-17T12:35:16.448-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)|#] [#|2010-11-17T12:35:16.448-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)|#] [#|2010-11-17T12:35:16.449-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)|#] [#|2010-11-17T12:35:16.449-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at java.lang.reflect.Method.invoke(Method.java:597)|#] [#|2010-11-17T12:35:16.449-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at org.glassfish.ejb.security.application.EJBSecurityManager.runMethod(EJBSecurityManager.java:1052)|#] [#|2010-11-17T12:35:16.450-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at org.glassfish.ejb.security.application.EJBSecurityManager.invoke(EJBSecurityManager.java:1124)|#] [#|2010-11-17T12:35:16.450-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.BaseContainer.invokeBeanMethod(BaseContainer.java:5367)|#] [#|2010-11-17T12:35:16.450-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.EjbInvocation.invokeBeanMethod(EjbInvocation.java:619)|#] [#|2010-11-17T12:35:16.451-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:801)|#] [#|2010-11-17T12:35:16.451-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.EjbInvocation.proceed(EjbInvocation.java:571)|#] [#|2010-11-17T12:35:16.451-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.interceptors.SystemInterceptorProxy.doAround(SystemInterceptorProxy.java:162)|#] [#|2010-11-17T12:35:16.452-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.interceptors.SystemInterceptorProxy.aroundInvoke(SystemInterceptorProxy.java:144)|#] [#|2010-11-17T12:35:16.452-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)|#] [#|2010-11-17T12:35:16.453-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)|#] [#|2010-11-17T12:35:16.453-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)|#] [#|2010-11-17T12:35:16.454-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at java.lang.reflect.Method.invoke(Method.java:597)|#] [#|2010-11-17T12:35:16.454-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.interceptors.AroundInvokeInterceptor.intercept(InterceptorManager.java:862)|#] [#|2010-11-17T12:35:16.455-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:801)|#] [#|2010-11-17T12:35:16.455-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.interceptors.InterceptorManager.intercept(InterceptorManager.java:371)|#] [#|2010-11-17T12:35:16.456-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.BaseContainer.__intercept(BaseContainer.java:5339)|#] [#|2010-11-17T12:35:16.456-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.BaseContainer.intercept(BaseContainer.java:5327)|#] [#|2010-11-17T12:35:16.457-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.EJBObjectInvocationHandler.invoke(EJBObjectInvocationHandler.java:206)|#] [#|2010-11-17T12:35:16.457-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.ejb.containers.EJBObjectInvocationHandler.invoke(EJBObjectInvocationHandler.java:123)|#] [#|2010-11-17T12:35:16.458-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at $Proxy183.getFedTax(Unknown Source)|#] [#|2010-11-17T12:35:16.458-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)|#] [#|2010-11-17T12:35:16.459-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)|#] [#|2010-11-17T12:35:16.459-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)|#] [#|2010-11-17T12:35:16.460-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at java.lang.reflect.Method.invoke(Method.java:597)|#] [#|2010-11-17T12:35:16.461-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:144)|#] [#|2010-11-17T12:35:16.461-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:174)|#] [#|2010-11-17T12:35:16.462-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaServerRequestDispatcherImpl.dispatchToServant(CorbaServerRequestDispatcherImpl.java:528)|#] [#|2010-11-17T12:35:16.462-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaServerRequestDispatcherImpl.dispatch(CorbaServerRequestDispatcherImpl.java:199)|#] [#|2010-11-17T12:35:16.463-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleRequestRequest(CorbaMessageMediatorImpl.java:1624)|#] [#|2010-11-17T12:35:16.463-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleRequest(CorbaMessageMediatorImpl.java:1486)|#] [#|2010-11-17T12:35:16.464-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleInput(CorbaMessageMediatorImpl.java:990)|#] [#|2010-11-17T12:35:16.464-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:214)|#] [#|2010-11-17T12:35:16.465-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.handleRequest(CorbaMessageMediatorImpl.java:742)|#] [#|2010-11-17T12:35:16.465-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.dispatch(CorbaMessageMediatorImpl.java:539)|#] [#|2010-11-17T12:35:16.466-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.protocol.CorbaMessageMediatorImpl.doWork(CorbaMessageMediatorImpl.java:2324)|#] [#|2010-11-17T12:35:16.466-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:496)|#] [#|2010-11-17T12:35:16.467-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:537)|#] [#|2010-11-17T12:35:16.468-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Caused by: javax.naming.NamingException [Root exception is javax.xml.ws.WebServiceException: null is not a valid service]|#] [#|2010-11-17T12:35:16.468-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at org.glassfish.webservices.WebServiceReferenceManagerImpl.resolveWSReference(WebServiceReferenceManagerImpl.java:275)|#] [#|2010-11-17T12:35:16.469-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$WebServiceRefProxy.create(ComponentEnvManagerImpl.java:986)|#] [#|2010-11-17T12:35:16.469-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:771)|#] [#|2010-11-17T12:35:16.470-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:740)|#] [#|2010-11-17T12:35:16.470-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:166)|#] [#|2010-11-17T12:35:16.471-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:538)|#] [#|2010-11-17T12:35:16.471-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| ... 46 more|#] [#|2010-11-17T12:35:16.472-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;|Caused by: javax.xml.ws.WebServiceException: null is not a valid service|#] [#|2010-11-17T12:35:16.472-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.xml.ws.client.WSServiceDelegate.(WSServiceDelegate.java:198)|#] [#|2010-11-17T12:35:16.472-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.xml.ws.client.WSServiceDelegate.(WSServiceDelegate.java:186)|#] [#|2010-11-17T12:35:16.473-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at com.sun.xml.ws.spi.ProviderImpl.createServiceDelegate(ProviderImpl.java:111)|#] [#|2010-11-17T12:35:16.473-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at javax.xml.ws.Service.(Service.java:57)|#] [#|2010-11-17T12:35:16.473-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at javax.xml.ws.Service.create(Service.java:687)|#] [#|2010-11-17T12:35:16.474-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| at org.glassfish.webservices.WebServiceReferenceManagerImpl.resolveWSReference(WebServiceReferenceManagerImpl.java:193)|#] [#|2010-11-17T12:35:16.474-0800|SEVERE|glassfish3.1|null|_ThreadID=16;_ThreadName=Thread-1;| ... 51 more|#]