Jobsub ID 359717.15@justin-prod-sched01.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM reco2 jobscript.
Input PFN = root://xrootd-archive.cr.cnaf.infn.it:1096//dune/usertests/18/27/000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z.root
Setting up larsoft UPS area... /cvmfs/larsoft.opensciencegrid.org
Setting up DUNE UPS area... /cvmfs/dune.opensciencegrid.org/products/dune/
/cvmfs/larsoft.opensciencegrid.org/products/xrootd/v5_5_5a/Linux64bit+3.10-2.17-e26-p3915-prof/lib/libXrdPosixPreload.so
=== Start last 50 lines of lar log file ===
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 01-Apr-2025 16:59:31 CEST run: 20000031 subRun: 0 event: 19
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 01-Apr-2025 16:59:31 CEST run: 20000031 subRun: 0 event: 19
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 01-Apr-2025 16:59:31 CEST run: 20000031 subRun: 0 event: 19
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
Begin processing the 20th record. run: 20000031 subRun: 0 event: 20 at 01-Apr-2025 16:59:31 CEST
PandoraContentApi::GetList(*this, m_inputHitListName, pCaloHitList) return STATUS_CODE_NOT_INITIALIZED
in function: GetVolumeIdToHitListMap
in file: /scratch/workspace/build-larsoft/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/ALMA9/build/larpandoracontent/v04_14_01-buildFW/src/larpandoracontent/LArControlFlow/MasterAlgorithm.cc line#: 271
this->GetVolumeIdToHitListMap(volumeIdToHitListMap) return STATUS_CODE_NOT_INITIALIZED
in function: Run
in file: /scratch/workspace/build-larsoft/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/ALMA9/build/larpandoracontent/v04_14_01-buildFW/src/larpandoracontent/LArControlFlow/MasterAlgorithm.cc line#: 165
iter->second->Run() throw STATUS_CODE_NOT_INITIALIZED
in function: RunAlgorithm
in file: /scratch/workspace/build-larbase/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/SLF7/build/pandora/v03_16_00l/src/pandora-v03-16-00/PandoraSDK-v03-04-01/src/Api/PandoraContentApiImpl.cc line#: 235
Failure in algorithm Alg0003, LArDLMaster, STATUS_CODE_NOT_INITIALIZED
01-Apr-2025 16:59:33 CEST Closed output file "000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z_reco_data_2025-04-01T_145652Z.root"
01-Apr-2025 16:59:33 CEST Closed input file "root://xrootd-archive.cr.cnaf.infn.it:1096//dune/usertests/18/27/000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z.root"
========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
========================================================================================================================================
Full event 0.34784 3.3626 53.8192 0.367022 11.5882 20
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.0345294 0.040051 0.0691725 0.034835 0.0121702 20
reco:linecluster:LineCluster 0.205709 0.678252 7.92179 0.207178 1.66507 20
reco:trajcluster:TrajCluster 0.102792 1.60359 28.2107 0.104211 6.10711 20
reco:pandora:StandardPandora 0.000674381 0.871504 16.0155 0.000976635 3.4767 20
reco:pandoraTrack:LArPandoraTrackCreation 8.5743e-05 0.0415224 0.536204 0.000114053 0.11737 20
reco:pandoraShower:LArPandoraModularShowerCreation 0.000126053 0.0540552 0.268519 0.000142544 0.0889218 20
reco:pandoracalo:Calorimetry 0.000180245 0.021416 0.221709 0.000200771 0.0499646 20
reco:pandorapid:Chi2ParticleID 2.567e-05 0.000248347 0.0019714 2.6946e-05 0.000439195 20
reco:trkshowersplit:TrackShowerHits 0.000189916 0.0238126 0.406497 0.000308085 0.0879949 20
reco:rns:RandomNumberSaver 1.1861e-05 1.87407e-05 9.6723e-05 1.27755e-05 1.84997e-05 20
[art]:TriggerResults:TriggerResultInserter 6.9e-06 8.7952e-06 2.7351e-05 7.8455e-06 4.28447e-06 20
end_path:out1:RootOutput 2.29e-06 2.97455e-06 9.76e-06 2.5605e-06 1.57938e-06 20
end_path:out1:RootOutput(write) 0.00229286 0.0277728 0.200979 0.00273114 0.0472709 20
========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 2270.92 MB
Peak resident set size usage (VmHWM): 1172.13 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
extractor_prod.py exit code 0
pdjson2metadata exit code 0
Metadata extraction succeeds
===Metadata JSON===
{
"metadata": {
"DUNE.campaign": "justIN.w5846s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 1,
"art.last_event": 20,
"art.process_name": "Reco2",
"art.run_type": "physics",
"core.application": "art.reco",
"core.application.family": "art",
"core.application.name": "reco",
"core.application.version": "v10_04_06d00",
"core.data_stream": "out1",
"core.data_tier": "full-reconstructed",
"core.end_time": 1743519573.0,
"core.event_count": 20,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 1,
"core.last_event_number": 20,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1743519447.0
},
"parents": [
{
"did": "usertests:000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z.root"
}
]
}
===================
.:
total 277684
-rw-r--r-- 1 dune001 dune 284193767 Apr 1 16:59 000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z_reco_data_2025-04-01T_145652Z.root
-rw-r--r-- 1 dune001 dune 109827 Apr 1 16:59 000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z_reco_2025-04-01T_145652Z.log
-rw-r--r-- 1 dune001 dune 7560 Apr 1 16:59 reco2_hist.root
-rw-r--r-- 1 dune001 dune 7221 Apr 1 16:59 jobscript.log
-rw-r--r-- 1 dune001 dune 1168 Apr 1 16:59 000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z_reco_data_2025-04-01T_145652Z.root.json
-rw-r--r-- 1 dune001 dune 1042 Apr 1 16:59 000009_reco_data_2025-03-31T_142644Z_reco_data_2025-03-31T_150022Z_reco_data_2025-04-01T_083013Z_reco_data_2025-04-01T_101339Z_reco_data_2025-04-01T_145652Z.root.ext.json
-rw-r--r-- 1 dune001 dune 284 Apr 1 16:56 all-input-dids.txt
-rw-r--r-- 1 dune001 dune 197 Apr 1 16:59 justin-processed-pfns.txt
-rw-r--r-- 1 dune001 dune 0 Apr 1 16:57 debugprod.log 