 |
 |
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 or view it on GitHub: 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