LISTSERV 16.5 - XROOTD-DEV Archives

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.

[ { "@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