[jacorb-bugs] [Bug 983] Failure to communicate to a restarted persistent CORBA reference with forward request usage

bugzilla-daemon at jacorb.org bugzilla-daemon at jacorb.org
Fri May 9 14:49:41 CEST 2014


http://www.jacorb.org/bugzilla/show_bug.cgi?id=983

--- Comment #2 from Hugo Roenick <hroenick at gmail.com> ---
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 at 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

-- 
You are receiving this mail because:
You are the assignee for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.spline.inf.fu-berlin.de/pipermail/jacorb-bugs/attachments/20140509/be19f777/attachment-0001.html>


More information about the jacorb-bugs mailing list