justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 267271.170@dunegpschedd02.fnal.gov

Jobsub ID267271.170@dunegpschedd02.fnal.gov
Workflow ID11638
Stage ID1
User nameykermaid@fnal.gov
RequestedProcessors1
GPUNo
RSS bytes4194304000 (4000 MiB)
Wall seconds limit3600 (1 hours)
Submitted time2026-01-09 13:34:20
SiteUS_FNAL-FermiGrid
EntryFNAL_GPGrid_ce04_mcore_op_duneonly
Last heartbeat2026-01-09 13:50:59
From worker nodeHostnamedunegli-8286776-0-fnpc19001.fnal.gov
cpuinfoIntel(R) Xeon(R) Platinum 8276 CPU @ 2.20GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit172800 (48 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2026-01-09 13:37:46
Input fileshd-protodune-det-reco:np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root
JobscriptExit code0
Real time12m (779s)
CPU time12m (770s = 98%)
Max RSS bytes1271169024 (1212 MiB)
Outputting started2026-01-09 13:50:46
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/ykermaid/fnal/11638/1/001/np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup_20260109T133802Z_singlehit.root
Finished2026-01-09 13:50:59
Saved logsjustin-logs:267271.170-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

.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/hd-protodune-det-reco/8f/26/np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root
did: hd-protodune-det-reco:np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root
output_file: np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup_20260109T133802Z_singlehit.root
Running reco stage1
%MSG-i MF_INIT_OK:  Early 09-Jan-2026 13:38:12 UTC JobSetup
Messagelogger initialization complete.
%MSG
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_16_00d00/gdml/protodunehd_v6_refactored.gdml
Info in <TGeoManager::TGeoManager>: Geometry GDMLImport, Geometry imported from GDML created
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 = 1148
Info in <TGeoManager::CloseGeometry>: 25610 nodes/ 5537 volume UID's in Geometry imported from GDML
Info in <TGeoManager::CloseGeometry>: ----------------modeler ready----------------
%MSG-i AuxDetGeometryCore:  Early 09-Jan-2026 13:38:16 UTC JobSetup
New detector geometry loaded from
	/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_16_00d00/gdml/protodunehd_v6_refactored.gdml
%MSG
%MSG-i GeometryCore:  Early 09-Jan-2026 13:38:16 UTC JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore:  Early 09-Jan-2026 13:38:16 UTC JobSetup
New detector geometry loaded from
	/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_16_00d00/gdml/protodunehd_v6_refactored.gdml
%MSG
%MSG-i DuneApaWireReadoutGeom:  Early 09-Jan-2026 13:38:16 UTC JobSetup
Initializing wire readout...
%MSG
Cryostat 0:
  10240 total channels
  4 APAs
  For all identical APA:
    Number of channels per APA = 2560
    U channels per APA = 800
    V channels per APA = 800
    Z channels per APA = 960
    Pitch in U Plane = 0.4669
    Pitch in V Plane = 0.4669
    Pitch in Z Plane = 0.4792
%MSG-i SimpleChannelStatusService:  Early 09-Jan-2026 13:38:16 UTC JobSetup
Loaded from configuration:
  - 0 bad channels
  - 0 noisy channels
  - largest channel ID: 10239, largest present: 10239
%MSG
%MSG-i setupProvider<DetectorPropertiesStandard>:  Early  09-Jan-2026 13:38:16 UTC JobSetup
Asked to ignore 1 keys: 'InheritNumberTimeSamples'
%MSG
IFDH constructor, got keys:
service_type, 
IFDH: didn't find a base uri...

09-Jan-2026 13:38:17 UTC  Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/8f/26/np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root"
09-Jan-2026 13:38:19 UTC  Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/8f/26/np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root"
Begin processing the 1st record. run: 28086 subRun: 1 event: 170695 at 09-Jan-2026 13:38:20 UTC
Begin processing the 2nd record. run: 28086 subRun: 1 event: 170719 at 09-Jan-2026 13:38:46 UTC
Begin processing the 3rd record. run: 28086 subRun: 1 event: 170727 at 09-Jan-2026 13:39:14 UTC
Begin processing the 4th record. run: 28086 subRun: 1 event: 170735 at 09-Jan-2026 13:39:20 UTC
Begin processing the 5th record. run: 28086 subRun: 1 event: 170743 at 09-Jan-2026 13:39:55 UTC
Begin processing the 6th record. run: 28086 subRun: 1 event: 170751 at 09-Jan-2026 13:40:28 UTC
Begin processing the 7th record. run: 28086 subRun: 1 event: 170759 at 09-Jan-2026 13:40:46 UTC
Begin processing the 8th record. run: 28086 subRun: 1 event: 170767 at 09-Jan-2026 13:41:17 UTC
Begin processing the 9th record. run: 28086 subRun: 1 event: 170775 at 09-Jan-2026 13:41:31 UTC
Begin processing the 10th record. run: 28086 subRun: 1 event: 170783 at 09-Jan-2026 13:42:06 UTC
Begin processing the 11th record. run: 28086 subRun: 1 event: 170791 at 09-Jan-2026 13:42:45 UTC
Begin processing the 12th record. run: 28086 subRun: 1 event: 170799 at 09-Jan-2026 13:43:55 UTC
Begin processing the 13th record. run: 28086 subRun: 1 event: 170807 at 09-Jan-2026 13:44:19 UTC
Begin processing the 14th record. run: 28086 subRun: 1 event: 170815 at 09-Jan-2026 13:44:33 UTC
Begin processing the 15th record. run: 28086 subRun: 1 event: 170823 at 09-Jan-2026 13:44:52 UTC
Begin processing the 16th record. run: 28086 subRun: 1 event: 170831 at 09-Jan-2026 13:44:59 UTC
Begin processing the 17th record. run: 28086 subRun: 1 event: 170839 at 09-Jan-2026 13:45:10 UTC
Begin processing the 18th record. run: 28086 subRun: 1 event: 170847 at 09-Jan-2026 13:45:54 UTC
Begin processing the 19th record. run: 28086 subRun: 1 event: 170855 at 09-Jan-2026 13:46:11 UTC
Begin processing the 20th record. run: 28086 subRun: 1 event: 170863 at 09-Jan-2026 13:46:21 UTC
Begin processing the 21st record. run: 28086 subRun: 1 event: 170871 at 09-Jan-2026 13:46:27 UTC
Begin processing the 22nd record. run: 28086 subRun: 1 event: 170879 at 09-Jan-2026 13:46:43 UTC
Begin processing the 23rd record. run: 28086 subRun: 1 event: 170887 at 09-Jan-2026 13:46:56 UTC
Begin processing the 24th record. run: 28086 subRun: 1 event: 170895 at 09-Jan-2026 13:47:06 UTC
Begin processing the 25th record. run: 28086 subRun: 1 event: 170903 at 09-Jan-2026 13:47:15 UTC
Begin processing the 26th record. run: 28086 subRun: 1 event: 170911 at 09-Jan-2026 13:49:17 UTC
Begin processing the 27th record. run: 28086 subRun: 1 event: 170919 at 09-Jan-2026 13:49:32 UTC
Begin processing the 28th record. run: 28086 subRun: 1 event: 170927 at 09-Jan-2026 13:49:40 UTC
Begin processing the 29th record. run: 28086 subRun: 1 event: 170935 at 09-Jan-2026 13:49:51 UTC
Begin processing the 30th record. run: 28086 subRun: 1 event: 170943 at 09-Jan-2026 13:50:01 UTC
Begin processing the 31st record. run: 28086 subRun: 1 event: 170959 at 09-Jan-2026 13:50:26 UTC
09-Jan-2026 13:50:42 UTC  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune-det-reco/8f/26/np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root"

====================================================================================================================
TimeTracker printout (sec)            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================
Full event                          5.62992       23.9156       121.12        16.6356       22.3781        31     
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read)            0.000336335   0.00342279     0.0172759    0.000470911   0.00567406       31     
end_path:ana:SingleHit              5.62939       23.912        121.107       16.6266       22.3755        31     
====================================================================================================================

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

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

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

TrigReport ---------- Modules in End-path ----------
TrigReport        Run    Success      Error Name
TrigReport         31         31          0 ana

TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 734.846039 Real = 741.499436

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 2301.16 VmHWM = 1271.17

Art has completed and will exit with status 0.
Output files:
\tHists: np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup_20260109T133802Z_singlehit.root
formed
{
  "name": "np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup_20260109T133802Z_singlehit.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": "runSingleHit_PDHD.fcl",
    "dune.campaign": "hd-protodune-sim-reco",
    "core.start_time": 1767966642.0,
    "core.end_time": 1767966642.0,
    "core.data_stream": "physics",
    "core.file_type": "detector",
    "core.run_type": "hd-protodune",
    "core.runs": [
      28086
    ],
    "core.runs_subruns": [
      2808600001
    ]
  },
  "parents": [
    {
      "did": "hd-protodune-det-reco:np04hd_raw_run028086_0688_dataflow7_datawriter_0_20240721T150643_reco_stage1_reco_stage2_20240811T001913_keepup.root"
    }
  ]
}
justIN time: 2026-02-04 13:01:03 UTC       justIN version: 01.06.00