 |
 |
Thanks for the update. Unfortunately, this doesn't look like it solves the problem. Rather, it looks like the client is using the file/CGI-opaque when building the login token. I ran this command: `xrdcp -v -d3 /bin/bash root://localhost/public/test-1` Execuative summary: dCache redirection targets: ``` localhost?org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376&org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45?org.dcache.door=localhost:1094 ``` The login token should be `org.dcache.door=localhost:1094`; however, the client sends a login token that includes the file-specific CGI elements: ``` xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=sprocket.fritz.box&xrd.rn=v20220314-3c87024f8&org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376 ``` Here are all the details from the above command. Here is what dCache says: ``` level=INFO ts=2022-03-14T12:47:22.435+0100 event=org.dcache.xrootd.connection.start session=door:Xrootd-sprocket@dCacheDomain:AAXaLD5QHFg socket.remote=127.0.0.1:56346 socket.local=127.0.0.1:1094 level=INFO ts=2022-03-14T12:47:22.500+0100 event=org.dcache.xrootd.request session=door:Xrootd-sprocket@dCacheDomain:AAXaLD5QHFg request=protocol response=ok level=INFO ts=2022-03-14T12:47:22.519+0100 event=org.dcache.xrootd.request session=door:Xrootd-sprocket@dCacheDomain:AAXaLD5QHFg request=login username=paul capver=5 pid=19732 token=xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=sprocket.fritz.box&xrd.rn=v20220314-3c87024f8 response=ok sessionId=A4573F2ACA7244C2C45494051BC63EFF level=ERROR ts=2022-03-14T12:47:22.553+0100 event=org.dcache.xrootd.request session=door:Xrootd-sprocket@dCacheDomain:AAXaLD5QHFg request=stat path=public/test-1 vfs=false response=error error.code=NotFound error.msg="No such file" level=INFO ts=2022-03-14T12:47:22.767+0100 event=org.dcache.xrootd.request session=door:Xrootd-sprocket@dCacheDomain:AAXaLD5QHFg request=open path=public/test-1 opaque=oss.asize=1234376 mode=0644 options=0x468 response=redirect host=localhost port=33137 token=org.dcache.door=localhost:1094 level=INFO ts=2022-03-14T12:47:22.787+0100 event=org.dcache.xrootd.connection.start session=pool:pool_write@dCacheDomain:xrootd:bf490e9a socket.remote=127.0.0.1:50554 socket.local=127.0.0.1:33137 level=INFO ts=2022-03-14T12:47:22.795+0100 event=org.dcache.xrootd.request session=pool:pool_write@dCacheDomain:xrootd:bf490e9a request=protocol response=ok level=INFO ts=2022-03-14T12:47:22.798+0100 event=org.dcache.xrootd.request session=pool:pool_write@dCacheDomain:xrootd:bf490e9a request=login username=paul capver=5 pid=19732 token=xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=sprocket.fritz.box&xrd.rn=v20220314-3c87024f8&org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376 response=ok sessionId=F98807C604D1B7999DFAC86C38C70728 level=INFO ts=2022-03-14T12:47:22.803+0100 event=org.dcache.xrootd.request session=pool:pool_write@dCacheDomain:xrootd:bf490e9a request=open path=public/test-1 opaque=org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376 mode=0644 options=0x468 response=ok handle=0 flags=0 modtime=1970-01-01T00:00:00Z size=0 level=INFO ts=2022-03-14T12:47:22.876+0100 event=org.dcache.xrootd.request session=pool:pool_write@dCacheDomain:xrootd:bf490e9a request=close handle=0 response=ok level=INFO ts=2022-03-14T12:47:22.879+0100 event=org.dcache.xrootd.connection.end session=door:Xrootd-sprocket@dCacheDomain:AAXaLD5QHFg level=INFO ts=2022-03-14T12:47:22.880+0100 event=org.dcache.xrootd.connection.end session=pool:pool_write@dCacheDomain:xrootd:bf490e9a ``` Here's what the `xrdcp` client reports back for the same transfer: ``` [2022-03-14 12:47:22.390700 +0100][Dump ][App ] Chunk size: 8388608, parallel chunks 4, streams: 1 [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] URL: root://localhost/public/test-1 [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.390806 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1 [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.390855 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1 [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.390909 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1 [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.390959 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.391041 +0100][Dump ][FileSystem ] [0xef5de7f0@localhost:1094] Sending kXR_stat (path: public/test-1, flags: none) [2022-03-14 12:47:22.391064 +0100][Debug ][Utility ] Env: trying to get a non-existent string entry: pollerpreference [2022-03-14 12:47:22.391080 +0100][Debug ][Poller ] Available pollers: built-in [2022-03-14 12:47:22.391091 +0100][Debug ][Poller ] Attempting to create a poller according to preference: built-in [2022-03-14 12:47:22.391101 +0100][Debug ][Poller ] Creating poller: built-in [2022-03-14 12:47:22.391114 +0100][Debug ][Poller ] Creating and starting the built-in poller... [2022-03-14 12:47:22.391241 +0100][Debug ][Poller ] Using 1 poller threads [2022-03-14 12:47:22.391257 +0100][Debug ][TaskMgr ] Starting the task manager... [2022-03-14 12:47:22.391298 +0100][Debug ][TaskMgr ] Task manager started [2022-03-14 12:47:22.391307 +0100][Debug ][JobMgr ] Starting the job manager... [2022-03-14 12:47:22.391371 +0100][Debug ][JobMgr ] Job manager started, 3 workers [2022-03-14 12:47:22.391382 +0100][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2022-03-14 12:47:22 +0100] [2022-03-14 12:47:22.391388 +0100][Dump ][XRootD ] [localhost:1094] Sending message kXR_stat (path: public/test-1, flags: none) [2022-03-14 12:47:22.391413 +0100][Debug ][ExDbgMsg ] [localhost:1094] MsgHandler created: 0xef5e3010 (message: kXR_stat (path: public/test-1, flags: none) ). [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] URL: localhost:1094 [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.391443 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] URL: root://localhost:1094 [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.391467 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.391481 +0100][Debug ][PostMaster ] Creating new channel to: root://localhost:1094 [2022-03-14 12:47:22.391497 +0100][Debug ][PostMaster ] [localhost:1094] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800 [2022-03-14 12:47:22.391514 +0100][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: root://localhost:1094" to be run at: [2022-03-14 12:47:37 +0100] [2022-03-14 12:47:22.391526 +0100][Dump ][PostMaster ] [localhost:1094] Sending message kXR_stat (path: public/test-1, flags: none) (0xef5dea80) through substream 0 expecting answer at 0 [2022-03-14 12:47:22.391673 +0100][Debug ][PostMaster ] [localhost:1094] Found 2 address(es): [::ffff:127.0.0.1]:1094, [::ffff:127.0.0.1]:1094 [2022-03-14 12:47:22.391699 +0100][Debug ][AsyncSock ] [localhost:1094.0] Attempting connection to [::ffff:127.0.0.1]:1094 [2022-03-14 12:47:22.391761 +0100][Debug ][Poller ] Adding socket 0xef5deb90 to the poller [2022-03-14 12:47:22.391855 +0100][Debug ][AsyncSock ] [localhost:1094.0] Async connection call returned [2022-03-14 12:47:22.391890 +0100][Debug ][XRootDTransport ] [localhost:1094.0] Sending out the initial hand shake + kXR_protocol [2022-03-14 12:47:22.391931 +0100][Dump ][AsyncSock ] [localhost:1094.0] Wrote a message: (0x38001370), 44 bytes [2022-03-14 12:47:22.391992 +0100][Dump ][TaskMgr ] Running task: "FileTimer task" [2022-03-14 12:47:22.392009 +0100][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2022-03-14 12:47:37 +0100] [2022-03-14 12:47:22.490884 +0100][Dump ][XRootDTransport ] [msg: 0x38001570] Expecting 8 bytes of message body [2022-03-14 12:47:22.490915 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message header, size: 8 [2022-03-14 12:47:22.490926 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received a message of 16 bytes [2022-03-14 12:47:22.490935 +0100][Debug ][XRootDTransport ] [localhost:1094.0] Got the server hand shake response (type: manager [], protocol version 500) [2022-03-14 12:47:22.507617 +0100][Dump ][XRootDTransport ] [msg: 0x38001570] Expecting 8 bytes of message body [2022-03-14 12:47:22.507646 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message header, size: 8 [2022-03-14 12:47:22.507654 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received a message of 16 bytes [2022-03-14 12:47:22.507667 +0100][Debug ][XRootDTransport ] [localhost:1094.0] kXR_protocol successful (type: manager [], protocol version 500) [2022-03-14 12:47:22.507783 +0100][Debug ][XRootDTransport ] [localhost:1094.0] Sending out kXR_login request, username: paul, cgi: xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=sprocket.fritz.box&xrd.rn=v20220314-3c87024f8, dual-stack: false, private IPv4: true, private IPv6: false [2022-03-14 12:47:22.507808 +0100][Dump ][AsyncSock ] [localhost:1094.0] Wrote a message: (0x38001830), 129 bytes [2022-03-14 12:47:22.520314 +0100][Dump ][XRootDTransport ] [msg: 0x38001370] Expecting 16 bytes of message body [2022-03-14 12:47:22.520347 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message header, size: 8 [2022-03-14 12:47:22.520357 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received a message of 24 bytes [2022-03-14 12:47:22.520371 +0100][Debug ][XRootDTransport ] [localhost:1094.0] Logged in, session: a4573f2aca7244c2c45494051bc63eff [2022-03-14 12:47:22.520386 +0100][Debug ][PostMaster ] [localhost:1094] Stream 0 connected. [2022-03-14 12:47:22.520396 +0100][Debug ][Utility ] Monitor library name not set. No monitoring [2022-03-14 12:47:22.520423 +0100][Dump ][AsyncSock ] [localhost:1094.0] Wrote a message: kXR_stat (path: public/test-1, flags: none) (0xef5dea80), 37 bytes [2022-03-14 12:47:22.520467 +0100][Dump ][AsyncSock ] [localhost:1094.0] Successfully sent message: kXR_stat (path: public/test-1, flags: none) (0xef5dea80). [2022-03-14 12:47:22.520481 +0100][Dump ][XRootD ] [localhost:1094] Message kXR_stat (path: public/test-1, flags: none) has been successfully sent. [2022-03-14 12:47:22.520487 +0100][Debug ][ExDbgMsg ] [localhost:1094] Moving MsgHandler: 0xef5e3010 (message: kXR_stat (path: public/test-1, flags: none) ) from out-queu to in-queue. [2022-03-14 12:47:22.520498 +0100][Dump ][PostMaster ] [localhost:1094.0] All messages consumed, disable uplink [2022-03-14 12:47:22.554163 +0100][Dump ][XRootDTransport ] [msg: 0x38002110] Expecting 17 bytes of message body [2022-03-14 12:47:22.554190 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message header for 0x38002110 size: 8 [2022-03-14 12:47:22.554208 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message 0x38002110 of 25 bytes [2022-03-14 12:47:22.554218 +0100][Dump ][PostMaster ] [localhost:1094] Handling received message: 0x38002110. [2022-03-14 12:47:22.554302 +0100][Dump ][XRootD ] [localhost:1094] Got a kXR_error response to request kXR_stat (path: public/test-1, flags: none) [3011] No such file [2022-03-14 12:47:22.554329 +0100][Debug ][XRootD ] [localhost:1094] Handling error while processing kXR_stat (path: public/test-1, flags: none): [ERROR] Error response: no such file or directory. [2022-03-14 12:47:22.554346 +0100][Debug ][ExDbgMsg ] [localhost:1094] Calling MsgHandler: 0xef5e3010 (message: kXR_stat (path: public/test-1, flags: none) ) with status: [ERROR] Error response: no such file or directory. [2022-03-14 12:47:22.554370 +0100][Debug ][ExDbgMsg ] [localhost:1094] Destroying MsgHandler: 0xef5e3010. [2022-03-14 12:47:22.554400 +0100][Dump ][App ] Processing source entry: /bin/bash, type local file, target file: root://localhost/public/test-1 [2022-03-14 12:47:22.554487 +0100][Dump ][Utility ] Adding job with properties: 'checkSumMode' = 'none', 'checkSumPreset' = '', 'checkSumType' = '', 'chunkSize' = '8388608', 'coerce' = '0', 'continue' = '0', 'cpTimeout' = '0', 'delegate' = '0', 'dynamicSource' = '0', 'force' = '0', 'initTimeout' = '600', 'makeDir' = '0', 'parallelChunks' = '4', 'posc' = '0', 'preserveXAttr' = '0', 'rmOnBadCksum' = '0', 'source' = 'file:///bin/bash', 'target' = 'root://localhost/public/test-1', 'targetIsDir' = '0', 'thirdParty' = 'none', 'tpcTimeout' = '1800', 'xcp' = '0', 'xcpBlockSize' = '134217728', 'xrate' = '0', 'xrateThreashold' = '0', 'zipAppend' = '0', 'zipArchive' = '0' [2022-03-14 12:47:22.554514 +0100][Debug ][Utility ] CopyProcess: 2 jobs to prepare [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] URL: file:///bin/bash [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554530 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] URL: root://localhost/public/test-1 [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554548 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] URL: file:///bin/bash [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554572 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] URL: root://localhost/public/test-1 [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554589 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.554601 +0100][Debug ][Utility ] Creating a classic copy job, from file://localhost/bin/bash to root://localhost:1094/public/test-1 [2022-03-14 12:47:22.554659 +0100][Debug ][Utility ] Opening file://localhost/bin/bash for reading [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] URL: file://localhost/bin/bash [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554673 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] URL: file://localhost/bin/bash [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554683 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] URL: file://localhost/bin/bash [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554693 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554738 +0100][Debug ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Sending an open command [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] URL: file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18 [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554764 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] URL: file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18 [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] Protocol: file [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554806 +0100][Dump ][Utility ] Path: /bin/bash [2022-03-14 12:47:22.554908 +0100][Debug ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Open has returned with status [SUCCESS] [2022-03-14 12:47:22.554927 +0100][Debug ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] successfully opened at localhost, handle: 0xb, session id: 1 [2022-03-14 12:47:22.554966 +0100][Debug ][Utility ] Opening root://localhost:1094/public/test-1?oss.asize=1234376 for writing [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1?oss.asize=1234376 [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.554997 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1?oss.asize=1234376 [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.555024 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1?oss.asize=1234376 [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.555041 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.555076 +0100][Debug ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Sending an open command [2022-03-14 12:47:22.555091 +0100][Dump ][XRootD ] [localhost:1094] Sending message kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) [2022-03-14 12:47:22.555102 +0100][Debug ][ExDbgMsg ] [localhost:1094] MsgHandler created: 0xef5e94a0 (message: kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) ). [2022-03-14 12:47:22.555113 +0100][Dump ][PostMaster ] [localhost:1094] Sending message kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) (0xef5e7da0) through substream 0 expecting answer at 0 [2022-03-14 12:47:22.555160 +0100][Dump ][AsyncSock ] [localhost:1094.0] Wrote a message: kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) (0xef5e7da0), 55 bytes [2022-03-14 12:47:22.555202 +0100][Dump ][AsyncSock ] [localhost:1094.0] Successfully sent message: kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) (0xef5e7da0). [2022-03-14 12:47:22.555213 +0100][Dump ][XRootD ] [localhost:1094] Message kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) has been successfully sent. [2022-03-14 12:47:22.555218 +0100][Debug ][ExDbgMsg ] [localhost:1094] Moving MsgHandler: 0xef5e94a0 (message: kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) ) from out-queu to in-queue. [2022-03-14 12:47:22.555224 +0100][Dump ][PostMaster ] [localhost:1094.0] All messages consumed, disable uplink [2022-03-14 12:47:22.768616 +0100][Dump ][XRootDTransport ] [msg: 0x38002650] Expecting 161 bytes of message body [2022-03-14 12:47:22.768643 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message header for 0x38002650 size: 8 [2022-03-14 12:47:22.768654 +0100][Dump ][AsyncSock ] [localhost:1094.0] Received message 0x38002650 of 169 bytes [2022-03-14 12:47:22.768661 +0100][Dump ][PostMaster ] [localhost:1094] Handling received message: 0x38002650. [2022-03-14 12:47:22.768712 +0100][Dump ][XRootD ] [localhost:1094] Got kXR_redirect response to message kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ): localhost?org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376&org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45?org.dcache.door=localhost:1094, port 33137 [2022-03-14 12:47:22.768724 +0100][Dump ][XRootD ] [localhost:1094] Current server has been assigned as a load-balancer for message kXR_open (file: public/test-1?oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] URL: localhost:33137/ [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] Port: 33137 [2022-03-14 12:47:22.768742 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] URL: fake://fake:111//fake?org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376&org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9& [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] Protocol: fake [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] Host Name: fake [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] Port: 111 [2022-03-14 12:47:22.768772 +0100][Dump ][Utility ] Path: /fake [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] URL: fake://fake:111/public/test-1?oss.asize=1234376 [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] Protocol: fake [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] Host Name: fake [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] Port: 111 [2022-03-14 12:47:22.768802 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] URL: root://localhost:1094/public/test-1 [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] Port: 1094 [2022-03-14 12:47:22.768834 +0100][Dump ][Utility ] Path: public/test-1 [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] URL: root://localhost:33137/ [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] Port: 33137 [2022-03-14 12:47:22.768848 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.768868 +0100][Debug ][ExDbgMsg ] [localhost:33137] Retry at server MsgHandler: 0xef5e94a0 (message: kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) ). [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] URL: localhost:33137 [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] Port: 33137 [2022-03-14 12:47:22.768879 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] URL: root://localhost:33137 [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] Port: 33137 [2022-03-14 12:47:22.768895 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.768907 +0100][Debug ][PostMaster ] Creating new channel to: root://localhost:33137 [2022-03-14 12:47:22.768925 +0100][Debug ][PostMaster ] [localhost:33137] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800 [2022-03-14 12:47:22.768936 +0100][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: root://localhost:33137" to be run at: [2022-03-14 12:47:37 +0100] [2022-03-14 12:47:22.768947 +0100][Dump ][PostMaster ] [localhost:33137] Sending message kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) (0xef5e7da0) through substream 0 expecting answer at 0 [2022-03-14 12:47:22.769069 +0100][Debug ][PostMaster ] [localhost:33137] Found 2 address(es): [::ffff:127.0.0.1]:33137, [::ffff:127.0.0.1]:33137 [2022-03-14 12:47:22.769088 +0100][Debug ][AsyncSock ] [localhost:33137.0] Attempting connection to [::ffff:127.0.0.1]:33137 [2022-03-14 12:47:22.769139 +0100][Debug ][Poller ] Adding socket 0x2c002290 to the poller [2022-03-14 12:47:22.769173 +0100][Debug ][AsyncSock ] [localhost:33137.0] Async connection call returned [2022-03-14 12:47:22.769187 +0100][Debug ][XRootDTransport ] [localhost:33137.0] Sending out the initial hand shake + kXR_protocol [2022-03-14 12:47:22.769206 +0100][Dump ][AsyncSock ] [localhost:33137.0] Wrote a message: (0x38001570), 44 bytes [2022-03-14 12:47:22.791419 +0100][Dump ][XRootDTransport ] [msg: 0x38001b00] Expecting 8 bytes of message body [2022-03-14 12:47:22.791453 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message header, size: 8 [2022-03-14 12:47:22.791462 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received a message of 16 bytes [2022-03-14 12:47:22.791469 +0100][Debug ][XRootDTransport ] [localhost:33137.0] Got the server hand shake response (type: server [], protocol version 500) [2022-03-14 12:47:22.795883 +0100][Dump ][XRootDTransport ] [msg: 0x38001b00] Expecting 8 bytes of message body [2022-03-14 12:47:22.795913 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message header, size: 8 [2022-03-14 12:47:22.795922 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received a message of 16 bytes [2022-03-14 12:47:22.795932 +0100][Debug ][XRootDTransport ] [localhost:33137.0] kXR_protocol successful (type: server [], protocol version 500) [2022-03-14 12:47:22.796041 +0100][Debug ][XRootDTransport ] [localhost:33137.0] Sending out kXR_login request, username: paul, cgi: xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=sprocket.fritz.box&xrd.rn=v20220314-3c87024f8&org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, dual-stack: false, private IPv4: true, private IPv6: false [2022-03-14 12:47:22.796067 +0100][Dump ][AsyncSock ] [localhost:33137.0] Wrote a message: (0x38002640), 246 bytes [2022-03-14 12:47:22.799125 +0100][Dump ][XRootDTransport ] [msg: 0x38001570] Expecting 16 bytes of message body [2022-03-14 12:47:22.799155 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message header, size: 8 [2022-03-14 12:47:22.799165 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received a message of 24 bytes [2022-03-14 12:47:22.799176 +0100][Debug ][XRootDTransport ] [localhost:33137.0] Logged in, session: f98807c604d1b7999dfac86c38c70728 [2022-03-14 12:47:22.799189 +0100][Debug ][PostMaster ] [localhost:33137] Stream 0 connected. [2022-03-14 12:47:22.799208 +0100][Dump ][AsyncSock ] [localhost:33137.0] Wrote a message: kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) (0xef5e7da0), 154 bytes [2022-03-14 12:47:22.799224 +0100][Dump ][AsyncSock ] [localhost:33137.0] Successfully sent message: kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) (0xef5e7da0). [2022-03-14 12:47:22.799233 +0100][Dump ][XRootD ] [localhost:33137] Message kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) has been successfully sent. [2022-03-14 12:47:22.799239 +0100][Debug ][ExDbgMsg ] [localhost:33137] Moving MsgHandler: 0xef5e94a0 (message: kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) ) from out-queu to in-queue. [2022-03-14 12:47:22.799248 +0100][Dump ][PostMaster ] [localhost:33137.0] All messages consumed, disable uplink [2022-03-14 12:47:22.803989 +0100][Dump ][XRootDTransport ] [msg: 0x38002650] Expecting 20 bytes of message body [2022-03-14 12:47:22.804023 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message header for 0x38002650 size: 8 [2022-03-14 12:47:22.804039 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message 0x38002650 of 28 bytes [2022-03-14 12:47:22.804047 +0100][Dump ][PostMaster ] [localhost:33137] Handling received message: 0x38002650. [2022-03-14 12:47:22.804073 +0100][Dump ][XRootD ] [localhost:33137] Got a kXR_ok response to request kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) [2022-03-14 12:47:22.804086 +0100][Debug ][ExDbgMsg ] [localhost:33137] Calling MsgHandler: 0xef5e94a0 (message: kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) ) with status: [SUCCESS] . [2022-03-14 12:47:22.804096 +0100][Dump ][XRootD ] [localhost:33137] Parsing the response to kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) as OpenInfo [2022-03-14 12:47:22.804103 +0100][Dump ][XRootD ] [localhost:33137] Parsing StatInfo in response to kXR_open (file: public/test-1?org.dcache.uuid=df739ef4-665c-4ab9-a67d-560fb3bb1c45&org.dcache.xrootd.client=paul.19732@localhost&oss.asize=1234376, mode: 0644, flags: kXR_new kXR_open_updt kXR_async kXR_retstat ) [2022-03-14 12:47:22.804141 +0100][Debug ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Open has returned with status [SUCCESS] [2022-03-14 12:47:22.804151 +0100][Debug ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] successfully opened at localhost:33137, handle: 0x0, session id: 1 [2022-03-14 12:47:22.804171 +0100][Debug ][XRootD ] Redirect trace-back: [2022-03-14 12:47:22.804171 +0100][Debug ][XRootD ] 0. Redirected from: root://localhost:1094/public/test-1 to: root://localhost:33137/ [2022-03-14 12:47:22.804179 +0100][Debug ][ExDbgMsg ] [localhost:33137] Destroying MsgHandler: 0xef5e94a0. [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] URL: localhost:33137 [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] Protocol: root [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] User Name: [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] Password: [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] Host Name: localhost [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] Port: 33137 [2022-03-14 12:47:22.804205 +0100][Dump ][Utility ] Path: [2022-03-14 12:47:22.804249 +0100][Debug ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Sending a read command for handle 0xb to localhost [2022-03-14 12:47:22.807264 +0100][Dump ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Got state response for message kXR_read (handle: 0x0b000000, offset: 0, size: 1234376) [2022-03-14 12:47:22.807325 +0100][Debug ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Sending a write command for handle 0x0 to localhost:33137 [2022-03-14 12:47:22.807353 +0100][Dump ][XRootD ] [localhost:33137] Sending message kXR_write (handle: 0x00000000, offset: 0, size: 1234376) [2022-03-14 12:47:22.807365 +0100][Debug ][ExDbgMsg ] [localhost:33137] MsgHandler created: 0xef5ea910 (message: kXR_write (handle: 0x00000000, offset: 0, size: 1234376) ). [2022-03-14 12:47:22.807378 +0100][Dump ][PostMaster ] [localhost:33137] Sending message kXR_write (handle: 0x00000000, offset: 0, size: 1234376) (0xef5e86e0) through substream 0 expecting answer at 0 [1.177MB/1.177MB][100%][==================================================][1.177MB/s] [2022-03-14 12:47:22.807446 +0100][Dump ][AsyncSock ] [localhost:33137.0] Wrote a message: kXR_write (handle: 0x00000000, offset: 0, size: 1234376) (0xef5e86e0), 24 bytes [2022-03-14 12:47:22.807700 +0100][Dump ][AsyncSock ] [localhost:33137.0] Wrote 1234376 bytes of raw data of message(0xef5e86e0) body. [2022-03-14 12:47:22.807721 +0100][Dump ][AsyncSock ] [localhost:33137.0] Successfully sent message: kXR_write (handle: 0x00000000, offset: 0, size: 1234376) (0xef5e86e0). [2022-03-14 12:47:22.807728 +0100][Dump ][XRootD ] [localhost:33137] Message kXR_write (handle: 0x00000000, offset: 0, size: 1234376) has been successfully sent. [2022-03-14 12:47:22.807733 +0100][Debug ][ExDbgMsg ] [localhost:33137] Moving MsgHandler: 0xef5ea910 (message: kXR_write (handle: 0x00000000, offset: 0, size: 1234376) ) from out-queu to in-queue. [2022-03-14 12:47:22.807739 +0100][Dump ][PostMaster ] [localhost:33137.0] All messages consumed, disable uplink [2022-03-14 12:47:22.838835 +0100][Dump ][XRootDTransport ] [msg: 0x38002650] Expecting 0 bytes of message body [2022-03-14 12:47:22.838866 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message header for 0x38002650 size: 8 [2022-03-14 12:47:22.838876 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message 0x38002650 of 8 bytes [2022-03-14 12:47:22.838884 +0100][Dump ][PostMaster ] [localhost:33137] Handling received message: 0x38002650. [2022-03-14 12:47:22.838904 +0100][Dump ][XRootD ] [localhost:33137] Got a kXR_ok response to request kXR_write (handle: 0x00000000, offset: 0, size: 1234376) [2022-03-14 12:47:22.838923 +0100][Debug ][ExDbgMsg ] [localhost:33137] Calling MsgHandler: 0xef5ea910 (message: kXR_write (handle: 0x00000000, offset: 0, size: 1234376) ) with status: [SUCCESS] . [2022-03-14 12:47:22.838930 +0100][Dump ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Got state response for message kXR_write (handle: 0x00000000, offset: 0, size: 1234376) [2022-03-14 12:47:22.838944 +0100][Debug ][ExDbgMsg ] [localhost:33137] Destroying MsgHandler: 0xef5ea910. [2022-03-14 12:47:22.839045 +0100][Debug ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Sending a close command for handle 0x0 to localhost:33137 [2022-03-14 12:47:22.839063 +0100][Dump ][XRootD ] [localhost:33137] Sending message kXR_close (handle: 0x00000000) [2022-03-14 12:47:22.839071 +0100][Debug ][ExDbgMsg ] [localhost:33137] MsgHandler created: 0xef5eb170 (message: kXR_close (handle: 0x00000000) ). [2022-03-14 12:47:22.839078 +0100][Dump ][PostMaster ] [localhost:33137] Sending message kXR_close (handle: 0x00000000) (0xef5ea910) through substream 0 expecting answer at 0 [2022-03-14 12:47:22.839103 +0100][Dump ][AsyncSock ] [localhost:33137.0] Wrote a message: kXR_close (handle: 0x00000000) (0xef5ea910), 24 bytes [2022-03-14 12:47:22.839133 +0100][Dump ][AsyncSock ] [localhost:33137.0] Successfully sent message: kXR_close (handle: 0x00000000) (0xef5ea910). [2022-03-14 12:47:22.839141 +0100][Dump ][XRootD ] [localhost:33137] Message kXR_close (handle: 0x00000000) has been successfully sent. [2022-03-14 12:47:22.839146 +0100][Debug ][ExDbgMsg ] [localhost:33137] Moving MsgHandler: 0xef5eb170 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue. [2022-03-14 12:47:22.839152 +0100][Dump ][PostMaster ] [localhost:33137.0] All messages consumed, disable uplink [2022-03-14 12:47:22.877626 +0100][Dump ][XRootDTransport ] [msg: 0x38002650] Expecting 0 bytes of message body [2022-03-14 12:47:22.877658 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message header for 0x38002650 size: 8 [2022-03-14 12:47:22.877666 +0100][Dump ][AsyncSock ] [localhost:33137.0] Received message 0x38002650 of 8 bytes [2022-03-14 12:47:22.877672 +0100][Dump ][PostMaster ] [localhost:33137] Handling received message: 0x38002650. [2022-03-14 12:47:22.877697 +0100][Dump ][XRootD ] [localhost:33137] Got a kXR_ok response to request kXR_close (handle: 0x00000000) [2022-03-14 12:47:22.877708 +0100][Debug ][ExDbgMsg ] [localhost:33137] Calling MsgHandler: 0xef5eb170 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] . [2022-03-14 12:47:22.877717 +0100][Debug ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Close returned from localhost:33137 with: [SUCCESS] [2022-03-14 12:47:22.877722 +0100][Dump ][File ] [0xef5e8b60@root://localhost:1094/public/test-1?oss.asize=1234376&xrdcl.requuid=23bc11da-5836-4ae5-88bb-b214e44124d9] Items in the fly 0, queued for recovery 0 [2022-03-14 12:47:22.877735 +0100][Debug ][ExDbgMsg ] [localhost:33137] Destroying MsgHandler: 0xef5eb170. [2022-03-14 12:47:22.877769 +0100][Debug ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Sending a close command for handle 0xb to localhost [2022-03-14 12:47:22.877800 +0100][Debug ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Close returned from localhost with: [SUCCESS] [2022-03-14 12:47:22.877807 +0100][Dump ][File ] [0xef5e76e0@file://localhost/bin/bash?xrdcl.requuid=a156572d-bd3e-4245-ac5b-9cf131780f18] Items in the fly 0, queued for recovery 0 [1.177MB/1.177MB][100%][==================================================][1.177MB/s] [2022-03-14 12:47:22.878000 +0100][Debug ][JobMgr ] Stopping the job manager... [2022-03-14 12:47:22.878014 +0100][Dump ][JobMgr ] Stopping worker #0... [2022-03-14 12:47:22.878177 +0100][Dump ][JobMgr ] Worker #0 stopped [2022-03-14 12:47:22.878192 +0100][Dump ][JobMgr ] Stopping worker #1... [2022-03-14 12:47:22.878237 +0100][Dump ][JobMgr ] Worker #1 stopped [2022-03-14 12:47:22.878246 +0100][Dump ][JobMgr ] Stopping worker #2... [2022-03-14 12:47:22.878295 +0100][Dump ][JobMgr ] Worker #2 stopped [2022-03-14 12:47:22.878304 +0100][Debug ][JobMgr ] Job manager stopped [2022-03-14 12:47:22.878310 +0100][Debug ][TaskMgr ] Stopping the task manager... [2022-03-14 12:47:22.878397 +0100][Debug ][TaskMgr ] Task manager stopped [2022-03-14 12:47:22.878408 +0100][Debug ][Poller ] Stopping the poller... [2022-03-14 12:47:22.878484 +0100][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: root://localhost:1094" [2022-03-14 12:47:22.878499 +0100][Debug ][AsyncSock ] [localhost:1094.0] Closing the socket [2022-03-14 12:47:22.878507 +0100][Debug ][Poller ] <[::ffff:127.0.0.1]:56346><--><[::ffff:127.0.0.1]:1094> Removing socket from the poller [2022-03-14 12:47:22.878547 +0100][Debug ][PostMaster ] [localhost:1094] Destroying stream [2022-03-14 12:47:22.878557 +0100][Debug ][AsyncSock ] [localhost:1094.0] Closing the socket [2022-03-14 12:47:22.878569 +0100][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: root://localhost:33137" [2022-03-14 12:47:22.878574 +0100][Debug ][AsyncSock ] [localhost:33137.0] Closing the socket [2022-03-14 12:47:22.878579 +0100][Debug ][Poller ] <[::ffff:127.0.0.1]:50554><--><[::ffff:127.0.0.1]:33137> Removing socket from the poller [2022-03-14 12:47:22.878603 +0100][Debug ][PostMaster ] [localhost:33137] Destroying stream [2022-03-14 12:47:22.878610 +0100][Debug ][AsyncSock ] [localhost:33137.0] Closing the socket ``` -- Reply to this email directly or view it on GitHub: https://github.com/xrootd/xrootd/issues/1533#issuecomment-1066700228 You are receiving this because you are subscribed to this thread. Message ID: <[log in to unmask]> ######################################################################## 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