[jacorb-developer] JacORB hangs in endless connection retry loop
Franz van Betteraey
fvbetteraey at web.de
Tue Jul 7 14:53:49 CEST 2015
The attached log was truncated. I try to put a reduced log version into the mail (hope to not exeed the text limit).
---
13:05:04,616 DEBUG o.j.config.JacORBConfiguration.<init>(257) - configuration jacorb loaded from classpath for ORBSingleton
13:05:13,935 DEBUG o.j.orb.ORBSingleton.configureCodeset(199) - Set default native char codeset to UTF8
13:05:13,938 DEBUG o.j.orb.ORBSingleton.<init>(115) - BufferManagerFactory: o.j.orb.DefaultBufferManagerFactory at 41abefe
13:05:13,938 DEBUG o.j.orb.ORBSingleton.<init>(116) - BufferManager: o.j.orb.BufferManager at 7e4d0096
13:05:13,938 DEBUG o.j.orb.ORBSingleton.<init>(117) - jacorb.interop.strict_check_on_tc_creation set to true
13:05:13,939 DEBUG o.j.orb.ORBSingleton.<init>(123) - OMG.ORB classloader (null) does not match JacORB ORBSingleton classloader (WebAppClassLoader=1489933928 at 58ce9668). This may cause problems; see the ProgrammingGuide for further details
13:05:13,939 DEBUG o.j.config.JacORBConfiguration.<init>(257) - configuration jacorb loaded from classpath for o.j.orb.ORB at 57fdd524
13:05:13,940 DEBUG o.j.orb.ORBSingleton.configure(160) - BufferManagerFactory: o.j.orb.DefaultBufferManagerFactory at 6330510
13:05:13,940 DEBUG o.j.orb.ORBSingleton.configure(161) - BufferManager: o.j.orb.BufferManager at 7e4d0096
13:05:13,940 DEBUG o.j.orb.ORBSingleton.configure(162) - jacorb.interop.strict_check_on_tc_creation set to true
13:05:13,940 DEBUG o.j.orb.ORBSingleton.configureCodeset(199) - Set default native char codeset to UTF8
13:05:13,945 DEBUG o.j.poa.RPPoolManagerFactory.<init>(54) - RequestProcessorPoolFactory settings: thread_pool_min=5 thread_pool_max=20 thread_pool_shared=false
13:05:13,947 INFO o.j.orb.ORB.set_parameters(1903) - Initialising ORB with ID:
13:05:13,954 DEBUG o.j.orb.factory.SocketFactoryManager.configure(111) - defaulting to DefaultSocketFactory
13:05:13,962 DEBUG o.j.orb.giop.MessageReceptorPool.<init>(66) - Maximum connection threads: 1000
13:05:13,962 DEBUG o.j.orb.giop.MessageReceptorPool.<init>(67) - Maximum idle threads: 5
13:05:13,965 DEBUG o.j.orb.ORB.initKnownReferencesMap(2160) - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
13:05:13,977 INFO o.j.orb.portableInterceptor.InterceptorManager.<init>(87) - InterceptorManager started with 0 Server Interceptors, 0 Client Interceptors and 1 IOR Interceptors
13:05:13,986 DEBUG o.j.orb.giop.TransportManager.instantiateFactories(263) - created org.omg.ETF.Factories: o.j.orb.iiop.IIOPFactories
13:05:14,071 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 5.6.7.8:0815 (40bd9738)
13:05:14,071 DEBUG o.j.util.threadpool.ThreadPool.createNewThread(192) - [0/0] creating new thread
13:05:14,073 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [0/1] removed idle thread (job scheduled)
13:05:14,074 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 5.6.7.8:0815 (40bd9738): will wait until connected
13:05:14,077 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:14,080 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:14,081 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 5.6.7.8:0815 (40bd9738): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 5972d4ba
13:05:14,082 DEBUG o.j.orb.factory.SocketFactoryManager.newSocketFactory(221) - created SocketFactory: o.j.orb.factory.DefaultSocketFactory
13:05:14,082 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 5.6.7.8:0815 with timeout=90000.
13:05:14,083 INFO o.j.orb.iiop.ClientIIOPConnection.connect(172) - Connected to 5.6.7.8:0815 from local port 58289
13:05:14,085 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 12 bytes to 5.6.7.8:0815
13:05:14,085 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 36 bytes to 5.6.7.8:0815
13:05:14,085 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 87 bytes to 5.6.7.8:0815
13:05:14,086 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (40bd9738)
13:05:14,086 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:14,087 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:14,087 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (40bd9738)
13:05:14,100 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 5.6.7.8:0815 (40bd9738)
13:05:14,100 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 5.6.7.8:0815 (40bd9738): close()
13:05:14,101 INFO o.j.orb.iiop.ClientIIOPConnection.close(397) - Client-side TCP transport to 5.6.7.8:0815 closed.
13:05:14,101 DEBUG o.j.orb.etf.StreamConnectionBase.read(136) - Transport to 5.6.7.8:0815: stream closed Socket closed
13:05:14,101 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:14,101 DEBUG o.j.util.threadpool.ThreadPool.createNewThread(192) - [0/1] creating new thread
13:05:14,101 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [0/2] removed idle thread (job scheduled)
13:05:14,101 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9): will wait until connected
13:05:14,103 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:14,104 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:14,104 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 12cdb422
13:05:14,104 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 5.6.7.8:0815 with timeout=90000.
13:05:14,105 INFO o.j.orb.iiop.ClientIIOPConnection.connect(172) - Connected to 5.6.7.8:0815 from local port 58290
13:05:14,105 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 100 bytes to 5.6.7.8:0815
13:05:14,105 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 28 bytes to 5.6.7.8:0815
13:05:14,105 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 47 bytes to 5.6.7.8:0815
13:05:14,105 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:14,105 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:14,106 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:14,106 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:14,109 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:14,109 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:14,109 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:14,110 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:14,110 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:14,110 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:14,134 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (629008db)
13:05:14,134 DEBUG o.j.util.threadpool.ThreadPool.createNewThread(192) - [0/2] creating new thread
13:05:14,134 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [0/3] removed idle thread (job scheduled)
13:05:14,134 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (629008db): will wait until connected
13:05:14,136 DEBUG o.j.orb.dii.Request._invoke(290) - DII::Request for IOR:000000000000001C49444C3A766D69732F446973706C6179536572766963653A312E3000000000010000000000000094010102000B00000031302E32302E302E3137000012270000630000003539363633343539352F7B687474703A262526257A73745F686575626F655F64657D446973706C617953657276696365434F5242415365727669636523446973706C617953657276696365434F524241506F72742F646973706C61795365727669636500010000000000000008000000000000004A414300
13:05:14,136 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:14,136 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 1.2.3.4:4711
13:05:14,136 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:14,136 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (629008db): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 716c690c
13:05:14,136 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:14,154 DEBUG o.j.orb.listener.SSLListenerUtil.processException(162) - Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed
13:05:14,155 DEBUG o.j.orb.etf.ConnectionBase.to_COMM_FAILURE(149) - to_COMM_FAILURE: Caught exception
java.net.SocketException: Socket closed
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:150)
at java.net.SocketInputStream.read(SocketInputStream.java:121)
at o.j.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:111)
at o.j.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:388)
at o.j.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:548)
at o.j.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:535)
at o.j.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69)
at o.j.util.threadpool.ConsumerTie.run(ConsumerTie.java:60)
at java.lang.Thread.run(Thread.java:745)
13:05:14,158 DEBUG o.j.orb.giop.GIOPConnection.getMessage(402) - ClientGIOPConnection to 5.6.7.8:0815 (40bd9738): getMessage() -- COMM_FAILURE
13:05:14,158 DEBUG o.j.orb.giop.ClientGIOPConnection.streamClosed(112) - ClientGIOPConnection to 5.6.7.8:0815 (40bd9738): streamClosed()
13:05:14,158 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 5.6.7.8:0815 (40bd9738): close()
13:05:14,158 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:15,147 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
at java.net.DualStackPlainSocketImpl.waitForConnect(Native Method)
at java.net.DualStackPlainSocketImpl.socketConnect(DualStackPlainSocketImpl.java:85)
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:345)
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:172)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:589)
at o.j.orb.factory.DefaultSocketFactory.doCreateSocket(DefaultSocketFactory.java:45)
at o.j.orb.factory.AbstractSocketFactory.createSocket(AbstractSocketFactory.java:60)
at o.j.orb.iiop.ClientIIOPConnection.createSocket(ClientIIOPConnection.java:317)
at o.j.orb.iiop.ClientIIOPConnection.connect(ClientIIOPConnection.java:152)
at o.j.orb.giop.GIOPConnection.sendMessage(GIOPConnection.java:1069)
at o.j.orb.giop.GIOPConnection.sendRequest(GIOPConnection.java:1014)
at o.j.orb.giop.ClientConnection.sendRequest(ClientConnection.java:309)
at o.j.orb.giop.ClientConnection.sendRequest(ClientConnection.java:290)
at o.j.orb.Delegate._invoke_internal(Delegate.java:1346)
at o.j.orb.Delegate.invoke_internal(Delegate.java:1171)
at o.j.orb.Delegate.invoke(Delegate.java:1159)
at o.j.orb.dii.Request._invoke(Request.java:316)
at o.j.orb.dii.Request.invoke(Request.java:434)
at org.apache.cxf.binding.corba.CorbaConduit.buildRequest(CorbaConduit.java:193)
at org.apache.cxf.binding.corba.CorbaConduit.close(CorbaConduit.java:143)
at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:62)
at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307)
at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:516)
at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:425)
at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:326)
at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:279)
at org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)
at org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:138)
13:05:15,147 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:15,648 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:16,659 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:16,660 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:17,160 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:18,172 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:18,173 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:18,674 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:19,685 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:19,687 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1076) - ClientGIOPConnection to 1.2.3.4:4711 (629008db): sendMessage() -- failed to open transport
13:05:19,687 DEBUG o.j.orb.Delegate._invoke_internal(1352) - invoke[-->]: SystemException
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 1.2.3.4:4711 vmcid: 0x0 minor code: 0 completed: No
at o.j.orb.iiop.ClientIIOPConnection.connect(ClientIIOPConnection.java:226)
at o.j.orb.giop.GIOPConnection.sendMessage(GIOPConnection.java:1069)
at o.j.orb.giop.GIOPConnection.sendRequest(GIOPConnection.java:1014)
at o.j.orb.giop.ClientConnection.sendRequest(ClientConnection.java:309)
at o.j.orb.giop.ClientConnection.sendRequest(ClientConnection.java:290)
at o.j.orb.Delegate._invoke_internal(Delegate.java:1346)
at o.j.orb.Delegate.invoke_internal(Delegate.java:1171)
at o.j.orb.Delegate.invoke(Delegate.java:1159)
at o.j.orb.dii.Request._invoke(Request.java:316)
at o.j.orb.dii.Request.invoke(Request.java:434)
at org.apache.cxf.binding.corba.CorbaConduit.buildRequest(CorbaConduit.java:193)
at org.apache.cxf.binding.corba.CorbaConduit.close(CorbaConduit.java:143)
at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:62)
at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307)
at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:516)
at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:425)
at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:326)
at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:279)
at org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)
at org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:138)
13:05:19,688 DEBUG o.j.orb.Delegate.try_rebind(1734) - Delegate.try_rebind: useNameService, going back to the NameService <corbaname::foobar:0815#lorem/ipsum>
13:05:19,689 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,689 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:19,689 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:19,690 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 103 bytes to 5.6.7.8:0815
13:05:19,690 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,691 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:19,691 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:19,691 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,693 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(179) - ClientConnectionManager: cannot release ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9) (still has 1 client(s))
13:05:19,693 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,693 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:19,694 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 151 bytes to 5.6.7.8:0815
13:05:19,694 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,694 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:19,695 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:19,695 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,695 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:19,696 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:19,696 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,697 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:19,697 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:19,698 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:19,698 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 1.2.3.4:4711 (629008db)
13:05:19,699 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 1.2.3.4:4711 (629008db): close()
13:05:19,699 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [2/3] job queue empty
13:05:19,699 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (1ecf5b22)
13:05:19,699 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [1/3] removed idle thread (job scheduled)
13:05:19,700 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:19,700 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:19,700 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (1ecf5b22): will wait until connected
13:05:19,700 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:19,701 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (1ecf5b22): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 23f2b5c4
13:05:19,701 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:20,702 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:20,704 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:21,204 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:22,225 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:22,226 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:22,727 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:23,728 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:23,728 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:24,230 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:25,231 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:25,232 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1076) - ClientGIOPConnection to 1.2.3.4:4711 (1ecf5b22): sendMessage() -- failed to open transport
13:05:25,233 DEBUG o.j.orb.Delegate._invoke_internal(1352) - invoke[-->]: SystemException
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 1.2.3.4:4711 vmcid: 0x0 minor code: 0 completed: No
[... see above ...]
13:05:25,233 DEBUG o.j.orb.Delegate.try_rebind(1734) - Delegate.try_rebind: useNameService, going back to the NameService <corbaname::foobar:0815#lorem/ipsum>
13:05:25,234 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,235 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:25,235 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:25,235 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 103 bytes to 5.6.7.8:0815
13:05:25,236 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,236 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:25,237 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:25,237 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,238 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(179) - ClientConnectionManager: cannot release ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9) (still has 2 client(s))
13:05:25,238 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,239 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:25,239 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 151 bytes to 5.6.7.8:0815
13:05:25,239 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,260 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:25,260 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:25,260 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,261 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:25,261 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:25,261 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,262 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:25,262 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:25,262 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:25,263 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 1.2.3.4:4711 (1ecf5b22)
13:05:25,263 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 1.2.3.4:4711 (1ecf5b22): close()
13:05:25,263 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [2/3] job queue empty
13:05:25,263 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (634172ed)
13:05:25,263 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [1/3] removed idle thread (job scheduled)
13:05:25,263 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:25,263 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:25,263 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (634172ed): will wait until connected
13:05:25,263 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:25,264 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (634172ed): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 6ac862ec
13:05:25,264 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:26,264 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:26,265 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:26,766 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:27,775 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:27,776 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:28,276 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:29,286 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:29,287 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:29,787 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:30,788 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:30,789 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1076) - ClientGIOPConnection to 1.2.3.4:4711 (634172ed): sendMessage() -- failed to open transport
13:05:30,790 DEBUG o.j.orb.Delegate._invoke_internal(1352) - invoke[-->]: SystemException
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 1.2.3.4:4711 vmcid: 0x0 minor code: 0 completed: No
[... see above ...]
13:05:30,790 DEBUG o.j.orb.Delegate.try_rebind(1734) - Delegate.try_rebind: useNameService, going back to the NameService <corbaname::foobar:0815#lorem/ipsum>
13:05:30,791 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,792 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:30,792 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:30,792 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 103 bytes to 5.6.7.8:0815
13:05:30,793 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,793 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:30,794 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:30,794 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,795 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(179) - ClientConnectionManager: cannot release ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9) (still has 3 client(s))
13:05:30,795 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,796 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:30,796 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 151 bytes to 5.6.7.8:0815
13:05:30,796 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,797 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:30,797 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:30,797 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,797 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:30,798 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:30,798 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,800 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:30,800 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:30,800 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:30,801 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 1.2.3.4:4711 (634172ed)
13:05:30,801 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 1.2.3.4:4711 (634172ed): close()
13:05:30,801 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [2/3] job queue empty
13:05:30,801 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (5b071170)
13:05:30,802 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [1/3] removed idle thread (job scheduled)
13:05:30,802 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:30,802 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (5b071170): will wait until connected
13:05:30,802 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:30,802 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:30,803 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (5b071170): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 7b218a49
13:05:30,803 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:31,814 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:31,815 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:32,315 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:33,316 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:33,317 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:33,817 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:34,818 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:34,819 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:35,319 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:36,325 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:36,326 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1076) - ClientGIOPConnection to 1.2.3.4:4711 (5b071170): sendMessage() -- failed to open transport
13:05:36,327 DEBUG o.j.orb.Delegate._invoke_internal(1352) - invoke[-->]: SystemException
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 1.2.3.4:4711 vmcid: 0x0 minor code: 0 completed: No
[... see above ...]
13:05:36,327 DEBUG o.j.orb.Delegate.try_rebind(1734) - Delegate.try_rebind: useNameService, going back to the NameService <corbaname::foobar:0815#lorem/ipsum>
13:05:36,328 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,328 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:36,329 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:36,329 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 103 bytes to 5.6.7.8:0815
13:05:36,329 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,331 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:36,331 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:36,331 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,332 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(179) - ClientConnectionManager: cannot release ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9) (still has 4 client(s))
13:05:36,332 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,333 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:36,333 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 151 bytes to 5.6.7.8:0815
13:05:36,334 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,334 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:36,334 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:36,334 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,335 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:36,335 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:36,336 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,337 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:36,337 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:36,337 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:36,338 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 1.2.3.4:4711 (5b071170)
13:05:36,338 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 1.2.3.4:4711 (5b071170): close()
13:05:36,338 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [2/3] job queue empty
13:05:36,338 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (2c704e65)
13:05:36,339 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [1/3] removed idle thread (job scheduled)
13:05:36,339 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:36,339 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (2c704e65): will wait until connected
13:05:36,339 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:36,339 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:36,340 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (2c704e65): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 5e2d5407
13:05:36,340 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:37,341 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
at org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:138)
13:05:37,342 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:37,842 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:38,843 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:38,844 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:39,344 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:40,338 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:40,339 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:40,839 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:41,840 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:41,842 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1076) - ClientGIOPConnection to 1.2.3.4:4711 (2c704e65): sendMessage() -- failed to open transport
13:05:41,842 DEBUG o.j.orb.Delegate._invoke_internal(1352) - invoke[-->]: SystemException
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 1.2.3.4:4711 vmcid: 0x0 minor code: 0 completed: No
[... see above ...]
13:05:41,842 DEBUG o.j.orb.Delegate.try_rebind(1734) - Delegate.try_rebind: useNameService, going back to the NameService <corbaname::foobar:0815#lorem/ipsum>
13:05:41,843 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,844 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:41,844 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:41,844 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 103 bytes to 5.6.7.8:0815
13:05:41,844 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,845 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:41,845 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:41,846 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,846 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(179) - ClientConnectionManager: cannot release ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9) (still has 5 client(s))
13:05:41,847 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,847 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:41,847 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 151 bytes to 5.6.7.8:0815
13:05:41,848 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,848 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:41,848 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:41,848 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,849 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:41,849 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:41,849 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,850 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:41,851 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:41,851 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:41,851 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 1.2.3.4:4711 (2c704e65)
13:05:41,852 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 1.2.3.4:4711 (2c704e65): close()
13:05:41,852 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [2/3] job queue empty
13:05:41,852 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (584bf545)
13:05:41,852 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [1/3] removed idle thread (job scheduled)
13:05:41,852 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:41,852 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:41,852 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (584bf545): will wait until connected
13:05:41,853 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:41,853 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (584bf545): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 5614cff1
13:05:41,854 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:42,854 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:42,855 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:43,355 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:44,379 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:44,380 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:44,880 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:45,886 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:45,887 INFO o.j.orb.iiop.ClientIIOPConnection.connect(197) - Retrying to connect to 1.2.3.4:4711
13:05:46,387 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
13:05:47,388 DEBUG o.j.orb.iiop.ClientIIOPConnection.connect(188) - Exception
java.net.ConnectException: Connection refused: connect
[... see above ...]
13:05:47,389 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1076) - ClientGIOPConnection to 1.2.3.4:4711 (584bf545): sendMessage() -- failed to open transport
13:05:47,390 DEBUG o.j.orb.Delegate._invoke_internal(1352) - invoke[-->]: SystemException
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 1.2.3.4:4711 vmcid: 0x0 minor code: 0 completed: No
[... see above ...]
13:05:47,390 DEBUG o.j.orb.Delegate.try_rebind(1734) - Delegate.try_rebind: useNameService, going back to the NameService <corbaname::foobar:0815#lorem/ipsum>
13:05:47,392 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,392 DEBUG o.j.orb.Delegate.getReplyGroup(1606) - Adding new retry group for 5.6.7.8:0815
13:05:47,392 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:47,393 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 103 bytes to 5.6.7.8:0815
13:05:47,393 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 103 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,394 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:47,394 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 164 bytes from 5.6.7.8:0815
13:05:47,394 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 176 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,395 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(179) - ClientConnectionManager: cannot release ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9) (still has 6 client(s))
13:05:47,396 INFO o.j.orb.giop.ClientConnectionManager.getConnection(148) - ClientConnectionManager: found ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,396 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:47,396 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 151 bytes to 5.6.7.8:0815
13:05:47,397 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 151 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,397 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:47,397 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 13 bytes from 5.6.7.8:0815
13:05:47,397 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 25 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,398 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:47,398 DEBUG o.j.orb.etf.StreamConnectionBase.write(197) - wrote 140 bytes to 5.6.7.8:0815
13:05:47,398 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1092) - wrote GIOP message of size 140 to ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,400 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 12 bytes from 5.6.7.8:0815
13:05:47,400 DEBUG o.j.orb.etf.StreamConnectionBase.read(159) - read 204 bytes from 5.6.7.8:0815
13:05:47,400 DEBUG o.j.orb.giop.GIOPConnection.getMessage(500) - read GIOP message of size 216 from ClientGIOPConnection to 5.6.7.8:0815 (19aeaea9)
13:05:47,401 DEBUG o.j.orb.giop.ClientConnectionManager.releaseConnection(167) - ClientConnectionManager: releasing ClientGIOPConnection to 1.2.3.4:4711 (584bf545)
13:05:47,401 DEBUG o.j.orb.giop.GIOPConnection.close(1157) - ClientGIOPConnection to 1.2.3.4:4711 (584bf545): close()
13:05:47,401 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [2/3] job queue empty
13:05:47,401 INFO o.j.orb.giop.ClientConnectionManager.getConnection(137) - ClientConnectionManager: created new ClientGIOPConnection to 1.2.3.4:4711 (1b527261)
13:05:47,401 DEBUG o.j.util.threadpool.ThreadPool.getJob(131) - [1/3] removed idle thread (job scheduled)
13:05:47,402 DEBUG o.j.orb.giop.GIOPConnection.waitUntilConnected(336) - ClientGIOPConnection to 1.2.3.4:4711 (1b527261): will wait until connected
13:05:47,402 DEBUG o.j.orb.giop.ClientConnection.setCodeSet(173) - No CodeSetComponentInfo in IOR. Will use default CodeSets
13:05:47,402 DEBUG o.j.util.threadpool.ThreadPool.getJob(111) - [1/3] job queue empty
13:05:47,402 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1042) - GIOPConnection.sendMessage timeout (millis): 0
13:05:47,402 DEBUG o.j.orb.giop.GIOPConnection.sendMessage(1058) - ClientGIOPConnection to 1.2.3.4:4711 (1b527261): sendMessage() - opening transport o.j.orb.iiop.ClientIIOPConnection at 5fdbe5c2
13:05:47,403 DEBUG o.j.orb.iiop.ClientIIOPConnection.createSocket(306) - Trying to connect to 1.2.3.4:4711 with timeout=90000.
More information about the jacorb-developer
mailing list