Jobsub ID 249756.8@dunegpschedd02.fnal.gov

Jobsub ID	249756.8@dunegpschedd02.fnal.gov
Workflow ID	10519
Stage ID	1
User name	smanthey@fnal.gov
Requested	Processors	1
	GPU	No
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-11-22 19:12:12
Site	US_FNAL-FermiGrid
Entry	FNAL_GPGrid_ce03_mcore_op_duneonly
Last heartbeat	2025-11-22 20:51:31
From worker node	Hostname	dunegli-7693773-0-fnpc22028.fnal.gov
	cpuinfo	AMD EPYC 7543 32-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	172800 (48 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Started	2025-11-22 20:28:36
Input files	fardet-hd:prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T184516Z_gen_003279_supernova_g4_halfActiveVol_detsim_reco_20250725T044411Z_reco2.root
Jobscript	Exit code	0
	Real time	2m (166s)
	CPU time	2m (158s = 95%)
	Max RSS bytes	859521024 (819 MiB)
Outputting started	2025-11-22 20:31:22
Output files	https://fndcadoor.fnal.gov:2880/dune/scratch/users/smanthey/ana/solar_ana_marley_flash_radiological_decay0_dune10kt_1x2x6_lateralAPA/fnal/10519/1/001/fdhd_ana_2025-11-22T_202842Z_hist.root
Finished	2025-11-22 20:51:31
Saved logs	justin-logs:249756.8-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

odule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-e ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[31mInvalid OpWaveform handle[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[34mOpFlash PE 460.94 with purity 0.99 time -17.91 plane 0
 - Vertex (-0.05, -427.92, 674.04)
	*** 1st Sanity check: Ratio 0.15 <= 1.00
	*** 2nd Sanity check: #OpHits 68 >= 68
OpFlash PE 29.26 with purity 0.51 time -17.70 plane 0
 - Vertex (0.02, 150.35, 604.71)
	*** 1st Sanity check: Ratio 0.19 <= 1.00
	*** 2nd Sanity check: #OpHits 12 >= 12
OpFlash PE 74.28 with purity 0.99 time -16.52 plane 0
 - Vertex (-0.05, -409.47, 696.40)
	*** 1st Sanity check: Ratio 0.19 <= 1.00
	*** 2nd Sanity check: #OpHits 24 >= 24
OpFlash PE 54.35 with purity 0.92 time -15.27 plane 0
 - Vertex (-0.05, -422.33, 708.18)
	*** 1st Sanity check: Ratio 0.23 <= 1.00
	*** 2nd Sanity check: #OpHits 12 >= 12
OpFlash PE 19.88 with purity 0.94 time -13.14 plane 0
 - Vertex (-0.05, -409.47, 739.26)
	*** 1st Sanity check: Ratio 0.23 <= 1.00
	*** 2nd Sanity check: #OpHits 7 >= 7
OpFlash PE 7.94 with purity 0.50 time -13.24 plane 0
 - Vertex (0.03, 183.75, 909.83)
	*** 1st Sanity check: Ratio 0.27 <= 1.00
	*** 2nd Sanity check: #OpHits 4 >= 4
OpFlash PE 3.86 with purity 1.00 time -11.64 plane 0
 - Vertex (-0.05, -248.76, 502.31)
	*** 1st Sanity check: Ratio 0.51 <= 1.00
	*** 2nd Sanity check: #OpHits 2 >= 2
OpFlash PE 5.88 with purity 1.00 time -10.51 plane 0
 - Vertex (-0.05, -423.08, 645.11)
	*** 1st Sanity check: Ratio 0.36 <= 1.00
	*** 2nd Sanity check: #OpHits 3 >= 3
OpFlash PE 58.79 with purity 0.33 time -5.76 plane 0
 - Vertex (-0.05, -155.68, 892.69)
	*** 1st Sanity check: Ratio 1.00 <= 1.00
	*** 2nd Sanity check: #OpHits 1 >= 1

# of OpHits (ophitspe): 40961 for times between -2256.53 us and 2227.44 us.
# of OpFlashes (solarflash): 3753 for times between -2256.28 us and 2227.44 us.
[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:15 UTC run: 5100 subRun: 0 event: 163950
[36m
# OpHits (ophitspe) in full geometry: 40961
# OpFlashes (solarflash) in full geometry: 3753
# Hits (hitfd) in each view: 149, 149, 278, 0
# Cluster from the hits: 89, 90, 254, 0
# Tracks (pmtracktc) in full geometry: 3[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:22 UTC run: 5100 subRun: 0 event: 163950
[32m
# ClusterReco: Looping over 144 matched clusters[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:22 UTC run: 5100 subRun: 0 event: 163950
[32m*** Matched preselection cluster: 20 from 144
 - MainTrackID 33212
 - Gen 1 -> marley TPC 9
 - Purity 1.00 Hits 6
 - Charge 1276.31 ( MaxHit 314.69 )
 - #AdjCl 2 ( 2 signal ):
    - Cluster 18 at distance 10.36 with time 349.75 and charge 80.84 in TPC 9 and hits 1
    - Cluster 22 at distance 2.82 with time 398.57 and charge 118.92 in TPC 9 and hits 1
 - RecoCol  Time,Y,Z ( 382.54, -416.81, 684.19 )
 - RecoInd0 Time,Y,Z ( 382.78, -416.78, 623.95 )
 - RecoInd1 Time,Y,Z ( 382.98, -416.85, 580.10 )
*** Matched flash: 1802 from 3753
 - Purity 98.76 % Plane 0 #Hits 68 PE 460.94 MainOpHitPE 70.89
 - Time -17.91 (us) Fast 57.17 % Residual 112.55
 - Reco Time,Y,Z ( -17.91, -427.92, 674.04 )
 - Found valid waveform: false
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( 64.64, -417.07, 683.21 )
 - Main X,Y,Z ( 66.06, -410.41, 692.64 )
 - EDep X,Y,Z ( 63.80, -416.01, 684.50 )
 - Reco X,Y,Z ( 64.30, -416.81, 684.19 )[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  22-Nov-2025 20:31:22 UTC run: 5100 subRun: 0 event: 163950
[32m-----------------------------------------------------------------------------------------
[0m
%MSG
22-Nov-2025 20:31:22 UTC  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/ff/c4/prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T184516Z_gen_003279_supernova_g4_halfActiveVol_detsim_reco_20250725T044411Z_reco2.root"
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@EndJob 22-Nov-2025 20:31:22 UTC  ModuleEndJob
[35mFinished running the SolarNuAna module[0m
%MSG

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                         0.118896       2.68232       9.25191      0.175728       3.71029        50     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.000973207   0.00720903     0.0328068    0.00186994    0.00752666       50     
end_path:solarnuana:SolarNuAna     0.117265       2.67493       9.23961      0.166617       3.70991        50     
====================================================================================================================
%MSG-i NuRandomService:  SolarNuAna:solarnuana@EndJob 22-Nov-2025 20:31:22 UTC  ModuleEndJob

Summary of seeds computed by the NuRandomService
Random policy: 'perEvent'
  algorithm version: EventTimestamp_v1

%MSG

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

  Peak virtual memory usage (VmPeak)  : 1491.58 MB
  Peak resident set size usage (VmHWM): 847.008 MB
====================================================================================================

TrigReport ---------- Event summary -------------
TrigReport Events total = 50 passed = 50 failed = 0

TrigReport ---------- Modules in End-path ----------
TrigReport        Run    Success      Error Name
TrigReport         50         50          0 solarnuana

TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 143.909519 Real = 149.440716

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 1491.58 VmHWM = 847.008

Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
lar exit code 0