dcmtls_scp_pool_tls test instability?

All other questions regarding DCMTK

Moderator: Moderator Team

Post Reply
Message
Author
TArvin
Posts: 4
Joined: Thu, 2021-04-08, 06:22

dcmtls_scp_pool_tls test instability?

#1 Post by TArvin » Thu, 2021-04-08, 11:22

I'm working on a dcmtk RPM package which I hope can be part of the EPEL 7 package collection.

When I build dcmtk 3.6.6, around ½ the time, the check step fails like this:
The following tests FAILED:
153 - dcmtls_scp_pool_tls (Failed)
Errors while running CTest
I'm trying to find out why. Meanwhile: Is there a known issue with that test?

--
Troels Arvin

TArvin
Posts: 4
Joined: Thu, 2021-04-08, 06:22

Re: dcmtls_scp_pool_tls test instability?

#2 Post by TArvin » Thu, 2021-04-08, 12:03

Here's more about the situation where test 153 fails. I cannot find a way to attach it as a file, so I'll copy-paste the excerpt from Testing/Temporary/LastTest.log

--
Regards,
Troels

Code: Select all

153/263 Testing: dcmtls_scp_pool_tls
153/263 Test: dcmtls_scp_pool_tls
Command: "/usr/bin/cmake" "-DDCMTK_CTEST_TESTCASE_COMMAND=/home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/bin/dcmtls_tests" "-DDCMTK_CTEST_TEST_NAME=dcmtls_scp_pool_tls" "-P" "/home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/CMakeFiles/dcmtkCTestRun.cmake"
Directory: /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests
"dcmtls_scp_pool_tls" start time: Apr 08 12:30 CEST
Output:
----------------------------------------------------------
2021-04-08 12:30:45.676 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.680 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.683 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.687 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.690 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.692 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.695 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.698 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.701 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.703 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.706 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.709 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.712 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.714 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.717 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.720 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.723 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.725 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.728 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:45.731 DEBUG: Configured a total of 1 presentation contexts for SCU
2021-04-08 12:30:50.731 DEBUG: Request Parameters:
2021-04-08 12:30:50.731 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.731 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.731 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.731 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.731 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.731 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.731 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.731 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.731 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.731 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.731 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.731 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.731 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.731 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.731 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.731 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.731 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.731 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.731 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.731 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.731 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.731 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.732  INFO: Requesting Association
2021-04-08 12:30:50.732 DEBUG: Request Parameters:
2021-04-08 12:30:50.732 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.732 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.732 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.732 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.732 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.732 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.732 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.732 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.732 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.732 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.732 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.732 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.732 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.732 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.732 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.732 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.732 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.732 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.732 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.732 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.732 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.732 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.733  INFO: Requesting Association
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.735  INFO: Requesting Association
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.736  INFO: Requesting Association
2021-04-08 12:30:50.736 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.736 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.736 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.737  INFO: Requesting Association
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.738  INFO: Requesting Association
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.739  INFO: Requesting Association
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.740  INFO: Requesting Association
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.741  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.742  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.743  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.744  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.745  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.745  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.746  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.746  INFO: Requesting Association
2021-04-08 12:30:50.746 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.746 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.746 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.747 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.747 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.747 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.747 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.747 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.747 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.747 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.747 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.747 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.747 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.747 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.747 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.747 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.747 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.747 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.736 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.736 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.736 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.748  INFO: Requesting Association
2021-04-08 12:30:50.748 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.748 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.748 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.737 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.737 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.737 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.737 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.737 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.737 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.738 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.738 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.738 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.734 DEBUG: Request Parameters:
2021-04-08 12:30:50.734 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.734 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.734 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.734 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.734 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.734 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.734 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.734 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.734 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.734 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.734 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.734 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.734 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.734 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.734 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.734 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.734 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.734 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.734 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.734 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.734 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.749  INFO: Requesting Association
2021-04-08 12:30:50.749 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.749 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.749 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.741 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.741 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.741 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.741 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.741 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.741 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.741 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.741 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.741 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.749  INFO: Requesting Association
2021-04-08 12:30:50.735 DEBUG: Request Parameters:
2021-04-08 12:30:50.735 DEBUG: ====================== BEGIN A-ASSOCIATE-RQ =====================
2021-04-08 12:30:50.735 DEBUG: Our Implementation Class UID:      1.2.276.0.7230010.3.0.3.6.6
2021-04-08 12:30:50.735 DEBUG: Our Implementation Version Name:   OFFIS_DCMTK_366
2021-04-08 12:30:50.735 DEBUG: Their Implementation Class UID:    
2021-04-08 12:30:50.735 DEBUG: Their Implementation Version Name: 
2021-04-08 12:30:50.735 DEBUG: Application Context Name:    1.2.840.10008.3.1.1.1
2021-04-08 12:30:50.735 DEBUG: Calling Application Name:    REQUESTOR
2021-04-08 12:30:50.735 DEBUG: Called Application Name:     ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Responding Application Name: ACCEPTOR
2021-04-08 12:30:50.735 DEBUG: Our Max PDU Receive Size:    16856
2021-04-08 12:30:50.735 DEBUG: Their Max PDU Receive Size:  0
2021-04-08 12:30:50.735 DEBUG: Presentation Contexts:
2021-04-08 12:30:50.735 DEBUG:   Context ID:        1 (Proposed)
2021-04-08 12:30:50.735 DEBUG:     Abstract Syntax: =VerificationSOPClass
2021-04-08 12:30:50.735 DEBUG:     Proposed SCP/SCU Role: SCU
2021-04-08 12:30:50.735 DEBUG:     Proposed Transfer Syntax(es):
2021-04-08 12:30:50.735 DEBUG:       =LittleEndianImplicit
2021-04-08 12:30:50.735 DEBUG: Requested Extended Negotiation: none
2021-04-08 12:30:50.735 DEBUG: Accepted Extended Negotiation:  none
2021-04-08 12:30:50.735 DEBUG: Requested User Identity Negotiation: none
2021-04-08 12:30:50.735 DEBUG: User Identity Negotiation Response:  none
2021-04-08 12:30:50.735 DEBUG: ======================= END A-ASSOCIATE-RQ ======================
2021-04-08 12:30:50.750  INFO: Requesting Association
2021-04-08 12:30:50.747 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.750 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.750 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.750 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.742 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.742 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.742 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.750 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.750 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.750 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.750 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.739 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.739 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.739 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.750 DEBUG: Association Request Failed: 0006:031b Failed to establish association
2021-04-08 12:30:50.750 DEBUG: 0006:0317 Peer aborted Association (or never connected)
2021-04-08 12:30:50.750 DEBUG: 0006:031c TCP Initialization Error: Connection refused
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
Test results for module 'dcmtls': 0 succeeded, 1 failed.
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
2021-04-08 12:30:50.751 DEBUG: Cleaning up internal association and network structures
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:144: negotiateAssociation().good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
FAILED test 'dcmtls_scp_pool_tls' at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc:425: (*it3)->result.good()
CMake Error at /home/tarvin/rpmbuild/BUILD/dcmtk-3.6.6/CMakeFiles/dcmtkCTestRun.cmake:17 (message):
  Test command returned: 1


<end of output>
Test time =   6.12 sec
----------------------------------------------------------
Test Failed.
"dcmtls_scp_pool_tls" end time: Apr 08 12:30 CEST
"dcmtls_scp_pool_tls" time elapsed: 00:00:06
----------------------------------------------------------

Michael Onken
DCMTK Developer
Posts: 1813
Joined: Fri, 2004-11-05, 13:47
Location: Oldenburg, Germany
Contact:

Re: dcmtls_scp_pool_tls test instability?

#3 Post by Michael Onken » Thu, 2021-04-08, 12:03

Hi Troels,

so far no instability I know of. Since the test uses client and server threads that interact with each other, I guess there is a timing problem.
My first guess is that the problem is the following line in the test (dcmtk/dcmnet/tests/tpool.cc):

Code: Select all

OFStandard::sleep(5)
Since this can be interrupted at any moment, you could try to replace it with

Code: Select all

// After the existing include section:
#include "dcmtk/ofstd/oftimer.h"

// And then, instead of OFStandard::sleep(5):
OFTimer timer;
while (timer.getDiff() < 5) OFStandard::sleep(1);
Best regards,
Michael

TArvin
Posts: 4
Joined: Thu, 2021-04-08, 06:22

Re: dcmtls_scp_pool_tls test instability?

#4 Post by TArvin » Thu, 2021-04-08, 14:18

With the code change, it still fails sometimes. I ran 10 builds, and 3 of them failed :-(

Michael Onken
DCMTK Developer
Posts: 1813
Joined: Fri, 2004-11-05, 13:47
Location: Oldenburg, Germany
Contact:

Re: dcmtls_scp_pool_tls test instability?

#5 Post by Michael Onken » Thu, 2021-04-08, 16:02

Hi,

thank you for trying this. I just realized the problem is in dcmtk/dcmtls/tests/tscuscptls.cc which a colleague wrote (I did not notice it before thats way the "tls" slipped through my attention when reading your report), sorry. I / we have to check the tscuscptls.cc test as well. My first try would be, to enter force_sleep(5) wherever you have force_sleep(1) in the code of tscuscptls.cc. But that's only a wild guess right now.

Best regards,
Michael

TArvin
Posts: 4
Joined: Thu, 2021-04-08, 06:22

Re: dcmtls_scp_pool_tls test instability?

#6 Post by TArvin » Thu, 2021-04-08, 23:26

I undid the previous adjustment in dcmtk/dcmnet/tests/tpool.cc and adjusted instead as expressed in the below diff. That didn't improve matters; it actually increased the fraction of failing test-runs.

Code: Select all

--- orig-dcmtk-3.6.6/dcmnet/tests/tscusession.cc	2021-04-08 13:21:51.143804449 +0200
+++ patched-dcmtk-3.6.6/dcmnet/tests/tscusession.cc	2021-04-08 21:55:34.042369617 +0200
@@ -280,7 +280,7 @@
 void scu_session(const OFString& called_ae_title, const OFString& sop_class)
 {
     // Make sure server is up
-    force_sleep(1);
+    force_sleep(5);
     // Basic configuration
     SessionSCU scu;
     scu.setAETitle("TEST_SCU");
@@ -334,13 +334,13 @@
 
     // Send FIND, and wait to be sure SCP has time to exit
     scu_session("FIND_SESSION", UID_FINDPatientRootQueryRetrieveInformationModel);
-    force_sleep(1);
+    force_sleep(5);
     scp.join();
 
     // Send MOVE, and wait to be sure SCP has time to exit
     scp.start();
     scu_session("MOVE_SESSION", UID_MOVEPatientRootQueryRetrieveInformationModel);
-    force_sleep(1);
+    force_sleep(5);
     scp.join();
 
     // TODO: Test C-GET
diff -urb orig-dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc patched-dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc
--- orig-dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc	2021-04-08 13:21:51.250807548 +0200
+++ patched-dcmtk-3.6.6/dcmtls/tests/tscuscptls.cc	2021-04-08 21:55:51.133864237 +0200
@@ -318,7 +318,7 @@
     scp.start();
 
     // Ensure server is up and listening
-    force_sleep(1);
+    force_sleep(5);
 
     // Configure SCU and run it against SCP
     DcmTLSSCU scu;
@@ -386,7 +386,7 @@
     pool.start();
 
     // Ensure server is up and listening
-    force_sleep(1);
+    force_sleep(5);
 
     OFVector<TestTLSSCU*> scus(20);
     OFVector<DcmTLSTransportLayer*> scuTlsLayers;

Michael Onken
DCMTK Developer
Posts: 1813
Joined: Fri, 2004-11-05, 13:47
Location: Oldenburg, Germany
Contact:

Re: dcmtls_scp_pool_tls test instability?

#7 Post by Michael Onken » Fri, 2021-04-09, 08:01

Ok thank you very much. Probably my colleague who wrote the test should check this more thoroughly. We'll get back to you.

Best regards
Michael

Marco Eichelberg
OFFIS DICOM Team
OFFIS DICOM Team
Posts: 1280
Joined: Tue, 2004-11-02, 17:22
Location: Oldenburg, Germany
Contact:

Re: dcmtls_scp_pool_tls test instability?

#8 Post by Marco Eichelberg » Sun, 2021-04-11, 17:40

I can confirm that there is an instability in this test case. We see this in our nightly build dashboard, where this test case fails sometimes, but not as often as you report. As of yesterday, 5 out of 117 builds report a failure of this test.

I have not yet had the time to analyze the problem in detail, but it seems that the SCP thread fails to open the socket for incoming network connections. This may be due to the same port number being used somewhere else, or having been used by another process a very short time ago (if I remember correctly, the operating system takes a bit of time to clean up a listen socket).

Post Reply

Who is online

Users browsing this forum: Google [Bot] and 1 guest