• Home
  • /
  • MLLP Question

MLLP Question

Homepage › Clovertech Forums › Read Only Archives › Cloverleaf › Cloverleaf › MLLP Question

  • Creator
    Topic
  • October 25, 2013 at 3:58 pm #53889
    Lori Owen
    Participant

    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

  • Creator
    Topic
Viewing 6 reply threads
  • Author
    Replies
    • October 25, 2013 at 4:25 pm #79401
      Lori Owen
      Participant

      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
          Jim Kosloskey
          Participant

          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
          Lori Owen
          Participant

          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
          Jim Kosloskey
          Participant

          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
          Lori Owen
          Participant

          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
          Max Drown (Infor)
          Keymaster

          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 #79407
          Michael Hertel
          Participant

          Quote:

          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?

      • Author
        Replies
      Viewing 6 reply threads
      • The forum ‘Cloverleaf’ is closed to new topics and replies.

      Forum Statistics

      Registered Users
      5,117
      Forums
      28
      Topics
      9,292
      Replies
      34,435
      Topic Tags
      286
      Empty Topic Tags
      10