justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 40716.1@dunegpschedd02.fnal.gov

Jobsub ID40716.1@dunegpschedd02.fnal.gov
Workflow ID2625
Stage ID1
User namesmanthey@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes4194304000 (4000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-09-17 00:36:31
SiteUS_FNAL-FermiGrid
EntryFNAL_GPGrid_ce03_mcore_op_duneonly
Last heartbeat2025-09-17 08:08:52
From worker nodeHostnamedunegli-6878159-0-fnpc17131.fnal.gov
cpuinfoIntel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit172800 (48 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-09-17 00:37:29
Input filesfardet-vd:prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250127T054831Z_gen_000326_supernova_g4stage1_g4stage2_detsim_reco_20250702T074338Z_reco2.root
JobscriptExit code0
Real time7h (27074s)
CPU time7h (26936s = 99%)
Max RSS bytes1929162752 (1839 MiB)
Outputting started2025-09-17 08:08:44
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/smanthey/ana/solar_ana_marley_flash_dunevd10kt_1x8x14_3view_30deg/fnal/02625/1/001/fdhd_ana_2025-09-17T_003740Z_hist.root
Finished2025-09-17 08:08:52
Saved logsjustin-logs:40716.1-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

ergy		EndPosition		TrackID	MotherID
--------------------------------------------------------------------------------
marley	11		0.21	(78.29, -93.54, 1073.51)	132598	132574
marley	-12		0.36	(78.29, -93.54, 1073.51)	132597	132574
marley	22		1.27	(78.29, -93.54, 1073.51)	132576	0
marley	2212		5.40	(78.29, -93.54, 1073.51)	132575	0
marley	1000180390	0.36	(78.29, -93.54, 1073.51)	132574	0
marley	11		47.94	(78.29, -93.54, 1073.51)	132573	0
Signal Track IDs: 132573; 132574; 132575; 132576; 132597; 132598; 
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 07:35:09 UTC run: 4933 subRun: 0 event: 3260
OpFlash PE 24.42 with purity 0.48 time -0.02 plane 0
 - Vertex (-327.50, -359.31, 1757.71)
	*** 1st Sanity check: Ratio 0.30 <= 1.00
	*** 2nd Sanity check: #OpHits 4 >= 4
OpFlash PE 1492.85 with purity 0.41 time 0.02 plane 0
 - Vertex (-327.50, -94.12, 1072.40)
	*** 1st Sanity check: Ratio 0.05 <= 1.00
	*** 2nd Sanity check: #OpHits 62 >= 62
OpFlash PE 170.84 with purity 0.05 time 0.02 plane 1
 - Vertex (229.57, 743.30, 1884.53)
	*** 1st Sanity check: Ratio 0.20 <= 1.00
	*** 2nd Sanity check: #OpHits 12 >= 12
OpFlash PE 102.95 with purity 0.53 time 0.03 plane 0
 - Vertex (-327.50, -310.06, 1778.46)
	*** 1st Sanity check: Ratio 0.13 <= 1.00
	*** 2nd Sanity check: #OpHits 12 >= 12
OpFlash PE 161.12 with purity 0.09 time 0.03 plane 1
 - Vertex (143.33, 743.30, 1363.23)
	*** 1st Sanity check: Ratio 0.13 <= 1.00
	*** 2nd Sanity check: #OpHits 15 >= 15
OpFlash PE 219.12 with purity 0.23 time 0.03 plane 2
 - Vertex (184.11, -743.30, 1279.56)
	*** 1st Sanity check: Ratio 0.11 <= 1.00
	*** 2nd Sanity check: #OpHits 20 >= 20
OpFlash PE 246.77 with purity 0.33 time 0.04 plane 0
 - Vertex (-327.50, -308.93, 1194.51)
	*** 1st Sanity check: Ratio 0.18 <= 1.00
	*** 2nd Sanity check: #OpHits 16 >= 16
OpFlash PE 303.30 with purity 0.18 time 0.03 plane 0
 - Vertex (-327.50, -127.59, 648.67)
	*** 1st Sanity check: Ratio 0.11 <= 1.00
	*** 2nd Sanity check: #OpHits 21 >= 21
OpFlash PE 66.86 with purity 0.04 time 0.05 plane 0
 - Vertex (-327.50, 469.08, 355.53)
	*** 1st Sanity check: Ratio 0.26 <= 1.00
	*** 2nd Sanity check: #OpHits 7 >= 7
OpFlash PE 36.93 with purity 0.15 time 0.06 plane 2
 - Vertex (99.95, -743.30, 589.73)
	*** 1st Sanity check: Ratio 0.53 <= 1.00
	*** 2nd Sanity check: #OpHits 3 >= 3
OpFlash PE 27.69 with purity 0.42 time 0.09 plane 0
 - Vertex (-327.50, 85.14, 1615.16)
	*** 1st Sanity check: Ratio 0.32 <= 1.00
	*** 2nd Sanity check: #OpHits 4 >= 4
OpFlash PE 11.02 with purity 0.19 time 0.13 plane 0
 - Vertex (-327.50, -151.87, 313.40)
	*** 1st Sanity check: Ratio 0.57 <= 1.00
	*** 2nd Sanity check: #OpHits 2 >= 2
OpFlash PE 26.00 with purity 0.15 time 0.12 plane 0
 - Vertex (-327.50, -446.85, 1063.70)
	*** 1st Sanity check: Ratio 0.19 <= 1.00
	*** 2nd Sanity check: #OpHits 6 >= 6

# of OpFlashes (solarflash) in full geometry: 18681

%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 07:35:12 UTC run: 4933 subRun: 0 event: 3260

# OpHits (ophit10ppm) in full geometry: 51739
# OpFlashes (solarflash) in full geometry: 18681
# Hits (gaushit) in each view: 6793, 6654, 6664, 0
# Cluster from the hits: 2807, 2821, 5647, 0
# Tracks (pmtracktc) in full geometry: 478
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:08:42 UTC run: 4933 subRun: 0 event: 3260

# ClusterReco: Looping over 5628 matched clusters
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:08:42 UTC run: 4933 subRun: 0 event: 3260
*** Matched preselection cluster: 2024
 - MainTrackID 132573
 - Gen 1 -> marley TPC 59
 - Purity 1.00 Hits 11
 - Charge 2837.65 ( MaxHit 697.76 )
 - #AdjCl 15 ( 1 signal ):
    - Cluster 1413 at distance 99.89 with time 1129.55 and charge 25.88 in TPC 58 and hits 1
    - Cluster 1424 at distance 77.81 with time 1134.91 and charge 29.97 in TPC 57 and hits 1
    - Cluster 1540 at distance 65.89 with time 1228.12 and charge 38.35 in TPC 62 and hits 1
    - Cluster 1567 at distance 73.49 with time 1246.49 and charge 26.50 in TPC 60 and hits 1
    - Cluster 1577 at distance 55.50 with time 1250.61 and charge 101.44 in TPC 59 and hits 1
    - Cluster 1748 at distance 59.37 with time 1361.41 and charge 29.96 in TPC 59 and hits 1
    - Cluster 1835 at distance 50.81 with time 1415.36 and charge 46.34 in TPC 51 and hits 2
    - Cluster 1866 at distance 97.21 with time 1431.95 and charge 182.83 in TPC 51 and hits 2
    - Cluster 1870 at distance 60.28 with time 1432.69 and charge 163.03 in TPC 51 and hits 1
    - Cluster 1880 at distance 99.33 with time 1438.31 and charge 22.48 in TPC 49 and hits 1
    - Cluster 1926 at distance 96.23 with time 1478.44 and charge 118.72 in TPC 54 and hits 1
    - Cluster 2073 at distance 11.85 with time 1583.34 and charge 58.72 in TPC 59 and hits 1
    - Cluster 2146 at distance 25.80 with time 1634.85 and charge 26.66 in TPC 57 and hits 1
    - Cluster 2259 at distance 97.83 with time 1718.39 and charge 27.54 in TPC 59 and hits 1
    - Cluster 2401 at distance 58.97 with time 1822.95 and charge 34.91 in TPC 61 and hits 1
 - RecoCol  Time,Y,Z ( 1549.56, -96.17, 1074.20 )
 - RecoInd0 Time,Y,Z ( 1548.68, -96.09, 1120.40 )
 - RecoInd1 Time,Y,Z ( 1549.16, -96.25, 1120.40 )
*** Matched flash: 
 - Purity 0.41 Plane 0 #Hits 62 PE 1492.85 MaxPE 73.58
 - Time 0.02 Fast 0.53 Residual 231.30
 - Reco Time,Y,Z ( 0.02, -94.12, 1072.40 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( 78.29, -93.54, 1073.51 )
 - Main X,Y,Z ( 75.85, -98.07, 1077.12 )
 - EDep X,Y,Z ( 75.98, -97.62, 1075.81 )
 - Reco X,Y,Z ( 76.24, -96.17, 1074.20 )
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:08:42 UTC run: 4933 subRun: 0 event: 3260
*** Matched preselection cluster: 2622
 - MainTrackID 132573
 - Gen 1 -> marley TPC 58
 - Purity 1.00 Hits 8
 - Charge 2573.19 ( MaxHit 569.27 )
 - #AdjCl 10 ( 0 signal ):
    - Cluster 1980 at distance 80.87 with time 1518.10 and charge 30.47 in TPC 58 and hits 1
    - Cluster 2259 at distance 81.09 with time 1718.39 and charge 27.54 in TPC 59 and hits 1
    - Cluster 2351 at distance 80.95 with time 1786.11 and charge 50.37 in TPC 56 and hits 1
    - Cluster 2401 at distance 53.59 with time 1822.95 and charge 34.91 in TPC 61 and hits 1
    - Cluster 2542 at distance 89.64 with time 1914.76 and charge 97.17 in TPC 51 and hits 1
    - Cluster 2591 at distance 43.12 with time 1948.25 and charge 35.02 in TPC 53 and hits 1
    - Cluster 2640 at distance 14.91 with time 1985.94 and charge 33.20 in TPC 62 and hits 1
    - Cluster 2678 at distance 93.23 with time 2022.72 and charge 32.47 in TPC 52 and hits 1
    - Cluster 3029 at distance 77.12 with time 2287.84 and charge 43.46 in TPC 51 and hits 1
    - Cluster 3298 at distance 99.90 with time 2497.53 and charge 34.65 in TPC 63 and hits 1
 - RecoCol  Time,Y,Z ( 1974.05, -180.51, 1069.76 )
 - RecoInd0 Time,Y,Z ( 1973.68, -180.50, 1122.01 )
 - RecoInd1 Time,Y,Z ( 1973.30, -180.52, 1068.58 )
*** Matched flash: 
 - Purity 0.41 Plane 0 #Hits 62 PE 1492.85 MaxPE 73.58
 - Time 0.02 Fast 0.53 Residual 272.54
 - Reco Time,Y,Z ( 0.02, -94.12, 1072.40 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( 78.29, -93.54, 1073.51 )
 - Main X,Y,Z ( 75.85, -98.07, 1077.12 )
 - EDep X,Y,Z ( 75.98, -97.62, 1075.81 )
 - Reco X,Y,Z ( 8.08, -180.51, 1069.76 )
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:08:42 UTC run: 4933 subRun: 0 event: 3260
-----------------------------------------------------------------------------------------

%MSG
17-Sep-2025 08:08:43 UTC  Closed input file "root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/a1/66/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250127T054831Z_gen_000326_supernova_g4stage1_g4stage2_detsim_reco_20250702T074338Z_reco2.root"

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                          2135.25       2700.02       3023.6        2755.95       257.089        10     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.000830779    0.0114084     0.037007     0.00660085     0.0119276       10     
end_path:solarnuana:SolarNuAna      2135.25       2700.01       3023.59       2755.95       257.081        10     
====================================================================================================================
%MSG-i NuRandomService:  SolarNuAna:solarnuana@EndJob 17-Sep-2025 08:08:43 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)  : 2547.54 MB
  Peak resident set size usage (VmHWM): 1929.16 MB
====================================================================================================

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

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

TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 26913.485834 Real = 27046.923223

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 2547.54 VmHWM = 1929.16

Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
lar exit code 0
justIN time: 2025-09-18 23:52:46 UTC       justIN version: 01.05.00