Jobsub ID 33073.89@dunegpschedd01.fnal.gov

Jobsub ID	33073.89@dunegpschedd01.fnal.gov
Workflow ID	1860
Stage ID	1
User name	epennacc@fnal.gov
HTCondor Group	group_dune.prod.mcsim
Requested	Processors	1
	GPU	No
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-08-26 15:35:02
Site	FR_CCIN2P3
Entry	DUNE_FR_CCIN2P3_cccondorce04
Last heartbeat	2025-08-26 16:48:13
From worker node	Hostname	ccwcondor0053
	cpuinfo	AMD EPYC 9334 32-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	106200 (29 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Started	2025-08-26 16:12:52
Input files	monte-carlo-001860-002963
Jobscript	Exit code	0
	Real time	34m (2044s)
	CPU time	33m (2006s = 98%)
	Max RSS bytes	3285012480 (3132 MiB)
Outputting started	2025-08-26 16:46:57
Output files	fardet-hd:prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim.root
Finished	2025-08-26 16:48:13
Saved logs	justin-logs:33073.89-dunegpschedd01.fnal.gov.logs.tgz
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

8:44:21.054] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.45 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.44 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.44 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Drifter : 0.42 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.42 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.4 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.37 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.36 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.34 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.31 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.3 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.3 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.29 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.29 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 0.28 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.28 sec
[18:44:21.054] I [ timer  ] Timer: wcls::FrameSaver : 0.27 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.27 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.27 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0.26 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.25 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::DepoBagger : 0.24 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.23 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.22 sec
[18:44:21.054] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.16 sec
[18:44:21.054] I [ timer  ] Timer: wcls::SimDepoSource : 0.08 sec
[18:44:21.054] I [ timer  ] Timer: Total node execution : 103.8000008314848 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 1860 subRun: 0 event: 29630 at 26-Aug-2025 18:44:33 CEST
SimDepoSource got 84572 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
SimDepoSource: ready with 84572 depos spanning: [-2243.39, 2295.95]us
Retagger: tagging trace set: daq with 30720 traces, 0 summary
[18:46:42.999] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 10.23 sec
[18:46:42.999] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 9.72 sec
[18:46:42.999] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 9.56 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 8.96 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 8.53 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 8.37 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 7.97 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 7.95 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 6.69 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 6.25 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 4.54 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoTransform : 4.15 sec
[18:46:43.000] I [ timer  ] Timer: wcls::SimChannelSink : 1.21 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.56 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.55 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.54 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.53 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.53 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.53 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.52 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.51 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.51 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.51 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Drifter : 0.51 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.51 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.5 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.5 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.47 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.47 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.47 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.45 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.44 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.43 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.43 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.43 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.43 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Digitizer : 0.43 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::IncoherentAddNoise : 0.41 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.41 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.38 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.36 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.34 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.32 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.3 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.29 sec
[18:46:43.000] I [ timer  ] Timer: wcls::FrameSaver : 0.29 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoBagger : 0.29 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.29 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.28 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.28 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.27 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.27 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.25 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::Reframer : 0.25 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 0.24 sec
[18:46:43.000] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0.22 sec
[18:46:43.000] I [ timer  ] Timer: wcls::SimDepoSource : 0.07 sec
[18:46:43.000] I [ timer  ] Timer: Total node execution : 111.69999953359365 sec
wclsFrameSaver: saving raw::RawDigits tagged "daq"
26-Aug-2025 18:46:55 CEST  Closed output file "prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim.root"
26-Aug-2025 18:46:55 CEST  Closed input file "prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      123.636       137.865       154.087       136.686       8.81224        10     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00120524     0.0037565    0.00739039    0.00363945    0.00141854       10     
simulate:tpcrawdecoder:WireCellToolkit          114.962       128.921       145.492       127.888       8.88456        10     
simulate:opdigi:OpDetDigitizerDUNE              1.41736       1.5363        1.67411       1.55154      0.0910023       10     
simulate:rns:RandomNumberSaver                 3.314e-05    6.90879e-05   0.000319996   4.0837e-05    8.38103e-05      10     
[art]:TriggerResults:TriggerResultInserter    1.3821e-05    2.09218e-05    4.654e-05    1.8473e-05    8.70797e-06      10     
end_path:out1:RootOutput                       4.196e-06    8.1176e-06     2.587e-05     6.45e-06     5.96658e-06      10     
end_path:out1:RootOutput(write)                 6.73673       7.40356       8.51859       7.37469      0.504685        10     
================================================================================================================================

====================================================================================================
MemoryTracker summary (base-10 MB units used)

  Peak virtual memory usage (VmPeak)  : 4994.42 MB
  Peak resident set size usage (VmHWM): 3285.01 MB
====================================================================================================
Art has completed and will exit with status 0.
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963.root 22097486
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4.root 131721964
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim.root 2145420451
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim.root.ext.json 1575
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim.root.json 1841
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim.root.temp.json 1841
written output file: prodbackground_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250826T161257Z_gen_002963_supernova_g4_detsim_hist.root 519