Jobsub ID 293869.5@justin-prod-sched01.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM detsim jobscript.
Input PFN = root://xgate.hec.lancs.ac.uk:1094//cephfs/grid/dune/usertests/8f/09/000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z.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 ===
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Reframer : 38.3802 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Reframer : 38.340225 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Reframer : 38.05019 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Reframer : 38.000195 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Reframer : 37.820213 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Reframer : 37.73023 sec
[23:52:57.869] I [ timer ] Timer: wcls::SimDepoSource : 21.820404 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::FrameFanin : 14.790243 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::DumpFrames : 14.550238 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::FrameFanin : 14.5002365 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::FrameFanin : 14.370234 sec
[23:52:57.869] I [ timer ] Timer: wcls::FrameSaver : 13.680218 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 13.33021 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::Retagger : 13.020203 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 12.8902 sec
[23:52:57.869] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 12.150183 sec
[23:52:57.869] I [ timer ] Timer: Total node execution : 123929.73597717285 sec
wclsFrameSaver: saving 880 traces tagged "gauss"
FrameSaver: q=18398.1 n=10286 tag=gauss
wclsFrameSaver: saving 869 traces tagged "wiener"
FrameSaver: q=28628.6 n=10434 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
19-Nov-2024 23:53:02 GMT Closed output file "000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z_reco_data_2024-11-18T_125448Z.root"
19-Nov-2024 23:53:02 GMT Closed input file "root://xgate.hec.lancs.ac.uk:1094//cephfs/grid/dune/usertests/8f/09/000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 96.4974 125.733 626.028 121.257 26.1379 1000
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000277687 0.00467342 0.322558 0.00059287 0.0219262 1000
simulate:tpcrawdecoder:WireCellToolkit 96.4647 125.565 619.594 121.22 25.8281 1000
simulate:opdigi:OpDetDigitizerDUNE 0.000205643 0.101897 5.06474 0.00100884 0.298972 1000
simulate:rns:RandomNumberSaver 2.224e-05 2.93152e-05 0.000198695 2.77915e-05 8.36327e-06 1000
[art]:TriggerResults:TriggerResultInserter 1.0609e-05 1.4329e-05 4.238e-05 1.3964e-05 2.13689e-06 1000
end_path:out1:RootOutput 4.229e-06 5.32389e-06 1.9246e-05 5.2095e-06 8.40813e-07 1000
end_path:out1:RootOutput(write) 0.0304294 0.0612473 1.36586 0.0378143 0.0884958 1000
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4572.29 MB
Peak resident set size usage (VmHWM): 2831.81 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 411820898
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 214505276
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 866659728
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z_reco_data_2024-11-18T_125448Z.root
extractor_prod.py exit code 0
pdjson2metadata exit code 0
Metadata extraction succeeds
===Metadata JSON===
{
"metadata": {
"DUNE.campaign": "justIN.w4131s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 1,
"art.last_event": 1000,
"art.process_name": "detsim",
"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": "detector-simulated",
"core.end_time": 1732060382.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": 1731934687.0
},
"parents": [
{
"did": "usertests:000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z.root"
}
]
}
===================
.:
total 2649736
-rw-r--r-- 1 pltdune002 pltdune 2636714735 Nov 19 23:53 000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z_reco_data_2024-11-18T_125448Z.root
-rw-r--r-- 1 pltdune002 pltdune 38604572 Nov 19 23:53 000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z_reco_2024-11-18T_125448Z.log
-rw-r--r-- 1 pltdune002 pltdune 37965870 Nov 19 23:53 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r-- 1 pltdune002 pltdune 6070 Nov 19 23:53 jobscript.log
-rw-r--r-- 1 pltdune002 pltdune 1115 Nov 19 23:53 000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z_reco_data_2024-11-18T_125448Z.root.json
-rw-r--r-- 1 pltdune002 pltdune 930 Nov 19 23:53 000243_reco_data_2024-11-14T_092757Z_reco_data_2024-11-14T_140458Z_reco_data_2024-11-18T_125448Z.root.ext.json
-rw-r--r-- 1 pltdune002 pltdune 164 Nov 18 12:54 all-input-dids.txt
-rw-r--r-- 1 pltdune002 pltdune 140 Nov 19 23:53 justin-processed-pfns.txt
-rw-r--r-- 1 pltdune002 pltdune 0 Nov 18 12:55 debugprod.log 