Forum Replies Created
-
AuthorReplies
-
Thanks for the feedback. In the meantime, we set the thread back to ‘Server’ and checked the ‘Close after write’ box. So far we have maintained a consistent connection. Does this support the theory that the vendor is not sending a disconnect? Should the VPN be sending the disconnect or the actual application/service sending the messages?
Thanks,
Lori
Our alerts call a batch proc that contains the following commands…
hcicmd -p UCADT -c “docutap_adt_in pstop”
call wait 20
hcicmd -p UCADT -c “docutap_adt_in pstart”
We also had another site on our production server that had some duplicate thread names to our main site. We have removed the other site. We are kind of grasping at straws but hopefully will get this resolved soon :D.
Thanks!
Hi,
We are on Windows 2003 – soon to be upgrading to 6.0 on Windows 2008. Can’t happen soon enough. We crashed again yesterday and I did an hcidbinit -AC during clean-up.
The only thing we have changed is adding two alerts to do a Stop/Start on two specific threads if we have had no activity for 30 minutes. These are VPN connections and we believe the vendor is not releasing our ports after sending messages. When they try to send the next message they get a socket error and the connection is not ‘opened’ until we bounce our threads. Sometimes bouncing their side of the VPN works as well. The vendor does not accept acks and does not log any of their messaging.
This is the only thing we can point to and we have Infor taking a look as well. We are thinking about setting the inbound threads up as multi-server so we can eliminate the alerts.
Thanks!
Lori
They fixed the beginning and I will ask them to fix the end. Hopefully this will correct it. Thanks again for your help!
Thanks Jim. This is a tcp/ip connection. The vendor is able to send ORM messages just fine but there is something going on with the ADT. The dump shows an extra character at the beginning of the message – maybe this is the issue?
here is the actual log with the message they are trying to send…
[pti :sche:DBUG/2:docutap_adt_in:10/25/2013 12:18:38] Thread 3 has been enabled
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:38] Thread has 1 ready events.
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Processing SOCKET (PDL server) event 0x00A9C7E8
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Calling cb 0x41fee1
[pdl :read:DBUG/2:docutap_adt_in:10/25/2013 12:18:38] Events: E 0, R 8, W 0
[pdl :PDL :DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Clearing PDL listen event
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Unregistering SOCKET (PDL server) event 0x00A9C7E8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] evUnregister SOCKET event 0xA9C7E8 for tid 3
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Closed WSAEvent 1032 on socket 1056
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] accepted fd = 948
[pdl :PDL :DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Creating PDL listen event on fd 948
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Registering SOCKET (PDL server) event 0x00A9C7E8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Registering SOCKET event for tid 3
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Associated WSAEvent 1056 with READ condition on socket 948 (0x3d)
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Calling Tcl procedure: hci_pd.default.open-ok
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] with args: {{client “10.240.4.79”} {status ok}}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Tcl procedure hci_pd.default.open-ok returns ”
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:38] PDL changed states: old 4, new 0
[pdl :init:DBUG/1:docutap_adt_in:10/25/2013 12:18:38] PDL did initialize: code = 0
[pd :pdtd:INFO/1:docutap_adt_in:10/25/2013 12:18:38] Set driver status to PD_STATUS_UP
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:38] Thread has 0 ready events left.
[pti :sche:DBUG/2:docutap_adt_in:10/25/2013 12:18:38] Thread 3 has been enabled
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:38] Thread has 1 ready events.
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Processing ACTIVE_TIMER () event 0x00A9BCB8
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Calling cb 0x525053
[msi :msi :DBUG/1:docutap_adt_in:10/25/2013 12:18:38] msiExportStats: export for thread: docutap_adt_in
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Unregistering ACTIVE_TIMER () event 0x00A9BCB8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] evUnregister ACTIVE_TIMER event 0xA9BCB8 for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] diag timeval alloc 0x00AA4C38
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] diag dqe alloc 0x00A9BE00
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Registering ACTIVE_TIMER () event 0x00A9BCB8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Registering ACTIVE_TIMER event for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] diag timeval free 0x00AA4C38
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:38] Thread has 0 ready events left.
[pti :sche:INFO/2:docutap_adt_in:10/25/2013 12:18:38] Performing apply callback for thread 3
[msi :msi :DBUG/1:docutap_adt_in:10/25/2013 12:18:38] msiExportStats: export for thread: docutap_adt_in
[pti :sche:DBUG/2:docutap_adt_in:10/25/2013 12:18:38] Thread 3 has been enabled
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:38] Thread has 1 ready events.
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:38] Processing SOCKET (PDL server) event 0x00A9C7E8
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:38] Calling cb 0x41fee1
[pdl :read:DBUG/2:docutap_adt_in:10/25/2013 12:18:38] Events: E 0, R 8, W 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] read 355 bytes
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] input buffer accepted 355 bytes, now 355
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 0b 0b 4d 53 48 7c 5e 7e |..MSH|^~|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 2f 26 7c 44 6f 63 75 54 |/&|DocuT|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 41 50 7c 44 6f 63 75 54 |AP|DocuT|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 41 50 7c 53 79 6e 61 70 |AP|Synap|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 73 65 50 61 63 73 7c 53 |sePacs|S|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 79 6e 61 70 73 65 50 61 |ynapsePa|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 63 73 7c 32 30 31 33 31 |cs|20131|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 30 32 35 31 31 31 38 33 |02511183|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 37 7c 7c 41 44 54 5e 41 |7||ADT^A|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 32 38 7c 33 31 30 32 35 |28|31025|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 31 31 31 38 33 37 30 30 |11183700|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 30 30 33 32 38 37 32 7c |0032872||
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 50 7c 32 2e 33 2e 31 0d |P|2.3.1.|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 45 56 4e 7c 41 32 38 7c |EVN|A28||
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 32 30 31 33 31 30 32 35 |20131025|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 31 31 31 38 33 37 0d 50 |111837.P|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 49 44 7c 31 7c 33 32 38 |ID|1|328|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 37 32 7c 7c 7c 54 65 73 |72|||Tes|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 74 5e 50 61 72 64 65 65 |t^Pardee|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 34 5e 7c 7c 32 30 30 30 |4^||2000|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 30 31 30 31 7c 4d 7c 7c |0101|M|||
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 7c 38 30 30 20 6e 20 6a ||800 n j|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 75 73 74 69 63 65 20 73 |ustice s|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 74 5e 5e 48 45 4e 44 45 |t^^HENDE|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 52 53 4f 4e 56 49 4c 4c |RSONVILL|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 45 5e 4e 43 5e 32 38 37 |E^NC^287|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 39 31 7c 7c 38 32 38 2d |91||828-|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 35 35 35 2d 31 32 33 34 |555-1234|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 7c 7c 7c 7c 7c 7c 39 39 |||||||99|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 39 39 39 39 39 39 39 0d |9999999.|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 50 56 31 7c 50 0d 47 54 |PV1|P.GT|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 31 7c 31 7c 33 32 38 37 |1|1|3287|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 32 7c 54 65 73 74 5e 50 |2|Test^P|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 61 72 64 65 65 34 5e 7c |ardee4^||
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 7c 38 30 30 20 6e 20 6a ||800 n j|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 75 73 74 69 63 65 20 73 |ustice s|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 74 5e 5e 48 45 4e 44 45 |t^^HENDE|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 52 53 4f 4e 56 49 4c 4c |RSONVILL|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 45 5e 4e 43 5e 32 38 37 |E^NC^287|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:38] 39 31 7c 38 32 38 2d 35 |91|828-5|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] 35 35 2d 31 32 33 34 7c |55-1234||
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] 7c 32 30 30 30 30 31 30 ||2000010|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] 31 7c 4d 7c 7c 7c 39 39 |1|M|||99|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] 39 39 39 39 39 39 39 0d |9999999.|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] 1c 0b 1c |…|
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] IDLE and 355 bytes but no error: starting READ
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 0, new 1
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Calling Tcl procedure: hci_pd.read
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] with args: {}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Tcl procedure hci_pd.read returns ‘RECEIVE’
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] trying to match phrase: basic-msg
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] multi: phrase #0 rejected; trying next
[pdl :PDL :ERR /0:docutap_adt_in:10/25/2013 12:18:39] no-match: no more phrases to try
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Calling Tcl procedure: read.error
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] with args: {{status error} {type no-match}}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: chop to 1, bolen 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: after memmove: 0 + 354
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: chop to 0, bolen 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: after memmove: 0 + 354
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Tcl procedure read.error returns ‘354’
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 1, new 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] READ operation completed (354 bytes buffered still, 355 before)
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] IDLE and 354 bytes but no error: starting READ
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 0, new 1
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Calling Tcl procedure: hci_pd.read
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] with args: {}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Tcl procedure hci_pd.read returns ‘RECEIVE’
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] trying to match phrase: basic-msg
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] multi: phrase #0 rejected; trying next
[pdl :PDL :ERR /0:docutap_adt_in:10/25/2013 12:18:39] no-match: no more phrases to try
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Calling Tcl procedure: read.error
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] with args: {{status error} {type no-match}}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: chop to 1, bolen 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: after memmove: 0 + 353
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: chop to 351, bolen 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: after memmove: 0 + 2
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Tcl procedure read.error returns ‘2’
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 1, new 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] READ operation completed (2 bytes buffered still, 354 before)
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] IDLE and 2 bytes but no error: starting READ
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 0, new 1
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Calling Tcl procedure: hci_pd.read
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] with args: {}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Tcl procedure hci_pd.read returns ‘RECEIVE’
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] trying to match phrase: basic-msg
[pdl :PDL :DBUG/1:docutap_adt_in:10/25/2013 12:18:39] PDL setting timeout in 15.00 seconds
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag ev alloc 0x00A97580
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag dqe alloc 0x00A97850
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Registering TIMER () event 0x00A97580 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Registering TIMER event for tid 3
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] READ operation in progress (2 bytes buffered still, 2 before)
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:39] Thread has 0 ready events left.
[pti :sche:INFO/2:docutap_adt_in:10/25/2013 12:18:39] Performing apply callback for thread 3
[msi :msi :DBUG/1:docutap_adt_in:10/25/2013 12:18:39] msiExportStats: export for thread: docutap_adt_in
[pti :sche:DBUG/2:docutap_adt_in:10/25/2013 12:18:39] Thread 3 has been enabled
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:39] Thread has 1 ready events.
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Processing SOCKET (PDL server) event 0x00A9C7E8
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:39] Calling cb 0x41fee1
[pdl :read:DBUG/2:docutap_adt_in:10/25/2013 12:18:39] Events: E 32, R 8, W 0
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Unregistering TIMER () event 0x00A97580 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] evUnregister TIMER event 0xA97580 for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag dqe free 0x00A97850
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag ev free 0x00A97580
[pdl :PDL :INFO/0:docutap_adt_in:10/25/2013 12:18:39] read nothing (link closed)
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] input buffer accepted 0 bytes, now 2
[pdl :PDL :ERR /0:docutap_adt_in:10/25/2013 12:18:39] read returned error 0 (No error)
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] multi_phrase_2: status = error
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Calling Tcl procedure: read.error
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] with args: {{status error} {type input-error}}
[pdl :PDL :ERR /0:docutap_adt_in:10/25/2013 12:18:39] PDL signaled exception: code 1, msg device error (remote side probably shut down)
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: chop to 4294967295, bolen 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] pdiIgnoreInput: after clear: 0 + 0
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Tcl procedure read.error returns ‘0’
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 1, new 0
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 0, new 5
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:39] PDL changed states: old 5, new 6
[pdl :PDL :DBUG/1:docutap_adt_in:10/25/2013 12:18:39] Clearing PDL listen event
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Unregistering SOCKET (PDL server) event 0x00A9C7E8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] evUnregister SOCKET event 0xA9C7E8 for tid 3
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:39] Closed WSAEvent 1056 on socket 948
[msg :Msg :DBUG/0:docutap_adt_in:10/25/2013 12:18:39] [0.0.1186] msgFree 0x009EDDF8
[pdl :clse:DBUG/1:docutap_adt_in:10/25/2013 12:18:39] PDL did shutdown: code = 1000
[pdl :open:INFO/1:docutap_adt_in:10/25/2013 12:18:39] Scheduling driver reopen try in 5.0 secs
[pd :pdtd:INFO/1:docutap_adt_in:10/25/2013 12:18:39] Set driver status to PD_STATUS_OPENING
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag timeval alloc 0x00AA4C38
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag ev alloc 0x00AA4C48
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag dqe alloc 0x00A975E0
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Registering TIMER () event 0x00AA4C48 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] Registering TIMER event for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:39] diag timeval free 0x00AA4C38
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:39] Thread has 0 ready events left.
[pti :sche:INFO/2:docutap_adt_in:10/25/2013 12:18:39] Performing apply callback for thread 3
[msi :msi :DBUG/1:docutap_adt_in:10/25/2013 12:18:39] msiExportStats: export for thread: docutap_adt_in
[pti :sche:DBUG/2:docutap_adt_in:10/25/2013 12:18:44] Thread 3 has been enabled
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:44] Thread has 1 ready events.
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Processing TIMER () event 0x00AA4C48
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:44] Calling cb 0x421ac5
[pdl :open:INFO/0:docutap_adt_in:10/25/2013 12:18:44] Driver attempting reopen
[dbi :elog:DBUG/3:docutap_adt_in:10/25/2013 12:18:44] [0.0.1253] Looking for mid in error db
[dbi :rlog:DBUG/3:docutap_adt_in:10/25/2013 12:18:44] [0.0.1253] Looking for mid in recovery db
[msg :Mid :DBUG/3:docutap_adt_in:10/25/2013 12:18:44] Assigned mid [0.0.1253] to msg 009EDDF8
[msg :Msg :DBUG/0:docutap_adt_in:10/25/2013 12:18:44] [0.0.1253] MSG alloc 0x009EDDF8
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:44] PDL changed states: old 6, new 7
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Evaling:
proc hci_pd.write { info } {
global MsgId
keylget info message MsgId
keylset continuations ok write.done
keylset continuations error write.error
keylset continuations timeout
hci_pd_send basic-msg
] $continuations
}
proc write.done {info} {}
proc write.error {info} {
hci_pd_report_exception 1 “write failure”
hci_pd_set_result_code 1
}
proc write.timeout {info} {
global MsgId
msgmetaset $MsgId FLAGS {{proto_timeout 1}}
hci_pd_set_result_code 1
}
proc hci_pd.read {info} {
keylset continuations basic-msg read.done
keylset continuations error read.error
keylset continuations timeout
hci_pd_receive $continuations
}
proc read.done {info} {
keylset accept text
]
keylset accept end [keylget info end]
hci_pd_accept $accept
}
proc read.error {info} {
keylget info type type
switch -exact — $type {
input-error {
hci_pd_report_exception 1 “device error (remote side probably shut down)”
hci_pd_ignore_input -all
}
no-match {
hci_pd_ignore_input 1
hci_pd_ignore_input -until xb
}
default {
hci_pd_report_exception 2 “unknown fail: $type”
hci_pd_ignore_input -all
}
}
}
proc read.timeout {info} {
hci_pd_ignore_input 1
hci_pd_ignore_input -until xb
}
[pdl :PDL :DBUG/2:docutap_adt_in:10/25/2013 12:18:44] PDL changed states: old 7, new 4
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Calling Tcl procedure: hci_pd.initialize
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:44] with args: {}
[pdl :PDL :DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Tcl procedure hci_pd.initialize returns ”
[pdl :PDL :DBUG/1:docutap_adt_in:10/25/2013 12:18:44] Creating PDL listen event on fd 948
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Registering SOCKET (PDL server) event 0x00A9C7E8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Registering SOCKET event for tid 3
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:44] Associated WSAEvent 1056 with READ condition on socket 948 (0x3d)
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Unregistering TIMER () event 0x00AA4C48 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] evUnregister TIMER event 0xAA4C48 for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] diag ev free 0x00AA4C48
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:44] Thread has 0 ready events left.
[pti :sche:DBUG/2:docutap_adt_in:10/25/2013 12:18:44] Thread 3 has been enabled
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:44] Thread has 1 ready events.
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Processing ACTIVE_TIMER () event 0x00A9BCB8
[pti :even:DBUG/1:docutap_adt_in:10/25/2013 12:18:44] Calling cb 0x525053
[msi :msi :DBUG/1:docutap_adt_in:10/25/2013 12:18:44] msiExportStats: export for thread: docutap_adt_in
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Unregistering ACTIVE_TIMER () event 0x00A9BCB8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] evUnregister ACTIVE_TIMER event 0xA9BCB8 for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] diag timeval alloc 0x00AA4C38
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] diag dqe alloc 0x00A9B8E0
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Registering ACTIVE_TIMER () event 0x00A9BCB8 for tid 3
[pti :even:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] Registering ACTIVE_TIMER event for tid 3
[diag:leak:DBUG/0:docutap_adt_in:10/25/2013 12:18:44] diag timeval free 0x00AA4C38
[pti :sche:INFO/1:docutap_adt_in:10/25/2013 12:18:44] Thread has 0 ready events left.
[pti :sche:INFO/2:docutap_adt_in:10/25/2013 12:18:44] Performing apply callback for thread 3
[msi :msi :DBUG/1:docutap_adt_in:10/25/2013 12:18:44] msiExportStats: export for thread: docutap_adt_in
Engine idle — 10/25/2013 12:18:54
Thanks Ron….the tcl commands were what we needed.
Forget the ‘active’ – just need to get the number of threads we currently have in our production and test sites.
Thanks for your help.
-
AuthorReplies