Jobsub ID 242469.0@dunegpschedd01.fnal.gov
| Jobsub ID | 242469.0@dunegpschedd01.fnal.gov | 
| Workflow ID | 9565 | 
| Stage ID | 1 | 
| User name | msiden@fnal.gov | 
| HTCondor Group | group_dune | 
| Requested | Processors | 1 | 
| GPU | No | 
| RSS bytes | 4193255424 (3999 MiB) | 
| Wall seconds limit | 80000 (22 hours) | 
| Submitted time | 2025-11-03 17:47:06 | 
| Site | CA_SFU | 
| Entry | DUNE_CA_SFU_lcg-ce3 | 
| Last heartbeat | 2025-11-03 17:54:01 | 
| From worker node | Hostname | fc30554.int.fir.alliancecan.ca | 
| cpuinfo | AMD EPYC 9655 96-Core Processor | 
| OS release | Scientific Linux release 7.9 (Nitrogen) | 
| Processors | 1 | 
| RSS bytes | 4194304000 (4000 MiB) | 
| Wall seconds limit | 84598 (23 hours) | 
| GPU |  | 
| Inner Apptainer? | True | 
| Job state | jobscript_error | 
| Started | 2025-11-03 17:48:34 | 
| Input files | vd-protodune-det-reco:np02vd_raw_run040097_0626_df-s04-d1_dw_0_20251016T113625_reco_stage1_20251017T051250_keepup.root
  | 
| Jobscript | Exit code | 1 | 
| Real time | 0m (0s) | 
| CPU time | 0m (0s = 0%) | 
| Max RSS bytes | 0 (0 MiB) | 
| Outputting started |   | 
| Output files |  | 
| Finished | 2025-11-03 17:54:01 | 
| Saved logs | justin-logs:242469.0-dunegpschedd01.fnal.gov.logs.tgz | 
| List job events     Cached HTCondor job logs | 
Jobscript log (last 10,000 characters)
p
Messagelogger initialization complete.
%MSG
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_10_02d00/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 AuxDetGeometryCore:  Early 03-Nov-2025 09:48:40 PST JobSetup
New detector geometry loaded from
	/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_10_02d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i GeometryCore:  Early 03-Nov-2025 09:48:40 PST JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore:  Early 03-Nov-2025 09:48:40 PST JobSetup
New detector geometry loaded from
	/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_10_02d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i CRPWireReadoutGeom:  Early 03-Nov-2025 09:48:40 PST JobSetup
Initializing CRPWireReadoutGeom channel mapping algorithm.
%MSG
%MSG-i CRPWireReadoutGeom:  Early 03-Nov-2025 09:48:40 PST JobSetup
Build readout planes for 1 16 3
%MSG
%MSG-i CRPWireReadoutGeom:  Early 03-Nov-2025 09:48:40 PST JobSetup
Counted 12288 channels.
%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 03-Nov-2025 09:48:40 PST JobSetup
Loaded from configuration:
  - 0 bad channels
  - 0 noisy channels
  - largest channel ID: 12287, largest present: 12287
%MSG
DAPHNE Channel Map: Building DAPHNE channel map from file PDVD_PDS_Mapping_v07082025.json
%MSG-i setupProvider<DetectorPropertiesStandard>:  Early  03-Nov-2025 09:48:40 PST JobSetup
Asked to ignore 1 keys: 'InheritNumberTimeSamples'
%MSG
%MSG-i SignalShapingServiceDUNE:  Early 03-Nov-2025 09:48:40 PST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE:  Early 03-Nov-2025 09:48:40 PST 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).
03-Nov-2025 09:48:40 PST  Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/80/9e/np02vd_raw_run040097_0626_df-s04-d1_dw_0_20251016T113625_reco_stage1_20251017T051250_keepup.root"
03-Nov-2025 09:48:42 PST  Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/80/9e/np02vd_raw_run040097_0626_df-s04-d1_dw_0_20251016T113625_reco_stage1_20251017T051250_keepup.root"
Begin processing the 1st record. run: 40097 subRun: 1 event: 118205 at 03-Nov-2025 09:48:44 PST
Begin processing the 2nd record. run: 40097 subRun: 1 event: 118209 at 03-Nov-2025 09:48:48 PST
Begin processing the 3rd record. run: 40097 subRun: 1 event: 118213 at 03-Nov-2025 09:48:50 PST
Begin processing the 4th record. run: 40097 subRun: 1 event: 118217 at 03-Nov-2025 09:48:53 PST
Begin processing the 5th record. run: 40097 subRun: 1 event: 118221 at 03-Nov-2025 09:48:57 PST
Begin processing the 6th record. run: 40097 subRun: 1 event: 118225 at 03-Nov-2025 09:49:05 PST
Begin processing the 7th record. run: 40097 subRun: 1 event: 118229 at 03-Nov-2025 09:49:09 PST
Begin processing the 8th record. run: 40097 subRun: 1 event: 118233 at 03-Nov-2025 09:49:16 PST
Begin processing the 9th record. run: 40097 subRun: 1 event: 118237 at 03-Nov-2025 09:49:21 PST
Begin processing the 10th record. run: 40097 subRun: 1 event: 118241 at 03-Nov-2025 09:49:25 PST
Begin processing the 11th record. run: 40097 subRun: 1 event: 118245 at 03-Nov-2025 09:49:31 PST
Begin processing the 12th record. run: 40097 subRun: 1 event: 118249 at 03-Nov-2025 09:49:37 PST
Begin processing the 13th record. run: 40097 subRun: 1 event: 118253 at 03-Nov-2025 09:49:43 PST
Begin processing the 14th record. run: 40097 subRun: 1 event: 118257 at 03-Nov-2025 09:49:50 PST
Begin processing the 15th record. run: 40097 subRun: 1 event: 118261 at 03-Nov-2025 09:50:00 PST
Begin processing the 16th record. run: 40097 subRun: 1 event: 118265 at 03-Nov-2025 09:50:07 PST
Begin processing the 17th record. run: 40097 subRun: 1 event: 118269 at 03-Nov-2025 09:50:12 PST
Begin processing the 18th record. run: 40097 subRun: 1 event: 118273 at 03-Nov-2025 09:50:17 PST
Begin processing the 19th record. run: 40097 subRun: 1 event: 118277 at 03-Nov-2025 09:50:50 PST
Begin processing the 20th record. run: 40097 subRun: 1 event: 118281 at 03-Nov-2025 09:50:57 PST
Begin processing the 21st record. run: 40097 subRun: 1 event: 118285 at 03-Nov-2025 09:51:13 PST
Begin processing the 22nd record. run: 40097 subRun: 1 event: 118289 at 03-Nov-2025 09:51:19 PST
Begin processing the 23rd record. run: 40097 subRun: 1 event: 118293 at 03-Nov-2025 09:51:24 PST
Begin processing the 24th record. run: 40097 subRun: 1 event: 118297 at 03-Nov-2025 09:51:32 PST
Begin processing the 25th record. run: 40097 subRun: 1 event: 118301 at 03-Nov-2025 09:51:44 PST
Begin processing the 26th record. run: 40097 subRun: 1 event: 118305 at 03-Nov-2025 09:51:50 PST
Begin processing the 27th record. run: 40097 subRun: 1 event: 118309 at 03-Nov-2025 09:51:55 PST
Begin processing the 28th record. run: 40097 subRun: 1 event: 118313 at 03-Nov-2025 09:52:00 PST
Begin processing the 29th record. run: 40097 subRun: 1 event: 118317 at 03-Nov-2025 09:52:10 PST
Begin processing the 30th record. run: 40097 subRun: 1 event: 118321 at 03-Nov-2025 09:52:16 PST
Begin processing the 31st record. run: 40097 subRun: 1 event: 118325 at 03-Nov-2025 09:52:23 PST
Begin processing the 32nd record. run: 40097 subRun: 1 event: 118329 at 03-Nov-2025 09:52:39 PST
Begin processing the 33rd record. run: 40097 subRun: 1 event: 118333 at 03-Nov-2025 09:52:45 PST
Begin processing the 34th record. run: 40097 subRun: 1 event: 118337 at 03-Nov-2025 09:52:50 PST
Begin processing the 35th record. run: 40097 subRun: 1 event: 118341 at 03-Nov-2025 09:52:55 PST
Begin processing the 36th record. run: 40097 subRun: 1 event: 118345 at 03-Nov-2025 09:53:00 PST
Begin processing the 37th record. run: 40097 subRun: 1 event: 118349 at 03-Nov-2025 09:53:06 PST
Begin processing the 38th record. run: 40097 subRun: 1 event: 118353 at 03-Nov-2025 09:53:10 PST
Begin processing the 39th record. run: 40097 subRun: 1 event: 118357 at 03-Nov-2025 09:53:13 PST
Begin processing the 40th record. run: 40097 subRun: 1 event: 118361 at 03-Nov-2025 09:53:18 PST
Begin processing the 41st record. run: 40097 subRun: 1 event: 118365 at 03-Nov-2025 09:53:24 PST
Begin processing the 42nd record. run: 40097 subRun: 1 event: 118369 at 03-Nov-2025 09:53:35 PST
Begin processing the 43rd record. run: 40097 subRun: 1 event: 118373 at 03-Nov-2025 09:53:40 PST
Begin processing the 44th record. run: 40097 subRun: 1 event: 118377 at 03-Nov-2025 09:53:46 PST
Begin processing the 45th record. run: 40097 subRun: 1 event: 118381 at 03-Nov-2025 09:53:52 PST
03-Nov-2025 09:53:52 PST  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/80/9e/np02vd_raw_run040097_0626_df-s04-d1_dw_0_20251016T113625_reco_stage1_20251017T051250_keepup.root"
%MSG-i NuRandomService:  CalibAnaTree:calibana@EndJob 03-Nov-2025 09:53:52 PST  ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'random'
  master seed: 128551372
  seed within: [ 1 ; 900000000 ]
%MSG
TrigReport ---------- Event summary -------------
TrigReport Events total = 45 passed = 45 failed = 0
TrigReport ---------- Modules in End-path ----------
TrigReport        Run    Success      Error Name
TrigReport         45         44          1 calibana
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 149.995415 Real = 308.650415
MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 1621.65 VmHWM = 973.894
%MSG-s ArtException:  PostEndJob 03-Nov-2025 09:53:52 PST 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 CalibAnaTree/calibana run: 40097 subRun: 1 event: 118381
      ---- 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 running. Exiting with 1