[omniORB] Remote Invocation Problem on omniORB
Luke Deller
ldeller@xplantechnology.com
Mon, 30 Apr 2001 11:47:56 +1000
This is a multi-part message in MIME format.
------=_NextPart_000_0002_01C0D16B.65ED24C0
Content-Type: text/plain;
charset="iso-8859-1"
Content-Transfer-Encoding: 7bit
Duncan Grisby wrote:
> The traces are very strange. The client says it receives a
> BAD_OPERATION exception, but the server never claims to have sent
> it. Can you run both programs with trace level 30, instead of 20. That
> will print all the GIOP traffic between them, which will at least show
> if the BAD_OPERATION really is being sent from the server to the
> client.
Here are the logs from using trace level 30 with the same
configuration (see attached).
Regards,
Luke.
------=_NextPart_000_0002_01C0D16B.65ED24C0
Content-Type: application/octet-stream;
name="omniNames.errlog"
Content-Transfer-Encoding: quoted-printable
Content-Disposition: attachment;
filename="omniNames.errlog"
# created with: /usr/local/bin/omniNames -start -logdir /var/local/omni =
-errlog /var/local/omni/omniNames.errlog -ORBtraceLevel 30 =
-ORBtraceInvocations
omniORB: strand Ripper: start.
omniORB: scavenger : start.
omniORB: gateKeeper is tcpwrapGK 1.0 - based on tcp_wrappers_7.6=20
omniORB: The omniDynamic library is not linked.
omniORB: Initialising incoming rope factories.
omniORB: strand Rope::incrRefCount: old value =3D 0
omniORB: Starting incoming rope factories.
Mon Apr 30 11:30:40 2001:
omniORB: tcpSocketMTfactory Rendezvouser: start.
Starting omniNames for the first time.omniORB: tcpSocketMTfactory =
Rendezvouser: block on accept()
Wrote initial log file.
omniORB: Activating: key<0x4e616d6553657276696365>
Read log file successfully.
omniORB: Creating ref to local: key<0x4e616d6553657276696365>
target id : IDL:omg.org/CosNaming/NamingContextExt:1.0
most derived id: IDL:omg.org/CosNaming/NamingContextExt:1.0
Root context is =
IOR:010000002b00000049444c3a6f6d672e6f72672f436f734e616d696e672f4e616d696=
e67436f6e746578744578743a312e300000010000000000000023000000010100000a0000=
003132372e302e302e3100f90a0b0000004e616d6553657276696365
Checkpointing Phase 1: Prepare.
Checkpointing Phase 2: Commit.
Checkpointing completed.
omniORB: scavenger : scanning connections
omniORB: tcpSocketMTfactory Rendezvouser: unblock from accept()
omniORB: tcpSocketMTfactory Rendezvouser: accept new strand.
omniORB: tcpSocketMTfactory Rendezvouser: block on accept()
omniORB: tcpSocketMTfactory Worker: start.
connect from 10.11.12.32
ll_recv: 108 bytes
4749 4f50 0100 0100 6000 0000 0000 0000 GIOP....`.......
0100 0000 0100 0000 0b00 0000 4e61 6d65 ............Name
5365 7276 6963 6500 0600 0000 5f69 735f Service....._is_
6100 0000 0700 0000 6e6f 626f 6479 0000 a.......nobody..
2800 0000 4944 4c3a 6f6d 672e 6f72 672f (...IDL:omg.org/
436f 734e 616d 696e 672f 4e61 6d69 6e67 CosNaming/Naming
436f 6e74 6578 743a 312e 3000 Context:1.0.
omniORB: Dispatching remote call '_is_a' to: =
key<0x4e616d6553657276696365>
ll_send: 25 bytes
4749 4f50 0100 0101 0d00 0000 0000 0000 GIOP............
0100 0000 0000 0000 01 .........
ll_recv: 108 bytes
4749 4f50 0100 0100 6000 0000 0000 0000 GIOP....`.......
0200 0000 0100 0000 0b00 0000 4e61 6d65 ............Name
5365 7276 6963 6500 0600 0000 5f69 735f Service....._is_
6100 0000 0700 0000 6e6f 626f 6479 0000 a.......nobody..
2800 0000 4944 4c3a 6f6d 672e 6f72 672f (...IDL:omg.org/
436f 734e 616d 696e 672f 4e61 6d69 6e67 CosNaming/Naming
436f 6e74 6578 743a 312e 3000 Context:1.0.
omniORB: Dispatching remote call '_is_a' to: =
key<0x4e616d6553657276696365>
ll_send: 25 bytes
4749 4f50 0100 0101 0d00 0000 0000 0000 GIOP............
0200 0000 0000 0000 01 .........
ll_recv: 68 bytes
4749 4f50 0100 0100 3800 0000 0000 0000 GIOP....8.......
0300 0000 0100 0000 0b00 0000 4e61 6d65 ............Name
5365 7276 6963 6500 0500 0000 6c69 7374 Service.....list
0000 0000 0700 0000 6e6f 626f 6479 0000 ........nobody..
0000 0000 ....
omniORB: Dispatching remote call 'list' to: =
key<0x4e616d6553657276696365>
omniORB: Activating: root/<0>
omniORB: Creating ref to local: root/<0>
target id : IDL:omg.org/CosNaming/BindingIterator:1.0
most derived id: IDL:omg.org/CosNaming/BindingIterator:1.0
omniORB: Dispatching local call 'next_n' to root/<0>
ll_send: 118 bytes
4749 4f50 0100 0101 6a00 0000 0000 0000 GIOP....j.......
0300 0000 0000 0000 0000 0000 2a00 0000 ............*...
4944 4c3a 6f6d 672e 6f72 672f 436f 734e IDL:omg.org/CosN
616d 696e 672f 4269 6e64 696e 6749 7465 aming/BindingIte
7261 746f 723a 312e 3000 0000 0100 0000 rator:1.0.......
0000 0000 1e00 0000 0101 0000 0a00 0000 ................
3132 372e 302e 302e 3100 f90a 0600 0000 127.0.0.1.......
ff00 0000 0000 ......
omniORB: Ref to: root/<0> -- deleted.
omniORB: throw omniConnectionBroken (minor 104) from =
tcpSocketMTfactory.cc:1060
omniORB: tcpSocketMTfactory Worker: #### Connection closed.
omniORB: tcpSocketMTfactory Worker: exit.
omniORB: tcpSocketStrand::~Strand() close socket no. 8
omniORB: scavenger : scanning connections
------=_NextPart_000_0002_01C0D16B.65ED24C0
Content-Type: application/octet-stream;
name="nameclt.log"
Content-Transfer-Encoding: quoted-printable
Content-Disposition: attachment;
filename="nameclt.log"
$ nameclt list -ORBInitRef NameService=3Dcorbaname::heater =
-ORBtraceLevel 30 -ORBtraceInvocations
omniORB: The omniDynamic library is not linked.
omniORB: strand Rope::incrRefCount: old value =3D 0
omniORB: Creating ref to remote: key<0x4e616d6553657276696365>
target id : IDL:omg.org/CORBA/Object:1.0
most derived id:
omniORB: strand Ripper: start.
omniORB: scavenger : start.
omniORB: Initial reference `NameService' resolved from -ORBInitRef =
argument.
omniORB: Invoke '_is_a' on remote: key<0x4e616d6553657276696365>
ll_send: 108 bytes
4749 4f50 0100 0100 6000 0000 0000 0000 GIOP....`.......
0100 0000 0100 0000 0b00 0000 4e61 6d65 ............Name
5365 7276 6963 6500 0600 0000 5f69 735f Service....._is_
6100 0000 0700 0000 6e6f 626f 6479 0000 a.......nobody..
2800 0000 4944 4c3a 6f6d 672e 6f72 672f (...IDL:omg.org/
436f 734e 616d 696e 672f 4e61 6d69 6e67 CosNaming/Naming
436f 6e74 6578 743a 312e 3000 Context:1.0.
ll_recv: 25 bytes
4749 4f50 0100 0101 0d00 0000 0000 0000 GIOP............
0100 0000 0000 0000 01 .........
omniORB: strand Rope::incrRefCount: old value =3D 1
omniORB: Creating ref to remote: key<0x4e616d6553657276696365>
target id : IDL:omg.org/CosNaming/NamingContext:1.0
most derived id:
omniORB: Invoke '_is_a' on remote: key<0x4e616d6553657276696365>
ll_send: 108 bytes
4749 4f50 0100 0100 6000 0000 0000 0000 GIOP....`.......
0200 0000 0100 0000 0b00 0000 4e61 6d65 ............Name
5365 7276 6963 6500 0600 0000 5f69 735f Service....._is_
6100 0000 0700 0000 6e6f 626f 6479 0000 a.......nobody..
2800 0000 4944 4c3a 6f6d 672e 6f72 672f (...IDL:omg.org/
436f 734e 616d 696e 672f 4e61 6d69 6e67 CosNaming/Naming
436f 6e74 6578 743a 312e 3000 Context:1.0.
ll_recv: 25 bytes
4749 4f50 0100 0101 0d00 0000 0000 0000 GIOP............
0200 0000 0000 0000 01 .........
omniORB: Invoke 'list' on remote: key<0x4e616d6553657276696365>
ll_send: 68 bytes
4749 4f50 0100 0100 3800 0000 0000 0000 GIOP....8.......
0300 0000 0100 0000 0b00 0000 4e61 6d65 ............Name
5365 7276 6963 6500 0500 0000 6c69 7374 Service.....list
0000 0000 0700 0000 6e6f 626f 6479 0000 ........nobody..
0000 0000 ....
ll_recv: 118 bytes
4749 4f50 0100 0101 6a00 0000 0000 0000 GIOP....j.......
0300 0000 0000 0000 0000 0000 2a00 0000 ............*...
4944 4c3a 6f6d 672e 6f72 672f 436f 734e IDL:omg.org/CosN
616d 696e 672f 4269 6e64 696e 6749 7465 aming/BindingIte
7261 746f 723a 312e 3000 0000 0100 0000 rator:1.0.......
0000 0000 1e00 0000 0101 0000 0a00 0000 ................
3132 372e 302e 302e 3100 f90a 0600 0000 127.0.0.1.......
ff00 0000 0000 ......
omniORB: strand Rope::incrRefCount: old value =3D 0
omniORB: Creating ref to remote: root/<0>
target id : IDL:omg.org/CosNaming/BindingIterator:1.0
most derived id: IDL:omg.org/CosNaming/BindingIterator:1.0
omniORB: LocateRequest to remote: root/<0>
ll_send: 26 bytes
4749 4f50 0100 0103 0e00 0000 0100 0000 GIOP............
0600 0000 ff00 0000 0000 ..........
ll_recv: 20 bytes
4749 4f50 0100 0104 0800 0000 0100 0000 GIOP............
0100 0000 ....
omniORB: Invoke 'next_one' on remote: root/<0>
ll_send: 63 bytes
4749 4f50 0100 0100 3300 0000 0000 0000 GIOP....3.......
0200 0000 0100 0000 0600 0000 ff00 0000 ................
0000 0000 0900 0000 6e65 7874 5f6f 6e65 ........next_one
0000 0000 0700 0000 6e6f 626f 6479 00 ........nobody.
ll_recv: 72 bytes
4749 4f50 0100 0101 3c00 0000 0000 0000 GIOP....<.......
0200 0000 0200 0000 2400 0000 4944 4c3a ........$...IDL:
6f6d 672e 6f72 672f 434f 5242 412f 4241 omg.org/CORBA/BA
445f 4f50 4552 4154 494f 4e3a 312e 3000 D_OPERATION:1.0.
0000 0000 0100 0000 ........
omniORB: throw BAD_OPERATION from giopClient.cc:495
omniORB: omniRemoteIdentity deleted.
omniORB: strand Rope::decrRefCount: old value =3D 1
omniORB: ObjRef(IDL:omg.org/CosNaming/BindingIterator:1.0) -- deleted.
list: Unexpected error encountered.
omniORB: Preparing to shutdown ORB.
omniORB: scavenger : woken by poke()
omniORB: scavenger : exit.
omniORB: strand Ripper: exit.
omniORB: ORB shutdown is complete.
omniORB: omniRemoteIdentity deleted.
omniORB: strand Rope::decrRefCount: old value =3D 2
omniORB: ObjRef() -- deleted.
omniORB: omniRemoteIdentity deleted.
omniORB: strand Rope::decrRefCount: old value =3D 1
omniORB: ObjRef() -- deleted.
------=_NextPart_000_0002_01C0D16B.65ED24C0--