2018-06-27 10:58:25 DEBUG Client:1233 - starting callback Connector: InvokerLocator [bisocket://10.1.4.176:299145052/callback?callbackServerHost=10.1.4.176&callbackServerPort=299145052&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=a144w-d08cd4-jivzfju7-1-jiwpldzl-1lca&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper] 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[77f1fef5, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1591 - SocketServerInvoker[10.1.4.176:299145052] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory. 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[77f1fef5, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1597 - SocketServerInvoker[10.1.4.176:299145052] created server socket factory javax.net.DefaultServerSocketFactory@709bb6a6 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1002404382] stopped 2018-06-27 10:58:25 DEBUG BisocketServerInvoker:688 - SocketServerInvoker[10.1.4.176:299145052] setting pingFrequency to 30000 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[38a5b9d0, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG BisocketServerInvoker:706 - SocketServerInvoker[10.1.4.176:299145052] setting pingWindowFactor to 71582 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[38a5b9d0, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG Connector:390 - org.jboss.remoting.transport.Connector@b6ff387 started 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:913284043] stopped 2018-06-27 10:58:25 DEBUG Client:703 - Client[347709039:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7].connect(null) 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4cee9296, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG Client:1926 - Client[347709039:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] connected to InvokerLocator [bisocket://10.1.4.176:299145052/callback?callbackServerHost=10.1.4.176&callbackServerPort=299145052&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=a144w-d08cd4-jivzfju7-1-jiwpldzl-1lca&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper] 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4cee9296, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG Client:740 - Client[347709039:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] is connected 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:2066102684] stopped 2018-06-27 10:58:25 DEBUG ServerInvoker:1845 - ServerInvoker (SocketServerInvoker[10.1.4.176:299145052]) added client callback handler CallbackManager[6b958f33] with session id of a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7+a144w-d08cd4-jivzfju7-1-jiwple0e-1lcc and callback handle object of null. 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[2e744da2, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG InvokerRegistry:645 - removed org.jboss.remoting.transport.local.LocalClientInvoker@61205e36 from registry 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[2e744da2, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG Client:824 - Client[347709039:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] is disconnected 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1170782453] stopped 2018-06-27 10:58:25 DEBUG BisocketClientInvoker:639 - getting secondary locator 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[27db1861, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[27db1861, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1098571810] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1a71f31, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1a71f31, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG BisocketClientInvoker:647 - secondary locator: InvokerLocator [null://10.4.9.52:1024/?] 2018-06-27 10:58:25 DEBUG BisocketServerInvoker:384 - creating control connection: InvokerLocator [null://10.4.9.52:1024/?] 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:570078772] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[6673656c, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[6673656c, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:423021832] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4c4796c8, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4c4796c8, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1276622234] stopped 2018-06-27 10:58:25 DEBUG BisocketServerInvoker:468 - SocketServerInvoker[10.1.4.176:299145052] created control connection (a144w-d08cd4-jivzfju7-1-jiwple0e-1lcc): Socket[addr=/10.4.9.52,port=1024,localport=53455] 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:135125846] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[12102e69, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[12102e69, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1041728727] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[7148ce05, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[7148ce05, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1639373070] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[6b53147, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[6b53147, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1389527217] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[5f2ec453, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[5f2ec453, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1688063435] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[73c85139, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[73c85139, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1154469779] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[27c7790f, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[27c7790f, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[71f1f206, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[71f1f206, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:89821353] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[731a1d29, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[731a1d29, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:488173060] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[7d1cf389, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[7d1cf389, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:30719525] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[2db4cf8, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[2db4cf8, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1289953237] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[493d6c7d, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[493d6c7d, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1533938786] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4233ec3c, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4233ec3c, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ConnectionValidator:303 - ConnectionValidator[2785ffab:null, pingPeriod=2000 ms] created 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1058740281] stopped 2018-06-27 10:58:25 DEBUG ConnectionValidator:424 - ConnectionValidator[2785ffab:null, pingPeriod=2000 ms] added ConnectionListener: ConsolidatedListener(UNINITIALIZED) for Client[1732870043:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[5ce197c4, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ConnectionValidator:781 - ConnectionValidator[2785ffab:null, pingPeriod=2000 ms]: pingPeriod: 2000 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[5ce197c4, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ConnectionValidator:782 - ConnectionValidator[2785ffab:null, pingPeriod=2000 ms]: pingTimeout: 1000 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1824478935] stopped 2018-06-27 10:58:25 DEBUG ConnectionValidator:783 - ConnectionValidator[2785ffab:null, pingPeriod=2000 ms]: ping retries: 1 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1f4e598c, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1f4e598c, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1322262939] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[3c3e7a41, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[3c3e7a41, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:753247747] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[48448cd8, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[48448cd8, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:945834089] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1aa4df89, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1aa4df89, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1623497156] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1fc5ce5f, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[1fc5ce5f, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4ce9277e, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[4ce9277e, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1238149788] stopped 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:588598999] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:604 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting client socket wrapper class name to org.jboss.jms.client.remoting.ClientSocketWrapper 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:613 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting shouldCheckConnection to false 2018-06-27 10:58:25 DEBUG SocketClientInvoker:105 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting timeout to 300000 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:300 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] constructed 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:604 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting client socket wrapper class name to org.jboss.jms.client.remoting.ClientSocketWrapper 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:613 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting shouldCheckConnection to false 2018-06-27 10:58:25 DEBUG SocketClientInvoker:105 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting timeout to 300000 2018-06-27 10:58:25 DEBUG BisocketClientInvoker:187 - Setting ping frequency to: 30000 2018-06-27 10:58:25 DEBUG BisocketClientInvoker:202 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] setting pingWindowFactor to 71582 2018-06-27 10:58:25 DEBUG MicroRemoteClientInvoker:305 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] connecting 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:1092 - Creating semaphore with size 50 2018-06-27 10:58:25 DEBUG MicroRemoteClientInvoker:319 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] connected 2018-06-27 10:58:25 DEBUG ConnectionValidator:828 - ConnectionValidator[2785ffab:SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] started 2018-06-27 10:58:25 DEBUG JmsConfiguration$CamelJmsTemplate:492 - Executing callback on JMS Session: JBossSession->SessionDelegate[1420338503, ID=rq52-02elpwij-1-w747jbij-zunerk-100j3] 2018-06-27 10:58:25 DEBUG JmsConfiguration:539 - Sending JMS message to: JBossQueue[npgToOssQueue] with message: delegator->JBossMessage[0]:PERSISTENT, deliveryId=0 2018-06-27 10:58:25 DEBUG ConnectionValidator:457 - ConnectionValidator[2785ffab:SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] removed ConnectionListener: ConnectionState[qq52-i1elpwij-1-w747jbij-zunerk-100j3].ConsolidatedListener for Client[1732870043:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] 2018-06-27 10:58:25 DEBUG InvokerRegistry:645 - removed SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] from registry 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:25 DEBUG ConnectionValidator:1034 - ConnectionValidator[2785ffab:SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] stopped, returning true 2018-06-27 10:58:25 DEBUG Client:608 - Client[1732870043:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7].removeConnectionListener() removed from static map: ConnectionValidator[2785ffab:SocketClientInvoker[57325a2e, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] 2018-06-27 10:58:25 DEBUG Client:703 - Client[1883195130:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7].connect(null) 2018-06-27 10:58:25 DEBUG Client:1926 - Client[1883195130:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] connected to InvokerLocator [bisocket://10.1.4.176:299145052/callback?callbackServerHost=10.1.4.176&callbackServerPort=299145052&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=a144w-d08cd4-jivzfju7-1-jiwpldzl-1lca&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper] 2018-06-27 10:58:25 DEBUG Client:740 - Client[1883195130:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] is connected 2018-06-27 10:58:25 DEBUG ServerInvoker:1854 - ServerInvoker (SocketServerInvoker[10.1.4.176:299145052]) removing client callback handler with session id of a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7+a144w-d08cd4-jivzfju7-1-jiwple0e-1lcc. 2018-06-27 10:58:25 DEBUG BisocketServerInvoker:484 - SocketServerInvoker[10.1.4.176:299145052] shutting down control connection: a144w-d08cd4-jivzfju7-1-jiwple0e-1lcc 2018-06-27 10:58:25 DEBUG InvokerRegistry:645 - removed org.jboss.remoting.transport.local.LocalClientInvoker@2e0b300f from registry 2018-06-27 10:58:25 DEBUG Client:824 - Client[1883195130:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] is disconnected 2018-06-27 10:58:25 DEBUG SocketServerInvoker:480 - SocketServerInvoker[10.1.4.176:299145052] stopped threads in clientpool 2018-06-27 10:58:25 DEBUG SocketServerInvoker:498 - SocketServerInvoker[10.1.4.176:299145052] stopped threads in threadpool 2018-06-27 10:58:25 DEBUG SocketServerInvoker:503 - SocketServerInvoker[10.1.4.176:299145052] exiting 2018-06-27 10:58:25 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:299145052] stopped 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:772 - SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457] setting timeout to 30000 for this invocation 2018-06-27 10:58:25 DEBUG MicroSocketClientInvoker:772 - SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457] setting timeout to 30000 for this invocation 2018-06-27 10:58:25 DEBUG InvokerRegistry:649 - decremented SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457]'s count, current count 2 2018-06-27 10:58:25 DEBUG Client:824 - Client[1732870043:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc7] is disconnected 2018-06-27 10:58:25 DEBUG InvokerRegistry:649 - decremented SocketClientInvoker[697b20d5, bisocket://10.4.9.52:4457]'s count, current count 2 2018-06-27 10:58:25 DEBUG Client:824 - Client[672906992:a144w-d08cd4-jivzfju7-1-jiwpldzh-1lc8] is disconnected 2018-06-27 10:58:25 INFO Tracer:95 - ID-TPPTIE08-62764-1530035160705-0-11672 >>> (mnp_npg_call) jms://queue:npgToOssQueue --> log[Queue processed the message sucessfully.] <<< Pattern:InOnly, Headers:{breadcrumbId=ID-TPPTIE08-62764-1530035160705-0-11671, firedTime=Wed Jun 27 10:58:25 PKT 2018}, BodyType:null, Body:[Body is null] 2018-06-27 10:58:25 DEBUG mnp_npg_call:71 - Queue processed the message sucessfully. 2018-06-27 10:58:25 INFO Tracer:95 - ID-TPPTIE08-62764-1530035160705-0-11672 >>> (mnp_npg_call) log[Queue processed the message sucessfully.] --> removeHeaders[x-*] <<< Pattern:InOnly, Headers:{breadcrumbId=ID-TPPTIE08-62764-1530035160705-0-11671, firedTime=Wed Jun 27 10:58:25 PKT 2018}, BodyType:null, Body:[Body is null] 2018-06-27 10:58:26 DEBUG AbstractInactivityMonitor:150 - WriteChecker: 10019ms elapsed since last write check. 2018-06-27 10:58:26 DEBUG AbstractInactivityMonitor:150 - WriteChecker: 10019ms elapsed since last write check. 2018-06-27 10:58:26 DEBUG AbstractInactivityMonitor:192 - Running WriteCheck[tcp://10.1.4.176:61616] 2018-06-27 10:58:26 DEBUG AbstractInactivityMonitor:192 - Running WriteCheck[tcp://10.1.4.176:61616] 2018-06-27 10:58:26 DEBUG AbstractInactivityMonitor:150 - WriteChecker: 10019ms elapsed since last write check. 2018-06-27 10:58:26 DEBUG AbstractInactivityMonitor:192 - Running WriteCheck[tcp://127.0.0.1:61616] 2018-06-27 10:58:28 DEBUG AbstractInactivityMonitor:150 - WriteChecker: 10000ms elapsed since last write check. 2018-06-27 10:58:28 DEBUG AbstractInactivityMonitor:192 - Running WriteCheck[tcp://10.1.4.176:61616] 2018-06-27 10:58:28 DEBUG AbstractInactivityMonitor:150 - WriteChecker: 10000ms elapsed since last write check. 2018-06-27 10:58:28 DEBUG AbstractInactivityMonitor:192 - Running WriteCheck[tcp://10.1.4.176:61616] 2018-06-27 10:58:32 INFO Tracer:95 - ID-TPPTIE08-62764-1530035160705-2-13384 >>> (mnp_npg_call) from(timer://mnp?fixedRate=true&period=10s) --> log[Queue Timer Triggered.] <<< Pattern:InOnly, Headers:{breadcrumbId=ID-TPPTIE08-62764-1530035160705-2-13383, firedTime=Wed Jun 27 10:58:32 PKT 2018}, BodyType:null, Body:[Body is null] 2018-06-27 10:58:32 DEBUG mnp_npg_call:71 - Queue Timer Triggered. 2018-06-27 10:58:32 INFO Tracer:95 - ID-TPPTIE08-62764-1530035160705-2-13384 >>> (mnp_npg_call) log[Queue Timer Triggered.] --> jms://queue:npgToOssQueue <<< Pattern:InOnly, Headers:{breadcrumbId=ID-TPPTIE08-62764-1530035160705-2-13383, firedTime=Wed Jun 27 10:58:32 PKT 2018}, BodyType:null, Body:[Body is null] 2018-06-27 10:58:32 DEBUG SendProcessor:141 - >>>> Endpoint[jms://queue:npgToOssQueue] Exchange[ID-TPPTIE08-62764-1530035160705-2-13384][Message: [Body is null]] 2018-06-27 10:58:32 DEBUG Client:703 - Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg].connect(null) 2018-06-27 10:58:32 DEBUG MicroSocketClientInvoker:772 - SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457] setting timeout to 30000 for this invocation 2018-06-27 10:58:33 DEBUG Client:1926 - Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] connected to InvokerLocator [bisocket://10.4.9.52:4457/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&dataType=jms&failureDisconnectTimeout=0&generalizeSocketException=true&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=5&pingFrequency=30000&pingWindowFactor=71582&socket.check_connection=false&stopLeaseOnFailure=true&timeout=300000&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat&useClientConnectionIdentity=true&validatorPingPeriod=10000&validatorPingTimeout=5000] 2018-06-27 10:58:33 DEBUG Client:740 - Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] is connected 2018-06-27 10:58:33 DEBUG Client:703 - Client[2104139218:a144w-d08cd4-jivzfju7-1-jiwpljet-1lch].connect(null) 2018-06-27 10:58:33 DEBUG Client:1926 - Client[2104139218:a144w-d08cd4-jivzfju7-1-jiwpljet-1lch] connected to InvokerLocator [bisocket://10.4.9.52:4457/?JBM_clientMaxPoolSize=200&clientLeasePeriod=10000&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&dataType=jms&failureDisconnectTimeout=0&generalizeSocketException=true&marshaller=org.jboss.jms.wireformat.JMSWireFormat&numberOfCallRetries=5&pingFrequency=30000&pingWindowFactor=71582&socket.check_connection=false&stopLeaseOnFailure=true&timeout=300000&unmarshaller=org.jboss.jms.wireformat.JMSWireFormat&useClientConnectionIdentity=true&validatorPingPeriod=10000&validatorPingTimeout=5000] 2018-06-27 10:58:33 DEBUG Client:740 - Client[2104139218:a144w-d08cd4-jivzfju7-1-jiwpljet-1lch] is connected 2018-06-27 10:58:33 DEBUG Client:1233 - starting callback Connector: InvokerLocator [bisocket://10.1.4.176:1981043057/callback?callbackServerHost=10.1.4.176&callbackServerPort=1981043057&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=a144w-d08cd4-jivzfju7-1-jiwpljn8-1lcj&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper] 2018-06-27 10:58:33 DEBUG ServerInvoker:1591 - SocketServerInvoker[10.1.4.176:1981043057] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory. 2018-06-27 10:58:33 DEBUG ServerInvoker:1597 - SocketServerInvoker[10.1.4.176:1981043057] created server socket factory javax.net.DefaultServerSocketFactory@709bb6a6 2018-06-27 10:58:33 DEBUG BisocketServerInvoker:688 - SocketServerInvoker[10.1.4.176:1981043057] setting pingFrequency to 30000 2018-06-27 10:58:33 DEBUG BisocketServerInvoker:706 - SocketServerInvoker[10.1.4.176:1981043057] setting pingWindowFactor to 71582 2018-06-27 10:58:33 DEBUG Connector:390 - org.jboss.remoting.transport.Connector@2c514c98 started 2018-06-27 10:58:33 DEBUG Client:703 - Client[1280346505:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg].connect(null) 2018-06-27 10:58:33 DEBUG Client:1926 - Client[1280346505:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] connected to InvokerLocator [bisocket://10.1.4.176:1981043057/callback?callbackServerHost=10.1.4.176&callbackServerPort=1981043057&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=a144w-d08cd4-jivzfju7-1-jiwpljn8-1lcj&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper] 2018-06-27 10:58:33 DEBUG Client:740 - Client[1280346505:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] is connected 2018-06-27 10:58:33 DEBUG ServerInvoker:1845 - ServerInvoker (SocketServerInvoker[10.1.4.176:1981043057]) added client callback handler CallbackManager[7a982e19] with session id of a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg+a144w-d08cd4-jivzfju7-1-jiwpljo1-1lcl and callback handle object of null. 2018-06-27 10:58:33 DEBUG InvokerRegistry:645 - removed org.jboss.remoting.transport.local.LocalClientInvoker@657d09dc from registry 2018-06-27 10:58:33 DEBUG Client:824 - Client[1280346505:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] is disconnected 2018-06-27 10:58:33 DEBUG BisocketClientInvoker:639 - getting secondary locator 2018-06-27 10:58:33 DEBUG BisocketClientInvoker:647 - secondary locator: InvokerLocator [null://10.4.9.52:1024/?] 2018-06-27 10:58:33 DEBUG BisocketServerInvoker:384 - creating control connection: InvokerLocator [null://10.4.9.52:1024/?] 2018-06-27 10:58:33 DEBUG BisocketServerInvoker:468 - SocketServerInvoker[10.1.4.176:1981043057] created control connection (a144w-d08cd4-jivzfju7-1-jiwpljo1-1lcl): Socket[addr=/10.4.9.52,port=1024,localport=53487] 2018-06-27 10:58:33 DEBUG ConnectionValidator:303 - ConnectionValidator[17950001:null, pingPeriod=2000 ms] created 2018-06-27 10:58:33 DEBUG ConnectionValidator:424 - ConnectionValidator[17950001:null, pingPeriod=2000 ms] added ConnectionListener: ConsolidatedListener(UNINITIALIZED) for Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] 2018-06-27 10:58:33 DEBUG ConnectionValidator:781 - ConnectionValidator[17950001:null, pingPeriod=2000 ms]: pingPeriod: 2000 2018-06-27 10:58:33 DEBUG ConnectionValidator:782 - ConnectionValidator[17950001:null, pingPeriod=2000 ms]: pingTimeout: 1000 2018-06-27 10:58:33 DEBUG ConnectionValidator:783 - ConnectionValidator[17950001:null, pingPeriod=2000 ms]: ping retries: 1 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:604 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting client socket wrapper class name to org.jboss.jms.client.remoting.ClientSocketWrapper 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:613 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting shouldCheckConnection to false 2018-06-27 10:58:33 DEBUG SocketClientInvoker:105 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting timeout to 300000 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:300 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] constructed 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:604 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting client socket wrapper class name to org.jboss.jms.client.remoting.ClientSocketWrapper 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:613 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting shouldCheckConnection to false 2018-06-27 10:58:33 DEBUG SocketClientInvoker:105 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting timeout to 300000 2018-06-27 10:58:33 DEBUG BisocketClientInvoker:187 - Setting ping frequency to: 30000 2018-06-27 10:58:33 DEBUG BisocketClientInvoker:202 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] setting pingWindowFactor to 71582 2018-06-27 10:58:33 DEBUG MicroRemoteClientInvoker:305 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] connecting 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:1092 - Creating semaphore with size 50 2018-06-27 10:58:33 DEBUG MicroRemoteClientInvoker:319 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] connected 2018-06-27 10:58:33 DEBUG ConnectionValidator:828 - ConnectionValidator[17950001:SocketClientInvoker[52073375, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] started 2018-06-27 10:58:33 DEBUG JmsConfiguration$CamelJmsTemplate:492 - Executing callback on JMS Session: JBossSession->SessionDelegate[1092885082, ID=xq52-qqjlpwij-1-w747jbij-zunerk-100j3] 2018-06-27 10:58:33 DEBUG JmsConfiguration:539 - Sending JMS message to: JBossQueue[npgToOssQueue] with message: delegator->JBossMessage[0]:PERSISTENT, deliveryId=0 2018-06-27 10:58:33 DEBUG ConnectionValidator:457 - ConnectionValidator[17950001:SocketClientInvoker[52073375, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] removed ConnectionListener: ConnectionState[wq52-bqjlpwij-1-w747jbij-zunerk-100j3].ConsolidatedListener for Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] 2018-06-27 10:58:33 DEBUG InvokerRegistry:645 - removed SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] from registry 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:426 - SocketClientInvoker[52073375, bisocket://10.4.9.52:4457] disconnecting ... 2018-06-27 10:58:33 DEBUG ConnectionValidator:1034 - ConnectionValidator[17950001:SocketClientInvoker[52073375, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] stopped, returning true 2018-06-27 10:58:33 DEBUG Client:608 - Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg].removeConnectionListener() removed from static map: ConnectionValidator[17950001:SocketClientInvoker[52073375, bisocket://10.4.9.52:4457], pingPeriod=2000 ms] 2018-06-27 10:58:33 DEBUG Client:703 - Client[1222087892:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg].connect(null) 2018-06-27 10:58:33 DEBUG Client:1926 - Client[1222087892:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] connected to InvokerLocator [bisocket://10.1.4.176:1981043057/callback?callbackServerHost=10.1.4.176&callbackServerPort=1981043057&callbackServerProtocol=bisocket&clientMaxPoolSize=1&clientSocketClass=org.jboss.jms.client.remoting.ClientSocketWrapper&datatype=jms&guid=a144w-d08cd4-jivzfju7-1-jiwpljn8-1lcj&isCallbackServer=true&onewayThreadPool=org.jboss.jms.server.remoting.DirectThreadPool&serverSocketClass=org.jboss.jms.server.remoting.ServerSocketWrapper] 2018-06-27 10:58:33 DEBUG Client:740 - Client[1222087892:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] is connected 2018-06-27 10:58:33 DEBUG ServerInvoker:1854 - ServerInvoker (SocketServerInvoker[10.1.4.176:1981043057]) removing client callback handler with session id of a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg+a144w-d08cd4-jivzfju7-1-jiwpljo1-1lcl. 2018-06-27 10:58:33 DEBUG BisocketServerInvoker:484 - SocketServerInvoker[10.1.4.176:1981043057] shutting down control connection: a144w-d08cd4-jivzfju7-1-jiwpljo1-1lcl 2018-06-27 10:58:33 DEBUG InvokerRegistry:645 - removed org.jboss.remoting.transport.local.LocalClientInvoker@35c8fb04 from registry 2018-06-27 10:58:33 DEBUG Client:824 - Client[1222087892:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] is disconnected 2018-06-27 10:58:33 DEBUG SocketServerInvoker:480 - SocketServerInvoker[10.1.4.176:1981043057] stopped threads in clientpool 2018-06-27 10:58:33 DEBUG SocketServerInvoker:498 - SocketServerInvoker[10.1.4.176:1981043057] stopped threads in threadpool 2018-06-27 10:58:33 DEBUG SocketServerInvoker:503 - SocketServerInvoker[10.1.4.176:1981043057] exiting 2018-06-27 10:58:33 DEBUG ServerInvoker:1048 - SocketServerInvoker[10.1.4.176:1981043057] stopped 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:772 - SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457] setting timeout to 30000 for this invocation 2018-06-27 10:58:33 DEBUG MicroSocketClientInvoker:772 - SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457] setting timeout to 30000 for this invocation 2018-06-27 10:58:33 DEBUG InvokerRegistry:649 - decremented SocketClientInvoker[4d8f640b, bisocket://10.4.9.52:4457]'s count, current count 2 2018-06-27 10:58:33 DEBUG Client:824 - Client[1975640311:a144w-d08cd4-jivzfju7-1-jiwpljet-1lcg] is disconnected 2018-06-27 10:58:33 DEBUG InvokerRegistry:649 - decremented SocketClientInvoker[697b20d5, bisocket://10.4.9.52:4457]'s count, current count 2 2018-06-27 10:58:33 DEBUG Client:824 - Client[2104139218:a144w-d08cd4-jivzfju7-1-jiwpljet-1lch] is disconnected