justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 242413.118@dunegpschedd01.fnal.gov

Jobsub ID242413.118@dunegpschedd01.fnal.gov
Workflow ID9555
Stage ID1
User nameimawby@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes1048576000 (1000 MiB)
Wall seconds limit7200 (2 hours)
Submitted time2025-11-03 16:39:03
SiteNL_NIKHEF
EntryVIRGO_NL_NIKHEF_dissel_multicore
Last heartbeat2025-11-03 16:47:08
From worker nodeHostnamewn-lot-022.farm.nikhef.nl
cpuinfoAMD EPYC 7702P 64-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes1048576000 (1000 MiB)
Wall seconds limit129600 (36 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-11-03 16:40:19
Input filesfardet-hd:nu_dune10kt_1x2x6_1412_963_20230826T153837Z_gen_g4_detsim_hitreco__20240229T164022Z_reco2.root
JobscriptExit code0
Real time6m (390s)
CPU time4m (292s = 74%)
Max RSS bytes1341038592 (1278 MiB)
Outputting started2025-11-03 16:46:49
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/imawby/splitting_nu_0/fnal/09555/1/001/CheatingKalmanSplittingU_nu_dune10kt_1x2x6_1412_963_20230826T153837Z_gen_g4_detsim_hitreco__20240229T164022Z_reco2.root
Finished2025-11-03 16:47:08
Saved logsjustin-logs:242413.118-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

--------------------
Input PFN = root://mover.pp.rl.ac.uk:1094/pnfs/pp.rl.ac.uk/data/dune/fardet-hd/6d/2a/nu_dune10kt_1x2x6_1412_963_20230826T153837Z_gen_g4_detsim_hitreco__20240229T164022Z_reco2.root
lar exit code 0
=== Start last 100 lines of lar log file ===
Begin processing the 33rd record. run: 1412 subRun: 1 event: 96333 at 03-Nov-2025 17:43:45 CET
Begin processing the 34th record. run: 1412 subRun: 1 event: 96334 at 03-Nov-2025 17:43:48 CET
Begin processing the 35th record. run: 1412 subRun: 1 event: 96335 at 03-Nov-2025 17:43:50 CET
Begin processing the 36th record. run: 1412 subRun: 1 event: 96336 at 03-Nov-2025 17:43:53 CET
Begin processing the 37th record. run: 1412 subRun: 1 event: 96337 at 03-Nov-2025 17:43:56 CET
Begin processing the 38th record. run: 1412 subRun: 1 event: 96338 at 03-Nov-2025 17:43:58 CET
Begin processing the 39th record. run: 1412 subRun: 1 event: 96339 at 03-Nov-2025 17:44:01 CET
Begin processing the 40th record. run: 1412 subRun: 1 event: 96340 at 03-Nov-2025 17:44:04 CET
Begin processing the 41st record. run: 1412 subRun: 1 event: 96341 at 03-Nov-2025 17:44:06 CET
Begin processing the 42nd record. run: 1412 subRun: 1 event: 96342 at 03-Nov-2025 17:44:09 CET
Begin processing the 43rd record. run: 1412 subRun: 1 event: 96343 at 03-Nov-2025 17:44:13 CET
Begin processing the 44th record. run: 1412 subRun: 1 event: 96344 at 03-Nov-2025 17:44:15 CET
Begin processing the 45th record. run: 1412 subRun: 1 event: 96345 at 03-Nov-2025 17:44:18 CET
Begin processing the 46th record. run: 1412 subRun: 1 event: 96346 at 03-Nov-2025 17:44:20 CET
Begin processing the 47th record. run: 1412 subRun: 1 event: 96347 at 03-Nov-2025 17:44:24 CET
Begin processing the 48th record. run: 1412 subRun: 1 event: 96348 at 03-Nov-2025 17:44:27 CET
Begin processing the 49th record. run: 1412 subRun: 1 event: 96349 at 03-Nov-2025 17:44:29 CET
Begin processing the 50th record. run: 1412 subRun: 1 event: 96350 at 03-Nov-2025 17:44:32 CET
Begin processing the 51st record. run: 1412 subRun: 1 event: 96351 at 03-Nov-2025 17:44:35 CET
Begin processing the 52nd record. run: 1412 subRun: 1 event: 96352 at 03-Nov-2025 17:44:38 CET
Begin processing the 53rd record. run: 1412 subRun: 1 event: 96353 at 03-Nov-2025 17:44:40 CET
Begin processing the 54th record. run: 1412 subRun: 1 event: 96354 at 03-Nov-2025 17:44:43 CET
Begin processing the 55th record. run: 1412 subRun: 1 event: 96355 at 03-Nov-2025 17:44:46 CET
Begin processing the 56th record. run: 1412 subRun: 1 event: 96356 at 03-Nov-2025 17:44:49 CET
Begin processing the 57th record. run: 1412 subRun: 1 event: 96357 at 03-Nov-2025 17:44:52 CET
Begin processing the 58th record. run: 1412 subRun: 1 event: 96358 at 03-Nov-2025 17:44:54 CET
Begin processing the 59th record. run: 1412 subRun: 1 event: 96359 at 03-Nov-2025 17:44:57 CET
Begin processing the 60th record. run: 1412 subRun: 1 event: 96360 at 03-Nov-2025 17:44:59 CET
Begin processing the 61st record. run: 1412 subRun: 1 event: 96361 at 03-Nov-2025 17:45:02 CET
Begin processing the 62nd record. run: 1412 subRun: 1 event: 96362 at 03-Nov-2025 17:45:05 CET
Begin processing the 63rd record. run: 1412 subRun: 1 event: 96363 at 03-Nov-2025 17:45:08 CET
Begin processing the 64th record. run: 1412 subRun: 1 event: 96364 at 03-Nov-2025 17:45:11 CET
Begin processing the 65th record. run: 1412 subRun: 1 event: 96365 at 03-Nov-2025 17:45:13 CET
PandoraContentApi::GetList(*this, m_inputHitListName, pCaloHitList) return STATUS_CODE_NOT_INITIALIZED
    in function: GetVolumeIdToHitListMap
    in file:     /exp/dune/app/users/imawby/dunesw_splitting/srcs/larpandoracontent/larpandoracontent/LArControlFlow/MasterAlgorithm.cc line#: 271
this->GetVolumeIdToHitListMap(volumeIdToHitListMap) return STATUS_CODE_NOT_INITIALIZED
    in function: Run
    in file:     /exp/dune/app/users/imawby/dunesw_splitting/srcs/larpandoracontent/larpandoracontent/LArControlFlow/MasterAlgorithm.cc line#: 165
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, LArDLMaster, STATUS_CODE_NOT_INITIALIZED
Begin processing the 66th record. run: 1412 subRun: 1 event: 96366 at 03-Nov-2025 17:45:13 CET
Begin processing the 67th record. run: 1412 subRun: 1 event: 96367 at 03-Nov-2025 17:45:18 CET
Begin processing the 68th record. run: 1412 subRun: 1 event: 96368 at 03-Nov-2025 17:45:21 CET
Begin processing the 69th record. run: 1412 subRun: 1 event: 96369 at 03-Nov-2025 17:45:24 CET
Begin processing the 70th record. run: 1412 subRun: 1 event: 96370 at 03-Nov-2025 17:45:26 CET
Begin processing the 71st record. run: 1412 subRun: 1 event: 96371 at 03-Nov-2025 17:45:29 CET
Begin processing the 72nd record. run: 1412 subRun: 1 event: 96372 at 03-Nov-2025 17:45:32 CET
Begin processing the 73rd record. run: 1412 subRun: 1 event: 96373 at 03-Nov-2025 17:45:34 CET
Begin processing the 74th record. run: 1412 subRun: 1 event: 96374 at 03-Nov-2025 17:45:37 CET
Begin processing the 75th record. run: 1412 subRun: 1 event: 96375 at 03-Nov-2025 17:45:40 CET
Begin processing the 76th record. run: 1412 subRun: 1 event: 96376 at 03-Nov-2025 17:45:43 CET
Begin processing the 77th record. run: 1412 subRun: 1 event: 96377 at 03-Nov-2025 17:45:45 CET
Begin processing the 78th record. run: 1412 subRun: 1 event: 96378 at 03-Nov-2025 17:45:47 CET
Begin processing the 79th record. run: 1412 subRun: 1 event: 96379 at 03-Nov-2025 17:45:50 CET
Begin processing the 80th record. run: 1412 subRun: 1 event: 96380 at 03-Nov-2025 17:45:53 CET
Begin processing the 81st record. run: 1412 subRun: 1 event: 96381 at 03-Nov-2025 17:45:55 CET
Begin processing the 82nd record. run: 1412 subRun: 1 event: 96382 at 03-Nov-2025 17:45:57 CET
Begin processing the 83rd record. run: 1412 subRun: 1 event: 96383 at 03-Nov-2025 17:46:00 CET
Begin processing the 84th record. run: 1412 subRun: 1 event: 96384 at 03-Nov-2025 17:46:03 CET
Begin processing the 85th record. run: 1412 subRun: 1 event: 96385 at 03-Nov-2025 17:46:05 CET
Begin processing the 86th record. run: 1412 subRun: 1 event: 96386 at 03-Nov-2025 17:46:08 CET
Begin processing the 87th record. run: 1412 subRun: 1 event: 96387 at 03-Nov-2025 17:46:11 CET
Begin processing the 88th record. run: 1412 subRun: 1 event: 96388 at 03-Nov-2025 17:46:13 CET
Begin processing the 89th record. run: 1412 subRun: 1 event: 96389 at 03-Nov-2025 17:46:16 CET
Begin processing the 90th record. run: 1412 subRun: 1 event: 96390 at 03-Nov-2025 17:46:19 CET
Begin processing the 91st record. run: 1412 subRun: 1 event: 96391 at 03-Nov-2025 17:46:22 CET
Begin processing the 92nd record. run: 1412 subRun: 1 event: 96392 at 03-Nov-2025 17:46:24 CET
Begin processing the 93rd record. run: 1412 subRun: 1 event: 96393 at 03-Nov-2025 17:46:27 CET
Begin processing the 94th record. run: 1412 subRun: 1 event: 96394 at 03-Nov-2025 17:46:30 CET
Begin processing the 95th record. run: 1412 subRun: 1 event: 96395 at 03-Nov-2025 17:46:32 CET
Begin processing the 96th record. run: 1412 subRun: 1 event: 96396 at 03-Nov-2025 17:46:35 CET
Begin processing the 97th record. run: 1412 subRun: 1 event: 96397 at 03-Nov-2025 17:46:37 CET
Begin processing the 98th record. run: 1412 subRun: 1 event: 96398 at 03-Nov-2025 17:46:40 CET
Begin processing the 99th record. run: 1412 subRun: 1 event: 96399 at 03-Nov-2025 17:46:42 CET
Begin processing the 100th record. run: 1412 subRun: 1 event: 96400 at 03-Nov-2025 17:46:45 CET
03-Nov-2025 17:46:48 CET  Closed output file "nu_dune10kt_1x2x6_1412_963_20230826T153837Z_gen_g4_detsim_hitreco__20240229T164022Z_reco2_reco2.root"
03-Nov-2025 17:46:48 CET  Closed input file "root://mover.pp.rl.ac.uk:1094/pnfs/pp.rl.ac.uk/data/dune/fardet-hd/6d/2a/nu_dune10kt_1x2x6_1412_963_20230826T153837Z_gen_g4_detsim_hitreco__20240229T164022Z_reco2.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.0855041      2.47335       5.50369       2.36549      0.537463        100    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                         0.0189878     0.0262066     0.0468768     0.0232157    0.00661005       100    
reco:pandora:StandardPandora                   0.0651409      2.44299       5.45703       2.33559      0.535044        100    
[art]:TriggerResults:TriggerResultInserter    1.1191e-05    1.5659e-05    6.7286e-05    1.43665e-05   6.02947e-06      100    
end_path:out1:RootOutput                       2.615e-06    3.43489e-06   1.3315e-05     3.236e-06    1.08352e-06      100    
end_path:out1:RootOutput(write)               0.000880109   0.00396565     0.0266261    0.00314336    0.00318054       100    
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 2321.97 MB
  Peak resident set size usage (VmHWM): 1341.04 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:55:03 UTC       justIN version: 01.05.01