Re: JacORB hangs in endless connection retry loop
"Franz van Betteraey" <[email protected]> Tue, 7 Jul 2015 14:53:49 +0200
| Newsgroups | gmane.comp.corba.jacorb.devel |
|---|---|
| Message-ID | <trinity-854ffbf2-fecd-40e3-8ffe-566643adc9b6-1436273629905@3capp-webde-bs28> |
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@41abefe 13:05:13,938 DEBUG o.j.orb.ORBSingleton.<init>(116) - BufferManager: o.j.orb.BufferManager@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@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@57fdd524 13:05:13,940 DEBUG o.j.orb.ORBSingleton.configure(160) - BufferManagerFactory: o.j.orb.DefaultBufferManagerFactory@6330510 13:05:13,940 DEBUG o.j.orb.ORBSingleton.configure(161) - BufferManager: o.j.orb.BufferManager@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@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@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@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@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@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@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@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@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@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. _______________________________________________ jacorb-developer maillist - [email protected] https://lists.spline.inf.fu-berlin.de/mailman/listinfo/jacorb-developer