false log information/or bad interpretation ERROR [FileSystemMgt2Service] High Water Mark

392 views

Skip to first unread message

rrose...@securerad.com

unread,

Nov 14, 2013, 8:25:26 AM11/14/13

to dcm...@googlegroups.com

going way back (07/Dec/10) Damien wrote:

"In a default installation (i.e. most of the ones that I see) these warnings are printed out all of the time, even if the system has 10 TB of free space! They become noise, so that if there was a real warning it would probably be ignored."

So, I'm running a dcmsnd to studies on an external drive to a dcm4chee-2.17.3 instance, which has about 10TB of free space via a NAS, which is functioning fine, attached, and working with other instances of dcm4chee. dcmsnd got nearly to the end of the external drive when I started getting errors.….. So, is the dcmsnd failing?? I don't understand why it did when it did. I have plenty of storage left. The dcmsnd was running for a couple of days without error when this started.

The following are not one message matched exactly to another (error A to dcmsnd error A), but the same messages applies to all

from dcm4chee log:

onfigured storage directory

07:58:41,111 ERROR [FileSystemMgt2Service] High Water Mark reached on storage file system FileSystem[pk=1, archive, groupID=ONLINE_STORAGE, aet=SR_NUADA, ONLINE, RW+, userinfo=null] - no alternative storage file system configured for file system group ONLINE_STORAGE

07:58:41,112 WARN [StoreScpService] org.dcm4che.net.DcmServiceException

org.dcm4che.net.DcmServiceException: org.dcm4che.net.DcmServiceException

at org.dcm4chex.archive.dcm.storescp.StoreScpService.selectStorageFileSystem(StoreScpService.java:803)

at org.dcm4chex.archive.dcm.storescp.StoreScp.doActualCStore(StoreScp.java:635)

at org.dcm4chex.archive.dcm.storescp.StoreScp.doCStore(StoreScp.java:471)

at org.dcm4che.net.DcmServiceBase.c_store(DcmServiceBase.java:116)

at org.dcm4cheri.net.ActiveAssociationImpl.run(ActiveAssociationImpl.java:230)

at org.dcm4cheri.util.LF_ThreadPool.join(LF_ThreadPool.java:174)

at org.dcm4cheri.net.ActiveAssociationImpl.run(ActiveAssociationImpl.java:164)

at org.dcm4cheri.server.DcmHandlerImpl.handle(DcmHandlerImpl.java:249)

at org.dcm4cheri.server.ServerImpl.run(ServerImpl.java:288)

at org.dcm4cheri.util.LF_ThreadPool.join(LF_ThreadPool.java:174)

at org.dcm4cheri.util.LF_ThreadPool$1.run(LF_ThreadPool.java:221)

at java.lang.Thread.run(Thread.java:680)

Caused by: org.dcm4che.net.DcmServiceException

at org.dcm4chex.archive.dcm.storescp.StoreScpService.selectStorageFileSystem(StoreScpService.java:800)

... 11 more

07:58:41,112 INFO [FsmImpl] sending [pc-1] 4021:C_STORE_RSP

class: 1.2.840.10008.5.1.4.1.1.4/MR Image Storage

inst: 1.3.12.2.1107.5.2.36.40442.201307011251149228075987/?

status: 110

error comment: org.dcm4che.net.DcmServiceException

07:58:41,115 INFO [FsmImpl] received [pc-1] 4022:C_STORE_RQ with Dataset

class: 1.2.840.10008.5.1.4.1.1.4/MR Image Storage

inst: 1.3.12.2.1107.5.2.36.40442.2013070112511298620775941/?

from dcmsnd:

F07:58:41,216 INFO - SR_NUADA(1) << 4037:C-STORE-RQ[pcid=1, prior=0

cuid=1.2.840.10008.5.1.4.1.1.4/MR Image Storage

iuid=1.3.12.2.1107.5.2.36.40442.2013070112511478680076020

ts=1.2.840.10008.1.2/Implicit VR Little Endian]

07:58:41,220 INFO - SR_NUADA(1) >> 4037:C-STORE-RSP[pcid=1, status=110H

error=org.dcm4che.net.DcmServiceException

cuid=1.2.840.10008.5.1.4.1.1.4/MR Image Storage

iuid=1.3.12.2.1107.5.2.36.40442.2013070112511478680076020]

ERROR: Received RSP with Status 0110H for 7/1/13/6B5F6855/42520991/E71FE48C, cuid=1.2.840.10008.5.1.4.1.1.4, tsuid=1.2.840.10008.1.2

(0000,0002) UI #26 [1.2.840.10008.5.1.4.1.1.4] Affected SOP Class UID

(0000,0100) US #2 [32769] Command Field

(0000,0120) US #2 [4037] Message ID Being Responded To

(0000,0800) US #2 [257] Command Data Set Type

(0000,0900) US #2 [272] Status

(0000,0902) LO #36 [org.dcm4che.net.DcmServiceException] Error Comment

(0000,1000) UI #52 [1.3.12.2.1107.5.2.36.40442.2013070112511478680076020] Affected SOP Instance UID

Thanks for any input,

Rich

fleetwoodfc

unread,

Nov 14, 2013, 12:23:54 PM11/14/13

to dcm...@googlegroups.com

Looks like the dcm4chee instance has not been configured to use the NAS storage - has been writing to the default archive location which is now full.

rrose...@securerad.com

unread,

Nov 14, 2013, 12:57:58 PM11/14/13

to dcm...@googlegroups.com

No, the default archive is a symlink pointing to the mounted directory of the NAS, which is the way all the instances are configured, and worked fine up until the last part of the dcmsnd. Using various methods, there is still 10TB available.

i.e.:

securecloud-host-04:archive admin$ cd /securecloud/dcm4chee-2.17.3-nuada/server/default/

securecloud-host-04:default admin$ ls -la

total 8

drwxr-xr-x@ 14 admin wheel 476 Nov 12 01:15 .

drwxr-xr-x@ 3 admin wheel 102 Jan 31 2013 ..

lrwxr-xr-x 1 admin wheel 36 Nov 12 01:15 archive -> /Volumes/pod03-Store2/nuada/archive/

rrose...@securerad.com

unread,

Nov 14, 2013, 1:18:37 PM11/14/13

to dcm...@googlegroups.com

Which also makes me wonder - how does dcm4chee come up with:

"[FileSystemMgt2Service] Free disk space on /securecloud/dcm4chee-2.17.3-nuada/server/default/archive: 20.19584MB"

How did it come up with 20MB? If I can replicate that task, maybe I can figure out why. DF/DU/the NAS OS and more show 669GB used/ 10T available…only using 6% of capacity

[pacs@pod03 /mnt/Store2]$ df -h nuada/

Filesystem Size Used Avail Capacity Mounted on

Store2 10T 669G 10T 6% /mnt/Store2

David Davies

unread,

Nov 14, 2013, 1:41:16 PM11/14/13

to dcm...@googlegroups.com

By default it uses 'df -P' - this is configurable via jmx-console->service=dfcmd

On 11/14/13 1:18 PM, rrose...@securerad.com wrote:

Which also makes me wonder - how does dcm4chee come up with:
"[FileSystemMgt2Service] Free disk space on /securecloud/dcm4chee-2.17.3-nuada/server/default/archive: 20.19584MB"

How did it come up with 20MB? If I can replicate that task, maybe I can figure out why. DF/DU/the NAS OS and more show 669GB used/ 10T availableï¿½only using 6% of capacity

[pacs@pod03 /mnt/Store2]$ df -h nuada/

Filesystem ï¿½ ï¿½Size ï¿½ ï¿½Used ï¿½ Avail Capacity ï¿½Mounted on

Store2 ï¿½ ï¿½ ï¿½ ï¿½ 10T ï¿½ ï¿½669G ï¿½ ï¿½ 10T ï¿½ ï¿½ 6% ï¿½ ï¿½/mnt/Store2

On Thursday, November 14, 2013 12:57:58 PM UTC-5, rrose...@securerad.com wrote:

No, the default archive is a symlink pointing to the mounted directory of the NAS, which is the way all the instances are configured, and worked fine up until the last part of the dcmsnd. Using various methods, there is still 10TB available.

i.e.:

securecloud-host-04:archive admin$ cd /securecloud/dcm4chee-2.17.3-nuada/server/default/

securecloud-host-04:default admin$ ls -la

total 8

drwxr-xr-x@ ï¿½14 admin ï¿½wheel ï¿½ 476 Nov 12 01:15 .

drwxr-xr-x@ ï¿½ 3 admin ï¿½wheel ï¿½ 102 Jan 31 ï¿½2013 ..

lrwxr-xr-x ï¿½ ï¿½1 admin ï¿½wheel ï¿½ ï¿½36 Nov 12 01:15 archive -> /Volumes/pod03-Store2/nuada/archive/

On Thursday, November 14, 2013 12:23:54 PM UTC-5, fleetwoodfc wrote:

Looks like the dcm4chee instance has not been configured to use the NAS storage - has been writing to the default archive location which is now full.

On Thursday, November 14, 2013 8:25:26 AM UTC-5, rrose...@securerad.com wrote:

going way back (07/Dec/10) Damien wrote:
"In a default installation (i.e. most of the ones that I see) these warnings are printed out all of the time, even if the system has 10 TB of free space! They become noise, so that if there was a real warning it would probably be ignored."

So, I'm running a dcmsnd to studies on an external drive to a dcm4chee-2.17.3 instance, which has about 10TB of free space via a NAS, which is functioning fine, attached, and working with other instances of dcm4chee. dcmsnd got nearly to the end of the external drive when I started getting errors.ï¿½.. So, is the dcmsnd failing?? I don't understand why it did when it did. I have plenty of storage left. The dcmsnd was running for a couple of days without error when this started.

The following are not one message matched exactly to another (error A to dcmsnd error A), but the same messagesï¿½applies to all

from dcm4chee log:

onfigured storage directory

07:58:41,111 ERROR [FileSystemMgt2Service] High Water Mark reached on storage file system FileSystem[pk=1, archive, groupID=ONLINE_STORAGE, aet=SR_NUADA, ONLINE, RW+, userinfo=null] - no alternative storage file system configured for file system group ONLINE_STORAGE

07:58:41,112 WARN ï¿½[StoreScpService] org.dcm4che.net.DcmServiceException

org.dcm4che.net.DcmServiceException: org.dcm4che.net.DcmServiceException

at org.dcm4chex.archive.dcm.storescp.StoreScpService.selectStorageFileSystem(StoreScpService.java:803)

at org.dcm4chex.archive.dcm.storescp.StoreScp.doActualCStore(StoreScp.java:635)

at org.dcm4chex.archive.dcm.storescp.StoreScp.doCStore(StoreScp.java:471)

at org.dcm4che.net.DcmServiceBase.c_store(DcmServiceBase.java:116)

at org.dcm4cheri.net.ActiveAssociationImpl.run(ActiveAssociationImpl.java:230)

at org.dcm4cheri.util.LF_ThreadPool.join(LF_ThreadPool.java:174)

at org.dcm4cheri.net.ActiveAssociationImpl.run(ActiveAssociationImpl.java:164)

at org.dcm4cheri.server.DcmHandlerImpl.handle(DcmHandlerImpl.java:249)

at org.dcm4cheri.server.ServerImpl.run(ServerImpl.java:288)

at org.dcm4cheri.util.LF_ThreadPool.join(LF_ThreadPool.java:174)

at org.dcm4cheri.util.LF_ThreadPool$1.run(LF_ThreadPool.java:221)

at java.lang.Thread.run(Thread.java:680)

Caused by: org.dcm4che.net.DcmServiceException

at org.dcm4chex.archive.dcm.storescp.StoreScpService.selectStorageFileSystem(StoreScpService.java:800)

... 11 more

07:58:41,112 INFO ï¿½[FsmImpl] sending [pc-1] 4021:C_STORE_RSP

class: 1.2.840.10008.5.1.4.1.1.4/MR Image Storage

inst: 1.3.12.2.1107.5.2.36.40442.201307011251149228075987/?

status: 110

error comment: org.dcm4che.net.DcmServiceException

07:58:41,115 INFO ï¿½[FsmImpl] received [pc-1] 4022:C_STORE_RQ with Dataset

class: 1.2.840.10008.5.1.4.1.1.4/MR Image Storage

inst: 1.3.12.2.1107.5.2.36.40442.2013070112511298620775941/?

from dcmsnd:

F07:58:41,216 INFO ï¿½ - SR_NUADA(1) << 4037:C-STORE-RQ[pcid=1, prior=0

cuid=1.2.840.10008.5.1.4.1.1.4/MR Image Storage

iuid=1.3.12.2.1107.5.2.36.40442.2013070112511478680076020

ts=1.2.840.10008.1.2/Implicit VR Little Endian]

07:58:41,220 INFO ï¿½ - SR_NUADA(1) >> 4037:C-STORE-RSP[pcid=1, status=110H

error=org.dcm4che.net.DcmServiceException

cuid=1.2.840.10008.5.1.4.1.1.4/MR Image Storage

iuid=1.3.12.2.1107.5.2.36.40442.2013070112511478680076020]

ERROR: Received RSP with Status 0110H for 7/1/13/6B5F6855/42520991/E71FE48C, cuid=1.2.840.10008.5.1.4.1.1.4, tsuid=1.2.840.10008.1.2

(0000,0002) UI #26 [1.2.840.10008.5.1.4.1.1.4] Affected SOP Class UID

(0000,0100) US #2 [32769] Command Field

(0000,0120) US #2 [4037] Message ID Being Responded To

(0000,0800) US #2 [257] Command Data Set Type

(0000,0900) US #2 [272] Status

(0000,0902) LO #36 [org.dcm4che.net.DcmServiceException] Error Comment

(0000,1000) UI #52 [1.3.12.2.1107.5.2.36.40442.2013070112511478680076020] Affected SOP Instance UID

Thanks for any input,

Rich

--
You received this message because you are subscribed to a topic in the Google Groups "dcm4che" group.
To unsubscribe from this topic, visit https://groups.google.com/d/topic/dcm4che/c1pss9Fj3Lw/unsubscribe.
To unsubscribe from this group and all its topics, send an email to dcm4che+u...@googlegroups.com.
To post to this group, send email to dcm...@googlegroups.com.
Visit this group at http://groups.google.com/group/dcm4che.
For more options, visit https://groups.google.com/groups/opt_out.

rrose...@securerad.com

unread,

Nov 14, 2013, 1:51:45 PM11/14/13

to dcm...@googlegroups.com, dda...@eesimed.com

Thanks for the info…..

So then df -P from both the dcm4chee server (OS X) and directly on the storage (FreeBSD). Neither are close to 20MB which is what dcm4chee is reporting….

OS X:

securecloud-host-04:2013 admin$ df -P /Volumes/pod03-Store2/nuada/archive/

Filesystem 512-blocks Used Available Capacity Mounted on

//pa...@192.168.179.157/pod03-Store2 22878437519 1403561594 21474875925 7% /Volumes/pod03-Store2

Storage:

[pacs@pod03 /mnt/Store2/nuada]$ df -P archive/

Filesystem 512-blocks Used Avail Capacity Mounted on

Store2 22878437519 1403561594 21474875925 6% /mnt/Store2

Now what????

David Davies

unread,

Nov 14, 2013, 3:11:09 PM11/14/13

to dcm...@googlegroups.com

What does "df -P /securecloud/dcm4chee-2.17.3-nuada/server/default/archive"ï¿½ report?

On 11/14/13 1:51 PM, rrose...@securerad.com wrote:

Thanks for the infoï¿½..

So then df -P from both the dcm4chee server (OS X) and directly on the storage (FreeBSD). Neither are close to 20MB which is what dcm4chee is reportingï¿½.

OS X:

securecloud-host-04:2013 admin$ df -P /Volumes/pod03-Store2/nuada/archive/

Filesystem ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ ï¿½ 512-blocks ï¿½ ï¿½ ï¿½ Used ï¿½ Available Capacity ï¿½Mounted on

//pa...@192.168.179.157/pod03-Store2 22878437519 1403561594 21474875925 ï¿½ ï¿½ 7% ï¿½ ï¿½/Volumes/pod03-Store2

Storage:

[pacs@pod03 /mnt/Store2/nuada]$ df -P archive/

Filesystem ï¿½512-blocks ï¿½ ï¿½ ï¿½ Used ï¿½ ï¿½ ï¿½ Avail Capacity ï¿½Mounted on

Store2 ï¿½ ï¿½ 22878437519 1403561594 21474875925 ï¿½ ï¿½ 6% ï¿½ ï¿½/mnt/Store2

Reply all

Reply to author

Forward

0 new messages