Jobsub ID 223578.92@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
r  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.38 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.37 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.37 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.36 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.35 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.31 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Drifter : 0.24 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.21 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.2 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.2 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.19 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.18 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.18 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.18 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::DepoBagger : 0.17 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.17 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.16 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.15 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.15 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.15 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.13 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.13 sec
[14:45:58.796] I [ timer  ] Timer: wcls::FrameSaver : 0.13 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 0.13 sec
[14:45:58.796] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0.1 sec
[14:45:58.796] I [ timer  ] Timer: wcls::SimDepoSource : 0 sec
[14:45:58.796] I [ timer  ] Timer: Total node execution : 127.95999894291162 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 8499 subRun: 0 event: 28590 at 03-Oct-2025 14:46:12 BST
SimDepoSource got 79924 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 79924 depos spanning: [-2245.71, 8.6942e+09]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 15.03 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 14.75 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 13.31 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 12.88 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 12.47 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 12.42 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 9.83 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 9.71 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 9.57 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 7.71 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 6.45 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 6.27 sec
[14:48:50.442] I [ timer  ] Timer: wcls::SimChannelSink : 1.19 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.92 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.89 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.87 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.86 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.86 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.85 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.85 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.84 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.84 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.81 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.77 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.77 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.42 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.41 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.4 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.39 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.39 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.39 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.37 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.37 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.37 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.35 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.35 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.35 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.26 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.23 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Drifter : 0.22 sec
[14:48:50.442] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.22 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.21 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.2 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.18 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.17 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.16 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::DepoBagger : 0.16 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.15 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.15 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0.13 sec
[14:48:50.443] I [ timer  ] Timer: wcls::FrameSaver : 0.12 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.12 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.12 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.11 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.11 sec
[14:48:50.443] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 0.09 sec
[14:48:50.443] I [ timer  ] Timer: wcls::SimDepoSource : 0.06 sec
[14:48:50.443] I [ timer  ] Timer: Total node execution : 149.45000008121133 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
03-Oct-2025 14:49:05 BST  Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4_detsim.root"
03-Oct-2025 14:49:05 BST  Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4.root"
================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      149.76        160.725       175.528       158.003       8.48273        10     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.000790297   0.00193144    0.00275719    0.00199655    0.000681367      10     
simulate:tpcrawdecoder:WireCellToolkit          139.581       150.513       165.433       147.874       8.52319        10     
simulate:opdigi:OpDetDigitizerDUNE              1.84958       1.88173       1.91286       1.88392      0.0222114       10     
simulate:rns:RandomNumberSaver                3.4164e-05    5.37551e-05   0.000213761   3.5967e-05    5.33531e-05      10     
[art]:TriggerResults:TriggerResultInserter    1.3261e-05    1.69813e-05    4.085e-05    1.46815e-05   7.98878e-06      10     
end_path:out1:RootOutput                       4.255e-06    6.0007e-06    1.8854e-05    4.6345e-06    4.28802e-06      10     
end_path:out1:RootOutput(write)                 8.20101       8.32864       8.80381       8.26424      0.170162        10     
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
  Peak virtual memory usage (VmPeak)  : 4976.1 MB
  Peak resident set size usage (VmHWM): 3321.41 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859.root 22130603
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4.root 130652979
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4_detsim.root 2141595513
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4_detsim.root.ext.json 1575
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4_detsim.root.json 1841
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4_detsim.root.temp.json 1841
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20251003T130919Z_gen_002859_supernova_g4_detsim_hist.root 519