Jobsub ID 242426.3@dunegpschedd01.fnal.gov
| Jobsub ID | 242426.3@dunegpschedd01.fnal.gov | 
| Workflow ID | 9555 | 
| Stage ID | 1 | 
| User name | imawby@fnal.gov | 
| HTCondor Group | group_dune | 
| Requested | Processors | 1 | 
| GPU | No | 
| RSS bytes | 1048576000 (1000 MiB) | 
| Wall seconds limit | 7200 (2 hours) | 
| Submitted time | 2025-11-03 16:53:06 | 
| Site | UK_RAL-Tier1 | 
| Entry | LIGO_UK_RAL_arc_ce03 | 
| Last heartbeat | 2025-11-03 17:02:13 | 
| From worker node | Hostname | dune001-78010.0-lcg2485.gridpp.rl.ac.uk | 
| cpuinfo | AMD EPYC 7452 32-Core Processor | 
| OS release | Scientific Linux release 7.9 (Nitrogen) | 
| Processors | 1 | 
| RSS bytes | 1048576000 (1000 MiB) | 
| Wall seconds limit | 216000 (60 hours) | 
| GPU |  | 
| Inner Apptainer? | True | 
| Job state | finished | 
| Started | 2025-11-03 16:53:57 | 
| Input files | fardet-hd:nu_dune10kt_1x2x6_1100_207_20230826T033506Z_gen_g4_detsim_hitreco__20240227T210120Z_reco2.root
  | 
| Jobscript | Exit code | 0 | 
| Real time | 7m (467s) | 
| CPU time | 7m (436s = 93%) | 
| Max RSS bytes | 1394757632 (1330 MiB) | 
| Outputting started | 2025-11-03 17:01:45 | 
| Output files | https://fndcadoor.fnal.gov:2880/dune/scratch/users/imawby/splitting_nu_0/fnal/09555/1/001/CheatingKalmanSplittingU_nu_dune10kt_1x2x6_1100_207_20230826T033506Z_gen_g4_detsim_hitreco__20240227T210120Z_reco2.root
  | 
| Finished | 2025-11-03 17:02:13 | 
| Saved logs | justin-logs:242426.3-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/36/c5/nu_dune10kt_1x2x6_1100_207_20230826T033506Z_gen_g4_detsim_hitreco__20240227T210120Z_reco2.root
lar exit code 0
=== Start last 100 lines of lar log file ===
Begin processing the 33rd record. run: 1100 subRun: 1 event: 20733 at 03-Nov-2025 16:57:14 UTC
Begin processing the 34th record. run: 1100 subRun: 1 event: 20734 at 03-Nov-2025 16:57:18 UTC
Begin processing the 35th record. run: 1100 subRun: 1 event: 20735 at 03-Nov-2025 16:57:21 UTC
Begin processing the 36th record. run: 1100 subRun: 1 event: 20736 at 03-Nov-2025 16:57:24 UTC
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 37th record. run: 1100 subRun: 1 event: 20737 at 03-Nov-2025 16:57:24 UTC
Begin processing the 38th record. run: 1100 subRun: 1 event: 20738 at 03-Nov-2025 16:57:27 UTC
Begin processing the 39th record. run: 1100 subRun: 1 event: 20739 at 03-Nov-2025 16:57:38 UTC
Begin processing the 40th record. run: 1100 subRun: 1 event: 20740 at 03-Nov-2025 16:57:43 UTC
Begin processing the 41st record. run: 1100 subRun: 1 event: 20741 at 03-Nov-2025 16:57:47 UTC
Begin processing the 42nd record. run: 1100 subRun: 1 event: 20742 at 03-Nov-2025 16:57:51 UTC
Begin processing the 43rd record. run: 1100 subRun: 1 event: 20743 at 03-Nov-2025 16:57:55 UTC
Begin processing the 44th record. run: 1100 subRun: 1 event: 20744 at 03-Nov-2025 16:57:59 UTC
Begin processing the 45th record. run: 1100 subRun: 1 event: 20745 at 03-Nov-2025 16:58:02 UTC
Begin processing the 46th record. run: 1100 subRun: 1 event: 20746 at 03-Nov-2025 16:58:13 UTC
Begin processing the 47th record. run: 1100 subRun: 1 event: 20747 at 03-Nov-2025 16:58:17 UTC
Begin processing the 48th record. run: 1100 subRun: 1 event: 20748 at 03-Nov-2025 16:58:21 UTC
Begin processing the 49th record. run: 1100 subRun: 1 event: 20749 at 03-Nov-2025 16:58:24 UTC
Begin processing the 50th record. run: 1100 subRun: 1 event: 20750 at 03-Nov-2025 16:58:29 UTC
Begin processing the 51st record. run: 1100 subRun: 1 event: 20751 at 03-Nov-2025 16:58:32 UTC
Begin processing the 52nd record. run: 1100 subRun: 1 event: 20752 at 03-Nov-2025 16:58:35 UTC
Begin processing the 53rd record. run: 1100 subRun: 1 event: 20753 at 03-Nov-2025 16:58:39 UTC
Begin processing the 54th record. run: 1100 subRun: 1 event: 20754 at 03-Nov-2025 16:58:42 UTC
Begin processing the 55th record. run: 1100 subRun: 1 event: 20755 at 03-Nov-2025 16:58:47 UTC
Begin processing the 56th record. run: 1100 subRun: 1 event: 20756 at 03-Nov-2025 16:58:50 UTC
Begin processing the 57th record. run: 1100 subRun: 1 event: 20757 at 03-Nov-2025 16:58:54 UTC
Begin processing the 58th record. run: 1100 subRun: 1 event: 20758 at 03-Nov-2025 16:58:57 UTC
Begin processing the 59th record. run: 1100 subRun: 1 event: 20759 at 03-Nov-2025 16:59:01 UTC
Begin processing the 60th record. run: 1100 subRun: 1 event: 20760 at 03-Nov-2025 16:59:07 UTC
Begin processing the 61st record. run: 1100 subRun: 1 event: 20761 at 03-Nov-2025 16:59:10 UTC
Begin processing the 62nd record. run: 1100 subRun: 1 event: 20762 at 03-Nov-2025 16:59:13 UTC
Begin processing the 63rd record. run: 1100 subRun: 1 event: 20763 at 03-Nov-2025 16:59:17 UTC
Begin processing the 64th record. run: 1100 subRun: 1 event: 20764 at 03-Nov-2025 16:59:21 UTC
Begin processing the 65th record. run: 1100 subRun: 1 event: 20765 at 03-Nov-2025 16:59:24 UTC
Begin processing the 66th record. run: 1100 subRun: 1 event: 20766 at 03-Nov-2025 16:59:30 UTC
Begin processing the 67th record. run: 1100 subRun: 1 event: 20767 at 03-Nov-2025 16:59:34 UTC
Begin processing the 68th record. run: 1100 subRun: 1 event: 20768 at 03-Nov-2025 16:59:37 UTC
Begin processing the 69th record. run: 1100 subRun: 1 event: 20769 at 03-Nov-2025 16:59:40 UTC
Begin processing the 70th record. run: 1100 subRun: 1 event: 20770 at 03-Nov-2025 16:59:44 UTC
Begin processing the 71st record. run: 1100 subRun: 1 event: 20771 at 03-Nov-2025 16:59:47 UTC
Begin processing the 72nd record. run: 1100 subRun: 1 event: 20772 at 03-Nov-2025 16:59:51 UTC
Begin processing the 73rd record. run: 1100 subRun: 1 event: 20773 at 03-Nov-2025 16:59:54 UTC
Begin processing the 74th record. run: 1100 subRun: 1 event: 20774 at 03-Nov-2025 16:59:58 UTC
Begin processing the 75th record. run: 1100 subRun: 1 event: 20775 at 03-Nov-2025 17:00:03 UTC
Begin processing the 76th record. run: 1100 subRun: 1 event: 20776 at 03-Nov-2025 17:00:07 UTC
Begin processing the 77th record. run: 1100 subRun: 1 event: 20777 at 03-Nov-2025 17:00:11 UTC
Begin processing the 78th record. run: 1100 subRun: 1 event: 20778 at 03-Nov-2025 17:00:14 UTC
Begin processing the 79th record. run: 1100 subRun: 1 event: 20779 at 03-Nov-2025 17:00:18 UTC
Begin processing the 80th record. run: 1100 subRun: 1 event: 20780 at 03-Nov-2025 17:00:21 UTC
Begin processing the 81st record. run: 1100 subRun: 1 event: 20781 at 03-Nov-2025 17:00:25 UTC
Begin processing the 82nd record. run: 1100 subRun: 1 event: 20782 at 03-Nov-2025 17:00:29 UTC
Begin processing the 83rd record. run: 1100 subRun: 1 event: 20783 at 03-Nov-2025 17:00:32 UTC
Begin processing the 84th record. run: 1100 subRun: 1 event: 20784 at 03-Nov-2025 17:00:36 UTC
Begin processing the 85th record. run: 1100 subRun: 1 event: 20785 at 03-Nov-2025 17:00:38 UTC
Begin processing the 86th record. run: 1100 subRun: 1 event: 20786 at 03-Nov-2025 17:00:42 UTC
Begin processing the 87th record. run: 1100 subRun: 1 event: 20787 at 03-Nov-2025 17:00:45 UTC
Begin processing the 88th record. run: 1100 subRun: 1 event: 20788 at 03-Nov-2025 17:00:48 UTC
Begin processing the 89th record. run: 1100 subRun: 1 event: 20789 at 03-Nov-2025 17:00:51 UTC
Begin processing the 90th record. run: 1100 subRun: 1 event: 20790 at 03-Nov-2025 17:00:55 UTC
Begin processing the 91st record. run: 1100 subRun: 1 event: 20791 at 03-Nov-2025 17:00:59 UTC
Begin processing the 92nd record. run: 1100 subRun: 1 event: 20792 at 03-Nov-2025 17:01:02 UTC
Begin processing the 93rd record. run: 1100 subRun: 1 event: 20793 at 03-Nov-2025 17:01:16 UTC
Begin processing the 94th record. run: 1100 subRun: 1 event: 20794 at 03-Nov-2025 17:01:19 UTC
Begin processing the 95th record. run: 1100 subRun: 1 event: 20795 at 03-Nov-2025 17:01:23 UTC
Begin processing the 96th record. run: 1100 subRun: 1 event: 20796 at 03-Nov-2025 17:01:26 UTC
Begin processing the 97th record. run: 1100 subRun: 1 event: 20797 at 03-Nov-2025 17:01:30 UTC
Begin processing the 98th record. run: 1100 subRun: 1 event: 20798 at 03-Nov-2025 17:01:33 UTC
Begin processing the 99th record. run: 1100 subRun: 1 event: 20799 at 03-Nov-2025 17:01:36 UTC
Begin processing the 100th record. run: 1100 subRun: 1 event: 20800 at 03-Nov-2025 17:01:39 UTC
03-Nov-2025 17:01:43 UTC  Closed output file "nu_dune10kt_1x2x6_1100_207_20230826T033506Z_gen_g4_detsim_hitreco__20240227T210120Z_reco2_reco2.root"
03-Nov-2025 17:01:43 UTC  Closed input file "root://mover.pp.rl.ac.uk:1094/pnfs/pp.rl.ac.uk/data/dune/fardet-hd/36/c5/nu_dune10kt_1x2x6_1100_207_20230826T033506Z_gen_g4_detsim_hitreco__20240227T210120Z_reco2.root"
================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.0227504      3.67301       12.6351       3.36191       1.48099        100    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00320549     0.0200377     0.0498421     0.0206107     0.0101651       100    
reco:pandora:StandardPandora                   0.0161859      3.64484       12.5752       3.33854       1.47396        100    
[art]:TriggerResults:TriggerResultInserter    1.7411e-05    3.38697e-05   0.000131981    3.064e-05    1.70884e-05      100    
end_path:out1:RootOutput                       4.08e-06     7.16218e-06    3.116e-05     7.35e-06     3.5401e-06       100    
end_path:out1:RootOutput(write)               0.00134419    0.00776983     0.0392934    0.00613133    0.00629315       100    
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
  Peak virtual memory usage (VmPeak)  : 2442.22 MB
  Peak resident set size usage (VmHWM): 1394.76 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