Hi JT,
Well, in the trace it looks like roots copy recovered and completed
successfully. In each cse (roots and https) please trun on tls tracing on
the server and when a failure happens please post the server loh s we can
see what it really is complaining about.
On Fri, 10 Mar 2023, jthiltges wrote:
> Good question! I'm able to reproduce the same behavior with `xrdcp roots://$HOST//store/hello_world.txt -`
>
> <details>
> <summary>Client logs</summary>
>
> ```
> [2023-03-10 15:43:36.899973 -0600][Debug ][Utility ] Initializing xrootd client version: v5.5.3
> [2023-03-10 15:43:36.900575 -0600][Debug ][Utility ] Unable to process user config file: [ERROR] OS Error: no such file or directory
> [2023-03-10 15:43:36.900750 -0600][Info ][Utility ] Env: Importing from shell XRD_NETWORKSTACK=IPv4 as networkstack
> [2023-03-10 15:43:36.900805 -0600][Debug ][PlugInMgr ] Initializing plug-in manager...
> [2023-03-10 15:43:36.900823 -0600][Debug ][PlugInMgr ] No default plug-in, loading plug-in configs...
> [2023-03-10 15:43:36.900836 -0600][Debug ][PlugInMgr ] Processing plug-in definitions in /etc/xrootd/client.plugins.d...
> [2023-03-10 15:43:36.900890 -0600][Dump ][PlugInMgr ] Processing: /etc/xrootd/client.plugins.d/recorder.conf
> [2023-03-10 15:43:36.900963 -0600][Dump ][PlugInMgr ] Settings from '/etc/xrootd/client.plugins.d/recorder.conf': url='*', lib='/usr/lib64/libXrdClRecorder-5.so', enable='false'
> [2023-03-10 15:43:36.900979 -0600][Debug ][PlugInMgr ] Trying to disable plug-in for '*'
> [2023-03-10 15:43:36.901262 -0600][Debug ][PlugInMgr ] Processing plug-in definitions in /home/swanson/jthiltge/.xrootd/client.plugins.d...
> [2023-03-10 15:43:36.901291 -0600][Debug ][PlugInMgr ] Unable to process directory /home/swanson/jthiltge/.xrootd/client.plugins.d: [ERROR] OS Error: no such file or directory
> [2023-03-10 15:43:36.904563 -0600][Dump ][App ] Chunk size: 8388608, parallel chunks 4, streams: 1
> [2023-03-10 15:43:36.904587 -0600][Dump ][App ] Processing source entry: roots://xrootd-local.unl.edu//store/hello_world.txt, type other, target file: -
> [2023-03-10 15:43:36.904744 -0600][Dump ][Utility ] Adding job with properties: 'checkSumMode' = 'none', 'checkSumPreset' = '', 'checkSumType' = '', 'chunkSize' = '8388608', 'coerce' = '0', 'continue' = '0', 'cpTimeout' = '0', 'delegate' = '0', 'doServer' = '0', 'dynamicSource' = '0', 'force' = '0', 'initTimeout' = '600', 'makeDir' = '0', 'parallelChunks' = '4', 'posc' = '0', 'preserveXAttr' = '0', 'rmOnBadCksum' = '0', 'source' = 'roots://xrootd-local.unl.edu//store/hello_world.txt', 'target' = '-', 'targetIsDir' = '0', 'thirdParty' = 'none', 'tpcTimeout' = '1800', 'xcp' = '0', 'xcpBlockSize' = '134217728', 'xrate' = '0', 'xrateThreshold' = '0', 'zipAppend' = '0', 'zipArchive' = '0'
> [2023-03-10 15:43:36.904780 -0600][Debug ][Utility ] CopyProcess: 2 jobs to prepare
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu//store/hello_world.txt
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.904816 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] URL: -
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] Protocol: stdio
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] Host Name:
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] Port: 0
> [2023-03-10 15:43:36.904848 -0600][Dump ][Utility ] Path: -
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu//store/hello_world.txt
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.904888 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] URL: -
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] Protocol: stdio
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] Host Name:
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] Port: 0
> [2023-03-10 15:43:36.905028 -0600][Dump ][Utility ] Path: -
> [2023-03-10 15:43:36.905049 -0600][Debug ][Utility ] Creating a classic copy job, from roots://xrootd-local.unl.edu:1094//store/hello_world.txt to stdio://-
> [2023-03-10 15:43:36.905081 -0600][Debug ][Utility ] Monitor library name not set. No monitoring
> [2023-03-10 15:43:36.905163 -0600][Debug ][Utility ] Opening roots://xrootd-local.unl.edu:1094//store/hello_world.txt for reading
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu:1094//store/hello_world.txt
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.905455 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu:1094//store/hello_world.txt
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.905491 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu:1094//store/hello_world.txt
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.905523 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:43:36.905610 -0600][Debug ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Sending an open command
> [2023-03-10 15:43:36.905658 -0600][Debug ][Utility ] Env: trying to get a non-existent string entry: pollerpreference
> [2023-03-10 15:43:36.905677 -0600][Debug ][Poller ] Available pollers: built-in
> [2023-03-10 15:43:36.905694 -0600][Debug ][Poller ] Attempting to create a poller according to preference: built-in
> [2023-03-10 15:43:36.905706 -0600][Debug ][Poller ] Creating poller: built-in
> [2023-03-10 15:43:36.905721 -0600][Debug ][Poller ] Creating and starting the built-in poller...
> [2023-03-10 15:43:36.915954 -0600][Debug ][Poller ] Using 1 poller threads
> [2023-03-10 15:43:36.915980 -0600][Debug ][TaskMgr ] Starting the task manager...
> [2023-03-10 15:43:36.916044 -0600][Debug ][TaskMgr ] Task manager started
> [2023-03-10 15:43:36.916068 -0600][Debug ][JobMgr ] Starting the job manager...
> [2023-03-10 15:43:36.916220 -0600][Debug ][JobMgr ] Job manager started, 3 workers
> [2023-03-10 15:43:36.916244 -0600][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2023-03-10 15:43:36 -0600]
> [2023-03-10 15:43:36.916257 -0600][Dump ][XRootD ] [xrootd-local.unl.edu:1094] Sending message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat )
> [2023-03-10 15:43:36.916293 -0600][Debug ][ExDbgMsg ] [xrootd-local.unl.edu:1094] MsgHandler created: 0x205e680 (message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ).
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] URL: xrootd-local.unl.edu:1094
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] Protocol: root
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.916347 -0600][Dump ][Utility ] Path:
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu:1094/
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:43:36.916384 -0600][Dump ][Utility ] Path:
> [2023-03-10 15:43:36.916419 -0600][Debug ][PostMaster ] Creating new channel to: roots://xrootd-local.unl.edu:1094/
> [2023-03-10 15:43:36.916458 -0600][Debug ][PostMaster ] [xrootd-local.unl.edu:1094] Stream parameters: Network Stack: IPv4, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800
> [2023-03-10 15:43:36.916485 -0600][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" to be run at: [2023-03-10 15:43:51 -0600]
> [2023-03-10 15:43:36.916506 -0600][Dump ][PostMaster ] [xrootd-local.unl.edu:1094] Sending message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x2058bb0) through substream 0 expecting answer at 0
> [2023-03-10 15:43:36.916882 -0600][Debug ][PostMaster ] [xrootd-local.unl.edu:1094] Found 1 address(es): [::ffff:129.93.239.164]:1094
> [2023-03-10 15:43:36.916935 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Attempting connection to [::ffff:129.93.239.164]:1094
> [2023-03-10 15:43:36.916996 -0600][Debug ][Poller ] Adding socket 0x205ebb0 to the poller
> [2023-03-10 15:43:36.924003 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Async connection call returned
> [2023-03-10 15:43:36.924056 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Sending out the initial hand shake + kXR_protocol
> [2023-03-10 15:43:36.924108 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: (0xb4000950), 44 bytes
> [2023-03-10 15:43:36.924344 -0600][Dump ][XRootDTransport ] [msg: 0xb4000ab0] Expecting 8 bytes of message body
> [2023-03-10 15:43:36.924360 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:43:36.924376 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 16 bytes
> [2023-03-10 15:43:36.924393 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Got the server hand shake response (type: manager [], protocol version 511)
> [2023-03-10 15:43:36.924415 -0600][Dump ][XRootDTransport ] [msg: 0xb4000ab0] Expecting 8 bytes of message body
> [2023-03-10 15:43:36.924433 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:43:36.924447 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 16 bytes
> [2023-03-10 15:43:36.924464 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] kXR_protocol successful (type: manager [], protocol version 511)
> [2023-03-10 15:43:36.924982 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Sending out kXR_login request, username: jthiltge, cgi: xrd.cc=us&xrd.tz=-6&xrd.appname=xrdcp&xrd.info=&xrd.hostname=t3.unl.edu&xrd.rn=v5.5.3, dual-stack: true, private IPv4: false, private IPv6: false
> [2023-03-10 15:43:36.925006 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:43:36.925982 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:43:36.926013 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:43:51 -0600]
> [2023-03-10 15:43:36.930822 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:43:36.934956 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:43:36.935099 -0600][Info ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake done.
> [2023-03-10 15:43:36.935160 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: (0xb4000be0), 109 bytes
> [2023-03-10 15:43:36.935415 -0600][Error ][TlsMsg ] [] TLS error rc=-1 ec=5 (error_syscall) errno=104.
> [2023-03-10 15:43:36.935442 -0600][Error ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Socket error while handshaking: [ERROR] TLS error
> [2023-03-10 15:43:36.935455 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Closing the socket
> [2023-03-10 15:43:36.935469 -0600][Debug ][Poller ] <[::ffff:129.93.239.166]:14642><--><[::ffff:129.93.239.164]:1094> Removing socket from the poller
> [2023-03-10 15:43:36.935552 -0600][Error ][PostMaster ] [xrootd-local.unl.edu:1094] elapsed = 0, pConnectionWindow = 120 seconds.
> [2023-03-10 15:43:36.935568 -0600][Info ][PostMaster ] [xrootd-local.unl.edu:1094] Attempting reconnection in 120 seconds.
> [2023-03-10 15:43:36.935580 -0600][Debug ][TaskMgr ] Registering task: "StreamConnectorTask for xrootd-local.unl.edu:1094" to be run at: [2023-03-10 15:45:36 -0600]
> [2023-03-10 15:43:51.927003 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:43:51.927057 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:44:06 -0600]
> [2023-03-10 15:43:51.927069 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:43:51.927084 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:44:06 -0600]
> [2023-03-10 15:44:06.928441 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:44:06.928488 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:44:21 -0600]
> [2023-03-10 15:44:06.928500 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:44:06.928515 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:44:21 -0600]
> [2023-03-10 15:44:21.929523 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:44:21.929574 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:44:36 -0600]
> [2023-03-10 15:44:21.929588 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:44:21.929603 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:44:36 -0600]
> [2023-03-10 15:44:36.930591 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:44:36.930638 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:44:51 -0600]
> [2023-03-10 15:44:36.930650 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:44:36.930665 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:44:51 -0600]
> [2023-03-10 15:44:51.931649 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:44:51.931704 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:45:06 -0600]
> [2023-03-10 15:44:51.931718 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:44:51.931734 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:45:06 -0600]
> [2023-03-10 15:45:06.932726 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:45:06.932772 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:45:21 -0600]
> [2023-03-10 15:45:06.932784 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:45:06.932799 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:45:21 -0600]
> [2023-03-10 15:45:21.933794 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:45:21.933845 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:45:36 -0600]
> [2023-03-10 15:45:21.933857 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:45:21.933871 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:45:36 -0600]
> [2023-03-10 15:45:36.934863 -0600][Dump ][TaskMgr ] Running task: "StreamConnectorTask for xrootd-local.unl.edu:1094"
> [2023-03-10 15:45:36.935251 -0600][Debug ][PostMaster ] [xrootd-local.unl.edu:1094] Found 1 address(es): [::ffff:129.93.239.164]:1094
> [2023-03-10 15:45:36.935292 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Attempting connection to [::ffff:129.93.239.164]:1094
> [2023-03-10 15:45:36.935344 -0600][Debug ][Poller ] Adding socket 0x205ebb0 to the poller
> [2023-03-10 15:45:36.935386 -0600][Debug ][TaskMgr ] Done with task: "StreamConnectorTask for xrootd-local.unl.edu:1094"
> [2023-03-10 15:45:36.935401 -0600][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:45:36.935418 -0600][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/" at [2023-03-10 15:45:51 -0600]
> [2023-03-10 15:45:36.935430 -0600][Dump ][TaskMgr ] Running task: "FileTimer task"
> [2023-03-10 15:45:36.935445 -0600][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2023-03-10 15:45:51 -0600]
> [2023-03-10 15:45:36.935554 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Async connection call returned
> [2023-03-10 15:45:36.935587 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Sending out the initial hand shake + kXR_protocol
> [2023-03-10 15:45:36.935632 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: (0xb40430f0), 44 bytes
> [2023-03-10 15:45:36.935873 -0600][Dump ][XRootDTransport ] [msg: 0xb4020400] Expecting 8 bytes of message body
> [2023-03-10 15:45:36.935892 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:36.935906 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 16 bytes
> [2023-03-10 15:45:36.935938 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Got the server hand shake response (type: manager [], protocol version 511)
> [2023-03-10 15:45:36.935961 -0600][Dump ][XRootDTransport ] [msg: 0xb4020400] Expecting 8 bytes of message body
> [2023-03-10 15:45:36.935973 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:36.935988 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 16 bytes
> [2023-03-10 15:45:36.936003 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] kXR_protocol successful (type: manager [], protocol version 511)
> [2023-03-10 15:45:36.936445 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Sending out kXR_login request, username: jthiltge, cgi: xrd.cc=us&xrd.tz=-6&xrd.appname=xrdcp&xrd.info=&xrd.hostname=t3.unl.edu&xrd.rn=v5.5.3, dual-stack: true, private IPv4: false, private IPv6: false
> [2023-03-10 15:45:36.936467 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:45:36.938352 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:45:36.940851 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:45:36.940970 -0600][Info ][AsyncSock ] [xrootd-local.unl.edu:1094.0] TLS hand-shake done.
> [2023-03-10 15:45:36.941024 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: (0xb40401d0), 109 bytes
> [2023-03-10 15:45:36.941203 -0600][Dump ][XRootDTransport ] [msg: 0xb40430f0] Expecting 75 bytes of message body
> [2023-03-10 15:45:36.941221 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:36.941260 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 83 bytes
> [2023-03-10 15:45:36.941282 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Logged in, session: c3eb220023490000080700007e552900
> [2023-03-10 15:45:36.941293 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Authentication is required: &P=gsi,v:10600,c:ssl,ca:ba240aa8.0|f5f0dfc2.0&P=ztn,0:4096:
> [2023-03-10 15:45:36.941304 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Sending authentication data
> [2023-03-10 15:45:36.942942 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Trying to authenticate using gsi
> [2023-03-10 15:45:37.755591 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: (0xb40584f0), 136 bytes
> [2023-03-10 15:45:37.763666 -0600][Dump ][XRootDTransport ] [msg: 0xb40439d0] Expecting 3252 bytes of message body
> [2023-03-10 15:45:37.763684 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.763752 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 3260 bytes
> [2023-03-10 15:45:37.763766 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Sending more authentication data for gsi
> [2023-03-10 15:45:37.795794 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: (0xb4057df0), 9932 bytes
> [2023-03-10 15:45:37.846827 -0600][Dump ][XRootDTransport ] [msg: 0xb40401d0] Expecting 0 bytes of message body
> [2023-03-10 15:45:37.846848 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.846859 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received a message of 8 bytes
> [2023-03-10 15:45:37.846925 -0600][Debug ][XRootDTransport ] [xrootd-local.unl.edu:1094.0] Authenticated with gsi.
> [2023-03-10 15:45:37.846951 -0600][Debug ][PostMaster ] [xrootd-local.unl.edu:1094] Stream 0 connected (IPv4).
> [2023-03-10 15:45:37.846992 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Wrote a message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x2058bb0), 46 bytes
> [2023-03-10 15:45:37.847031 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Successfully sent message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x2058bb0).
> [2023-03-10 15:45:37.847049 -0600][Dump ][XRootD ] [xrootd-local.unl.edu:1094] Message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) has been successfully sent.
> [2023-03-10 15:45:37.847060 -0600][Debug ][ExDbgMsg ] [xrootd-local.unl.edu:1094] Moving MsgHandler: 0x205e680 (message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) from out-queu to in-queue.
> [2023-03-10 15:45:37.847074 -0600][Dump ][PostMaster ] [xrootd-local.unl.edu:1094.0] All messages consumed, disable uplink
> [2023-03-10 15:45:37.847561 -0600][Dump ][XRootDTransport ] [msg: 0xb4043630] Expecting 21 bytes of message body
> [2023-03-10 15:45:37.847579 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message header for 0xb4043630 size: 8
> [2023-03-10 15:45:37.847592 -0600][Debug ][ExDbgMsg ] [msg: 0xb4043630] Assigned MsgHandler: 0x205e680.
> [2023-03-10 15:45:37.847603 -0600][Debug ][ExDbgMsg ] [handler: 0x205e680] Removed MsgHandler: 0x205e680 from the in-queue.
> [2023-03-10 15:45:37.847644 -0600][Dump ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Received message 0xb4043630 of 29 bytes
> [2023-03-10 15:45:37.847663 -0600][Dump ][PostMaster ] [xrootd-local.unl.edu:1094] Handling received message: 0xb4043630.
> [2023-03-10 15:45:37.847738 -0600][Dump ][XRootD ] [xrootd-local.unl.edu:1094] Got kXR_redirect response to message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): red-xfer8.unl.edu, port 1094
> [2023-03-10 15:45:37.847766 -0600][Dump ][XRootD ] [xrootd-local.unl.edu:1094] Current server has been assigned as a load-balancer for message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat )
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] URL: red-xfer8.unl.edu:1094/
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] Protocol: root
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] Host Name: red-xfer8.unl.edu
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:45:37.847798 -0600][Dump ][Utility ] Path:
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] URL: fake://fake:111//fake?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316&
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] Protocol: fake
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] Host Name: fake
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] Port: 111
> [2023-03-10 15:45:37.847840 -0600][Dump ][Utility ] Path: /fake
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] URL: fake://fake:111//store/hello_world.txt
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] Protocol: fake
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] Host Name: fake
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] Port: 111
> [2023-03-10 15:45:37.847893 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] URL: roots://xrootd-local.unl.edu:1094//store/hello_world.txt
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] Host Name: xrootd-local.unl.edu
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:45:37.847960 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] URL: roots://red-xfer8.unl.edu:1094/
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] Host Name: red-xfer8.unl.edu
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:45:37.847998 -0600][Dump ][Utility ] Path:
> [2023-03-10 15:45:37.848039 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Retry at server MsgHandler: 0x205e680 (message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ).
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] URL: red-xfer8.unl.edu:1094
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] Protocol: root
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] Host Name: red-xfer8.unl.edu
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:45:37.848067 -0600][Dump ][Utility ] Path:
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] URL: roots://red-xfer8.unl.edu:1094/
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] Host Name: red-xfer8.unl.edu
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:45:37.848100 -0600][Dump ][Utility ] Path:
> [2023-03-10 15:45:37.848124 -0600][Debug ][PostMaster ] Creating new channel to: roots://red-xfer8.unl.edu:1094/
> [2023-03-10 15:45:37.848158 -0600][Debug ][PostMaster ] [red-xfer8.unl.edu:1094] Stream parameters: Network Stack: IPv4, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800
> [2023-03-10 15:45:37.848178 -0600][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: roots://red-xfer8.unl.edu:1094/" to be run at: [2023-03-10 15:45:52 -0600]
> [2023-03-10 15:45:37.848194 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094] Sending message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x2058bb0) through substream 0 expecting answer at 0
> [2023-03-10 15:45:37.848531 -0600][Debug ][PostMaster ] [red-xfer8.unl.edu:1094] Found 1 address(es): [::ffff:129.93.239.138]:1094
> [2023-03-10 15:45:37.848570 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Attempting connection to [::ffff:129.93.239.138]:1094
> [2023-03-10 15:45:37.848618 -0600][Debug ][Poller ] Adding socket 0xb0001e00 to the poller
> [2023-03-10 15:45:37.848719 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Async connection call returned
> [2023-03-10 15:45:37.848746 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Sending out the initial hand shake + kXR_protocol
> [2023-03-10 15:45:37.848778 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: (0xb4000be0), 44 bytes
> [2023-03-10 15:45:37.848866 -0600][Dump ][XRootDTransport ] [msg: 0xb4039090] Expecting 8 bytes of message body
> [2023-03-10 15:45:37.848888 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.848902 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received a message of 16 bytes
> [2023-03-10 15:45:37.848922 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Got the server hand shake response (type: server [], protocol version 511)
> [2023-03-10 15:45:37.848942 -0600][Dump ][XRootDTransport ] [msg: 0xb4039090] Expecting 14 bytes of message body
> [2023-03-10 15:45:37.848953 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.848966 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received a message of 22 bytes
> [2023-03-10 15:45:37.848982 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] kXR_protocol successful (type: server [], protocol version 511)
> [2023-03-10 15:45:37.849429 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Sending out kXR_login request, username: jthiltge, cgi: xrd.cc=us&xrd.tz=-6&xrd.appname=xrdcp&xrd.info=&xrd.hostname=t3.unl.edu&xrd.rn=v5.5.3, dual-stack: true, private IPv4: false, private IPv6: false
> [2023-03-10 15:45:37.849450 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:45:37.851225 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:45:37.853330 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] TLS hand-shake exchange.
> [2023-03-10 15:45:37.853440 -0600][Info ][AsyncSock ] [red-xfer8.unl.edu:1094.0] TLS hand-shake done.
> [2023-03-10 15:45:37.853493 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: (0xb40430f0), 109 bytes
> [2023-03-10 15:45:37.853639 -0600][Dump ][XRootDTransport ] [msg: 0xb4000be0] Expecting 75 bytes of message body
> [2023-03-10 15:45:37.853653 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.853692 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received a message of 83 bytes
> [2023-03-10 15:45:37.853712 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Logged in, session: 30335e00410500008700000091e15f00
> [2023-03-10 15:45:37.853722 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Authentication is required: &P=gsi,v:10600,c:ssl,ca:ba240aa8.0|f5f0dfc2.0&P=ztn,0:4096:
> [2023-03-10 15:45:37.853733 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Sending authentication data
> [2023-03-10 15:45:37.853762 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Trying to authenticate using gsi
> [2023-03-10 15:45:37.879376 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: (0xb4069560), 136 bytes
> [2023-03-10 15:45:37.891120 -0600][Dump ][XRootDTransport ] [msg: 0xb40430f0] Expecting 3272 bytes of message body
> [2023-03-10 15:45:37.891139 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.891218 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received a message of 3280 bytes
> [2023-03-10 15:45:37.891234 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Sending more authentication data for gsi
> [2023-03-10 15:45:37.923098 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: (0xb4068310), 9932 bytes
> [2023-03-10 15:45:37.937567 -0600][Dump ][XRootDTransport ] [msg: 0xb40698b0] Expecting 0 bytes of message body
> [2023-03-10 15:45:37.937586 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header, size: 8
> [2023-03-10 15:45:37.937597 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received a message of 8 bytes
> [2023-03-10 15:45:37.937981 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] XrdSecProtect loaded.
> [2023-03-10 15:45:37.938002 -0600][Debug ][XRootDTransport ] [red-xfer8.unl.edu:1094.0] Authenticated with gsi.
> [2023-03-10 15:45:37.938029 -0600][Debug ][PostMaster ] [red-xfer8.unl.edu:1094] Stream 0 connected (IPv4).
> [2023-03-10 15:45:37.938069 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x2058bb0), 46 bytes
> [2023-03-10 15:45:37.938100 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Successfully sent message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x2058bb0).
> [2023-03-10 15:45:37.938115 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Message kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) has been successfully sent.
> [2023-03-10 15:45:37.938127 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Moving MsgHandler: 0x205e680 (message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) from out-queu to in-queue.
> [2023-03-10 15:45:37.938139 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094.0] All messages consumed, disable uplink
> [2023-03-10 15:45:37.938407 -0600][Dump ][XRootDTransport ] [msg: 0xb40788c0] Expecting 87 bytes of message body
> [2023-03-10 15:45:37.938424 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header for 0xb40788c0 size: 8
> [2023-03-10 15:45:37.938436 -0600][Debug ][ExDbgMsg ] [msg: 0xb40788c0] Assigned MsgHandler: 0x205e680.
> [2023-03-10 15:45:37.938447 -0600][Debug ][ExDbgMsg ] [handler: 0x205e680] Removed MsgHandler: 0x205e680 from the in-queue.
> [2023-03-10 15:45:37.938490 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message 0xb40788c0 of 95 bytes
> [2023-03-10 15:45:37.938506 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094] Handling received message: 0xb40788c0.
> [2023-03-10 15:45:37.938581 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Got a kXR_ok response to request kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat )
> [2023-03-10 15:45:37.938613 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Calling MsgHandler: 0x205e680 (message: kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) with status: [SUCCESS] .
> [2023-03-10 15:45:37.938626 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Parsing the response to kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) as OpenInfo
> [2023-03-10 15:45:37.938637 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Parsing StatInfo in response to kXR_open (file: /store/hello_world.txt, mode: 00, flags: kXR_open_read kXR_async kXR_retstat )
> [2023-03-10 15:45:37.938688 -0600][Debug ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Open has returned with status [SUCCESS]
> [2023-03-10 15:45:37.938708 -0600][Debug ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] successfully opened at red-xfer8.unl.edu:1094, handle: 0x0, session id: 1
> [2023-03-10 15:45:37.938750 -0600][Debug ][XRootD ] Redirect trace-back:
> [2023-03-10 15:45:37.938750 -0600][Debug ][XRootD ] 0. Redirected from: roots://xrootd-local.unl.edu:1094//store/hello_world.txt to: roots://red-xfer8.unl.edu:1094/
> [2023-03-10 15:45:37.938765 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Destroying MsgHandler: 0x205e680.
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] URL: roots://red-xfer8.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] Protocol: roots
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] User Name:
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] Password:
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] Host Name: red-xfer8.unl.edu
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] Port: 1094
> [2023-03-10 15:45:37.938791 -0600][Dump ][Utility ] Path: /store/hello_world.txt
> [2023-03-10 15:45:37.938838 -0600][Debug ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Sending a read command for handle 0x0 to red-xfer8.unl.edu:1094
> [2023-03-10 15:45:37.938873 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Sending message kXR_read (handle: 0x00000000, offset: 0, size: 13)
> [2023-03-10 15:45:37.938892 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] MsgHandler created: 0x205e680 (message: kXR_read (handle: 0x00000000, offset: 0, size: 13) ).
> [2023-03-10 15:45:37.938910 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094] Sending message kXR_read (handle: 0x00000000, offset: 0, size: 13) (0x205afc0) through substream 0 expecting answer at 0
> [2023-03-10 15:45:37.938962 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: kXR_read (handle: 0x00000000, offset: 0, size: 13) (0x205afc0), 32 bytes
> [2023-03-10 15:45:37.938994 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Successfully sent message: kXR_read (handle: 0x00000000, offset: 0, size: 13) (0x205afc0).
> [2023-03-10 15:45:37.939007 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Message kXR_read (handle: 0x00000000, offset: 0, size: 13) has been successfully sent.
> [2023-03-10 15:45:37.939018 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Moving MsgHandler: 0x205e680 (message: kXR_read (handle: 0x00000000, offset: 0, size: 13) ) from out-queu to in-queue.
> [2023-03-10 15:45:37.939029 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094.0] All messages consumed, disable uplink
> [2023-03-10 15:45:37.939104 -0600][Dump ][XRootDTransport ] [msg: 0xb4068400] Expecting 13 bytes of message body
> [2023-03-10 15:45:37.939119 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header for 0xb4068400 size: 8
> [2023-03-10 15:45:37.939129 -0600][Debug ][ExDbgMsg ] [msg: 0xb4068400] Assigned MsgHandler: 0x205e680.
> [2023-03-10 15:45:37.939139 -0600][Debug ][ExDbgMsg ] [handler: 0x205e680] Removed MsgHandler: 0x205e680 from the in-queue.
> [2023-03-10 15:45:37.939149 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Will use the raw handler to read body of message 0xb4068400
> [2023-03-10 15:45:37.939181 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message 0xb4068400 of 21 bytes
> [2023-03-10 15:45:37.939196 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094] Handling received message: 0xb4068400.
> [2023-03-10 15:45:37.939256 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Got a kXR_ok response to request kXR_read (handle: 0x00000000, offset: 0, size: 13)
> [2023-03-10 15:45:37.939281 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Calling MsgHandler: 0x205e680 (message: kXR_read (handle: 0x00000000, offset: 0, size: 13) ) with status: [SUCCESS] .
> [2023-03-10 15:45:37.939296 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Parsing the response to kXR_read (handle: 0x00000000, offset: 0, size: 13) as ChunkInfo
> [2023-03-10 15:45:37.939311 -0600][Dump ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Got state response for message kXR_read (handle: 0x00000000, offset: 0, size: 13)
> [2023-03-10 15:45:37.939337 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Destroying MsgHandler: 0x205e680.
> hello world!
> [2023-03-10 15:45:37.939371 -0600][Debug ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Sending a close command for handle 0x0 to red-xfer8.unl.edu:1094
> [2023-03-10 15:45:37.939404 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Sending message kXR_close (handle: 0x00000000)
> [2023-03-10 15:45:37.939421 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] MsgHandler created: 0x205e680 (message: kXR_close (handle: 0x00000000) ).
> [2023-03-10 15:45:37.939439 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094] Sending message kXR_close (handle: 0x00000000) (0x2058a70) through substream 0 expecting answer at 0
> [2023-03-10 15:45:37.939479 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Wrote a message: kXR_close (handle: 0x00000000) (0x2058a70), 24 bytes
> [2023-03-10 15:45:37.939509 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Successfully sent message: kXR_close (handle: 0x00000000) (0x2058a70).
> [2023-03-10 15:45:37.939523 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Message kXR_close (handle: 0x00000000) has been successfully sent.
> [2023-03-10 15:45:37.939533 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Moving MsgHandler: 0x205e680 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue.
> [2023-03-10 15:45:37.939544 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094.0] All messages consumed, disable uplink
> [2023-03-10 15:45:37.939700 -0600][Dump ][XRootDTransport ] [msg: 0xb4063300] Expecting 0 bytes of message body
> [2023-03-10 15:45:37.939716 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message header for 0xb4063300 size: 8
> [2023-03-10 15:45:37.939726 -0600][Debug ][ExDbgMsg ] [msg: 0xb4063300] Assigned MsgHandler: 0x205e680.
> [2023-03-10 15:45:37.939736 -0600][Debug ][ExDbgMsg ] [handler: 0x205e680] Removed MsgHandler: 0x205e680 from the in-queue.
> [2023-03-10 15:45:37.939747 -0600][Dump ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Received message 0xb4063300 of 8 bytes
> [2023-03-10 15:45:37.939758 -0600][Dump ][PostMaster ] [red-xfer8.unl.edu:1094] Handling received message: 0xb4063300.
> [2023-03-10 15:45:37.939791 -0600][Dump ][XRootD ] [red-xfer8.unl.edu:1094] Got a kXR_ok response to request kXR_close (handle: 0x00000000)
> [2023-03-10 15:45:37.939808 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Calling MsgHandler: 0x205e680 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] .
> [2023-03-10 15:45:37.939824 -0600][Debug ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Close returned from red-xfer8.unl.edu:1094 with: [SUCCESS]
> [2023-03-10 15:45:37.939835 -0600][Dump ][File ] ***@***.***://xrootd-local.unl.edu:1094//store/hello_world.txt?xrdcl.requuid=1e31cbb6-5cab-4b1d-aa29-5810685d9316] Items in the fly 0, queued for recovery 0
> [2023-03-10 15:45:37.939855 -0600][Debug ][ExDbgMsg ] [red-xfer8.unl.edu:1094] Destroying MsgHandler: 0x205e680.
> [2023-03-10 15:45:37.940452 -0600][Debug ][JobMgr ] Stopping the job manager...
> [2023-03-10 15:45:37.940475 -0600][Dump ][JobMgr ] Stopping worker #0...
> [2023-03-10 15:45:37.940602 -0600][Dump ][JobMgr ] Worker #0 stopped
> [2023-03-10 15:45:37.940620 -0600][Dump ][JobMgr ] Stopping worker #1...
> [2023-03-10 15:45:37.940679 -0600][Dump ][JobMgr ] Worker #1 stopped
> [2023-03-10 15:45:37.940695 -0600][Dump ][JobMgr ] Stopping worker #2...
> [2023-03-10 15:45:37.940752 -0600][Dump ][JobMgr ] Worker #2 stopped
> [2023-03-10 15:45:37.940768 -0600][Debug ][JobMgr ] Job manager stopped
> [2023-03-10 15:45:37.940779 -0600][Debug ][TaskMgr ] Stopping the task manager...
> [2023-03-10 15:45:37.940882 -0600][Debug ][TaskMgr ] Task manager stopped
> [2023-03-10 15:45:37.940900 -0600][Debug ][Poller ] Stopping the poller...
> [2023-03-10 15:45:37.941133 -0600][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: roots://red-xfer8.unl.edu:1094/"
> [2023-03-10 15:45:37.941159 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Closing the socket
> [2023-03-10 15:45:37.941205 -0600][Debug ][Poller ] <[::ffff:129.93.239.166]:13868><--><[::ffff:129.93.239.138]:1094> Removing socket from the poller
> [2023-03-10 15:45:37.941281 -0600][Debug ][PostMaster ] [red-xfer8.unl.edu:1094] Destroying stream
> [2023-03-10 15:45:37.941300 -0600][Debug ][AsyncSock ] [red-xfer8.unl.edu:1094.0] Closing the socket
> [2023-03-10 15:45:37.941319 -0600][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: roots://xrootd-local.unl.edu:1094/"
> [2023-03-10 15:45:37.941331 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Closing the socket
> [2023-03-10 15:45:37.941341 -0600][Debug ][Poller ] <[::ffff:129.93.239.166]:33348><--><[::ffff:129.93.239.164]:1094> Removing socket from the poller
> [2023-03-10 15:45:37.941457 -0600][Debug ][PostMaster ] [xrootd-local.unl.edu:1094] Destroying stream
> [2023-03-10 15:45:37.941474 -0600][Debug ][AsyncSock ] [xrootd-local.unl.edu:1094.0] Closing the socket
> ```
> </details>
>
> --
> Reply to this email directly or view it on GitHub:
> https://github.com/xrootd/xrootd/issues/1952#issuecomment-1464519072
> You are receiving this because you are subscribed to this thread.
>
> Message ID: ***@***.***>
—
Reply to this email directly, view it on GitHub, or unsubscribe.
You are receiving this because you are subscribed to this thread.
Message ID: <xrootd/xrootd/issues/1952/1464545830@github.com>
[
{
"@context": "http://schema.org",
"@type": "EmailMessage",
"potentialAction": {
"@type": "ViewAction",
"target": "https://github.com/xrootd/xrootd/issues/1952#issuecomment-1464545830",
"url": "https://github.com/xrootd/xrootd/issues/1952#issuecomment-1464545830",
"name": "View Issue"
},
"description": "View this Issue on GitHub",
"publisher": {
"@type": "Organization",
"name": "GitHub",
"url": "https://github.com"
}
}
]
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