Jobsub ID 276358.32@dunegpschedd02.fnal.gov

Jobsub ID	276358.32@dunegpschedd02.fnal.gov
Workflow ID	12103
Stage ID	1
User name	jlechevr@fnal.gov
Requested	Processors	4
	GPU	No
	RSS bytes	6815744000 (6500 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2026-01-19 14:47:16
Site	US_UChicago
Entry	Engage_US_MWT2_uiuc_condce_mcore
Last heartbeat	2026-01-19 14:52:40
From worker node	Hostname	uct2-c525.mwt2.org
	cpuinfo	AMD EPYC 7402 24-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	4
	RSS bytes	7864320000 (7500 MiB)
	Wall seconds limit	86400 (24 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Started	2026-01-19 14:51:13
Input files	vd-protodune-det-reco:np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup.root
Jobscript	Exit code	0
	Real time	0m (36s)
	CPU time	0m (28s = 77%)
	Max RSS bytes	652824576 (622 MiB)
Outputting started	2026-01-19 14:51:49
Output files	usertests:np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup_20260119T145119Z_michel.root
Finished	2026-01-19 14:52:40
Saved logs	justin-logs:276358.32-dunegpschedd02.fnal.gov.logs.tgz
List job events (HTCondor job logs unavailable)

Jobscript log (last 10,000 characters)

x64bit+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/47/84/np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup.root
did: vd-protodune-det-reco:np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup.root
Running reco stage1
%MSG-i MF_INIT_OK:  Early 19-Jan-2026 08:51:20 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 19-Jan-2026 08:51:26 CST JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore:  Early 19-Jan-2026 08:51:26 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 19-Jan-2026 08:51:26 CST JobSetup
Initializing CRPWireReadoutGeom channel mapping algorithm.
%MSG
%MSG-i CRPWireReadoutGeom:  Early 19-Jan-2026 08:51:26 CST JobSetup
Build readout planes for 1 16 3
%MSG
%MSG-i CRPWireReadoutGeom:  Early 19-Jan-2026 08:51:26 CST JobSetup
Counted 12288 channels.
%MSG
%MSG-i AuxDetGeometryCore:  Early 19-Jan-2026 08:51:26 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 19-Jan-2026 08:51:26 CST JobSetup
Loaded from configuration:
  - 0 bad channels
  - 0 noisy channels
  - largest channel ID: 12287, largest present: 12287
%MSG
%MSG-i setupProvider<DetectorPropertiesStandard>:  Early  19-Jan-2026 08:51:26 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 19-Jan-2026 08:51:26 CST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE:  Early 19-Jan-2026 08:51:26 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
19-Jan-2026 08:51:27 CST  Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/47/84/np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup.root"
19-Jan-2026 08:51:29 CST  Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/47/84/np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup.root"
db: runtime1759943064
Begin processing the 1st record. run: 39933 subRun: 1 event: 2541 at 19-Jan-2026 08:51:30 CST
Begin processing the 2nd record. run: 39933 subRun: 1 event: 2549 at 19-Jan-2026 08:51:31 CST
Begin processing the 3rd record. run: 39933 subRun: 1 event: 2557 at 19-Jan-2026 08:51:31 CST
Begin processing the 4th record. run: 39933 subRun: 1 event: 2565 at 19-Jan-2026 08:51:32 CST
Begin processing the 5th record. run: 39933 subRun: 1 event: 2573 at 19-Jan-2026 08:51:33 CST
Begin processing the 6th record. run: 39933 subRun: 1 event: 2581 at 19-Jan-2026 08:51:34 CST
Begin processing the 7th record. run: 39933 subRun: 1 event: 2589 at 19-Jan-2026 08:51:34 CST
Begin processing the 8th record. run: 39933 subRun: 1 event: 2597 at 19-Jan-2026 08:51:35 CST
[1;91mNo valid recob::Hit handle[0m
Begin processing the 9th record. run: 39933 subRun: 1 event: 2605 at 19-Jan-2026 08:51:35 CST
Begin processing the 10th record. run: 39933 subRun: 1 event: 2613 at 19-Jan-2026 08:51:36 CST
Begin processing the 11th record. run: 39933 subRun: 1 event: 2621 at 19-Jan-2026 08:51:36 CST
Begin processing the 12th record. run: 39933 subRun: 1 event: 2629 at 19-Jan-2026 08:51:38 CST
Begin processing the 13th record. run: 39933 subRun: 1 event: 2637 at 19-Jan-2026 08:51:40 CST
Begin processing the 14th record. run: 39933 subRun: 1 event: 2645 at 19-Jan-2026 08:51:40 CST
Begin processing the 15th record. run: 39933 subRun: 1 event: 2653 at 19-Jan-2026 08:51:41 CST
Begin processing the 16th record. run: 39933 subRun: 1 event: 2661 at 19-Jan-2026 08:51:42 CST
Begin processing the 17th record. run: 39933 subRun: 1 event: 2669 at 19-Jan-2026 08:51:42 CST
Begin processing the 18th record. run: 39933 subRun: 1 event: 2677 at 19-Jan-2026 08:51:43 CST
Begin processing the 19th record. run: 39933 subRun: 1 event: 2685 at 19-Jan-2026 08:51:44 CST
Begin processing the 20th record. run: 39933 subRun: 1 event: 2693 at 19-Jan-2026 08:51:45 CST
Begin processing the 21st record. run: 39933 subRun: 1 event: 2701 at 19-Jan-2026 08:51:45 CST
19-Jan-2026 08:51:46 CST  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/47/84/np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup.root"

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                        0.000774606     0.75182       2.03382      0.690263      0.407015        21     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.000162195   0.00290793     0.0170812    0.000287601   0.00517498       21     
end_path:checks:MichelAnalysis    0.000262874    0.748768       2.02258      0.687953      0.405903        21     
====================================================================================================================
%MSG-i NuRandomService:  MichelAnalysis:checks@EndJob 19-Jan-2026 08:51:46 CST  ModuleEndJob

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

%MSG

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

TrigReport ---------- Modules in End-path ----------
TrigReport        Run    Success      Error Name
TrigReport         21         21          0 checks

TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 11.620107 Real = 15.853806

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 2100.3 VmHWM = 639.324

Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup_20260119T145119Z_michel.root
Forming reco metadata
Ran successfully
{
  "name": "np02vd_raw_run039933_0015_df-s04-d1_dw_0_20251008T170426_reco_stage1_20251008T224408_keepup_20260119T145119Z_michel.root",
  "namespace": "usertests",
  "metadata": {
    "core.file_format": "root",
    "core.application.name": "reco",
    "core.application.family": "dunesw",
    "core.application.version": "v10_16_00d00",
    "core.data_tier": "root-tuple-virtual",
    "dune.config_file": "monfhicl.fcl",
    "dune.campaign": "usertests",
    "core.start_time": 1768834279.0,
    "core.end_time": 1768834306.0
  }
}