justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 173719.0@justin-prod-sched02.dune.hep.ac.uk

Jobsub ID173719.0@justin-prod-sched02.dune.hep.ac.uk
Workflow ID6119
Stage ID1
User nameimawby@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes4194304000 (4000 MiB)
Wall seconds limit43200 (12 hours)
Submitted time2025-04-09 03:36:43
SiteCA_SFU
EntryDUNE_CA_SFU_lcg-ce3
Last heartbeat2025-04-09 04:21:16
From worker nodeHostnamecdr134.int.cedar.computecanada.ca
cpuinfoIntel(R) Xeon(R) CPU E5-2683 v4 @ 2.10GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit84598 (23 hours)
GPU
Inner Apptainer?True
Job statefinished
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2025-04-09 03:38:18
Input filesfardet-hd:nu_dune10kt_1x2x6_1429_423_20230828T003357Z_gen_g4_detsim_hitreco__20240224T092805Z_reco2.root
JobscriptExit code0
Real time18m (1119s)
CPU time6m (386s = 34%)
Max RSS bytes1334284288 (1272 MiB)
Outputting started2025-04-09 03:56:58
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/imawby/hierarchyRecoVal_nu_0/06119/1/001/HierarchyRecoPerformance_nu_dune10kt_1x2x6_1429_423_20230828T003357Z_gen_g4_detsim_hitreco__20240224T092805Z_reco2.root
Finished2025-04-09 04:21:16
Saved logsjustin-logs:173719.0-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

Justin processors: 1
Setting up larsoft UPS area... /cvmfs/larsoft.opensciencegrid.org
Setting up DUNE UPS area... /cvmfs/dune.opensciencegrid.org/products/dune/

MRB_PROJECT=larsoft
MRB_PROJECT_VERSION=v10_04_07
MRB_QUALS=e26:prof
MRB_TOP=/cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3
MRB_SOURCE=/cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3/srcs
MRB_BUILDDIR=/cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3/build_slf7.x86_64
MRB_INSTALL=/cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3/localProducts_larsoft_v10_04_07_e26_prof

PRODUCTS=/cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3/localProducts_larsoft_v10_04_07_e26_prof:/cvmfs/dune.opensciencegrid.org/products/dune:/cvmfs/larsoft.opensciencegrid.org/products:/cvmfs/larsoft.opensciencegrid.org/packages:/cvmfs/fermilab.opensciencegrid.org/products/common/db/
CETPKG_INSTALL=/cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3/localProducts_larsoft_v10_04_07_e26_prof

local product directory is /cvmfs/fifeuser4.opensciencegrid.org/sw/dune/094e1f323afca9188feedcf4e7e6e1104fdc11c3/localProducts_larsoft_v10_04_07_e26_prof
----------- this block should be empty ------------------
---------------------------------------------------------
Input PFN = root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/15/63/nu_dune10kt_1x2x6_1429_423_20230828T003357Z_gen_g4_detsim_hitreco__20240224T092805Z_reco2.root
lar exit code 0
=== Start last 100 lines of lar log file ===
Begin processing the 58th record. run: 1429 subRun: 1 event: 42358 at 08-Apr-2025 20:54:01 PDT
YES RECO: 321 GEN: 3
YES RECO: 2212 GEN: 4
YES RECO: -13 GEN: 4
Begin processing the 59th record. run: 1429 subRun: 1 event: 42359 at 08-Apr-2025 20:54:05 PDT
YES RECO: -13 GEN: 3
YES RECO: -11 GEN: 4
YES RECO: -211 GEN: 3
Begin processing the 60th record. run: 1429 subRun: 1 event: 42360 at 08-Apr-2025 20:54:08 PDT
Begin processing the 61st record. run: 1429 subRun: 1 event: 42361 at 08-Apr-2025 20:54:11 PDT
Begin processing the 62nd record. run: 1429 subRun: 1 event: 42362 at 08-Apr-2025 20:54:16 PDT
Begin processing the 63rd record. run: 1429 subRun: 1 event: 42363 at 08-Apr-2025 20:54:19 PDT
YES RECO: 2212 GEN: 3
YES RECO: 2212 GEN: 3
YES RECO: -211 GEN: 3
Begin processing the 64th record. run: 1429 subRun: 1 event: 42364 at 08-Apr-2025 20:54:23 PDT
Begin processing the 65th record. run: 1429 subRun: 1 event: 42365 at 08-Apr-2025 20:54:26 PDT
Begin processing the 66th record. run: 1429 subRun: 1 event: 42366 at 08-Apr-2025 20:54:29 PDT
Begin processing the 67th record. run: 1429 subRun: 1 event: 42367 at 08-Apr-2025 20:54:33 PDT
Begin processing the 68th record. run: 1429 subRun: 1 event: 42368 at 08-Apr-2025 20:54:36 PDT
YES RECO: 22 GEN: 3
YES RECO: -11 GEN: 3
YES RECO: 11 GEN: 3
Begin processing the 69th record. run: 1429 subRun: 1 event: 42369 at 08-Apr-2025 20:54:40 PDT
Begin processing the 70th record. run: 1429 subRun: 1 event: 42370 at 08-Apr-2025 20:54:43 PDT
YES RECO: 11 GEN: 3
YES RECO: 2212 GEN: 3
Begin processing the 71st record. run: 1429 subRun: 1 event: 42371 at 08-Apr-2025 20:54:48 PDT
Begin processing the 72nd record. run: 1429 subRun: 1 event: 42372 at 08-Apr-2025 20:54:51 PDT
YES RECO: 22 GEN: 3
YES RECO: 22 GEN: 3
YES RECO: -11 GEN: 3
Begin processing the 73rd record. run: 1429 subRun: 1 event: 42373 at 08-Apr-2025 20:55:00 PDT
Begin processing the 74th record. run: 1429 subRun: 1 event: 42374 at 08-Apr-2025 20:55:04 PDT
Begin processing the 75th record. run: 1429 subRun: 1 event: 42375 at 08-Apr-2025 20:55:07 PDT
Begin processing the 76th record. run: 1429 subRun: 1 event: 42376 at 08-Apr-2025 20:55:10 PDT
Begin processing the 77th record. run: 1429 subRun: 1 event: 42377 at 08-Apr-2025 20:55:13 PDT
Begin processing the 78th record. run: 1429 subRun: 1 event: 42378 at 08-Apr-2025 20:55:18 PDT
YES RECO: 321 GEN: 3
YES RECO: 321 GEN: 4
YES RECO: -211 GEN: 3
Begin processing the 79th record. run: 1429 subRun: 1 event: 42379 at 08-Apr-2025 20:55:24 PDT
Begin processing the 80th record. run: 1429 subRun: 1 event: 42380 at 08-Apr-2025 20:55:28 PDT
PandoraContentApi::GetList(*this, m_pfoListName, pPfoList) return STATUS_CODE_NOT_INITIALIZED
    in function: Run
    in file:     /exp/dune/app/users/imawby/dunesw_pandoraBugFix/srcs/larpandoracontent/larpandoracontent/LArMonitoring/SecondaryValidationAlgorithm.cc line#: 55
iter->second->Run() throw STATUS_CODE_NOT_INITIALIZED
    in function: RunAlgorithm
    in file:     /scratch/workspace/build-larbase/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/SLF7/build/pandora/v03_16_00l/src/pandora-v03-16-00/PandoraSDK-v03-04-01/src/Api/PandoraContentApiImpl.cc line#: 235
Failure in algorithm Alg0003, LArSecondaryValidation, STATUS_CODE_NOT_INITIALIZED
Begin processing the 81st record. run: 1429 subRun: 1 event: 42381 at 08-Apr-2025 20:55:31 PDT
Begin processing the 82nd record. run: 1429 subRun: 1 event: 42382 at 08-Apr-2025 20:55:35 PDT
Begin processing the 83rd record. run: 1429 subRun: 1 event: 42383 at 08-Apr-2025 20:55:38 PDT
Begin processing the 84th record. run: 1429 subRun: 1 event: 42384 at 08-Apr-2025 20:55:41 PDT
Begin processing the 85th record. run: 1429 subRun: 1 event: 42385 at 08-Apr-2025 20:55:45 PDT
Begin processing the 86th record. run: 1429 subRun: 1 event: 42386 at 08-Apr-2025 20:55:49 PDT
Begin processing the 87th record. run: 1429 subRun: 1 event: 42387 at 08-Apr-2025 20:55:52 PDT
Begin processing the 88th record. run: 1429 subRun: 1 event: 42388 at 08-Apr-2025 20:55:56 PDT
Begin processing the 89th record. run: 1429 subRun: 1 event: 42389 at 08-Apr-2025 20:56:01 PDT
YES RECO: 2212 GEN: 3
Begin processing the 90th record. run: 1429 subRun: 1 event: 42390 at 08-Apr-2025 20:56:04 PDT
Begin processing the 91st record. run: 1429 subRun: 1 event: 42391 at 08-Apr-2025 20:56:09 PDT
Begin processing the 92nd record. run: 1429 subRun: 1 event: 42392 at 08-Apr-2025 20:56:12 PDT
YES RECO: 211 GEN: 3
YES RECO: 2212 GEN: 3
Begin processing the 93rd record. run: 1429 subRun: 1 event: 42393 at 08-Apr-2025 20:56:16 PDT
YES RECO: 211 GEN: 3
YES RECO: 2212 GEN: 3
Begin processing the 94th record. run: 1429 subRun: 1 event: 42394 at 08-Apr-2025 20:56:20 PDT
Begin processing the 95th record. run: 1429 subRun: 1 event: 42395 at 08-Apr-2025 20:56:24 PDT
Begin processing the 96th record. run: 1429 subRun: 1 event: 42396 at 08-Apr-2025 20:56:30 PDT
YES RECO: 11 GEN: 3
Begin processing the 97th record. run: 1429 subRun: 1 event: 42397 at 08-Apr-2025 20:56:35 PDT
YES RECO: -11 GEN: 3
Begin processing the 98th record. run: 1429 subRun: 1 event: 42398 at 08-Apr-2025 20:56:42 PDT
Begin processing the 99th record. run: 1429 subRun: 1 event: 42399 at 08-Apr-2025 20:56:46 PDT
YES RECO: 11 GEN: 3
Begin processing the 100th record. run: 1429 subRun: 1 event: 42400 at 08-Apr-2025 20:56:51 PDT
08-Apr-2025 20:56:57 PDT  Closed output file "nu_dune10kt_1x2x6_1429_423_20230828T003357Z_gen_g4_detsim_hitreco__20240224T092805Z_reco2_reco2.root"
08-Apr-2025 20:56:57 PDT  Closed input file "root://fndca1.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/15/63/nu_dune10kt_1x2x6_1429_423_20230828T003357Z_gen_g4_detsim_hitreco__20240224T092805Z_reco2.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.446087       3.48999       41.546        2.50062       4.59811        100    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                         0.0717465     0.130735      0.452686      0.123156      0.0444062       100    
reco:pandora:StandardPandora                   0.329977       3.34398       41.2888       2.3385        4.58527        100    
[art]:TriggerResults:TriggerResultInserter    1.7746e-05    2.78596e-05   9.1801e-05    2.6843e-05    8.53202e-06      100    
end_path:out1:RootOutput                       3.012e-06    4.92486e-06   2.2257e-05    3.6755e-06    2.43628e-06      100    
end_path:out1:RootOutput(write)               0.00117142     0.0150165     0.110451      0.0119157     0.0150702       100    
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 2313.54 MB
  Peak resident set size usage (VmHWM): 1334.28 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
justIN time: 2025-05-26 04:45:10 UTC       justIN version: 01.03.01