Jul 5, 2011 6:22:46 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: JVM invocation command line: /usr/java6_64/bin/java -cp /export/sqe/lidia/glassfish3/glassfish/modules/glassfish.jar -XX:+UnlockDiagnosticVMOptions -XX:NewRatio=2 -XX:MaxPermSize=192m -Xmx512m -server -javaagent:/export/sqe/lidia/glassfish3/glassfish/lib/monitor/flashlight-agent.jar -Dcom.sun.aas.instanceRoot=/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1 -Dfelix.fileinstall.bundles.startTransient=true -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 -Djava.security.auth.login.config=/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1/config/login.conf -Dfelix.fileinstall.dir=/export/sqe/lidia/glassfish3/glassfish/modules/autostart/ -Dosgi.shell.telnet.ip=127.0.0.1 -Dcom.ibm.xml.xlxp.support.dtd.compat.mode=false -Djavax.net.ssl.trustStore=/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1/config/cacerts.jks -Djava.security.policy=/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1/config/server.policy -Djava.endorsed.dirs=/export/sqe/lidia/glassfish3/glassfish/modules/endorsed:/export/sqe/lidia/glassfish3/glassfish/lib/endorsed -Dfelix.fileinstall.bundles.new.start=true -Djdbc.drivers=org.apache.derby.jdbc.ClientDriver -Dfelix.fileinstall.log.level=3 -Dfelix.fileinstall.poll=5000 -Dfelix.fileinstall.disableConfigSave=false -Dosgi.shell.telnet.port=26667 -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 -Djavax.net.ssl.keyStore=/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1/config/keystore.jks -Dgosh.args=--noshutdown -c noop=true -Dcom.sun.aas.installRoot=/export/sqe/lidia/glassfish3/glassfish -Djava.awt.headless=true -Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as -Djava.ext.dirs=/usr/java6_64/lib/ext:/usr/java6_64/jre/lib/ext:/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1/lib/ext -Djava.library.path=/export/sqe/lidia/glassfish3/glassfish/lib:/usr/java6_64/jre/lib/ppc64/default:/usr/java6_64/jre/lib/ppc64:/usr/java6_64/jre/lib/ppc64/j9vm:/usr/java6_64/lib/ppc64:/usr/lib com.sun.enterprise.glassfish.bootstrap.ASMain -asadmin-classpath /export/sqe/lidia/glassfish3/glassfish/modules/admin-cli.jar -verbose false -asadmin-classname com.sun.enterprise.admin.cli.AsadminMain -debug false -type INSTANCE -asadmin-args --host,,,localhost,,,--port,,,4848,,,--secure=false,,,--terse=false,,,--echo=false,,,--interactive=false,,,start-local-instance,,,--verbose=false,,,--debug=false,,,--node,,,localhost-domain1,,,cl1in1 -instancedir /export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1 -instancename cl1in1 -read-stdin true -upgrade false Jul 5, 2011 6:22:46 PM com.sun.enterprise.admin.launcher.GFLauncherLogger info INFO: Successfully launched in 71 msec. [#|2011-07-05T18:23:01.577-0700|INFO|null|null|_ThreadID=1;_ThreadName=Thread-9;|Running GlassFish Version: Oracle GlassFish Server 3.1.1 (build 11)|#] [#|2011-07-05T18:23:01.579-0700|WARNING|null|null|_ThreadID=1;_ThreadName=Thread-9;|Record begin marker is not a proper value so using default.|#] [#|2011-07-05T18:23:01.579-0700|WARNING|null|null|_ThreadID=1;_ThreadName=Thread-9;|Record end marker is not a proper value so using default.|#] [#|2011-07-05T18:23:01.579-0700|WARNING|null|null|_ThreadID=1;_ThreadName=Thread-9;|Log Format field separator is not a character so using default.|#] [#|2011-07-05T18:23:01.997-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|GMS1095: member: cl1in1 is joining group: cl1 ...|#] [#|2011-07-05T18:23:02.048-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|instantiated following NetworkManager implementation:com.sun.enterprise.mgmt.transport.grizzly.GrizzlyNetworkManager|#] [#|2011-07-05T18:23:02.053-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|GMS MAX_MESSAGE_LENGTH=4196352|#] [#|2011-07-05T18:23:02.055-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;| GrizzlyNetworkManager Configuration BIND_INTERFACE_ADDRESS:null NetworkInterfaceName:null TCPSTARTPORT..TCPENDPORT:9090..9200 MULTICAST_ADDRESS:MULTICAST_PORT:228.9.104.175:18299 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-07-05T18:23:02.101-0700|INFO|oracle-glassfish3.1.1|grizzly|_ThreadID=1;_ThreadName=Thread-9;|GRIZZLY0001: Starting Grizzly Framework 1.9.36 - 7/5/11 6:23 PM|#] [#|2011-07-05T18:23:02.183-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|Grizzly controller listening on /0:0:0:0:0:0:0:0:9155. Controller started in 94 ms|#] [#|2011-07-05T18:23:02.205-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|GMS Local Peer ID=10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1|#] [#|2011-07-05T18:23:02.233-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|Set via default minimum: MulticastSocket.getTimeToLive()=4|#] [#|2011-07-05T18:23:02.237-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|MulticastSocket configuration: local socket address: 0.0.0.0/0.0.0.0:18299 network interface: name:null index: -1 addresses: 0.0.0.0/0.0.0.0; multicast address:/228.9.104.175 timeToLive=4|#] [#|2011-07-05T18:23:02.267-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|HealthMonitor: heartBeatTimeout(ms)=2000 maxMissedBeats=3 failureDetectionTCPTimeout(ms)=10000 failureDetectionTCPPort=9000|#] [#|2011-07-05T18:23:02.270-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|starting cluster cl1 for member:cl1in1|#] [#|2011-07-05T18:23:02.272-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|GMS1061: MasterNode message listener is registered for member: cl1in1 group: cl1|#] [#|2011-07-05T18:23:02.282-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1092: GMS View Change Received for group: cl1 : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: cl1in1, MemberType: CORE, Address: 10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1 |#] [#|2011-07-05T18:23:02.287-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1016: Analyzing new membership snapshot received as part of event: MASTER_CHANGE_EVENT for member: cl1in1 of group: cl1|#] [#|2011-07-05T18:23:02.280-0700|INFO|oracle-glassfish3.1.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=0;_ThreadName=Thread-9;|GMSAD1005: Member cl1in1 joined group cl1|#] [#|2011-07-05T18:23:02.299-0700|INFO|oracle-glassfish3.1.1|javax.org.glassfish.gms.org.glassfish.gms|_ThreadID=0;_ThreadName=Thread-9;|GMSAD1004: Started GMS for instance cl1in1 in group cl1|#] [#|2011-07-05T18:23:02.308-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1093: adding GroupLeadershipNotification signal leadermember: cl1in1 of group: cl1|#] [#|2011-07-05T18:23:02.329-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=3;_ThreadName=Thread-9;|MySystemAdvertisement(summary): GMSMember name: cl1in1 group: cl1 memberType: CORE startTime: Jul 5, 2011 6:23:01 PM PDT ID:10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1 TCP uri(s):[tcp://10.133.169.2:4000]|#] [#|2011-07-05T18:23:02.336-0700|CONFIG|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=3;_ThreadName=Thread-9;|MySystemAdvertisement(dump)=PEERID[10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1] CUSTOMTAGS[START_TIME[6:23:01 PM PDT on Jul 5, 2011], GROUP_NAME:cl1, MEMBER_TYPE:CORE], ENDPONTADDRESSES[[tcp://10.133.169.2:4000]], NAME:cl1in1, OSNAME:AIX, OSVERSION:6.1, OSARCH:ppc64, HWARCH:ppc64, HWVENDOR:IBM Corporation|#] [#|2011-07-05T18:23:02.354-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1092: GMS View Change Received for group: cl1 : Members in view for MASTER_CHANGE_EVENT(before change analysis) are : 1: MemberId: cl1in1, MemberType: CORE, Address: 10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1 2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.2:9153:228.9.104.175:18299:cl1:server |#] [#|2011-07-05T18:23:02.355-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1016: Analyzing new membership snapshot received as part of event: MASTER_CHANGE_EVENT for member: server of group: cl1|#] [#|2011-07-05T18:23:02.356-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1093: adding GroupLeadershipNotification signal leadermember: server of group: cl1|#] [#|2011-07-05T18:23:02.362-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1092: GMS View Change Received for group: cl1 : Members in view for ADD_EVENT(before change analysis) are : 1: MemberId: cl1in1, MemberType: CORE, Address: 10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1 2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.2:9153:228.9.104.175:18299:cl1:server |#] [#|2011-07-05T18:23:02.363-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1016: Analyzing new membership snapshot received as part of event: ADD_EVENT for member: cl1in1 of group: cl1|#] [#|2011-07-05T18:23:02.367-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1024: Adding Join member: cl1in1 group: cl1 StartupState: GROUP_STARTUP |#] [#|2011-07-05T18:23:02.855-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=5;_ThreadName=Thread-9;|Grizzly Framework 1.9.36 started in: 170ms - bound to [0.0.0.0:28182]|#] [#|2011-07-05T18:23:02.855-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=4;_ThreadName=Thread-9;|Grizzly Framework 1.9.36 started in: 239ms - bound to [0.0.0.0:28081]|#] [#|2011-07-05T18:23:02.867-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=6;_ThreadName=Thread-9;|Grizzly Framework 1.9.36 started in: 58ms - bound to [0.0.0.0:24849]|#] [#|2011-07-05T18:23:02.902-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=7;_ThreadName=Thread-9;|Grizzly Framework 1.9.36 started in: 26ms - bound to [0.0.0.0:27677]|#] [#|2011-07-05T18:23:03.420-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=0;_ThreadName=Thread-9;|JTS5014: Recoverable JTS instance, serverId = [100]|#] [#|2011-07-05T18:23:03.955-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-9;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:28081]|#] [#|2011-07-05T18:23:04.012-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-9;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:28182]|#] [#|2011-07-05T18:23:04.024-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-9;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:24849]|#] [#|2011-07-05T18:23:04.083-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-9;|WEB0171: Created virtual server [server]|#] [#|2011-07-05T18:23:04.090-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-9;|WEB0171: Created virtual server [__asadmin]|#] [#|2011-07-05T18:23:05.177-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=0;_ThreadName=Thread-9;|WEB0172: Virtual server [server] loaded default web module []|#] [#|2011-07-05T18:23:06.278-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.com.sun.enterprise.v3.services.impl|_ThreadID=0;_ThreadName=Thread-9;|core.start_container_done|#] [#|2011-07-05T18:23:06.287-0700|INFO|oracle-glassfish3.1.1|org.glassfish.ha.store.spi.BackingStoreFactoryRegistry|_ThreadID=0;_ThreadName=Thread-9;|Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry|#] [#|2011-07-05T18:23:06.382-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=0;_ThreadName=Thread-9;|Oracle GlassFish Server 3.1.1 (11) startup time : Felix (14,278ms), startup services(5,074ms), total(19,352ms)|#] [#|2011-07-05T18:23:06.385-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=0;_ThreadName=Thread-9;|GMS1099: GMS:Reporting Joined and Ready state to group: cl1|#] [#|2011-07-05T18:23:06.416-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1092: GMS View Change Received for group: cl1 : Members in view for JOINED_AND_READY_EVENT(before change analysis) are : 1: MemberId: cl1in1, MemberType: CORE, Address: 10.133.169.2:9155:228.9.104.175:18299:cl1:cl1in1 2: MemberId: server, MemberType: SPECTATOR, Address: 10.133.169.2:9153:228.9.104.175:18299:cl1:server |#] [#|2011-07-05T18:23:06.418-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1016: Analyzing new membership snapshot received as part of event: JOINED_AND_READY_EVENT for member: cl1in1 of group: cl1|#] [#|2011-07-05T18:23:06.419-0700|INFO|oracle-glassfish3.1.1|ShoalLogger|_ThreadID=2;_ThreadName=Thread-9;|GMS1025: Adding Joined And Ready member: cl1in1 group: cl1 StartupState: GROUP_STARTUP |#] [#|2011-07-05T18:23:06.466-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.inbound|_ThreadID=8;_ThreadName=Thread-9;|Recovery of Inbound Transactions started.|#] [#|2011-07-05T18:23:06.683-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.core.transaction.com.sun.jts.CosTransactions|_ThreadID=8;_ThreadName=Thread-9;|JTS5014: Recoverable JTS instance, serverId = [100]|#] [#|2011-07-05T18:23:07.788-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=9;_ThreadName=Thread-9;|/export/sqe/lidia/glassfish3/glassfish/nodes/localhost-domain1/cl1in1/autodeploy/bundles does not exist, please create it.|#] [#|2011-07-05T18:23:07.960-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=10;_ThreadName=Thread-9;|JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://aixas14:28687/jndi/rmi://aixas14:28687/jmxrmi|#] [#|2011-07-05T18:23:08.199-0700|INFO|oracle-glassfish3.1.1|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=11;_ThreadName=Thread-9;|____________________________ Welcome to Apache Felix Gogo |#] [#|2011-07-05T18:23:41.416-0700|INFO|oracle-glassfish3.1.1|org.hibernate.validator.util.Version|_ThreadID=12;_ThreadName=Thread-9;|Hibernate Validator 4.1.0.Final|#] [#|2011-07-05T18:23:41.427-0700|INFO|oracle-glassfish3.1.1|org.hibernate.validator.engine.resolver.DefaultTraversableResolver|_ThreadID=12;_ThreadName=Thread-9;|Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver.|#] [#|2011-07-05T18:23:41.775-0700|WARNING|oracle-glassfish3.1.1|null|_ThreadID=13;_ThreadName=Thread-9;|Unprocessed event : UnprocessedChangeEvent{PropertyName=system-property, OldValue = null, NewValue = GlassFishConfigBean.com.sun.enterprise.config.serverbeans.SystemProperty, Source = GlassFishConfigBean.com.sun.enterprise.config.serverbeans.Server}, reason = The system-property, OSGI_SHELL_TELNET_PORT, that is referenced by the Java configuration, was modified, when = 1309915421592|#]