# 1285876500092 Do not modify this line [30/Sep/2010:12:54:59 PDT] [B1002]: An existing property file for stclusterinstance101 was not found, no stored properties will be loaded [30/Sep/2010:12:55:00 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:12:55:01 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:12:55:01 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:12:55:01 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:12:55:01 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:12:55:01 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq1600273335581006900.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:12:55:01 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:12:55:01 PDT] Broker Properties: imq.passfile.name=asmq1600273335581006900.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:12:55:01 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:12:55:01 PDT] [B1060]: Loading persistent data... [30/Sep/2010:12:55:01 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:12:55:01 PDT] WARNING Existing file: incompleteTxnStorehas older cookie version than current version. Current version = 1. Original file version = 0 [30/Sep/2010:12:55:01 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:12:55:01 PDT] new transaction log enabled [30/Sep/2010:12:55:01 PDT] sync writes to disk = false [30/Sep/2010:12:55:01 PDT] logNonTransactedMsgSend = false [30/Sep/2010:12:55:01 PDT] logNonTransactedMsgAck = false [30/Sep/2010:12:55:01 PDT] opening transaction log with file mode=rw [30/Sep/2010:12:55:02 PDT] [B1136]: Processing stored transactions [30/Sep/2010:12:55:02 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:12:55:02 PDT] [B1144]: Creating Dead Message Queue [30/Sep/2010:12:55:02 PDT] [B1158]: Administrator has created destination mq.sys.dmq [Queue] [30/Sep/2010:12:55:02 PDT] [B1239]: Using platform MBean server [30/Sep/2010:12:55:02 PDT] RMI Registry started on port 27776 [30/Sep/2010:12:55:04 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:12:55:05 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:12:55:05 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:12:55:05 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:12:55:05 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:12:55:05 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:12:55:05 PDT] [B1068]: Initializing master broker for the first time. [30/Sep/2010:12:55:05 PDT] [B1383]: Received cluster configuration change records (1, 1285876505294) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=6319723886515243264 [30/Sep/2010:12:55:05 PDT] [B1263]: Resume full JMS service [30/Sep/2010:12:55:05 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:12:55:05 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:60817 ] with min threads 1 and max threads of 1 [30/Sep/2010:12:55:05 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:12:55:05 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:12:55:05 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: java.io.EOFException [30/Sep/2010:12:55:06 PDT] [B1065]: Accepting: admin@127.0.0.1:60821->admin:60815. Count: service=1 broker=1 [30/Sep/2010:12:55:06 PDT] [B1065]: Accepting: guest@127.0.0.1:60825->jms:60814. Count: service=1 broker=2 [30/Sep/2010:12:58:25 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: java.io.EOFException [30/Sep/2010:13:01:46 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: java.io.EOFException [30/Sep/2010:13:03:17 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:03:17 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:03:17 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:03:17 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:13:03:17 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:03:17 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq5879819050891939099.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:03:17 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:13:03:17 PDT] Broker Properties: imq.passfile.name=asmq5879819050891939099.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:03:17 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:13:03:17 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:03:17 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:13:03:18 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:03:18 PDT] new transaction log enabled [30/Sep/2010:13:03:18 PDT] sync writes to disk = false [30/Sep/2010:13:03:18 PDT] logNonTransactedMsgSend = false [30/Sep/2010:13:03:18 PDT] logNonTransactedMsgAck = false [30/Sep/2010:13:03:18 PDT] opening transaction log with file mode=rw [30/Sep/2010:13:03:18 PDT] [B1270]: Processing messages from transaction log file... [30/Sep/2010:13:03:18 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:03:18 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:03:18 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:03:18 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:03:18 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:03:18 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:03:20 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:13:03:20 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:03:20 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:03:20 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:03:20 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:03:20 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:13:03:20 PDT] [B1383]: Received cluster configuration change records (1, 1285877000821) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=5106848211996189440 [30/Sep/2010:13:03:20 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:13:03:20 PDT] [B1263]: Resume full JMS service [30/Sep/2010:13:03:20 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:61462 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:03:20 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:03:20 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:13:03:20 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: Connection reset [30/Sep/2010:13:03:21 PDT] [B1065]: Accepting: admin@127.0.0.1:61468->admin:61460. Count: service=1 broker=1 [30/Sep/2010:13:03:22 PDT] [B1065]: Accepting: guest@127.0.0.1:61470->jms:61459. Count: service=1 broker=2 [30/Sep/2010:13:04:11 PDT] [B1065]: Accepting: guest@127.0.0.1:61585->jms:61459. Count: service=2 broker=3 [30/Sep/2010:13:04:11 PDT] [B1132]: Auto-creating destination SampleQ [Queue] [30/Sep/2010:13:04:11 PDT] imq.persist.file.minimizeWrites=false [30/Sep/2010:13:05:18 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:05:18 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:05:18 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:05:18 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:13:05:18 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:05:18 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq887587704833624992.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:05:18 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:13:05:18 PDT] Broker Properties: imq.passfile.name=asmq887587704833624992.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:05:18 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:13:05:18 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:05:18 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:13:05:18 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:05:18 PDT] new transaction log enabled [30/Sep/2010:13:05:18 PDT] sync writes to disk = false [30/Sep/2010:13:05:18 PDT] logNonTransactedMsgSend = false [30/Sep/2010:13:05:18 PDT] logNonTransactedMsgAck = false [30/Sep/2010:13:05:18 PDT] opening transaction log with file mode=rw [30/Sep/2010:13:05:19 PDT] [B1270]: Processing messages from transaction log file... [30/Sep/2010:13:05:19 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:05:19 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:05:19 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:05:19 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:05:19 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:05:19 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:05:21 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:13:05:21 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:05:21 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:05:21 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:05:21 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:05:21 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:13:05:21 PDT] [B1383]: Received cluster configuration change records (0, 1285877121320) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=2247062448646764544 [30/Sep/2010:13:05:21 PDT] [B1263]: Resume full JMS service [30/Sep/2010:13:05:21 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:13:05:21 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:61687 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:05:21 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:05:21 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:13:05:21 PDT] [B1065]: Accepting: admin@127.0.0.1:61686->admin:61684. Count: service=1 broker=1 [30/Sep/2010:13:05:21 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: Connection reset [30/Sep/2010:13:05:22 PDT] [B1065]: Accepting: guest@127.0.0.1:61691->jms:61683. Count: service=1 broker=2 [30/Sep/2010:13:05:22 PDT] [B1065]: Accepting: guest@10.5.220.200:61693->jms:61683. Count: service=2 broker=3 [30/Sep/2010:13:05:23 PDT] [B1066]: Closing: guest@10.5.220.200:61693->jms:61683 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:08:22 PDT] [B1065]: Accepting: guest@127.0.0.1:61964->jms:61683. Count: service=2 broker=3 [30/Sep/2010:13:08:22 PDT] [B1151]: Loading destination SampleQ [Queue] with 300 messages [30/Sep/2010:13:08:22 PDT] [B1152]: Loading of destination SampleQ [Queue] complete [30/Sep/2010:13:08:22 PDT] imq.persist.file.minimizeWrites=false [30/Sep/2010:13:09:29 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:09:29 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:09:29 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:09:29 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:13:09:30 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:09:30 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq991768161903934826.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:09:30 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:13:09:30 PDT] Broker Properties: imq.passfile.name=asmq991768161903934826.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:09:30 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:13:09:30 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:09:30 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:13:09:30 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:09:30 PDT] new transaction log enabled [30/Sep/2010:13:09:30 PDT] sync writes to disk = false [30/Sep/2010:13:09:30 PDT] logNonTransactedMsgSend = false [30/Sep/2010:13:09:30 PDT] logNonTransactedMsgAck = false [30/Sep/2010:13:09:30 PDT] opening transaction log with file mode=rw [30/Sep/2010:13:09:30 PDT] [B1270]: Processing messages from transaction log file... [30/Sep/2010:13:09:30 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:09:30 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:09:30 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:09:31 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:09:31 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:09:31 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:09:32 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:13:09:33 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:09:33 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:09:33 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:09:33 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:09:33 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:13:09:33 PDT] [B1383]: Received cluster configuration change records (0, 1285877373189) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=7425357595257134848 [30/Sep/2010:13:09:33 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:13:09:33 PDT] [B1263]: Resume full JMS service [30/Sep/2010:13:09:33 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:62066 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:09:33 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:09:33 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:13:09:33 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: java.io.EOFException [30/Sep/2010:13:09:34 PDT] [B1065]: Accepting: admin@127.0.0.1:62069->admin:62064. Count: service=1 broker=1 [30/Sep/2010:13:09:34 PDT] [B1065]: Accepting: guest@127.0.0.1:62071->jms:62063. Count: service=1 broker=2 [30/Sep/2010:13:09:35 PDT] [B1065]: Accepting: guest@10.5.220.200:62073->jms:62063. Count: service=2 broker=3 [30/Sep/2010:13:09:35 PDT] [B1066]: Closing: guest@10.5.220.200:62073->jms:62063 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:12:53 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: java.io.EOFException [30/Sep/2010:13:13:57 PDT] [B1065]: Accepting: guest@127.0.0.1:62454->jms:62063. Count: service=2 broker=3 [30/Sep/2010:13:13:57 PDT] [B1151]: Loading destination SampleQ [Queue] with 600 messages [30/Sep/2010:13:13:57 PDT] [B1152]: Loading of destination SampleQ [Queue] complete [30/Sep/2010:13:13:57 PDT] imq.persist.file.minimizeWrites=false [30/Sep/2010:13:15:02 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:15:03 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:15:03 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:15:03 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:13:15:03 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:15:03 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq4427336375769142058.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:15:03 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:13:15:03 PDT] Broker Properties: imq.passfile.name=asmq4427336375769142058.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:15:03 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:13:15:03 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:15:03 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:13:15:03 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:15:03 PDT] new transaction log enabled [30/Sep/2010:13:15:03 PDT] sync writes to disk = false [30/Sep/2010:13:15:03 PDT] logNonTransactedMsgSend = false [30/Sep/2010:13:15:03 PDT] logNonTransactedMsgAck = false [30/Sep/2010:13:15:03 PDT] opening transaction log with file mode=rw [30/Sep/2010:13:15:03 PDT] [B1270]: Processing messages from transaction log file... [30/Sep/2010:13:15:03 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:15:03 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:15:03 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:15:03 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:15:04 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:15:04 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:15:05 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:13:15:06 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:15:06 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:15:06 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:15:06 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:15:06 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:13:15:06 PDT] [B1383]: Received cluster configuration change records (0, 1285877706181) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=7742579894095309312 [30/Sep/2010:13:15:06 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:13:15:06 PDT] [B1263]: Resume full JMS service [30/Sep/2010:13:15:06 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:62563 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:15:06 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:15:06 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:13:15:06 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: Connection reset [30/Sep/2010:13:15:06 PDT] [B1065]: Accepting: admin@127.0.0.1:62566->admin:62561. Count: service=1 broker=1 [30/Sep/2010:13:15:07 PDT] [B1065]: Accepting: guest@127.0.0.1:62569->jms:62560. Count: service=1 broker=2 [30/Sep/2010:13:15:07 PDT] [B1065]: Accepting: guest@10.5.220.200:62571->jms:62560. Count: service=2 broker=3 [30/Sep/2010:13:15:08 PDT] [B1066]: Closing: guest@10.5.220.200:62571->jms:62560 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:15:54 PDT] [B1065]: Accepting: guest@127.0.0.1:62628->jms:62560. Count: service=2 broker=3 [30/Sep/2010:13:15:54 PDT] [B1151]: Loading destination SampleQ [Queue] with 900 messages [30/Sep/2010:13:15:55 PDT] [B1152]: Loading of destination SampleQ [Queue] complete [30/Sep/2010:13:15:55 PDT] imq.persist.file.minimizeWrites=false [30/Sep/2010:13:17:46 PDT] [B1066]: Closing: guest@127.0.0.1:62628->jms:62560 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:18:07 PDT] [B1065]: Accepting: guest@127.0.0.1:62849->jms:62560. Count: service=2 broker=3 [30/Sep/2010:13:18:26 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: java.io.EOFException [30/Sep/2010:13:19:32 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:19:32 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:19:32 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:19:32 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:13:19:32 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:19:32 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq7038320837736268673.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:19:32 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:13:19:32 PDT] Broker Properties: imq.passfile.name=asmq7038320837736268673.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:19:32 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:13:19:32 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:19:32 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:13:19:33 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:19:33 PDT] new transaction log enabled [30/Sep/2010:13:19:33 PDT] sync writes to disk = false [30/Sep/2010:13:19:33 PDT] logNonTransactedMsgSend = false [30/Sep/2010:13:19:33 PDT] logNonTransactedMsgAck = false [30/Sep/2010:13:19:33 PDT] opening transaction log with file mode=rw [30/Sep/2010:13:19:33 PDT] [B1270]: Processing messages from transaction log file... [30/Sep/2010:13:19:33 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:19:33 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:19:33 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:19:33 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:19:33 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:19:33 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:19:35 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:13:19:35 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:19:35 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:19:35 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:19:35 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:19:35 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:13:19:35 PDT] [B1383]: Received cluster configuration change records (0, 1285877975858) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=5254059625065401856 [30/Sep/2010:13:19:35 PDT] [B1263]: Resume full JMS service [30/Sep/2010:13:19:35 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:13:19:35 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:62969 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:19:35 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:19:35 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:13:19:35 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null[jed-asqe-3.red.iplanet.com/10.5.220.200:60816] failed: Connection reset [30/Sep/2010:13:19:36 PDT] [B1065]: Accepting: admin@127.0.0.1:62972->admin:62967. Count: service=1 broker=1 [30/Sep/2010:13:19:37 PDT] [B1065]: Accepting: guest@127.0.0.1:62974->jms:62966. Count: service=1 broker=2 [30/Sep/2010:13:19:38 PDT] [B1065]: Accepting: guest@10.5.220.200:62978->jms:62966. Count: service=2 broker=3 [30/Sep/2010:13:19:38 PDT] [B1066]: Closing: guest@10.5.220.200:62978->jms:62966 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:22:08 PDT] [B1066]: Closing: guest@127.0.0.1:62974->jms:62966 because "[B0059]: Client closed the connection". Count: service=0 broker=1 [30/Sep/2010:13:22:08 PDT] [B1093]: Shutdown requested by broker administrator [30/Sep/2010:13:22:08 PDT] [B1047]: Shutting down broker... [30/Sep/2010:13:22:08 PDT] [B1077]: Broadcast good-bye to all connections ... [30/Sep/2010:13:22:08 PDT] [B1078]: Flushing good-bye messages ... [30/Sep/2010:13:22:08 PDT] [B1007]: Stopping Service admin with protocol tcp(host = *, port=0, mode=dedicated) [30/Sep/2010:13:22:09 PDT] WARNING [B2181]: Removing 1 messages associated with destination temporary_destination://queue/10.5.220.200/62972/1 [Queue] [30/Sep/2010:13:22:09 PDT] [B1066]: Closing: admin@127.0.0.1:62972->admin:62967 because "[B0061]: Client exited without closing connections". Count: service=0 broker=0 [30/Sep/2010:13:22:09 PDT] [B1007]: Stopping Service jms with protocol tcp(host = *, port=0, mode=dedicated) [30/Sep/2010:13:22:14 PDT] JMX Connector Server jmxrmi stopped successfully [30/Sep/2010:13:22:16 PDT] [B1048]: Shutdown of broker complete. [30/Sep/2010:13:23:24 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:23:24 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:23:24 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:23:24 PDT] SunOS 5.10 sparc jed-asqe-3 (2 cpu) root [30/Sep/2010:13:23:24 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:23:24 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq9110576612170451792.tmp -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:23:24 PDT] JMSRA BrokerProps: : imq.cluster.brokerlist=mq://jed-asqe-3.red.iplanet.com:27676/,mq://jed-asqe-3.red.iplanet.com:27677/, imq.cluster.masterbroker=mq://jed-asqe-3.red.iplanet.com:27676/, imq.cluster.dynamicChangeMasterBrokerEnabled=true, imq.imqcmd.password=*****, imq.jmsra.managed=true, imq.cluster.nowaitForMasterBroker=true [30/Sep/2010:13:23:24 PDT] Broker Properties: imq.passfile.name=asmq9110576612170451792.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.instancename=stclusterinstance101, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:23:24 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, * ] with min threads 1 and max threads of 1 [30/Sep/2010:13:23:25 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:23:25 PDT] Using built-in file-based persistent store: /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances/stclusterinstance101/ [30/Sep/2010:13:23:25 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:23:25 PDT] new transaction log enabled [30/Sep/2010:13:23:25 PDT] sync writes to disk = false [30/Sep/2010:13:23:25 PDT] logNonTransactedMsgSend = false [30/Sep/2010:13:23:25 PDT] logNonTransactedMsgAck = false [30/Sep/2010:13:23:25 PDT] opening transaction log with file mode=rw [30/Sep/2010:13:23:25 PDT] [B1270]: Processing messages from transaction log file... [30/Sep/2010:13:23:26 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:23:26 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:23:26 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:23:26 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:23:27 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:23:27 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:23:30 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3:27776/jed-asqe-3/27676/jmxrmi [30/Sep/2010:13:23:30 PDT] [B1004]: Starting the admin service using tcp(host = *, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:23:30 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:23:30 PDT] [B1004]: Starting the jms service using tcp(host = *, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:23:30 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:23:30 PDT] [B1069]: Running as master broker for the cluster. [30/Sep/2010:13:23:30 PDT] [B1383]: Received cluster configuration change records (0, 1285878210529) from mq://10.5.220.200:27676/?instName=stclusterinstance101&brokerSessionUID=1049386423021050368 [30/Sep/2010:13:23:30 PDT] [B1263]: Resume full JMS service [30/Sep/2010:13:23:30 PDT] [B1075]: Received persistent state change records from the master broker. Ready to accept client connections. [30/Sep/2010:13:23:30 PDT] [B1004]: Starting the cluster service using tcp [ 0.0.0.0/0.0.0.0:63271 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:23:30 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:23:30 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerSessionUID=null failed: Connection refused [30/Sep/2010:13:23:30 PDT] [B1039]: Broker "stclusterinstance101@jed-asqe-3:27676" ready. [30/Sep/2010:13:23:30 PDT] [B1065]: Accepting: admin@127.0.0.1:63270->admin:63268. Count: service=1 broker=1 [30/Sep/2010:13:23:31 PDT] [B1065]: Accepting: guest@127.0.0.1:63274->jms:63267. Count: service=1 broker=2 [30/Sep/2010:13:23:32 PDT] [B1065]: Accepting: guest@10.5.220.200:63277->jms:63267. Count: service=2 broker=3 [30/Sep/2010:13:23:37 PDT] [B1066]: Closing: guest@10.5.220.200:63277->jms:63267 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:24:36 PDT] [B1066]: Closing: guest@127.0.0.1:63274->jms:63267 because "[B0059]: Client closed the connection". Count: service=0 broker=1 [30/Sep/2010:13:24:36 PDT] [B1093]: Shutdown requested by broker administrator [30/Sep/2010:13:24:36 PDT] [B1047]: Shutting down broker... [30/Sep/2010:13:24:36 PDT] [B1077]: Broadcast good-bye to all connections ... [30/Sep/2010:13:24:36 PDT] [B1078]: Flushing good-bye messages ... [30/Sep/2010:13:24:36 PDT] [B1007]: Stopping Service admin with protocol tcp(host = *, port=0, mode=dedicated) [30/Sep/2010:13:24:36 PDT] [B1007]: Stopping Service jms with protocol tcp(host = *, port=0, mode=dedicated) [30/Sep/2010:13:24:36 PDT] WARNING [B2181]: Removing 1 messages associated with destination temporary_destination://queue/10.5.220.200/63270/1 [Queue] [30/Sep/2010:13:24:36 PDT] [B1066]: Closing: admin@127.0.0.1:63270->admin:63268 because "[B0061]: Client exited without closing connections". Count: service=0 broker=0 [30/Sep/2010:13:24:41 PDT] JMX Connector Server jmxrmi stopped successfully [30/Sep/2010:13:24:43 PDT] [B1048]: Shutdown of broker complete. [30/Sep/2010:13:26:38 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:13:55:13 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:13:55:14 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:13:55:14 PDT] SunOS 5.10 sparc jed-asqe-3.red.iplanet.com (2 cpu) root [30/Sep/2010:13:55:14 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:13:55:14 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq7154722823726626011.tmp -Dimq.hostname=jed-asqe-3.red.iplanet.com -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:13:55:14 PDT] JMSRA BrokerProps: : imq.persist.jdbc.mysql.user=dbuser, imq.cluster.nowaitForMasterBroker=true, imq.cluster.ha=true, imq.persist.jdbc.dbVendor=mysql, imq.brokerid=stclusterinstance101, imq.persist.jdbc.mysql.password=*****, imq.persist.jdbc.mysql.property.url=jdbc:mysql://jed-asqe-1.red.iplanet.com:3306/dbsmpl1, imq.jmsra.managed=true, imq.cluster.clusterid=stcluster_MQ, imq.imqcmd.password=*****, imq.persist.store=jdbc, imq.cluster.dynamicChangeMasterBrokerEnabled=true [30/Sep/2010:13:55:14 PDT] Broker Properties: imq.passfile.name=asmq7154722823726626011.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.hostname=jed-asqe-3.red.iplanet.com, imq.instancename=stclusterinstance101, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:13:55:14 PDT] [B1202]: Broker stclusterinstance101 is running in HA mode as part of the stcluster_MQ cluster [30/Sep/2010:13:55:14 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, 10.5.220.200 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:55:14 PDT] [B1060]: Loading persistent data... [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.mysql.driver=com.mysql.jdbc.jdbc2.optional.MysqlConnectionPoolDataSource [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.mysql.property.cacheResultSetMetadata=true [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.mysql.property.cachePreparedStatements=true [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.mysql.property.preparedStatementCacheSize=25 [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.mysql.tableoption=ENGINE=InnoDB [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.connection.validationQuery=/* ping */ [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.connection.validateOnGet=true [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.connection.timeoutIdle=true [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.connection.reaptime=300 [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.min_connections=5 [30/Sep/2010:13:55:14 PDT] imq.persist.jdbc.max_connections=5 [30/Sep/2010:13:55:15 PDT] MySQL, 5.1.51, mysql-connector-java-5.1.13 ( Revision: ${bzr.revision-id} ) [30/Sep/2010:13:55:15 PDT] Using plugged-in persistent store: version=410 brokerid=stclusterinstance101 database connection url=jdbc:mysql://jed-asqe-1.red.iplanet.com:3306/dbsmpl1 database user=dbuser [30/Sep/2010:13:55:15 PDT] [B1170]: Auto Creation of plugged-in persistent store is enabled [30/Sep/2010:13:55:15 PDT] WARNING [B2219]: Broker property setting imq.persist.file.newTxnLog.enabled=true will be ignored [30/Sep/2010:13:55:16 PDT] [B1204]: Starting HA Monitor Service [30/Sep/2010:13:55:16 PDT] [B1206]: Monitor service initializing for brokerid stclusterinstance101 at mq://10.5.220.200:27676/ [30/Sep/2010:13:55:16 PDT] imq.cluster.monitor.interval=30 [30/Sep/2010:13:55:16 PDT] imq.cluster.monitor.threshold=3 [30/Sep/2010:13:55:16 PDT] [B1193]: Broker has started using a store session of 2511648863947055104 and a broker session of 6614428188056665600 [30/Sep/2010:13:55:16 PDT] [B1205]: Starting HA Heartbeat Service [30/Sep/2010:13:55:16 PDT] [B1187]: Heartbeat service binds to jed-asqe-3.red.iplanet.com/10.5.220.200:27676 [30/Sep/2010:13:55:16 PDT] [B1004]: Starting the heartbeat service using udp ( jed-asqe-3.red.iplanet.com/10.5.220.200:27676 ) with min threads 1 and max threads of 1 [30/Sep/2010:13:55:16 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:13:55:16 PDT] [B1136]: Processing stored transactions [30/Sep/2010:13:55:16 PDT] [B1079]: Found 1 transactions. 0 will be rolled back. [30/Sep/2010:13:55:16 PDT] [B1080]: Of 1 transactions, 0 are in PREPARED state, 1 are in COMMITTED state [30/Sep/2010:13:55:16 PDT] [B1284]: 0 cluster transactions remain in PREPARED state, 0 in COMMITTED state waiting for remote broker completion [30/Sep/2010:13:55:16 PDT] Of 0 remote transactions, 0 in PREPARED state, 0 in COMPLETE state, 0 in COMMITTED state [30/Sep/2010:13:55:16 PDT] [B1285]: Reaper thread for committed transactions has started (limit 500, interval 900sec). [30/Sep/2010:13:55:17 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:13:55:17 PDT] [B1159]: Destination Quotes [Topic] has been destroyed [30/Sep/2010:13:55:17 PDT] [B1151]: Loading destination Quotes [Topic] with 0 messages [30/Sep/2010:13:55:17 PDT] [B1152]: Loading of destination Quotes [Topic] complete [30/Sep/2010:13:55:25 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:13:55:25 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:13:55:25 PDT] Loading stored durable MultiTopicSubscriberBean:MultiTopicSubscriberBean [30/Sep/2010:13:55:25 PDT] Loading stored durable abcdef:SomeName [30/Sep/2010:13:55:25 PDT] [B1239]: Using platform MBean server [30/Sep/2010:13:55:26 PDT] RMI Registry started on port 27776 [30/Sep/2010:13:55:27 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3.red.iplanet.com:27776/jed-asqe-3.red.iplanet.com/27676/jmxrmi [30/Sep/2010:13:55:28 PDT] [B1004]: Starting the admin service using tcp(host = jed-asqe-3.red.iplanet.com, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:13:55:28 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:13:55:28 PDT] [B1004]: Starting the jms service using tcp(host = jed-asqe-3.red.iplanet.com, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:13:55:28 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:13:55:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:13:55:28 PDT] [B1004]: Starting the cluster service using tcp [ jed-asqe-3.red.iplanet.com/10.5.220.200:65314 ] with min threads 1 and max threads of 1 [30/Sep/2010:13:55:28 PDT] [B1229]: Using cluster ID stcluster_MQ [30/Sep/2010:13:55:28 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:13:55:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerID=stclusterinstance102&brokerSessionUID=null&ha=true&storeSessionUID=null failed: [B4256]: Unable to get cluster service port from broker mq://10.5.220.200:27677/?instName=???&brokerID=stclusterinstance102&brokerSessionUID=null&ha=true&storeSessionUID=null [30/Sep/2010:13:55:28 PDT] [B1039]: Broker "stclusterinstance101@10.5.220.200:27676" ready. [30/Sep/2010:13:55:28 PDT] [B1065]: Accepting: admin@10.5.220.200:65316->admin:65311. Count: service=1 broker=1 [30/Sep/2010:13:55:29 PDT] [B1065]: Accepting: guest@10.5.220.200:65320->jms:65309. Count: service=1 broker=2 [30/Sep/2010:13:55:30 PDT] [B1065]: Accepting: guest@10.5.220.200:65323->jms:65309. Count: service=2 broker=3 [30/Sep/2010:13:55:33 PDT] [B1185]: Starting heartbeat to stclusterinstance102 [30/Sep/2010:13:55:33 PDT] [B1181]: Added heartbeat endpoint /10.5.220.200:27677 [brokerID=stclusterinstance102, brokerSession=1045164298859995904] (seq#=0, ts=1285880133277, interval=2, len=232) [30/Sep/2010:13:55:33 PDT] [B1179]: Activated broker Address = mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=1045164298859995904&ha=true&storeSessionUID=2905995306318732544 StartTime = 1285880117667 ProtocolVersion = 410 HeartbeatHost = 10.5.220.200 HeartbeatPort = 27677 [30/Sep/2010:13:55:33 PDT] [B1071]: Established cluster connection to broker mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=1045164298859995904&ha=true&storeSessionUID=2905995306318732544[/10.5.220.200:65335] [30/Sep/2010:13:55:35 PDT] [B1066]: Closing: guest@10.5.220.200:65323->jms:65309 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:13:56:20 PDT] [B1065]: Accepting: guest@10.5.220.200:65418->jms:65309. Count: service=2 broker=3 [30/Sep/2010:13:56:21 PDT] [B1151]: Loading destination SampleQ [Queue] with 14066 messages [30/Sep/2010:13:56:23 PDT] [B1153]: Loading of destination SampleQ [Queue] in process, 10000 of 14066 messages loaded, 71% complete [30/Sep/2010:13:56:23 PDT] [B1153]: Loading of destination SampleQ [Queue] in process, 14066 of 14066 messages loaded, 100% complete [30/Sep/2010:14:00:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:01:20 PDT] [B1066]: Closing: guest@10.5.220.200:32819->jms:65309 because "[B0059]: Client closed the connection". Count: service=0 broker=4 [30/Sep/2010:14:02:08 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:05:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:05:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:08:48 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:10:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:12:08 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:15:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:15:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:18:48 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:20:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:22:08 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:25:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:25:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:28:48 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:30:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:32:08 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:35:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:35:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:38:48 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:40:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:42:08 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:45:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:45:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:48:48 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:50:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:52:08 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:55:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:14:55:28 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:14:58:48 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:00:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:15:02:09 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:05:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:15:05:29 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:08:49 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:10:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:15:12:09 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:15:15 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:15:15:29 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:18:49 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:19:43 PDT] [B1066]: Closing: guest@10.5.220.200:65418->jms:65309 because "[B0059]: Client closed the connection". Count: service=0 broker=3 [30/Sep/2010:15:19:43 PDT] [B1066]: Closing: guest@10.5.220.200:32817->jms:65309 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:15:19:43 PDT] [B1173]: Received GOODBYE [requestTakeover=true mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=1045164298859995904&ha=true&storeSessionUID=2905995306318732544] from mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=1045164298859995904&ha=true&storeSessionUID=2905995306318732544 [30/Sep/2010:15:19:43 PDT] [B1072]: Closed cluster connection to broker mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=1045164298859995904&ha=true&storeSessionUID=2905995306318732544 [30/Sep/2010:15:19:43 PDT] [B1180]: Deactivated broker Address = mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=1045164298859995904&ha=true&storeSessionUID=2905995306318732544 StartTime = 1285880117667 ProtocolVersion = 410 HeartbeatHost = 10.5.220.200 HeartbeatPort = 27677 [30/Sep/2010:15:19:43 PDT] [B1066]: Closing: guest@10.5.220.200:65320->jms:65309 because "[B0059]: Client closed the connection". Count: service=0 broker=1 [30/Sep/2010:15:19:43 PDT] [B1093]: Shutdown requested by broker administrator [30/Sep/2010:15:19:43 PDT] [B1047]: Shutting down broker... [30/Sep/2010:15:19:43 PDT] [B1077]: Broadcast good-bye to all connections ... [30/Sep/2010:15:19:43 PDT] [B1078]: Flushing good-bye messages ... [30/Sep/2010:15:19:43 PDT] [B1007]: Stopping Service admin with protocol tcp(host = jed-asqe-3.red.iplanet.com, port=0, mode=dedicated) [30/Sep/2010:15:19:43 PDT] [B1007]: Stopping Service jms with protocol tcp(host = jed-asqe-3.red.iplanet.com, port=0, mode=dedicated) [30/Sep/2010:15:19:43 PDT] WARNING [B2181]: Removing 1 messages associated with destination temporary_destination://queue/10.5.220.200/65316/1 [Queue] [30/Sep/2010:15:19:43 PDT] [B1066]: Closing: admin@10.5.220.200:65316->admin:65311 because "[B0061]: Client exited without closing connections". Count: service=0 broker=0 [30/Sep/2010:15:19:48 PDT] JMX Connector Server jmxrmi stopped successfully [30/Sep/2010:15:19:48 PDT] [B1048]: Shutdown of broker complete. [30/Sep/2010:15:22:15 PDT] ================================================================================ Open Message Queue 4.5 Oracle Version: 4.5 (Build 17-d) Compile: Wed Sep 22 23:36:16 PDT 2010 Copyright (c) 2010, Oracle and/or its affiliates. All rights reserved. ================================================================================ Java Runtime: 1.6.0_20 Sun Microsystems Inc. /export/sonia/jdk1.6.0_20/jre [30/Sep/2010:15:22:15 PDT] IMQ_HOME=/export/sonia/hatest/v3/glassfishv3/mq [30/Sep/2010:15:22:15 PDT] IMQ_VARHOME=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq [30/Sep/2010:15:22:15 PDT] SunOS 5.10 sparc jed-asqe-3.red.iplanet.com (2 cpu) root [30/Sep/2010:15:22:15 PDT] Java Heap Size: max=188416k, current=188416k [30/Sep/2010:15:22:15 PDT] Arguments: -javahome /export/sonia/jdk1.6.0_20/jre -varhome /export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq -startRmiRegistry -rmiRegistryPort 27776 -Dimq.imqcmd.user=admin -passfile /var/tmp/asmq2155388382841012353.tmp -Dimq.hostname=jed-asqe-3.red.iplanet.com -save -name stclusterinstance101 -port 27676 -silent -read-stdin [30/Sep/2010:15:22:15 PDT] JMSRA BrokerProps: : imq.persist.jdbc.mysql.user=dbuser, imq.cluster.nowaitForMasterBroker=true, imq.cluster.ha=true, imq.persist.jdbc.dbVendor=mysql, imq.brokerid=stclusterinstance101, imq.persist.jdbc.mysql.password=*****, imq.persist.jdbc.mysql.property.url=jdbc:mysql://jed-asqe-1.red.iplanet.com:3306/dbsmpl1, imq.jmsra.managed=true, imq.cluster.clusterid=stcluster_MQ, imq.imqcmd.password=*****, imq.persist.store=jdbc, imq.cluster.dynamicChangeMasterBrokerEnabled=true [30/Sep/2010:15:22:15 PDT] Broker Properties: imq.passfile.name=asmq2155388382841012353.tmp, imq.passfile.dirpath=/var/tmp, imq.passfile.enabled=true, imq.etchome=/export/sonia/hatest/v3/glassfishv3/mq/etc, imq.varhome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq, imq.hostname=jed-asqe-3.red.iplanet.com, imq.instancename=stclusterinstance101, imq.portmapper.port=27676, imq.imqcmd.user=admin, imq.log.console.output=NONE, imq.home=/export/sonia/hatest/v3/glassfishv3/mq, imq.jmx.rmiregistry.port=27776, imq.instanceshome=/export/sonia/hatest/v3/glassfishv3/glassfish/nodes/agent1/instance101/imq/instances, imq.libhome=/export/sonia/hatest/v3/glassfishv3/mq/lib, imq.jmx.rmiregistry.start=true, imq.readstdin.enabled=true [30/Sep/2010:15:22:15 PDT] [B1202]: Broker stclusterinstance101 is running in HA mode as part of the stcluster_MQ cluster [30/Sep/2010:15:22:15 PDT] [B1004]: Starting the portmapper service using tcp [ 27676, 50, 10.5.220.200 ] with min threads 1 and max threads of 1 [30/Sep/2010:15:22:15 PDT] [B1060]: Loading persistent data... [30/Sep/2010:15:22:15 PDT] imq.persist.jdbc.mysql.driver=com.mysql.jdbc.jdbc2.optional.MysqlConnectionPoolDataSource [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.mysql.property.cacheResultSetMetadata=true [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.mysql.property.cachePreparedStatements=true [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.mysql.property.preparedStatementCacheSize=25 [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.mysql.tableoption=ENGINE=InnoDB [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.connection.validationQuery=/* ping */ [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.connection.validateOnGet=true [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.connection.timeoutIdle=true [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.connection.reaptime=300 [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.min_connections=5 [30/Sep/2010:15:22:16 PDT] imq.persist.jdbc.max_connections=5 [30/Sep/2010:15:22:17 PDT] MySQL, 5.1.51, mysql-connector-java-5.1.13 ( Revision: ${bzr.revision-id} ) [30/Sep/2010:15:22:17 PDT] Using plugged-in persistent store: version=410 brokerid=stclusterinstance101 database connection url=jdbc:mysql://jed-asqe-1.red.iplanet.com:3306/dbsmpl1 database user=dbuser [30/Sep/2010:15:22:17 PDT] [B1170]: Auto Creation of plugged-in persistent store is enabled [30/Sep/2010:15:22:17 PDT] WARNING [B2219]: Broker property setting imq.persist.file.newTxnLog.enabled=true will be ignored [30/Sep/2010:15:22:17 PDT] [B1204]: Starting HA Monitor Service [30/Sep/2010:15:22:17 PDT] [B1206]: Monitor service initializing for brokerid stclusterinstance101 at mq://10.5.220.200:27676/ [30/Sep/2010:15:22:17 PDT] imq.cluster.monitor.interval=30 [30/Sep/2010:15:22:17 PDT] imq.cluster.monitor.threshold=3 [30/Sep/2010:15:22:17 PDT] [B1193]: Broker has started using a store session of 2511648863947055104 and a broker session of 2332067893717502976 [30/Sep/2010:15:22:17 PDT] [B1205]: Starting HA Heartbeat Service [30/Sep/2010:15:22:17 PDT] [B1187]: Heartbeat service binds to jed-asqe-3.red.iplanet.com/10.5.220.200:27676 [30/Sep/2010:15:22:17 PDT] [B1004]: Starting the heartbeat service using udp ( jed-asqe-3.red.iplanet.com/10.5.220.200:27676 ) with min threads 1 and max threads of 1 [30/Sep/2010:15:22:17 PDT] [B1041]: Cluster initialization successful. [30/Sep/2010:15:22:18 PDT] [B1136]: Processing stored transactions [30/Sep/2010:15:22:18 PDT] [B1079]: Found 1 transactions. 0 will be rolled back. [30/Sep/2010:15:22:18 PDT] [B1080]: Of 1 transactions, 0 are in PREPARED state, 1 are in COMMITTED state [30/Sep/2010:15:22:18 PDT] [B1284]: 0 cluster transactions remain in PREPARED state, 0 in COMMITTED state waiting for remote broker completion [30/Sep/2010:15:22:18 PDT] Of 0 remote transactions, 0 in PREPARED state, 0 in COMPLETE state, 0 in COMMITTED state [30/Sep/2010:15:22:18 PDT] [B1013]: Auto Creation of Queues is enabled [30/Sep/2010:15:22:18 PDT] [B1285]: Reaper thread for committed transactions has started (limit 500, interval 900sec). [30/Sep/2010:15:22:18 PDT] [B1159]: Destination Quotes [Topic] has been destroyed [30/Sep/2010:15:22:18 PDT] [B1151]: Loading destination Quotes [Topic] with 0 messages [30/Sep/2010:15:22:19 PDT] [B1152]: Loading of destination Quotes [Topic] complete [30/Sep/2010:15:22:27 PDT] [B1151]: Loading destination mq.sys.dmq [Queue] with 0 messages [30/Sep/2010:15:22:27 PDT] [B1152]: Loading of destination mq.sys.dmq [Queue] complete [30/Sep/2010:15:22:27 PDT] Loading stored durable MultiTopicSubscriberBean:MultiTopicSubscriberBean [30/Sep/2010:15:22:27 PDT] Loading stored durable abcdef:SomeName [30/Sep/2010:15:22:27 PDT] [B1239]: Using platform MBean server [30/Sep/2010:15:22:27 PDT] RMI Registry started on port 27776 [30/Sep/2010:15:22:29 PDT] JMX Connector Server jmxrmi started successfully with url service:jmx:rmi://jed-asqe-3/jndi/rmi://jed-asqe-3.red.iplanet.com:27776/jed-asqe-3.red.iplanet.com/27676/jmxrmi [30/Sep/2010:15:22:29 PDT] [B1004]: Starting the admin service using tcp(host = jed-asqe-3.red.iplanet.com, port=0, mode=dedicated) with min threads 4 and max threads of 10 [30/Sep/2010:15:22:29 PDT] [B1227]: Using file user repository for admin service connection authentication [30/Sep/2010:15:22:29 PDT] [B1004]: Starting the jms service using tcp(host = jed-asqe-3.red.iplanet.com, port=0, mode=dedicated) with min threads 10 and max threads of 1000 [30/Sep/2010:15:22:29 PDT] [B1227]: Using file user repository for jms service connection authentication [30/Sep/2010:15:22:29 PDT] [B1004]: Starting the cluster service using tcp [ jed-asqe-3.red.iplanet.com/10.5.220.200:38964 ] with min threads 1 and max threads of 1 [30/Sep/2010:15:22:29 PDT] [B1229]: Using cluster ID stcluster_MQ [30/Sep/2010:15:22:29 PDT] [B1228]: Cluster ping interval is 60 seconds [30/Sep/2010:15:22:29 PDT] [B1039]: Broker "stclusterinstance101@10.5.220.200:27676" ready. [30/Sep/2010:15:22:29 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:22:29 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27677/?instName=???&brokerID=stclusterinstance102&brokerSessionUID=null&ha=true&storeSessionUID=null failed: [B4256]: Unable to get cluster service port from broker mq://10.5.220.200:27677/?instName=???&brokerID=stclusterinstance102&brokerSessionUID=null&ha=true&storeSessionUID=null [30/Sep/2010:15:22:30 PDT] [B1065]: Accepting: admin@10.5.220.200:38969->admin:38962. Count: service=1 broker=1 [30/Sep/2010:15:22:30 PDT] [B1065]: Accepting: guest@10.5.220.200:38973->jms:38961. Count: service=1 broker=2 [30/Sep/2010:15:22:31 PDT] [B1065]: Accepting: guest@10.5.220.200:38976->jms:38961. Count: service=2 broker=3 [30/Sep/2010:15:22:34 PDT] [B1185]: Starting heartbeat to stclusterinstance102 [30/Sep/2010:15:22:34 PDT] [B1181]: Added heartbeat endpoint /10.5.220.200:27677 [brokerID=stclusterinstance102, brokerSession=324306884840849408] (seq#=0, ts=1285885354611, interval=2, len=232) [30/Sep/2010:15:22:34 PDT] [B1179]: Activated broker Address = mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=324306884840849408&ha=true&storeSessionUID=2905995306318732544 StartTime = 1285885339685 ProtocolVersion = 410 HeartbeatHost = 10.5.220.200 HeartbeatPort = 27677 [30/Sep/2010:15:22:34 PDT] [B1071]: Established cluster connection to broker mq://10.5.220.200:27677/?instName=stclusterinstance102&brokerID=stclusterinstance102&brokerSessionUID=324306884840849408&ha=true&storeSessionUID=2905995306318732544[/10.5.220.200:38990] [30/Sep/2010:15:22:36 PDT] [B1066]: Closing: guest@10.5.220.200:38976->jms:38961 because "[B0059]: Client closed the connection". Count: service=0 broker=2 [30/Sep/2010:15:22:42 PDT] [B1065]: Accepting: guest@10.5.220.200:39020->jms:38961. Count: service=2 broker=3 [30/Sep/2010:15:22:42 PDT] [B1151]: Loading destination SampleQ [Queue] with 14508 messages [30/Sep/2010:15:22:44 PDT] [B1065]: Accepting: guest@10.5.220.200:39025->jms:38961. Count: service=3 broker=4 [30/Sep/2010:15:22:44 PDT] [B1065]: Accepting: guest@10.5.220.200:39027->jms:38961. Count: service=4 broker=5 [30/Sep/2010:15:22:45 PDT] [B1153]: Loading of destination SampleQ [Queue] in process, 10000 of 14508 messages loaded, 68% complete [30/Sep/2010:15:22:45 PDT] [B1153]: Loading of destination SampleQ [Queue] in process, 14508 of 14508 messages loaded, 100% complete [30/Sep/2010:15:24:35 PDT] [B1152]: Loading of destination SampleQ [Queue] complete [30/Sep/2010:15:25:49 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused [30/Sep/2010:15:27:17 PDT] [B1342]: Reaped 0 excessive connections from JDBC connection pool(dbp) [30/Sep/2010:15:27:42 PDT] [B1066]: Closing: guest@10.5.220.200:39025->jms:38961 because "[B0059]: Client closed the connection". Count: service=0 broker=4 [30/Sep/2010:15:27:42 PDT] [B1066]: Closing: guest@10.5.220.200:39027->jms:38961 because "[B0059]: Client closed the connection". Count: service=0 broker=3 [30/Sep/2010:15:27:46 PDT] [B1065]: Accepting: guest@10.5.220.200:39403->jms:38961. Count: service=3 broker=4 [30/Sep/2010:15:27:46 PDT] [B1065]: Accepting: guest@10.5.220.200:39405->jms:38961. Count: service=4 broker=5 [30/Sep/2010:15:29:09 PDT] WARNING [B2105]: Attempting to initiate a cluster connection to mq://10.5.220.200:27678/?instName=???&brokerID=stclusterinstance103&brokerSessionUID=null&ha=true&storeSessionUID=null failed: Connection refused