justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 242416.82@dunegpschedd01.fnal.gov

Jobsub ID242416.82@dunegpschedd01.fnal.gov
Workflow ID9556
Stage ID1
User nameimawby@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes4194304000 (4000 MiB)
Wall seconds limit43200 (12 hours)
Submitted time2025-11-03 16:45:05
SiteNL_SURFsara
EntryDUNE_SurfSARA_arc03
Last heartbeat2025-11-03 17:20:26
From worker nodeHostnamewn-la-25.gina.surf.nl
cpuinfoAMD EPYC 9754 128-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit129600 (36 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-11-03 17:12:59
Input filesfardet-hd:nu_dune10kt_1x2x6_1412_994_20230826T153848Z_gen_g4_detsim_hitreco__20240227T210247Z_reco2.root
JobscriptExit code0
Real time6m (412s)
CPU time6m (389s = 94%)
Max RSS bytes1293402112 (1233 MiB)
Outputting started2025-11-03 17:19:52
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/imawby/splitting_nu_1/fnal/09556/1/001/CheatingKalmanSplittingU_nu_dune10kt_1x2x6_1412_994_20230826T153848Z_gen_g4_detsim_hitreco__20240227T210247Z_reco2.root
Finished2025-11-03 17:20:26
Saved logsjustin-logs:242416.82-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

-----------------------
Input PFN = root://dune.dcache.nikhef.nl:1094/pnfs/nikhef.nl/data/dune/generic/rucio/fardet-hd/61/3c/nu_dune10kt_1x2x6_1412_994_20230826T153848Z_gen_g4_detsim_hitreco__20240227T210247Z_reco2.root
lar exit code 0
=== Start last 100 lines of lar log file ===
Begin processing the 38th record. run: 1412 subRun: 1 event: 99438 at 03-Nov-2025 18:16:20 CET
Begin processing the 39th record. run: 1412 subRun: 1 event: 99439 at 03-Nov-2025 18:16:23 CET
Begin processing the 40th record. run: 1412 subRun: 1 event: 99440 at 03-Nov-2025 18:16:26 CET
Begin processing the 41st record. run: 1412 subRun: 1 event: 99441 at 03-Nov-2025 18:16:30 CET
Begin processing the 42nd record. run: 1412 subRun: 1 event: 99442 at 03-Nov-2025 18:16:33 CET
Begin processing the 43rd record. run: 1412 subRun: 1 event: 99443 at 03-Nov-2025 18:16:36 CET
Begin processing the 44th record. run: 1412 subRun: 1 event: 99444 at 03-Nov-2025 18:16:39 CET
Begin processing the 45th record. run: 1412 subRun: 1 event: 99445 at 03-Nov-2025 18:16:42 CET
Begin processing the 46th record. run: 1412 subRun: 1 event: 99446 at 03-Nov-2025 18:16:47 CET
Begin processing the 47th record. run: 1412 subRun: 1 event: 99447 at 03-Nov-2025 18:16:50 CET
Begin processing the 48th record. run: 1412 subRun: 1 event: 99448 at 03-Nov-2025 18:16:53 CET
Begin processing the 49th record. run: 1412 subRun: 1 event: 99449 at 03-Nov-2025 18:16:56 CET
Begin processing the 50th record. run: 1412 subRun: 1 event: 99450 at 03-Nov-2025 18:16:59 CET
Begin processing the 51st record. run: 1412 subRun: 1 event: 99451 at 03-Nov-2025 18:17:02 CET
Begin processing the 52nd record. run: 1412 subRun: 1 event: 99452 at 03-Nov-2025 18:17:05 CET
Begin processing the 53rd record. run: 1412 subRun: 1 event: 99453 at 03-Nov-2025 18:17:09 CET
Begin processing the 54th record. run: 1412 subRun: 1 event: 99454 at 03-Nov-2025 18:17:12 CET
Begin processing the 55th record. run: 1412 subRun: 1 event: 99455 at 03-Nov-2025 18:17:15 CET
Begin processing the 56th record. run: 1412 subRun: 1 event: 99456 at 03-Nov-2025 18:17:18 CET
Begin processing the 57th record. run: 1412 subRun: 1 event: 99457 at 03-Nov-2025 18:17:21 CET
Begin processing the 58th record. run: 1412 subRun: 1 event: 99458 at 03-Nov-2025 18:17:24 CET
PandoraContentApi::GetList(*this, listname, pCaloHitList) return STATUS_CODE_NOT_INITIALIZED
    in function: Infer
    in file:     /exp/dune/app/users/imawby/dunesw_splitting/srcs/larpandoracontent/larpandoradlcontent/LArVertex/DlVertexingAlgorithm.cc line#: 192
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 Alg0002, LArDLVertexing, STATUS_CODE_NOT_INITIALIZED
DLVertexing: Input vertex list is empty! Can't perform pass 2
PandoraContentApi::GetList(*this, listname, pCaloHitList) return STATUS_CODE_NOT_INITIALIZED
    in function: Infer
    in file:     /exp/dune/app/users/imawby/dunesw_splitting/srcs/larpandoracontent/larpandoradlcontent/LArVertex/DlSecondaryVertexingAlgorithm.cc line#: 186
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 Alg0004, LArDLSecondaryVertexing, STATUS_CODE_NOT_INITIALIZED
Begin processing the 59th record. run: 1412 subRun: 1 event: 99459 at 03-Nov-2025 18:17:24 CET
Begin processing the 60th record. run: 1412 subRun: 1 event: 99460 at 03-Nov-2025 18:17:28 CET
Begin processing the 61st record. run: 1412 subRun: 1 event: 99461 at 03-Nov-2025 18:17:31 CET
Begin processing the 62nd record. run: 1412 subRun: 1 event: 99462 at 03-Nov-2025 18:17:34 CET
Begin processing the 63rd record. run: 1412 subRun: 1 event: 99463 at 03-Nov-2025 18:17:37 CET
Begin processing the 64th record. run: 1412 subRun: 1 event: 99464 at 03-Nov-2025 18:17:40 CET
Begin processing the 65th record. run: 1412 subRun: 1 event: 99465 at 03-Nov-2025 18:17:43 CET
Begin processing the 66th record. run: 1412 subRun: 1 event: 99466 at 03-Nov-2025 18:17:46 CET
Begin processing the 67th record. run: 1412 subRun: 1 event: 99467 at 03-Nov-2025 18:17:50 CET
Begin processing the 68th record. run: 1412 subRun: 1 event: 99468 at 03-Nov-2025 18:17:53 CET
Begin processing the 69th record. run: 1412 subRun: 1 event: 99469 at 03-Nov-2025 18:17:56 CET
Begin processing the 70th record. run: 1412 subRun: 1 event: 99470 at 03-Nov-2025 18:17:59 CET
Begin processing the 71st record. run: 1412 subRun: 1 event: 99471 at 03-Nov-2025 18:18:02 CET
Begin processing the 72nd record. run: 1412 subRun: 1 event: 99472 at 03-Nov-2025 18:18:05 CET
Begin processing the 73rd record. run: 1412 subRun: 1 event: 99473 at 03-Nov-2025 18:18:08 CET
Begin processing the 74th record. run: 1412 subRun: 1 event: 99474 at 03-Nov-2025 18:18:11 CET
Begin processing the 75th record. run: 1412 subRun: 1 event: 99475 at 03-Nov-2025 18:18:15 CET
Begin processing the 76th record. run: 1412 subRun: 1 event: 99476 at 03-Nov-2025 18:18:18 CET
Begin processing the 77th record. run: 1412 subRun: 1 event: 99477 at 03-Nov-2025 18:18:24 CET
Begin processing the 78th record. run: 1412 subRun: 1 event: 99478 at 03-Nov-2025 18:18:28 CET
Begin processing the 79th record. run: 1412 subRun: 1 event: 99479 at 03-Nov-2025 18:18:32 CET
Begin processing the 80th record. run: 1412 subRun: 1 event: 99480 at 03-Nov-2025 18:18:35 CET
Begin processing the 81st record. run: 1412 subRun: 1 event: 99481 at 03-Nov-2025 18:18:38 CET
Begin processing the 82nd record. run: 1412 subRun: 1 event: 99482 at 03-Nov-2025 18:18:41 CET
Begin processing the 83rd record. run: 1412 subRun: 1 event: 99483 at 03-Nov-2025 18:18:45 CET
Begin processing the 84th record. run: 1412 subRun: 1 event: 99484 at 03-Nov-2025 18:18:49 CET
Begin processing the 85th record. run: 1412 subRun: 1 event: 99485 at 03-Nov-2025 18:18:52 CET
Begin processing the 86th record. run: 1412 subRun: 1 event: 99486 at 03-Nov-2025 18:18:56 CET
Begin processing the 87th record. run: 1412 subRun: 1 event: 99487 at 03-Nov-2025 18:19:02 CET
Begin processing the 88th record. run: 1412 subRun: 1 event: 99488 at 03-Nov-2025 18:19:05 CET
Begin processing the 89th record. run: 1412 subRun: 1 event: 99489 at 03-Nov-2025 18:19:09 CET
Begin processing the 90th record. run: 1412 subRun: 1 event: 99490 at 03-Nov-2025 18:19:13 CET
Begin processing the 91st record. run: 1412 subRun: 1 event: 99491 at 03-Nov-2025 18:19:16 CET
Begin processing the 92nd record. run: 1412 subRun: 1 event: 99492 at 03-Nov-2025 18:19:19 CET
Begin processing the 93rd record. run: 1412 subRun: 1 event: 99493 at 03-Nov-2025 18:19:23 CET
Begin processing the 94th record. run: 1412 subRun: 1 event: 99494 at 03-Nov-2025 18:19:26 CET
Begin processing the 95th record. run: 1412 subRun: 1 event: 99495 at 03-Nov-2025 18:19:30 CET
Begin processing the 96th record. run: 1412 subRun: 1 event: 99496 at 03-Nov-2025 18:19:33 CET
Begin processing the 97th record. run: 1412 subRun: 1 event: 99497 at 03-Nov-2025 18:19:36 CET
Begin processing the 98th record. run: 1412 subRun: 1 event: 99498 at 03-Nov-2025 18:19:40 CET
Begin processing the 99th record. run: 1412 subRun: 1 event: 99499 at 03-Nov-2025 18:19:43 CET
Begin processing the 100th record. run: 1412 subRun: 1 event: 99500 at 03-Nov-2025 18:19:47 CET
03-Nov-2025 18:19:51 CET  Closed output file "nu_dune10kt_1x2x6_1412_994_20230826T153848Z_gen_g4_detsim_hitreco__20240227T210247Z_reco2_reco2.root"
03-Nov-2025 18:19:51 CET  Closed input file "root://dune.dcache.nikhef.nl:1094/pnfs/nikhef.nl/data/dune/generic/rucio/fardet-hd/61/3c/nu_dune10kt_1x2x6_1412_994_20230826T153848Z_gen_g4_detsim_hitreco__20240227T210247Z_reco2.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.0507022      3.30265       8.4645        3.16693      0.786763        100    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00393085     0.0611895      2.0857       0.0298525     0.231729        100    
reco:pandora:StandardPandora                   0.0179174      3.23636       6.36485       3.12123      0.659587        100    
[art]:TriggerResults:TriggerResultInserter    1.5403e-05    2.50784e-05   0.00013447    2.1938e-05    1.47205e-05      100    
end_path:out1:RootOutput                       4.616e-06    5.63304e-06   2.0701e-05     5.328e-06    1.80232e-06      100    
end_path:out1:RootOutput(write)               0.00124218    0.00483082     0.0270932    0.00423868    0.00371373       100    
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 2273.43 MB
  Peak resident set size usage (VmHWM): 1293.4 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
mv: cannot stat 'CheatingKalmanSplittingV.root': No such file or directory
mv: cannot stat 'CheatingKalmanSplittingW.root': No such file or directory
justIN time: 2025-11-04 01:41:37 UTC       justIN version: 01.05.01