[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:48:10 CEST 2014


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

--- Comment #1 from Hugo Roenick <hroenick at gmail.com> ---
The expected transient exception has the following stack trace with jacorb 3.3:

org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to
10.0.64.108:3033
    at
org.jacorb.orb.iiop.ClientIIOPConnection.connect(ClientIIOPConnection.java:223)
    at org.jacorb.orb.giop.GIOPConnection.sendMessage(GIOPConnection.java:1042)
    at org.jacorb.orb.giop.GIOPConnection.sendRequest(GIOPConnection.java:987)
    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:1363)
    at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1188)
    at org.jacorb.orb.Delegate.invoke(Delegate.java:1176)
    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
2014-05-08 07:02:49.781 FINE  Delegate.try_rebind
2014-05-08 07:02:49.781 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:695)
    at org.jacorb.orb.Delegate.try_rebind(Delegate.java:1699)
    at org.jacorb.orb.Delegate._invoke_internal(Delegate.java:1389)
    at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1188)
    at org.jacorb.orb.Delegate.invoke(Delegate.java:1176)
    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 3.3:

[MY PRINT ON APPLICATION SCOPE] waiting for reactivation...
2014-05-08 07:03:15.782 FINE  No CodeSetComponentInfo in IOR. Will use default
CodeSets
2014-05-08 07:03:15.782 FINE  created with invocationContext:
{REQUEST_END_TIME=null, REPLY_END_TIME=null}
[MY DEBUG ON INTERCEPTORS] send request: sayHello
2014-05-08 07:03:15.783 FINE  GIOPConnection.sendMessage timeout (millis): 0
2014-05-08 07:03:15.783 FINE  ClientGIOPConnection to 10.0.64.108:3033
(322c1e6b): sendMessage() - opening transport
org.jacorb.orb.iiop.ClientIIOPConnection at 5e785d65
2014-05-08 07:03:15.783 FINE  Trying to connect to 10.0.64.108:3033 with
timeout=90000.
2014-05-08 07:03:15.784 INFO  Connected to 10.0.64.108:3033 from local port
50757
2014-05-08 07:03:15.785 FINE  wrote 80 bytes to 10.0.64.108:3033
2014-05-08 07:03:15.785 FINE  wrote 60 bytes to 10.0.64.108:3033
2014-05-08 07:03:15.786 FINE  wrote GIOP message of size 88 to
ClientGIOPConnection to 10.0.64.108:3033 (322c1e6b)
2014-05-08 07:03:15.817 FINE  read 12 bytes from 10.0.64.108:3033
2014-05-08 07:03:15.823 FINE  read 76 bytes from 10.0.64.108:3033
2014-05-08 07:03:15.823 FINE  read GIOP message of size 88 from
ClientGIOPConnection to 10.0.64.108:3033 (322c1e6b)
[MY DEBUG ON INTERCEPTORS] receive exception: sayHello
[MY DEBUG ON INTERCEPTORS] doing ForwardRequest: sayHello
2014-05-08 07:03:15.829 FINE  invoke[<--]: SystemException
org.omg.CORBA.TRANSIENT: 
    at org.jacorb.orb.Delegate.rebind(Delegate.java:695)
    at org.jacorb.orb.Delegate.rebind(Delegate.java:656)
    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:1419)
    at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1188)
    at org.jacorb.orb.Delegate.invoke(Delegate.java:1176)
    at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:80)
    at demo._HelloStub.sayHello(_HelloStub.java:117)
    at demo.Client2.main(Client2.java:44)
2014-05-08 07:03:15.830 FINE  release the connection
2014-05-08 07:03:15.830 FINE  ClientConnectionManager: releasing
ClientGIOPConnection to 10.0.64.108:3033 (322c1e6b)
2014-05-08 07:03:15.831 FINE  ClientGIOPConnection to 10.0.64.108:3033
(322c1e6b): close()
2014-05-08 07:03:15.838 INFO  Client-side TCP transport to 10.0.64.108:3033
closed.
[MY PRINT ON APPLICATION SCOPE] Why didn't worked?
org.omg.CORBA.TRANSIENT: 
    at org.jacorb.orb.Delegate.rebind(Delegate.java:695)
    at org.jacorb.orb.Delegate.rebind(Delegate.java:656)
    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:1419)
    at org.jacorb.orb.Delegate.invoke_internal(Delegate.java:1188)
    at org.jacorb.orb.Delegate.invoke(Delegate.java:1176)
    at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:80)
    at demo._HelloStub.sayHello(_HelloStub.java:117)
    at demo.Client2.main(Client2.java:44)
2014-05-08 07:03:15.839 FINE  Transport to 10.0.64.108:3033: stream closed
Socket closed
2014-05-08 07:03:15.840 FINE  Unknown exception type java.net.SocketException
with exception java.net.SocketException: Socket closed


On the server side we can see that it doesn't receive the forward request. The
log with jacORB 3.3 is:

2014-05-08 07:03:15.789 INFO  Opened new server-side TCP/IP transport to
10.0.64.108:50757
2014-05-08 07:03:15.791 FINE  GIOPConnectionManager: created new
ServerGIOPConnection to 10.0.64.108:50757 from [10.0.64.108:3033] (1055e55f)
2014-05-08 07:03:15.791 FINE  [0/0] creating new thread
2014-05-08 07:03:15.792 FINE  [0/1] removed idle thread (job scheduled)
2014-05-08 07:03:15.794 FINE  read 12 bytes from 10.0.64.108:50757
2014-05-08 07:03:15.794 FINE  read 128 bytes from 10.0.64.108:50757
2014-05-08 07:03:15.795 FINE  read GIOP message of size 140 from
ServerGIOPConnection to 10.0.64.108:50757 from [10.0.64.108:3033] (1055e55f)
2014-05-08 07:03:15.797 FINE  Received CodeSetContext. Using ISO8859_1 as TCS
and UTF16 as TCSW
2014-05-08 07:03:15.799 FINE  POA HelloPOAP rid: 4 opname: sayHello _invoke:
queuing request
2014-05-08 07:03:15.800 FINE  rid: 4 opname: sayHello is queued (queue size: 1)
2014-05-08 07:03:15.800 FINE  rid: 4 opname: sayHello trying to get a
RequestProcessor
2014-05-08 07:03:15.802 FINE  waiting for queue
2014-05-08 07:03:15.803 FINE  rid: 4 opname: sayHello starts with request
processing
[MY DEBUG ON INTERCEPTORS] receive request: sayHello
[MY DEBUG ON INTERCEPTORS] send exception: sayHello
2014-05-08 07:03:15.810 FINE  ServerRequest: reply to sayHello
2014-05-08 07:03:15.811 FINE  GIOPConnection.sendMessage timeout (millis): 0
2014-05-08 07:03:15.814 FINE  wrote 20 bytes to 10.0.64.108:50757
2014-05-08 07:03:15.814 FINE  wrote 20 bytes to 10.0.64.108:50757
2014-05-08 07:03:15.814 FINE  wrote 48 bytes to 10.0.64.108:50757
2014-05-08 07:03:15.816 FINE  wrote GIOP message of size 72 to
ServerGIOPConnection to 10.0.64.108:50757 from [10.0.64.108:3033] (1055e55f)
2014-05-08 07:03:15.816 FINE  rid: 4 opname: sayHello ends with request
processing
2014-05-08 07:03:15.835 FINE  Transport to 10.0.64.108:50757: stream closed on
read  < 0
2014-05-08 07:03:15.836 FINE  ServerGIOPConnection to 10.0.64.108:50757 from
[10.0.64.108:3033] (1055e55f): getMessage() -- COMM_FAILURE
2014-05-08 07:03:15.836 FINE  ServerGIOPConnection to 10.0.64.108:50757 from
[10.0.64.108:3033] (1055e55f): streamClosed()
2014-05-08 07:03:15.837 FINE  ServerGIOPConnection to 10.0.64.108:50757 from
[10.0.64.108:3033] (1055e55f): close()
2014-05-08 07:03:15.839 INFO  Closed server-side transport to 10.0.64.108:50757
2014-05-08 07:03:15.840 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/7a209c24/attachment.html>


More information about the jacorb-bugs mailing list