Jobsub ID 272051.1@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
PEG_TURBO_INC=/cvmfs/larsoft.opensciencegrid.org/products/libjpeg_turbo/v2_1_0/Linux64bit+3.10-2.17/include
LAREVENTDISPLAY_LIB=/cvmfs/larsoft.opensciencegrid.org/products/lareventdisplay/v10_00_28/slf7.x86_64.e26.prof/lib
PPFX_VERSION=v02_20_06
NUG4_VERSION=v1_16_10
SETUP_LARDATAALG=lardataalg v10_01_04 -f Linux64bit+3.10-2.17 -z /cvmfs/larsoft.opensciencegrid.org/products -q e26:prof
LARCOREALG_INC=/cvmfs/larsoft.opensciencegrid.org/products/larcorealg/v10_00_03/include
G4PARTICLEHPDATA=/cvmfs/larsoft.opensciencegrid.org/products/g4tendl/v1_3_2/G4TENDL1.3.2
SETUP_LARANA=larana v10_01_01 -f Linux64bit+3.10-2.17 -z /cvmfs/larsoft.opensciencegrid.org/products -q e26:prof
SETUP_LARSOFT=larsoft v10_12_00 -f Linux64bit+3.10-2.17 -z /cvmfs/larsoft.opensciencegrid.org/products -q e26:prof
SETUP_CETLIB=cetlib v3_18_02 -f Linux64bit+3.10-2.17 -z /cvmfs/larsoft.opensciencegrid.org/products -q e26:prof
SETUP_FHICLCPP=fhiclcpp v4_18_04 -f Linux64bit+3.10-2.17 -z /cvmfs/larsoft.opensciencegrid.org/products -q e26:prof
OPENBLAS_INC=/cvmfs/larsoft.opensciencegrid.org/products/openblas/v0_3_23/Linux64bit+3.10-2.17-e26/include
PYTHON_INCLUDE=/cvmfs/larsoft.opensciencegrid.org/products/python/v3_9_15/Linux64bit+3.10-2.17/include/python3.9
_=/usr/bin/env
Will use justin-get-file
pfn: root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/43/4c/np04hd_raw_run028086_0037_dataflow3_datawriter_0_20240721T070339_reco_stage1_reco_stage2_20240810T191632_keepup.root
did: hd-protodune-det-reco:np04hd_raw_run028086_0037_dataflow3_datawriter_0_20240721T070339_reco_stage1_reco_stage2_20240810T191632_keepup.root
output_file: np04hd_raw_run028086_0037_dataflow3_datawriter_0_20240721T070339_reco_stage1_reco_stage2_20240810T191632_keepup_20260106T152604Z_singlehit.root
Running reco stage1
%MSG-i MF_INIT_OK: Early 06-Jan-2026 15:26:53 UTC JobSetup
Messagelogger initialization complete.
%MSG
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunehd_v6_refactored.gdml
Info in <TGeoManager::TGeoManager>: Geometry GDMLImport, Geometry imported from GDML created
Info in <TGeoManager::SetTopVolume>: Top volume is volWorld. Master volume is volWorld
Info in <TGeoNavigator::BuildCache>: --- Maximum geometry depth set to 100
Info in <TGeoManager::CheckGeometry>: Fixing runtime shapes...
Info in <TGeoManager::CheckGeometry>: ...Nothing to fix
Info in <TGeoManager::CloseGeometry>: Counting nodes...
Info in <TGeoManager::Voxelize>: Voxelizing...
Info in <TGeoManager::CloseGeometry>: Building cache...
Info in <TGeoManager::CountLevels>: max level = 5, max placements = 1148
Info in <TGeoManager::CloseGeometry>: 25610 nodes/ 5537 volume UID's in Geometry imported from GDML
Info in <TGeoManager::CloseGeometry>: ----------------modeler ready----------------
%MSG-i AuxDetGeometryCore: Early 06-Jan-2026 15:29:44 UTC JobSetup
New detector geometry loaded from
/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunehd_v6_refactored.gdml
%MSG
%MSG-i GeometryCore: Early 06-Jan-2026 15:29:44 UTC JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore: Early 06-Jan-2026 15:29:44 UTC JobSetup
New detector geometry loaded from
/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunehd_v6_refactored.gdml
%MSG
%MSG-i DuneApaWireReadoutGeom: Early 06-Jan-2026 15:29:44 UTC JobSetup
Initializing wire readout...
%MSG
Cryostat 0:
10240 total channels
4 APAs
For all identical APA:
Number of channels per APA = 2560
U channels per APA = 800
V channels per APA = 800
Z channels per APA = 960
Pitch in U Plane = 0.4669
Pitch in V Plane = 0.4669
Pitch in Z Plane = 0.4792
%MSG-i SimpleChannelStatusService: Early 06-Jan-2026 15:29:44 UTC JobSetup
Loaded from configuration:
- 0 bad channels
- 0 noisy channels
- largest channel ID: 10239, largest present: 10239
%MSG
%MSG-i setupProvider<DetectorPropertiesStandard>: Early 06-Jan-2026 15:29:44 UTC JobSetup
Asked to ignore 1 keys: 'InheritNumberTimeSamples'
%MSG
IFDH constructor, got keys:
service_type,
IFDH: didn't find a base uri...
06-Jan-2026 15:29:49 UTC Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/43/4c/np04hd_raw_run028086_0037_dataflow3_datawriter_0_20240721T070339_reco_stage1_reco_stage2_20240810T191632_keepup.root"
06-Jan-2026 15:30:08 UTC Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/43/4c/np04hd_raw_run028086_0037_dataflow3_datawriter_0_20240721T070339_reco_stage1_reco_stage2_20240810T191632_keepup.root"
Begin processing the 1st record. run: 28086 subRun: 1 event: 9251 at 06-Jan-2026 15:30:21 UTC
Begin processing the 2nd record. run: 28086 subRun: 1 event: 9259 at 06-Jan-2026 15:30:30 UTC
Begin processing the 3rd record. run: 28086 subRun: 1 event: 9267 at 06-Jan-2026 15:30:35 UTC
Begin processing the 4th record. run: 28086 subRun: 1 event: 9275 at 06-Jan-2026 15:30:41 UTC
Begin processing the 5th record. run: 28086 subRun: 1 event: 9283 at 06-Jan-2026 15:30:56 UTC
Begin processing the 6th record. run: 28086 subRun: 1 event: 9323 at 06-Jan-2026 15:31:20 UTC
Begin processing the 7th record. run: 28086 subRun: 1 event: 9331 at 06-Jan-2026 15:31:41 UTC
Begin processing the 8th record. run: 28086 subRun: 1 event: 9339 at 06-Jan-2026 15:31:56 UTC
Begin processing the 9th record. run: 28086 subRun: 1 event: 9347 at 06-Jan-2026 15:32:13 UTC
Begin processing the 10th record. run: 28086 subRun: 1 event: 9355 at 06-Jan-2026 15:32:16 UTC
Begin processing the 11th record. run: 28086 subRun: 1 event: 9363 at 06-Jan-2026 15:32:30 UTC
Begin processing the 12th record. run: 28086 subRun: 1 event: 9371 at 06-Jan-2026 15:32:33 UTC
Begin processing the 13th record. run: 28086 subRun: 1 event: 9379 at 06-Jan-2026 15:32:38 UTC
Begin processing the 14th record. run: 28086 subRun: 1 event: 9387 at 06-Jan-2026 15:33:02 UTC
Begin processing the 15th record. run: 28086 subRun: 1 event: 9395 at 06-Jan-2026 15:33:10 UTC
Begin processing the 16th record. run: 28086 subRun: 1 event: 9403 at 06-Jan-2026 15:33:19 UTC
Begin processing the 17th record. run: 28086 subRun: 1 event: 9411 at 06-Jan-2026 15:33:23 UTC
Begin processing the 18th record. run: 28086 subRun: 1 event: 9419 at 06-Jan-2026 15:33:37 UTC
Begin processing the 19th record. run: 28086 subRun: 1 event: 9427 at 06-Jan-2026 15:33:56 UTC
Begin processing the 20th record. run: 28086 subRun: 1 event: 9435 at 06-Jan-2026 15:34:12 UTC
Begin processing the 21st record. run: 28086 subRun: 1 event: 9443 at 06-Jan-2026 15:34:17 UTC
Begin processing the 22nd record. run: 28086 subRun: 1 event: 9451 at 06-Jan-2026 15:34:24 UTC
Begin processing the 23rd record. run: 28086 subRun: 1 event: 9459 at 06-Jan-2026 15:34:29 UTC
Begin processing the 24th record. run: 28086 subRun: 1 event: 9467 at 06-Jan-2026 15:34:56 UTC
06-Jan-2026 15:34:57 UTC Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/43/4c/np04hd_raw_run028086_0037_dataflow3_datawriter_0_20240721T070339_reco_stage1_reco_stage2_20240810T191632_keepup.root"
====================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
====================================================================================================================
Full event 0.045521 11.4309 27.2634 9.34442 7.61568 24
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00302301 0.0488061 0.157926 0.0416654 0.0360716 24
end_path:ana:SingleHit 2.68424 11.8357 27.2147 9.5354 7.36996 23
====================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 2243.28 MB
Peak resident set size usage (VmHWM): 117.404 MB
Details saved in: 'mem.db'
====================================================================================================
TrigReport ---------- Event summary -------------
TrigReport Events total = 24 passed = 24 failed = 0
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 24 23 1 ana
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 182.917247 Real = 277.665755
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 2243.28 VmHWM = 117.404
%MSG-s ArtException: PostEndJob 06-Jan-2026 15:34:59 UTC ModuleEndJob
---- EventProcessorFailure BEGIN
EventProcessor: an exception occurred during current event processing
---- EventProcessorFailure BEGIN
EndPathExecutor: an exception occurred during current event processing
---- ScheduleExecutionFailure BEGIN
Path: ProcessingStopped.
---- ProductNotFound BEGIN
Found zero products matching all selection criteria
C++ type: std::vector<recob::Hit>
Module label: 'hitpdune'
Product instance name: ''
Process name: (empty)
The above exception was thrown while processing module SingleHit/ana run: 28086 subRun: 1 event: 9467
---- ProductNotFound END
Exception going through path end_path
---- ScheduleExecutionFailure END
---- EventProcessorFailure END
---- EventProcessorFailure END
%MSG
Art has completed and will exit with status 1.
Error in reco1 