<html>
<head>
<base href="http://www.jacorb.org/bugzilla/" />
</head>
<body>
<p>
<div>
<b><a class="bz_bug_link
bz_status_NEW "
title="NEW - Failure to communicate to a restarted persistent CORBA reference with forward request usage"
href="http://www.jacorb.org/bugzilla/show_bug.cgi?id=983#c2">Comment # 2</a>
on <a class="bz_bug_link
bz_status_NEW "
title="NEW - Failure to communicate to a restarted persistent CORBA reference with forward request usage"
href="http://www.jacorb.org/bugzilla/show_bug.cgi?id=983">bug 983</a>
from <span class="vcard"><a class="email" href="mailto:hroenick@gmail.com" title="Hugo Roenick <hroenick@gmail.com>"> <span class="fn">Hugo Roenick</span></a>
</span></b>
<pre>The expected transient exception has the following stack trace with JacORB from
branch BZ979:
org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to
10.0.64.108:6969
at
org.jacorb.orb.iiop.ClientIIOPConnection.connect(ClientIIOPConnection.java:224)
at org.jacorb.orb.giop.GIOPConnection.sendMessage(GIOPConnection.java:1060)
at org.jacorb.orb.giop.GIOPConnection.sendRequest(GIOPConnection.java:1005)
at
org.jacorb.orb.giop.ClientConnection.sendRequest(ClientConnection.java:308)
at
org.jacorb.orb.giop.ClientConnection.sendRequest(ClientConnection.java:289)
at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1420)
at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1245)
at org.jacorb.orb.Delegate.invoke(Delegate.java:1233)
at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:80)
at demo._HelloStub.sayHello(_HelloStub.java:117)
at demo.Client2.main(Client2.java:35)
[MY DEBUG ON INTERCEPTORS] receive exception: sayHello
FINE Delegate.try_rebind
FINE Delegate: falling back to original IOR
[MY PRINT ON APPLICATION SCOPE] Ok! It was really down.
org.omg.CORBA.TRANSIENT:
at org.jacorb.orb.Delegate.rebind(Delegate.java:755)
at org.jacorb.orb.Delegate.rebind(Delegate.java:712)
at org.jacorb.orb.Delegate.try_rebind(Delegate.java:1756)
at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1446)
at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1245)
at org.jacorb.orb.Delegate.invoke(Delegate.java:1233)
at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:80)
at demo._HelloStub.sayHello(_HelloStub.java:117)
at demo.Client2.main(Client2.java:35)
While the unexpected one, after the server come back available, has this one
with JacORB from branch BZ979:
[MY PRINT ON APPLICATION SCOPE] waiting for reactivation...
INFO Negotiated char codeset of ISO8859_1 and wchar of UTF16
FINE created with invocationContext: {REQUEST_END_TIME=null,
REPLY_END_TIME=null}
[MY DEBUG ON INTERCEPTORS] send request: sayHello
FINE GIOPConnection.sendMessage timeout (millis): 0
FINE ClientGIOPConnection to 10.0.64.108:6969 (2b2d96f2): sendMessage() -
opening transport org.jacorb.orb.iiop.ClientIIOPConnection@2adb1d4
FINE Trying to connect to 10.0.64.108:6969 with timeout=90000.
INFO Connected to 10.0.64.108:6969 from local port 50864
FINE wrote 80 bytes to 10.0.64.108:6969
FINE wrote 60 bytes to 10.0.64.108:6969
FINE wrote GIOP message of size 88 to ClientGIOPConnection to 10.0.64.108:6969
(2b2d96f2)
FINE read 12 bytes from 10.0.64.108:6969
FINE read 76 bytes from 10.0.64.108:6969
FINE read GIOP message of size 88 from ClientGIOPConnection to 10.0.64.108:6969
(2b2d96f2)
[MY DEBUG ON INTERCEPTORS] receive exception: sayHello
[MY DEBUG ON INTERCEPTORS] doing ForwardRequest: sayHello
FINE invoke[<--]: SystemException
org.omg.CORBA.TRANSIENT:
at org.jacorb.orb.Delegate.rebind(Delegate.java:755)
at org.jacorb.orb.Delegate.rebind(Delegate.java:701)
at
org.jacorb.orb.portableInterceptor.DefaultClientInterceptorHandler.invokeInterceptors(DefaultClientInterceptorHandler.java:354)
at
org.jacorb.orb.portableInterceptor.DefaultClientInterceptorHandler.handle_receive_exception(DefaultClientInterceptorHandler.java:264)
at org.jacorb.orb.ReplyReceiver.getReply(ReplyReceiver.java:459)
at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1476)
at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1245)
at org.jacorb.orb.Delegate.invoke(Delegate.java:1233)
at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:80)
at demo._HelloStub.sayHello(_HelloStub.java:117)
at demo.Client2.main(Client2.java:44)
FINE release the connection
FINE ClientConnectionManager: releasing ClientGIOPConnection to
10.0.64.108:6969 (2b2d96f2)
FINE ClientGIOPConnection to 10.0.64.108:6969 (2b2d96f2): close()
FINE Transport to 10.0.64.108:6969: 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:129)
at
org.jacorb.orb.etf.StreamConnectionBase.read(StreamConnectionBase.java:110)
at org.jacorb.orb.giop.GIOPConnection.getMessage(GIOPConnection.java:383)
at
org.jacorb.orb.giop.GIOPConnection.receiveMessagesLoop(GIOPConnection.java:543)
at
org.jacorb.orb.giop.GIOPConnection.receiveMessages(GIOPConnection.java:530)
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:695)
FINE ClientGIOPConnection to 10.0.64.108:6969 (2b2d96f2): getMessage() --
COMM_FAILURE
FINE ClientGIOPConnection to 10.0.64.108:6969 (2b2d96f2): streamClosed()
FINE ClientGIOPConnection to 10.0.64.108:6969 (2b2d96f2): close()
INFO Client-side TCP transport to 10.0.64.108:6969 closed.
[MY PRINT ON APPLICATION SCOPE] Why didn't worked?
org.omg.CORBA.TRANSIENT:
at org.jacorb.orb.Delegate.rebind(Delegate.java:755)
at org.jacorb.orb.Delegate.rebind(Delegate.java:701)
at
org.jacorb.orb.portableInterceptor.DefaultClientInterceptorHandler.invokeInterceptors(DefaultClientInterceptorHandler.java:354)
at
org.jacorb.orb.portableInterceptor.DefaultClientInterceptorHandler.handle_receive_exception(DefaultClientInterceptorHandler.java:264)
at org.jacorb.orb.ReplyReceiver.getReply(ReplyReceiver.java:459)
at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1476)
at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1245)
at org.jacorb.orb.Delegate.invoke(Delegate.java:1233)
at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:80)
at demo._HelloStub.sayHello(_HelloStub.java:117)
at demo.Client2.main(Client2.java:44)
FINE [2/2] job queue empty
On the server side we can see that it doesn't receive the forward request. The
log with JacORB from branch BZ979 is :
INFO Opened new server-side TCP/IP transport to 10.0.64.108:50864
FINE GIOPConnectionManager: created new ServerGIOPConnection to
10.0.64.108:50864 from [10.0.64.108:6969] (52c05d3b)
FINE [0/0] creating new thread
FINE [0/1] removed idle thread (job scheduled)
FINE read 12 bytes from 10.0.64.108:50864
FINE read 128 bytes from 10.0.64.108:50864
FINE read GIOP message of size 140 from ServerGIOPConnection to
10.0.64.108:50864 from [10.0.64.108:6969] (52c05d3b)
FINE Received CodeSetContext. Using ISO8859_1 as TCS and UTF16 as TCSW
FINE POA HelloPOAP rid: 2 opname: sayHello _invoke: queuing request
FINE rid: 2 opname: sayHello is queued (queue size: 1)
FINE rid: 2 opname: sayHello trying to get a RequestProcessor
FINE waiting for queue
FINE rid: 2 opname: sayHello starts with request processing
[MY DEBUG ON INTERCEPTORS] receive request: sayHello
[MY DEBUG ON INTERCEPTORS] send exception: sayHello
FINE ServerRequest: reply to sayHello
FINE GIOPConnection.sendMessage timeout (millis): 0
FINE wrote 20 bytes to 10.0.64.108:50864
FINE wrote 20 bytes to 10.0.64.108:50864
FINE wrote 48 bytes to 10.0.64.108:50864
FINE wrote GIOP message of size 72 to ServerGIOPConnection to 10.0.64.108:50864
from [10.0.64.108:6969] (52c05d3b)
FINE rid: 2 opname: sayHello ends with request processing
FINE Transport to 10.0.64.108:50864: stream closed on read < 0
FINE ServerGIOPConnection to 10.0.64.108:50864 from [10.0.64.108:6969]
(52c05d3b): getMessage() -- COMM_FAILURE
FINE ServerGIOPConnection to 10.0.64.108:50864 from [10.0.64.108:6969]
(52c05d3b): streamClosed()
FINE ServerGIOPConnection to 10.0.64.108:50864 from [10.0.64.108:6969]
(52c05d3b): close()
INFO Closed server-side transport to 10.0.64.108:50864
FINE [1/1] job queue empty</pre>
</div>
</p>
<hr>
<span>You are receiving this mail because:</span>
<ul>
<li>You are the assignee for the bug.</li>
</ul>
</body>
</html>