MLLP Question
› Clovertech Forums › Read Only Archives › Cloverleaf › Cloverleaf › MLLP Question
- This topic has 7 replies, 4 voices, and was last updated 11 years, 1 month ago by
Michael Hertel.
-
CreatorTopic
-
October 25, 2013 at 3:58 pm #53889
I have a vendor who is unable to send an ADT successfully to Cloverleaf. Here is the HEX dump of the file he is trying to send. Should there be a hex 0b as a first character in the message? 0000: 0b 4d 53 48 7c 5e 7e 2f 26 7c 44 6f 63 75 54 41 MSH|^~/&|DocuTA
0010: 50 7c 44 6f 63 75 54 41 50 7c 53 79 6e 61 70 73 P|DocuTAP|Synaps
0020: 65 50 61 63 73 7c 53 79 6e 61 70 73 65 50 61 63 ePacs|SynapsePac
0030: 73 7c 32 30 31 33 31 30 31 37 31 31 34 33 30 37 s|20131017114307
0040: 7c 7c 41 44 54 5e 41 32 38 7c 33 31 30 31 37 31 ||ADT^A28|310171
0050: 31 34 33 30 37 30 30 30 30 33 32 38 36 38 7c 50 14307000032868|P
0060: 7c 32 2e 33 2e 31 0d 45 56 4e 7c 41 32 38 7c 32 |2.3.1 EVN|A28|2
0070: 30 31 33 31 30 31 37 31 31 34 33 30 37 0d 50 49 0131017114307 PI
0080: 44 7c 31 7c 33 32 38 36 38 7c 7c 7c 54 65 73 74 D|1|32868|||Test
0090: 5e 50 61 72 64 65 65 31 5e 41 7c 7c 31 39 35 30 ^Pardee1^A||1950
00A0: 30 31 30 31 7c 4d 7c 7c 7c 31 32 33 20 61 20 73 0101|M|||123 a s
00B0: 74 5e 5e 53 49 4f 55 58 20 46 41 4c 4c 53 5e 53 t^^SIOUX FALLS^S
00C0: 44 5e 35 37 31 30 33 7c 7c 36 30 35 2d 35 35 35 D^57103||605-555
00D0: 2d 35 35 35 35 7c 7c 7c 7c 7c 7c 39 39 39 39 39 -5555||||||99999
00E0: 39 39 39 39 0d 50 56 31 7c 50 0d 47 54 31 7c 31 9999 PV1|P GT1|1
00F0: 7c 33 32 38 36 38 7c 54 65 73 74 5e 50 61 72 64 |32868|Test^Pard
0100: 65 65 31 5e 41 7c 7c 31 32 33 20 61 20 73 74 5e ee1^A||123 a st^
0110: 5e 53 49 4f 55 58 20 46 41 4c 4c 53 5e 53 44 5e ^SIOUX FALLS^SD^
0120: 35 37 31 30 33 7c 36 30 35 2d 35 35 35 2d 35 35 57103|605-555-55
0130: 35 35 7c 7c 31 39 35 30 30 31 30 31 7c 4d 7c 7c 55||19500101|M||
0140: 7c 39 39 39 39 39 39 39 39 39 0d |999999999
-
CreatorTopic
-
AuthorReplies
-
-
October 25, 2013 at 4:25 pm #79401
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
-
October 25, 2013 at 4:26 pm #79402
Lori,
Looks like he is using the MLP encoding in a file. If this is indeed a file based exchange, then there is no MLP encoding around the messages.
You need to make sure as the hex dump you provided does not show the end so I am unclear if they are using the ending values for the MLP.
However, I am guessing that is what is going on.
email: jim.kosloskey@jim-kosloskey.com 29+ years Cloverleaf, 59 years IT - old fart.
-
October 25, 2013 at 4:34 pm #79403
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?
-
October 25, 2013 at 5:29 pm #79404
Lori,
yes if this is a mlp_pdl connection then the encoding they have is incorect at the beginning – the end does not look quite correct either.
I would have them go back to the drwing board (give them the hex dump to help them) and have them fix this.
email: jim.kosloskey@jim-kosloskey.com 29+ years Cloverleaf, 59 years IT - old fart.
-
October 25, 2013 at 5:41 pm #79405
They fixed the beginning and I will ask them to fix the end. Hopefully this will correct it. Thanks again for your help!
-
October 28, 2013 at 6:38 pm #79406
A little more info … The beginning of mllp (HL7) messages should be 0b and the end should be 1c 0d. If there is anything between the 1c 0d (end of one message and the next 0b (beginning of the next message), then Cloverleaf will produce an error in the log signifying that junk was found on the stream of data and the junk will be sent to the error database.
-- Max Drown (Infor)
-
October 29, 2013 at 8:35 pm #79407Quote:
then Cloverleaf will produce an error in the log signifying that junk was found on the stream of data and the junk will be sent to the error database.
Is this something new?
-
-
AuthorReplies
- The forum ‘Cloverleaf’ is closed to new topics and replies.
