Jobsub ID 208969.2@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
12:56:15.483] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.65 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.64 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Drifter : 0.64 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.63 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.61 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.6 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.6 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.59 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.59 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.56 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.52 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.52 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.49 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.49 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.48 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.48 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.43 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Reframer : 0.42 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.42 sec
[12:56:15.483] I [ timer ] Timer: WireCell::Gen::Retagger : 0.4 sec
[12:56:15.483] I [ timer ] Timer: wcls::FrameSaver : 0.32 sec
[12:56:15.483] I [ timer ] Timer: wcls::SimDepoSource : 0.13 sec
[12:56:15.483] I [ timer ] Timer: Total node execution : 215.03999826312065 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 8201 subRun: 0 event: 9910 at 29-Sep-2025 12:56:29 UTC
SimDepoSource got 139639 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 139639 depos spanning: [-2245.77, 2245.93]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[13:00:15.412] I [ timer ] Timer: WireCell::Gen::DepoTransform : 19.31 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 15.81 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 15.51 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 15.21 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 15 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 14.47 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.48 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 12.84 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 11.57 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 10.45 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 10.44 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoTransform : 9.63 sec
[13:00:15.413] I [ timer ] Timer: wcls::SimChannelSink : 2.47 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Drifter : 0.97 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.82 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.81 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.78 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.77 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.77 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.76 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.76 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.74 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.74 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.72 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.71 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.71 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.71 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.71 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.7 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.69 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.69 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.68 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.68 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.68 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.67 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.67 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.66 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.66 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.65 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.64 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.64 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.64 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.63 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.63 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.56 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.55 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.55 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Retagger : 0.54 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.52 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.51 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.48 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::Reframer : 0.47 sec
[13:00:15.413] I [ timer ] Timer: wcls::FrameSaver : 0.47 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.43 sec
[13:00:15.413] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.42 sec
[13:00:15.413] I [ timer ] Timer: wcls::SimDepoSource : 0.17 sec
[13:00:15.413] I [ timer ] Timer: Total node execution : 194.50999926030636 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
29-Sep-2025 13:00:31 UTC Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4_detsim.root"
29-Sep-2025 13:00:31 UTC Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 230.55 254.227 278.903 255.529 13.7968 10
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00106036 0.00344413 0.00473459 0.00368622 0.000985574 10
simulate:tpcrawdecoder:WireCellToolkit 219.791 242.852 267.027 243.11 13.3148 10
simulate:opdigi:OpDetDigitizerDUNE 2.68161 2.89294 3.25385 2.78783 0.216929 10
simulate:rns:RandomNumberSaver 9.3353e-05 0.000133599 0.000416021 0.000101077 9.44854e-05 10
[art]:TriggerResults:TriggerResultInserter 1.128e-05 2.03475e-05 6.3212e-05 1.3955e-05 1.49862e-05 10
end_path:out1:RootOutput 2.88e-06 7.2372e-06 2.6851e-05 5.8505e-06 6.69888e-06 10
end_path:out1:RootOutput(write) 7.43312 8.47805 10.3003 8.35722 0.882026 10
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5283.53 MB
Peak resident set size usage (VmHWM): 3479.58 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991.root 21948485
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4.root 180324726
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4_detsim.root 2351437206
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4_detsim.root.ext.json 1557
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4_detsim.root.json 1823
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4_detsim.root.temp.json 1823
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250929T120313Z_gen_000991_supernova_g4_detsim_hist.root 519