Jobsub ID 19349.20@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ity ] Env: overriding entry: requesttimeout=4096 with 14400
[2025-07-30 13:30:27.223165 +0100][Debug ][Utility ] Env: overriding entry: redirectlimit=16 with 64
[2025-07-30 13:30:27.223193 +0100][Debug ][Utility ] Env: overriding entry: multiprotocol=0 with 1
[2025-07-30 13:30:27.223636 +0100][Debug ][File ] [0xecca2f0@root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root?xrdcl.requuid=a8f9072c-9b1d-48ca-ab49-f1962552e063] Sending an open command
[2025-07-30 13:30:27.224192 +0100][Debug ][Utility ] Env: trying to get a non-existent string entry: pollerpreference
[2025-07-30 13:30:27.224293 +0100][Debug ][Poller ] Available pollers: built-in
[2025-07-30 13:30:27.224298 +0100][Debug ][Poller ] Attempting to create a poller according to preference: built-in
[2025-07-30 13:30:27.224303 +0100][Debug ][Poller ] Creating poller: built-in
[2025-07-30 13:30:27.224396 +0100][Debug ][Poller ] Creating and starting the built-in poller...
[2025-07-30 13:30:27.224965 +0100][Debug ][Poller ] Using 1 poller threads
[2025-07-30 13:30:27.225012 +0100][Debug ][TaskMgr ] Starting the task manager...
[2025-07-30 13:30:27.225311 +0100][Debug ][TaskMgr ] Task manager started
[2025-07-30 13:30:27.225365 +0100][Debug ][JobMgr ] Starting the job manager...
[2025-07-30 13:30:27.225478 +0100][Debug ][JobMgr ] Job manager started, 3 workers
[2025-07-30 13:30:27.225585 +0100][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2025-07-30 13:30:27 +0100]
[2025-07-30 13:30:27.225936 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] MsgHandler created: 0xec81d00 (message: kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ).
[2025-07-30 13:30:27.226425 +0100][Debug ][PostMaster ] Creating new channel to: root://fndca1.fnal.gov:1094/
[2025-07-30 13:30:27.226776 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Stream parameters: Network Stack: IPAuto, Connection Window: 30, ConnectionRetry: 5, Stream Error Window: 1800
[2025-07-30 13:30:27.226941 +0100][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: root://fndca1.fnal.gov:1094/" to be run at: [2025-07-30 13:30:42 +0100]
[2025-07-30 13:30:27.228304 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Found 1 address(es): [::ffff:131.225.69.121]:1094
[2025-07-30 13:30:27.228393 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Attempting connection to [::ffff:131.225.69.121]:1094
[2025-07-30 13:30:27.228483 +0100][Debug ][Poller ] Adding socket 0xecb2f50 to the poller
[2025-07-30 13:30:27.353893 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Async connection call returned
[2025-07-30 13:30:27.354216 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending out the initial hand shake + kXR_protocol
[2025-07-30 13:30:27.480043 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Got the server hand shake response (type: manager [], protocol version 500)
[2025-07-30 13:30:27.480241 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] kXR_protocol successful (type: manager [], protocol version 500)
[2025-07-30 13:30:27.481257 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending out kXR_login request, username: gl05pi6, cgi: xrd.cc=uk&xrd.tz=0&xrd.appname=lar&xrd.info=&xrd.hostname=node2b07.ecdf.ed.ac.uk&xrd.rn=v5.5.5, dual-stack: false, private IPv4: false, private IPv6: false
[2025-07-30 13:30:27.481307 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake exchange.
[2025-07-30 13:30:27.617485 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake exchange.
[2025-07-30 13:30:27.745160 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake exchange.
[2025-07-30 13:30:27.745428 +0100][Info ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake done.
[2025-07-30 13:30:27.871270 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Logged in, session: b584abc6884f458efdc32279a0d98f7a
[2025-07-30 13:30:27.871312 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Authentication is required: &P=ztn,0:4096:&P=gsi,v:10400,c:ssl,ca:3cbc995f
[2025-07-30 13:30:27.871352 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending authentication data
[2025-07-30 13:30:27.885434 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Trying to authenticate using ztn
[2025-07-30 13:30:27.885485 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Cannot get credentials for protocol ztn: Secztn: No token found; runtime fetch disallowed.
[2025-07-30 13:30:27.904216 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Trying to authenticate using gsi
[2025-07-30 13:30:28.219715 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending more authentication data for gsi
[2025-07-30 13:30:28.366236 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Authenticated with gsi.
[2025-07-30 13:30:28.366371 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Stream 0 connected (IPv4).
[2025-07-30 13:30:28.366419 +0100][Debug ][Utility ] Monitor library name not set. No monitoring
[2025-07-30 13:30:28.366738 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] Moving MsgHandler: 0xec81d00 (message: kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) from out-queu to in-queue.
[2025-07-30 13:30:28.616013 +0100][Debug ][ExDbgMsg ] [msg: 0xe812510] Assigned MsgHandler: 0xec81d00.
[2025-07-30 13:30:28.616056 +0100][Debug ][ExDbgMsg ] [handler: 0xec81d00] Removed MsgHandler: 0xec81d00 from the in-queue.
[2025-07-30 13:30:28.616345 +0100][Debug ][XRootD ] [fndca1.fnal.gov:1094] Handling error while processing kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): [ERROR] Error response: bad address.
[2025-07-30 13:30:28.616560 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] Calling MsgHandler: 0xec81d00 (message: kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ) with status: [ERROR] Error response: bad address.
[2025-07-30 13:30:28.616746 +0100][Debug ][File ] [0xecca2f0@root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root?xrdcl.requuid=a8f9072c-9b1d-48ca-ab49-f1962552e063] Open has returned with status [ERROR] Server responded with an error: [3012] Failed to open file (Pool unavailable [1010])
[2025-07-30 13:30:28.616787 +0100][Debug ][File ] [0xecca2f0@root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root?xrdcl.requuid=a8f9072c-9b1d-48ca-ab49-f1962552e063] Error while opening at fndca1.fnal.gov:1094: [ERROR] Server responded with an error: [3012] Failed to open file (Pool unavailable [1010])
[2025-07-30 13:30:28.618303 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] Destroying MsgHandler: 0xec81d00.
MultiPandoraApiImpl::DeletePandoraInstances - unable to find daughter instances associated with primary 0
%MSG-s ArtException: TriggerResultInserter:TriggerResults@Construction 30-Jul-2025 13:30:28 BST ModuleConstruction
cet::exception caught in art
---- FileOpenError BEGIN
RootInputFileSequence::initFile(): Input file root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/86/30/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250205T121604Z_gen_004168_supernova_g4stage1_g4stage2_detsim_reco.root was not found or could not be opened.
---- FileOpenError END
%MSG
[2025-07-30 13:30:28.859577 +0100][Debug ][JobMgr ] Stopping the job manager...
[2025-07-30 13:30:28.859935 +0100][Debug ][JobMgr ] Job manager stopped
[2025-07-30 13:30:28.860007 +0100][Debug ][TaskMgr ] Stopping the task manager...
[2025-07-30 13:30:28.860137 +0100][Debug ][TaskMgr ] Task manager stopped
[2025-07-30 13:30:28.860157 +0100][Debug ][Poller ] Stopping the poller...
[2025-07-30 13:30:28.860799 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Closing the socket
[2025-07-30 13:30:28.860885 +0100][Debug ][Poller ] <[::ffff:192.41.105.40]:42208><--><[::ffff:131.225.69.121]:1094> Removing socket from the poller
[2025-07-30 13:30:28.861196 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Destroying stream
[2025-07-30 13:30:28.861277 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Closing the socket
Art has completed and will exit with status 20.