Verify that configured Retrieve Cache is used by C-MOVE SCP : Study Level ==============dcm4che tool log================= movescu -c DCM4CHEE@localhost:11112 -m StudyInstanceUID=1.3.12.2.1107.5.8.1.12345678.199508041416590859569 --dest STORESCP 16:38:59,623 INFO - Initiate connection from 0.0.0.0/0.0.0.0:0 to localhost:11112 16:38:59,627 INFO - Established connection Socket[addr=localhost/127.0.0.1,port=11112,localport=47599] 16:38:59,638 DEBUG - /127.0.0.1:47599->localhost/127.0.0.1:11112(1): enter state: Sta4 - Awaiting transport connection opening to complete 16:38:59,640 INFO - MOVESCU->DCM4CHEE(1) << A-ASSOCIATE-RQ 16:38:59,646 DEBUG - A-ASSOCIATE-RQ[ calledAET: DCM4CHEE callingAET: MOVESCU applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name implClassUID: 1.2.40.0.13.1.3 implVersionName: dcm4che-5.13.3 maxPDULength: 16378 maxOpsInvoked/maxOpsPerformed: 0/0 PresentationContext[id: 1 as: 1.2.840.10008.5.1.4.1.2.2.2 - Study Root Query/Retrieve Information Model - MOVE ts: 1.2.840.10008.1.2 - Implicit VR Little Endian ts: 1.2.840.10008.1.2.1 - Explicit VR Little Endian ts: 1.2.840.10008.1.2.2 - Explicit VR Big Endian (Retired) ] ] 16:38:59,646 DEBUG - MOVESCU->DCM4CHEE(1): enter state: Sta5 - Awaiting A-ASSOCIATE-AC or A-ASSOCIATE-RJ PDU 16:38:59,656 INFO - MOVESCU->DCM4CHEE(1) >> A-ASSOCIATE-AC 16:38:59,657 DEBUG - A-ASSOCIATE-AC[ calledAET: DCM4CHEE callingAET: MOVESCU applicationContext: 1.2.840.10008.3.1.1.1 - DICOM Application Context Name implClassUID: 1.2.40.0.13.1.3 implVersionName: dcm4che-5.13.3 maxPDULength: 16378 maxOpsInvoked/maxOpsPerformed: 0/0 PresentationContext[id: 1 result: 0 - acceptance ts: 1.2.840.10008.1.2 - Implicit VR Little Endian ] ] 16:38:59,657 DEBUG - MOVESCU->DCM4CHEE(1): enter state: Sta6 - Association established and ready for data transfer 16:38:59,660 INFO - MOVESCU->DCM4CHEE(1) << 1:C-MOVE-RQ[pcid=1, prior=0 cuid=1.2.840.10008.5.1.4.1.2.2.2 - Study Root Query/Retrieve Information Model - MOVE tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:38:59,680 DEBUG - Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.2.2.2] AffectedSOPClassUID (0000,0100) US [33] CommandField (0000,0110) US [1] MessageID (0000,0600) AE [STORESCP] MoveDestination (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType 16:38:59,681 DEBUG - Dataset: (0008,0018) UI [1.3.12.2.1107.5.8.1.12345678.199508041417220733405] SOPInstanc (0008,0052) CS [STUDY] QueryRetrieveLevel (0020,000D) UI [1.3.12.2.1107.5.8.1.12345678.199508041416590859569] StudyInsta (0020,000E) UI [1.3.12.2.1107.5.8.1.12345678.199508041416590860429] SeriesInst 16:38:59,945 INFO - MOVESCU->DCM4CHEE(1) >> 1:C-MOVE-RSP[pcid=1, remaining=9, completed=0, failed=0, warning=0, status=ff00H cuid=1.2.840.10008.5.1.4.1.2.2.2 - Study Root Query/Retrieve Information Model - MOVE tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:38:59,945 DEBUG - Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.2.2.2] AffectedSOPClassUID (0000,0100) US [32801] CommandField (0000,0120) US [1] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [65280] Status (0000,1020) US [9] NumberOfRemainingSuboperations (0000,1021) US [0] NumberOfCompletedSuboperations (0000,1022) US [0] NumberOfFailedSuboperations (0000,1023) US [0] NumberOfWarningSuboperations 16:39:01,754 INFO - MOVESCU->DCM4CHEE(1) >> 1:C-MOVE-RSP[pcid=1, completed=9, failed=0, warning=0, status=0H cuid=1.2.840.10008.5.1.4.1.2.2.2 - Study Root Query/Retrieve Information Model - MOVE tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,754 DEBUG - Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.2.2.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 [9] NumberOfCompletedSuboperations (0000,1022) US [0] NumberOfFailedSuboperations (0000,1023) US [0] NumberOfWarningSuboperations 16:39:01,754 INFO - MOVESCU->DCM4CHEE(1) << A-RELEASE-RQ 16:39:01,754 DEBUG - MOVESCU->DCM4CHEE(1): enter state: Sta7 - Awaiting A-RELEASE-RP PDU 16:39:01,755 INFO - MOVESCU->DCM4CHEE(1) >> A-RELEASE-RP 16:39:01,755 INFO - MOVESCU->DCM4CHEE(1): close Socket[addr=localhost/127.0.0.1,port=11112,localport=47599] 16:39:01,755 DEBUG - MOVESCU->DCM4CHEE(1): enter state: Sta1 - Idle =============Wildfly Server log================ 16:38:38,293 INFO [org.dcm4che3.net.Dimse] (EE-ManagedScheduledExecutorService-default-Thread-6) DCM4CHEE<-MOVESCU(2) << 1:C-MOVE-RSP[pcid=1, remaining=1, completed=0, failed=0, warning=0, status=ff00H cuid=1.2.840.10008.5.1.4.1.2.2.2 - Study Root Query/Retrieve Information Model - MOVE tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:38:24,294 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedScheduledExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.2.2.2] AffectedSOPClassUID (0000,0100) US [32801] CommandField (0000,0120) US [1] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [65280] Status (0000,1020) US [9] NumberOfRemainingSuboperations (0000,1021) US [0] NumberOfCompletedSuboperations (0000,1022) US [0] NumberOfFailedSuboperations (0000,1023) US [0] NumberOfWarningSuboperations .............................. .............................. 2018-05-25 16:39:01,027 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 2018-05-25 16:39:01,028 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041416590861483,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9653, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/5E9C75E8, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 2018-05-25 16:39:01,038 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 2018-05-25 16:39:01,038 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 13 ms 2018-05-25 16:39:01,038 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 1:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041416590861483 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,038 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [1] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041416590861483] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 2018-05-25 16:39:01,097 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 1:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041416590861483 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,097 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [1] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041416590861483] AffectedSO .............................. .............................. 2018-05-25 16:39:01,070 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 2018-05-25 16:39:01,070 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417310957616,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9654, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/ECFD2411, tsuid=1.2.840.10008.1.2, size=529442, status=OK, objectType=DICOM_FILE] 2018-05-25 16:39:01,071 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 2018-05-25 16:39:01,071 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 2 ms 2018-05-25 16:39:01,071 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 2:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417310957616 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,072 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [2] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417310957616] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 2018-05-25 16:39:01,071 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 2:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417310957616 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,072 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [2] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417310957616] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID .............................. .............................. 2018-05-25 16:39:01,158 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 2018-05-25 16:39:01,158 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417130540448,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9655, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/8D51F014, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 2018-05-25 16:39:01,159 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 2018-05-25 16:39:01,159 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 2018-05-25 16:39:01,160 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 3:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417130540448 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,160 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [3] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417130540448] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 2018-05-25 16:39:01,163 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 3:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417130540448 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,163 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [3] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417130540448] AffectedSO .............................. .............................. 16:39:01,263 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,264 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417090311793,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9656, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/64C116C4, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 16:39:01,265 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 16:39:01,265 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 16:39:01,265 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 4:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417090311793 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,265 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [4] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417090311793] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 16:39:01,268 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 4:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417090311793 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,268 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [4] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417090311793] AffectedSO .............................. .............................. 16:39:01,363 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,363 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417370757753,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9657, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/58DA8447, tsuid=1.2.840.10008.1.2, size=529442, status=OK, objectType=DICOM_FILE] 16:39:01,364 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 16:39:01,364 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 16:39:01,364 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 5:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417370757753 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,364 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [5] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417370757753] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 16:39:01,367 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 5:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417370757753 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,367 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [5] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417370757753] AffectedSO .............................. .............................. 16:39:01,447 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,447 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417270376034,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9658, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/BDDE52E9, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 16:39:01,448 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 16:39:01,449 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 16:39:01,449 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 6:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417270376034 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,449 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [6] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417270376034] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 16:39:01,451 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 6:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417270376034 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,451 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [6] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417270376034] AffectedSO .............................. .............................. 16:39:01,539 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,539 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417040742898,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9659, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/6459578D, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 16:39:01,540 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 16:39:01,540 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 16:39:01,540 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 7:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417040742898 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,541 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [7] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417040742898] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 16:39:01,543 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 7:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417040742898 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,543 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [7] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417040742898] AffectedSO .............................. .............................. 16:39:01,629 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,629 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417180157831,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9660, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/8DF5C2EB, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 16:39:01,630 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 16:39:01,630 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 16:39:01,630 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 8:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417180157831 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,631 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [8] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417180157831] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 2018-05-25 16:39:01,633 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 8:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417180157831 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 2018-05-25 16:39:01,633 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [8] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417180157831] AffectedSO .............................. .............................. 16:39:01,719 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,719 DEBUG [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-7) Read Instance[iuid=1.3.12.2.1107.5.8.1.12345678.199508041417220733405,cuid=1.2.840.10008.5.1.4.1.1.2] from Location[pk=9661, systemID=retrieve-cache, path=2018/05/25/5E9204E8/5E9C00D5/BD122891, tsuid=1.2.840.10008.1.2, size=529440, status=OK, objectType=DICOM_FILE] 16:39:01,720 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> ... 16:39:01,720 DEBUG [org.dcm4che3.util.ReverseDNS] (EE-ManagedExecutorService-default-Thread-7) rDNS 127.0.0.1 -> localhost in 1 ms 16:39:01,720 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << 9:C-STORE-RQ[pcid=1, prior=0 orig=MOVESCU >> 1:C-MOVE-RQ cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417220733405 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,720 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [1] CommandField (0000,0110) US [9] MessageID (0000,0700) US [0] Priority (0000,0800) US [0] CommandDataSetType (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417220733405] AffectedSO (0000,1030) AE [MOVESCU] MoveOriginatorApplicationEntityTitle (0000,1031) US [1] MoveOriginatorMessageID 16:39:01,723 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE->STORESCP(2) >> 9:C-STORE-RSP[pcid=1, status=0H cuid=1.2.840.10008.5.1.4.1.1.2 - CT Image Storage iuid=1.3.12.2.1107.5.8.1.12345678.199508041417220733405 - ? tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,723 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.1.2] AffectedSOPClassUID (0000,0100) US [32769] CommandField (0000,0120) US [9] MessageIDBeingRespondedTo (0000,0800) US [257] CommandDataSetType (0000,0900) US [0] Status (0000,1000) UI [1.3.12.2.1107.5.8.1.12345678.199508041417220733405] AffectedSO .............................. .............................. 16:39:01,752 DEBUG [org.dcm4chee.arc.retrieve.impl.CopyToRetrieveCacheTask] (EE-ManagedExecutorService-default-Thread-7) Got next finished copy to retrieve cache 16:39:01,752 INFO [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2) << A-RELEASE-RQ 16:39:01,752 DEBUG [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE->STORESCP(2): enter state: Sta7 - Awaiting A-RELEASE-RP PDU 16:39:01,752 INFO [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) DCM4CHEE<-MOVESCU(1) << 1:C-MOVE-RSP[pcid=1, completed=9, failed=0, warning=0, status=0H cuid=1.2.840.10008.5.1.4.1.2.2.2 - Study Root Query/Retrieve Information Model - MOVE tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian 16:39:01,753 DEBUG [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-7) Command: (0000,0002) UI [1.2.840.10008.5.1.4.1.2.2.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 [9] NumberOfCompletedSuboperations (0000,1022) US [0] NumberOfFailedSuboperations (0000,1023) US [0] NumberOfWarningSuboperations