Jobsub ID 401792.90@justin-prod-sched01.dune.hep.ac.uk

Jobsub ID	401792.90@justin-prod-sched01.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 05:45:21
Site	UK_Durham
Entry	DUNE_UK_SGridDurham_ce3
Last heartbeat	2025-05-22 08:13:38
From worker node	Hostname	n228.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 06:17:07
Input files	monte-carlo-007106-001201
Jobscript	Exit code	0
	Real time	1h (6921s)
	CPU time	1h (6827s = 98%)
	Max RSS bytes	13996793856 (13348 MiB)
Outputting started	2025-05-22 08:12:29
Output files	usertests:extracted_wires_r401792_s00090_20250522T061749Z_401792_90_1.h5
Finished	2025-05-22 08:13:38
Saved logs	justin-logs:401792.90-justin-prod-sched01.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 09:06:08 BST  Initiating request to open input file "placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5.root"
22-May-2025 09:06:09 BST  Opened input file "placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5.root"
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 89828 traces, 89828 summary
Retagger: tagging trace set: gauss with 76769 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 76769 traces tagged "gauss"
FrameSaver: q=2.15317e+06 n=1180131 tag=gauss
wclsFrameSaver: saving 89828 traces tagged "wiener"
FrameSaver: q=2.39334e+06 n=1093243 tag=wiener
22-May-2025 09:07:07 BST  Opened output file with pattern "placeholder_1_401792_90_1747894673_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 107417 traces, 107417 summary
Retagger: tagging trace set: gauss with 88946 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 88946 traces tagged "gauss"
FrameSaver: q=2.93099e+06 n=1424302 tag=gauss
wclsFrameSaver: saving 107417 traces tagged "wiener"
FrameSaver: q=3.22836e+06 n=1324135 tag=wiener
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 99113 traces, 99113 summary
Retagger: tagging trace set: gauss with 82387 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 82387 traces tagged "gauss"
FrameSaver: q=2.3145e+06 n=1276951 tag=gauss
wclsFrameSaver: saving 99113 traces tagged "wiener"
FrameSaver: q=2.59963e+06 n=1173306 tag=wiener
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 107719 traces, 107719 summary
Retagger: tagging trace set: gauss with 87419 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 87419 traces tagged "gauss"
FrameSaver: q=2.65948e+06 n=1348753 tag=gauss
wclsFrameSaver: saving 107719 traces tagged "wiener"
FrameSaver: q=2.95504e+06 n=1224222 tag=wiener
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 104243 traces, 104243 summary
Retagger: tagging trace set: gauss with 86677 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 86677 traces tagged "gauss"
FrameSaver: q=2.63806e+06 n=1394004 tag=gauss
wclsFrameSaver: saving 104243 traces tagged "wiener"
FrameSaver: q=2.90967e+06 n=1295862 tag=wiener
22-May-2025 09:11:19 BST  Closed output file "placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5_stage6.root"
22-May-2025 09:11:19 BST  Closed input file "placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      53.9304       55.7894       57.7482       55.2203       1.62089         5     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.000594682   0.000896617   0.00125707    0.000920231   0.000240773       5     
simulate:wclsnfspbadapa1:WireCellToolkit        53.541        55.3515       57.2933       54.7487       1.60066         5     
[art]:TriggerResults:TriggerResultInserter    3.0541e-05    4.25936e-05   7.9796e-05    3.3432e-05    1.8715e-05        5     
end_path:out1:RootOutput                       6.912e-06    9.7082e-06    2.0372e-05     7.023e-06    5.33346e-06       5     
end_path:out1:RootOutput(write)                0.385691      0.433522      0.468055      0.449433      0.029719         5     
================================================================================================================================

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

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

{'art_out': 'placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5_stage6.root'}
extract
Running extract
['lar', '-c', 'run_extract_wire.fcl', 'placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5_stage6.root', '-n', '-1', '-e', '401792:90:1']
Stdout:
Begin processing the 1st record. run: 401792 subRun: 90 event: 1 at 22-May-2025 09:11:45 BST
Begin processing the 2nd record. run: 401792 subRun: 90 event: 2 at 22-May-2025 09:11:53 BST
Begin processing the 3rd record. run: 401792 subRun: 90 event: 3 at 22-May-2025 09:12:02 BST
Begin processing the 4th record. run: 401792 subRun: 90 event: 4 at 22-May-2025 09:12:09 BST
Begin processing the 5th record. run: 401792 subRun: 90 event: 5 at 22-May-2025 09:12:18 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 09:11:45 BST  Initiating request to open input file "placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5_stage6.root"
22-May-2025 09:11:45 BST  Opened input file "placeholder_1_401792_90_1747894673_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 09:12:28 BST  Closed input file "placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5_stage6.root"

======================================================================================================================
TimeTracker printout (sec)              Min           Avg           Max         Median          RMS         nEvts   
======================================================================================================================
Full event                            3.11698       3.77939        4.759        3.75924      0.566613         5     
----------------------------------------------------------------------------------------------------------------------
source:RootInput(read)              0.000445344   0.000857555   0.00134743    0.000857285   0.000339105       5     
end_path:extractwire:ExtractWire      3.11634       3.77824       4.75744       3.75794      0.566336         5     
======================================================================================================================

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

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

{'art_out': 'placeholder_1_401792_90_1747894673_stage1_stage2_stage3_stage4_stage5_stage6_extract.root', 'start_time': 1747901480.8145132, 'end_time': 1747901548.9447494}
extracted_wires_r401792_s00090.h5