Jobsub ID 214206.67@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
Timer: WireCell::Gen::IncoherentAddNoise : 1.12 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.11 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.11 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.06 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.01 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.98 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.97 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.96 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.94 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.93 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.88 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.88 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.87 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.86 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::DepoBagger : 0.86 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 0.85 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.84 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0.81 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.79 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.76 sec
[21:06:06.375] I [ timer  ] Timer: wcls::FrameSaver : 0.74 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.64 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.58 sec
[21:06:06.375] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.55 sec
[21:06:06.375] I [ timer  ] Timer: wcls::SimDepoSource : 0.32 sec
[21:06:06.375] I [ timer  ] Timer: Total node execution : 322.8200029730797 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 8190 subRun: 0 event: 98300 at 28-Sep-2025 21:06:29 BST
SimDepoSource got 145316 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 145316 depos spanning: [-2245.51, 2245.81]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 30 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 24.95 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 24.77 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 24.6 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 24.45 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 24.38 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 22.13 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 21.37 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 21.18 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 21.11 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 19.3 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 18.76 sec
[21:12:58.979] I [ timer  ] Timer: wcls::SimChannelSink : 3.9 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.96 sec
[21:12:58.979] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.84 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.83 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.82 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.73 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.7 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.67 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.66 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.66 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.62 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.62 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Digitizer : 1.52 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Drifter : 1.48 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.23 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.21 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.21 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.21 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.19 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.14 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.1 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 1.08 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.08 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.07 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 1.06 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.03 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1.02 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 1 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 1 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.99 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::DepoBagger : 0.99 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.99 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.97 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.95 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.95 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.91 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.9 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.83 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0.81 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.8 sec
[21:12:58.980] I [ timer  ] Timer: wcls::FrameSaver : 0.77 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.77 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.74 sec
[21:12:58.980] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 0.72 sec
[21:12:58.980] I [ timer  ] Timer: wcls::SimDepoSource : 0.34 sec
[21:12:58.980] I [ timer  ] Timer: Total node execution : 333.07000240683556 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
28-Sep-2025 21:13:26 BST  Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4_detsim.root"
28-Sep-2025 21:13:26 BST  Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4.root"
================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      396.272       435.335       478.792       431.748       25.3745        10     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00129704    0.00586688    0.00813398    0.00597545    0.00179361       10     
simulate:tpcrawdecoder:WireCellToolkit          376.457       415.157       457.846       411.683       25.5424        10     
simulate:opdigi:OpDetDigitizerDUNE              4.19433       4.35641       4.50863       4.36587      0.0932854       10     
simulate:rns:RandomNumberSaver                2.8083e-05    6.04872e-05   0.000321978   3.21055e-05   8.71961e-05      10     
[art]:TriggerResults:TriggerResultInserter    1.4528e-05    2.02771e-05   6.0634e-05    1.5544e-05     1.349e-05       10     
end_path:out1:RootOutput                       3.476e-06    5.8642e-06    2.5058e-05    3.7065e-06    6.40168e-06      10     
end_path:out1:RootOutput(write)                 13.7689       15.8148       17.2749       15.6441       1.10615        10     
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
  Peak virtual memory usage (VmPeak)  : 5171.35 MB
  Peak resident set size usage (VmHWM): 3477.48 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830.root 21947566
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4.root 180728931
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4_detsim.root 2351753114
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4_detsim.root.ext.json 1561
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4_detsim.root.json 1827
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4_detsim.root.temp.json 1827
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T183710Z_gen_009830_supernova_g4_detsim_hist.root 519