Jobsub ID 112904.40@justin-prod-sched02.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM reco1 jobscript.
Input PFN = root://xgate.hec.lancs.ac.uk:1094//cephfs/grid/dune/usertests/5b/0c/000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z.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 ===
Begin processing the 995th record. run: 20000031 subRun: 0 event: 995 at 02-Dec-2024 13:36:01 CET
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 361 XUs and 1199 XVs -> 223 XUVs
C:0 T:2 4144 XUs and 6243 XVs -> 1185 XUVs
C:0 T:4 593 XUs and 1472 XVs -> 303 XUVs
1711 XUVs total
822 collection wire objects
1711 potential space points
Neighbour search...
127185 tests to find 48364 neighbours
Iterating with no regularization...
Begin: 9.27326e+07
0 7.22785e+07
1 7.11908e+07
2 7.10122e+07
3 7.09574e+07
Now with regularization...
Begin: 6.23189e+07
0 6.22615e+07
Begin processing the 996th record. run: 20000031 subRun: 0 event: 996 at 02-Dec-2024 13:36:01 CET
Begin processing the 997th record. run: 20000031 subRun: 0 event: 997 at 02-Dec-2024 13:36:02 CET
Begin processing the 998th record. run: 20000031 subRun: 0 event: 998 at 02-Dec-2024 13:36:02 CET
Begin processing the 999th record. run: 20000031 subRun: 0 event: 999 at 02-Dec-2024 13:36:02 CET
Begin processing the 1000th record. run: 20000031 subRun: 0 event: 1000 at 02-Dec-2024 13:36:03 CET
02-Dec-2024 13:36:04 CET Closed output file "000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z_reco_data_2024-12-02T_122332Z.root"
02-Dec-2024 13:36:04 CET Closed input file "root://xgate.hec.lancs.ac.uk:1094//cephfs/grid/dune/usertests/5b/0c/000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 0.0796483 0.154667 17.8103 0.0877902 0.750372 1000
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000160741 0.00260196 0.0556598 0.000284694 0.00707751 1000
reco:gaushit:GausHitFinder 0.0783045 0.0977927 0.53564 0.0854021 0.0396215 1000
reco:spsolve:SpacePointSolver 6.8118e-05 0.0462018 17.5554 0.000119704 0.738826 1000
reco:hitfd:DisambigFromSpacePoints 0.000126387 0.00252254 0.198845 0.000193373 0.0111485 1000
reco:rns:RandomNumberSaver 1.7032e-05 2.83944e-05 0.0010222 2.38045e-05 3.35585e-05 1000
[art]:TriggerResults:TriggerResultInserter 8.005e-06 2.1075e-05 0.0073047 1.26735e-05 0.000230528 1000
end_path:out1:RootOutput 3.397e-06 5.01577e-06 2.8694e-05 4.448e-06 2.45188e-06 1000
end_path:out1:RootOutput(write) 0.000655461 0.00518097 0.117759 0.00110961 0.0126408 1000
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1874.45 MB
Peak resident set size usage (VmHWM): 941.543 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.w4334s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 1,
"art.last_event": 1000,
"art.process_name": "Reco1",
"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": "hit-reconstructed",
"core.end_time": 1733142964.0,
"core.event_count": 1000,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 1,
"core.last_event_number": 1000,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1733142261.0
},
"parents": [
{
"did": "usertests:000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z.root"
}
]
}
===================
.:
total 2283456
-rw-r--r-- 1 dune001 lbno 2338049822 Dec 2 13:36 000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z_reco_data_2024-12-02T_122332Z.root
-rw-r--r-- 1 dune001 lbno 178919 Dec 2 13:36 000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z_reco_2024-12-02T_122332Z.log
-rw-r--r-- 1 dune001 lbno 5493 Dec 2 13:36 jobscript.log
-rw-r--r-- 1 dune001 lbno 1143 Dec 2 13:36 000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z_reco_data_2024-12-02T_122332Z.root.json
-rw-r--r-- 1 dune001 lbno 988 Dec 2 13:36 000994_reco_data_2024-11-14T_093628Z_reco_data_2024-11-14T_141128Z_reco_data_2024-11-18T_132021Z_reco_data_2024-12-02T_122332Z.root.ext.json
-rw-r--r-- 1 dune001 lbno 519 Dec 2 13:36 reco1_hist.root
-rw-r--r-- 1 dune001 lbno 224 Dec 2 13:23 all-input-dids.txt
-rw-r--r-- 1 dune001 lbno 170 Dec 2 13:36 justin-processed-pfns.txt
-rw-r--r-- 1 dune001 lbno 0 Dec 2 13:23 debugprod.log 