justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 47313.0@dunegpschedd01.fnal.gov

Jobsub ID47313.0@dunegpschedd01.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 03:36:43
SiteUS_FNAL-FermiGrid
EntryFNAL_GPGrid_ce03_mcore_op_duneonly
Last heartbeat2025-09-17 08:04:56
From worker nodeHostnamedunegli-6879605-0-fnpc23102.fnal.gov
cpuinfoAMD EPYC 9354 32-Core Processor
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 03:38:01
Input filesfardet-vd:prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250128T091433Z_gen_003397_supernova_g4stage1_g4stage2_detsim_reco_20250702T160402Z_reco2.root
JobscriptExit code0
Real time4h (16008s)
CPU time4h (15883s = 99%)
Max RSS bytes1921626112 (1832 MiB)
Outputting started2025-09-17 08:04:49
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_033810Z_hist.root
Finished2025-09-17 08:04:56
Saved logsjustin-logs:47313.0-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

28, 560.16)	132730	0
Signal Track IDs: 132730; 132731; 132732; 132733; 132734; 132735; 132794; 132795; 132796; 
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 07:42:58 UTC run: 4963 subRun: 0 event: 33970
OpFlash PE 44.85 with purity 0.09 time -0.00 plane 0
 - Vertex (-327.50, 291.20, 1283.68)
	*** 1st Sanity check: Ratio 0.26 <= 1.00
	*** 2nd Sanity check: #OpHits 6 >= 6
OpFlash PE 1625.42 with purity 0.22 time 0.02 plane 0
 - Vertex (-327.50, 478.70, 592.18)
	*** 1st Sanity check: Ratio 0.09 <= 1.00
	*** 2nd Sanity check: #OpHits 33 >= 33
OpFlash PE 229.22 with purity 0.37 time 0.02 plane 1
 - Vertex (113.59, 743.30, 764.75)
	*** 1st Sanity check: Ratio 0.15 <= 1.00
	*** 2nd Sanity check: #OpHits 16 >= 16
OpFlash PE 150.68 with purity 0.27 time 0.02 plane 1
 - Vertex (96.85, 743.30, 1238.02)
	*** 1st Sanity check: Ratio 0.11 <= 1.00
	*** 2nd Sanity check: #OpHits 13 >= 13
OpFlash PE 35.87 with purity 0.17 time 0.02 plane 0
 - Vertex (-327.50, -25.02, 1248.01)
	*** 1st Sanity check: Ratio 0.24 <= 1.00
	*** 2nd Sanity check: #OpHits 5 >= 5
OpFlash PE 5.35 with purity 0.25 time 0.03 plane 0
 - Vertex (-327.50, 202.70, 2044.46)
	*** 1st Sanity check: Ratio 1.00 <= 1.00
	*** 2nd Sanity check: #OpHits 1 >= 1
OpFlash PE 107.06 with purity 0.25 time 0.04 plane 0
 - Vertex (-327.50, -47.58, 804.78)
	*** 1st Sanity check: Ratio 0.16 <= 1.00
	*** 2nd Sanity check: #OpHits 13 >= 13
OpFlash PE 39.75 with purity 1.00 time 0.05 plane 1
 - Vertex (210.07, 743.30, 450.26)
	*** 1st Sanity check: Ratio 0.50 <= 1.00
	*** 2nd Sanity check: #OpHits 2 >= 2
OpFlash PE 210.23 with purity 0.07 time 0.09 plane 2
 - Vertex (130.04, -743.30, 152.42)
	*** 1st Sanity check: Ratio 0.20 <= 1.00
	*** 2nd Sanity check: #OpHits 13 >= 13
OpFlash PE 10.75 with purity 0.46 time 0.08 plane 0
 - Vertex (-327.50, 373.40, 646.32)
	*** 1st Sanity check: Ratio 0.54 <= 1.00
	*** 2nd Sanity check: #OpHits 2 >= 2
OpFlash PE 254.13 with purity 0.51 time 0.11 plane 1
 - Vertex (285.07, 743.30, 450.26)
	*** 1st Sanity check: Ratio 0.48 <= 1.00
	*** 2nd Sanity check: #OpHits 3 >= 3

# of OpFlashes (solarflash) in full geometry: 18824

%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 07:43:00 UTC run: 4963 subRun: 0 event: 33970

# OpHits (ophit10ppm) in full geometry: 52190
# OpFlashes (solarflash) in full geometry: 18824
# Hits (gaushit) in each view: 6744, 6684, 6798, 0
# Cluster from the hits: 2809, 2857, 5798, 0
# Tracks (pmtracktc) in full geometry: 457
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:04:48 UTC run: 4963 subRun: 0 event: 33970

# ClusterReco: Looping over 5790 matched clusters
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:04:48 UTC run: 4963 subRun: 0 event: 33970
*** Matched preselection cluster: 3183
 - MainTrackID 132730
 - Gen 1 -> marley TPC 38
 - Purity 1.00 Hits 4
 - Charge 604.11 ( MaxHit 253.36 )
 - #AdjCl 18 ( 1 signal ):
    - Cluster 2667 at distance 90.22 with time 1973.81 and charge 88.40 in TPC 38 and hits 1
    - Cluster 2759 at distance 59.82 with time 2045.33 and charge 41.50 in TPC 38 and hits 1
    - Cluster 2814 at distance 82.82 with time 2089.45 and charge 168.24 in TPC 38 and hits 2
    - Cluster 2917 at distance 50.33 with time 2171.25 and charge 31.35 in TPC 34 and hits 1
    - Cluster 2918 at distance 78.51 with time 2172.75 and charge 43.58 in TPC 38 and hits 1
    - Cluster 2954 at distance 96.00 with time 2199.29 and charge 31.61 in TPC 24 and hits 1
    - Cluster 2955 at distance 84.88 with time 2199.84 and charge 34.70 in TPC 35 and hits 1
    - Cluster 2962 at distance 47.11 with time 2205.91 and charge 32.77 in TPC 39 and hits 1
    - Cluster 3102 at distance 50.07 with time 2296.85 and charge 198.96 in TPC 38 and hits 2
    - Cluster 3127 at distance 48.86 with time 2313.67 and charge 87.34 in TPC 38 and hits 1
    - Cluster 3207 at distance 80.05 with time 2368.21 and charge 36.11 in TPC 37 and hits 1
    - Cluster 3221 at distance 45.37 with time 2381.73 and charge 41.50 in TPC 35 and hits 1
    - Cluster 3299 at distance 23.27 with time 2437.86 and charge 198.16 in TPC 38 and hits 2
    - Cluster 3350 at distance 88.98 with time 2472.86 and charge 45.49 in TPC 36 and hits 1
    - Cluster 3704 at distance 68.38 with time 2743.41 and charge 28.64 in TPC 33 and hits 1
    - Cluster 3711 at distance 81.29 with time 2749.20 and charge 39.31 in TPC 35 and hits 1
    - Cluster 3765 at distance 72.60 with time 2783.03 and charge 26.33 in TPC 33 and hits 1
    - Cluster 3916 at distance 99.17 with time 2896.27 and charge 45.86 in TPC 39 and hits 1
 - RecoCol  Time,Y,Z ( 2355.67, 438.48, 656.38 )
 - RecoInd0 Time,Y,Z ( 2355.34, 438.48, 672.64 )
 - RecoInd1 Time,Y,Z ( 2355.46, 438.49, 672.64 )
*** Matched flash: 
 - Purity 0.22 Plane 0 #Hits 33 PE 1625.42 MaxPE 152.80
 - Time 0.02 Fast 0.42 Residual 1773.59
 - Reco Time,Y,Z ( 0.02, 478.70, 592.18 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( -153.01, 488.28, 560.16 )
 - Main X,Y,Z ( -148.44, 485.76, 566.92 )
 - EDep X,Y,Z ( -148.11, 485.43, 566.51 )
 - Reco X,Y,Z ( -53.19, 438.48, 656.38 )
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:04:48 UTC run: 4963 subRun: 0 event: 33970
*** Matched preselection cluster: 4003
 - MainTrackID 132730
 - Gen 1 -> marley TPC 30
 - Purity 1.00 Hits 16
 - Charge 2482.84 ( MaxHit 268.48 )
 - #AdjCl 15 ( 1 signal ):
    - Cluster 3414 at distance 97.55 with time 2522.73 and charge 26.11 in TPC 28 and hits 1
    - Cluster 3554 at distance 67.66 with time 2639.02 and charge 26.37 in TPC 25 and hits 1
    - Cluster 3802 at distance 64.15 with time 2815.71 and charge 39.50 in TPC 29 and hits 1
    - Cluster 3847 at distance 85.16 with time 2851.14 and charge 27.79 in TPC 31 and hits 1
    - Cluster 3932 at distance 48.98 with time 2908.36 and charge 31.20 in TPC 29 and hits 1
    - Cluster 4048 at distance 85.40 with time 2984.47 and charge 77.73 in TPC 30 and hits 2
    - Cluster 4162 at distance 84.82 with time 3066.58 and charge 233.78 in TPC 30 and hits 2
    - Cluster 4258 at distance 92.70 with time 3133.74 and charge 34.14 in TPC 37 and hits 1
    - Cluster 4426 at distance 73.81 with time 3250.97 and charge 30.50 in TPC 24 and hits 1
    - Cluster 4428 at distance 69.24 with time 3252.37 and charge 30.39 in TPC 26 and hits 1
    - Cluster 4515 at distance 67.73 with time 3321.65 and charge 28.23 in TPC 29 and hits 1
    - Cluster 4518 at distance 97.08 with time 3325.41 and charge 29.06 in TPC 35 and hits 1
    - Cluster 4554 at distance 87.19 with time 3354.02 and charge 46.12 in TPC 26 and hits 2
    - Cluster 4620 at distance 78.60 with time 3409.06 and charge 65.54 in TPC 30 and hits 1
    - Cluster 4638 at distance 97.39 with time 3423.64 and charge 101.90 in TPC 30 and hits 1
 - RecoCol  Time,Y,Z ( 2958.27, 486.44, 563.96 )
 - RecoInd0 Time,Y,Z ( 2958.37, 486.40, 564.75 )
 - RecoInd1 Time,Y,Z ( 2958.13, 486.47, 521.47 )
*** Matched flash: 
 - Purity 0.22 Plane 0 #Hits 33 PE 1625.42 MaxPE 152.80
 - Time 0.02 Fast 0.42 Residual 292.94
 - Reco Time,Y,Z ( 0.02, 478.70, 592.18 )
*** Reconstructed Interaction Vertex: 
 - True X,Y,Z ( -153.01, 488.28, 560.16 )
 - Main X,Y,Z ( -148.44, 485.76, 566.92 )
 - EDep X,Y,Z ( -148.11, 485.43, 566.51 )
 - Reco X,Y,Z ( -149.95, 486.44, 563.96 )
%MSG
%MSG-i ProducerUtils:  SolarNuAna:solarnuana@BeginModule  17-Sep-2025 08:04:48 UTC run: 4963 subRun: 0 event: 33970
-----------------------------------------------------------------------------------------

%MSG
17-Sep-2025 08:04:48 UTC  Closed input file "root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-vd/c0/96/prodmarley_nue_cc_flat_radiological_decay0_dunevd10kt_1x8x14_3view_30deg_20250128T091433Z_gen_003397_supernova_g4stage1_g4stage2_detsim_reco_20250702T160402Z_reco2.root"

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                          1336.61       1597.18       1858.39       1624.22       167.427        10     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.000536907   0.00772969     0.0220094    0.00480365    0.00784572       10     
end_path:solarnuana:SolarNuAna      1336.61       1597.17       1858.38       1624.21       167.426        10     
====================================================================================================================
%MSG-i NuRandomService:  SolarNuAna:solarnuana@EndJob 17-Sep-2025 08:04:48 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)  : 2542.4 MB
  Peak resident set size usage (VmHWM): 1921.63 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 = 15873.745015 Real = 15992.290354

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 2542.4 VmHWM = 1921.63

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-19 08:47:46 UTC       justIN version: 01.05.00