Problem when trying C-MOVE from Java Application

575 views

Skip to first unread message

Fabrício H. Simozo

unread,

Nov 8, 2018, 6:23:33 AM11/8/18

to dcm...@googlegroups.com

Hello all.

I am writing a Java application (using DCM4CHE 5.14.1) to communicate with a DCM4CHEE-ARC-LIGHT 5.14.1. I am already able to perform C-FIND and C-GET successfully from my Java app, but I am having trouble implementing a C-MOVE.

When I try the C-MOVE, the Move Association completes, and then the server starts an Association for Store. After both applications reaches "Sta6 - Association established and ready for data transfer" on the Store Association, both just hang. No other response is sent, and no error occurs*.

Application side:

2018-11-08 08:15:24 INFO  Connection:936 - Initiate connection from 0.0.0.0/0.0.0.0:0 to ** SERVER IP **:11112
2018-11-08 08:15:24 INFO  Connection:971 - Established connection Socket[addr=/** SERVER IP **,port=11112,localport=59701]
2018-11-08 08:15:24 DEBUG Association:483 - /** MY IP **:59701->/** SERVER IP **:11112(3): enter state: Sta4 - Awaiting transport connection opening to complete
2018-11-08 08:15:24 INFO  Association:456 - MYAET->DCM4CHEE(3) << A-ASSOCIATE-RQ
2018-11-08 08:15:24 DEBUG Association:457 - A-ASSOCIATE-RQ[
  calledAET: DCM4CHEE
  callingAET: MYAET
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 1/1
  PresentationContext[id: 1
    as: 1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  ExtendedNegotiation[
    sopClass: 1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
    info: [1, ]
  ]
]
2018-11-08 08:15:24 DEBUG Association:483 - MYAET->DCM4CHEE(3): enter state: Sta5 - Awaiting A-ASSOCIATE-AC or A-ASSOCIATE-RJ PDU
2018-11-08 08:15:24 INFO  Connection:95 - Start TCP Listener on /** MY IP **:11112
2018-11-08 08:15:24 DEBUG Connection:98 - Wait for connection on /** MY IP **:11112
2018-11-08 08:15:24 INFO  Association:584 - MYAET->DCM4CHEE(3) >> A-ASSOCIATE-AC
2018-11-08 08:15:24 DEBUG Association:585 - A-ASSOCIATE-AC[
  calledAET: DCM4CHEE
  callingAET: MYAET
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 1/1
  PresentationContext[id: 1
    result: 0 - acceptance
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  ExtendedNegotiation[
    sopClass: 1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
    info: [1, ]
  ]
]
2018-11-08 08:15:24 DEBUG Association:483 - MYAET->DCM4CHEE(3): enter state: Sta6 - Association established and ready for data transfer
2018-11-08 08:15:24 INFO  Dimse:406 - MYAET->DCM4CHEE(3) << 1:C-MOVE-RQ[pcid=1, prior=0
  cuid=1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
2018-11-08 08:15:24 DEBUG Dimse:407 - Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.2.1.2] AffectedSOPClassUID
(0000,0100) US [33] CommandField
(0000,0110) US [1] MessageID
(0000,0600) AE [MYAET] MoveDestination
(0000,0700) US [0] Priority
(0000,0800) US [0] CommandDataSetType


2018-11-08 08:15:24 DEBUG Dimse:409 - Dataset:
(0008,0018) UI [2.25.223575143560193908633431847479049422605] SOPInstanceUID
(0008,0052) CS [IMAGE] QueryRetrieveLevel


2018-11-08 08:15:24 INFO  Connection:124 - Accept connection Socket[addr=/** SERVER IP **,port=44829,localport=11112]
2018-11-08 08:15:24 DEBUG Association:483 - /** MY IP **:11112<-/** SERVER IP **:44829(4): enter state: Sta2 - Transport connection open
2018-11-08 08:15:24 DEBUG Connection:98 - Wait for connection on /** MY IP **:11112
2018-11-08 08:15:24 INFO  Association:557 - MYAET<-DCM4CHEE(4) >> A-ASSOCIATE-RQ
2018-11-08 08:15:24 DEBUG Association:558 - A-ASSOCIATE-RQ[
  calledAET: MYAET
  callingAET: DCM4CHEE
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 0/0
  PresentationContext[id: 1
    as: 1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  PresentationContext[id: 3
    as: 1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
    ts: 1.2.840.10008.1.2.1 - Explicit VR Little Endian
  ]
]
2018-11-08 08:15:24 DEBUG Association:483 - MYAET<-DCM4CHEE(4): enter state: Sta3 - Awaiting local A-ASSOCIATE response primitive
2018-11-08 08:15:24 INFO  Association:464 - MYAET<-DCM4CHEE(4) << A-ASSOCIATE-AC
2018-11-08 08:15:24 DEBUG Association:465 - A-ASSOCIATE-AC[
  calledAET: MYAET
  callingAET: DCM4CHEE
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 1/1
  PresentationContext[id: 1
    result: 0 - acceptance
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  PresentationContext[id: 3
    result: 0 - acceptance
    ts: 1.2.840.10008.1.2.1 - Explicit VR Little Endian
  ]
]
2018-11-08 08:15:24 DEBUG Association:483 - MYAET<-DCM4CHEE(4): enter state: Sta6 - Association established and ready for data transfer

Server side:

08:15:23,143 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Accept connection Socket[addr=/**MY IP**,port=59701,localport=11112]
08:15:23,143 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) /** SERVER IP **:11112<-/**MY IP**:59701(3): enter state: Sta2 - Transport connection open
08:15:23,143 DEBUG [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Wait for connection on /0.0.0.0:11112
08:15:23,143 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3) >> A-ASSOCIATE-RQ
08:15:23,144 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) A-ASSOCIATE-RQ[
  calledAET: DCM4CHEE
  callingAET: MYAET
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 1/1
  PresentationContext[id: 1
    as: 1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  ExtendedNegotiation[
    sopClass: 1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
    info: [1, ]
  ]
]
08:15:23,144 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3): enter state: Sta3 - Awaiting local A-ASSOCIATE response primitive
08:15:23,144 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3) << A-ASSOCIATE-AC
08:15:23,144 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) A-ASSOCIATE-AC[
  calledAET: DCM4CHEE
  callingAET: MYAET
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 1/1
  PresentationContext[id: 1
    result: 0 - acceptance
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  ExtendedNegotiation[
    sopClass: 1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
    info: [1, ]
  ]
]
08:15:23,144 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3): enter state: Sta6 - Association established and ready for data transfer
08:15:23,151 INFO  [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-2) DCM4CHEE<-MYAET(2): close Socket[addr=LAPTOP-1I11BGIG.semfio.rp.usp.br/**MY IP**,port=59700,localport=11112]
08:15:23,154 DEBUG [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-2) DCM4CHEE<-MYAET(2): enter state: Sta1 - Idle
08:15:23,171 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3) >> 1:C-MOVE-RQ[pcid=1, prior=0
  cuid=1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
08:15:23,172 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-4) Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.2.1.2] AffectedSOPClassUID
(0000,0100) US [33] CommandField
(0000,0110) US [1] MessageID
(0000,0600) AE [MYAET] MoveDestination
(0000,0700) US [0] Priority
(0000,0800) US [0] CommandDataSetType


08:15:23,173 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-4) Dataset:
(0008,0018) UI [2.25.223575143560193908633431847479049422605] SOPInstanceUID
(0008,0052) CS [IMAGE] QueryRetrieveLevel


08:15:23,173 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3): Process C-MOVE RQ:
(0008,0018) UI [2.25.223575143560193908633431847479049422605] SOPInstanceUID
(0008,0052) CS [IMAGE] QueryRetrieveLevel


08:15:23,214 DEBUG [com.querydsl.jpa.hibernate.HibernateQuery] (EE-ManagedExecutorService-default-Thread-4) select location.pk, location.storageID, location.storagePath, location.objectType, location.transferSyntaxUID, location.digest, location.size, location.status, series.pk, instance.pk, instance.retrieveAETs, instance.externalRetrieveAET, instance.availability, instance.createdTime, instance.updatedTime, codeEntity.codeValue, codeEntity.codingSchemeDesignator, codeEntity.codeMeaning, uIDMap.pk, uIDMap.encodedMap, instanceAttributesBlob.encodedAttributes from Instance instance   inner join instance.attributesBlob as instanceAttributesBlob   inner join instance.series as series   inner join series.study as study   left join instance.rejectionNoteCode as codeEntity   left join instance.locations as location with location.objectType = ?1   left join location.uidMap as uIDMap where instance.rejectionNoteCode is null and (instance.conceptNameCode is null or instance.conceptNameCode <> ?2) and instance.sopInstanceUID = ?3
08:15:23,216 DEBUG [org.hibernate.hql.internal.ast.QueryTranslatorImpl] (EE-ManagedExecutorService-default-Thread-4) parse() - HQL: select location.pk, location.storageID, location.storagePath, location.objectType, location.transferSyntaxUID, location.digest, location.size, location.status, series.pk, instance.pk, instance.retrieveAETs, instance.externalRetrieveAET, instance.availability, instance.createdTime, instance.updatedTime, codeEntity.codeValue, codeEntity.codingSchemeDesignator, codeEntity.codeMeaning, uIDMap.pk, uIDMap.encodedMap, instanceAttributesBlob.encodedAttributes
from org.dcm4chee.arc.entity.Instance instance


............ other server log that are *apparently* from other DCM4CHEE threads ..............


08:15:23,586 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-4) Initiate connection from /0.0.0.0:0 to **MY IP**:11112
08:15:23,592 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-4) Established connection Socket[addr=/**MY IP**,port=11112,localport=44829]
08:15:23,593 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) /** SERVER IP **:44829->/**MY IP**:11112(4): enter state: Sta4 - Awaiting transport connection opening to complete
08:15:23,593 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE->MYAET(4) << A-ASSOCIATE-RQ
08:15:23,594 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) A-ASSOCIATE-RQ[
  calledAET: MYAET
  callingAET: DCM4CHEE
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 0/0
  PresentationContext[id: 1
    as: 1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  PresentationContext[id: 3
    as: 1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
    ts: 1.2.840.10008.1.2.1 - Explicit VR Little Endian
  ]
]
08:15:23,594 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE->MYAET(4): enter state: Sta5 - Awaiting A-ASSOCIATE-AC or A-ASSOCIATE-RJ PDU
08:15:23,597 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE->MYAET(4) >> A-ASSOCIATE-AC
08:15:23,597 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) A-ASSOCIATE-AC[
  calledAET: MYAET
  callingAET: DCM4CHEE
  applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name
  implClassUID: 1.2.40.0.13.1.3
  implVersionName: dcm4che-5.14.1
  maxPDULength: 16378
  maxOpsInvoked/maxOpsPerformed: 1/1
  PresentationContext[id: 1
    result: 0 - acceptance
    ts: 1.2.840.10008.1.2 - Implicit VR Little Endian
  ]
  PresentationContext[id: 3
    result: 0 - acceptance
    ts: 1.2.840.10008.1.2.1 - Explicit VR Little Endian
  ]
]
08:15:23,597 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE->MYAET(4): enter state: Sta6 - Association established and ready for data transfer
08:15:23,600 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-4) rDNS **MY IP** -> ...
08:15:23,602 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-4) rDNS **MY IP** -> LAPTOP-1I11BGIG.semfio.rp.usp.br in 2132 ms

Then, if I cloes my Java application, nothing happens. BUT, if I send DCM4CHEE a terminate command (ctrl+c), this happens, among all other DCM4CHEE log:

Client side:

2018-11-08 08:26:06 INFO  Dimse:447 - MYAET<-DCM4CHEE(4) >> 1:C-STORE-RQ[pcid=1, prior=0
  orig=MYAET >> 1:C-MOVE-RQ
  cuid=1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
  iuid=2.25.223575143560193908633431847479049422605 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
2018-11-08 08:26:06 DEBUG Dimse:448 - Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.1.88.59] AffectedSOPClassUID
(0000,0100) US [1] CommandField
(0000,0110) US [1] MessageID
(0000,0700) US [0] Priority
(0000,0800) US [0] CommandDataSetType
(0000,1000) UI [2.25.223575143560193908633431847479049422605] AffectedSOPInsta
(0000,1030) AE [MYAET] MoveOriginatorApplicationEntityTitle
(0000,1031) US [1] MoveOriginatorMessageID


2018-11-08 08:26:06 INFO  Dimse:406 - MYAET<-DCM4CHEE(4) << 1:C-STORE-RSP[pcid=1, status=0H
  cuid=1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
  iuid=2.25.223575143560193908633431847479049422605 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
2018-11-08 08:26:06 DEBUG Dimse:407 - Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.1.88.59] AffectedSOPClassUID
(0000,0100) US [32769] CommandField
(0000,0120) US [1] MessageIDBeingRespondedTo
(0000,0800) US [257] CommandDataSetType
(0000,0900) US [0] Status
(0000,1000) UI [2.25.223575143560193908633431847479049422605] AffectedSOPInsta


2018-11-08 08:26:06 INFO  Association:611 - MYAET<-DCM4CHEE(4) >> A-RELEASE-RQ
2018-11-08 08:26:06 DEBUG Association:483 - MYAET<-DCM4CHEE(4): enter state: Sta8 - Awaiting local A-RELEASE response primitive
2018-11-08 08:26:06 INFO  Association:624 - MYAET<-DCM4CHEE(4) << A-RELEASE-RP
2018-11-08 08:26:06 INFO  Dimse:447 - MYAET->DCM4CHEE(3) >> 1:C-MOVE-RSP[pcid=1, completed=1, failed=0, warning=0, status=0H
  cuid=1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
2018-11-08 08:26:06 DEBUG Association:483 - MYAET<-DCM4CHEE(4): enter state: Sta13 - Awaiting Transport Connection Close Indication
2018-11-08 08:26:06 DEBUG Dimse:448 - Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.2.1.2] AffectedSOPClassUID
(0000,0100) US [32801] CommandField
(0000,0120) US [1] MessageIDBeingRespondedTo
(0000,0800) US [257] CommandDataSetType
(0000,0900) US [0] Status
(0000,1021) US [1] NumberOfCompletedSuboperations
(0000,1022) US [0] NumberOfFailedSuboperations
(0000,1023) US [0] NumberOfWarningSuboperations


2018-11-08 08:26:06 INFO  Association:376 - MYAET->DCM4CHEE(3) << A-RELEASE-RQ
2018-11-08 08:26:06 DEBUG Association:483 - MYAET->DCM4CHEE(3): enter state: Sta7 - Awaiting A-RELEASE-RP PDU
2018-11-08 08:26:06 INFO  Connection:936 - Initiate connection from 0.0.0.0/0.0.0.0:0 to ** SERVER IP **:11112
2018-11-08 08:26:06 INFO  Association:651 - MYAET->DCM4CHEE(3) >> A-RELEASE-RP
2018-11-08 08:26:06 INFO  Association:334 - MYAET->DCM4CHEE(3): close Socket[addr=/** SERVER IP **,port=11112,localport=59701]
2018-11-08 08:26:06 DEBUG Association:483 - MYAET->DCM4CHEE(3): enter state: Sta1 - Idle
2018-11-08 08:26:06 INFO  Association:334 - MYAET<-DCM4CHEE(4): close Socket[addr=/** SERVER IP **,port=44829,localport=11112]
2018-11-08 08:26:06 DEBUG Association:483 - MYAET<-DCM4CHEE(4): enter state: Sta1 - Idle

Server side (among A LOT of other things):

08:26:05,642 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-1) rDNS **MY IP** -> ...
08:26:05,642 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-1) rDNS **MY IP** -> LAPTOP-1I11BGIG.semfio.rp.usp.br in 5 ms
08:26:05,643 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-1) DCM4CHEE->MYAET(4) << 1:C-STORE-RQ[pcid=1, prior=0
  orig=MYAET >> 1:C-MOVE-RQ
  cuid=1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
  iuid=2.25.223575143560193908633431847479049422605 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
08:26:05,644 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-1) Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.1.88.59] AffectedSOPClassUID
(0000,0100) US [1] CommandField
(0000,0110) US [1] MessageID
(0000,0700) US [0] Priority
(0000,0800) US [0] CommandDataSetType
(0000,1000) UI [2.25.223575143560193908633431847479049422605] AffectedSOPInsta
(0000,1030) AE [MYAET] MoveOriginatorApplicationEntityTitle
(0000,1031) US [1] MoveOriginatorMessageID


08:26:05,653 DEBUG [org.dcm4chee.arc.store.scu.impl.RetrieveTaskImpl] (EE-ManagedExecutorService-default-Thread-1) DCM4CHEE<-MYAET(3): wait for outstanding C-STORE RSP(s) on association to MYAET
08:26:05,686 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE->MYAET(4) >> 1:C-STORE-RSP[pcid=1, status=0H
  cuid=1.2.840.10008.5.1.4.1.1.88.59 - Key Object Selection Document Storage
  iuid=2.25.223575143560193908633431847479049422605 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
08:26:05,686 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-3) Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.1.88.59] AffectedSOPClassUID
(0000,0100) US [32769] CommandField
(0000,0120) US [1] MessageIDBeingRespondedTo
(0000,0800) US [257] CommandDataSetType
(0000,0900) US [0] Status
(0000,1000) UI [2.25.223575143560193908633431847479049422605] AffectedSOPInsta


08:26:05,691 DEBUG [org.dcm4chee.arc.store.scu.impl.RetrieveTaskImpl] (EE-ManagedExecutorService-default-Thread-1) DCM4CHEE<-MYAET(3): received outstanding C-STORE RSP(s) on association to MYAET
08:26:05,691 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-1) DCM4CHEE->MYAET(4) << A-RELEASE-RQ
08:26:05,691 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-1) DCM4CHEE->MYAET(4): enter state: Sta7 - Awaiting A-RELEASE-RP PDU
08:26:05,692 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-1) DCM4CHEE<-MYAET(3) << 1:C-MOVE-RSP[pcid=1, completed=1, failed=0, warning=0, status=0H
  cuid=1.2.840.10008.5.1.4.1.2.1.2 - Patient Root Query/Retrieve Information Model - MOVE
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian
08:26:05,693 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-1) Command:
(0000,0002) UI [1.2.840.10008.5.1.4.1.2.1.2] AffectedSOPClassUID
(0000,0100) US [32801] CommandField
(0000,0120) US [1] MessageIDBeingRespondedTo
(0000,0800) US [257] CommandDataSetType
(0000,0900) US [0] Status
(0000,1021) US [1] NumberOfCompletedSuboperations
(0000,1022) US [0] NumberOfFailedSuboperations
(0000,1023) US [0] NumberOfWarningSuboperations


08:26:05,697 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE->MYAET(4) >> A-RELEASE-RP
08:26:05,697 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE->MYAET(4): close Socket[addr=LAPTOP-1I11BGIG.semfio.rp.usp.br/**MY IP**,port=11112,localport=44829]
08:26:05,715 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE->MYAET(4): enter state: Sta1 - Idle
08:26:05,716 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3) >> A-RELEASE-RQ
08:26:05,716 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3): enter state: Sta8 - Awaiting local A-RELEASE response primitive
08:26:05,717 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3) << A-RELEASE-RP
08:26:05,717 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE<-MYAET(3): enter state: Sta13 - Awaiting Transport Connection Close Indication

And the image I was asking for in the C-MOVE request gets stored in the directory defined by my storescp implementation. I have no idea what I am missing, and it is really hard for me to look at the Server log and find information about it. I don't even know if my problem can be isolated by the logs alone or if I will need to share some code for it to help. If that is the case, I'll try to write a small test class I can share. My current code is heavily based on StoreSCP, MoveSCU and an old MoveTool class used for testing in previous DCM4CHE versions, though.

*MYAET* is (as far as I can tell) correctly configured in DCM4CHEE.

I also tried to run the StoreCSP and MoveSCU classes from DCM4CHE by creating and passing arguments to their main methods inside my Java app, but it also does not work. I am able to retrieve images from Weasis application using C-MOVE, so I know my DCM4CHEE server is able to perform it.

EDIT: I rechecked, and I am unable to perform C-MOVE from both WEASIS and K-PACS. I must have gotten confused in my annotations, and I was only able to perform retrieve from my dcm4chee server by using C-GET. K-PACS receives images by C-MOVE if one application is closed, similar with my Java app.

I encountered a similar problem when I started working on this, in which an association stopped with Client on Sta5 and Server on Sta6, and I solved that by changing the executor for my local device (https://stackoverflow.com/a/52972764/2811235), but it might have nothing to do with any of this.

*There are two errors that appear in the server log, but they are AFTER I interrupt the process, so I don't really think they are related to my problem.

08:26:05,767 ERROR [org.jboss.as.ee] (EE-ManagedScheduledExecutorService-default-Thread-1) WFLYEE0110: Failed to run scheduled task: java.lang.IllegalStateException: WFLYEE0111: Cannot run scheduled task org.dcm4che3.net.Association$1@6a88a281 as container is suspended
 at org.jboss.as.ee//org.jboss.as.ee.concurrent.ControlPointUtils$ControlledScheduledRunnable.run(ControlPointUtils.java:164)
 at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
 at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
 at org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedFutureTask.run(ManagedFutureTask.java:141)
 at org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$101(ManagedScheduledThreadPoolExecutor.java:383)
 at org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:532)
 at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
 at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
 at java.base/java.lang.Thread.run(Thread.java:844)
 at org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250)
 at org.jboss.as.ee//org.jboss.as.ee.concurrent.service.ElytronManagedThreadFactory$ElytronManagedThread.run(ElytronManagedThreadFactory.java:78)

ANY help or tip is more than welcome. As I added in another question, I'm still new to Java and dcm4che.