Jobsub ID 214686.181@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
er ] Timer: WireCell::Gen::IncoherentAddNoise : 0.63 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.62 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.61 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.6 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.58 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.56 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.51 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Drifter : 0.49 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.45 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.43 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.43 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.42 sec
[19:32:28.195] I [ timer ] Timer: wcls::FrameSaver : 0.42 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.4 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.4 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Retagger : 0.4 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.38 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.38 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.38 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.38 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.37 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.37 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.34 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.31 sec
[19:32:28.195] I [ timer ] Timer: WireCell::Gen::Reframer : 0.31 sec
[19:32:28.195] I [ timer ] Timer: wcls::SimDepoSource : 0.12 sec
[19:32:28.195] I [ timer ] Timer: Total node execution : 153.85999988764524 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 8215 subRun: 0 event: 85740 at 29-Sep-2025 19:32:43 CEST
SimDepoSource got 82366 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 82366 depos spanning: [-2245.09, 2367.82]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 18.57 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 15.53 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 14.27 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.78 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 11.6 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 7.88 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 6.56 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 5.88 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 5.38 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 4.97 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 4.84 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoTransform : 4.59 sec
[19:35:26.348] I [ timer ] Timer: wcls::SimChannelSink : 1.42 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.78 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.74 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.73 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.72 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.72 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.71 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.71 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.71 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.7 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.7 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.69 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.69 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.68 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.68 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.67 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.65 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.65 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.64 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.62 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.61 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.59 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Drifter : 0.58 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.57 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.54 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.53 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.52 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.5 sec
[19:35:26.348] I [ timer ] Timer: wcls::FrameSaver : 0.49 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.48 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.48 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.48 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.47 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.46 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.44 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.44 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.43 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.42 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.41 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.36 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.36 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Retagger : 0.34 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.33 sec
[19:35:26.348] I [ timer ] Timer: WireCell::Gen::Reframer : 0.28 sec
[19:35:26.348] I [ timer ] Timer: wcls::SimDepoSource : 0.21 sec
[19:35:26.348] I [ timer ] Timer: Total node execution : 139.7800002247095 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
29-Sep-2025 19:35:38 CEST Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4_detsim.root"
29-Sep-2025 19:35:38 CEST Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 163.056 185.547 226.929 177.785 19.288 10
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00116907 0.00396543 0.00528989 0.00417003 0.00128881 10
simulate:tpcrawdecoder:WireCellToolkit 150.848 175.378 214.908 168.834 18.6264 10
simulate:opdigi:OpDetDigitizerDUNE 1.89543 2.14783 2.54494 1.94277 0.285826 10
simulate:rns:RandomNumberSaver 2.664e-05 5.04915e-05 0.000239939 2.9554e-05 6.31695e-05 10
[art]:TriggerResults:TriggerResultInserter 1.1387e-05 1.74914e-05 6.0591e-05 1.2614e-05 1.43801e-05 10
end_path:out1:RootOutput 4.006e-06 5.9769e-06 2.1062e-05 4.3265e-06 5.0308e-06 10
end_path:out1:RootOutput(write) 6.9999 8.01691 9.67587 7.06693 1.2068 10
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4967.78 MB
Peak resident set size usage (VmHWM): 3321.26 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574.root 22114546
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4.root 131232071
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4_detsim.root 2143719690
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4_detsim.root.ext.json 1575
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4_detsim.root.json 1841
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4_detsim.root.temp.json 1841
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250929T165351Z_gen_008574_supernova_g4_detsim_hist.root 519