Jobsub ID 208659.35@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
Gen::IncoherentAddNoise : 0.69 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.69 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.67 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.66 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.65 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.64 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.64 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.63 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.63 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.63 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.6 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.59 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.57 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.55 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.55 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.52 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.51 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.46 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.45 sec
[00:27:17.514] I [ timer ] Timer: wcls::FrameSaver : 0.44 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Reframer : 0.43 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.43 sec
[00:27:17.514] I [ timer ] Timer: WireCell::Gen::Retagger : 0.39 sec
[00:27:17.514] I [ timer ] Timer: wcls::SimDepoSource : 0.2 sec
[00:27:17.514] I [ timer ] Timer: Total node execution : 207.68999974429607 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 8191 subRun: 0 event: 96560 at 29-Sep-2025 00:27:30 CEST
SimDepoSource got 137869 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 137869 depos spanning: [-2245.55, 2244.55]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 20.92 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 19.33 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 15.32 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.82 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.77 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.58 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.54 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 13.4 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 12.25 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 11.63 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 10.23 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoTransform : 7.73 sec
[00:31:15.741] I [ timer ] Timer: wcls::SimChannelSink : 1.87 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.84 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.83 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.83 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.83 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.82 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.79 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.79 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Drifter : 0.78 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.77 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.77 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.75 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.74 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.73 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.72 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.72 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.69 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.69 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.68 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.68 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Digitizer : 0.67 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.66 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.65 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.62 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.62 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.6 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.59 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.58 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.57 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::IncoherentAddNoise : 0.57 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.57 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Retagger : 0.55 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.54 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.53 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.53 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.52 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.51 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 0.49 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::DepoBagger : 0.47 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.47 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::Reframer : 0.42 sec
[00:31:15.741] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.36 sec
[00:31:15.742] I [ timer ] Timer: wcls::FrameSaver : 0.32 sec
[00:31:15.742] I [ timer ] Timer: wcls::SimDepoSource : 0.12 sec
[00:31:15.742] I [ timer ] Timer: Total node execution : 194.95999892801046 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
29-Sep-2025 00:31:34 CEST Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4_detsim.root"
29-Sep-2025 00:31:34 CEST Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 230.312 240.833 252.214 242.01 6.52274 10
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000973021 0.00470305 0.00817566 0.0046379 0.0016927 10
simulate:tpcrawdecoder:WireCellToolkit 220.687 230.532 241.503 230.194 6.32463 10
simulate:opdigi:OpDetDigitizerDUNE 2.37234 2.55946 3.02415 2.43838 0.225364 10
simulate:rns:RandomNumberSaver 3.5336e-05 6.85472e-05 0.000281828 4.60105e-05 7.15461e-05 10
[art]:TriggerResults:TriggerResultInserter 1.4147e-05 2.05835e-05 4.5526e-05 1.55395e-05 9.22643e-06 10
end_path:out1:RootOutput 3.216e-06 7.1865e-06 2.4927e-05 5.2445e-06 6.0922e-06 10
end_path:out1:RootOutput(write) 6.83048 7.73661 10.2716 7.52591 0.943932 10
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5228.05 MB
Peak resident set size usage (VmHWM): 3509.56 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656.root 21985553
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4.root 180580054
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4_detsim.root 2354105091
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4_detsim.root.ext.json 1561
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4_detsim.root.json 1827
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4_detsim.root.temp.json 1827
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_centralAPA_20250928T214057Z_gen_009656_supernova_g4_detsim_hist.root 519