Fallback C-Move SCP not working #4760

dbutti · 2025-07-10T22:16:34Z

dbutti
Jul 10, 2025

Describe the bug
Fallback C-Move SCP (cache retrieve) is not working

To Reproduce
Setup a Fallback C-Move SCP System as described in the documentation and perform a Q/R request from any DICOM client

Expected behavior
If the requested DICOM object does not exist in the archive, DCM4 starts a fallback C-Move SCP request against an external system and retrieves the missing objects. This part works perfectly.
After the objects have been retrieved and stored into DCM4, the original request from the client should be fulfilled by having DCM4 perform C-Store operations. This never happens, and the log do not contain any errors or hints.

Additional context
Connectivity issues are ruled out; if the client sends the request a second time, after the DICOM objects have been retrieved from remote, then the C-MOVE is fulfilled immediately and successfully.

I am also perplexed by the poorly documented setting "C-STORE SCU of C-MOVE SCP"; must this be set in any special way for this function to work?

Thanks everybody for your valuable feedback!

dbutti · 2025-07-15T13:57:47Z

dbutti
Jul 15, 2025
Author

I will answer my own question here: Fallback C-MOVE SCP works indeed, but in my setup only if the "C-STORE SCU of C-MOVE SCP" field is configured with the AE titles of the Remote Archive and Original Requester, respectively. The documentation should perhaps mention this, or offer some minimal working example.

1 reply

vrindanayak Jul 15, 2025
Maintainer

No - C-STORE SCU of C-MOVE SCP config is not required for Proxy retrieve requests to another Archive - Proxy Retrieve OR Proxy retrieve requests to another Archive - Cache Retrieve

vrindanayak · 2025-07-15T13:58:03Z

vrindanayak
Jul 15, 2025
Maintainer

Proxy retrieve requests to another Archive - Proxy Retrieve

Config on archive1 - followed https://github.com/dcm4che/dcm4chee-arc-light/wiki/Proxy-retrieve-requests-to-another-Archive#configuration (replacing QRSCP with DCM4CHEE2)

Verify no studies on archive 1

Study present on archive 2

Trigger C-MOVE request to archive 1

archive1 server.log

15:52:08,553 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Accept connection Socket[addr=/127.0.0.1,port=37503,localport=11112]
15:52:08,564 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1) >> A-ASSOCIATE-RQ
15:52:08,571 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1) << A-ASSOCIATE-AC
15:52:08,602 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1) >> 1:C-MOVE-RQ[pcid=1, prior=0, dest=STORESCP
  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]
15:52:08,620 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1): Process C-MOVE RQ:
(0008,0052) CS [STUDY] QueryRetrieveLevel
(0020,000D) UI [1.3.12.2.1107.5.8.1.12345678.199508041416590859569] StudyInsta

15:52:08,707 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1): No objects of study[1.3.12.2.1107.5.8.1.12345678.199508041416590859569] found - forward C-MOVE RQ to DCM4CHEE2
15:52:08,742 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-260) Initiate connection from /0.0.0.0:0 to localhost:21112
15:52:08,743 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-260) Established connection Socket[addr=localhost/127.0.0.1,port=21112,localport=35751]
15:52:08,744 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-260) MOVESCU->DCM4CHEE2(2) << A-ASSOCIATE-RQ
15:52:08,745 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-261) MOVESCU->DCM4CHEE2(2) >> A-ASSOCIATE-AC
15:52:08,749 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-262) MOVESCU->DCM4CHEE2(2) << 1:C-MOVE-RQ[pcid=1, prior=0, dest=STORESCP
  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]
15:52:08,750 INFO  [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-262) DCM4CHEE<-MOVESCU(1): wait for outstanding C-MOVE RSP(s) for C-MOVE RQ(s) forwarded to DCM4CHEE2
15:52:08,855 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-261) MOVESCU->DCM4CHEE2(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]
15:52:08,857 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-261) DCM4CHEE<-MOVESCU(1) << 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]
15:52:08,988 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-261) MOVESCU->DCM4CHEE2(2) >> 1:C-MOVE-RSP[pcid=1, completed=1, 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]
15:52:08,991 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-261) DCM4CHEE<-MOVESCU(1) << 1:C-MOVE-RSP[pcid=1, completed=1, 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]
15:52:08,992 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-262) MOVESCU->DCM4CHEE2(2) << A-RELEASE-RQ
15:52:08,992 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1) >> A-RELEASE-RQ
15:52:08,993 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-260) DCM4CHEE<-MOVESCU(1) << A-RELEASE-RP
15:52:08,993 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-261) MOVESCU->DCM4CHEE2(2) >> A-RELEASE-RP
15:52:08,993 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-261) MOVESCU->DCM4CHEE2(2): close Socket[addr=localhost/127.0.0.1,port=21112,localport=35751]
15:52:09,044 INFO  [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-6) DCM4CHEE<-MOVESCU(1): close Socket[addr=/127.0.0.1,port=37503,localport=11112]

archive2 server.log

15:52:08,743 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-1) Accept connection Socket[addr=/127.0.0.1,port=35751,localport=21112]
15:52:08,744 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(2) >> A-ASSOCIATE-RQ
15:52:08,745 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(2) << A-ASSOCIATE-AC
15:52:08,751 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(2) >> 1:C-MOVE-RQ[pcid=1, prior=0, dest=STORESCP
  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]
15:52:08,752 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(2): Process C-MOVE RQ:
(0008,0052) CS [STUDY] QueryRetrieveLevel
(0020,000D) UI [1.3.12.2.1107.5.8.1.12345678.199508041416590859569] StudyInsta

15:52:08,818 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Initiate connection from /0.0.0.0:0 to localhost:11117
15:52:08,819 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Established connection Socket[addr=localhost/127.0.0.1,port=11117,localport=52309]
15:52:08,820 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2->STORESCP(3) << A-ASSOCIATE-RQ
15:52:08,848 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE2->STORESCP(3) >> A-ASSOCIATE-AC
15:52:08,854 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedScheduledExecutorService-default-Thread-6) DCM4CHEE2<-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]
15:52:08,861 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE2->STORESCP(3) << 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]
15:52:08,987 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE2->STORESCP(3) >> 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]
15:52:08,987 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE2->STORESCP(3) << A-RELEASE-RQ
15:52:08,988 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-4) DCM4CHEE2<-MOVESCU(2) << 1:C-MOVE-RSP[pcid=1, completed=1, 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]
15:52:08,989 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE2->STORESCP(3) >> A-RELEASE-RP
15:52:08,990 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-3) DCM4CHEE2->STORESCP(3): close Socket[addr=localhost/127.0.0.1,port=11117,localport=52309]
15:52:08,992 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(2) >> A-RELEASE-RQ
15:52:08,992 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(2) << A-RELEASE-RP
15:52:09,043 INFO  [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-7) DCM4CHEE2<-MOVESCU(2): close Socket[addr=view-localhost/127.0.0.1,port=35751,localport=21112]

0 replies

vrindanayak · 2025-07-15T14:17:59Z

vrindanayak
Jul 15, 2025
Maintainer

Proxy retrieve requests to another Archive - Cache Retrieve

Config on archive1 - followed https://github.com/dcm4che/dcm4chee-arc-light/wiki/Proxy-retrieve-requests-to-another-Archive#configuration-1 (replacing QRSCP with DCM4CHEE2)

Verify no studies on archive 1

Study present on archive 2

Trigger C-MOVE request to archive 1

archive1 server.log

16:12:40,457 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Accept connection Socket[addr=/127.0.0.1,port=42175,localport=11112]
16:12:40,473 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3) >> A-ASSOCIATE-RQ
16:12:40,473 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3) << A-ASSOCIATE-AC
16:12:40,499 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3) >> 1:C-MOVE-RQ[pcid=1, prior=0, dest=STORESCP
  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:12:40,499 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3): Process C-MOVE RQ:
(0008,0052) CS [STUDY] QueryRetrieveLevel
(0020,000D) UI [1.2.840.113619.2.216.2.1.2642006103252234.10589] StudyInstance

16:12:40,530 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3): No objects of study[1.2.840.113619.2.216.2.1.2642006103252234.10589] found - forward C-MOVE RQ to DCM4CHEE2
16:12:40,562 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-347) Initiate connection from /0.0.0.0:0 to localhost:21112
16:12:40,562 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-347) Established connection Socket[addr=localhost/127.0.0.1,port=21112,localport=48049]
16:12:40,563 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-347) MOVESCU->DCM4CHEE2(4) << A-ASSOCIATE-RQ
16:12:40,563 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-348) MOVESCU->DCM4CHEE2(4) >> A-ASSOCIATE-AC
16:12:40,571 INFO  [org.dcm4chee.arc.audit.AuditService] (EE-ManagedExecutorService-default-Thread-349) Neither matches nor C-Store Forwards nor failed matches present. Exit spooling retrieve event Audit Event Type[EventClass = RETRIEVE, EventID = org.dcm4che3.audit.AuditMessages$EventID@23494dcb, EventActionCode = E, RoleIDSource = org.dcm4che3.audit.AuditMessages$RoleIDCode@656a7522, RoleIDDestination = org.dcm4che3.audit.AuditMessages$RoleIDCode@1ad4b9c8, EventTypeCode = null]
16:12:40,571 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-349) MOVESCU->DCM4CHEE2(4) << 1:C-MOVE-RQ[pcid=1, prior=0, dest=DCM4CHEE
  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:12:40,571 INFO  [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-349) DCM4CHEE<-MOVESCU(3): wait for outstanding C-MOVE RSP(s) for C-MOVE RQ(s) forwarded to DCM4CHEE2
16:12:40,793 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Accept connection Socket[addr=/127.0.0.1,port=60543,localport=11112]
16:12:40,794 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5) >> A-ASSOCIATE-RQ
16:12:40,794 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5) << A-ASSOCIATE-AC
16:12:40,797 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-348) MOVESCU->DCM4CHEE2(4) >> 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:12:40,800 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5) >> 1:C-STORE-RQ[pcid=1, prior=0
  orig=MOVESCU >> 1:C-MOVE-RQ
  cuid=1.2.840.10008.5.1.4.1.1.20 - Nuclear Medicine Image Storage
  iuid=1.2.840.113619.2.216.2.1.2642006103434968.10942 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian]
16:12:40,830 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedScheduledExecutorService-default-Thread-11) DCM4CHEE<-MOVESCU(3) << 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:12:40,989 INFO  [org.dcm4chee.arc.patient.impl.PatientServiceEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Create Patient[pk=138, id=[MADTPID3597^^^JMS], name=VENTRI^TEST]
16:12:40,998 INFO  [org.dcm4chee.arc.store.impl.StoreServiceEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Create Study[pk=124, uid=1.2.840.113619.2.216.2.1.2642006103252234.10589, id=Dual Isotope]
16:12:41,006 INFO  [org.dcm4chee.arc.store.impl.StoreServiceEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Create Series[pk=151, uid=1.2.840.113619.2.216.2.1.2642006103436281.10716, no=1, mod=NM]
16:12:41,009 INFO  [org.dcm4chee.arc.store.impl.StoreServiceEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Create Instance[pk=242, uid=1.2.840.113619.2.216.2.1.2642006103434968.10942, class=1.2.840.10008.5.1.4.1.1.20, no=null]
16:12:41,016 INFO  [org.dcm4chee.arc.store.impl.StoreServiceEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Create Location[pk=242, systemID=fs1, path=2025/07/15/DD3B02A9/FCADE19F/C483554C, tsuid=1.2.840.10008.1.2, size=497172, status=OK, objectType=DICOM_FILE]
16:12:41,096 INFO  [org.dcm4chee.arc.store.impl.StoreServiceImpl] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Updated DB in 173 ms
16:12:41,113 INFO  [org.dcm4chee.arc.ups.impl.UPSServiceEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Create UPS[pk=227, uid=2.25.256810772346609130126617719574532284292, state=SCHEDULED]
16:12:41,137 INFO  [org.dcm4chee.arc.hl7.psu.HL7PSUEJB] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5): Created HL7PSUTask[pk=24, deviceName=dcm4chee-arc, studyInstanceUID=1.2.840.113619.2.216.2.1.2642006103252234.10589, seriesInstanceUID=1.2.840.113619.2.216.2.1.2642006103436281.10716, accessionNumber=null, scheduledTime=Tue Jul 15 16:13:41 CEST 2025]
16:12:41,146 INFO  [org.dcm4chee.arc.store.scu.impl.CStoreForward] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-MOVESCU(3): open association to STORESCP for forwarding C-STORE-RQ received in association DCM4CHEE<-DCM4CHEE2(5)
16:12:41,146 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-350) Initiate connection from /0.0.0.0:0 to localhost:11117
16:12:41,147 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-350) Established connection Socket[addr=localhost/127.0.0.1,port=11117,localport=43837]
16:12:41,147 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE->STORESCP(6) << A-ASSOCIATE-RQ
16:12:41,149 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-351) DCM4CHEE->STORESCP(6) >> A-ASSOCIATE-AC
16:12:41,152 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5) << 1:C-STORE-RSP[pcid=1, status=0H
  cuid=1.2.840.10008.5.1.4.1.1.20 - Nuclear Medicine Image Storage
  iuid=1.2.840.113619.2.216.2.1.2642006103434968.10942 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian]
16:12:41,153 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5) >> A-RELEASE-RQ
16:12:41,154 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-350) DCM4CHEE<-DCM4CHEE2(5) << A-RELEASE-RP
16:12:41,154 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-348) MOVESCU->DCM4CHEE2(4) >> 1:C-MOVE-RSP[pcid=1, completed=1, 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:12:41,155 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-349) MOVESCU->DCM4CHEE2(4) << A-RELEASE-RQ
16:12:41,156 INFO  [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-349) DCM4CHEE<-MOVESCU(3): wait for pending forward of objects of study[1.2.840.113619.2.216.2.1.2642006103252234.10589] from DCM4CHEE2 to STORESCP
16:12:41,156 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-348) MOVESCU->DCM4CHEE2(4) >> A-RELEASE-RP
16:12:41,156 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-348) MOVESCU->DCM4CHEE2(4): close Socket[addr=localhost/127.0.0.1,port=21112,localport=48049]
16:12:41,158 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-352) DCM4CHEE->STORESCP(6) << 1:C-STORE-RQ[pcid=1, prior=0
  orig=MOVESCU >> 1:C-MOVE-RQ
  cuid=1.2.840.10008.5.1.4.1.1.20 - Nuclear Medicine Image Storage
  iuid=1.2.840.113619.2.216.2.1.2642006103434968.10942 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian]
16:12:41,169 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-351) DCM4CHEE->STORESCP(6) >> 1:C-STORE-RSP[pcid=1, status=0H
  cuid=1.2.840.10008.5.1.4.1.1.20 - Nuclear Medicine Image Storage
  iuid=1.2.840.113619.2.216.2.1.2642006103434968.10942 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian]
16:12:41,170 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-352) DCM4CHEE->STORESCP(6) << A-RELEASE-RQ
16:12:41,170 INFO  [org.dcm4chee.arc.retrieve.impl.RetrieveServiceImpl] (EE-ManagedExecutorService-default-Thread-349) DCM4CHEE<-MOVESCU(3): complete forward of objects of study[1.2.840.113619.2.216.2.1.2642006103252234.10589] from DCM4CHEE2 to STORESCP - remaining=0, completed=1, failed=0, warning=0
16:12:41,171 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-351) DCM4CHEE->STORESCP(6) >> A-RELEASE-RP
16:12:41,171 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-351) DCM4CHEE->STORESCP(6): close Socket[addr=localhost/127.0.0.1,port=11117,localport=43837]
16:12:41,177 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-349) DCM4CHEE<-MOVESCU(3) << 1:C-MOVE-RSP[pcid=1, completed=1, 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:12:41,179 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3) >> A-RELEASE-RQ
16:12:41,179 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-347) DCM4CHEE<-MOVESCU(3) << A-RELEASE-RP
16:12:41,204 INFO  [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-4) DCM4CHEE<-DCM4CHEE2(5): close Socket[addr=view-localhost/127.0.0.1,port=60543,localport=11112]
16:12:41,229 INFO  [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-13) DCM4CHEE<-MOVESCU(3): close Socket[addr=view-localhost/127.0.0.1,port=42175,localport=11112]

archive2 server.log

16:12:40,562 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-1) Accept connection Socket[addr=/127.0.0.1,port=48049,localport=21112]
16:12:40,563 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(6) >> A-ASSOCIATE-RQ
16:12:40,563 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(6) << A-ASSOCIATE-AC
16:12:40,571 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(6) >> 1:C-MOVE-RQ[pcid=1, prior=0, dest=DCM4CHEE
  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:12:40,572 INFO  [org.dcm4chee.arc.retrieve.scp.CommonCMoveSCP] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(6): Process C-MOVE RQ:
(0008,0052) CS [STUDY] QueryRetrieveLevel
(0020,000D) UI [1.2.840.113619.2.216.2.1.2642006103252234.10589] StudyInstance

16:12:40,792 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Initiate connection from /0.0.0.0:0 to localhost:11112
16:12:40,793 INFO  [org.dcm4che3.net.Connection] (EE-ManagedExecutorService-default-Thread-2) Established connection Socket[addr=localhost/127.0.0.1,port=11112,localport=60543]
16:12:40,794 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2->DCM4CHEE(7) << A-ASSOCIATE-RQ
16:12:40,795 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-5) DCM4CHEE2->DCM4CHEE(7) >> A-ASSOCIATE-AC
16:12:40,796 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedScheduledExecutorService-default-Thread-5) DCM4CHEE2<-MOVESCU(6) << 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:12:40,798 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE2->DCM4CHEE(7) << 1:C-STORE-RQ[pcid=1, prior=0
  orig=MOVESCU >> 1:C-MOVE-RQ
  cuid=1.2.840.10008.5.1.4.1.1.20 - Nuclear Medicine Image Storage
  iuid=1.2.840.113619.2.216.2.1.2642006103434968.10942 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian]
16:12:41,153 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-5) DCM4CHEE2->DCM4CHEE(7) >> 1:C-STORE-RSP[pcid=1, status=0H
  cuid=1.2.840.10008.5.1.4.1.1.20 - Nuclear Medicine Image Storage
  iuid=1.2.840.113619.2.216.2.1.2642006103434968.10942 - ?
  tsuid=1.2.840.10008.1.2 - Implicit VR Little Endian]
16:12:41,153 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE2->DCM4CHEE(7) << A-RELEASE-RQ
16:12:41,154 INFO  [org.dcm4che3.net.Dimse] (EE-ManagedExecutorService-default-Thread-6) DCM4CHEE2<-MOVESCU(6) << 1:C-MOVE-RSP[pcid=1, completed=1, 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:12:41,154 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-5) DCM4CHEE2->DCM4CHEE(7) >> A-RELEASE-RP
16:12:41,154 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-5) DCM4CHEE2->DCM4CHEE(7): close Socket[addr=localhost/127.0.0.1,port=11112,localport=60543]
16:12:41,155 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(6) >> A-RELEASE-RQ
16:12:41,156 INFO  [org.dcm4che3.net.Association] (EE-ManagedExecutorService-default-Thread-2) DCM4CHEE2<-MOVESCU(6) << A-RELEASE-RP
16:12:41,206 INFO  [org.dcm4che3.net.Association] (EE-ManagedScheduledExecutorService-default-Thread-10) DCM4CHEE2<-MOVESCU(6): close Socket[addr=view-localhost/127.0.0.1,port=48049,localport=21112]

0 replies

dbutti · 2025-07-15T14:59:44Z

dbutti
Jul 15, 2025
Author

Thank-you for your kind reply and for the snapshots, which make it easier to follow the setup.

This is indeed the way I had it setup, but it never worked until I added "C-STORE SCU of C-MOVE SCP".

In you example, I can see that you use DCM4CHEE as called AE title in the movescu request. But the message flow in the documentation states that this should be "QRSCP" instead (so, DCM4CHEE2 in your example).

Am I misunderstanding something?

1 reply

vrindanayak Jul 15, 2025
Maintainer

In both Proxy Retrieve / Cache Retrieve flows,

the C-MOVE SCU (MOVESCU AET) initiates the C-MOVE request to the "Primary" C-MOVE SCP (DCM4CHEE AET) - which may not contain the study that C-MOVE SCU (MOVESCU AET) is requesting for
when "Primary" C-MOVE SCP (DCM4CHEE AET) doesn't find the objects of study to be retrieved in its own DB and if this feature is configured -> it will forward the C-MOVE request to "Fallback" C-MOVE SCP (in wiki QRSCP, in my test DCM4CHEE2)
the "Primary" C-MOVE SCP (DCM4CHEE AET) always acts as the C-MOVE SCU for the "Fallback" C-MOVE SCP (in wiki QRSCP, in my test DCM4CHEE2)

The only difference between Proxy Retrieve and Cache Retrieve flows, as you can see is that the configurations can be done in a way to tell the "Fallback" C-MOVE SCP to

either directly store the objects to "Initial" C-STORESCP (STORESCP AET) - Proxy Retrieve flow
or store the objects first to "Primary" C-MOVE SCP (DCM4CHEE AET) - which also acts as C-STORE SCP for the "Fallback" C-MOVE SCP. And then the "Primary" C-MOVE SCP (DCM4CHEE AET) will store it to the "Initial" C-STORE SCP (STORESCP AET) - Cache Retrieve flow

dbutti · 2025-07-15T15:29:05Z

dbutti
Jul 15, 2025
Author

Thanks, it is perfectly clear explained that way.

Then, wouldn't you agree that this part of the diagram is wrong, and the first ASSOCIATE-RQ should go to DCM4CHEE and not to QRSCP?

1 reply

vrindanayak Jul 17, 2025
Maintainer

I've rectified both diagrams, thanks for pointing it out.

Fallback C-Move SCP not working #4760

Uh oh!

dbutti Jul 10, 2025

Replies: 5 comments · 3 replies

Uh oh!

dbutti Jul 15, 2025 Author

Uh oh!

Uh oh!

vrindanayak Jul 15, 2025 Maintainer

Uh oh!

Uh oh!

vrindanayak Jul 15, 2025 Maintainer

Uh oh!

Uh oh!

vrindanayak Jul 15, 2025 Maintainer

Uh oh!

dbutti Jul 15, 2025 Author

Uh oh!

vrindanayak Jul 15, 2025 Maintainer

Uh oh!

dbutti Jul 15, 2025 Author

Uh oh!

vrindanayak Jul 17, 2025 Maintainer

dbutti
Jul 10, 2025

Replies: 5 comments 3 replies

dbutti
Jul 15, 2025
Author

vrindanayak Jul 15, 2025
Maintainer

vrindanayak
Jul 15, 2025
Maintainer

vrindanayak
Jul 15, 2025
Maintainer

dbutti
Jul 15, 2025
Author

vrindanayak Jul 15, 2025
Maintainer

dbutti
Jul 15, 2025
Author

vrindanayak Jul 17, 2025
Maintainer