What does it mean when you try to send to another AETitle and you get this error? 90% of the time we try to send out, it works fine, but the other 10% we get this error. How can we fix it?
Thanks
Failed SOP Instances...
The error means those particular instances failed to be forwarded to the destination AE, which could be due to many reasons, e.g., network congestion, the destination AE has crashed, the destination AE does not support the Dicom storage SOP class of those particular instances, etc.
If you retry the very same instances and they go through successfully, then it seems like a network congestion issue. Otherwise if those instances fail to be forwarded to the destination AE consistently, then you should check the Dicom Conformance Statement of the destination AE and make sure it supports the particular Dicom storage SOP class for those images.
You may also want to check the PacsOne Server logs under the log/ sub-folder where PacsOne is installed, as well as the destination AE to see if there is any error indication there.
If you retry the very same instances and they go through successfully, then it seems like a network congestion issue. Otherwise if those instances fail to be forwarded to the destination AE consistently, then you should check the Dicom Conformance Statement of the destination AE and make sure it supports the particular Dicom storage SOP class for those images.
You may also want to check the PacsOne Server logs under the log/ sub-folder where PacsOne is installed, as well as the destination AE to see if there is any error indication there.
We are still having issues with this happening and it is getting worse. Looking at the logs these are the different errors we are getting:
Thu Nov 6 14:40:05 2008 INFO - Processing database job <222>
Thu Nov 6 14:40:05 2008 INFO - C-STORE Forward-session: <87223> started for SCP: 192.168.10.37 Port: 104
Thu Nov 6 14:40:05 2008 INFO - <87223> Sending A-ASSOCIATE-RQ PDU
Thu Nov 6 14:40:05 2008 INFO - <87223> Waiting for A-ASSOCIATE result
Thu Nov 6 14:40:05 2008 INFO - <87223> A-ASSOCIATE-RQ accepted
Thu Nov 6 14:40:05 2008 INFO - <87223> Sending P-DATA-TF PDU
Thu Nov 6 14:40:05 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/raid/archive/Old_Archive/1.3.12.2.1107.5.12.7.1039.30000006052612152815600000027.dcm>
Thu Nov 6 14:40:05 2008 ERROR - <87223> Received A-ABORT PDU, aborting
When we tried to find this file, it didn't exist.
Any ideas as to how this is happening?
Thu Nov 6 14:40:05 2008 INFO - Processing database job <222>
Thu Nov 6 14:40:05 2008 INFO - C-STORE Forward-session: <87223> started for SCP: 192.168.10.37 Port: 104
Thu Nov 6 14:40:05 2008 INFO - <87223> Sending A-ASSOCIATE-RQ PDU
Thu Nov 6 14:40:05 2008 INFO - <87223> Waiting for A-ASSOCIATE result
Thu Nov 6 14:40:05 2008 INFO - <87223> A-ASSOCIATE-RQ accepted
Thu Nov 6 14:40:05 2008 INFO - <87223> Sending P-DATA-TF PDU
Thu Nov 6 14:40:05 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/raid/archive/Old_Archive/1.3.12.2.1107.5.12.7.1039.30000006052612152815600000027.dcm>
Thu Nov 6 14:40:05 2008 ERROR - <87223> Received A-ABORT PDU, aborting
When we tried to find this file, it didn't exist.
Any ideas as to how this is happening?
Make sure the user account PacsOne Server service is running under as sufficient permissions to read/write/create files under the assigned archive directories, e.g., in this case, PacsOne could not create the file /mnt/raid/archive/Old_Archive/1.3.12.2.1107.5.12.7.1039.30000006052612152815600000027.dcm to store the receivedi image.gjame wrote: Thu Nov 6 14:40:05 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/raid/archive/Old_Archive/1.3.12.2.1107.5.12.7.1039.30000006052612152815600000027.dcm>
I don't think this is the issue as everything is set to full permissions <777>. This wasn't a problem at first, but now it seems to keep getting worse. We've also noticed when searching for a patient, there could be 10 pages of results that all list the same patient, but the number of studies is 0 for all but 1 or 2 of the listings where it will have the actual studies. It acts like the pointer to where the file is located is broken. When we try to pull studies from our pacs server rather than pacsone, this is what shows up in the logs:
Wed Nov 12 11:18:32 2008 INFO - 93 tags processed in CDataSetPdv
Wed Nov 12 11:18:32 2008 INFO - <127583> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:32 2008 INFO - <127583> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:32 2008 INFO - <127583> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:32 2008 INFO - Session: <127583> disconnected for client: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - Session: <127584> started from SCU: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127584> Receiving A-ASSOCIATE-RQ PDU
Wed Nov 12 11:18:35 2008 INFO - 1 record found in [applentity] table
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-RQ received successfully
Wed Nov 12 11:18:35 2008 INFO - <127584> Sending A-ASSOCIATE-AC PDU
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-AC PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - <127584> Receiving P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - <127584> C-STORE [1] command received in P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - Session: <127585> started from SCU: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127585> Receiving A-ASSOCIATE-RQ PDU
Wed Nov 12 11:18:35 2008 INFO - 1 record found in [applentity] table
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-RQ received successfully
Wed Nov 12 11:18:35 2008 INFO - <127585> Sending A-ASSOCIATE-AC PDU
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-AC PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - <127585> Receiving P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - <127585> C-STORE [1] command received in P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - Session: <127586> started from SCU: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127586> Receiving A-ASSOCIATE-RQ PDU
Wed Nov 12 11:18:35 2008 INFO - 1 record found in [applentity] table
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-RQ received successfully
Wed Nov 12 11:18:35 2008 INFO - <127586> Sending A-ASSOCIATE-AC PDU
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-AC PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - <127586> Receiving P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 WARNING - Tag: 0x00080008 length [56] will be truncated to maximum of [16]
Wed Nov 12 11:18:35 2008 WARNING - Tag: 0x00080008 length [54] will be truncated to maximum of [16]
Wed Nov 12 11:18:35 2008 INFO - 83 tags processed in CDataSetPdv
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:35 2008 INFO - <127584> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - 95 tags processed in CDataSetPdv
Wed Nov 12 11:18:35 2008 INFO - Session: <127584> disconnected for client: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:35 2008 INFO - <127585> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - Session: <127585> disconnected for client: 192.168.10.42
Wed Nov 12 11:18:35 2008 WARNING - Tag: 0x00080008 length [34] will be truncated to maximum of [16]
Wed Nov 12 11:18:35 2008 INFO - 93 tags processed in CDataSetPdv
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:35 2008 INFO - <127586> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - Session: <127586> disconnected for client: 192.168.10.42
It doesn't look like there are any errors, but the images never show up on our pacs server.
Wed Nov 12 11:18:32 2008 INFO - 93 tags processed in CDataSetPdv
Wed Nov 12 11:18:32 2008 INFO - <127583> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:32 2008 INFO - <127583> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:32 2008 INFO - <127583> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:32 2008 INFO - Session: <127583> disconnected for client: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - Session: <127584> started from SCU: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127584> Receiving A-ASSOCIATE-RQ PDU
Wed Nov 12 11:18:35 2008 INFO - 1 record found in [applentity] table
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-RQ received successfully
Wed Nov 12 11:18:35 2008 INFO - <127584> Sending A-ASSOCIATE-AC PDU
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-AC PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - <127584> Receiving P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - <127584> C-STORE [1] command received in P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - Session: <127585> started from SCU: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127585> Receiving A-ASSOCIATE-RQ PDU
Wed Nov 12 11:18:35 2008 INFO - 1 record found in [applentity] table
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-RQ received successfully
Wed Nov 12 11:18:35 2008 INFO - <127585> Sending A-ASSOCIATE-AC PDU
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-AC PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - <127585> Receiving P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - <127585> C-STORE [1] command received in P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 INFO - Session: <127586> started from SCU: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127586> Receiving A-ASSOCIATE-RQ PDU
Wed Nov 12 11:18:35 2008 INFO - 1 record found in [applentity] table
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-RQ received successfully
Wed Nov 12 11:18:35 2008 INFO - <127586> Sending A-ASSOCIATE-AC PDU
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-AC PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - <127586> Receiving P-DATA-TF PDU
Wed Nov 12 11:18:35 2008 WARNING - Tag: 0x00080008 length [56] will be truncated to maximum of [16]
Wed Nov 12 11:18:35 2008 WARNING - Tag: 0x00080008 length [54] will be truncated to maximum of [16]
Wed Nov 12 11:18:35 2008 INFO - 83 tags processed in CDataSetPdv
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:35 2008 INFO - <127584> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:35 2008 INFO - <127584> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - 95 tags processed in CDataSetPdv
Wed Nov 12 11:18:35 2008 INFO - Session: <127584> disconnected for client: 192.168.10.42
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:35 2008 INFO - <127585> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:35 2008 INFO - <127585> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - Session: <127585> disconnected for client: 192.168.10.42
Wed Nov 12 11:18:35 2008 WARNING - Tag: 0x00080008 length [34] will be truncated to maximum of [16]
Wed Nov 12 11:18:35 2008 INFO - 93 tags processed in CDataSetPdv
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-RELEASE-RQ PDU received
Wed Nov 12 11:18:35 2008 INFO - <127586> Sending A-ASSOCIATE-RELEASE-RSP PDU
Wed Nov 12 11:18:35 2008 INFO - <127586> A-ASSOCIATE-RELEASE-RSP PDU sent successfully
Wed Nov 12 11:18:35 2008 INFO - Session: <127586> disconnected for client: 192.168.10.42
It doesn't look like there are any errors, but the images never show up on our pacs server.
The logs you just posted are for an inbound association where a remote AE was sending images to PacsOne Server, but the error you mentioned in the original post was about errors when PacsOne Server was sending images to a remote AE (outbound associations). So can you describe in details about exactly where the problem is?
We are unable to pull studies from pacsone from our pacs our modalities, and we can't send them from pacsone either. Here's an error when trying to send a study from pacsone to our pacs system:
Thu Nov 13 09:32:00 2008 INFO - Processing database job <228>
Thu Nov 13 09:32:00 2008 INFO - C-STORE Forward-session: <137411> started for SCP: 192.168.10.42 Port: 104
Thu Nov 13 09:32:00 2008 INFO - <137411> Sending A-ASSOCIATE-RQ PDU
Thu Nov 13 09:32:00 2008 INFO - <137411> Waiting for A-ASSOCIATE result
Thu Nov 13 09:32:00 2008 INFO - <137411> A-ASSOCIATE-RQ accepted
Thu Nov 13 09:32:00 2008 INFO - <137411> Sending P-DATA-TF PDU
Thu Nov 13 09:32:00 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/sdb/archive/2008/05/21/1.3.12.2.1107.5.6.1.10109.30050108052120293632800000002.DCM>
Thu Nov 13 09:32:00 2008 ERROR - CSocketServer: Session 137411 remote error: [0]
Thu Nov 13 09:32:00 2008 INFO - C-STORE Forward-session: <137412> started for SCP: 192.168.10.42 Port: 104
Thu Nov 13 09:32:00 2008 INFO - <137412> Sending A-ASSOCIATE-RQ PDU
Thu Nov 13 09:32:00 2008 INFO - Session: <137411> disconnected for client: 192.168.10.42
Thu Nov 13 09:32:00 2008 INFO - <137412> Waiting for A-ASSOCIATE result
Thu Nov 13 09:32:01 2008 INFO - <137412> A-ASSOCIATE-RQ accepted
Thu Nov 13 09:32:01 2008 INFO - <137412> Sending P-DATA-TF PDU
Thu Nov 13 09:32:01 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/sdb/archive/2008/05/21/1.3.12.2.1107.5.6.1.10109.30050108052120293632800000018.DCM>
Thu Nov 13 09:32:01 2008 ERROR - CSocketServer: Session 137412 remote error: [0]
Thu Nov 13 09:32:01 2008 INFO - Session: <137412> disconnected for client: 192.168.10.42
It says it cannot open file, but the file doesn't exist which doesn't make sense because it was sent to pacsone from our pacs. Permissions are set for full access so it should have been able to create the file. It's baffling us and we really don't want to have to re-import all our studies again. It was working well for a few months and then this all of a sudden started happening. Also, when I did a search for this patient, we get like 10 pages of results, all of the same patient, but there's only one of the entries that actually has any studies associated with it.
Thu Nov 13 09:32:00 2008 INFO - Processing database job <228>
Thu Nov 13 09:32:00 2008 INFO - C-STORE Forward-session: <137411> started for SCP: 192.168.10.42 Port: 104
Thu Nov 13 09:32:00 2008 INFO - <137411> Sending A-ASSOCIATE-RQ PDU
Thu Nov 13 09:32:00 2008 INFO - <137411> Waiting for A-ASSOCIATE result
Thu Nov 13 09:32:00 2008 INFO - <137411> A-ASSOCIATE-RQ accepted
Thu Nov 13 09:32:00 2008 INFO - <137411> Sending P-DATA-TF PDU
Thu Nov 13 09:32:00 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/sdb/archive/2008/05/21/1.3.12.2.1107.5.6.1.10109.30050108052120293632800000002.DCM>
Thu Nov 13 09:32:00 2008 ERROR - CSocketServer: Session 137411 remote error: [0]
Thu Nov 13 09:32:00 2008 INFO - C-STORE Forward-session: <137412> started for SCP: 192.168.10.42 Port: 104
Thu Nov 13 09:32:00 2008 INFO - <137412> Sending A-ASSOCIATE-RQ PDU
Thu Nov 13 09:32:00 2008 INFO - Session: <137411> disconnected for client: 192.168.10.42
Thu Nov 13 09:32:00 2008 INFO - <137412> Waiting for A-ASSOCIATE result
Thu Nov 13 09:32:01 2008 INFO - <137412> A-ASSOCIATE-RQ accepted
Thu Nov 13 09:32:01 2008 INFO - <137412> Sending P-DATA-TF PDU
Thu Nov 13 09:32:01 2008 ERROR - C-STORE-RQ DataSet PDV: Cannot open file </mnt/sdb/archive/2008/05/21/1.3.12.2.1107.5.6.1.10109.30050108052120293632800000018.DCM>
Thu Nov 13 09:32:01 2008 ERROR - CSocketServer: Session 137412 remote error: [0]
Thu Nov 13 09:32:01 2008 INFO - Session: <137412> disconnected for client: 192.168.10.42
It says it cannot open file, but the file doesn't exist which doesn't make sense because it was sent to pacsone from our pacs. Permissions are set for full access so it should have been able to create the file. It's baffling us and we really don't want to have to re-import all our studies again. It was working well for a few months and then this all of a sudden started happening. Also, when I did a search for this patient, we get like 10 pages of results, all of the same patient, but there's only one of the entries that actually has any studies associated with it.
These errors suggest the raw Dicom image files were removed from the archive directory but PacsOne was not aware of it, so the PacsOne database records were still linking to these files, but the actual raw image files have been deleted.
You should check the security permissions for the archive directories and make sure no other application can remove files under the archive directories. Also, you should check and find if there is any more of these missing image files by running the PacsOne Server database integrity check:
http://localhost/pacsone/integrityCheck.php
You should check the security permissions for the archive directories and make sure no other application can remove files under the archive directories. Also, you should check and find if there is any more of these missing image files by running the PacsOne Server database integrity check:
http://localhost/pacsone/integrityCheck.php