justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 253216.1@dunegpschedd02.fnal.gov

Jobsub ID253216.1@dunegpschedd02.fnal.gov
Workflow ID10801
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors4
GPUNo
RSS bytes6815744000 (6500 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-11-26 16:34:40
SiteCA_SFU
EntryDUNE_CA_SFU_lcg-ce3
Last heartbeat2025-11-26 18:19:35
From worker nodeHostnamefc30607.int.fir.alliancecan.ca
cpuinfoAMD EPYC 9655 96-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors4
RSS bytes7864320000 (7500 MiB)
Wall seconds limit84598 (23 hours)
GPU
Inner Apptainer?True
Job statejobscript_error
Started2025-11-26 18:14:36
Input filesvd-protodune-det-reco:np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup.root
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-11-26 18:19:35
Saved logsjustin-logs:253216.1-dunegpschedd02.fnal.gov.logs.tgz
List job events     (HTCondor job logs unavailable)

Jobscript log (last 10,000 characters)

ASE=/cvmfs/larsoft.opensciencegrid.org/products/mrb/v6_09_11/slf7.x86_64/CPAN
GIT_EXEC_PATH=/cvmfs/larsoft.opensciencegrid.org/products/git/v2_45_1/Linux64bit+3.10-2.17/libexec/git-core
PROTODUNEANA_INC=/cvmfs/dune.opensciencegrid.org/products/dune/protoduneana/v10_12_01d00/include
DUNEDATAPREP_LIB=/cvmfs/dune.opensciencegrid.org/products/dune/dunedataprep/v10_12_01d00/slf7.x86_64.e26.prof/lib
DUNERECO_DIR=/cvmfs/dune.opensciencegrid.org/products/dune/dunereco/v10_12_01d00
WIRECELL_LIB=/cvmfs/larsoft.opensciencegrid.org/products/wirecell/v0_30_6/Linux64bit+3.10-2.17-e26-prof/lib
TRITON_INC=/cvmfs/larsoft.opensciencegrid.org/products/triton/v2_25_0d/Linux64bit+3.10-2.17-e26/include
LIBJPEG_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/vd-protodune-det-reco/10/d3/np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup.root
did: vd-protodune-det-reco:np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup.root
output_file: np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup_20251126T181448Z_singlehit.root
Running reco stage1
%MSG-i MF_INIT_OK:  Early 26-Nov-2025 10:14:50 PST JobSetup
Messagelogger initialization complete.
%MSG
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/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 26-Nov-2025 10:15:00 PST JobSetup
New detector geometry loaded from
	/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i GeometryCore:  Early 26-Nov-2025 10:15:00 PST JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore:  Early 26-Nov-2025 10:15:00 PST JobSetup
New detector geometry loaded from
	/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i CRPWireReadoutGeom:  Early 26-Nov-2025 10:15:00 PST JobSetup
Initializing CRPWireReadoutGeom channel mapping algorithm.
%MSG
%MSG-i CRPWireReadoutGeom:  Early 26-Nov-2025 10:15:00 PST JobSetup
Build readout planes for 1 16 3
%MSG
%MSG-i CRPWireReadoutGeom:  Early 26-Nov-2025 10:15:00 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 26-Nov-2025 10:15:00 PST JobSetup
Loaded from configuration:
  - 0 bad channels
  - 0 noisy channels
  - largest channel ID: 12287, largest present: 12287
%MSG
%MSG-i setupProvider<DetectorPropertiesStandard>:  Early  26-Nov-2025 10:15:00 PST 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 26-Nov-2025 10:15:00 PST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE:  Early 26-Nov-2025 10:15:00 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).
26-Nov-2025 10:15:00 PST  Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/10/d3/np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup.root"
26-Nov-2025 10:15:04 PST  Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/10/d3/np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup.root"
db: runtime1764115279
Begin processing the 1st record. run: 40890 subRun: 1 event: 13118 at 26-Nov-2025 10:15:06 PST
Begin processing the 2nd record. run: 40890 subRun: 1 event: 13120 at 26-Nov-2025 10:16:36 PST
Begin processing the 3rd record. run: 40890 subRun: 1 event: 13122 at 26-Nov-2025 10:17:48 PST
Begin processing the 4th record. run: 40890 subRun: 1 event: 13124 at 26-Nov-2025 10:19:01 PST
26-Nov-2025 10:19:02 PST  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/10/d3/np02vd_raw_run040890_0284_df-s04-d0_dw_0_20251126T000122_reco_stage1_20251126T034338_keepup.root"

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                        0.000377868     58.7581       89.1786       72.9268       34.5665         4     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.000215019    0.0171274     0.0676745    0.000310038    0.0291835        4     
end_path:ana:SingleHit              72.8611       78.3211       89.1104       72.9917       7.62942         3     
====================================================================================================================
%MSG-i NuRandomService:  SingleHit:ana@EndJob 26-Nov-2025 10:19:02 PST  ModuleEndJob

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

%MSG

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

  Peak virtual memory usage (VmPeak)  : 2191.4 MB
  Peak resident set size usage (VmHWM): 815.01 MB
  Details saved in: 'mem.db'
====================================================================================================

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

TrigReport ---------- Modules in End-path ----------
TrigReport        Run    Success      Error Name
TrigReport          4          3          1 ana

TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 231.791482 Real = 235.779747

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 2191.4 VmHWM = 815.01

%MSG-s ArtException:  PostEndJob 26-Nov-2025 10:19:02 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 SingleHit/ana run: 40890 subRun: 1 event: 13124
      ---- 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
justIN time: 2025-12-19 04:35:47 UTC       justIN version: 01.05.03