Jobsub ID 11266.0@dunegpschedd02.fnal.gov

Jobsub ID	11266.0@dunegpschedd02.fnal.gov
Workflow ID	59
Stage ID	1
User name	epennacc@fnal.gov
HTCondor Group	group_dune.prod.mcsim
Requested	Processors	1
	GPU	No
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-07-25 17:15:31
Site	BR_CBPF
Entry	DUNE_BR_CBPF_ce02
Last heartbeat	2025-07-25 22:36:54
From worker node	Hostname	wn31
	cpuinfo	Intel(R) Xeon(R) CPU E5-2660 0 @ 2.20GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	257400 (71 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Started	2025-07-25 17:15:53
Input files	fardet-hd:prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco.root
Jobscript	Exit code	0
	Real time	5h (19089s)
	CPU time	4h (15744s = 82%)
	Max RSS bytes	1728561152 (1648 MiB)
Outputting started	2025-07-25 22:34:04
Output files	fardet-hd:prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root
Finished	2025-07-25 22:36:54
Saved logs	justin-logs:11266.0-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

fied*
*Electron identified*
*Electron identified*
*Photon identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Photon identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
Printing U view candidate length: 24
Printing V view candidate length: 26
Printing W view candidate length: 20
Printing 2D view candidate length: 70
Printing background CaloHitList length: 1
Printing New CaloHitList Names: 
CaloHitListU | CaloHitListV | CaloHitListW | CaloHitList2D | BCaloHitList
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  25-Jul-2025 19:25:24 -03 run: 5100 subRun: 0 event: 223399
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  25-Jul-2025 19:25:24 -03 run: 5100 subRun: 0 event: 223399
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  25-Jul-2025 19:25:24 -03 run: 5100 subRun: 0 event: 223399
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  25-Jul-2025 19:25:24 -03 run: 5100 subRun: 0 event: 223399
Comparing two wires in the same plane: return failure
%MSG
33258
***Wiener Filter*******ApplyPostfilter***
Running Ophitfinder with InputDigiType = 'recob'
Found hits: 40909!
Genrating OpFlash 0 with 10 hits
Genrating OpFlash 1 with 3 hits
Genrating OpFlash 2 with 4 hits
Genrating OpFlash 3 with 3 hits
Genrating OpFlash 4 with 14 hits
Genrating OpFlash 5 with 3 hits
Genrating OpFlash 6 with 3 hits
Genrating OpFlash 7 with 4 hits
Genrating OpFlash 8 with 3 hits
Genrating OpFlash 9 with 5 hits
...
Generated 1211 OpFlashes
Begin processing the 50th record. run: 5100 subRun: 0 event: 223400 at 25-Jul-2025 19:27:52 -03
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
*Signal Pixel identified*
Printing U view candidate length: 4
Printing V view candidate length: 5
Printing W view candidate length: 4
Printing 2D view candidate length: 13
Printing background CaloHitList length: 440
Printing New CaloHitList Names: 
SCaloHitListU | SCaloHitListV | SCaloHitListW | SCaloHitList2D | BBCaloHitList
*Photon identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Electron identified*
*Photon identified*
*Photon identified*
*Photon identified*
*Electron identified*
*Electron identified*
*Electron identified*
Printing U view candidate length: 4
Printing V view candidate length: 5
Printing W view candidate length: 3
Printing 2D view candidate length: 12
Printing background CaloHitList length: 9
Printing New CaloHitList Names: 
CaloHitListU | CaloHitListV | CaloHitListW | CaloHitList2D | BCaloHitList
%MSG-e ShowerProducedPtrsHolder:   LArPandoraModularShowerCreation:pandoraShower@BeginModule  25-Jul-2025 19:30:34 -03 run: 5100 subRun: 0 event: 223400
Trying to add data product: InitialTrack. This element does not exist in the element holder
%MSG
33680
***Wiener Filter*******ApplyPostfilter***
Running Ophitfinder with InputDigiType = 'recob'
Found hits: 41060!
Genrating OpFlash 0 with 13 hits
Genrating OpFlash 1 with 3 hits
Genrating OpFlash 2 with 3 hits
Genrating OpFlash 3 with 6 hits
Genrating OpFlash 4 with 4 hits
Genrating OpFlash 5 with 6 hits
Genrating OpFlash 6 with 3 hits
Genrating OpFlash 7 with 3 hits
Genrating OpFlash 8 with 3 hits
Genrating OpFlash 9 with 7 hits
...
Generated 1197 OpFlashes
25-Jul-2025 19:33:54 -03  Closed output file "prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root"
25-Jul-2025 19:33:54 -03  Closed input file "root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/69/e8/prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              260.539       340.743       379.478       344.854       22.4031        50     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                 0.137968      0.186608      0.399022      0.151424      0.0669611       50     
reco:linecluster:LineCluster                            1.26925       1.48884       1.81019       1.50698      0.135683        50     
reco:trajcluster:TrajCluster                           0.416531      0.428399      0.462411      0.420387      0.0140775       50     
reco:pandora:StandardPandora                            111.088       152.192       170.709       155.954       13.8433        50     
reco:pandoraTrack:LArPandoraTrackCreation             0.000287856   0.00383761     0.011948     0.00405209    0.00208481       50     
reco:pandoraShower:LArPandoraModularShowerCreation    0.000474329   0.00214734     0.016535     0.00187833    0.00218721       50     
reco:pandoracalo:Calorimetry                          0.000172738   0.000713724   0.00495836    0.000648951   0.000641662      50     
reco:pandorapid:Chi2ParticleID                        7.9592e-05    0.000212916   0.00186886    0.000193309   0.000241331      50     
reco:pmtracktc:PMAlgTrackMaker                        0.00361925     0.0557913     0.260028      0.0360325     0.0613568       50     
reco:pmtracktccalo:Calorimetry                        0.000197298   0.000702752   0.00147338    0.000654401   0.000313282      50     
reco:pmtracktcpid:Chi2ParticleID                      8.4591e-05    0.000191722   0.000324748   0.000190261   6.18947e-05      50     
reco:emtrkmichelid:EmTrackMichelId                      5.12395        8.935        11.3533       9.13369       1.51742        50     
reco:opdec:Deconvolution                                26.8626       30.2238       32.867        30.3056       1.31318        50     
reco:ophitspe:OpHitFinderDeco                           105.921       141.258       163.232       142.846       11.076         50     
reco:opflash:OpFlashFinder                              4.43899       5.35882        6.46         5.37572      0.418013        50     
reco:solarflash:SolarOpFlash                           0.219367      0.438207      0.566802      0.483798       0.10679        50     
reco:rns:RandomNumberSaver                            5.5845e-05    9.1366e-05    0.000501803   8.3572e-05    6.09702e-05      50     
[art]:TriggerResults:TriggerResultInserter            2.7536e-05    4.62522e-05   9.9856e-05    4.5657e-05    1.20438e-05      50     
end_path:out1:RootOutput                               9.097e-06    1.44738e-05    5.096e-05     1.35e-05     6.52762e-06      50     
end_path:out1:RootOutput(write)                        0.112944      0.167589      0.223336      0.177648      0.0273035       50     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 3208.73 MB
  Peak resident set size usage (VmHWM): 1728.56 MB
====================================================================================================
Art has completed and will exit with status 0.
Moving on to metadata extractor 
============OUTPUT FILE:  prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root
Extracted metadata
checking file====== fardet-hd:prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco.root
MD conversion to MetaCat OK
all-input-dids.txt
debugprod.log
jobscript.log
justin-processed-pfns.txt
prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root
prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root.ext.json
prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root.json
prodmarley_nue_cc_flat_radiological_decay0_dune10kt_1x2x6_lateralAPA_20250209T194812Z_gen_004468_supernova_g4_halfActiveVol_detsim_reco_20250725T171558Z_reco2.root.temp.json
reco2_hist.root