| T.R | Title | User | Personal
Name | Date | Lines |
|---|
| 2398.1 | Need more details | REQUE::whocrz.zko.dec.com::Gumbel | Dick Gumbel | Fri Dec 13 1996 07:54 | 20 |
| 2398.2 | more info... | PROFET::TAURIAINEN | Matti Tauriainen | Sun Dec 15 1996 04:01 | 30 |
| 2398.3 | | REQUE::BOWER | Peter Bower, ObjectBroker | Mon Dec 16 1996 22:11 | 15 |
| 2398.4 | still more info... | PROFET::TAURIAINEN | Matti Tauriainen | Tue Dec 17 1996 05:13 | 13 |
| 2398.5 | | REQUE::BOWER | Peter Bower, ObjectBroker | Thu Dec 26 1996 08:36 | 2 |
| 2398.6 | No errors so far... | PROFET::TAURIAINEN | Matti Tauriainen | Wed Jan 01 1997 05:20 | 9 |
| 2398.7 | Trace from a hanging client | PROFET::TAURIAINEN | Matti Tauriainen | Mon Jan 13 1997 08:16 | 73 |
| 2398.8 | | PROFET::TAURIAINEN | Matti Tauriainen | Tue Jan 14 1997 09:37 | 10 |
| 2398.9 | | REQUE::BOWER | Peter Bower, ObjectBroker | Sat Jan 18 1997 09:05 | 25 |
| 2398.10 | show process... | PROFET::TAURIAINEN | Matti Tauriainen | Tue Jan 21 1997 08:40 | 23 |
| 2398.11 | SDA log | PROFET::TAURIAINEN | Matti Tauriainen | Tue Jan 21 1997 08:42 | 145 |
| 2398.12 | | REQUE::BOWER | Peter Bower, ObjectBroker | Tue Jan 21 1997 09:29 | 6 |
| 2398.13 | Updated server to v2.7 | PROFET::TAURIAINEN | Matti Tauriainen | Mon Jan 27 1997 03:32 | 8 |
| Hi,
we updated the server side to v2.7 and recompiled and relinked the
server image. Now we seem to be getting a GPF at client side rather
than see the OBB$AGENT hang. I'll add a trace from the crashing
client to the next reply.
--Matti
|
| 2398.14 | Trace from the client | PROFET::TAURIAINEN | Matti Tauriainen | Mon Jan 27 1997 03:33 | 310 |
|
**** Skip Method Selection, OpInfo Created by STUB
**** Implementation Selection
No method map definition found.
*** Load Network implementation MicrosoftTCP
FamilyName<5> <TCPIP>
ImagePath<26> <%OBB_ROOT\bin\obbtrwsk.dll>
LibraryName: C:\OBB\bin\obbtrwsk.dll
--- WM_CREATE message
--- WinSock implementation Microsoft Windows Sockets Version 1.1.
--- Min Version: 1.1
--- Max Version: 1.1
--- Max Sockets: 256
--- Max Datagram: 65467
**** Server Instance Selection
Selection policy defaulting to advertisements, local_node, default_nodes.
Context scope default to USER.
Get Server Selection Node List:
Possible server selection nodes: <2>
000. OBB_LOCAL = lokki
001. OBB_DEFAULT_NODES = armi
Looking for running server:
Looking for servers on node lokki.
*** Load Agent implementation OrbV12
FamilyName<3> <OBB>
ImagePath<26> <%OBB_ROOT\bin\obbagncl.dll>
LibraryName: C:\OBB\bin\obbagncl.dll
OBB__TrnWsckBindWellKnown
--- Connecting to lokki
--- Port: 62
--- Attempting connect on socket: 6
--- Connect failed: 10061
--- Transport Error: Connect was forcefully rejected.
Looking for servers on node armi.
OBB__TrnWsckBindWellKnown
--- Connecting to armi
--- Port: 62
--- Attempting connect on socket: 7
--- Setting nonblocking socket : 7
--- Set asynch socket
--- Socket: 7
--- Message: WSA_READ
--- SocketSetAsynch - Successful
*** Load Authentication implementation Trusted
FamilyName<3> <TRS>
ImagePath<26> <%OBB_ROOT\bin\obbsectr.dll>
LibraryName: C:\OBB\bin\obbsectr.dll
*** Request Sent: Synchronous Invoke.
*** Method: 65e448f20f7c.0c.7e.0b.00.00.00.00.00.
*** MethodServerClass: 65e448ecbd2c.0c.7e.0b.00.00.00.00.00
*** Marshalled Buffer: 548
*** Allocated Buffer : 1879
OBB__TrnWsckSar
--- SAR: Sending message on socket 7
--- Sending on socket 7
--- Socket send buffer size is 8192
--- Sending data on socket - len is 548
--- SAR: Waiting for message on socket 7
--- SAR: Receiving message on socket 7
--- Receiving on socket 7
--- Receiving Header on socket - len is 20
--- Received data - retval is 20
--- Received the transport header
*** In SocketRecvBuf ***--- Receiving data - remaining is 472
--- ioctlsocket buffer available for recv() is 492
*** Transport Status: OBB_SUCCESS (s), Successful completion.
*** Operation Status: OBB_SUCCESS (s), Successful completion.
OBB__TrnWsckFreeHandle
--- Closing socket: 7
Server selected from Registry: 7a71c4e6aac2.0c.0e.04.00.00.00.00.00 (armi).
OBB__TrnWsckBind
--- Connecting to armi
--- Port: 1045
--- Attempting connect on socket: 8
--- WSA_READ message for FD_READ
--- On socket 7
--- Family 0
--- Setting nonblocking socket : 8
--- Set asynch socket
--- Socket: 8
--- Message: WSA_READ
--- SocketSetAsynch - Successful
*** Request Sent: Synchronous Invoke.
*** Method: 78bb457f5e4e.0c.0e.04.00.00.00.00.00.
*** MethodServerClass: 769d1760085e.0c.0e.04.00.00.00.00.00
*** Marshalled Buffer: 456
*** Allocated Buffer : 1672
OBB__TrnWsckSar
--- SAR: Sending message on socket 8
--- Sending on socket 8
--- Socket send buffer size is 8192
--- Sending data on socket - len is 456
--- SAR: Waiting for message on socket 8
--- SAR: Receiving message on socket 8
--- Receiving on socket 8
--- Receiving Header on socket - len is 20
--- Received data - retval is 20
--- Received the transport header
*** In SocketRecvBuf ***--- Receiving data - remaining is 108
--- ioctlsocket buffer available for recv() is 128
*** Transport Status: OBB_SUCCESS (s), Successful completion.
*** Operation Status: OBB_SUCCESS (s), Successful completion.
OBB__TrnWsckFreeHandle
--- Closing socket: 8
**** Skip Method Selection, OpInfo Created by STUB
**** Implementation Selection
No method map definition found.
**** Server Instance Selection
Selection policy defaulting to advertisements, local_node, default_nodes.
Context scope default to USER.
Get Server Selection Node List:
Possible server selection nodes: <2>
000. OBB_LOCAL = lokki
001. OBB_DEFAULT_NODES = armi
Looking for running server:
Looking for servers on node lokki.
OBB__TrnWsckBindWellKnown
--- Connecting to lokki
--- Port: 62
--- Attempting connect on socket: 9
--- WSA_READ message for FD_READ
--- On socket 8
--- Family 0
--- Connect failed: 10061
--- Transport Error: Connect was forcefully rejected.
Looking for servers on node armi.
OBB__TrnWsckBindWellKnown
--- Connecting to armi
--- Port: 62
--- Attempting connect on socket: 10
--- Setting nonblocking socket : 10
--- Set asynch socket
--- Socket: 10
--- Message: WSA_READ
--- SocketSetAsynch - Successful
*** Request Sent: Synchronous Invoke.
*** Method: 65e448f20f7c.0c.7e.0b.00.00.00.00.00.
*** MethodServerClass: 65e448ecbd2c.0c.7e.0b.00.00.00.00.00
*** Marshalled Buffer: 548
*** Allocated Buffer : 1879
OBB__TrnWsckSar
--- SAR: Sending message on socket 10
--- Sending on socket 10
--- Socket send buffer size is 8192
--- Sending data on socket - len is 548
--- SAR: Waiting for message on socket 10
--- SAR: Receiving message on socket 10
--- Receiving on socket 10
--- Receiving Header on socket - len is 20
--- Received data - retval is 20
--- Received the transport header
*** In SocketRecvBuf ***--- Receiving data - remaining is 472
--- ioctlsocket buffer available for recv() is 492
*** Transport Status: OBB_SUCCESS (s), Successful completion.
*** Operation Status: OBB_SUCCESS (s), Successful completion.
OBB__TrnWsckFreeHandle
--- Closing socket: 10
Server selected from Registry: 7a71c4e6aac2.0c.0e.04.00.00.00.00.00 (armi).
OBB__TrnWsckBind
--- Connecting to armi
--- Port: 1045
--- Attempting connect on socket: 11
--- WSA_READ message for FD_READ
--- On socket 10
--- Family 0
--- Setting nonblocking socket : 11
--- Set asynch socket
--- Socket: 11
--- Message: WSA_READ
--- SocketSetAsynch - Successful
*** Request Sent: Synchronous Invoke.
*** Method: 78bb457f5e4e.0c.0e.04.00.00.00.00.00.
*** MethodServerClass: 769d1760085e.0c.0e.04.00.00.00.00.00
*** Marshalled Buffer: 456
*** Allocated Buffer : 1672
OBB__TrnWsckSar
--- SAR: Sending message on socket 11
--- Sending on socket 11
--- Socket send buffer size is 8192
--- Sending data on socket - len is 456
--- SAR: Waiting for message on socket 11
--- SAR: Receiving message on socket 11
--- Receiving on socket 11
--- Receiving Header on socket - len is 20
--- Received data - retval is 20
--- Received the transport header
*** In SocketRecvBuf ***--- Receiving data - remaining is 108
--- ioctlsocket buffer available for recv() is 128
*** Transport Status: OBB_SUCCESS (s), Successful completion.
*** Operation Status: OBB_SUCCESS (s), Successful completion.
OBB__TrnWsckFreeHandle
--- Closing socket: 11
.
.
.
<Part of trace file edited out here - the above is just repeating...>
.
.
**** Skip Method Selection, OpInfo Created by STUB
**** Implementation Selection
No method map definition found.
**** Server Instance Selection
Selection policy defaulting to advertisements, local_node, default_nodes.
Context scope default to USER.
Get Server Selection Node List:
Possible server selection nodes: <2>
000. OBB_LOCAL = lokki
001. OBB_DEFAULT_NODES = armi
Looking for running server:
Looking for servers on node lokki.
OBB__TrnWsckBindWellKnown
--- Connecting to lokki
--- Port: 62
--- Attempting connect on socket: 144
--- Connect failed: 10055
--- Transport Error: No buffer space available
Looking for servers on node armi.
OBB__TrnWsckBindWellKnown
--- Connecting to armi
--- Port: 62
--- Attempting connect on socket: 145
--- Connect failed: 10055
--- Transport Error: No buffer space available
No server selected.
**** Host Selection for Server Startup
No startup attributes found, defaulting to selection attributes.
Possible server startup nodes: <2>
000. OBB_LOCAL = lokki
001. OBB_DEFAULT_NODES = armi
Scanning server startup nodes:
Node lokki matched; selected.
OBB__TrnWsckBindWellKnown
--- Connecting to lokki
--- Port: 62
--- Attempting connect on socket: 146
--- Connect failed: 10055
--- Transport Error: No buffer space available
Server could not be started on node lokki; looking for another node.
Scanning server startup nodes:
Node armi matched; selected.
OBB__TrnWsckBindWellKnown
--- Connecting to armi
--- Port: 62
--- Attempting connect on socket: 147
--- Connect failed: 10055
--- Transport Error: No buffer space available
Server could not be started on node armi; looking for another node.
Scanning server startup nodes:
No host found for requested platforms.
--- WSA_READ message for FD_READ
--- On socket 143
--- Family 2
Spurious blocking READ Message - ignoring
|
| 2398.15 | Server hanging... | PROFET::TAURIAINEN | Matti Tauriainen | Fri Jan 31 1997 05:26 | 7 |
| The server hung again. OBB$AGENT seemed to have lots of channels open
(BG driver, so it's probably TCP/IP sockets). It could also be a timing
problem - maybe the client sends invocatins too rapidly.
I'll put the output from show process + the SDA log to next replies.
--Matti
|
| 2398.16 | obb$agent show process output | PROFET::TAURIAINEN | Matti Tauriainen | Fri Jan 31 1997 05:27 | 21 |
| Process OBB$AGENT
State HIB Working set 257
Cur/base priority 13/8 Virtual pages 2028
Current PC 80000304 CPU time 0 00:00:07.58
Current PSL 0000001B Direct I/O 8227
Current user SP 7FA5D850 Buffered I/O 7517
PID 0000088C Page faults 8607
UIC [SYSTEM] Event flags E0000001
A0000002
$4$DRA0:[SYS0.SYSCOMMON.][SYSEXE]OBB$AGENT.EXE;2
|
| 2398.17 | SDA log | PROFET::TAURIAINEN | Matti Tauriainen | Fri Jan 31 1997 05:28 | 206 |
| SDA> show process/images
Process index: 008C Name: OBB$AGENT Extended PID: 0000088C
--------------------------------------------------------------
Process activated images
------------------------
IMCB Start End Sym Vect Type Image Name Major ID,Minor ID
-------- -------- -------- -------- ------------ -----------------------------
7FF721F8 00010000 000817FF 00000000 MAIN OBB$AGENT 0,0
7FF722D8 00128000 0026B3FF 001595D0 GLBL SHR OBB$SHR 2,1
7FF74300 000E6000 001267FF 000E76E0 GLBL SHR SECURESHR 1,3
7FF743E0 00082000 000E47B8 00085BF0 GLBL PRT SHR SECURESHRP 1,3
7FF74AD0 82E909A0 82E9D6B0 82E909A0 GLBL SYS$BASE_IMAGE 125,16188654
7FF745A0 7FEC2000 7FF23FFF 7FED71B0 GLBL SHR DECC$SHR 1,1
Base End ImageOff Section Type
80566000 80683200 00000000 System Resident Code
7FEC2000 7FEDD000 00120000 Shareable Address Data
7FEE2000 7FEEBA00 00140000 Read-Write Data
7FEF2000 7FEF6E00 00150000 Shareable Read-Only Data
7FF02000 7FF02200 00160000 Read-Write Data
Press RETURN for more.
Process index: 008C Name: OBB$AGENT Extended PID: 0000088C
--------------------------------------------------------------
IMCB Start End Sym Vect Type Image Name Major ID,Minor ID
-------- -------- -------- -------- ------------ -----------------------------
7FF12000 7FF16600 00170000 Demand Zero Data
7FF22000 7FF23C00 00180000 Read-Write Data
7FF74140 7FC32000 7FD93FFF 7FC80370 GLBL SHR DPML$SHR 1,0
Base End ImageOff Section Type
804A0000 80564600 00090000 System Resident Code
7FC32000 7FC68600 00000000 Shareable Read-Only Data
7FC72000 7FC83E00 00040000 Shareable Address Data
7FC92000 7FC92400 00060000 Read-Write Data
7FCA2000 7FCBE200 00070000 Shareable Read-Only Data
7FD92000 7FD93000 00160000 Read-Write Data
7FF74060 7FBB2000 7FBE3FFF 7FBC2000 GLBL SHR LIBOTS 1,3
Base End ImageOff Section Type
8048E000 8049CC00 00020000 System Resident Code
7FBB2000 7FBB4600 00000000 Shareable Read-Only Data
7FBC2000 7FBC3C00 00010000 Shareable Address Data
7FBE2000 7FBE2200 00030000 Read-Write Data
Press RETURN for more.
Process index: 008C Name: OBB$AGENT Extended PID: 0000088C
--------------------------------------------------------------
IMCB Start End Sym Vect Type Image Name Major ID,Minor ID
-------- -------- -------- -------- ------------ -----------------------------
7FF74830 7FBF2000 7FC23FFF 7FBF2540 GLBL SHR CMA$TIS_SHR 1,1
Base End ImageOff Section Type
8049E000 8049EE00 00020000 System Resident Code
7FBF2000 7FBF2A00 00000000 Shareable Address Data
7FC02000 7FC02200 00010000 Read-Write Data
7FC22000 7FC22200 00030000 Read-Write Data
7FF74220 7FB52000 7FBA3FFF 7FB5C3C0 GLBL SHR LIBRTL 1,1
Base End ImageOff Section Type
80400000 8048E000 00000000 System Resident Code
7FB52000 7FB5E800 00090000 Shareable Address Data
7FB62000 7FB63000 000A0000 Read-Write Data
7FB72000 7FB7DC00 000B0000 Shareable Read-Only Data
7FB82000 7FB82200 000C0000 Read-Write Data
7FB92000 7FB93000 000D0000 Demand Zero Data
7FBA2000 7FBA3600 000E0000 Read-Write Data
7FF744C0 82E82AF8 82E840E8 82E82AF8 GLBL SYS$PUBLIC_VECTORS 69,8183133
Press RETURN for more.
Process index: 008C Name: OBB$AGENT Extended PID: 0000088C
--------------------------------------------------------------
IMCB Start End Sym Vect Type Image Name Major ID,Minor ID
-------- -------- -------- -------- ------------ -----------------------------
7FF74DC0 002BE000 0031E9FF 002CE970 MRGD SHR OBB$TRANSPORT_DNET 0,0
7FF74EA0 00320000 00382068 00340100 GLBL PRT SHR OBB$PRVSHR 2,1
7FF753B0 00394000 003F51FF 003A4FD0 MRGD SHR OBB$SECURITY 0,0
7FF749F0 00406000 004669FF 004168F0 MRGD SHR OBB$TRANSPORT_TCP 0,0
7FF75730 00468000 004D83FF 004A8000 MRGD SHR UCX$IPC_SHR 0,0
7FF75650 004DA000 0056A5FF 0053A010 GLBL SHR MULTINET_SOCKET_LIBRARY 1,10
7FF74910 0056C000 005CC9FF 0057CC40 MRGD SHR OBB$AGENT_CLIENT 0,0
Total images = 18 Pages allocated = 990
SDA> show call
Call Frame Information
----------------------
Stack Frame Procedure Descriptor
Flags: Base Register = FP, No Jacket, Native
Procedure Entry: 00000000 00224710
Return address on stack = 00000000 001E1C10
Registers saved on stack
------------------------
7FA5D868 00000000 001508B0 Saved R2
7FA5D870 00000000 00000000 Saved R3
7FA5D878 00000000 7FA5DA30 Saved R4
7FA5D880 00000000 048B8001 Saved R5
7FA5D888 00000000 0026D800 Saved R6
7FA5D890 00000000 0027B928 Saved R7
7FA5D898 00000000 048B8001 Saved R8
7FA5D8A0 FFFFFFFF 890072C8 Saved R9 EXE$CATCH_ALL
7FA5D8A8 00000000 7FA5D8B0 Saved R29
Result: 32-bit zero extended integer returned in register R0
Argument List:
Press RETURN for more.
Call Frame Information
----------------------
SDA> show call/next
Call Frame Information
----------------------
Stack Frame Procedure Descriptor
Flags: Base Register = FP, No Jacket, Native
Procedure Entry: 00000000 001E1AE0
Return address on stack = 00000000 001E1DF8
Registers saved on stack
------------------------
7FA5D8D0 00000000 00150840 Saved R2
7FA5D8D8 00000000 7FA5DA30 Saved R3
7FA5D8E0 00000000 048B8001 Saved R4
7FA5D8E8 00000000 0027B928 Saved R5
7FA5D8F0 00000000 7FEE2020 Saved R6
7FA5D8F8 00000000 048B8001 Saved R7
7FA5D900 00000000 00000000 Saved R8
7FA5D908 00000000 7FA5D910 Saved R29
Result: 32-bit zero extended integer returned in register R0
Argument List:
SDA> show call/next
Call Frame Information
----------------------
Stack Frame Procedure Descriptor
Flags: Base Register = FP, No Jacket, Native
Procedure Entry: 00000000 001E1D40
Return address on stack = 00000000 000508FC
Registers saved on stack
------------------------
7FA5D928 00000000 00030188 Saved R2
7FA5D930 00000000 048B8001 Saved R3
7FA5D938 00000000 7FEE2020 Saved R4
7FA5D940 00000000 00040008 Saved R5 UCB$M_SUPMVMSG+00008
7FA5D948 00000000 7FA5D960 Saved R29
Result: 32-bit zero extended integer returned in register R0
Argument List:
SDA> show call
Call Frame Information
----------------------
Stack Frame Procedure Descriptor
Flags: Base Register = FP, No Jacket, Native
Procedure Entry: 00000000 00224710
Return address on stack = 00000000 001E1C10
Registers saved on stack
------------------------
7FA5D868 00000000 001508B0 Saved R2
7FA5D870 00000000 00000000 Saved R3
7FA5D878 00000000 7FA5DA30 Saved R4
7FA5D880 00000000 048B8001 Saved R5
7FA5D888 00000000 0026D800 Saved R6
7FA5D890 00000000 0027B928 Saved R7
7FA5D898 00000000 048B8001 Saved R8
7FA5D8A0 FFFFFFFF 890072C8 Saved R9 EXE$CATCH_ALL
7FA5D8A8 00000000 7FA5D8B0 Saved R29
Result: 32-bit zero extended integer returned in register R0
Argument List:
Press RETURN for more.
Call Frame Information
----------------------
SDA> show call/next
Call Frame Information
----------------------
Stack Frame Procedure Descriptor
Flags: Base Register = FP, No Jacket, Native
Procedure Entry: 00000000 001E1AE0
Return address on stack = 00000000 001E1DF8
Registers saved on stack
------------------------
7FA5D8D0 00000000 00150840 Saved R2
7FA5D8D8 00000000 7FA5DA30 Saved R3
7FA5D8E0 00000000 048B8001 Saved R4
7FA5D8E8 00000000 0027B928 Saved R5
7FA5D8F0 00000000 7FEE2020 Saved R6
7FA5D8F8 00000000 048B8001 Saved R7
7FA5D900 00000000 00000000 Saved R8
7FA5D908 00000000 7FA5D910 Saved R29
Result: 32-bit zero extended integer returned in register R0
Argument List:
|
| 2398.18 | | REQUE::BOWER | Peter Bower, ObjectBroker | Sat Feb 01 1997 17:24 | 17 |
| How many sockets does the agent have open ?
The sda log indicates that the agent is waiting in its transport
event loop. It calls sys$hiber and waits for an ast to occur due
to a transport event.
I suggest enabling transport events on the agent to see if there
are any differences due to multinet behavior.
$ define/system/exec obb_trace_flags "INF"
$ define/system/exec obb_trace <filename on disk with space>
$ @sys$startup:obb$agent
Check to make sure the log is created, then
$deassign/system/exec obb_trace_flags
$deassign/system/exec obb_trace
|
| 2398.19 | Sockets | PROFET::TAURIAINEN | Matti Tauriainen | Sun Feb 02 1997 03:22 | 12 |
| re: .18
> How many sockets does the agent have open ?
About forty (don't remember the exact number - the hang was on friday).
We'll try and trace the transport events...
Thanks,
--Matti
|
| 2398.20 | Server log - Tranport level communication failed | EEMELI::JTOUKKARI | | Fri Feb 07 1997 06:15 | 33 |
| Hi!
As Matti told to you, we still have this problem open. I got the new
server log where we can notice the following error, when this hang
happens:
<normal log.....>
.....
--- Receiving on socket 21
--- Receving data on socket - len is 20
--- Recv failed on socket with error: 54
--- Deleting connection on socket: 21
--- Error reading from socket 21
--- Error status: %OBB-E-INV_COMMFAIL, Transport level communication
failed.
--- Error sstub status: %OBB-S-SUCCESS, Successful completion.
--- Error list: <1>
000. :Facility: OBB_INV :Severity: Error
:Status: (LOBIT) %X048BB282
<Log continues...>
.....
Does this help you?? We are running on server Alpha OpenVMS 6.2-1H3,
TGV Multinet 4.0 Rev A and ORB 2.7 .The client side is still running
ORB 2.5B.
cheers,
Jukka
|
| 2398.21 | | RECV::SLAVIN | | Fri Feb 07 1997 09:03 | 2 |
| I assume you know that we do not support TGV as a transport. Have you
tried the same operations with a supported ObjectBroker transport?
|