Jobsub ID 12744.4@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
ity ] Env: overriding entry: requesttimeout=4096 with 14400
[2025-07-30 11:05:43.701685 +0100][Debug ][Utility ] Env: overriding entry: redirectlimit=16 with 64
[2025-07-30 11:05:43.701711 +0100][Debug ][Utility ] Env: overriding entry: multiprotocol=0 with 1
[2025-07-30 11:05:43.702197 +0100][Debug ][File ] [0xef09be0@root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_supernova_g4stage1_g4stage2_detsim_reco.root?xrdcl.requuid=2cc816ce-d38d-4e70-aa95-1ea8f7bafa3a] Sending an open command
[2025-07-30 11:05:43.702878 +0100][Debug ][Utility ] Env: trying to get a non-existent string entry: pollerpreference
[2025-07-30 11:05:43.702994 +0100][Debug ][Poller ] Available pollers: built-in
[2025-07-30 11:05:43.702999 +0100][Debug ][Poller ] Attempting to create a poller according to preference: built-in
[2025-07-30 11:05:43.703004 +0100][Debug ][Poller ] Creating poller: built-in
[2025-07-30 11:05:43.703105 +0100][Debug ][Poller ] Creating and starting the built-in poller...
[2025-07-30 11:05:43.703673 +0100][Debug ][Poller ] Using 1 poller threads
[2025-07-30 11:05:43.703721 +0100][Debug ][TaskMgr ] Starting the task manager...
[2025-07-30 11:05:43.704011 +0100][Debug ][TaskMgr ] Task manager started
[2025-07-30 11:05:43.704062 +0100][Debug ][JobMgr ] Starting the job manager...
[2025-07-30 11:05:43.704168 +0100][Debug ][JobMgr ] Job manager started, 3 workers
[2025-07-30 11:05:43.704280 +0100][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2025-07-30 11:05:43 +0100]
[2025-07-30 11:05:43.704668 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] MsgHandler created: 0xeebede0 (message: kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_supernova_g4stage1_g4stage2_detsim_reco.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) ).
[2025-07-30 11:05:43.705218 +0100][Debug ][PostMaster ] Creating new channel to: root://fndca1.fnal.gov:1094/
[2025-07-30 11:05:43.705562 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Stream parameters: Network Stack: IPAuto, Connection Window: 30, ConnectionRetry: 5, Stream Error Window: 1800
[2025-07-30 11:05:43.705744 +0100][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: root://fndca1.fnal.gov:1094/" to be run at: [2025-07-30 11:05:58 +0100]
[2025-07-30 11:05:43.706982 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Found 1 address(es): [::ffff:131.225.69.121]:1094
[2025-07-30 11:05:43.707074 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Attempting connection to [::ffff:131.225.69.121]:1094
[2025-07-30 11:05:43.707163 +0100][Debug ][Poller ] Adding socket 0xeeead20 to the poller
[2025-07-30 11:05:43.849074 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Async connection call returned
[2025-07-30 11:05:43.849406 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending out the initial hand shake + kXR_protocol
[2025-07-30 11:05:43.991704 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Got the server hand shake response (type: manager [], protocol version 500)
[2025-07-30 11:05:43.991920 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] kXR_protocol successful (type: manager [], protocol version 500)
[2025-07-30 11:05:43.995862 +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=node2b05.ecdf.ed.ac.uk&xrd.rn=v5.5.5, dual-stack: false, private IPv4: false, private IPv6: false
[2025-07-30 11:05:43.995921 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake exchange.
[2025-07-30 11:05:44.149790 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake exchange.
[2025-07-30 11:05:44.293817 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake exchange.
[2025-07-30 11:05:44.294105 +0100][Info ][AsyncSock ] [fndca1.fnal.gov:1094.0] TLS hand-shake done.
[2025-07-30 11:05:44.436437 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Logged in, session: 6fbf06e962c20b75da09d706d2a34735
[2025-07-30 11:05:44.436479 +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 11:05:44.436523 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending authentication data
[2025-07-30 11:05:44.450465 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Trying to authenticate using ztn
[2025-07-30 11:05:44.450515 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Cannot get credentials for protocol ztn: Secztn: No token found; runtime fetch disallowed.
[2025-07-30 11:05:44.470497 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Trying to authenticate using gsi
[2025-07-30 11:05:45.369626 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Sending more authentication data for gsi
[2025-07-30 11:05:45.533769 +0100][Debug ][XRootDTransport ] [fndca1.fnal.gov:1094.0] Authenticated with gsi.
[2025-07-30 11:05:45.533911 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Stream 0 connected (IPv4).
[2025-07-30 11:05:45.533961 +0100][Debug ][Utility ] Monitor library name not set. No monitoring
[2025-07-30 11:05:45.534290 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] Moving MsgHandler: 0xeebede0 (message: kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_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 11:05:45.687142 +0100][Debug ][ExDbgMsg ] [msg: 0xef09ad0] Assigned MsgHandler: 0xeebede0.
[2025-07-30 11:05:45.687185 +0100][Debug ][ExDbgMsg ] [handler: 0xeebede0] Removed MsgHandler: 0xeebede0 from the in-queue.
[2025-07-30 11:05:45.687507 +0100][Debug ][XRootD ] [fndca1.fnal.gov:1094] Handling error while processing kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_supernova_g4stage1_g4stage2_detsim_reco.root, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): [ERROR] Error response: bad address.
[2025-07-30 11:05:45.687742 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] Calling MsgHandler: 0xeebede0 (message: kXR_open (file: pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_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 11:05:45.687943 +0100][Debug ][File ] [0xef09be0@root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_supernova_g4stage1_g4stage2_detsim_reco.root?xrdcl.requuid=2cc816ce-d38d-4e70-aa95-1ea8f7bafa3a] Open has returned with status [ERROR] Server responded with an error: [3012] Failed to open file (Pool unavailable [1010])
[2025-07-30 11:05:45.687989 +0100][Debug ][File ] [0xef09be0@root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_supernova_g4stage1_g4stage2_detsim_reco.root?xrdcl.requuid=2cc816ce-d38d-4e70-aa95-1ea8f7bafa3a] 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 11:05:45.688127 +0100][Debug ][ExDbgMsg ] [fndca1.fnal.gov:1094] Destroying MsgHandler: 0xeebede0.
MultiPandoraApiImpl::DeletePandoraInstances - unable to find daughter instances associated with primary 0
%MSG-s ArtException: TriggerResultInserter:TriggerResults@Construction 30-Jul-2025 11:05:45 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/ea/c7/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250202T112456Z_gen_004742_supernova_g4stage1_g4stage2_detsim_reco.root was not found or could not be opened.
---- FileOpenError END
%MSG
[2025-07-30 11:05:45.791308 +0100][Debug ][JobMgr ] Stopping the job manager...
[2025-07-30 11:05:45.791675 +0100][Debug ][JobMgr ] Job manager stopped
[2025-07-30 11:05:45.791753 +0100][Debug ][TaskMgr ] Stopping the task manager...
[2025-07-30 11:05:45.791911 +0100][Debug ][TaskMgr ] Task manager stopped
[2025-07-30 11:05:45.791930 +0100][Debug ][Poller ] Stopping the poller...
[2025-07-30 11:05:45.792407 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Closing the socket
[2025-07-30 11:05:45.792802 +0100][Debug ][Poller ] <[::ffff:192.41.105.38]:58166><--><[::ffff:131.225.69.121]:1094> Removing socket from the poller
[2025-07-30 11:05:45.793291 +0100][Debug ][PostMaster ] [fndca1.fnal.gov:1094] Destroying stream
[2025-07-30 11:05:45.793393 +0100][Debug ][AsyncSock ] [fndca1.fnal.gov:1094.0] Closing the socket
Art has completed and will exit with status 20.