Jobsub ID 271454.11@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
VERSION=v02_20_07
NUG4_VERSION=v1_16_11
SETUP_LARDATAALG=lardataalg v10_02_01 -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_08 -f Linux64bit+3.10-2.17 -z /cvmfs/larsoft.opensciencegrid.org/products -q e26:prof
SETUP_LARSOFT=larsoft v10_16_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/vd-protodune-det-reco/81/40/np02vd_raw_run039693_0001_df-s04-d2_dw_0_20250929T163958_reco_stage1_20250929T181111_keepup.root
did: vd-protodune-det-reco:np02vd_raw_run039693_0001_df-s04-d2_dw_0_20250929T163958_reco_stage1_20250929T181111_keepup.root
Running reco stage1
%MSG-i MF_INIT_OK: Early 13-Jan-2026 08:43:03 CST JobSetup
Messagelogger initialization complete.
%MSG
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_16_00d00/gdml/protodunevd_v5_ggd.gdml
Info in <TGeoManager::TGeoManager>: Geometry GDMLImport, Geometry imported from GDML created
Error: Unsupported GDML Tag Used :gdml_simple_extension. Please Check Geometry/Schema.
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 = 292
Info in <TGeoManager::CloseGeometry>: 16484 nodes/ 2469 volume UID's in Geometry imported from GDML
Info in <TGeoManager::CloseGeometry>: ----------------modeler ready----------------
%MSG-i GeometryCore: Early 13-Jan-2026 08:43:19 CST JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore: Early 13-Jan-2026 08:43:19 CST JobSetup
New detector geometry loaded from
/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_16_00d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i CRPWireReadoutGeom: Early 13-Jan-2026 08:43:19 CST JobSetup
Initializing CRPWireReadoutGeom channel mapping algorithm.
%MSG
%MSG-i CRPWireReadoutGeom: Early 13-Jan-2026 08:43:19 CST JobSetup
Build readout planes for 1 16 3
%MSG
%MSG-i CRPWireReadoutGeom: Early 13-Jan-2026 08:43:19 CST JobSetup
Counted 12288 channels.
%MSG
%MSG-i AuxDetGeometryCore: Early 13-Jan-2026 08:43:19 CST JobSetup
New detector geometry loaded from
/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_16_00d00/gdml/protodunevd_v5_ggd.gdml
%MSG
GeoApaChannelGroupService::ctor: Group 0 (apa0) has 3072 channels from 3/3 readout planes.
GeoApaChannelGroupService::ctor: Group 1 (apa1) has 3072 channels from 3/3 readout planes.
GeoApaChannelGroupService::ctor: Group 2 (apa2) has 3072 channels from 3/3 readout planes.
GeoApaChannelGroupService::ctor: Group 3 (apa3) has 3072 channels from 3/3 readout planes.
%MSG-i SimpleChannelStatusService: Early 13-Jan-2026 08:43:19 CST JobSetup
Loaded from configuration:
- 0 bad channels
- 0 noisy channels
- largest channel ID: 12287, largest present: 12287
%MSG
%MSG-i setupProvider<DetectorPropertiesStandard>: Early 13-Jan-2026 08:43:19 CST JobSetup
Asked to ignore 1 keys: 'InheritNumberTimeSamples'
%MSG
PDSP Channel Map: Building RCE TPC wiremap from file protoDUNETPCChannelMap_RCE_v4.txt
PDSP Channel Map: Building FELIX TPC wiremap from file protoDUNETPCChannelMap_RCE_v4.txt
PDSP Channel Map: Building SSP channel map from file protoDUNESSPChannelMap_v1.txt
%MSG-i SignalShapingServiceDUNE: Early 13-Jan-2026 08:43:19 CST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE: Early 13-Jan-2026 08:43:19 CST JobSetup
using the field response provided from a .root file
%MSG
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_U (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_V (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_Y (Potential memory leak).
[1;93mDetector Properties:[0m
Detector Geometry: protodunevd_v5 (PDVD)
Tick Window: [0, 6000]
Top Bounds: (3,-336.4,0.6) -> (341.56,336.4,298.7)
Bot Bounds: (-341.56,-336.4,0.6) -> (-3,336.4,298.7)
[1;93mAnalysis Parameters:[0m
Track Length Cut: 30 cm
Fiducial Length: 20 cm
Barycenter Radius: 10 cm
Michel Space Radius: 20 cm
13-Jan-2026 08:43:20 CST Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/81/40/np02vd_raw_run039693_0001_df-s04-d2_dw_0_20250929T163958_reco_stage1_20250929T181111_keepup.root"
13-Jan-2026 08:43:25 CST Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/81/40/np02vd_raw_run039693_0001_df-s04-d2_dw_0_20250929T163958_reco_stage1_20250929T181111_keepup.root"
db: runtime1759163998
Begin processing the 1st record. run: 39693 subRun: 1 event: 174 at 13-Jan-2026 08:43:26 CST
Begin processing the 2nd record. run: 39693 subRun: 1 event: 182 at 13-Jan-2026 08:43:27 CST
Begin processing the 3rd record. run: 39693 subRun: 1 event: 190 at 13-Jan-2026 08:43:27 CST
Begin processing the 4th record. run: 39693 subRun: 1 event: 198 at 13-Jan-2026 08:43:28 CST
Begin processing the 5th record. run: 39693 subRun: 1 event: 206 at 13-Jan-2026 08:43:29 CST
Begin processing the 6th record. run: 39693 subRun: 1 event: 214 at 13-Jan-2026 08:43:29 CST
Begin processing the 7th record. run: 39693 subRun: 1 event: 222 at 13-Jan-2026 08:43:30 CST
Begin processing the 8th record. run: 39693 subRun: 1 event: 230 at 13-Jan-2026 08:43:30 CST
Begin processing the 9th record. run: 39693 subRun: 1 event: 238 at 13-Jan-2026 08:43:31 CST
Begin processing the 10th record. run: 39693 subRun: 1 event: 246 at 13-Jan-2026 08:43:31 CST
Begin processing the 11th record. run: 39693 subRun: 1 event: 254 at 13-Jan-2026 08:43:32 CST
Begin processing the 12th record. run: 39693 subRun: 1 event: 262 at 13-Jan-2026 08:43:33 CST
Begin processing the 13th record. run: 39693 subRun: 1 event: 270 at 13-Jan-2026 08:43:33 CST
Begin processing the 14th record. run: 39693 subRun: 1 event: 278 at 13-Jan-2026 08:43:33 CST
Begin processing the 15th record. run: 39693 subRun: 1 event: 286 at 13-Jan-2026 08:43:34 CST
Begin processing the 16th record. run: 39693 subRun: 1 event: 294 at 13-Jan-2026 08:43:35 CST
Begin processing the 17th record. run: 39693 subRun: 1 event: 302 at 13-Jan-2026 08:43:35 CST
Begin processing the 18th record. run: 39693 subRun: 1 event: 310 at 13-Jan-2026 08:43:36 CST
Begin processing the 19th record. run: 39693 subRun: 1 event: 318 at 13-Jan-2026 08:43:36 CST
Begin processing the 20th record. run: 39693 subRun: 1 event: 326 at 13-Jan-2026 08:43:37 CST
Begin processing the 21st record. run: 39693 subRun: 1 event: 334 at 13-Jan-2026 08:43:37 CST
Begin processing the 22nd record. run: 39693 subRun: 1 event: 342 at 13-Jan-2026 08:43:37 CST
13-Jan-2026 08:43:38 CST Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/81/40/np02vd_raw_run039693_0001_df-s04-d2_dw_0_20250929T163958_reco_stage1_20250929T181111_keepup.root"
====================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
====================================================================================================================
Full event 0.379014 0.55778 1.00399 0.505932 0.159961 22
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000241584 0.00147925 0.00567656 0.000286979 0.00212105 22
end_path:checks:MichelAnalysis 0.378662 0.556139 0.998018 0.505533 0.159832 22
====================================================================================================================
%MSG-i NuRandomService: MichelAnalysis:checks@EndJob 13-Jan-2026 08:43:38 CST ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'random'
master seed: 318023814
seed within: [ 1 ; 900000000 ]
%MSG
TrigReport ---------- Event summary -------------
TrigReport Events total = 22 passed = 22 failed = 0
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 22 22 0 checks
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 11.356577 Real = 12.346028
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 2116.15 VmHWM = 640.045
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039693_0001_df-s04-d2_dw_0_20250929T163958_reco_stage1_20250929T181111_keepup_pdvd_MichelAnalysis.root
Forming reco metadata
/cvmfs/larsoft.opensciencegrid.org/products/python/v3_9_15/Linux64bit+3.10-2.17/bin/python: No module named meta_maker
Error in reco metadata