Jobsub ID 72767.4@justin-prod-sched02.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM reco2 jobscript.
Input PFN = root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/usertests/3f/50/000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z.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_4_3b/Linux64bit+3.10-2.17-e20-p3913-prof/lib/libXrdPosixPreload.so
=== Start last 50 lines of lar log file ===
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 16-Sep-2024 19:29:01 CDT run: 20000031 subRun: 0 event: 967
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 16-Sep-2024 19:29:01 CDT run: 20000031 subRun: 0 event: 967
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 16-Sep-2024 19:29:01 CDT run: 20000031 subRun: 0 event: 967
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 16-Sep-2024 19:29:01 CDT run: 20000031 subRun: 0 event: 967
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 16-Sep-2024 19:29:01 CDT run: 20000031 subRun: 0 event: 967
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
%MSG-e ShowerProducedPtrsHolder: LArPandoraModularShowerCreation:pandoraShower@BeginModule 16-Sep-2024 19:29:01 CDT run: 20000031 subRun: 0 event: 967
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
16-Sep-2024 19:29:12 CDT Closed output file "000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z_reco_data_2024-09-16T_142013Z.root"
16-Sep-2024 19:29:12 CDT Closed input file "root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/usertests/3f/50/000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z.root"
========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
========================================================================================================================================
Full event 0.163381 4560.08 36478.6 0.317316 12064.1 8
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00487751 0.00795735 0.0194705 0.00512371 0.00476193 8
reco:linecluster:LineCluster 0.0298331 161.195 1289.09 0.0634803 426.305 8
reco:trajcluster:TrajCluster 0.0152357 111.439 891.078 0.0752973 294.676 8
reco:pandora:StandardPandora 0.0122929 4283.18 34265 0.0662762 11332.1 8
reco:pandoraTrack:LArPandoraTrackCreation 0.00124147 1.58767 12.6548 0.00728015 4.18298 8
reco:pandoraShower:LArPandoraModularShowerCreation 0.0011577 0.455281 3.53809 0.00899379 1.16527 8
reco:pandoracalo:Calorimetry 0.000764617 0.573232 4.55433 0.00531317 1.50472 8
reco:pandorapid:Chi2ParticleID 0.000126157 0.00107528 0.00704623 0.000224337 0.0022581 8
reco:trkshowersplit:TrackShowerHits 0.000220785 0.900233 7.18207 0.00333918 2.37431 8
reco:ophit:OpHitFinder 0.0324368 0.1831 1.17116 0.035929 0.373782 8
reco:opflash:OpFlashFinder 0.000264267 0.000655898 0.00217086 0.000412885 0.00059508 8
reco:rns:RandomNumberSaver 1.575e-05 4.35867e-05 0.000177924 2.3674e-05 5.15708e-05 8
[art]:TriggerResults:TriggerResultInserter 7.875e-06 1.53415e-05 5.0716e-05 1.062e-05 1.35365e-05 8
end_path:out1:RootOutput 3.847e-06 7.0595e-06 2.0208e-05 4.408e-06 5.39977e-06 8
end_path:out1:RootOutput(write) 0.00463408 0.551874 4.30316 0.0168398 1.41787 8
========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 3395.54 MB
Peak resident set size usage (VmHWM): 2077.12 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.w3262s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 31,
"art.last_event": 967,
"art.process_name": "Reco2",
"art.run_type": "physics",
"core.application": "art.reco",
"core.application.family": "art",
"core.application.name": "reco",
"core.application.version": "v09_75_03d00",
"core.data_stream": "out1",
"core.data_tier": "full-reconstructed",
"core.end_time": 1726532952.0,
"core.event_count": 8,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 31,
"core.last_event_number": 967,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1726496443.0
},
"parents": [
{
"did": "usertests:000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z.root"
}
]
}
===================
.:
total 1125868
-rw-r--r--. 1 dune osgvo 1152599380 Sep 16 19:29 000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z_reco_data_2024-09-16T_142013Z.root
-rw-r--r--. 1 dune osgvo 247094 Sep 16 19:29 000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z_reco_2024-09-16T_142013Z.log
-rw-r--r--. 1 dune osgvo 7560 Sep 16 19:29 reco2_hist.root
-rw-r--r--. 1 dune osgvo 7139 Sep 16 19:29 jobscript.log
-rw-r--r--. 1 dune osgvo 1171 Sep 16 19:29 000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z_reco_data_2024-09-16T_142013Z.root.json
-rw-r--r--. 1 dune osgvo 1046 Sep 16 19:29 000758_reco_data_2024-08-16T_162825Z_reco_data_2024-08-19T_110514Z_reco_data_2024-08-20T_132602Z_reco_data_2024-08-22T_092900Z_reco_data_2024-09-16T_142013Z.root.ext.json
-rw-r--r--. 1 dune osgvo 284 Sep 16 09:20 all-input-dids.txt
-rw-r--r--. 1 dune osgvo 218 Sep 16 19:29 justin-processed-pfns.txt
-rw-r--r--. 1 dune osgvo 0 Sep 16 09:20 debugprod.log