*** multiplex: connection terminated on receiving side

    Hi,


    the debug log is helpful. It should show:


    Connected by address:
    (N times) Testing transfer:


    if N equals 0 it gives: No valid presentation contexts found and hangs up.


    This means that either the sending server gives out no transfer syntaxes, or that the receiving end maybe muddles up the list processing e.g., while reading dgatesop.lst (which I believe is reread very often).


    You could try for a test to remove dgatesop.lst from the receiving end: this will cause conquest to accept anything from anybody.


    Marcel

    its a conquest forwarder that is the sending side. i did one change at a time and by restarting the sending side after it begins does nothing. by restarting only the receiving side and not the sending i was able to send the complete study with no errors. this means something on the receiving side by being restarted as a service fixes it temporarily. The logs are kind of hard to make anything out of. when it begins receiving it does have a bunch of good


    CONQUEST2] 20110517 07:10:38 UPACS THREAD 7951: STARTED AT: Tue May 17 07:10:37 2011
    [CONQUEST2] 20110517 07:10:38 A-ASSOCIATE-RQ Packet Dump
    [CONQUEST2] 20110517 07:10:38 Calling Application Title : "CONQUEST1 "
    [CONQUEST2] 20110517 07:10:38 Called Application Title : "CONQUEST2 "
    [CONQUEST2] 20110517 07:10:38 Application Context : "1.2.840.10008.3.1.1.1", PDU length: 16384
    [CONQUEST2] 20110517 07:10:38 Number of Proposed Presentation Contexts: 2
    [CONQUEST2] 20110517 07:10:38 Presentation Context 0 "1.2.840.10008.5.1.4.1.1.2" 1
    [CONQUEST2] 20110517 07:10:38 Presentation Context 1 "1.2.840.10008.1.1" 1
    [CONQUEST2] 20110517 07:10:38 Server Command := 0001
    [CONQUEST2] 20110517 07:10:38 Message ID := 019d
    [CONQUEST2] 20110517 07:10:38 0000,0002 26 UI AffectedSOPClassUID "1.2.840.10008.5.1.4.1.1.2"
    [CONQUEST2] 20110517 07:10:38 0000,0100 2 US CommandField 1
    [CONQUEST2] 20110517 07:10:38 0000,0110 2 US MessageID 413
    [CONQUEST2] 20110517 07:10:38 0000,0700 2 US Priority 0
    [CONQUEST2] 20110517 07:10:38 0000,0800 2 US DataSetType 258
    [CONQUEST2] 20110517 07:10:38 0000,1000 42 UI AffectedSOPInstanceU "1.2.840.113704.1.111.2492.1305559672.4783"
    [CONQUEST2] 20110517 07:10:38 0002,0010 22 UI TransferSyntaxUID "1.2.840.10008.1.2.4.90"
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #0 = '1.2.840.10008.1.2'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #1 = '1.2.840.10008.1.2.1'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #2 = '1.2.840.10008.1.2.4.50'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #3 = '1.2.840.10008.1.2.4.51'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #4 = '1.2.840.10008.1.2.4.53'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #5 = '1.2.840.10008.1.2.4.55'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #6 = '1.2.840.10008.1.2.4.57'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #7 = '1.2.840.10008.1.2.4.70'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #8 = '1.2.840.10008.1.2.4.90'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #0 = '1.2.840.10008.1.2'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #1 = '1.2.840.10008.1.2.1'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #2 = '1.2.840.10008.1.2.4.50'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #3 = '1.2.840.10008.1.2.4.51'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #4 = '1.2.840.10008.1.2.4.53'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #5 = '1.2.840.10008.1.2.4.55'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #6 = '1.2.840.10008.1.2.4.57'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #7 = '1.2.840.10008.1.2.4.70'
    [CONQUEST2] 20110517 07:10:38 Testing transfer: '1.2.840.10008.1.2.4.90' against list #8 = '1.2.840.10008.1.2.4.90'
    [CONQUEST2] 20110517 07:10:38



    however when it begins to multiplex i get these only on failing threads while other threads continue to do proper presentation testing/accepting. After 60 sec tcp/ip timeout i believe it picks up again properly to get the rest of the images. I will try deleting the dgatesop.lst once it begins choking on the receiving end and see if it allows it through even without restarting dicom server.


    [CONQUEST2] 20110517 07:10:56 UPACS THREAD 7966: STARTED AT: Tue May 17 07:10:56 2011
    [CONQUEST2] 20110517 07:10:56 A-ASSOCIATE-RQ Packet Dump
    [CONQUEST2] 20110517 07:10:56 Calling Application Title : "CONQUEST1 "
    [CONQUEST2] 20110517 07:10:56 Called Application Title : "CONQUEST2 "
    [CONQUEST2] 20110517 07:10:56 Application Context : "1.2.840.10008.3.1.1.1", PDU length: 16384
    [CONQUEST2] 20110517 07:10:56 Number of Proposed Presentation Contexts: 2
    [CONQUEST2] 20110517 07:10:56 Presentation Context 0 "1.2.840.10008.5.1.4.1.1.2" 1
    [CONQUEST2] 20110517 07:10:56 Presentation Context 1 "1.2.840.10008.1.1" 1
    [CONQUEST2] 20110517 07:10:56 Server Command := 0001
    [CONQUEST2] 20110517 07:10:56 Message ID := 1825
    [CONQUEST2] 20110517 07:10:56 0000,0002 26 UI AffectedSOPClassUID "1.2.840.10008.5.1.4.1.1.2"
    [CONQUEST2] 20110517 07:10:56 0000,0100 2 US CommandField 1
    [CONQUEST2] 20110517 07:10:56 0000,0110 2 US MessageID 6181
    [CONQUEST2] 20110517 07:10:56 0000,0700 2 US Priority 0
    [CONQUEST2] 20110517 07:10:56 0000,0800 2 US DataSetType 258
    [CONQUEST2] 20110517 07:10:56 0000,1000 42 UI AffectedSOPInstanceU "1.2.840.113704.1.111.2492.1305559134.4222"
    [CONQUEST2] 20110517 07:10:56 0002,0010 22 UI TransferSyntaxUID "1.2.840.10008.1.2.4.90"
    [CONQUEST2] 20110517 07:10:57 Connected by address: 0c094b0a
    [CONQUEST2] 20110517 07:10:57 No valid presentation contexts found
    [CONQUEST2] 20110517 07:10:57 InterogateAAssociateRQ failed
    [CONQUEST2] 20110517 07:10:57 *** multiplex: connection terminated

    I wanted to update on this. On the receiver, when deleting the dgatesop.lst file while the service is already started has eliminated the multiplex issues and errors of failed to connect on the receiving side. This leads me to believe that it is the receiving side that muddles up the list while processing images. Is there anyway to read this file into memory once during service start and not re-read during processing that could cause issues?

    I'm pretty convinced it's something to do with JK compression. Switching to j2 seems to be the easiest solution for now and doesn't seem to require any tweaking to eliminate errors on sending/receiving sides.

    using j2 it still happens. just adds delays but never misses images. after tcpip timeout seems to be reached it goes through on new association. there is definitely no issue with format of images/presentation contexts etc. still a mystery. added in new 1.4.16c dgate....here is pacstrouble and some servstatus debug on 4 level


    pacstrouble log during multiplex
    20110630 13:26:06 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:06 ***In 0 presentation contexts
    20110630 13:26:06 ***#Possible transfer syntaxes: 10
    20110630 13:26:06 *** multiplex: connection terminated
    20110630 13:26:09 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:09 ***In 0 presentation contexts
    20110630 13:26:09 ***#Possible transfer syntaxes: 10
    20110630 13:26:09 *** multiplex: connection terminated
    20110630 13:26:12 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:12 ***In 0 presentation contexts
    20110630 13:26:12 ***#Possible transfer syntaxes: 10
    20110630 13:26:12 *** multiplex: connection terminated
    20110630 13:26:15 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:15 ***In 0 presentation contexts
    20110630 13:26:15 ***#Possible transfer syntaxes: 10
    20110630 13:26:15 *** multiplex: connection terminated
    20110630 13:26:18 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:18 ***In 0 presentation contexts
    20110630 13:26:18 ***#Possible transfer syntaxes: 10
    20110630 13:26:18 *** multiplex: connection terminated
    20110630 13:26:21 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:21 ***In 0 presentation contexts
    20110630 13:26:21 ***#Possible transfer syntaxes: 10
    20110630 13:26:21 *** multiplex: connection terminated
    20110630 13:26:24 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:24 ***In 0 presentation contexts
    20110630 13:26:24 ***#Possible transfer syntaxes: 10
    20110630 13:26:24 *** multiplex: connection terminated
    20110630 13:26:27 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:27 ***In 0 presentation contexts
    20110630 13:26:27 ***#Possible transfer syntaxes: 10
    20110630 13:26:27 *** multiplex: connection terminated
    20110630 13:26:30 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:30 ***In 0 presentation contexts
    20110630 13:26:30 ***#Possible transfer syntaxes: 10
    20110630 13:26:30 *** multiplex: connection terminated
    20110630 13:26:33 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:26:33 ***In 0 presentation contexts
    20110630 13:26:33 ***#Possible transfer syntaxes: 10
    20110630 13:26:33 *** multiplex: connection terminated
    20110630 13:32:12 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:32:12 ***In 0 presentation contexts
    20110630 13:32:12 ***#Possible transfer syntaxes: 10
    20110630 13:32:12 *** multiplex: connection terminated
    20110630 13:32:15 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:32:15 ***In 0 presentation contexts
    20110630 13:32:15 ***#Possible transfer syntaxes: 10
    20110630 13:32:15 *** multiplex: connection terminated
    20110630 13:32:18 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:32:18 ***In 0 presentation contexts
    20110630 13:32:18 ***#Possible transfer syntaxes: 10
    20110630 13:32:18 *** multiplex: connection terminated
    20110630 13:32:21 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:32:21 ***In 0 presentation contexts
    20110630 13:32:21 ***#Possible transfer syntaxes: 10
    20110630 13:32:21 *** multiplex: connection terminated


    servstatuslog
    20110630 13:32:12 Connected by address: ea6ea8c0
    20110630 13:32:12 ***No valid presentation contexts/transfer syntax found in 0 candidates
    20110630 13:32:12 ***In 0 presentation contexts
    20110630 13:32:12 ***#Possible transfer syntaxes: 10
    20110630 13:32:12 InterogateAAssociateRQ failed
    20110630 13:32:12 *** multiplex: connection terminated
    20110630 13:32:12 Exportconverter5.0 executes: ifmatch " 7", "*5"
    20110630 13:32:12 Exportconverter5.1 not executed because of previous statement

    nice!


    things have been better. every now and then a connection error happens but fixes itself after 60 second timeouts and retries. once or twice a day max and sometimes not for days. Usually not having any kind of network issues when it happens which is strange.


    20111011 12:22:35 *** ExportConverter5.1: Forward failed to send DICOM image to XXXXXXX 20111011 12:22:35 *** Queue: holding processing of file C:\nradstore\data\1401-33\1.2.840.113619.2.80.975376958.21870.1318346688.1.4.1_0550_000055_1318350039c4f1.dcm 20111011 12:22:35 *** Queue: holding processing of file C:\nradstore\data\1401-33\1.2.840.113619.2.80.975376958.21870.1318346688.1.4.1_0550_000031_1318350039c4f2.dcm 20111011 12:22:35 *** Queue: holding processing of file C:\nradstore\data\1401-33\1.2.840.113619.2.80.975376958.21870.1318346688.1.4.1_0550_000037_1318350039c4f3.dcm 20111011 12:22:35 *** Queue: holding processing of file C:\nradstore\data\1401-33\1.2.840.113619.2.80.975376958.21870.1318346688.1.4.1_0550_000052_1318350039c4f4.dcm 20111011 12:22:35 *** Queue: holding processing of file C:\nradstore\data\1401-33\1.2.840.113619.2.80.975376958.21870.1318346688.1.4.1_0550_000039_1318350040c4f5.dcm 20111011 12:22:35 *** Queue: holding processing of file C:\nradstore\data\1401-33

Participate now!

Don’t have an account yet? Register yourself now and be a part of our community!

Register Yourself Login