n@earth: ~]$ xrdcp -d 2 -f root://deepthought.crc.nd.edu//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root /dev/null [2022-04-04 11:52:47.074893 -0400][Debug ][Utility ] CopyProcess: 2 jobs to prepare [2022-04-04 11:52:47.075163 -0400][Debug ][Utility ] Creating a classic copy job, from root://deepthought.crc.nd.edu:1094//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root to file://localhost/dev/null [2022-04-04 11:52:47.075268 -0400][Debug ][Utility ] Monitor library name not set. No monitoring [2022-04-04 11:52:47.075438 -0400][Debug ][Utility ] Env: trying to get a non-existent string entry: pollerpreference [2022-04-04 11:52:47.075472 -0400][Debug ][Poller ] Available pollers: built-in [2022-04-04 11:52:47.075498 -0400][Debug ][Poller ] Attempting to create a poller according to preference: built-in [2022-04-04 11:52:47.075522 -0400][Debug ][Poller ] Creating poller: built-in [2022-04-04 11:52:47.075553 -0400][Debug ][Poller ] Creating and starting the built-in poller... [2022-04-04 11:52:47.075940 -0400][Debug ][Poller ] Using 1 poller threads [2022-04-04 11:52:47.075969 -0400][Debug ][TaskMgr ] Starting the task manager... [2022-04-04 11:52:47.076046 -0400][Debug ][TaskMgr ] Task manager started [2022-04-04 11:52:47.076073 -0400][Debug ][JobMgr ] Starting the job manager... [2022-04-04 11:52:47.076217 -0400][Debug ][JobMgr ] Job manager started, 3 workers [2022-04-04 11:52:47.076248 -0400][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2022-04-04 11:52:47 -0400] [2022-04-04 11:52:47.076288 -0400][Debug ][Utility ] Opening root://deepthought.crc.nd.edu:1094//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root for reading [2022-04-04 11:52:47.076487 -0400][Debug ][File ] [0x1731f70@root://deepthought.crc.nd.edu:1094//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root?xrdcl.requuid=8c44a2a6-db97-42f7-a890-69171852a087] Sending an open command [2022-04-04 11:52:47.076593 -0400][Debug ][ExDbgMsg ] [deepthought.crc.nd.edu:1094] MsgHandler created: 0x17379d0 (message: kXR_open (file: /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ). [2022-04-04 11:52:47.076694 -0400][Debug ][PostMaster ] Creating new channel to: root://deepthought.crc.nd.edu:1094 [2022-04-04 11:52:47.076756 -0400][Debug ][PostMaster ] [deepthought.crc.nd.edu:1094] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800 [2022-04-04 11:52:47.076805 -0400][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: root://deepthought.crc.nd.edu:1094" to be run at: [2022-04-04 11:53:02 -0400] [2022-04-04 11:52:47.077466 -0400][Debug ][PostMaster ] [deepthought.crc.nd.edu:1094] Found 1 address(es): [::ffff:129.74.85.55]:1094 [2022-04-04 11:52:47.077522 -0400][Debug ][AsyncSock ] [deepthought.crc.nd.edu:1094.0] Attempting connection to [::ffff:129.74.85.55]:1094 [2022-04-04 11:52:47.077579 -0400][Debug ][Poller ] Adding socket 0x1737ea0 to the poller [2022-04-04 11:52:47.077984 -0400][Debug ][AsyncSock ] [deepthought.crc.nd.edu:1094.0] Async connection call returned [2022-04-04 11:52:47.078132 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Sending out the initial hand shake + kXR_protocol [2022-04-04 11:52:47.078425 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Got the server hand shake response (type: manager [], protocol version 510) [2022-04-04 11:52:47.078481 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] kXR_protocol successful (type: manager [], protocol version 510) [2022-04-04 11:52:47.079142 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Sending out kXR_login request, username: kmohrman, cgi: ?xrd.cc=us&xrd.tz=-5&xrd.appname=xrdcp&xrd.info=&xrd.hostname=earth.crc.nd.edu&xrd.rn=v5.4.2, dual-stack: false, private IPv4: true, private IPv6: false [2022-04-04 11:52:47.079351 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Logged in, session: aeca0000557a06002000000008d70000 [2022-04-04 11:52:47.079378 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Authentication is required: &P=gsi,v:10600,c:ssl,ca:ba240aa8.0|f5f0dfc2.0 [2022-04-04 11:52:47.079410 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Sending authentication data [2022-04-04 11:52:47.083617 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Trying to authenticate using gsi [2022-04-04 11:52:47.468306 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Sending more authentication data for gsi [2022-04-04 11:52:47.513539 -0400][Debug ][XRootDTransport ] [deepthought.crc.nd.edu:1094.0] Authenticated with gsi. [2022-04-04 11:52:47.513591 -0400][Debug ][PostMaster ] [deepthought.crc.nd.edu:1094] Stream 0 connected. [2022-04-04 11:52:47.513672 -0400][Debug ][ExDbgMsg ] [deepthought.crc.nd.edu:1094] Moving MsgHandler: 0x17379d0 (message: kXR_open (file: /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) from out-queu to in-queue. [2022-04-04 11:52:47.514421 -0400][Debug ][ExDbgMsg ] [skynet013.crc.nd.edu:1094] Retry at server MsgHandler: 0x17379d0 (message: kXR_open (file: /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ). [2022-04-04 11:52:47.514538 -0400][Debug ][PostMaster ] Creating new channel to: root://skynet013.crc.nd.edu:1094 [2022-04-04 11:52:47.514637 -0400][Debug ][PostMaster ] [skynet013.crc.nd.edu:1094] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800 [2022-04-04 11:52:47.514702 -0400][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: root://skynet013.crc.nd.edu:1094" to be run at: [2022-04-04 11:53:02 -0400] [2022-04-04 11:52:47.515450 -0400][Debug ][PostMaster ] [skynet013.crc.nd.edu:1094] Found 1 address(es): [::ffff:129.74.85.12]:1094 [2022-04-04 11:52:47.515519 -0400][Debug ][AsyncSock ] [skynet013.crc.nd.edu:1094.0] Attempting connection to [::ffff:129.74.85.12]:1094 [2022-04-04 11:52:47.515617 -0400][Debug ][Poller ] Adding socket 0x60001260 to the poller [2022-04-04 11:52:47.515776 -0400][Debug ][AsyncSock ] [skynet013.crc.nd.edu:1094.0] Async connection call returned [2022-04-04 11:52:47.515947 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Sending out the initial hand shake + kXR_protocol [2022-04-04 11:52:47.516154 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Got the server hand shake response (type: server [], protocol version 510) [2022-04-04 11:52:47.516233 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] kXR_protocol successful (type: server [], protocol version 510) [2022-04-04 11:52:47.516959 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Sending out kXR_login request, username: kmohrman, cgi: ?xrd.cc=us&xrd.tz=-5&xrd.appname=xrdcp&xrd.info=&xrd.hostname=earth.crc.nd.edu&xrd.rn=v5.4.2, dual-stack: false, private IPv4: true, private IPv6: false [2022-04-04 11:52:47.517157 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Logged in, session: 400700001c2c00002900000040070000 [2022-04-04 11:52:47.517206 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Authentication is required: &P=gsi,v:10600,c:ssl,ca:ba240aa8.0|f5f0dfc2.0 [2022-04-04 11:52:47.517241 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Sending authentication data [2022-04-04 11:52:47.517299 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Trying to authenticate using gsi [2022-04-04 11:52:47.534428 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Sending more authentication data for gsi [2022-04-04 11:52:47.583805 -0400][Debug ][XRootDTransport ] [skynet013.crc.nd.edu:1094.0] Authenticated with gsi. [2022-04-04 11:52:47.583883 -0400][Debug ][PostMaster ] [skynet013.crc.nd.edu:1094] Stream 0 connected. [2022-04-04 11:52:47.583957 -0400][Debug ][ExDbgMsg ] [skynet013.crc.nd.edu:1094] Moving MsgHandler: 0x17379d0 (message: kXR_open (file: /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) from out-queu to in-queue. [2022-04-04 11:52:47.584262 -0400][Debug ][XRootD ] [skynet013.crc.nd.edu:1094] Handling error while processing kXR_open (file: /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): [ERROR] Error response: permission denied. [2022-04-04 11:52:47.584391 -0400][Debug ][ExDbgMsg ] [skynet013.crc.nd.edu:1094] Calling MsgHandler: 0x17379d0 (message: kXR_open (file: /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) with status: [ERROR] Error response: permission denied. [2022-04-04 11:52:47.584489 -0400][Debug ][File ] [0x1731f70@root://deepthought.crc.nd.edu:1094//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root?xrdcl.requuid=8c44a2a6-db97-42f7-a890-69171852a087] Open has returned with status [ERROR] Server responded with an error: [3010] Unable to open /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root; permission denied [2022-04-04 11:52:47.584528 -0400][Debug ][File ] [0x1731f70@root://deepthought.crc.nd.edu:1094//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root?xrdcl.requuid=8c44a2a6-db97-42f7-a890-69171852a087] Error while opening at skynet013.crc.nd.edu:1094: [ERROR] Server responded with an error: [3010] Unable to open /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root; permission denied [2022-04-04 11:52:47.584627 -0400][Debug ][XRootD ] Redirect trace-back: [2022-04-04 11:52:47.584627 -0400][Debug ][XRootD ] 0. Redirected from: root://deepthought.crc.nd.edu:1094//store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root to: root://skynet013.crc.nd.edu:1094/ [2022-04-04 11:52:47.584694 -0400][Debug ][ExDbgMsg ] [skynet013.crc.nd.edu:1094] Destroying MsgHandler: 0x17379d0. [0B/0B][100%][==================================================][0B/s] Run: [ERROR] Server responded with an error: [3010] Unable to open /store/user/awightma/skims/mc/new-lepMVA-v2/central_bkgd_p1/FullRun2/v2/UL16APV_DY50/output_101.root; permission denied (source) [2022-04-04 11:52:47.585692 -0400][Debug ][JobMgr ] Stopping the job manager... [2022-04-04 11:52:47.586356 -0400][Debug ][JobMgr ] Job manager stopped [2022-04-04 11:52:47.586384 -0400][Debug ][TaskMgr ] Stopping the task manager... [2022-04-04 11:52:47.586543 -0400][Debug ][TaskMgr ] Task manager stopped [2022-04-04 11:52:47.586568 -0400][Debug ][Poller ] Stopping the poller... [2022-04-04 11:52:47.586687 -0400][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: root://deepthought.crc.nd.edu:1094" [2022-04-04 11:52:47.586717 -0400][Debug ][AsyncSock ] [deepthought.crc.nd.edu:1094.0] Closing the socket [2022-04-04 11:52:47.586750 -0400][Debug ][Poller ] <[::ffff:10.32.77.18]:15946><--><[::ffff:129.74.85.55]:1094> Removing socket from the poller [2022-04-04 11:52:47.586804 -0400][Debug ][PostMaster ] [deepthought.crc.nd.edu:1094] Destroying stream [2022-04-04 11:52:47.586832 -0400][Debug ][AsyncSock ] [deepthought.crc.nd.edu:1094.0] Closing the socket [2022-04-04 11:52:47.586893 -0400][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: root://skynet013.crc.nd.edu:1094" [2022-04-04 11:52:47.586922 -0400][Debug ][AsyncSock ] [skynet013.crc.nd.edu:1094.0] Closing the socket [2022-04-04 11:52:47.586945 -0400][Debug ][Poller ] <[::ffff:10.32.77.18]:22558><--><[::ffff:129.74.85.12]:1094> Removing socket from the poller [2022-04-04 11:52:47.586983 -0400][Debug ][PostMaster ] [skynet013.crc.nd.edu:1094] Destroying stream [2022-04-04 11:52:47.587004 -0400][Debug ][AsyncSock ] [skynet013.crc.nd.edu:1094.0] Closing the socket