Jobsub ID 214599.0@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
53.475] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.71 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.7 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.69 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.68 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.67 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.65 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.63 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.62 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.58 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.57 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.57 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.57 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.54 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Retagger : 0.54 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.53 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.51 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::Reframer : 0.49 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.49 sec
[14:49:53.475] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.47 sec
[14:49:53.476] I [ timer ] Timer: WireCell::Gen::Reframer : 0.45 sec
[14:49:53.476] I [ timer ] Timer: WireCell::Gen::Reframer : 0.44 sec
[14:49:53.476] I [ timer ] Timer: wcls::FrameSaver : 0.42 sec
[14:49:53.476] I [ timer ] Timer: wcls::SimDepoSource : 0.16 sec
[14:49:53.476] I [ timer ] Timer: Total node execution : 230.31000038981438 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 8201 subRun: 0 event: 11000 at 29-Sep-2025 14:50:11 BST
SimDepoSource got 144983 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 144983 depos spanning: [-2245.8, 2245.85]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[14:54:19.678] I [ timer ] Timer: WireCell::Gen::DepoTransform : 19.48 sec
[14:54:19.678] I [ timer ] Timer: WireCell::Gen::DepoTransform : 19.36 sec
[14:54:19.678] I [ timer ] Timer: WireCell::Gen::DepoTransform : 18.97 sec
[14:54:19.678] I [ timer ] Timer: WireCell::Gen::DepoTransform : 18.44 sec
[14:54:19.678] I [ timer ] Timer: WireCell::Gen::DepoTransform : 17.23 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 16.9 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 16.73 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 14.21 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.92 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 10.28 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 9.84 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoTransform : 8.38 sec
[14:54:19.679] I [ timer ] Timer: wcls::SimChannelSink : 1.9 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.89 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.85 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.84 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.8 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.8 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.8 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.79 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.78 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.77 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.77 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.76 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.76 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.75 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.75 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.75 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.74 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.74 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Drifter : 0.73 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.73 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.7 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.7 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.7 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.69 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.67 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.65 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.64 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.58 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.58 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.56 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.55 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.54 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.53 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.53 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.53 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.53 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.52 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.51 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Retagger : 0.49 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::Reframer : 0.48 sec
[14:54:19.679] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.44 sec
[14:54:19.679] I [ timer ] Timer: wcls::FrameSaver : 0.42 sec
[14:54:19.679] I [ timer ] Timer: wcls::SimDepoSource : 0.21 sec
[14:54:19.679] I [ timer ] Timer: Total node execution : 214.3699985295534 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
29-Sep-2025 14:54:36 BST Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4_detsim.root"
29-Sep-2025 14:54:36 BST Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 249.491 275.806 326.66 272.397 22.7185 10
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00103476 0.00387441 0.0053291 0.00400511 0.00106733 10
simulate:tpcrawdecoder:WireCellToolkit 239.188 263.062 310.109 260.309 20.7969 10
simulate:opdigi:OpDetDigitizerDUNE 2.53133 3.0303 3.33005 3.13563 0.264892 10
simulate:rns:RandomNumberSaver 3.9264e-05 9.25549e-05 0.000360798 5.82395e-05 9.16026e-05 10
[art]:TriggerResults:TriggerResultInserter 1.3074e-05 2.29422e-05 6.1726e-05 1.7974e-05 1.3779e-05 10
end_path:out1:RootOutput 3.276e-06 8.518e-06 2.8374e-05 7.1735e-06 7.03876e-06 10
end_path:out1:RootOutput(write) 6.99976 9.70843 13.2181 9.71185 2.23124 10
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5183.7 MB
Peak resident set size usage (VmHWM): 3518.48 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100.root 21967517
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4.root 180882546
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4_detsim.root 2354041368
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4_detsim.root.ext.json 1561
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4_detsim.root.json 1827
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4_detsim.root.temp.json 1827
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T125110Z_gen_001100_supernova_g4_detsim_hist.root 519