Run options: # Running tests: ##### running test Multi_Threading/Simple FINE no properties found for configuration jacorb FINE BufferManagerFactory: org.jacorb.orb.DefaultBufferManagerFactory@2f686d1f FINE BufferManager: org.jacorb.orb.BufferManager@3fee9989 FINE jacorb.interop.strict_check_on_tc_creation set to true FINE OMG.ORB classloader (null) does not match JacORB ORBSingleton classloader (org.jruby.util.JRubyClassLoader@4e50df2e). This may cause problems; see the ProgrammingGuide for further details FINE no properties found for configuration jacorb FINE BufferManagerFactory: org.jacorb.orb.DefaultBufferManagerFactory@73ad2d6 FINE BufferManager: org.jacorb.orb.BufferManager@7085bdee FINE jacorb.interop.strict_check_on_tc_creation set to true FINE OMG.ORB classloader (null) does not match JacORB ORBSingleton classloader (org.jruby.util.JRubyClassLoader@4e50df2e). This may cause problems; see the ProgrammingGuide for further details FINE BufferManagerFactory: org.jacorb.orb.DefaultBufferManagerFactory@5a1c0542 FINE BufferManager: org.jacorb.orb.BufferManager@3fee9989 FINE jacorb.interop.strict_check_on_tc_creation set to true FINE RequestProcessorPoolFactory settings: thread_pool_min=5 thread_pool_max=20 thread_pool_shared=false INFO Initialising ORB with ID: myORB FINE adding attribute ORBDebugLevel=0 FINE defaulting to DefaultSocketFactory FINE Maximum connection threads: 1000 FINE Maximum idle threads: 5 FINE Property "jacorb.hashtable_class" is set to: java.util.HashMap FINE created org.omg.ETF.Factories: org.jacorb.orb.iiop.IIOPFactories FINE POA RootPOA ready FINE Maximum connection threads: 1000 FINE Maximum idle threads: 5 FINE waiting for queue FINE created ServerSocketFactory: org.jacorb.orb.factory.DefaultServerSocketFactory FINE Created socket listener on 0.0.0.0/0.0.0.0:51766 FINE primaryIP is <10.4.0.119> FINE components.addComponent: adding addrHostAddress <2001:982:a072:1:8c7b:b86e:7f95:36f3%em1> as TAG_ALTERNATE_IIOP_ADDRESS FINE TCS set to UTF8 FINE components.addComponent: adding addrHostAddress <2001:982:a072:1:edf8:ca17:aa89:d8d0%em1> as TAG_ALTERNATE_IIOP_ADDRESS FINE components.addComponent: adding addrHostAddress <2001:982:a072:1:d267:e5ff:fe49:1034%em1> as TAG_ALTERNATE_IIOP_ADDRESS FINE components.addComponent: adding addrHostAddress <2001:982:a072:1:e966:24cd:bec8:fc7a%em1> as TAG_ALTERNATE_IIOP_ADDRESS FINE components.addComponent: adding addrHostAddress <2001:982:a072:1:a89b:d496:f1a2:6a73%em1> as TAG_ALTERNATE_IIOP_ADDRESS FINE LoopbackAcceptor creates loopbackAddress using: <127.0.0.1:51766> FINE reset a previous completion call INFO oid: 00 01 0F 1F 42 07 10 0F 4C 4B 10 06 30 46 38 14 14 1B 48 4C ....B...LK..0F8...HL1B .object is activated INFO Using server ID (49758518) for transient POA INFO ORB run FINE no properties found for configuration jacorb FINE BufferManagerFactory: org.jacorb.orb.DefaultBufferManagerFactory@3fee9989 FINE BufferManager: org.jacorb.orb.BufferManager@73ad2d6 FINE jacorb.interop.strict_check_on_tc_creation set to true FINE OMG.ORB classloader (null) does not match JacORB ORBSingleton classloader (org.jruby.util.JRubyClassLoader@4e50df2e). This may cause problems; see the ProgrammingGuide for further details FINE no properties found for configuration jacorb FINE BufferManagerFactory: org.jacorb.orb.DefaultBufferManagerFactory@7085bdee FINE BufferManager: org.jacorb.orb.BufferManager@1ce92674 FINE jacorb.interop.strict_check_on_tc_creation set to true FINE OMG.ORB classloader (null) does not match JacORB ORBSingleton classloader (org.jruby.util.JRubyClassLoader@4e50df2e). This may cause problems; see the ProgrammingGuide for further details FINE BufferManagerFactory: org.jacorb.orb.DefaultBufferManagerFactory@2b552920 FINE BufferManager: org.jacorb.orb.BufferManager@73ad2d6 FINE jacorb.interop.strict_check_on_tc_creation set to true FINE RequestProcessorPoolFactory settings: thread_pool_min=5 thread_pool_max=20 thread_pool_shared=false INFO Initialising ORB with ID: myORB FINE adding attribute ORBDebugLevel=0 FINE defaulting to DefaultSocketFactory FINE Maximum connection threads: 1000 FINE Maximum idle threads: 5 FINE Property "jacorb.hashtable_class" is set to: java.util.HashMap FINE Trying to resolve URL/IOR from: file://server.ior FINE TCS set to UTF8 FINE created org.omg.ETF.Factories: org.jacorb.orb.iiop.IIOPFactories INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (6de54f69) FINE [0/0] creating new thread FINE [0/1] removed idle thread (job scheduled) FINE ClientGIOPConnection to 10.4.0.119:51766 (6de54f69): will wait until connected FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE Adding new retry group for 10.4.0.119:51766 FINE Adding new retry group for 10.4.0.119:51766 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (6de54f69): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@4910e3f6 FINE created SocketFactory: org.jacorb.orb.factory.DefaultSocketFactory FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46214 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46214 FINE wrote 76 bytes to 10.4.0.119:51766 FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (6de54f69) FINE wrote 84 bytes to 10.4.0.119:51766 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (6de54f69) FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5) FINE [0/0] creating new thread FINE [0/1] removed idle thread (job scheduled) FINE read 12 bytes from 10.4.0.119:46214 FINE read 96 bytes from 10.4.0.119:46214 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE read 12 bytes from 10.4.0.119:46214 FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE read 72 bytes from 10.4.0.119:46214 FINE read GIOP message of size 84 from ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5) FINE POA RootPOA rid: 2 opname: get_string _invoke: queuing request FINE rid: 2 opname: get_string is queued (queue size: 2) FINE rid: 0 opname: get_string starts with request processing FINE rid: 2 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 2 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE rid: 2 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46214 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5) FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE rid: 2 opname: get_string ends with request processing FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (6de54f69) FINE wrote 81 bytes to 10.4.0.119:46214 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5) FINE rid: 0 opname: get_string ends with request processing FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (6de54f69) FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (6de54f69) FINE ClientGIOPConnection to 10.4.0.119:51766 (6de54f69): close() FINE Transport to 10.4.0.119:46214: stream closed on read < 0 INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE Transport to 10.4.0.119:51766: stream closed Socket closed FINE ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5): streamClosed() FINE Delegate released! FINE ServerGIOPConnection to 10.4.0.119:46214 from [10.4.0.119:51766] (3c3a20b5): close() INFO Closed server-side transport to 10.4.0.119:46214 FINE [1/1] job queue empty FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed [thread #1] string returned <[server #] Hello there thread #1!>[thread #0] string returned <[server #] Hello there thread #0!> FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (70202af1) FINE [0/1] creating new thread FINE ClientGIOPConnection to 10.4.0.119:51766 (6de54f69): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (6de54f69): streamClosed() FINE ClientGIOPConnection to 10.4.0.119:51766 (6de54f69): close() FINE [0/2] removed idle thread (job scheduled) FINE ClientGIOPConnection to 10.4.0.119:51766 (70202af1): will wait until connected FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (70202af1): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@fe48624 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46215 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46215 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b) FINE wrote 76 bytes to 10.4.0.119:51766 FINE [0/1] removed idle thread (job scheduled) FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (70202af1) FINE read 12 bytes from 10.4.0.119:46215 FINE read 96 bytes from 10.4.0.119:46215 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE wrote 84 bytes to 10.4.0.119:51766 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (70202af1) FINE read 12 bytes from 10.4.0.119:46215 FINE read 72 bytes from 10.4.0.119:46215 FINE read GIOP message of size 84 from ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b) FINE POA RootPOA rid: 2 opname: get_string _invoke: queuing request FINE rid: 2 opname: get_string is queued (queue size: 1) FINE rid: 2 opname: get_string trying to get a RequestProcessor FINE ServerRequest: reply to get_string FINE waiting for queue FINE GIOPConnection.sendMessage timeout (millis): 0 FINE rid: 2 opname: get_string starts with request processing FINE wrote 81 bytes to 10.4.0.119:46215 FINE rid: 2 opname: get_string invoke operation on servant (dsi based) FINE read 12 bytes from 10.4.0.119:51766 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b) FINE rid: 0 opname: get_string ends with request processing FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46215 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b) FINE rid: 2 opname: get_string ends with request processing FINE read 69 bytes from 10.4.0.119:51766 FINE [1/2] job queue empty FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (70202af1) FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (70202af1) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (70202af1) FINE ClientGIOPConnection to 10.4.0.119:51766 (70202af1): close() INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE Transport to 10.4.0.119:51766: stream closed Socket closed FINE Delegate released! FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) FINE ClientGIOPConnection to 10.4.0.119:51766 (70202af1): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (70202af1): streamClosed() FINE Transport to 10.4.0.119:46215: stream closed on read < 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (70202af1): close() FINE [2/2] job queue empty FINE ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b): streamClosed() FINE ServerGIOPConnection to 10.4.0.119:46215 from [10.4.0.119:51766] (5aa7bd1b): close() [thread #0] string returned <[server #] Hello there thread #0!> INFO Closed server-side transport to 10.4.0.119:46215 FINE [1/1] job queue empty INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (b56921a) [thread #1] string returned <[server #] Hello there thread #1!>FINE [1/2] removed idle thread (job scheduled) FINE [1/2] job queue empty FINE ClientGIOPConnection to 10.4.0.119:51766 (b56921a): will wait until connected FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (b56921a): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@57752ee2 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46216 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46216 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752) FINE wrote 84 bytes to 10.4.0.119:51766 FINE [0/1] removed idle thread (job scheduled) FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (b56921a) FINE wrote 84 bytes to 10.4.0.119:51766 FINE read 12 bytes from 10.4.0.119:46216 FINE read 72 bytes from 10.4.0.119:46216 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (b56921a) FINE read GIOP message of size 84 from ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752) FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE read 12 bytes from 10.4.0.119:46216 FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE read 72 bytes from 10.4.0.119:46216 FINE read GIOP message of size 84 from ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752) FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE POA RootPOA rid: 2 opname: get_string _invoke: queuing request FINE rid: 2 opname: get_string is queued (queue size: 1) FINE rid: 2 opname: get_string trying to get a RequestProcessor FINE rid: 2 opname: get_string starts with request processing FINE waiting for queue FINE rid: 2 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46216 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752) FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (b56921a) FINE rid: 2 opname: get_string ends with request processing FINE wrote 81 bytes to 10.4.0.119:46216 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752) FINE rid: 0 opname: get_string ends with request processing FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (b56921a) FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE ClientGIOPConnection to 10.4.0.119:51766 (b56921a): close() FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (b56921a) FINE Transport to 10.4.0.119:46216: stream closed on read < 0 INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752): streamClosed() FINE Delegate released! FINE ServerGIOPConnection to 10.4.0.119:46216 from [10.4.0.119:51766] (3623a752): close() FINE Transport to 10.4.0.119:51766: stream closed Socket closed FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) [thread #0] string returned <[server #] Hello there thread #0!> FINE ClientGIOPConnection to 10.4.0.119:51766 (b56921a): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (b56921a): streamClosed() INFO Closed server-side transport to 10.4.0.119:46216 FINE ClientGIOPConnection to 10.4.0.119:51766 (b56921a): close() FINE [1/1] job queue empty FINE [2/2] job queue empty [thread #1] string returned <[server #] Hello there thread #1!> INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (531b9020) FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (531b9020): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@636046b0 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46217 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46217 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17) FINE wrote 76 bytes to 10.4.0.119:51766 FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE [0/1] removed idle thread (job scheduled) FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (531b9020) FINE read 12 bytes from 10.4.0.119:46217 FINE read 96 bytes from 10.4.0.119:46217 FINE [1/2] removed idle thread (job scheduled) FINE wrote 84 bytes to 10.4.0.119:51766 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17) FINE [1/2] job queue empty FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (531b9020) FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE read 12 bytes from 10.4.0.119:46217 FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE read 72 bytes from 10.4.0.119:46217 FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE read GIOP message of size 84 from ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17) FINE POA RootPOA rid: 2 opname: get_string _invoke: queuing request FINE rid: 2 opname: get_string is queued (queue size: 1) FINE rid: 2 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 2 opname: get_string starts with request processing FINE rid: 2 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46217 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17) FINE read 12 bytes from 10.4.0.119:51766 FINE rid: 0 opname: get_string ends with request processing FINE read 69 bytes from 10.4.0.119:51766 FINE ServerRequest: reply to get_string FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (531b9020) FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (531b9020) FINE ClientGIOPConnection to 10.4.0.119:51766 (531b9020): close() FINE wrote 81 bytes to 10.4.0.119:46217 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17) FINE rid: 2 opname: get_string ends with request processing WARNING Abnormal connection termination. Lost 1 outstanding replie(s)! FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE Transport to 10.4.0.119:46217: stream closed on read < 0 FINE ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17): streamClosed() FINE ServerGIOPConnection to 10.4.0.119:46217 from [10.4.0.119:51766] (bc00c17): close() FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (531b9020) FINE invoke[<--]: SystemException org.omg.CORBA.COMM_FAILURE: vmcid: 0x0 minor code: 0 completed: Maybe at org.jacorb.orb.giop.ReplyPlaceholder.getInputStream(ReplyPlaceholder.java:127) at org.jacorb.orb.ReplyReceiver.getReply(ReplyReceiver.java:386) at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1479) at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1248) at org.jacorb.orb.Delegate.invoke(Delegate.java:1236) at org.jacorb.orb.dii.Request._invoke(Request.java:316) at org.jacorb.orb.dii.Request.invoke(Request.java:435) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at org.jruby.javasupport.JavaMethod.invokeDirectWithExceptionHandling(JavaMethod.java:440) at org.jruby.javasupport.JavaMethod.invokeDirect(JavaMethod.java:304) at org.jruby.java.invokers.InstanceMethodInvoker.call(InstanceMethodInvoker.java:52) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:134) at org.jruby.ast.CallNoArgNode.interpret(CallNoArgNode.java:60) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.RescueNode.executeBody(RescueNode.java:221) at org.jruby.ast.RescueNode.interpret(RescueNode.java:116) at org.jruby.ast.BeginNode.interpret(BeginNode.java:83) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.evaluator.ASTInterpreter.INTERPRET_METHOD(ASTInterpreter.java:74) at org.jruby.internal.runtime.methods.InterpretedMethod.call(InterpretedMethod.java:139) at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:187) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:134) at org.jruby.ast.CallNoArgNode.interpret(CallNoArgNode.java:60) at org.jruby.ast.ReturnNode.interpret(ReturnNode.java:92) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.ast.IfNode.interpret(IfNode.java:116) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.evaluator.ASTInterpreter.INTERPRET_METHOD(ASTInterpreter.java:74) at org.jruby.internal.runtime.methods.InterpretedMethod.call(InterpretedMethod.java:225) at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:219) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:202) at org.jruby.ast.CallTwoArgNode.interpret(CallTwoArgNode.java:59) at org.jruby.ast.LocalAsgnNode.interpret(LocalAsgnNode.java:123) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.evaluator.ASTInterpreter.INTERPRET_METHOD(ASTInterpreter.java:74) at org.jruby.internal.runtime.methods.InterpretedMethod.call(InterpretedMethod.java:182) at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:203) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) at client.block_7$RUBY$__file__(client.rb:65) at client$block_7$RUBY$__file__.call(client$block_7$RUBY$__file__) at org.jruby.runtime.CompiledBlock19.yieldSpecificInternal(CompiledBlock19.java:117) at org.jruby.runtime.CompiledBlock19.yieldSpecific(CompiledBlock19.java:92) at org.jruby.runtime.Block.yieldSpecific(Block.java:111) at org.jruby.RubyFixnum.times(RubyFixnum.java:275) at org.jruby.RubyFixnum$INVOKER$i$0$0$times.call(RubyFixnum$INVOKER$i$0$0$times.gen) at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:316) at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:145) at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:154) at client.block_6$RUBY$__file__(client.rb:64) at client$block_6$RUBY$__file__.call(client$block_6$RUBY$__file__) at org.jruby.runtime.CompiledBlock19.yield(CompiledBlock19.java:159) at org.jruby.runtime.CompiledBlock19.call(CompiledBlock19.java:87) at org.jruby.runtime.Block.call(Block.java:101) at org.jruby.RubyProc.call(RubyProc.java:290) at org.jruby.RubyProc.call(RubyProc.java:228) at org.jruby.internal.runtime.RubyRunnable.run(RubyRunnable.java:99) at java.lang.Thread.run(Thread.java:745) INFO Closed server-side transport to 10.4.0.119:46217 WARNING Received reply for unknown request id: 2 FINE [1/1] job queue empty INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE Delegate released! FINE DII Request caught Exception org.omg.CORBA.COMM_FAILURE: vmcid: 0x0 minor code: 0 completed: Maybe at org.jacorb.orb.giop.ReplyPlaceholder.getInputStream(ReplyPlaceholder.java:127) at org.jacorb.orb.ReplyReceiver.getReply(ReplyReceiver.java:386) at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1479) at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1248) at org.jacorb.orb.Delegate.invoke(Delegate.java:1236) at org.jacorb.orb.dii.Request._invoke(Request.java:316) at org.jacorb.orb.dii.Request.invoke(Request.java:435) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at org.jruby.javasupport.JavaMethod.invokeDirectWithExceptionHandling(JavaMethod.java:440) at org.jruby.javasupport.JavaMethod.invokeDirect(JavaMethod.java:304) at org.jruby.java.invokers.InstanceMethodInvoker.call(InstanceMethodInvoker.java:52) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:134) at org.jruby.ast.CallNoArgNode.interpret(CallNoArgNode.java:60) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.RescueNode.executeBody(RescueNode.java:221) at org.jruby.ast.RescueNode.interpret(RescueNode.java:116) at org.jruby.ast.BeginNode.interpret(BeginNode.java:83) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.evaluator.ASTInterpreter.INTERPRET_METHOD(ASTInterpreter.java:74) at org.jruby.internal.runtime.methods.InterpretedMethod.call(InterpretedMethod.java:139) at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:187) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:134) at org.jruby.ast.CallNoArgNode.interpret(CallNoArgNode.java:60) at org.jruby.ast.ReturnNode.interpret(ReturnNode.java:92) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.ast.IfNode.interpret(IfNode.java:116) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.evaluator.ASTInterpreter.INTERPRET_METHOD(ASTInterpreter.java:74) at org.jruby.internal.runtime.methods.InterpretedMethod.call(InterpretedMethod.java:225) at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:219) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:202) at org.jruby.ast.CallTwoArgNode.interpret(CallTwoArgNode.java:59) at org.jruby.ast.LocalAsgnNode.interpret(LocalAsgnNode.java:123) at org.jruby.ast.NewlineNode.interpret(NewlineNode.java:105) at org.jruby.ast.BlockNode.interpret(BlockNode.java:71) at org.jruby.evaluator.ASTInterpreter.INTERPRET_METHOD(ASTInterpreter.java:74) at org.jruby.internal.runtime.methods.InterpretedMethod.call(InterpretedMethod.java:182) at org.jruby.internal.runtime.methods.DefaultMethod.call(DefaultMethod.java:203) at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:168) at client.block_7$RUBY$__file__(client.rb:65) at client$block_7$RUBY$__file__.call(client$block_7$RUBY$__file__) at org.jruby.runtime.CompiledBlock19.yieldSpecificInternal(CompiledBlock19.java:117) at org.jruby.runtime.CompiledBlock19.yieldSpecific(CompiledBlock19.java:92) at org.jruby.runtime.Block.yieldSpecific(Block.java:111) at org.jruby.RubyFixnum.times(RubyFixnum.java:275) at org.jruby.RubyFixnum$INVOKER$i$0$0$times.call(RubyFixnum$INVOKER$i$0$0$times.gen) at org.jruby.runtime.callsite.CachingCallSite.cacheAndCall(CachingCallSite.java:316) at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:145) at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:154) at client.block_6$RUBY$__file__(client.rb:64) at client$block_6$RUBY$__file__.call(client$block_6$RUBY$__file__) at org.jruby.runtime.CompiledBlock19.yield(CompiledBlock19.java:159) at org.jruby.runtime.CompiledBlock19.call(CompiledBlock19.java:87) at org.jruby.runtime.Block.call(Block.java:101) at org.jruby.RubyProc.call(RubyProc.java:290) at org.jruby.RubyProc.call(RubyProc.java:228) at org.jruby.internal.runtime.RubyRunnable.run(RubyRunnable.java:99) at java.lang.Thread.run(Thread.java:745) [thread #0] string returned <[server #] Hello there thread #0!> FINE [2/2] job queue empty INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4) FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@20315507 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46218 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46218 FINE wrote 76 bytes to 10.4.0.119:51766 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46218 from [10.4.0.119:51766] (4ea2c406) FINE wrote 28 bytes to 10.4.0.119:51766 FINE [0/1] removed idle thread (job scheduled) FINE wrote 4 bytes to 10.4.0.119:51766 FINE read 12 bytes from 10.4.0.119:46218 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4) FINE [1/2] removed idle thread (job scheduled) FINE read 96 bytes from 10.4.0.119:46218 FINE [1/2] job queue empty FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46218 from [10.4.0.119:51766] (4ea2c406) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46218 FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4) FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46218 from [10.4.0.119:51766] (4ea2c406) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4) FINE rid: 0 opname: get_string ends with request processing FINE ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4): close() FINE Transport to 10.4.0.119:46218: stream closed on read < 0 INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE Transport to 10.4.0.119:51766: stream closed Socket closed FINE ServerGIOPConnection to 10.4.0.119:46218 from [10.4.0.119:51766] (4ea2c406): getMessage() -- COMM_FAILURE FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE Delegate released! FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) FINE ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4): streamClosed() FINE ClientGIOPConnection to 10.4.0.119:51766 (642dcbb4): close() FINE ServerGIOPConnection to 10.4.0.119:46218 from [10.4.0.119:51766] (4ea2c406): streamClosed() FINE [2/2] job queue empty FINE ServerGIOPConnection to 10.4.0.119:46218 from [10.4.0.119:51766] (4ea2c406): close() [thread #0] string returned <[server #] Hello there thread #0!> INFO Closed server-side transport to 10.4.0.119:46218 FINE [1/1] job queue empty INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d) FINE [1/2] removed idle thread (job scheduled) FINE ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d): will wait until connected FINE [1/2] job queue empty FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@1022b5e1 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46219 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46219 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46219 from [10.4.0.119:51766] (298af7ac) FINE wrote 76 bytes to 10.4.0.119:51766 FINE [0/1] removed idle thread (job scheduled) FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d) FINE read 12 bytes from 10.4.0.119:46219 FINE read 96 bytes from 10.4.0.119:46219 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46219 from [10.4.0.119:51766] (298af7ac) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46219 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46219 from [10.4.0.119:51766] (298af7ac) FINE rid: 0 opname: get_string ends with request processing FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d) FINE ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d): close() FINE Transport to 10.4.0.119:46219: stream closed on read < 0 INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE ServerGIOPConnection to 10.4.0.119:46219 from [10.4.0.119:51766] (298af7ac): getMessage() -- COMM_FAILURE FINE Delegate released! FINE ServerGIOPConnection to 10.4.0.119:46219 from [10.4.0.119:51766] (298af7ac): streamClosed() FINE ServerGIOPConnection to 10.4.0.119:46219 from [10.4.0.119:51766] (298af7ac): close() FINE Transport to 10.4.0.119:51766: stream closed Socket closed [thread #0] string returned <[server #] Hello there thread #0!>INFO Closed server-side transport to 10.4.0.119:46219 FINE [1/1] job queue empty FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (4357956) FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) FINE [0/2] removed idle thread (job scheduled) FINE ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (4357956): will wait until connected FINE ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d): streamClosed() FINE ClientGIOPConnection to 10.4.0.119:51766 (2a48d61d): close() FINE [1/2] job queue empty FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (4357956): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@40b24f57 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46220 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46220 FINE wrote 76 bytes to 10.4.0.119:51766 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46220 from [10.4.0.119:51766] (238ccf8) FINE [0/1] removed idle thread (job scheduled) FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE read 12 bytes from 10.4.0.119:46220 FINE read 96 bytes from 10.4.0.119:46220 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (4357956) FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46220 from [10.4.0.119:51766] (238ccf8) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46220 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46220 from [10.4.0.119:51766] (238ccf8) FINE rid: 0 opname: get_string ends with request processing FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (4357956) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (4357956) FINE ClientGIOPConnection to 10.4.0.119:51766 (4357956): close() FINE Transport to 10.4.0.119:46220: stream closed on read < 0 FINE ServerGIOPConnection to 10.4.0.119:46220 from [10.4.0.119:51766] (238ccf8): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46220 from [10.4.0.119:51766] (238ccf8): streamClosed() FINE ServerGIOPConnection to 10.4.0.119:46220 from [10.4.0.119:51766] (238ccf8): close() INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE Delegate released! INFO Closed server-side transport to 10.4.0.119:46220 FINE [1/1] job queue empty FINE Transport to 10.4.0.119:51766: stream closed Socket closed [thread #0] string returned <[server #] Hello there thread #0!> FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (2bce521a) FINE ClientGIOPConnection to 10.4.0.119:51766 (4357956): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (4357956): streamClosed() FINE ClientGIOPConnection to 10.4.0.119:51766 (4357956): close() FINE [1/2] removed idle thread (job scheduled) FINE ClientGIOPConnection to 10.4.0.119:51766 (2bce521a): will wait until connected FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (2bce521a): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@7087b557 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46221 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46221 FINE wrote 76 bytes to 10.4.0.119:51766 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46221 from [10.4.0.119:51766] (993d81c) FINE [0/1] removed idle thread (job scheduled) FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (2bce521a) FINE read 12 bytes from 10.4.0.119:46221 FINE [1/2] job queue empty FINE read 96 bytes from 10.4.0.119:46221 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46221 from [10.4.0.119:51766] (993d81c) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46221 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46221 from [10.4.0.119:51766] (993d81c) FINE rid: 0 opname: get_string ends with request processing FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (2bce521a) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (2bce521a) FINE ClientGIOPConnection to 10.4.0.119:51766 (2bce521a): close() FINE Transport to 10.4.0.119:46221: stream closed on read < 0 FINE Transport to 10.4.0.119:51766: stream closed Socket closed INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE ServerGIOPConnection to 10.4.0.119:46221 from [10.4.0.119:51766] (993d81c): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46221 from [10.4.0.119:51766] (993d81c): streamClosed() FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE Delegate released! FINE ServerGIOPConnection to 10.4.0.119:46221 from [10.4.0.119:51766] (993d81c): close() FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) INFO Closed server-side transport to 10.4.0.119:46221 FINE [1/1] job queue empty FINE ClientGIOPConnection to 10.4.0.119:51766 (2bce521a): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (2bce521a): streamClosed() [thread #0] string returned <[server #] Hello there thread #0!>FINE ClientGIOPConnection to 10.4.0.119:51766 (2bce521a): close() FINE [2/2] job queue empty INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (328ead2d) FINE [1/2] removed idle thread (job scheduled) FINE [1/2] job queue empty FINE ClientGIOPConnection to 10.4.0.119:51766 (328ead2d): will wait until connected FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (328ead2d): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@74cc8042 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46222 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46222 FINE wrote 76 bytes to 10.4.0.119:51766 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46222 from [10.4.0.119:51766] (51c9afdc) FINE [0/1] removed idle thread (job scheduled) FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE read 12 bytes from 10.4.0.119:46222 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (328ead2d) FINE read 96 bytes from 10.4.0.119:46222 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46222 from [10.4.0.119:51766] (51c9afdc) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46222 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46222 from [10.4.0.119:51766] (51c9afdc) FINE rid: 0 opname: get_string ends with request processing FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (328ead2d) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (328ead2d) FINE ClientGIOPConnection to 10.4.0.119:51766 (328ead2d): close() FINE Transport to 10.4.0.119:46222: stream closed on read < 0 FINE Transport to 10.4.0.119:51766: stream closed Socket closed INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE ServerGIOPConnection to 10.4.0.119:46222 from [10.4.0.119:51766] (51c9afdc): getMessage() -- COMM_FAILURE FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE ServerGIOPConnection to 10.4.0.119:46222 from [10.4.0.119:51766] (51c9afdc): streamClosed() FINE ServerGIOPConnection to 10.4.0.119:46222 from [10.4.0.119:51766] (51c9afdc): close() FINE Delegate released! FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) FINE ClientGIOPConnection to 10.4.0.119:51766 (328ead2d): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (328ead2d): streamClosed() [thread #0] string returned <[server #] Hello there thread #0!> INFO ClientConnectionManager: created new ClientGIOPConnection to 10.4.0.119:51766 (5782f576) FINE ClientGIOPConnection to 10.4.0.119:51766 (328ead2d): close() FINE [1/2] removed idle thread (job scheduled) FINE [1/2] job queue empty FINE ClientGIOPConnection to 10.4.0.119:51766 (5782f576): will wait until connected INFO Closed server-side transport to 10.4.0.119:46222 FINE [1/1] job queue empty FINE DII::Request for IOR:000000000000001349444C3A546573742F48656C6C6F3A312E300000000000010000000000000168000102000000000B31302E342E302E3131390000CA3600000000001E34393735383531382F00010F1F4207100F4C4B100630463814141B484C1B000000000006000000030000002E0000000000000024323030313A3938323A613037323A313A386337623A623836653A376639353A3336663300CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A656466383A636131373A616138393A6438643000CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A643236373A653566663A666534393A3130333400CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A653936363A323463643A626563383A6663376100CA360000000000030000002E0000000000000024323030313A3938323A613037323A313A613839623A643439363A663161323A3661373300CA3600000000000000000008000000004A414300 FINE No CodeSetComponentInfo in IOR. Will use default CodeSets FINE GIOPConnection.sendMessage timeout (millis): 0 FINE ClientGIOPConnection to 10.4.0.119:51766 (5782f576): sendMessage() - opening transport org.jacorb.orb.iiop.ClientIIOPConnection@7ad4ecd6 FINE Trying to connect to 10.4.0.119:51766 with timeout=90000. INFO Connected to 10.4.0.119:51766 from local port 46223 INFO Opened new server-side TCP/IP transport to 10.4.0.119:46223 FINE wrote 76 bytes to 10.4.0.119:51766 FINE GIOPConnectionManager: created new ServerGIOPConnection to 10.4.0.119:46223 from [10.4.0.119:51766] (2f0015de) FINE [0/1] removed idle thread (job scheduled) FINE wrote 28 bytes to 10.4.0.119:51766 FINE wrote 4 bytes to 10.4.0.119:51766 FINE read 12 bytes from 10.4.0.119:46223 FINE wrote GIOP message of size 84 to ClientGIOPConnection to 10.4.0.119:51766 (5782f576) FINE read 96 bytes from 10.4.0.119:46223 FINE read GIOP message of size 108 from ServerGIOPConnection to 10.4.0.119:46223 from [10.4.0.119:51766] (2f0015de) FINE Received CodeSetContext. Using UTF8 as TCS and UTF16 as TCSW FINE POA RootPOA rid: 0 opname: get_string _invoke: queuing request FINE rid: 0 opname: get_string is queued (queue size: 1) FINE rid: 0 opname: get_string trying to get a RequestProcessor FINE waiting for queue FINE rid: 0 opname: get_string starts with request processing FINE rid: 0 opname: get_string invoke operation on servant (dsi based) FINE ServerRequest: reply to get_string FINE GIOPConnection.sendMessage timeout (millis): 0 FINE wrote 81 bytes to 10.4.0.119:46223 FINE wrote GIOP message of size 81 to ServerGIOPConnection to 10.4.0.119:46223 from [10.4.0.119:51766] (2f0015de) FINE rid: 0 opname: get_string ends with request processing FINE read 12 bytes from 10.4.0.119:51766 FINE read 69 bytes from 10.4.0.119:51766 FINE read GIOP message of size 81 from ClientGIOPConnection to 10.4.0.119:51766 (5782f576) FINE ClientConnectionManager: releasing ClientGIOPConnection to 10.4.0.119:51766 (5782f576) FINE ClientGIOPConnection to 10.4.0.119:51766 (5782f576): close() FINE Transport to 10.4.0.119:46223: stream closed on read < 0 FINE Transport to 10.4.0.119:51766: stream closed Socket closed FINE Unknown exception type java.net.SocketException with exception java.net.SocketException: Socket closed FINE ServerGIOPConnection to 10.4.0.119:46223 from [10.4.0.119:51766] (2f0015de): getMessage() -- COMM_FAILURE FINE ServerGIOPConnection to 10.4.0.119:46223 from [10.4.0.119:51766] (2f0015de): streamClosed() FINE ServerGIOPConnection to 10.4.0.119:46223 from [10.4.0.119:51766] (2f0015de): close() FINE 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 org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110) at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:382) at org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:540) at org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:529) at org.jacorb.orb.giop.MessageReceptor.doWork(MessageReceptor.java:69) at org.jacorb.util.threadpool.ConsumerTie.run(ConsumerTie.java:60) at java.lang.Thread.run(Thread.java:745) FINE ClientGIOPConnection to 10.4.0.119:51766 (5782f576): getMessage() -- COMM_FAILURE FINE ClientGIOPConnection to 10.4.0.119:51766 (5782f576): streamClosed() FINE ClientGIOPConnection to 10.4.0.119:51766 (5782f576): close() INFO Closed server-side transport to 10.4.0.119:46223 FINE [1/1] job queue empty INFO Client-side TCP transport to 10.4.0.119:51766 closed. FINE [2/2] job queue empty FINE Delegate released! [thread #0] string returned <[server #] Hello there thread #0!> INFO prepare ORB for shutdown... INFO ORB going down... FINE GIOPConnectionManager.shutdown(), 0 connections FINE ClientConnectionManager shut down (all connections released) FINE [2/2] shutting down pool INFO ConsumerTie exited INFO ORB shutdown complete INFO ConsumerTie exited R2CORBA::CORBA::COMM_FAILURE: CORBA::COMM_FAILURE() [minor=0;completed=MAYBE] _raise at /home/martin/develop/r2corba/lib/corba/jbase/exception.rb:75 invoke at /home/martin/develop/r2corba/lib/corba/jbase/Request.rb:161 _invoke at /home/martin/develop/r2corba/lib/corba/jbase/Stub.rb:29 get_string at :40 (root) at client.rb:65 times at org/jruby/RubyFixnum.java:275 (root) at client.rb:64 ERROR: client.rb returned: 1