Jobsub ID 168152.16@justin-prod-sched02.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM detsim jobscript.
Input PFN = root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/2b/dd/000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z.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 ===
wclsFrameSaver: no traces tagged "wiener"
FrameSaver: q=0 n=0 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
Begin processing the 19th record. run: 20000031 subRun: 0 event: 19 at 01-Apr-2025 09:40:44 BST
SimDepoSource got 127 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 127 depos spanning: [0.0453814, 0.0584465]us
Retagger: tagging trace set: wiener with 311 traces, 311 summary
Retagger: tagging trace set: gauss with 295 traces, 0 summary
wclsFrameSaver: saving 295 traces tagged "gauss"
FrameSaver: q=7303.96 n=3371 tag=gauss
wclsFrameSaver: saving 311 traces tagged "wiener"
FrameSaver: q=10328.5 n=3282 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
Begin processing the 20th record. run: 20000031 subRun: 0 event: 20 at 01-Apr-2025 09:41:08 BST
SimDepoSource got 101904 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 101904 depos spanning: [0.0423727, 0.0880371]us
Retagger: tagging trace set: wiener with 15897 traces, 15897 summary
Retagger: tagging trace set: gauss with 11342 traces, 0 summary
wclsFrameSaver: saving 11342 traces tagged "gauss"
FrameSaver: q=2.55368e+06 n=190007 tag=gauss
wclsFrameSaver: saving 15897 traces tagged "wiener"
FrameSaver: q=2.79514e+06 n=164891 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
01-Apr-2025 09:42:24 BST Closed output file "000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z_reco_data_2025-04-01T_083016Z.root"
01-Apr-2025 09:42:24 BST Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/2b/dd/000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 0.790073 20.8349 90.4489 1.04314 30.342 20
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000331824 0.00970371 0.123804 0.000811852 0.0271182 20
simulate:tpcrawdecoder:WireCellToolkit 0.302851 20.1116 89.2671 0.390943 30.1755 20
simulate:opdigi:OpDetDigitizerDUNE 5.2809e-05 0.108694 0.470048 0.000477343 0.162793 20
simulate:rns:RandomNumberSaver 1.7413e-05 3.61626e-05 0.000236164 2.38095e-05 4.63499e-05 20
[art]:TriggerResults:TriggerResultInserter 8.666e-06 1.38054e-05 4.3201e-05 1.24635e-05 6.96869e-06 20
end_path:out1:RootOutput 2.926e-06 6.6091e-06 3.174e-05 4.338e-06 6.6537e-06 20
end_path:out1:RootOutput(write) 0.402621 0.604229 0.747763 0.620144 0.0767876 20
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8470.22 MB
Peak resident set size usage (VmHWM): 6433.41 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z_reco_data_2025-04-01T_083016Z.root
extractor_prod.py exit code 0
pdjson2metadata exit code 0
Metadata extraction succeeds
===Metadata JSON===
{
"metadata": {
"DUNE.campaign": "justIN.w5838s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 1,
"art.last_event": 20,
"art.process_name": "detsim",
"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": "detector-simulated",
"core.end_time": 1743496944.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": 1743496523.0
},
"parents": [
{
"did": "usertests:000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z.root"
}
]
}
===================
.:
total 160972
-rw-r--r-- 1 pltdune04 pltdune 155078341 Apr 1 09:42 000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z_reco_data_2025-04-01T_083016Z.root
-rw-r--r-- 1 pltdune04 pltdune 9685687 Apr 1 09:42 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r-- 1 pltdune04 pltdune 42681 Apr 1 09:42 000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z_reco_2025-04-01T_083016Z.log
-rw-r--r-- 1 pltdune04 pltdune 5757 Apr 1 09:42 jobscript.log
-rw-r--r-- 1 pltdune04 pltdune 1109 Apr 1 09:42 000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z_reco_data_2025-04-01T_083016Z.root.json
-rw-r--r-- 1 pltdune04 pltdune 923 Apr 1 09:42 000015_reco_data_2025-03-31T_142813Z_reco_data_2025-03-31T_150001Z_reco_data_2025-04-01T_083016Z.root.ext.json
-rw-r--r-- 1 pltdune04 pltdune 164 Apr 1 09:30 all-input-dids.txt
-rw-r--r-- 1 pltdune04 pltdune 143 Apr 1 09:42 justin-processed-pfns.txt
-rw-r--r-- 1 pltdune04 pltdune 0 Apr 1 09:30 debugprod.log 