Jobsub ID 207690.113@justin-prod-sched02.dune.hep.ac.uk

Jobsub ID	207690.113@justin-prod-sched02.dune.hep.ac.uk
Workflow ID	7106
Stage ID	1
User name	calcuttj@fnal.gov
HTCondor Group	group_dune
Requested	Processors	1
	GPU	No
	RSS bytes	8387559424 (7999 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-05-22 08:33:27
Site	UK_Durham
Entry	DUNE_UK_SGridDurham_ce3
Last heartbeat	2025-05-22 11:42:10
From worker node	Hostname	n200.dur.scotgrid.ac.uk
	cpuinfo	Intel(R) Xeon(R) Gold 5220 CPU @ 2.20GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	8387559424 (7999 MiB)
	Wall seconds limit	171000 (47 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Allocator name	justin-allocator-pro.dune.hep.ac.uk
Started	2025-05-22 09:41:56
Input files	monte-carlo-007106-006441
Jobscript	Exit code	0
	Real time	1h (7131s)
	CPU time	1h (7072s = 99%)
	Max RSS bytes	11322925056 (10798 MiB)
Outputting started	2025-05-22 11:40:48
Output files	usertests:extracted_wires_r207690_s00113_20250522T094158Z_207690_113_1.h5
Finished	2025-05-22 11:42:10
Saved logs	justin-logs:207690.113-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events Wrapper job log

Jobscript log (last 10,000 characters)

ctionsMap_
registering to  steppingActionsMap_
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_U (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_V (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_Y (Potential memory leak).
Inputer: "wclsRawFrameSource"
Outputer: "wclsFrameSaver:spsaver"
wclsFrameSaver: promising to produce recob::Wires named "gauss"
wclsFrameSaver: promising to produce recob::Wires named "wiener"
22-May-2025 12:32:50 BST  Initiating request to open input file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5.root"
22-May-2025 12:32:50 BST  Opened input file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5.root"
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 78052 traces, 78052 summary
Retagger: tagging trace set: gauss with 65486 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 65486 traces tagged "gauss"
FrameSaver: q=1.52128e+06 n=892795 tag=gauss
wclsFrameSaver: saving 78052 traces tagged "wiener"
FrameSaver: q=1.70875e+06 n=816061 tag=wiener
22-May-2025 12:34:06 BST  Opened output file with pattern "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root"
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 71185 traces, 71185 summary
Retagger: tagging trace set: gauss with 60944 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 60944 traces tagged "gauss"
FrameSaver: q=1.42211e+06 n=824482 tag=gauss
wclsFrameSaver: saving 71185 traces tagged "wiener"
FrameSaver: q=1.62628e+06 n=769996 tag=wiener
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 83206 traces, 83206 summary
Retagger: tagging trace set: gauss with 68249 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 68249 traces tagged "gauss"
FrameSaver: q=1.67299e+06 n=924615 tag=gauss
wclsFrameSaver: saving 83206 traces tagged "wiener"
FrameSaver: q=1.91595e+06 n=838443 tag=wiener
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 94059 traces, 94059 summary
Retagger: tagging trace set: gauss with 76546 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 76546 traces tagged "gauss"
FrameSaver: q=1.99941e+06 n=1078435 tag=gauss
wclsFrameSaver: saving 94059 traces tagged "wiener"
FrameSaver: q=2.2828e+06 n=983174 tag=wiener
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 92799 traces, 92799 summary
Retagger: tagging trace set: gauss with 76289 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 76289 traces tagged "gauss"
FrameSaver: q=2.04126e+06 n=1144065 tag=gauss
wclsFrameSaver: saving 92799 traces tagged "wiener"
FrameSaver: q=2.30194e+06 n=1052786 tag=wiener
22-May-2025 12:39:18 BST  Closed output file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root"
22-May-2025 12:39:18 BST  Closed input file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      68.4929       69.503        72.0561       69.0098       1.31273         5     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.000720488   0.000977038   0.00116149     0.0010738    0.000163388       5     
simulate:wclsnfspbadapa1:WireCellToolkit        67.9395       69.0033       71.4869       68.5361       1.28234         5     
[art]:TriggerResults:TriggerResultInserter    4.1219e-05    5.57572e-05   0.000111806   4.1745e-05    2.80274e-05       5     
end_path:out1:RootOutput                      1.0433e-05    1.52514e-05    3.173e-05     1.128e-05    8.2562e-06        5     
end_path:out1:RootOutput(write)                0.425489      0.495959      0.565206      0.470261      0.0528572        5     
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4855.82 MB
  Peak resident set size usage (VmHWM): 3225.55 MB
  Details saved in: 'mem.db'
====================================================================================================

{'art_out': 'placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root'}
extract
Running extract
['lar', '-c', 'run_extract_wire.fcl', 'placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root', '-n', '-1', '-e', '207690:113:1']
Stdout:
Begin processing the 1st record. run: 207690 subRun: 113 event: 1 at 22-May-2025 12:39:47 BST
Begin processing the 2nd record. run: 207690 subRun: 113 event: 2 at 22-May-2025 12:39:59 BST
Begin processing the 3rd record. run: 207690 subRun: 113 event: 3 at 22-May-2025 12:40:09 BST
Begin processing the 4th record. run: 207690 subRun: 113 event: 4 at 22-May-2025 12:40:22 BST
Begin processing the 5th record. run: 207690 subRun: 113 event: 5 at 22-May-2025 12:40:35 BST
Art has completed and will exit with status 0.

Stderr:
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_01_03d00/gdml/protodunehd_v6_refactored.gdml
Info in <TGeoManager::TGeoManager>: Geometry GDMLImport, Geometry imported from GDML created
Info in <TGeoManager::SetTopVolume>: Top volume is volWorld. Master volume is volWorld
Info in <TGeoNavigator::BuildCache>: --- Maximum geometry depth set to 100
Info in <TGeoManager::CheckGeometry>: Fixing runtime shapes...
Info in <TGeoManager::CheckGeometry>: ...Nothing to fix
Info in <TGeoManager::CloseGeometry>: Counting nodes...
Info in <TGeoManager::Voxelize>: Voxelizing...
Info in <TGeoManager::CloseGeometry>: Building cache...
Info in <TGeoManager::CountLevels>: max level = 5, max placements = 1148
Info in <TGeoManager::CloseGeometry>: 25608 nodes/ 5535 volume UID's in Geometry imported from GDML
Info in <TGeoManager::CloseGeometry>: ----------------modeler ready----------------
registering to  primaryGeneratorActionsMap_
registering to   eventActionsMap_
registering to   trackingActionsMap_
registering to  steppingActionsMap_
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_U (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_V (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_Y (Potential memory leak).
22-May-2025 12:39:46 BST  Initiating request to open input file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root"
22-May-2025 12:39:47 BST  Opened input file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root"
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
initializeNtuple() called with cflag_=6
22-May-2025 12:40:47 BST  Closed input file "placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6.root"

======================================================================================================================
TimeTracker printout (sec)              Min           Avg           Max         Median          RMS         nEvts   
======================================================================================================================
Full event                            3.62708       4.9364        5.98399       4.7003       0.855773         5     
----------------------------------------------------------------------------------------------------------------------
source:RootInput(read)               0.0006493    0.00111516    0.00187718    0.000934123   0.000468499       5     
end_path:extractwire:ExtractWire      3.62611       4.93486       5.98183       4.69861      0.855414         5     
======================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 3097.19 MB
  Peak resident set size usage (VmHWM): 2242.59 MB
  Details saved in: 'mem.db'
====================================================================================================

{'art_out': 'placeholder_1_207690_113_1747906924_stage1_stage2_stage3_stage4_stage5_stage6_extract.root', 'start_time': 1747913959.6396303, 'end_time': 1747914048.259614}
extracted_wires_r207690_s00113.h5