티스토리 뷰
본 포스팅은 JMS Failover Test에 대해 알아보겠습니다.
1. JEUSMain.xml
virtual muticast방식을 사용하고, connection을 pooling하지 않도록 하기 위해서
jeus.jms.client.use-pooled-connection-factory 옵션을 적용한다.(IMS- 80590)
|
<name>container1</name> <base-port>10061</base-port> <command-option>-Xms256m -Xmx512m -XX:MaxPermSize=128m -Djeus.jms.log.level=FINEST -Djeus.jms.client.use-pooled-connection-factory=false -Djeus.jms.cluster.virtual-multicast-list=jungwon:10071|jungwon_container2 </command-option>
|
|
<name>container2</name> <base-port>10071</base-port> <command-option>-Xms256m -Xmx512m -XX:MaxPermSize=128m -Xss180k -Djeus.jms.log.level=FINEST -Djeus.jms.client.use-pooled-connection-factory=false -Djeus.jms.cluster.virtual-multicast-list=jungwon:10061|jungwon_container1
|
Base-port를 반드시 지정합니다.
Jeus.jms.log.level=FINEST는 JMS관련 디버깅을 위한 것이므로 제거해도 된다.
2. JMSMain.xml
|
<!—primary --> <?xml version="1.0"?> <jms-server xmlns="http://www.tmaxsoft.com/xml/ns/jeus"> <broker-name>active</broker-name> <service-config> <name>jmstest</name> <!--port>19741</port--> <server-url>tcp://localhost:19741</server-url> <blocking-socket>true</blocking-socket> <connection-timeout>1000</connection-timeout> <client-keepalive-timeout>10</client-keepalive-timeout> <backup-service-url>tcp://localhost:39741</backup-service-url> </service-config> <fail-over> <active> <listen-transport-url>gms</listen-transport-url> <check-health-response-timeout>5000</check-health-response-timeout> </active> </fail-over> <persistence-store> <journal> <base-dir>/home/jeus6/jeusmq</base-dir> </journal> </persistence-store> <thread-pool> <min>10</min> <max>20</max> </thread-pool> <connection-factory> <type>queue</type> <name>QueueConnectionFactory</name> <reconnect-enabled>true</reconnect-enabled> </connection-factory> <connection-factory> <type>topic</type> <name>TopicConnectionFactory</name> <reconnect-enabled>true</reconnect-enabled> </connection-factory> <destination> <type>queue</type> <name>ExamplesQueue</name> </destination> <destination> <type>topic</type> <name>ExamplesTopic</name> </destination> </jms-server>
|
|
<!—backup -->
<?xml version="1.0"?> <jms-server xmlns="http://www.tmaxsoft.com/xml/ns/jeus"> <broker-name>standby</broker-name> <service-config> <name>jmstest</name> <!--port>39741</port--> <server-url>tcp://localhost:39741</server-url> <blocking-socket>true</blocking-socket> <connection-timeout>1000</connection-timeout> <client-keepalive-timeout>10</client-keepalive-timeout> <backup-service-url>tcp://localhost:19741</backup-service-url> </service-config> <fail-over> <standby> <active-transport-url>gms://active</active-transport-url> <check-health-response-timeout>5000</check-health-response-timeout> <discovery-response-timeout>5000</discovery-response-timeout> <start-up-max-try-count>0</start-up-max-try-count> <fail-over-max-try-count>-1</fail-over-max-try-count> <auto-switchover>true</auto-switchover> </standby> </fail-over> <persistence-store> <journal> <base-dir>/home/jeus6/jeusmq</base-dir> </journal> </persistence-store> <thread-pool> <min>10</min> <max>20</max> </thread-pool> <connection-factory> <type>queue</type> <name>QueueConnectionFactory</name> <reconnect-enabled>true</reconnect-enabled> </connection-factory> <connection-factory> <type>topic</type> <name>TopicConnectionFactory</name> <reconnect-enabled>true</reconnect-enabled> </connection-factory> <destination> <type>queue</type> <name>ExamplesQueue</name> </destination> <destination> <type>topic</type> <name>ExamplesTopic</name> </destination> </jms-server>
|
다음으로 테스트 및 확인 방법에 대해 알아보겠습니다.
1. 기동시 로그
• active로그
|
[[2013.11.25 00:04:03][2][b288] [container1-44] [GMS-1000] the member(jungwon_container1) will join the group(GMSCluster) [2013.11.25 00:04:27][4][b288] [container1-44] [JMS-6161] initializing jeus.jms.server.manager.ThreadPoolManager [2013.11.25 00:04:27][4][b288] [container1-44] [JMS-12152] the active jms availability agent 'active' is created. [2013.11.25 00:04:27][1][b288] [container1-44] [GMS-1012] warning: JoinedAndReadyState is already reported but we ignored it. group = GMSCluster [2013.11.25 00:04:31][4][b288] [container1-44] [JMS-12250] sending a message '{A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q2} {HEALTHY:active:null}'. [2013.11.25 00:04:31][6][b288] [container1-44] [JMS-1321] waiting for {A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q2} {HEALTHY:active:null}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:04:36][6][b288] [container1-44] [JMS-5801] initialized service[name=JMSServiceChannel-jmstest] [2013.11.25 00:04:36][4][b288] [container1-44] [JMS-7361] creating a persistence store manager instance for jms broker 'active'. [2013.11.25 00:04:36][4][b288] [container1-44] [JMS-7362] initializing jms broker(active)'s persistence store manager. [2013.11.25 00:04:36][4][b288] [container1-44] [JMS-7365] default table names for jms broker(active)'s persistence store manager are destination-table=ACTIVE_DEST, durable-subscription-table=ACTIVE_DSUB, message-table=ACTIVE_MESG, subscription-message-table=ACTIVE_MSGS, transaction-table=ACTIVE_SMSG. [2013.11.25 00:04:36][4][b288] [container1-44] [JMS-7366] building journal persistence store for jms broker(active). [2013.11.25 00:04:36][3][b288] [container1-44] [JMS-7369] table names for journal peristence store of jms broker(active)are destination-table=ACTIVE_DEST, durable-subscription-table=ACTIVE_DSUB. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7371] registering magic number(376006416) of ServerMessage to persistence store manager. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7371] registering magic number(375969110) of Object to persistence store manager. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7371] registering magic number(373367955) of QueueSubscriptionStatus to persistence store manager. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7371] registering magic number(373306515) of TopicDurableSubscriptionStatus to persistence store manager. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7371] registering magic number(376031068) of MessageStatus to persistence store manager. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7371] registering magic number(373387277) of XAParticipant to persistence store manager. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7373] jms broker active's persistence store manager is initialized. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7363] starting jms broker(active)'s persistence store manager. [2013.11.25 00:04:37][5][b288] [container1-44] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@51fb8c6). [2013.11.25 00:04:37][6][b288] [container1-44] [JMS-5501] [SQL/CHECK_TABLE]SELECT TABLENAME FROM SYS.SYSTABLES WHERE TABLENAME=?[TABLE_NAME:string=ACTIVE_DEST] [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7431] recovering destinations in jdbc destination store. table-name=ACTIVE_DEST. [2013.11.25 00:04:37][5][b288] [container1-44] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@51fb8c6). [2013.11.25 00:04:37][6][b288] [container1-44] [JMS-5501] [SQL/RECOVER_DESTINATIONS]SELECT DT_ID, DT_NAME, DT_QUEUE, DT_VALID, DT_LVID, DT_DYNAMIC, DT_OBJECT FROM ACTIVE_DEST [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7433] recovered destination (Q(1:null:ExamplesQueue)) from jdbc destination store [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6572] starting to rebind (name=ExamplesQueue, Object=Q(1:null:ExamplesQueue)) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6574] finished rebinding (name=ExamplesQueue, Object=Q(1:null:ExamplesQueue)) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7433] recovered destination (T(2:null:ExamplesTopic)) from jdbc destination store [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6572] starting to rebind (name=ExamplesTopic, Object=T(2:null:ExamplesTopic)) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6574] finished rebinding (name=ExamplesTopic, Object=T(2:null:ExamplesTopic)) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7433] recovered destination (Q(3:null:JEUSMQ_DLQ)) from jdbc destination store [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6572] starting to rebind (name=JEUSMQ_DLQ, Object=Q(3:null:JEUSMQ_DLQ)) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6574] finished rebinding (name=JEUSMQ_DLQ, Object=Q(3:null:JEUSMQ_DLQ)) [2013.11.25 00:04:37][5][b288] [container1-44] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@51fb8c6). [2013.11.25 00:04:37][6][b288] [container1-44] [JMS-5501] [SQL/BATCH_UPDATE_DESTINATIONS]UPDATE ACTIVE_DEST SET DT_LVID=?, DT_OBJECT=?, DT_VALID=?, DT_DYNAMIC=? WHERE DT_ID=? [2013.11.25 00:04:37][5][b288] [container1-44] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@51fb8c6). [2013.11.25 00:04:37][6][b288] [container1-44] [JMS-5501] [SQL/CHECK_TABLE]SELECT TABLENAME FROM SYS.SYSTABLES WHERE TABLENAME=?[TABLE_NAME:string=ACTIVE_DSUB] [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7511] recovering jdbc durable subscription store. table-name=ACTIVE_DSUB. [2013.11.25 00:04:37][5][b288] [container1-44] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@51fb8c6). [2013.11.25 00:04:37][6][b288] [container1-44] [JMS-5501] [SQL/RECOVER_DURABLE_SUBSCRIPTIONS]SELECT DS_ID, DS_CLIENT_ID, DS_NAME, DS_SELECTOR, DS_VALID, DS_LVID, DT_NAME FROM ACTIVE_DSUB, ACTIVE_DEST WHERE ACTIVE_DSUB.DT_ID=ACTIVE_DEST.DT_ID [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7572] resolving journal message store. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7512] resolving jdbc duralbe subscription store. table-name=ACTIVE_DSUB. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7431] recovering destinations in jdbc destination store. table-name=ACTIVE_DEST. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7632] preparing journal subscription message store [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7672] resolving transaction store. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7633] resolving journal subscription message store [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7572] resolving journal message store. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7512] resolving jdbc duralbe subscription store. table-name=ACTIVE_DSUB. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-7431] recovering destinations in jdbc destination store. table-name=ACTIVE_DEST. [2013.11.25 00:04:37][2][b288] [container1-44] [JMS-7374] jms broker active's persistence store manager is started. [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-5051] connection factory created: XACF(1)[QueueConnectionFactory[172.30.1.2:19741(null)]] [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-5051] connection factory created: XACF(2)[TopicConnectionFactory[172.30.1.2:19741(null)]] [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6572] starting to rebind (name=TopicConnectionFactory, Object=XACF(2)[TopicConnectionFactory[172.30.1.2:19741(null)]]) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6574] finished rebinding (name=TopicConnectionFactory, Object=XACF(2)[TopicConnectionFactory[172.30.1.2:19741(null)]]) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6572] starting to rebind (name=QueueConnectionFactory, Object=XACF(1)[QueueConnectionFactory[172.30.1.2:19741(null)]]) [2013.11.25 00:04:37][4][b288] [container1-44] [JMS-6574] finished rebinding (name=QueueConnectionFactory, Object=XACF(1)[QueueConnectionFactory[172.30.1.2:19741(null)]]) [2013.11.25 00:04:37][0][b288] [container1-44] [JMS-6822] JMS server started [2013.11.25 00:04:48][4][b288] [container1-45] [JMS-12241] received a message '{A:HELLO_ACTIVE_BROKER:F-1968949943:T0:x:x:x:x:x::Q2} {active:standby, {}}' from jungwon_container2. [2013.11.25 00:04:48][4][b288] [container1-45] [JMS-12250] sending a message '{A:HELLO_STANDBY_BROKER:F-1968993091:T0:x:x:x:x:x::A2} {active broker=active, standby broker=standby}'. [2013.11.25 00:04:50][4][b288] [container1-144] [JMS-12251] join event occured. member token is jungwon_container2. [2013.11.25 00:04:50][2][b288] [container1-145] [JMS-12252] joined and ready event occured. member token is jungwon_container2.
|
• standby로그
|
[2013.11.25 00:04:46][2][b288] [container2-44] [GMS-1000] the member(jungwon_container2) will join the group(GMSCluster) [2013.11.25 00:04:48][4][b288] [container2-44] [JMS-6161] initializing jeus.jms.server.manager.ThreadPoolManager [2013.11.25 00:04:48][4][b288] [container2-44] [JMS-12151] the standby jms availability agent 'standby' for the active availability agent 'active' is created. [2013.11.25 00:04:48][1][b288] [container2-44] [GMS-1012] warning: JoinedAndReadyState is already reported but we ignored it. group = GMSCluster [2013.11.25 00:04:48][0][b288] [container2-44] [JMS-6822] JMS server started [2013.11.25 00:04:48][4][b288] [container2-77] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1968949943:T0:x:x:x:x:x::Q2} {active:standby, {}}'. [2013.11.25 00:04:48][6][b288] [container2-77] [JMS-1321] waiting for {A:HELLO_ACTIVE_BROKER:F-1968949943:T0:x:x:x:x:x::Q2} {active:standby, {}}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:04:49][4][b288] [container2-45] [JMS-12241] received a message '{A:HELLO_STANDBY_BROKER:F-1968993091:T0:x:x:x:x:x::A2} {active broker=active, standby broker=standby}' from jungwon_container1. [2013.11.25 00:04:49][4][b288] [container2-45] [JMS-1202] enqueueing {A:HELLO_STANDBY_BROKER:F-1968993091:T0:x:x:x:x:x::A2} {active broker=active, standby broker=standby} as last work into null.REPLY.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:04:49][4][b288] [container2-83] [JMS-12251] join event occured. member token is jungwon_container2. [2013.11.25 00:04:49][4][b288] [container2-85] [JMS-12251] join event occured. member token is jungwon_container1. [2013.11.25 00:04:49][4][b288] [container2-84] [JMS-1205] dequeuing first work out of null.REPLY.QUEUE[1] (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container2-45],5,main]) [2013.11.25 00:04:49][6][b288] [container2-84] [JMS-1203] dequeued first message of null.REPLY.QUEUE[0]: {A:HELLO_STANDBY_BROKER:F-1968993091:T0:x:x:x:x:x::A2} {active broker=active, standby broker=standby} (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container2-45],5,main]) [2013.11.25 00:04:49][2][b288] [container2-77] [JMS-12183] standby broker 'standby' discovered the active broker 'active'. it is running at the engine container 'jungwon_container1'. [2013.11.25 00:04:49][4][b288] [container2-84] [JMS-1205] dequeuing first work out of null.REPLY.QUEUE[0] (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container2-45],5,main]) [2013.11.25 00:04:49][6][b288] [container2-84] [JMS-1203] dequeued first message of null.REPLY.QUEUE[0]: null (executing=false, thread=null)
|
2. Fail-over시 로그(강제로 container kill)
• active로그
|
[2013.11.25 00:52:13][4][b288] [container1-161] [JMS-7291] processing shutdown of JMSClient-RE85308000003 [2013.11.25 00:52:13][4][b288] [container1-161] [JMS-7289] preparing for shutdown of JMSClient-RE85308000003 [2013.11.25 00:52:13][4][b288] [container1-161] [JMS-7295] unregistering JMSClient-RE85308000003 from JMSServiceChannel-jmstest
|
• standby로그
|
[2013.11.25 00:52:29][4][b288] [container2-142] [JMS-12254] failure suspected event occured. member token is jungwon_container1. [2013.11.25 00:52:37][4][b288] [container2-142] [JMS-12256] group leadership event occured. member token is jungwon_container2. [2013.11.25 00:52:40][4][b288] [container2-142] [JMS-12273] failure recovery event occurred. member token is jungwon_container1. [2013.11.25 00:52:40][4][b288] [container2-143] [JMS-12255] failure event occured. member token is jungwon_container1. [2013.11.25 00:52:40][4][b288] [container2-144] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q3} {active:standby, {}}'. [2013.11.25 00:52:40][6][b288] [container2-144] [JMS-1321] waiting for {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q3} {active:standby, {}}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:52:45][2][b288] [container2-144] [JMS-12184] standby broker 'standby' is failed to discover the active broker 'active'. try count is 1. [2013.11.25 00:52:45][4][b288] [container2-144] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q4} {active:standby, {}}'. [2013.11.25 00:52:45][6][b288] [container2-144] [JMS-1321] waiting for {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q4} {active:standby, {}}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:52:50][2][b288] [container2-144] [JMS-12184] standby broker 'standby' is failed to discover the active broker 'active'. try count is 2. [2013.11.25 00:52:50][4][b288] [container2-144] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q5} {active:standby, {}}'. [2013.11.25 00:52:50][6][b288] [container2-144] [JMS-1321] waiting for {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q5} {active:standby, {}}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:52:55][2][b288] [container2-144] [JMS-12184] standby broker 'standby' is failed to discover the active broker 'active'. try count is 3. [2013.11.25 00:52:55][4][b288] [container2-144] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q6} {active:standby, {}}'. [2013.11.25 00:52:55][6][b288] [container2-144] [JMS-1321] waiting for {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q6} {active:standby, {}}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:53:00][2][b288] [container2-144] [JMS-12184] standby broker 'standby' is failed to discover the active broker 'active'. try count is 4. [2013.11.25 00:53:00][4][b288] [container2-144] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q7} {active:standby, {}}'. [2013.11.25 00:53:00][6][b288] [container2-144] [JMS-1321] waiting for {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::Q7} {active:standby, {}}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:53:05][2][b288] [container2-144] [JMS-12184] standby broker 'standby' is failed to discover the active broker 'active'. try count is 5. [2013.11.25 00:53:05][2][b288] [container2-144] [JMS-12186] starting fail-over for the active broker 'active'. [2013.11.25 00:53:09][4][b288] [container2-144] [JMS-12250] sending a message '{A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q8} {HEALTHY:active:standby}'. [2013.11.25 00:53:09][6][b288] [container2-144] [JMS-1321] waiting for {A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q8} {HEALTHY:active:standby}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:53:14][6][b288] [container2-144] [JMS-5801] initialized service[name=JMSServiceChannel-jmstest] [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7361] creating a persistence store manager instance for jms broker 'active'. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7362] initializing jms broker(active)'s persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7365] default table names for jms broker(active)'s persistence store manager are destination-table=ACTIVE_DEST, durable-subscription-table=ACTIVE_DSUB, message-table=ACTIVE_MESG, subscription-message-table=ACTIVE_MSGS, transaction-table=ACTIVE_SMSG. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7366] building journal persistence store for jms broker(active). [2013.11.25 00:53:14][3][b288] [container2-144] [JMS-7369] table names for journal peristence store of jms broker(active)are destination-table=ACTIVE_DEST, durable-subscription-table=ACTIVE_DSUB. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7371] registering magic number(376006416) of ServerMessage to persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7371] registering magic number(375969110) of Object to persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7371] registering magic number(373367955) of QueueSubscriptionStatus to persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7371] registering magic number(373306515) of TopicDurableSubscriptionStatus to persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7371] registering magic number(376031068) of MessageStatus to persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7371] registering magic number(373387277) of XAParticipant to persistence store manager. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7373] jms broker active's persistence store manager is initialized. [2013.11.25 00:53:14][4][b288] [container2-144] [JMS-7363] starting jms broker(active)'s persistence store manager. [2013.11.25 00:53:14][5][b288] [container2-144] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@1494a05c). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-5501] [SQL/CHECK_TABLE]SELECT TABLENAME FROM SYS.SYSTABLES WHERE TABLENAME=?[TABLE_NAME:string=ACTIVE_DEST] [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7431] recovering destinations in jdbc destination store. table-name=ACTIVE_DEST. [2013.11.25 00:53:15][5][b288] [container2-144] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@1494a05c). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-5501] [SQL/RECOVER_DESTINATIONS]SELECT DT_ID, DT_NAME, DT_QUEUE, DT_VALID, DT_LVID, DT_DYNAMIC, DT_OBJECT FROM ACTIVE_DEST [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7433] recovered destination (Q(1:null:ExamplesQueue)) from jdbc destination store [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6572] starting to rebind (name=ExamplesQueue, Object=Q(1:null:ExamplesQueue)) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6574] finished rebinding (name=ExamplesQueue, Object=Q(1:null:ExamplesQueue)) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7433] recovered destination (T(2:null:ExamplesTopic)) from jdbc destination store [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6572] starting to rebind (name=ExamplesTopic, Object=T(2:null:ExamplesTopic)) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6574] finished rebinding (name=ExamplesTopic, Object=T(2:null:ExamplesTopic)) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7433] recovered destination (Q(3:null:JEUSMQ_DLQ)) from jdbc destination store [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6572] starting to rebind (name=JEUSMQ_DLQ, Object=Q(3:null:JEUSMQ_DLQ)) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6574] finished rebinding (name=JEUSMQ_DLQ, Object=Q(3:null:JEUSMQ_DLQ)) [2013.11.25 00:53:15][5][b288] [container2-144] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@1494a05c). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-5501] [SQL/BATCH_UPDATE_DESTINATIONS]UPDATE ACTIVE_DEST SET DT_LVID=?, DT_OBJECT=?, DT_VALID=?, DT_DYNAMIC=? WHERE DT_ID=? [2013.11.25 00:53:15][5][b288] [container2-144] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@1494a05c). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-5501] [SQL/CHECK_TABLE]SELECT TABLENAME FROM SYS.SYSTABLES WHERE TABLENAME=?[TABLE_NAME:string=ACTIVE_DSUB] [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7511] recovering jdbc durable subscription store. table-name=ACTIVE_DSUB. [2013.11.25 00:53:15][5][b288] [container2-144] [JMS-7403] getting jdbc connection from data source(org.apache.derby.jdbc.EmbeddedConnectionPoolDataSource@1494a05c). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-5501] [SQL/RECOVER_DURABLE_SUBSCRIPTIONS]SELECT DS_ID, DS_CLIENT_ID, DS_NAME, DS_SELECTOR, DS_VALID, DS_LVID, DT_NAME FROM ACTIVE_DSUB, ACTIVE_DEST WHERE ACTIVE_DSUB.DT_ID=ACTIVE_DEST.DT_ID [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-7571] recovered message (R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(null:ExamplesQueue)}). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-7571] recovered message (R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(null:ExamplesQueue)}). [2013.11.25 00:53:15][6][b288] [container2-144] [JMS-7571] recovered message (R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(null:ExamplesQueue)}). [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7572] resolving journal message store. [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6134] pre committing produced message of ID:0:85308000003:2:2 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6134] pre committing produced message of ID:0:85308000003:2:2 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6139] post committing produced message of ID:0:85308000003:2:2 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : null: 0 + 81 = 81 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7221] message event[type=ARRIVED occurred for R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6137] message event[type=ARRIVED, id=ID:0:85308000003:2:2] occurred to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-1202] enqueueing R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} as last work into jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (work size=0, executing=false, thread=null) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6134] pre committing produced message of ID:0:85308000003:2:3 [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6134] pre committing produced message of ID:0:85308000003:2:3 [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6139] post committing produced message of ID:0:85308000003:2:3 [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6511] distributing R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : null: 81 + 145 = 226 [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-7221] message event[type=UNDISTRIBUTED occurred for R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7221] message event[type=ARRIVED occurred for R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6137] message event[type=ARRIVED, id=ID:0:85308000003:2:3] occurred to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6137] message event[type=UNDISTRIBUTED, id=ID:0:85308000003:2:2] occurred to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-1202] enqueueing R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} as last work into jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (work size=0, executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6003] message not distributed in SQM[ExamplesQueue], manager : null: ID:0:85308000003:2:2 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6134] pre committing produced message of ID:0:85308000003:2:4 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6134] pre committing produced message of ID:0:85308000003:2:4 [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6139] post committing produced message of ID:0:85308000003:2:4 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : null: 226 + 79 = 305 [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6231] enqueueing message of ID:0:85308000003:2:2 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7221] message event[type=ARRIVED occurred for R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6137] message event[type=ARRIVED, id=ID:0:85308000003:2:4] occurred to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-1202] enqueueing R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} as last work into jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (work size=1, executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7512] resolving jdbc duralbe subscription store. table-name=ACTIVE_DSUB. [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7431] recovering destinations in jdbc destination store. table-name=ACTIVE_DEST. [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7632] preparing journal subscription message store [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-6141] the number of remaining messages in distributor is 2. [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7672] resolving transaction store. [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7633] resolving journal subscription message store [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6511] distributing R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-7221] message event[type=UNDISTRIBUTED occurred for R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6137] message event[type=UNDISTRIBUTED, id=ID:0:85308000003:2:3] occurred to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6003] message not distributed in SQM[ExamplesQueue], manager : null: ID:0:85308000003:2:3 [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7572] resolving journal message store. [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6231] enqueueing message of ID:0:85308000003:2:3 [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-6141] the number of remaining messages in distributor is 1. [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7512] resolving jdbc duralbe subscription store. table-name=ACTIVE_DSUB. [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-7431] recovering destinations in jdbc destination store. table-name=ACTIVE_DEST. [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6511] distributing R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-7221] message event[type=UNDISTRIBUTED occurred for R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6137] message event[type=UNDISTRIBUTED, id=ID:0:85308000003:2:4] occurred to SQM[ExamplesQueue], manager : null [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6003] message not distributed in SQM[ExamplesQueue], manager : null: ID:0:85308000003:2:4 [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-6231] enqueueing message of ID:0:85308000003:2:4 [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-6141] the number of remaining messages in distributor is 0. [2013.11.25 00:53:15][4][b288] [container2-150] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-INTERNAL-3 [container2-144],5,main]) [2013.11.25 00:53:15][6][b288] [container2-150] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: null (executing=false, thread=null) [2013.11.25 00:53:15][2][b288] [container2-144] [JMS-7374] jms broker active's persistence store manager is started. [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-5051] connection factory created: XACF(1)[QueueConnectionFactory[172.30.1.2:39741(null)]] [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-5051] connection factory created: XACF(2)[TopicConnectionFactory[172.30.1.2:39741(null)]] [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6572] starting to rebind (name=TopicConnectionFactory, Object=XACF(2)[TopicConnectionFactory[172.30.1.2:39741(null)]]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6574] finished rebinding (name=TopicConnectionFactory, Object=XACF(2)[TopicConnectionFactory[172.30.1.2:39741(null)]]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6572] starting to rebind (name=QueueConnectionFactory, Object=XACF(1)[QueueConnectionFactory[172.30.1.2:39741(null)]]) [2013.11.25 00:53:15][4][b288] [container2-144] [JMS-6574] finished rebinding (name=QueueConnectionFactory, Object=XACF(1)[QueueConnectionFactory[172.30.1.2:39741(null)]]) [2013.11.25 00:53:15][2][b288] [container2-144] [JMS-12187] fail-over completed for the active broker 'active'.
|
=>설정상 5번 시도후 fail-over시작, 빠른 감지를 원한다면 1으로 셋팅
3. Active에서 서비스 수행시 로그
JEUS의 설치 디렉토리 하위의 samples소스를 이용하여 메시지 송수신 할 때 서버로그를 분석함
• 테스트 절차
receiver수행 (jms 샘플 QueueReceive.java 이용)
|
[[jeus6@jungwon(6008):/home/jeus6/jeus6008/samples/jms/queue]$ jant -f build.xml receive Buildfile: build.xml
receive:
runjavaclient: [java] [2013.11.25 00:46:36][2][] [client-1] [JMS-2053] connecting to server at 172.30.1.2:19741(null), 172.30.1.2:39741(null) with Thread[main [client-1],5,main] for 5000ms [java] JMS Ready To Receive Messages. [java] Message Received: Hello~ [java] Message Received: Congratulations! [java] You succeed in testing PTP messaging application. ;) [java] Message Received: quit
|
Sender수행 (QueueSender참고)
|
[jeus6@jungwon(6008):/home/jeus6/jeus6008/samples/jms/queue]$ jant -f build.xml send Buildfile: build.xml
send:
runjavaclient: [java] [2013.11.25 00:47:31][2][] [client-1] [JMS-2053] connecting to server at 172.30.1.2:19741(null), 172.30.1.2:39741(null) with Thread[main [client-1],5,main] for 5000ms
BUILD SUCCESSFUL Total time: 2 seconds
|
• Active로그
|
[2013.11.25 00:47:31][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-5683] received {A:CREATE_CONNECTION:T0:T3(0):85308000002:x:x:x:x::Q2} through JMSClient-RE85308000002 [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-1202] enqueueing {A:CREATE_CONNECTION:T0:T3(0):85308000002:x:x:x:x::Q2} as last work into JMSClient-RE85308000002.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-154] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:31][6][b288] [container1-154] [JMS-1203] dequeued first message of JMSClient-RE85308000002.RCV.QUEUE[0]: {A:CREATE_CONNECTION:T0:T3(0):85308000002:x:x:x:x::Q2} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:31][4][b288] [container1-154] [JMS-5711] initialized JMSClient-RE85308000002.C2 [2013.11.25 00:47:31][4][b288] [container1-154] [JMS-7286] sending {A:ADMIN_MESSAGE:F0:T7(0):85308000002:2:x:x:x::A2} through JMSClient-RE85308000002 [2013.11.25 00:47:31][4][b288] [container1-154] [JMS-1202] enqueueing T7(0):85308000002:2:x:x:x::A2 as last work into JMSClient-RE85308000002[/172.30.1.2:35263] (work size=0, executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-148] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-6 [container1-154],5,main]) [2013.11.25 00:47:31][6][b288] [container1-148] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: T7(0):85308000002:2:x:x:x::A2 (executing=true, thread=Thread[JMS-ENDPOINT-6 [container1-154],5,main]) [2013.11.25 00:47:31][4][b288] [container1-148] [JMS-1001] writing T7(0):85308000002:2:x:x:x::A2 through JMSClient-RE85308000002[/172.30.1.2:35263] [2013.11.25 00:47:31][4][b288] [container1-148] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-6 [container1-154],5,main]) [2013.11.25 00:47:31][6][b288] [container1-148] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: null (executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-154] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:31][6][b288] [container1-154] [JMS-1203] dequeued first message of JMSClient-RE85308000002.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-5683] received {A:CREATE_SESSION:F1:T7(0):85308000002:2:x:x:x::Q3}{ACK:1} through JMSClient-RE85308000002 [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-1202] enqueueing {A:CREATE_SESSION:F1:T7(0):85308000002:2:x:x:x::Q3}{ACK:1} as last work into JMSClient-RE85308000002.C2.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-155] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.C2.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:31][6][b288] [container1-155] [JMS-1203] dequeued first message of JMSClient-RE85308000002.C2.RCV.QUEUE[0]: {A:CREATE_SESSION:F1:T7(0):85308000002:2:x:x:x::Q3}{ACK:1} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:31][4][b288] [container1-155] [JMS-5716] handling {A:CREATE_SESSION:F1:T7(0):85308000002:2:x:x:x::Q3}{ACK:1} [2013.11.25 00:47:31][4][b288] [container1-155] [JMS-7286] sending {A:CREATE_SESSION:F1:T15(0):85308000002:2:2:x:x::A3}{ACK:1} through JMSClient-RE85308000002 [2013.11.25 00:47:31][4][b288] [container1-155] [JMS-1202] enqueueing T15(0):85308000002:2:2:x:x::A3 as last work into JMSClient-RE85308000002[/172.30.1.2:35263] (work size=0, executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-155] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.C2.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:31][6][b288] [container1-155] [JMS-1203] dequeued first message of JMSClient-RE85308000002.C2.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-148] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-7 [container1-155],5,main]) [2013.11.25 00:47:31][6][b288] [container1-148] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: T15(0):85308000002:2:2:x:x::A3 (executing=true, thread=Thread[JMS-ENDPOINT-7 [container1-155],5,main]) [2013.11.25 00:47:31][4][b288] [container1-148] [JMS-1001] writing T15(0):85308000002:2:2:x:x::A3 through JMSClient-RE85308000002[/172.30.1.2:35263] [2013.11.25 00:47:31][4][b288] [container1-148] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-7 [container1-155],5,main]) [2013.11.25 00:47:31][6][b288] [container1-148] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: null (executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-5683] received {A:CREATE_PRODUCER:F0:T15(0):85308000002:2:2:x:x::Q4},producerID={2},destination={ExamplesQueue},cluster{false,false} through JMSClient-RE85308000002 [2013.11.25 00:47:31][4][b288] [container1-153] [JMS-1202] enqueueing {A:CREATE_PRODUCER:F0:T15(0):85308000002:2:2:x:x::Q4},producerID={2},destination={ExamplesQueue},cluster{false,false} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:31][4][b288] [container1-156] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-156] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:CREATE_PRODUCER:F0:T15(0):85308000002:2:2:x:x::Q4},producerID={2},destination={ExamplesQueue},cluster{false,false} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-156] [JMS-5010] handling {A:CREATE_PRODUCER:F0:T15(0):85308000002:2:2:x:x::Q4},producerID={2},destination={ExamplesQueue},cluster{false,false} in SESSION.RCV.QUEUE[0] [2013.11.25 00:47:32][4][b288] [container1-156] [JMS-7286] sending {A:ADMIN_MESSAGE:F0:T47(0):85308000002:2:2:x:x::A4} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-156] [JMS-1202] enqueueing T47(0):85308000002:2:2:x:x::A4 as last work into JMSClient-RE85308000002[/172.30.1.2:35263] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-8 [container1-156],5,main]) [2013.11.25 00:47:32][4][b288] [container1-156] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: T47(0):85308000002:2:2:x:x::A4 (executing=true, thread=Thread[JMS-ENDPOINT-8 [container1-156],5,main]) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1001] writing T47(0):85308000002:2:2:x:x::A4 through JMSClient-RE85308000002[/172.30.1.2:35263] [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-8 [container1-156],5,main]) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: null (executing=false, thread=null) [2013.11.25 00:47:32][6][b288] [container1-156] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-5683] received {A:START_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1202] enqueueing {A:START_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} as last work into JMSClient-RE85308000002.C2.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-157] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.C2.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-157] [JMS-1203] dequeued first message of JMSClient-RE85308000002.C2.RCV.QUEUE[0]: {A:START_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-157] [JMS-5716] handling {A:START_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} [2013.11.25 00:47:32][4][b288] [container1-157] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.C2.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-157] [JMS-1203] dequeued first message of JMSClient-RE85308000002.C2.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-5683] received R0:Mx{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(null:ExamplesQueue)} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1202] enqueueing R0:Mx{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(null:ExamplesQueue)} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-158] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: R0:Mx{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(null:ExamplesQueue)} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6132] producing MessageContainer[metaHeader=MetaHeader[brokerID=0,entryID=85308000002,connectionID=2,sessionID=2,consumerID=-1,producerID=2,admin=false]] + UserMessage[messageID=ID:0:85308000002:2:2,msgHeader=jeus.jms.common.message.MessageHeader@1108565d,properties=null] + ServerMessage[status=R0,handle=RemoteMessageContentHandle[buffer=00 00 00 00 00 00 13 dc c0 c7 02 00 00 00 02 00 00 00 02 01 51 51 01 00 00 0d 45 78 61 6d 70 6c 00 00 00 00 03 00 00 00 00 00 06 48 65 6c 6c 6f 7e , reference=null]] to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6133] preparing to produceR0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(null:ExamplesQueue)} [2013.11.25 00:47:32][6][b288] [container1-158] [JMS-7575] inserting server message R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6134] pre committing produced message of ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6134] pre committing produced message of ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6139] post committing produced message of ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true: 0 + 81 = 81 [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-7221] message event[type=ARRIVED occurred for R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-6137] message event[type=ARRIVED, id=ID:0:85308000002:2:2] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-1202] enqueueing R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(1:null:ExamplesQueue)} as last work into jeus.jms.server.manager.DestinationManager$Distributor@332af08b (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@332af08b (executing=true, thread=Thread[JMS-ENDPOINT-10 [container1-158],5,main]) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@332af08b: R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-ENDPOINT-10 [container1-158],5,main]) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6511] distributing R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6089] message event[type=ARRIVED, message=R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:x::Q5->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-7255] sending reply message of {A:MESSAGE_ACK:T3:T47(0):85308000002:2:2:x:2::Q5},T{ID:0:85308000002:2:2} [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-7286] sending {A:MESSAGE_ACK:T3:T47(0):85308000002:2:2:x:2::A5},T{ID:0:85308000002:2:2} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-1202] enqueueing Batch Message. size=1 as last work into JMSClient-RE85308000002[/172.30.1.2:35263] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-158] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-158] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-10 [container1-158],5,main]) [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: Batch Message. size=1 (executing=true, thread=Thread[JMS-ENDPOINT-10 [container1-158],5,main]) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6231] enqueueing message of ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6234] checking executor by Thread[JMS-INTERNAL-1 [container1-148],5,main]: true [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1001] writing Batch Message. size=1 through JMSClient-RE85308000002[/172.30.1.2:35263] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-10 [container1-158],5,main]) [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-6229] dequeued oldest message: ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-6225] enqueueing acknowledgement for ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-7221] message event[type=DISTRIBUTED occurred for R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:2::Q5->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-6851] dispaching R0:4{N:{ID:0:85308000002:2:2}T47(0):85308000002:2:2:x:x::x->Q(1:null:ExamplesQueue)} to JMSClient-RE85308000001.C2.S2.C2 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6137] message event[type=DISTRIBUTED, id=ID:0:85308000002:2:2] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-6141] the number of remaining messages in distributor is 0. [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@332af08b (executing=true, thread=Thread[JMS-ENDPOINT-10 [container1-158],5,main]) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@332af08b: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-7286] sending R0:4{N:{ID:0:85308000002:2:2}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} through JMSClient-RE85308000001 [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1202] enqueueing T31(0):85308000002:2:2:2:x::x as last work into JMSClient-RE85308000001[/172.30.1.2:35249] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-5683] received R0:Mx{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(null:ExamplesQueue)} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1202] enqueueing R0:Mx{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(null:ExamplesQueue)} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1205] dequeuing first work out of JMSClient-RE85308000001[/172.30.1.2:35249] (executing=true, thread=Thread[JMS-INTERNAL-3 [container1-160],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-6703] the number of remaining messages in SubscriptionQueue is 0. [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-1203] dequeued first message of JMSClient-RE85308000001[/172.30.1.2:35249]: T31(0):85308000002:2:2:2:x::x (executing=true, thread=Thread[JMS-INTERNAL-3 [container1-160],5,main]) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-6229] dequeued oldest message: null [2013.11.25 00:47:32][6][b288] [container1-147] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: R0:Mx{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(null:ExamplesQueue)} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6132] producing MessageContainer[metaHeader=MetaHeader[brokerID=0,entryID=85308000002,connectionID=2,sessionID=2,consumerID=-1,producerID=2,admin=false]] + UserMessage[messageID=ID:0:85308000002:2:3,msgHeader=jeus.jms.common.message.MessageHeader@7f10f850,properties=null] + ServerMessage[status=R0,handle=RemoteMessageContentHandle[buffer=00 00 00 00 00 00 13 dc c0 c7 02 00 00 00 02 00 00 00 03 01 51 51 01 00 00 0d 45 78 61 6d 70 6c 67 20 61 70 70 6c 69 63 61 74 69 6f 6e 2e 20 3b 29 , reference=null]] to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6133] preparing to produceR0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(null:ExamplesQueue)} [2013.11.25 00:47:32][6][b288] [container1-147] [JMS-7575] inserting server message R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6089] message event[type=DISPATCHED, message=R0:4{N:{ID:0:85308000002:2:2}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1001] writing T31(0):85308000002:2:2:2:x::x through JMSClient-RE85308000001[/172.30.1.2:35249] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1205] dequeuing first work out of JMSClient-RE85308000001[/172.30.1.2:35249] (executing=true, thread=Thread[JMS-INTERNAL-3 [container1-160],5,main]) [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-1203] dequeued first message of JMSClient-RE85308000001[/172.30.1.2:35249]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-5683] received {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:2} through JMSClient-RE85308000001 [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-1202] enqueueing {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:2} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6134] pre committing produced message of ID:0:85308000002:2:3 [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6134] pre committing produced message of ID:0:85308000002:2:3 [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:2} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6139] post committing produced message of ID:0:85308000002:2:3 [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true: 81 + 145 = 226 [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-7221] message event[type=ARRIVED occurred for R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-6137] message event[type=ARRIVED, id=ID:0:85308000002:2:3] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-1202] enqueueing R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(1:null:ExamplesQueue)} as last work into jeus.jms.server.manager.DestinationManager$Distributor@332af08b (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-5941] handling acknowledge for message[id=ID:0:85308000002:2:2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6082] acknowledging ID:0:85308000002:2:2 to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6228] removing acknowledgement for ID:0:85308000002:2:2 [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6089] message event[type=DELIVERED, message=R0:4{N:{ID:0:85308000002:2:2}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-7221] message event[type=COMPLETED occurred for R0:4{N:{ID:0:85308000002:2:2}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6137] message event[type=COMPLETED, id=ID:0:85308000002:2:2] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@332af08b (executing=true, thread=Thread[JMS-ENDPOINT-1 [container1-147],5,main]) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@332af08b: R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-ENDPOINT-1 [container1-147],5,main]) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6511] distributing R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6089] message event[type=ARRIVED, message=R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:x::Q6->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6231] enqueueing message of ID:0:85308000002:2:3 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6234] checking executor by Thread[JMS-INTERNAL-1 [container1-148],5,main]: true [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-7221] message event[type=DISTRIBUTED occurred for R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:2::Q6->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6137] message event[type=DISTRIBUTED, id=ID:0:85308000002:2:3] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-6141] the number of remaining messages in distributor is 0. [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@332af08b (executing=true, thread=Thread[JMS-ENDPOINT-1 [container1-147],5,main]) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@332af08b: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6229] dequeued oldest message: ID:0:85308000002:2:3 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6225] enqueueing acknowledgement for ID:0:85308000002:2:3 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6851] dispaching R0:5{N:{ID:0:85308000002:2:3}T47(0):85308000002:2:2:x:x::x->Q(1:null:ExamplesQueue)} to JMSClient-RE85308000001.C2.S2.C2 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-7286] sending R0:5{N:{ID:0:85308000002:2:3}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} through JMSClient-RE85308000001 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1202] enqueueing T31(0):85308000002:2:2:2:x::x as last work into JMSClient-RE85308000001[/172.30.1.2:35249] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-6703] the number of remaining messages in SubscriptionQueue is 0. [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6229] dequeued oldest message: null [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-7255] sending reply message of {A:MESSAGE_ACK:T3:T47(0):85308000002:2:2:x:2::Q6},T{ID:0:85308000002:2:3} [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-7286] sending {A:MESSAGE_ACK:T3:T47(0):85308000002:2:2:x:2::A6},T{ID:0:85308000002:2:3} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-1202] enqueueing Batch Message. size=1 as last work into JMSClient-RE85308000002[/172.30.1.2:35263] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6089] message event[type=DONE, message=R0:4{N:{ID:0:85308000002:2:2}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-7541] deleting journal persistence store data R0:4{N:{ID:0:85308000002:2:2}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-147] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-147] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000001[/172.30.1.2:35249] (executing=true, thread=Thread[JMS-INTERNAL-1 [container1-148],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000001[/172.30.1.2:35249]: T31(0):85308000002:2:2:2:x::x (executing=true, thread=Thread[JMS-INTERNAL-1 [container1-148],5,main]) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-6089] message event[type=DISPATCHED, message=R0:5{N:{ID:0:85308000002:2:3}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1001] writing T31(0):85308000002:2:2:2:x::x through JMSClient-RE85308000001[/172.30.1.2:35249] [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000001[/172.30.1.2:35249] (executing=true, thread=Thread[JMS-INTERNAL-1 [container1-148],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000001[/172.30.1.2:35249]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-1 [container1-147],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: Batch Message. size=1 (executing=true, thread=Thread[JMS-ENDPOINT-1 [container1-147],5,main]) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1001] writing Batch Message. size=1 through JMSClient-RE85308000002[/172.30.1.2:35263] [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-1 [container1-147],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-5683] received R0:Mx{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(null:ExamplesQueue)} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1202] enqueueing R0:Mx{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(null:ExamplesQueue)} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-150] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: R0:Mx{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(null:ExamplesQueue)} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6132] producing MessageContainer[metaHeader=MetaHeader[brokerID=0,entryID=85308000002,connectionID=2,sessionID=2,consumerID=-1,producerID=2,admin=false]] + UserMessage[messageID=ID:0:85308000002:2:4,msgHeader=jeus.jms.common.message.MessageHeader@6d63de20,properties=null] + ServerMessage[status=R0,handle=RemoteMessageContentHandle[buffer=00 00 00 00 00 00 13 dc c0 c7 02 00 00 00 02 00 00 00 04 01 51 51 01 00 00 0d 45 78 61 6d 70 6c 00 00 00 00 03 00 00 00 00 00 04 71 75 69 74 , reference=null]] to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6133] preparing to produceR0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(null:ExamplesQueue)} [2013.11.25 00:47:32][6][b288] [container1-150] [JMS-7575] inserting server message R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-5683] received {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:3} through JMSClient-RE85308000001 [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-1202] enqueueing {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:3} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true: 226 - 81 = 145 [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:3} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-5941] handling acknowledge for message[id=ID:0:85308000002:2:3] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6082] acknowledging ID:0:85308000002:2:3 to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6228] removing acknowledgement for ID:0:85308000002:2:3 [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6089] message event[type=DELIVERED, message=R0:5{N:{ID:0:85308000002:2:3}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-7221] message event[type=COMPLETED occurred for R0:5{N:{ID:0:85308000002:2:3}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6137] message event[type=COMPLETED, id=ID:0:85308000002:2:3] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6089] message event[type=DONE, message=R0:5{N:{ID:0:85308000002:2:3}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-7541] deleting journal persistence store data R0:5{N:{ID:0:85308000002:2:3}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6134] pre committing produced message of ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6134] pre committing produced message of ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6139] post committing produced message of ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true: 145 + 79 = 224 [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-7221] message event[type=ARRIVED occurred for R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-6137] message event[type=ARRIVED, id=ID:0:85308000002:2:4] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-1202] enqueueing R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(1:null:ExamplesQueue)} as last work into jeus.jms.server.manager.DestinationManager$Distributor@332af08b (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@332af08b (executing=true, thread=Thread[JMS-ENDPOINT-3 [container1-150],5,main]) [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-7255] sending reply message of {A:MESSAGE_ACK:T3:T47(0):85308000002:2:2:x:2::Q7},T{ID:0:85308000002:2:4} [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-7286] sending {A:MESSAGE_ACK:T3:T47(0):85308000002:2:2:x:2::A7},T{ID:0:85308000002:2:4} through JMSClient-RE85308000002 [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@332af08b: R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-ENDPOINT-3 [container1-150],5,main]) [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-1202] enqueueing Batch Message. size=1 as last work into JMSClient-RE85308000002[/172.30.1.2:35263] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6511] distributing R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-3 [container1-150],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: Batch Message. size=1 (executing=true, thread=Thread[JMS-ENDPOINT-3 [container1-150],5,main]) [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6089] message event[type=ARRIVED, message=R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:x::Q7->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1001] writing Batch Message. size=1 through JMSClient-RE85308000002[/172.30.1.2:35263] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6231] enqueueing message of ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6234] checking executor by Thread[JMS-INTERNAL-2 [container1-159],5,main]: true [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-5683] received {A:CLOSE_FACILITY:F0:T47(0):85308000002:2:2:x:2::x} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002[/172.30.1.2:35263] (executing=true, thread=Thread[JMS-ENDPOINT-3 [container1-150],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000002[/172.30.1.2:35263]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6229] dequeued oldest message: ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6225] enqueueing acknowledgement for ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6851] dispaching R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:x::x->Q(1:null:ExamplesQueue)} to JMSClient-RE85308000001.C2.S2.C2 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-7286] sending R0:6{N:{ID:0:85308000002:2:4}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} through JMSClient-RE85308000001 [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-1202] enqueueing T31(0):85308000002:2:2:2:x::x as last work into JMSClient-RE85308000001[/172.30.1.2:35249] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][6][b288] [container1-148] [JMS-6703] the number of remaining messages in SubscriptionQueue is 0. [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000001[/172.30.1.2:35249] (executing=true, thread=Thread[JMS-INTERNAL-1 [container1-148],5,main]) [2013.11.25 00:47:32][4][b288] [container1-148] [JMS-6229] dequeued oldest message: null [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000001[/172.30.1.2:35249]: T31(0):85308000002:2:2:2:x::x (executing=true, thread=Thread[JMS-INTERNAL-1 [container1-148],5,main]) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-6089] message event[type=DISPATCHED, message=R0:6{N:{ID:0:85308000002:2:4}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-7221] message event[type=DISTRIBUTED occurred for R0:6{N:{ID:0:85308000002:2:4}T47(0):85308000002:2:2:x:2::Q7->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1001] writing T31(0):85308000002:2:2:2:x::x through JMSClient-RE85308000001[/172.30.1.2:35249] [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-6137] message event[type=DISTRIBUTED, id=ID:0:85308000002:2:4] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true: 224 - 145 = 79 [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-5683] received {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:4} through JMSClient-RE85308000001 [2013.11.25 00:47:32][4][b288] [container1-146] [JMS-1202] enqueueing {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:4} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-160] [JMS-1205] dequeuing first work out of JMSClient-RE85308000001[/172.30.1.2:35249] (executing=true, thread=Thread[JMS-INTERNAL-1 [container1-148],5,main]) [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-6141] the number of remaining messages in distributor is 0. [2013.11.25 00:47:32][4][b288] [container1-159] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@332af08b (executing=true, thread=Thread[JMS-ENDPOINT-3 [container1-150],5,main]) [2013.11.25 00:47:32][6][b288] [container1-159] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@332af08b: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-150] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-160] [JMS-1203] dequeued first message of JMSClient-RE85308000001[/172.30.1.2:35249]: null (executing=false, thread=null) [2013.11.25 00:47:32][6][b288] [container1-150] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1202] enqueueing {A:CLOSE_FACILITY:F0:T47(0):85308000002:2:2:x:2::x} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1032] received following buffer contents: [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:MESSAGE_ACK:F0:T31(0):85308000001:2:2:2:x::x},{ID:0:85308000002:2:4} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-5683] received {A:STOP_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} through JMSClient-RE85308000002 [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-5941] handling acknowledge for message[id=ID:0:85308000002:2:4] [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-1202] enqueueing {A:STOP_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} as last work into JMSClient-RE85308000002.C2.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-152] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-152] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:CLOSE_FACILITY:F0:T47(0):85308000002:2:2:x:2::x} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-151] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.C2.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-151] [JMS-1203] dequeued first message of JMSClient-RE85308000002.C2.RCV.QUEUE[0]: {A:STOP_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][4][b288] [container1-151] [JMS-5716] handling {A:STOP_FACILITY:F0:T7(0):85308000002:2:x:x:x::x} [2013.11.25 00:47:32][4][b288] [container1-151] [JMS-1205] dequeuing first work out of JMSClient-RE85308000002.C2.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-151] [JMS-1203] dequeued first message of JMSClient-RE85308000002.C2.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-152] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-2[Socket[addr=/172.30.1.2,port=35263,localport=19741]] [container1-153],5,main]) [2013.11.25 00:47:32][6][b288] [container1-152] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6082] acknowledging ID:0:85308000002:2:4 to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6228] removing acknowledgement for ID:0:85308000002:2:4 [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6089] message event[type=DELIVERED, message=R0:6{N:{ID:0:85308000002:2:4}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-7221] message event[type=COMPLETED occurred for R0:6{N:{ID:0:85308000002:2:4}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6137] message event[type=COMPLETED, id=ID:0:85308000002:2:4] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6089] message event[type=DONE, message=R0:6{N:{ID:0:85308000002:2:4}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2] [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-7541] deleting journal persistence store data R0:6{N:{ID:0:85308000002:2:4}T31(0):85308000002:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308000001.C2.S2.C2], active : true: 79 - 79 = 0 [2013.11.25 00:47:32][4][b288] [container1-149] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=35249,localport=19741]] [container1-146],5,main]) [2013.11.25 00:47:32][6][b288] [container1-149] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-7296] connection closed in JMSClient-RE85308000002 with exception [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-8031] starting xa participant (id=1, status=null, gtid-hash-code=1706671532). [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-6645] created jeus.jms.server.manager.Scheduler (offset=32400000ms) [2013.11.25 00:47:32][4][b288] [container1-153] [JMS-6642] scheduled shutdown task for JMSClient-RE85308000002 in 10000ms [2013.11.25 00:47:42][4][b288] [container1-161] [JMS-7291] processing shutdown of JMSClient-RE85308000002 [2013.11.25 00:47:42][4][b288] [container1-161] [JMS-7289] preparing for shutdown of JMSClient-RE85308000002 [2013.11.25 00:47:42][4][b288] [container1-161] [JMS-7295] unregistering JMSClient-RE85308000002 from JMSServiceChannel-jmstest
|
|
jungwon_container1>! ======================================================================== Statistics for ExamplesQueue ------------------------------------------------------------------------ Arrival Completion ------------------------------------------------------------------------ Number of messages: 3 3 Start time: 2013.11.25 00:45:52.451 2013.11.25 00:45:52.451 Last sample time: 2013.11.25 00:47:32.230 2013.11.25 00:47:32.262 Messages per second: 0.02 0.02 ------------------------------------------------------------------------
|
• stanby로그
|
없음 |
4. fail-over된 상태에서 standby로그
• 시나리오
active수행시 메세지 보냄
|
jungwon_container1>! ======================================================================== Statistics for ExamplesQueue ------------------------------------------------------------------------ Arrival Completion ------------------------------------------------------------------------ Number of messages: 6 3 Start time: 2013.11.25 00:45:52.451 2013.11.25 00:45:52.451 Last sample time: 2013.11.25 00:52:02.818 2013.11.25 00:47:32.262 Messages per second: 0.013 0.02 ------------------------------------------------------------------------
|
active강제로 죽여서 fail-over시킴
|
[jeus6@jungwon(6008):/home/jeus6/jeus6008/config/jungwon]$ kill -9 12777 |
|
[2013.11.25 00:53:15][2][b288] [container2-144] [JMS-12187] fail-over completed for the active broker 'active'. |
• Receiver수행시 정상 처리 확인
|
jeus6@jungwon(6008):/home/jeus6/jeus6008/samples/jms/queue]$ jant -f build.xml receive Buildfile: build.xml
receive:
runjavaclient: [java] [2013.11.25 00:55:37][2][] [client-1] [JMS-2053] connecting to server at 172.30.1.2:39741(null), 172.30.1.2:19741(null) with Thread[main [client-1],5,main] for 5000ms [java] JMS Ready To Receive Messages. [java] Message Received: quit [java] Message Received: Congratulations! [java] You succeed in testing PTP messaging application. ;) [java] Message Received: Hello~ |
|
jungwon_container2>stat ExamplesQueue ======================================================================== Statistics for ExamplesQueue ------------------------------------------------------------------------ Arrival Completion ------------------------------------------------------------------------ Number of messages: 3 3 Start time: 2013.11.25 00:53:15.210 2013.11.25 00:53:15.210 Last sample time: 2013.11.25 00:53:15.458 2013.11.25 00:55:37.723 Messages per second: 8.064 0.014 ------------------------------------------------------------------------
|
=> 2번기에서 정상적으로 메세지 가져옴.
• container2에 메세지 관련 내용 로깅됨.
|
[2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:CREATE_CONNECTION:T0:T3(0):85308400001:x:x:x:x::Q2} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:CREATE_CONNECTION:T0:T3(0):85308400001:x:x:x:x::Q2} as last work into JMSClient-RE85308400001.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-153] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-153] [JMS-1203] dequeued first message of JMSClient-RE85308400001.RCV.QUEUE[0]: {A:CREATE_CONNECTION:T0:T3(0):85308400001:x:x:x:x::Q2} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-153] [JMS-5711] initialized JMSClient-RE85308400001.C2 [2013.11.25 00:55:37][4][b288] [container2-153] [JMS-7286] sending {A:ADMIN_MESSAGE:F0:T7(0):85308400001:2:x:x:x::A2} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-153] [JMS-1202] enqueueing T7(0):85308400001:2:x:x:x::A2 as last work into JMSClient-RE85308400001[/172.30.1.2:65468] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-ENDPOINT-1 [container2-153],5,main]) [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: T7(0):85308400001:2:x:x:x::A2 (executing=true, thread=Thread[JMS-ENDPOINT-1 [container2-153],5,main]) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1001] writing T7(0):85308400001:2:x:x:x::A2 through JMSClient-RE85308400001[/172.30.1.2:65468] [2013.11.25 00:55:37][4][b288] [container2-153] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-ENDPOINT-1 [container2-153],5,main]) [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: null (executing=false, thread=null) [2013.11.25 00:55:37][6][b288] [container2-153] [JMS-1203] dequeued first message of JMSClient-RE85308400001.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:CREATE_SESSION:F1:T7(0):85308400001:2:x:x:x::Q3}{ACK:1} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:CREATE_SESSION:F1:T7(0):85308400001:2:x:x:x::Q3}{ACK:1} as last work into JMSClient-RE85308400001.C2.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-154] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001.C2.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-154] [JMS-1203] dequeued first message of JMSClient-RE85308400001.C2.RCV.QUEUE[0]: {A:CREATE_SESSION:F1:T7(0):85308400001:2:x:x:x::Q3}{ACK:1} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-154] [JMS-5716] handling {A:CREATE_SESSION:F1:T7(0):85308400001:2:x:x:x::Q3}{ACK:1} [2013.11.25 00:55:37][4][b288] [container2-154] [JMS-7286] sending {A:CREATE_SESSION:F1:T15(0):85308400001:2:2:x:x::A3}{ACK:1} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-154] [JMS-1202] enqueueing T15(0):85308400001:2:2:x:x::A3 as last work into JMSClient-RE85308400001[/172.30.1.2:65468] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-ENDPOINT-2 [container2-154],5,main]) [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: T15(0):85308400001:2:2:x:x::A3 (executing=true, thread=Thread[JMS-ENDPOINT-2 [container2-154],5,main]) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1001] writing T15(0):85308400001:2:2:x:x::A3 through JMSClient-RE85308400001[/172.30.1.2:65468] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-ENDPOINT-2 [container2-154],5,main]) [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-154] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001.C2.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-154] [JMS-1203] dequeued first message of JMSClient-RE85308400001.C2.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:CREATE_CONSUMER:F0:T15(0):85308400001:2:2:x:x::Q4},destination={ExamplesQueue},selector={null}} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:CREATE_CONSUMER:F0:T15(0):85308400001:2:2:x:x::Q4},destination={ExamplesQueue},selector={null}} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-155] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:CREATE_CONSUMER:F0:T15(0):85308400001:2:2:x:x::Q4},destination={ExamplesQueue},selector={null}} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-5010] handling {A:CREATE_CONSUMER:F0:T15(0):85308400001:2:2:x:x::Q4},destination={ExamplesQueue},selector={null}} in SESSION.RCV.QUEUE[0] [2013.11.25 00:55:37][6][b288] [container2-155] [JMS-6481] initialized (max pending limit=8192, resume dispatch factor=0.4) [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-6081] activating QSM[1:Q(1:null:ExamplesQueue)][---] with JMSClient-RE85308400001.C2.S2.C2 [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-6002] activating SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true by QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-7286] sending {A:ADMIN_MESSAGE:F0:T31(0):85308400001:2:2:2:x::A4} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-1202] enqueueing T31(0):85308400001:2:2:2:x::A4 as last work into JMSClient-RE85308400001[/172.30.1.2:65468] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-ENDPOINT-3 [container2-155],5,main]) [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: T31(0):85308400001:2:2:2:x::A4 (executing=true, thread=Thread[JMS-ENDPOINT-3 [container2-155],5,main]) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1001] writing T31(0):85308400001:2:2:2:x::A4 through JMSClient-RE85308400001[/172.30.1.2:65468] [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-ENDPOINT-3 [container2-155],5,main]) [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:ASYNC_MESSAGE_REQUEST:T0:T31(0):85308400001:2:2:2:x::x} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-155] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-155] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:ASYNC_MESSAGE_REQUEST:T0:T31(0):85308400001:2:2:2:x::x} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-156] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:START_FACILITY:F0:T7(0):85308400001:2:x:x:x::x} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:START_FACILITY:F0:T7(0):85308400001:2:x:x:x::x} as last work into JMSClient-RE85308400001.C2.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][6][b288] [container2-156] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:ASYNC_MESSAGE_REQUEST:T0:T31(0):85308400001:2:2:2:x::x} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:START_FACILITY:F0:T31(0):85308400001:2:2:2:x::x} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-157] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001.C2.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-156] [JMS-6001] triggering SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:START_FACILITY:F0:T31(0):85308400001:2:2:2:x::x} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-157] [JMS-1203] dequeued first message of JMSClient-RE85308400001.C2.RCV.QUEUE[0]: {A:START_FACILITY:F0:T7(0):85308400001:2:x:x:x::x} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-157] [JMS-5716] handling {A:START_FACILITY:F0:T7(0):85308400001:2:x:x:x::x} [2013.11.25 00:55:37][4][b288] [container2-157] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001.C2.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-157] [JMS-1203] dequeued first message of JMSClient-RE85308400001.C2.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6511] distributing R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-156] [JMS-6234] checking executor by Thread[JMS-ENDPOINT-4 [container2-156],5,main]: false [2013.11.25 00:55:37][4][b288] [container2-156] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6089] message event[type=ARRIVED, message=R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][6][b288] [container2-156] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:START_FACILITY:F0:T31(0):85308400001:2:2:2:x::x} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-156] [JMS-6234] checking executor by Thread[JMS-ENDPOINT-4 [container2-156],5,main]: false [2013.11.25 00:55:37][4][b288] [container2-156] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6231] enqueueing message of ID:0:85308000003:2:4 [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6234] checking executor by Thread[JMS-INTERNAL-3 [container2-144],5,main]: true [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-7221] message event[type=DISTRIBUTED occurred for R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6229] dequeued oldest message: ID:0:85308000003:2:4 [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6137] message event[type=DISTRIBUTED, id=ID:0:85308000003:2:4] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6225] enqueueing acknowledgement for ID:0:85308000003:2:4 [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-6141] the number of remaining messages in distributor is 2. [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6851] dispaching R0:9{N:{ID:0:85308000003:2:4}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to JMSClient-RE85308400001.C2.S2.C2 [2013.11.25 00:55:37][6][b288] [container2-156] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-7286] sending R0:9{N:{ID:0:85308000003:2:4}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6511] distributing R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6089] message event[type=ARRIVED, message=R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6231] enqueueing message of ID:0:85308000003:2:3 [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6234] checking executor by Thread[JMS-INTERNAL-3 [container2-144],5,main]: false [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-7221] message event[type=DISTRIBUTED occurred for R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6137] message event[type=DISTRIBUTED, id=ID:0:85308000003:2:3] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-6141] the number of remaining messages in distributor is 1. [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1202] enqueueing T31:x:2:2:2:x::x as last work into JMSClient-RE85308400001[/172.30.1.2:65468] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6511] distributing R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6089] message event[type=ARRIVED, message=R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6223] acquiring sync requests of [] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6231] enqueueing message of ID:0:85308000003:2:2 [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-6703] the number of remaining messages in SubscriptionQueue is 1. [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6229] dequeued oldest message: ID:0:85308000003:2:3 [2013.11.25 00:55:37][6][b288] [container2-158] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: T31:x:2:2:2:x::x (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6225] enqueueing acknowledgement for ID:0:85308000003:2:3 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6851] dispaching R0:8{N:{ID:0:85308000003:2:3}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to JMSClient-RE85308400001.C2.S2.C2 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-7286] sending R0:8{N:{ID:0:85308000003:2:3}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1202] enqueueing T31:x:2:2:2:x::x as last work into JMSClient-RE85308400001[/172.30.1.2:65468] (work size=0, executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-6703] the number of remaining messages in SubscriptionQueue is 1. [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-6089] message event[type=DISPATCHED, message=R0:9{N:{ID:0:85308000003:2:4}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6234] checking executor by Thread[JMS-INTERNAL-3 [container2-144],5,main]: false [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1001] writing T31:x:2:2:2:x::x through JMSClient-RE85308400001[/172.30.1.2:65468] [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6229] dequeued oldest message: ID:0:85308000003:2:2 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6225] enqueueing acknowledgement for ID:0:85308000003:2:2 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6851] dispaching R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} to JMSClient-RE85308400001.C2.S2.C2 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-7286] sending R0:7{N:{ID:0:85308000003:2:2}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-1202] enqueueing T31:x:2:2:2:x::x as last work into JMSClient-RE85308400001[/172.30.1.2:65468] (work size=1, executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][6][b288] [container2-150] [JMS-6703] the number of remaining messages in SubscriptionQueue is 0. [2013.11.25 00:55:37][4][b288] [container2-150] [JMS-6229] dequeued oldest message: null [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-7221] message event[type=DISTRIBUTED occurred for R0:7{N:{ID:0:85308000003:2:2}T0:x:x:x:x:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-6137] message event[type=DISTRIBUTED, id=ID:0:85308000003:2:2] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-6141] the number of remaining messages in distributor is 0. [2013.11.25 00:55:37][4][b288] [container2-144] [JMS-1205] dequeuing first work out of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6 (executing=true, thread=Thread[JMS-ENDPOINT-4 [container2-156],5,main]) [2013.11.25 00:55:37][6][b288] [container2-144] [JMS-1203] dequeued first message of jeus.jms.server.manager.DestinationManager$Distributor@2450fe6: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][6][b288] [container2-158] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: T31:x:2:2:2:x::x (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-6089] message event[type=DISPATCHED, message=R0:8{N:{ID:0:85308000003:2:3}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1001] writing T31:x:2:2:2:x::x through JMSClient-RE85308400001[/172.30.1.2:65468] [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][6][b288] [container2-158] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: T31:x:2:2:2:x::x (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-6089] message event[type=DISPATCHED, message=R0:7{N:{ID:0:85308000003:2:2}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1001] writing T31:x:2:2:2:x::x through JMSClient-RE85308400001[/172.30.1.2:65468] [2013.11.25 00:55:37][4][b288] [container2-158] [JMS-1205] dequeuing first work out of JMSClient-RE85308400001[/172.30.1.2:65468] (executing=true, thread=Thread[JMS-INTERNAL-4 [container2-150],5,main]) [2013.11.25 00:55:37][6][b288] [container2-158] [JMS-1203] dequeued first message of JMSClient-RE85308400001[/172.30.1.2:65468]: null (executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:4} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:4} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1032] received following buffer contents: [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:4} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:3} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:3} as last work into SESSION.RCV.QUEUE[0] (work size=0, executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-5683] received {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:2} through JMSClient-RE85308400001 [2013.11.25 00:55:37][4][b288] [container2-152] [JMS-1202] enqueueing {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:2} as last work into SESSION.RCV.QUEUE[1] (work size=1, executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-5941] handling acknowledge for message[id=ID:0:85308000003:2:4] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6082] acknowledging ID:0:85308000003:2:4 to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6228] removing acknowledgement for ID:0:85308000003:2:4 [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6089] message event[type=DELIVERED, message=R0:9{N:{ID:0:85308000003:2:4}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-7221] message event[type=COMPLETED occurred for R0:9{N:{ID:0:85308000003:2:4}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6137] message event[type=COMPLETED, id=ID:0:85308000003:2:4] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6089] message event[type=DONE, message=R0:9{N:{ID:0:85308000003:2:4}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-7541] deleting journal persistence store data R0:9{N:{ID:0:85308000003:2:4}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true: 305 - 79 = 226 [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[2] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[1]: {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:3} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-5941] handling acknowledge for message[id=ID:0:85308000003:2:3] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6082] acknowledging ID:0:85308000003:2:3 to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6228] removing acknowledgement for ID:0:85308000003:2:3 [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6089] message event[type=DELIVERED, message=R0:8{N:{ID:0:85308000003:2:3}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-7221] message event[type=COMPLETED occurred for R0:8{N:{ID:0:85308000003:2:3}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6137] message event[type=COMPLETED, id=ID:0:85308000003:2:3] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6089] message event[type=DONE, message=R0:8{N:{ID:0:85308000003:2:3}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-7541] deleting journal persistence store data R0:8{N:{ID:0:85308000003:2:3}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true: 226 - 145 = 81 [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[1] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: {A:MESSAGE_ACK:F0:T31(0):85308400001:2:2:2:x::x},{ID:0:85308000003:2:2} (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-5941] handling acknowledge for message[id=ID:0:85308000003:2:2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6082] acknowledging ID:0:85308000003:2:2 to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6228] removing acknowledgement for ID:0:85308000003:2:2 [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6089] message event[type=DELIVERED, message=R0:7{N:{ID:0:85308000003:2:2}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-7221] message event[type=COMPLETED occurred for R0:7{N:{ID:0:85308000003:2:2}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6137] message event[type=COMPLETED, id=ID:0:85308000003:2:2] occurred to SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6089] message event[type=DONE, message=R0:7{N:{ID:0:85308000003:2:2}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)}] occurred to QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-7541] deleting journal persistence store data R0:7{N:{ID:0:85308000003:2:2}T31:x:2:2:2:x::x->Q(1:null:ExamplesQueue)} [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-6138] changing memory usage of SQM[ExamplesQueue], manager : QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2], active : true: 81 - 81 = 0 [2013.11.25 00:55:37][4][b288] [container2-159] [JMS-1205] dequeuing first work out of SESSION.RCV.QUEUE[0] (executing=true, thread=Thread[JEUSMQ_SOCKET_THREAD-1[Socket[addr=/172.30.1.2,port=65468,localport=39741]] [container2-152],5,main]) [2013.11.25 00:55:37][6][b288] [container2-159] [JMS-1203] dequeued first message of SESSION.RCV.QUEUE[0]: null (executing=false, thread=null)
|
5. fail-back시 로그(<auto-switchover>true</auto-switchover>)
• 시나리오
|
stanby가 기동된 상태에서 active 기동하여 정상적으로 fail-back됨을 확인 |
• active로그
|
[2013.11.25 00:59:40][2][b288] [container1-44] [GMS-1000] the member(jungwon_container1) will join the group(GMSCluster) [2013.11.25 00:59:43][4][b288] [container1-44] [JMS-6161] initializing jeus.jms.server.manager.ThreadPoolManager [2013.11.25 00:59:43][4][b288] [container1-44] [JMS-12152] the active jms availability agent 'active' is created. [2013.11.25 00:59:43][1][b288] [container1-44] [GMS-1012] warning: JoinedAndReadyState is already reported but we ignored it. group = GMSCluster [2013.11.25 00:59:44][4][b288] [container1-77] [JMS-12251] join event occured. member token is jungwon_container2. [2013.11.25 00:59:44][4][b288] [container1-78] [JMS-12251] join event occured. member token is jungwon_container1. [2013.11.25 00:59:45][4][b288] [container1-44] [JMS-12250] sending a message '{A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q2} {HEALTHY:active:null}'. [2013.11.25 00:59:45][6][b288] [container1-44] [JMS-1321] waiting for {A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q2} {HEALTHY:active:null}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:59:45][4][b288] [container1-45] [JMS-12241] received a message '{A:CHECK_HEALTH:F0:T0:x:x:x:x:x::A2} {HEALTHY:active:standby}' from jungwon_container2. [2013.11.25 00:59:45][4][b288] [container1-45] [JMS-1202] enqueueing {A:CHECK_HEALTH:F0:T0:x:x:x:x:x::A2} {HEALTHY:active:standby} as last work into null.REPLY.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:59:45][4][b288] [container1-79] [JMS-1205] dequeuing first work out of null.REPLY.QUEUE[1] (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container1-45],5,main]) [2013.11.25 00:59:45][6][b288] [container1-79] [JMS-1203] dequeued first message of null.REPLY.QUEUE[0]: {A:CHECK_HEALTH:F0:T0:x:x:x:x:x::A2} {HEALTHY:active:standby} (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container1-45],5,main]) [2013.11.25 00:59:45][4][b288] [container1-79] [JMS-1205] dequeuing first work out of null.REPLY.QUEUE[0] (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container1-45],5,main]) [2013.11.25 00:59:45][4][b288] [container1-44] [JMS-12250] sending a message '{A:HELLO_STANDBY_BROKER:F-1965656311:T0:x:x:x:x:x::Q3} {active broker=active, standby broker=null}'. [2013.11.25 00:59:45][6][b288] [container1-79] [JMS-1203] dequeued first message of null.REPLY.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:59:45][6][b288] [container1-44] [JMS-1321] waiting for {A:HELLO_STANDBY_BROKER:F-1965656311:T0:x:x:x:x:x::Q3} {active broker=active, standby broker=null}'s reply. timeout is 5000 ms. time to wait is 5000ms. [2013.11.25 00:59:45][4][b288] [container1-45] [JMS-12241] received a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::A3} {active:standby, {}}' from jungwon_container2. [2013.11.25 00:59:45][4][b288] [container1-45] [JMS-1202] enqueueing {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::A3} {active:standby, {}} as last work into null.REPLY.QUEUE[0] (work size=0, executing=false, thread=null) [2013.11.25 00:59:45][4][b288] [container1-79] [JMS-1205] dequeuing first work out of null.REPLY.QUEUE[1] (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container1-45],5,main]) [2013.11.25 00:59:45][6][b288] [container1-79] [JMS-1203] dequeued first message of null.REPLY.QUEUE[0]: {A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::A3} {active:standby, {}} (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container1-45],5,main]) [2013.11.25 00:59:45][4][b288] [container1-79] [JMS-1205] dequeuing first work out of null.REPLY.QUEUE[0] (executing=true, thread=Thread[com.sun.enterprise.ee.cms.impl.common.Router Thread [container1-45],5,main]) [2013.11.25 00:59:45][6][b288] [container1-79] [JMS-1203] dequeued first message of null.REPLY.QUEUE[0]: null (executing=false, thread=null) [2013.11.25 00:59:45][0][b288] [container1-44] [JMS-6822] JMS server started [2013.11.25 00:59:45][4][b288] [container1-45] [JMS-12241] received a message '{A:REPORT_ERROR:F0:T0:x:x:x:x:x::x} {ERROR_TRYING_TO_RECOVER:active:standby}' from jungwon_container2. [2013.11.25 00:59:45][2][b288] [container1-45] [JMS-12246] starting fail-over by report error. [2013.11.25 00:59:46][6][b288] [container1-45] [JMS-5801] initialized service[name=JMSServiceChannel-jmstest] [2013.11.25 00:59:46][4][b288] [container1-45] [JMS-7361] creating a persistence store manager instance for jms broker 'active'. [2013.11.25 00:59:46][4][b288] [container1-45] [JMS-7362] initializing jms broker(active)'s persistence store manager. [2013.11.25 00:59:46][4][b288] [container1-45] [JMS-7365] default table names for jms broker(active)'s persistence store manager are destination-table=ACTIVE_DEST, durable-subscription-table=ACTIVE_DSUB, message-table=ACTIVE_MESG, subscription-message-table=ACTIVE_MSGS, transaction-table=ACTIVE_SMSG. [2013.11.25 00:59:46][4][b288] [container1-45] [JMS-7366] building journal persistence store for jms broker(active). [2013.11.25 00:59:46][3][b288] [container1-45] [JMS-7369] table names for journal peristence store of jms broker(active)are destination-table=ACTIVE_DEST, durable-subscription-table=ACTIVE_DSUB. [2013.11.25 00:59:46][0][b288] [container1-45] [JMS-6823] failed to start JMS server [2013.11.25 00:59:46][4][b288] [container1-45] [JMS-7364] shutting down jms broker(active)'s persistence store manager. [2013.11.25 00:59:46][2][b288] [container1-45] [JMS-7375] jms broker active's persistence store manager is shut down. [2013.11.25 00:59:46][4][b288] [container1-45] [JMS-6162] processing shutdown of jeus.util.StatisticThreadPoolExecutor@6cff7cd8 [2013.11.25 00:59:46][2][b288] [container1-45] [GMS-1001] the member(jungwon_container1) will leave the group(GMSCluster) [2013.11.25 00:59:46][0][b288] [container1-45] [JMS-12245] exception occured while fail-over by report error.
|
=> 기동되었던 것을 확인하고 starting switch over 수행
=> 그러면서 standby가 잡고 있는 filelock때문게 기동 실패
(연구소 문의 에정중)
• stanby로그
|
[2013.11.25 00:59:43][4][b288] [container2-162] [JMS-12251] join event occured. member token is jungwon_container1. [2013.11.25 00:59:43][2][b288] [container2-163] [JMS-12252] joined and ready event occured. member token is jungwon_container1. [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-12241] received a message '{A:CHECK_HEALTH:F0:T0:x:x:x:x:x::Q2} {HEALTHY:active:null}' from jungwon_container1. [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-12250] sending a message '{A:CHECK_HEALTH:F0:T0:x:x:x:x:x::A2} {HEALTHY:active:standby}'. [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-12241] received a message '{A:HELLO_STANDBY_BROKER:F-1965656311:T0:x:x:x:x:x::Q3} {active broker=active, standby broker=null}' from jungwon_container1. [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-12250] sending a message '{A:HELLO_ACTIVE_BROKER:F-1966474781:T0:x:x:x:x:x::A3} {active:standby, {}}'. [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-6825] starting switch over. [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6090] consumer closed for QSM[1:Q(1:null:ExamplesQueue)][JMSClient-RE85308400001.C2.S2.C2] [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6734] recovering all for shutdown of QSM[1:Q(1:null:ExamplesQueue)][---] [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6007] messages recovering in SQM[ExamplesQueue], manager : null: [] [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6007] messages recovering in SQM[ExamplesQueue], manager : null: [] [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-7291] processing shutdown of JMSClient-RE85308400001 [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-7289] preparing for shutdown of JMSClient-RE85308400001 [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-7295] unregistering JMSClient-RE85308400001 from JMSServiceChannel-jmstest [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-6589] starting to unbind gracefully (name=ExamplesQueue) [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6591] finished unbinding gracefully (name=ExamplesQueue) [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-6589] starting to unbind gracefully (name=ExamplesTopic) [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6591] finished unbinding gracefully (name=ExamplesTopic) [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-6589] starting to unbind gracefully (name=JEUSMQ_DLQ) [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6591] finished unbinding gracefully (name=JEUSMQ_DLQ) [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-6589] starting to unbind gracefully (name=TopicConnectionFactory) [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6591] finished unbinding gracefully (name=TopicConnectionFactory) [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-6589] starting to unbind gracefully (name=QueueConnectionFactory) [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-6591] finished unbinding gracefully (name=QueueConnectionFactory) [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-7364] shutting down jms broker(active)'s persistence store manager. [2013.11.25 00:59:45][2][b288] [container2-45] [JMS-7375] jms broker active's persistence store manager is shut down. [2013.11.25 00:59:45][4][b288] [container2-45] [JMS-12250] sending a message '{A:REPORT_ERROR:F0:T0:x:x:x:x:x::x} {ERROR_TRYING_TO_RECOVER:active:standby}'. [2013.11.25 00:59:45][0][b288] [container2-45] [JMS-6827] switch over completed. [2013.11.25 00:59:46][4][b288] [container2-163] [JMS-12253] planned shutdown event occured. member token is jungwon_container1.
|
이상입니다.
'④ 미들웨어 > ⓙ JEUS' 카테고리의 다른 글
| [Web Application Server] Java Message Service(JMS - QUEUE) (0) | 2018.03.26 |
|---|---|
| [JEUS] log4jdbc 설정 가이드 (0) | 2018.03.25 |
| [JMS] Failover Test (1/2) (0) | 2018.03.25 |
| [JEUS] IIS 연동가이드 (2) | 2018.03.25 |
| [JEUS6] AddContainer & ApplicationDeploy (2) | 2018.03.24 |
- Total
- Today
- Yesterday
- SA
- JEUS6
- Da
- wildfly
- aws
- MSA
- 오픈스택
- Docker
- SWA
- jeus
- JBoss
- webtob
- aa
- openstack tenant
- k8s
- OpenStack
- kubernetes
- 마이크로서비스
- 아키텍처
- 마이크로서비스 아키텍처
- API Gateway
- apache
- git
- node.js
- JEUS7
- nodejs
- openstack token issue
- TA
- Architecture
- 쿠버네티스
| 일 | 월 | 화 | 수 | 목 | 금 | 토 |
|---|---|---|---|---|---|---|
| 1 | 2 | 3 | 4 | 5 | 6 | |
| 7 | 8 | 9 | 10 | 11 | 12 | 13 |
| 14 | 15 | 16 | 17 | 18 | 19 | 20 |
| 21 | 22 | 23 | 24 | 25 | 26 | 27 |
| 28 | 29 | 30 |