Jobsub ID 46462.0@dunegpschedd01.fnal.gov

Jobsub ID	46462.0@dunegpschedd01.fnal.gov
Workflow ID	2589
Stage ID	1
User name	smanthey@fnal.gov
HTCondor Group	group_dune
Requested	Processors	1
	GPU	No
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-09-14 23:45:00
Site	US_FNAL-FermiGrid
Entry	FNAL_GPGrid_ce04_mcore_op_duneonly
Last heartbeat	2025-09-15 00:10:48
From worker node	Hostname	dunegli-7020405-0-fnpc9023.fnal.gov
	cpuinfo	Intel(R) Xeon(R) CPU E5-2680 v4 @ 2.40GHz
	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-09-14 23:45:35
Input files	fardet-hd:prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_centralAPA_20250207T181706Z_gen_003789_supernova_g4_detsim_reco_20250724T125812Z_reco2.root
Jobscript	Exit code	0
	Real time	25m (1502s)
	CPU time	24m (1470s = 97%)
	Max RSS bytes	924983296 (882 MiB)
Outputting started	2025-09-15 00:10:37
Output files	https://fndcadoor.fnal.gov:2880/dune/scratch/users/smanthey/ana/solar_ana_marley_flash_radiological_decay0_dune10kt_1x2x6_centralAPA/fnal/02589/1/001/fdhd_ana_2025-09-14T_234542Z_hist.root
Finished	2025-09-15 00:10:48
Saved logs	justin-logs:46462.0-dunegpschedd01.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

MaxPE 127.35
 - Time 0.01 Fast 0.76 Residual 4786.56
 - Reco Time,Y,Z ( 0.01, 297.59, 1034.63 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( -290.51, 372.01, 1023.31 )
 - Main X,Y,Z ( -285.13, 379.86, 1024.21 )
 - EDep X,Y,Z ( -279.00, 385.28, 1025.85 )
 - Reco X,Y,Z ( 243.58, 275.37, 1036.50 )[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:11 UTC run: 5092 subRun: 0 event: 189449
[32m*** Matched preselection cluster: 160
 - MainTrackID 32841
 - Gen 1 -> marley TPC 18
 - Purity 1.00 Hits 6
 - Charge 2063.29 ( MaxHit 651.09 )
 - #AdjCl 2 ( 1 signal ):
    - Cluster 133 at distance 86.52 with time 1220.66 and charge 41.09 in TPC 18 and hits 1
    - Cluster 175 at distance 46.22 with time 1721.97 and charge 982.61 in TPC 18 and hits 4
 - RecoCol  Time,Y,Z ( 1553.49, 421.56, 1032.45 )
 - RecoInd0 Time,Y,Z ( 1554.01, 421.51, 1044.88 )
 - RecoInd1 Time,Y,Z ( 1554.08, 421.60, 1044.83 )
*** Matched flash: 
 - Purity 0.74 Plane 1 #Hits 206 PE 1359.12 MaxPE 127.35
 - Time 0.01 Fast 0.76 Residual 8967.01
 - Reco Time,Y,Z ( 0.01, 297.59, 1034.63 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( -290.51, 372.01, 1023.31 )
 - Main X,Y,Z ( -285.13, 379.86, 1024.21 )
 - EDep X,Y,Z ( -279.00, 385.28, 1025.85 )
 - Reco X,Y,Z ( 249.43, 421.56, 1032.45 )[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:11 UTC run: 5092 subRun: 0 event: 189449
[32m-----------------------------------------------------------------------------------------
[0m
%MSG
Begin processing the 50th record. run: 5092 subRun: 0 event: 189450 at 15-Sep-2025 00:10:11 UTC
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:12 UTC run: 5092 subRun: 0 event: 189450
[35m
#########################################
Event: 189450 Flag: 1754897241
Geometry: dune10kt_v6_1x2x6 (HD)
PDS Frequency in [MHz]: 62.50
PDS Tick in [us]: 0.016
TPC Frequency in [MHz]: 2.00
TPC Tick in [us]: 0.50
TPC Map: 25 TPCs found
TPC DriftLength in [cm]: 360.37
TPC DriftTime in [us]: 2244.44
Fiducial Volume in [cm]: 759.89, 1357.70, 1746.48
#########################################[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:12 UTC run: 5092 subRun: 0 event: 189450
[91m
There are a total of 32721 Particles in the event

# of particles 11	from gen 1 marley
# of particles 10853	from gen 2 Ar39GenInLAr
# of particles 1176	from gen 3 Kr85GenInLAr
# of particles 2	from gen 4 Ar42GenInLAr
# of particles 0	from gen 5 K42From42ArGenInLAr
# of particles 16	from gen 6 Rn222ChainRn222GenInLAr
# of particles 9	from gen 7 Rn222ChainPo218GenInLAr
# of particles 27	from gen 8 Rn222ChainPb214GenInLAr
# of particles 18	from gen 9 Rn222ChainBi214GenInLAr
# of particles 7	from gen 10 Rn222ChainPb210GenInLAr
# of particles 10	from gen 11 Rn220ChainPb212GenInLAr
# of particles 15	from gen 12 K40GenInCPA
# of particles 218	from gen 13 U238ChainGenInCPA
# of particles 216	from gen 14 Th232ChainGenInCPA
# of particles 6	from gen 15 K42From42ArGenInCPA
# of particles 3	from gen 16 Rn222ChainPo218GenInCPA
# of particles 24	from gen 17 Rn222ChainPb214GenInCPA
# of particles 40	from gen 18 Rn222ChainBi214GenInCPA
# of particles 22	from gen 19 Rn222ChainPb210GenInCPA
# of particles 23	from gen 20 Rn222ChainFromBi210GenInCPA
# of particles 49	from gen 21 Rn220ChainFromPb212GenInCPA
# of particles 0	from gen 22 Co60GenInAPA
# of particles 6	from gen 23 U238ChainGenInAPA
# of particles 2	from gen 24 Th232ChainGenInAPA
# of particles 42	from gen 25 U238Th232K40GenInLArAPAboards1x2x6
# of particles 3	from gen 26 Rn222ChainGenInPDS
# of particles 19159	from gen 27 CavernwallGammasAtLAr1x2x6lowBgAPA
# of particles 764	from gen 28 foamGammasAtLAr1x2x6lowBgAPA
# of particles 0	from gen 29 CavernwallNeutronsAtLAr1x2x6lowBgAPA
# of particles 0	from gen 30 CryostatNGammasAtLAr1x2x6lowBgAPA
# of particles 0	from gen 31 CavernNGammasAtLAr1x2x6lowBgAPA[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:12 UTC run: 5092 subRun: 0 event: 189450
[33m
Number of Signal Particles: 7
Neutrino Interaction: -1
Neutrino Energy: 11.22 MeV
Position (290.39, 148.43, 1334.46) cm
Gen.	PdgCode		Energy		EndPosition		TrackID	MotherID
--------------------------------------------------------------------------------
marley	22		1.46	(290.39, 148.43, 1334.46)	32769	32712
marley	1000180400	0.00	(290.39, 148.43, 1334.46)	32768	32712
marley	12		0.04	(290.39, 148.43, 1334.46)	32767	32712
marley	22		0.00	(290.39, 148.43, 1334.46)	32765	32712
marley	11		0.00	(290.39, 148.43, 1334.46)	32764	32712
marley	11		0.00	(290.39, 148.43, 1334.46)	32763	32712
marley	22		0.03	(290.39, 148.43, 1334.46)	32715	0
marley	22		0.77	(290.39, 148.43, 1334.46)	32714	0
marley	22		3.04	(290.39, 148.43, 1334.46)	32713	0
marley	1000190400	0.00	(290.39, 148.43, 1334.46)	32712	0
marley	11		5.87	(290.39, 148.43, 1334.46)	32711	0
Signal Track IDs: 32711; 32712; 32713; 32714; 32715; 32763; 32764; 32765; 32767; 32768; 32769; [0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:19 UTC run: 5092 subRun: 0 event: 189450
[34mOpFlash PE 14.06 with purity 0.25 time 0.04 plane 1
 - Vertex (0.05, 363.83, 610.97)
	*** 1st Sanity check: Ratio 0.19 <= 1.00
	*** 2nd Sanity check: #OpHits 7 >= 7
OpFlash PE 11.36 with purity 0.16 time 0.08 plane 0
 - Vertex (-0.05, -145.15, 944.58)
	*** 1st Sanity check: Ratio 0.18 <= 1.00
	*** 2nd Sanity check: #OpHits 6 >= 6
OpFlash PE 29.46 with purity 0.63 time 0.08 plane 1
 - Vertex (0.05, 267.43, 1308.67)
	*** 1st Sanity check: Ratio 0.44 <= 1.00
	*** 2nd Sanity check: #OpHits 7 >= 7

# of OpFlashes (solarflash) in full geometry: 1545
[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:19 UTC run: 5092 subRun: 0 event: 189450
[36m
# OpHits (ophitspe) in full geometry: 70986
# OpFlashes (solarflash) in full geometry: 1545
# Hits (hitfd) in each view: 173, 174, 439, 0
# Cluster from the hits: 113, 110, 410, 0
# Tracks (pmtracktc) in full geometry: 1[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:36 UTC run: 5092 subRun: 0 event: 189450
[32m
# ClusterReco: Looping over 231 matched clusters[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:36 UTC run: 5092 subRun: 0 event: 189450
[32m*** Matched preselection cluster: 144
 - MainTrackID 32711
 - Gen 1 -> marley TPC 23
 - Purity 1.00 Hits 3
 - Charge 710.47 ( MaxHit 458.22 )
 - #AdjCl 4 ( 2 signal ):
    - Cluster 149 at distance 20.91 with time 1892.69 and charge 95.86 in TPC 23 and hits 1
    - Cluster 164 at distance 37.15 with time 1987.20 and charge 108.45 in TPC 23 and hits 1
    - Cluster 183 at distance 70.47 with time 2186.62 and charge 55.63 in TPC 21 and hits 1
    - Cluster 185 at distance 95.70 with time 2192.20 and charge 48.04 in TPC 23 and hits 1
 - RecoCol  Time,Y,Z ( 1793.14, 149.18, 1334.03 )
 - RecoInd0 Time,Y,Z ( 1792.94, 149.12, 1310.07 )
 - RecoInd1 Time,Y,Z ( 1793.08, 149.23, 1277.27 )
*** Matched flash: 
 - Purity 0.00 Plane 1 #Hits 21 PE 191.29 MaxPE 93.20
 - Time -7.11 Fast 0.83 Residual 5873.05
 - Reco Time,Y,Z ( -7.11, 259.42, 1357.47 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( 290.39, 148.43, 1334.46 )
 - Main X,Y,Z ( 290.87, 149.56, 1333.86 )
 - EDep X,Y,Z ( 290.87, 149.36, 1333.98 )
 - Reco X,Y,Z ( 289.05, 149.18, 1334.03 )[0m
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  15-Sep-2025 00:10:36 UTC run: 5092 subRun: 0 event: 189450
[32m-----------------------------------------------------------------------------------------
[0m
%MSG
15-Sep-2025 00:10:36 UTC  Closed input file "root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/3c/eb/prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_centralAPA_20250207T181706Z_gen_003789_supernova_g4_detsim_reco_20250724T125812Z_reco2.root"

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                          24.3097       28.4978       33.4918       28.6027       1.82436        50     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.00132549    0.00823829     0.0545329    0.00189462     0.0120202       50     
end_path:solarnuana:SolarNuAna      24.3074       28.4894       33.4898       28.6011       1.82399        50     
====================================================================================================================
%MSG-i NuRandomService:  SolarNuAna:solarnuana@EndJob 15-Sep-2025 00:10:36 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)  : 1553.62 MB
  Peak resident set size usage (VmHWM): 908.997 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 = 1445.386096 Real = 1473.155938

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 1553.62 VmHWM = 908.997

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