LISTSERV 16.5 - XROOTD-DEV Archives

A trace of such an incident from the XrdCl client's perspective

[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] URL: root://tpsrv452.cern.ch:[log in to unmask]@castorns.6889850064&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.exptime=1521220581&castor.txtype=tape&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==
[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] Protocol:  root
[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] User Name: 
[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] Password:  
[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] Host Name: tpsrv452.cern.ch
[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] Port:      1095
[2018-03-16 17:16:21.366568 +0100][Dump   ][Utility           ] Path:      [log in to unmask]
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] URL: root://tpsrv452.cern.ch:[log in to unmask]@castorns.6889850064&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.exptime=1521220581&castor.txtype=tape&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] Protocol:  root
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] User Name: 
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] Password:  
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] Host Name: tpsrv452.cern.ch
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] Port:      1095
[2018-03-16 17:16:21.366640 +0100][Dump   ][Utility           ] Path:      [log in to unmask]
[2018-03-16 17:16:21.366657 +0100][Debug  ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Sending an open command
[2018-03-16 17:16:21.366680 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Sending message kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )
[2018-03-16 17:16:21.366699 +0100][Dump   ][PostMaster        ] [tpsrv452.cern.ch:1095 #0] Sending message kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60) through substream 0 expecting answer at 0
[2018-03-16 17:16:21.366745 +0100][Dump   ][AsyncSock         ] [tpsrv452.cern.ch:1095 #0.0] Wrote a message: kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60), 335 bytes
[2018-03-16 17:16:21.366762 +0100][Dump   ][AsyncSock         ] [tpsrv452.cern.ch:1095 #0.0] Successfully sent message: kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60).
[2018-03-16 17:16:21.366785 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Message kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) has been successfully sent.
[2018-03-16 17:17:22.009295 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Stream event reported for msg kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )
[2018-03-16 17:17:22.009306 +0100][Debug  ][XRootD            ] [tpsrv452.cern.ch:1095] Handling error while processing kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ): [ERROR] Socket error.
[2018-03-16 17:17:22.009309 +0100][Debug  ][File              ] [0x68008a40@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:ae8aa0b2-2588-42d5-b758-66526f24d8cc&castor.signature=QZLFVGTqrdWgudfJxCSROSDY/Se446SI26b4WjozzPe5olJk38zqr/OEZSSC1bgHTytJTjtjCBmqCvqFRlp7Mg==&castor.txtype=tape] Close returned from tpsrv452.cern.ch:1095 with: [ERROR] Socket error
[2018-03-16 17:17:22.009317 +0100][Dump   ][PostMaster        ] [tpsrv452.cern.ch:1095 #0] Sending message kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60) through substream 0 expecting answer at 0
[2018-03-16 17:17:22.013987 +0100][Dump   ][AsyncSock         ] [tpsrv452.cern.ch:1095 #0.0] Wrote a message: kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60), 335 bytes
[2018-03-16 17:17:22.013999 +0100][Dump   ][AsyncSock         ] [tpsrv452.cern.ch:1095 #0.0] Successfully sent message: kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60).
[2018-03-16 17:17:22.014008 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Message kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) has been successfully sent.
[2018-03-16 17:17:22.019252 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Got a kXR_ok response to request kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )
[2018-03-16 17:17:22.019267 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Parsing the response to kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) as OpenInfo
[2018-03-16 17:17:22.019276 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Parsing StatInfo in response to kXR_open (file: [log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )
[2018-03-16 17:17:22.019326 +0100][Debug  ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Open has returned with status [SUCCESS] 
[2018-03-16 17:17:22.019336 +0100][Debug  ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] successfully opened at tpsrv452.cern.ch:1095, handle: 0x2, session id: 6
[2018-03-16 17:17:22.019471 +0100][Debug  ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Sending a write command for handle 0x2 to tpsrv452.cern.ch:1095

Then there is a continuous stream of writes and the close:

[2018-03-16 17:19:18.271335 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Got a kXR_ok response to request kXR_write (handle: 0x02000000, offset: 3046113280, size: 8316)
[2018-03-16 17:19:18.271347 +0100][Dump   ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011
674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Got state response for message kXR_write (handle: 0x02000000, offset: 3046113280, size: 8316)
[2018-03-16 17:19:18.271403 +0100][Debug  ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011
674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Sending a close command for handle 0x2 to tpsrv452.cern.ch:1095
[2018-03-16 17:19:18.285227 +0100][Dump   ][XRootD            ] [tpsrv452.cern.ch:1095] Got a kXR_ok response to request kXR_close (handle: 0x02000000)
[2018-03-16 17:19:18.285248 +0100][Debug  ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011
674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Close returned from tpsrv452.cern.ch:1095 with: [SUCCESS] 
[2018-03-16 17:19:18.285302 +0100][Dump   ][File              ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]&[log in to unmask]&castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4&castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==&castor.txtype=tape] Items in the fly 0, queued for recovery 0

—
You are receiving this because you commented.
Reply to this email directly, view it on GitHub, or mute the thread.

{"api_version":"1.0","publisher":{"api_key":"05dde50f1d1a384dd78767c55493e4bb","name":"GitHub"},"entity":{"external_key":"github/xrootd/xrootd","title":"xrootd/xrootd","subtitle":"GitHub repository","main_image_url":"https://cloud.githubusercontent.com/assets/143418/17495839/a5054eac-5d88-11e6-95fc-7290892c7bb5.png","avatar_image_url":"https://cloud.githubusercontent.com/assets/143418/15842166/7c72db34-2c0b-11e6-9aed-b52498112777.png","action":{"name":"Open in GitHub","url":"https://github.com/xrootd/xrootd"}},"updates":{"snippets":[{"icon":"PERSON","message":"@esindril in #673: A trace of such an incident from the XrdCl client's perspective\r\n\r\n```\r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] URL: root://tpsrv452.cern.ch:[log in to unmask]@castorns.6889850064\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.exptime=1521220581\u0026castor.txtype=tape\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] Protocol: root\r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] User Name: \r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] Password: \r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] Host Name: tpsrv452.cern.ch\r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] Port: 1095\r\n[2018-03-16 17:16:21.366568 +0100][Dump ][Utility ] Path: [log in to unmask]\r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] URL: root://tpsrv452.cern.ch:[log in to unmask]@castorns.6889850064\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.exptime=1521220581\u0026castor.txtype=tape\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] Protocol: root\r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] User Name: \r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] Password: \r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] Host Name: tpsrv452.cern.ch\r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] Port: 1095\r\n[2018-03-16 17:16:21.366640 +0100][Dump ][Utility ] Path: [log in to unmask]\r\n[2018-03-16 17:16:21.366657 +0100][Debug ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Sending an open command\r\n[2018-03-16 17:16:21.366680 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Sending message kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )\r\n[2018-03-16 17:16:21.366699 +0100][Dump ][PostMaster ] [tpsrv452.cern.ch:1095 #0] Sending message kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60) through substream 0 expecting answer at 0\r\n[2018-03-16 17:16:21.366745 +0100][Dump ][AsyncSock ] [tpsrv452.cern.ch:1095 #0.0] Wrote a message: kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60), 335 bytes\r\n[2018-03-16 17:16:21.366762 +0100][Dump ][AsyncSock ] [tpsrv452.cern.ch:1095 #0.0] Successfully sent message: kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60).\r\n[2018-03-16 17:16:21.366785 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Message kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) has been successfully sent.\r\n[2018-03-16 17:17:22.009295 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Stream event reported for msg kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )\r\n[2018-03-16 17:17:22.009306 +0100][Debug ][XRootD ] [tpsrv452.cern.ch:1095] Handling error while processing kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ): [ERROR] Socket error.\r\n[2018-03-16 17:17:22.009309 +0100][Debug ][File ] [0x68008a40@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:ae8aa0b2-2588-42d5-b758-66526f24d8cc\u0026castor.signature=QZLFVGTqrdWgudfJxCSROSDY/Se446SI26b4WjozzPe5olJk38zqr/OEZSSC1bgHTytJTjtjCBmqCvqFRlp7Mg==\u0026castor.txtype=tape] Close returned from tpsrv452.cern.ch:1095 with: [ERROR] Socket error\r\n[2018-03-16 17:17:22.009317 +0100][Dump ][PostMaster ] [tpsrv452.cern.ch:1095 #0] Sending message kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60) through substream 0 expecting answer at 0\r\n[2018-03-16 17:17:22.013987 +0100][Dump ][AsyncSock ] [tpsrv452.cern.ch:1095 #0.0] Wrote a message: kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60), 335 bytes\r\n[2018-03-16 17:17:22.013999 +0100][Dump ][AsyncSock ] [tpsrv452.cern.ch:1095 #0.0] Successfully sent message: kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) (0x70005e60).\r\n[2018-03-16 17:17:22.014008 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Message kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) has been successfully sent.\r\n[2018-03-16 17:17:22.019252 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Got a kXR_ok response to request kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )\r\n[2018-03-16 17:17:22.019267 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Parsing the response to kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat ) as OpenInfo\r\n[2018-03-16 17:17:22.019276 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Parsing StatInfo in response to kXR_open (file: [log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape, mode: 00, flags: kXR_delete kXR_seqio kXR_async kXR_retstat )\r\n[2018-03-16 17:17:22.019326 +0100][Debug ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Open has returned with status [SUCCESS] \r\n[2018-03-16 17:17:22.019336 +0100][Debug ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] successfully opened at tpsrv452.cern.ch:1095, handle: 0x2, session id: 6\r\n[2018-03-16 17:17:22.019471 +0100][Debug ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Sending a write command for handle 0x2 to tpsrv452.cern.ch:1095\r\n```\r\n\r\nThen there is a continuous stream of writes and the close:\r\n\r\n```\r\n[2018-03-16 17:19:18.271335 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Got a kXR_ok response to request kXR_write (handle: 0x02000000, offset: 3046113280, size: 8316)\r\n[2018-03-16 17:19:18.271347 +0100][Dump ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011\r\n674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Got state response for message kXR_write (handle: 0x02000000, offset: 3046113280, size: 8316)\r\n[2018-03-16 17:19:18.271403 +0100][Debug ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011\r\n674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Sending a close command for handle 0x2 to tpsrv452.cern.ch:1095\r\n[2018-03-16 17:19:18.285227 +0100][Dump ][XRootD ] [tpsrv452.cern.ch:1095] Got a kXR_ok response to request kXR_close (handle: 0x02000000)\r\n[2018-03-16 17:19:18.285248 +0100][Debug ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011\r\n674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Close returned from tpsrv452.cern.ch:1095 with: [SUCCESS] \r\n[2018-03-16 17:19:18.285302 +0100][Dump ][File ] [0x7000f0a0@root://tpsrv452.cern.ch:[log in to unmask]\[log in to unmask]\u0026castor.pfn2=0:15511:660c04e5-491a-4d53-abe6-011674bb3fd4\u0026castor.signature=M+zmafoPSQ1Dh9WQvf2j2s+Ppg3atlDffJPwQzvsTAlf1NnoxQjMl2SlFuTFOqEdlAcGqkvH5RvaK+vVhRQ/4g==\u0026castor.txtype=tape] Items in the fly 0, queued for recovery 0\r\n```\r\n"}],"action":{"name":"View Issue","url":"https://github.com/xrootd/xrootd/issues/673#issuecomment-374870385"}}}

Use REPLY-ALL to reply to list

To unsubscribe from the XROOTD-DEV list, click the following link:
https://listserv.slac.stanford.edu/cgi-bin/wa?SUBED1=XROOTD-DEV&A=1