Jobsub ID 236812.3@dunegpschedd02.fnal.gov

Jobsub ID	236812.3@dunegpschedd02.fnal.gov
Workflow ID	9568
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 18:25:09
Site	US_UChicago
Entry	Engage_US_MWT2_uiuc_gk02_condce_mcore
Last heartbeat	2025-11-03 18:48:20
From worker node	Hostname	iut2-c349.iu.edu
	cpuinfo	AMD EPYC 7302 16-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	86400 (24 hours)
	GPU
	Inner Apptainer?	True
Job state	jobscript_error
Started	2025-11-03 18:42:01
Input files	vd-protodune-det-reco:np02vd_raw_run040270_0234_df-s04-d2_dw_0_20251027T190640_reco_stage1_20251027T212531_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 18:48:20
Saved logs	justin-logs:236812.3-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

Setting up larsoft UPS area... /cvmfs/larsoft.opensciencegrid.org
Setting up DUNE UPS area... /cvmfs/dune.opensciencegrid.org/products/dune/
  File: '/cvmfs/fifeuser2.opensciencegrid.org/sw/dune/e5a4dae4a8bc50714f00830299ac9c15a6cca185'
  Size: 4096      	Blocks: 9          IO Block: 4096   directory
Device: 1000b1h/1048753d	Inode: 34780435    Links: 5
Access: (0755/drwxr-xr-x)  Uid: (65534/ UNKNOWN)   Gid: (65534/ UNKNOWN)
Context: system_u:object_r:fusefs_t:s0
Access: 2025-11-03 12:08:57.000000000 -0500
Modify: 2025-11-03 12:08:57.000000000 -0500
Change: 2025-11-03 12:08:57.000000000 -0500
 Birth: -
Set dunesw to /cvmfs/fifeuser2.opensciencegrid.org/sw/dune/e5a4dae4a8bc50714f00830299ac9c15a6cca185
Will use justin-get-file
pfn: root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/c1/10/np02vd_raw_run040270_0234_df-s04-d2_dw_0_20251027T190640_reco_stage1_20251027T212531_keepup.root
did: vd-protodune-det-reco:np02vd_raw_run040270_0234_df-s04-d2_dw_0_20251027T190640_reco_stage1_20251027T212531_keepup.root
%MSG-i MF_INIT_OK:  Early 03-Nov-2025 13:42:14 EST JobSetup
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 13:42:16 EST 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 13:42:16 EST JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore:  Early 03-Nov-2025 13:42:16 EST 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 13:42:16 EST JobSetup
Initializing CRPWireReadoutGeom channel mapping algorithm.
%MSG
%MSG-i CRPWireReadoutGeom:  Early 03-Nov-2025 13:42:16 EST JobSetup
Build readout planes for 1 16 3
%MSG
%MSG-i CRPWireReadoutGeom:  Early 03-Nov-2025 13:42:16 EST 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 13:42:16 EST 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 13:42:16 EST JobSetup
Asked to ignore 1 keys: 'InheritNumberTimeSamples'
%MSG
%MSG-i SignalShapingServiceDUNE:  Early 03-Nov-2025 13:42:16 EST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE:  Early 03-Nov-2025 13:42:16 EST 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 13:42:17 EST  Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/c1/10/np02vd_raw_run040270_0234_df-s04-d2_dw_0_20251027T190640_reco_stage1_20251027T212531_keepup.root"
03-Nov-2025 13:42:21 EST  Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/c1/10/np02vd_raw_run040270_0234_df-s04-d2_dw_0_20251027T190640_reco_stage1_20251027T212531_keepup.root"
Begin processing the 1st record. run: 40270 subRun: 1 event: 43430 at 03-Nov-2025 13:42:22 EST
Begin processing the 2nd record. run: 40270 subRun: 1 event: 43438 at 03-Nov-2025 13:42:28 EST
Begin processing the 3rd record. run: 40270 subRun: 1 event: 43446 at 03-Nov-2025 13:43:14 EST
Begin processing the 4th record. run: 40270 subRun: 1 event: 43454 at 03-Nov-2025 13:43:57 EST
Begin processing the 5th record. run: 40270 subRun: 1 event: 43462 at 03-Nov-2025 13:44:36 EST
Begin processing the 6th record. run: 40270 subRun: 1 event: 43470 at 03-Nov-2025 13:44:50 EST
Begin processing the 7th record. run: 40270 subRun: 1 event: 43478 at 03-Nov-2025 13:45:10 EST
Begin processing the 8th record. run: 40270 subRun: 1 event: 43486 at 03-Nov-2025 13:45:20 EST
Begin processing the 9th record. run: 40270 subRun: 1 event: 43494 at 03-Nov-2025 13:45:32 EST
Begin processing the 10th record. run: 40270 subRun: 1 event: 43502 at 03-Nov-2025 13:45:44 EST
Begin processing the 11th record. run: 40270 subRun: 1 event: 43510 at 03-Nov-2025 13:45:53 EST
Begin processing the 12th record. run: 40270 subRun: 1 event: 43518 at 03-Nov-2025 13:46:16 EST
Begin processing the 13th record. run: 40270 subRun: 1 event: 43526 at 03-Nov-2025 13:46:27 EST
Begin processing the 14th record. run: 40270 subRun: 1 event: 43534 at 03-Nov-2025 13:46:46 EST
Begin processing the 15th record. run: 40270 subRun: 1 event: 43542 at 03-Nov-2025 13:46:56 EST
Begin processing the 16th record. run: 40270 subRun: 1 event: 43550 at 03-Nov-2025 13:47:17 EST
Begin processing the 17th record. run: 40270 subRun: 1 event: 43558 at 03-Nov-2025 13:47:30 EST
Begin processing the 18th record. run: 40270 subRun: 1 event: 43566 at 03-Nov-2025 13:47:52 EST
Begin processing the 19th record. run: 40270 subRun: 1 event: 43574 at 03-Nov-2025 13:48:04 EST
03-Nov-2025 13:48:04 EST  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/c1/10/np02vd_raw_run040270_0234_df-s04-d2_dw_0_20251027T190640_reco_stage1_20251027T212531_keepup.root"
%MSG-i NuRandomService:  CalibAnaTree:calibana@EndJob 03-Nov-2025 13:48:04 EST  ModuleEndJob

Summary of seeds computed by the NuRandomService
Random policy: 'random'
  master seed: 629656218
  seed within: [ 1 ; 900000000 ]

%MSG

TrigReport ---------- Event summary -------------
TrigReport Events total = 19 passed = 19 failed = 0

TrigReport ---------- Modules in End-path ----------
TrigReport        Run    Success      Error Name
TrigReport         19         18          1 calibana

TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 328.644903 Real = 342.394083

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 1518.15 VmHWM = 903.152

%MSG-s ArtException:  PostEndJob 03-Nov-2025 13:48:04 EST 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: 40270 subRun: 1 event: 43574
      ---- 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