dcm4chee-arc: dicom echo stucks with Sta5 - Awaiting A-ASSOCIATE-AC or A-ASSOCIATE-RJ

[Ando Malisch]

My freshly installed and started archive (ui is reachable) doesn't respond properly to an dicom echo

root@archiv-dm:/opt/dcm4che/dcm4che/bin# ./storescu -c D...@127.0.0.1:11112

20:32:57,341 INFO  - Initiate connection from 0.0.0.0/0.0.0.0:0 to 127.0.0.1:11112
20:32:57,379 INFO  - Established connection Socket[addr=/127.0.0.1,port=11112,localport=45035]
20:32:57,486 DEBUG - /127.0.0.1:45035->/127.0.0.1:11112(1): enter state: Sta4 - Awaiting transport connection opening to complete
20:32:57,499 INFO  - STORESCU->DM(1) << A-ASSOCIATE-RQ
20:32:57,597 DEBUG - A-ASSOCIATE-RQ[
  calledAET: DM
  callingAET: STORESCU
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.16.0
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 0/0
  PresentationContext[id: 1
    as: 1.2.840.10008.1.1 - Verification SOP Class
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
]
20:32:57,614 DEBUG - STORESCU->DM(1): enter state: Sta5 - Awaiting A-ASSOCIATE-AC or A-ASSOCIATE-RJ PDU

that's it. Nothing happens until I hit ctrl-c.

server.log only tells:

2019-03-22 20:32:57,389 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Accept connection Socket[addr=/127.0.0.1,port=45035,localport=11112]

on the network (two different clients):

storescu

    3 11.632573819    127.0.0.1 → 127.0.0.1    TCP 74 36949 → 11112 [SYN] Seq=0 Win=43690 Len=0 MSS=65495 SACK_PERM=1 TSval=3340209045 TSecr=0 WS=128
    4 11.632612879    127.0.0.1 → 127.0.0.1    TCP 74 11112 → 36949 [SYN, ACK] Seq=0 Ack=1 Win=43690 Len=0 MSS=65495 SACK_PERM=1 TSval=3340209045 TSecr=3340209045 WS=128
    5 11.632650716    127.0.0.1 → 127.0.0.1    TCP 66 36949 → 11112 [ACK] Seq=1 Ack=1 Win=43776 Len=0 TSval=3340209045 TSecr=3340209045
    6 11.771533154    127.0.0.1 → 127.0.0.1    TCP 272 36949 → 11112 [PSH, ACK] Seq=1 Ack=1 Win=43776 Len=206 TSval=3340209184 TSecr=3340209045
    7 11.771565089    127.0.0.1 → 127.0.0.1    TCP 66 11112 → 36949 [ACK] Seq=1 Ack=207 Win=44800 Len=0 TSval=3340209184 TSecr=3340209184
 

    8 91.154816066    127.0.0.1 → 127.0.0.1    TCP 66 36949 → 11112 [FIN, ACK] Seq=207 Ack=1 Win=43776 Len=0 TSval=3340288587 TSecr=3340209184
    9 91.195505664    127.0.0.1 → 127.0.0.1    TCP 66 11112 → 36949 [ACK] Seq=1 Ack=208 Win=44800 Len=0 TSval=3340288628 TSecr=3340288587

Enterprise archive GE

   12 227.806097365 192.168.222.57 → 10.10.0.18   TCP 52 53745 → 11112 [SYN, ECN, CWR] Seq=0 Win=8192 Len=0 MSS=1368 WS=256 SACK_PERM=1
   13 227.806196522   10.10.0.18 → 192.168.222.57 TCP 52 11112 → 53745 [SYN, ACK] Seq=0 Ack=1 Win=29200 Len=0 MSS=1460 SACK_PERM=1 WS=128
   14 227.836329654 192.168.222.57 → 10.10.0.18   TCP 40 53745 → 11112 [ACK] Seq=1 Ack=1 Win=65536 Len=0
   15 227.839901082 192.168.222.57 → 10.10.0.18   TCP 285 53745 → 11112 [PSH, ACK] Seq=1 Ack=1 Win=65536 Len=245
   16 227.839975114   10.10.0.18 → 192.168.222.57 TCP 40 11112 → 53745 [ACK] Seq=1 Ack=246 Win=30336 Len=0

   17 287.819938419 192.168.222.57 → 10.10.0.18   TCP 40 53745 → 11112 [FIN, ACK] Seq=246 Ack=1 Win=65536 Len=0
   18 287.860701062   10.10.0.18 → 192.168.222.57 TCP 40 11112 → 53745 [ACK] Seq=1 Ack=247 Win=30336 Len=0

dicom echo is stucked and so am I