Jobsub ID 279276.173@dunegpschedd01.fnal.gov

Jobsub ID	279276.173@dunegpschedd01.fnal.gov
Workflow ID	11972
Stage ID	1
User name	imawby@fnal.gov
Requested	Processors	1
	GPU	No
	RSS bytes	1048576000 (1000 MiB)
	Wall seconds limit	7200 (2 hours)
Submitted time	2026-01-15 19:36:14
Site	UK_Bristol
Entry	CMSHTPC_T2_UK_SGrid_Bristol_lcgce02
Last heartbeat	2026-01-15 20:49:36
From worker node	Hostname	hd68.dice.priv
	cpuinfo	AMD EPYC 7551P 32-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	1048576000 (1000 MiB)
	Wall seconds limit	259200 (72 hours)
	GPU
	Inner Apptainer?	True
Job state	outputting_failed
Started	2026-01-15 19:37:01
Input files	fardet-hd:nu_dune10kt_1x2x6_1413_478_20230826T183336Z_gen_g4_detsim_hitreco__20240229T191223Z_reco2.root
Jobscript	Exit code	0
	Real time	13m (794s)
	CPU time	9m (599s = 75%)
	Max RSS bytes	1222909952 (1166 MiB)
Outputting started	2026-01-15 19:50:16
Output files
Finished	2026-01-15 20:49:36
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

Products_larsoft_v10_13_00_e26_prof
----------- this block should be empty ------------------
---------------------------------------------------------
Input PFN = root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/fardet-hd/03/e3/nu_dune10kt_1x2x6_1413_478_20230826T183336Z_gen_g4_detsim_hitreco__20240229T191223Z_reco2.root
lar exit code 0
=== Start last 100 lines of lar log file ===
Begin processing the 26th record. run: 1413 subRun: 1 event: 47826 at 15-Jan-2026 19:41:32 UTC
Begin processing the 27th record. run: 1413 subRun: 1 event: 47827 at 15-Jan-2026 19:41:37 UTC
Begin processing the 28th record. run: 1413 subRun: 1 event: 47828 at 15-Jan-2026 19:41:43 UTC
Begin processing the 29th record. run: 1413 subRun: 1 event: 47829 at 15-Jan-2026 19:41:49 UTC
Begin processing the 30th record. run: 1413 subRun: 1 event: 47830 at 15-Jan-2026 19:41:55 UTC
Begin processing the 31st record. run: 1413 subRun: 1 event: 47831 at 15-Jan-2026 19:42:02 UTC
Begin processing the 32nd record. run: 1413 subRun: 1 event: 47832 at 15-Jan-2026 19:42:15 UTC
Begin processing the 33rd record. run: 1413 subRun: 1 event: 47833 at 15-Jan-2026 19:42:22 UTC
Begin processing the 34th record. run: 1413 subRun: 1 event: 47834 at 15-Jan-2026 19:42:28 UTC
Begin processing the 35th record. run: 1413 subRun: 1 event: 47835 at 15-Jan-2026 19:42:33 UTC
Begin processing the 36th record. run: 1413 subRun: 1 event: 47836 at 15-Jan-2026 19:42:39 UTC
Begin processing the 37th record. run: 1413 subRun: 1 event: 47837 at 15-Jan-2026 19:42:49 UTC
Begin processing the 38th record. run: 1413 subRun: 1 event: 47838 at 15-Jan-2026 19:42:55 UTC
Begin processing the 39th record. run: 1413 subRun: 1 event: 47839 at 15-Jan-2026 19:43:00 UTC
Begin processing the 40th record. run: 1413 subRun: 1 event: 47840 at 15-Jan-2026 19:43:05 UTC
Begin processing the 41st record. run: 1413 subRun: 1 event: 47841 at 15-Jan-2026 19:43:21 UTC
Begin processing the 42nd record. run: 1413 subRun: 1 event: 47842 at 15-Jan-2026 19:43:28 UTC
Begin processing the 43rd record. run: 1413 subRun: 1 event: 47843 at 15-Jan-2026 19:43:33 UTC
Begin processing the 44th record. run: 1413 subRun: 1 event: 47844 at 15-Jan-2026 19:43:39 UTC
Begin processing the 45th record. run: 1413 subRun: 1 event: 47845 at 15-Jan-2026 19:43:45 UTC
Begin processing the 46th record. run: 1413 subRun: 1 event: 47846 at 15-Jan-2026 19:43:53 UTC
Begin processing the 47th record. run: 1413 subRun: 1 event: 47847 at 15-Jan-2026 19:43:58 UTC
Begin processing the 48th record. run: 1413 subRun: 1 event: 47848 at 15-Jan-2026 19:44:05 UTC
Begin processing the 49th record. run: 1413 subRun: 1 event: 47849 at 15-Jan-2026 19:44:10 UTC
Begin processing the 50th record. run: 1413 subRun: 1 event: 47850 at 15-Jan-2026 19:44:19 UTC
Begin processing the 51st record. run: 1413 subRun: 1 event: 47851 at 15-Jan-2026 19:44:28 UTC
Begin processing the 52nd record. run: 1413 subRun: 1 event: 47852 at 15-Jan-2026 19:44:33 UTC
Begin processing the 53rd record. run: 1413 subRun: 1 event: 47853 at 15-Jan-2026 19:44:39 UTC
Begin processing the 54th record. run: 1413 subRun: 1 event: 47854 at 15-Jan-2026 19:44:44 UTC
Begin processing the 55th record. run: 1413 subRun: 1 event: 47855 at 15-Jan-2026 19:44:50 UTC
Begin processing the 56th record. run: 1413 subRun: 1 event: 47856 at 15-Jan-2026 19:44:57 UTC
Begin processing the 57th record. run: 1413 subRun: 1 event: 47857 at 15-Jan-2026 19:45:02 UTC
Begin processing the 58th record. run: 1413 subRun: 1 event: 47858 at 15-Jan-2026 19:45:09 UTC
Begin processing the 59th record. run: 1413 subRun: 1 event: 47859 at 15-Jan-2026 19:45:14 UTC
Begin processing the 60th record. run: 1413 subRun: 1 event: 47860 at 15-Jan-2026 19:45:23 UTC
Begin processing the 61st record. run: 1413 subRun: 1 event: 47861 at 15-Jan-2026 19:45:28 UTC
Begin processing the 62nd record. run: 1413 subRun: 1 event: 47862 at 15-Jan-2026 19:45:41 UTC
Begin processing the 63rd record. run: 1413 subRun: 1 event: 47863 at 15-Jan-2026 19:45:47 UTC
Begin processing the 64th record. run: 1413 subRun: 1 event: 47864 at 15-Jan-2026 19:45:53 UTC
Begin processing the 65th record. run: 1413 subRun: 1 event: 47865 at 15-Jan-2026 19:46:15 UTC
Begin processing the 66th record. run: 1413 subRun: 1 event: 47866 at 15-Jan-2026 19:46:20 UTC
Begin processing the 67th record. run: 1413 subRun: 1 event: 47867 at 15-Jan-2026 19:46:26 UTC
Begin processing the 68th record. run: 1413 subRun: 1 event: 47868 at 15-Jan-2026 19:46:32 UTC
Begin processing the 69th record. run: 1413 subRun: 1 event: 47869 at 15-Jan-2026 19:46:37 UTC
Begin processing the 70th record. run: 1413 subRun: 1 event: 47870 at 15-Jan-2026 19:46:44 UTC
Begin processing the 71st record. run: 1413 subRun: 1 event: 47871 at 15-Jan-2026 19:46:50 UTC
Begin processing the 72nd record. run: 1413 subRun: 1 event: 47872 at 15-Jan-2026 19:46:57 UTC
Begin processing the 73rd record. run: 1413 subRun: 1 event: 47873 at 15-Jan-2026 19:47:03 UTC
Begin processing the 74th record. run: 1413 subRun: 1 event: 47874 at 15-Jan-2026 19:47:10 UTC
PandoraContentApi::GetList(*this, listName, pList) return STATUS_CODE_NOT_INITIALIZED
    in function: GetList
    in file:     /exp/dune/app/users/imawby/dunesw_validation/srcs/larpandoracontent/larpandoradlcontent/LArTwoDReco/DLThreeDClusterSplittingAlgorithm.cc line#: 194
Begin processing the 75th record. run: 1413 subRun: 1 event: 47875 at 15-Jan-2026 19:47:15 UTC
Begin processing the 76th record. run: 1413 subRun: 1 event: 47876 at 15-Jan-2026 19:47:21 UTC
Begin processing the 77th record. run: 1413 subRun: 1 event: 47877 at 15-Jan-2026 19:47:26 UTC
Begin processing the 78th record. run: 1413 subRun: 1 event: 47878 at 15-Jan-2026 19:47:33 UTC
Begin processing the 79th record. run: 1413 subRun: 1 event: 47879 at 15-Jan-2026 19:47:39 UTC
Begin processing the 80th record. run: 1413 subRun: 1 event: 47880 at 15-Jan-2026 19:47:46 UTC
Begin processing the 81st record. run: 1413 subRun: 1 event: 47881 at 15-Jan-2026 19:47:52 UTC
Begin processing the 82nd record. run: 1413 subRun: 1 event: 47882 at 15-Jan-2026 19:48:13 UTC
Begin processing the 83rd record. run: 1413 subRun: 1 event: 47883 at 15-Jan-2026 19:48:18 UTC
Begin processing the 84th record. run: 1413 subRun: 1 event: 47884 at 15-Jan-2026 19:48:22 UTC
Begin processing the 85th record. run: 1413 subRun: 1 event: 47885 at 15-Jan-2026 19:48:28 UTC
Begin processing the 86th record. run: 1413 subRun: 1 event: 47886 at 15-Jan-2026 19:48:33 UTC
Begin processing the 87th record. run: 1413 subRun: 1 event: 47887 at 15-Jan-2026 19:48:38 UTC
Begin processing the 88th record. run: 1413 subRun: 1 event: 47888 at 15-Jan-2026 19:48:45 UTC
Begin processing the 89th record. run: 1413 subRun: 1 event: 47889 at 15-Jan-2026 19:48:51 UTC
Begin processing the 90th record. run: 1413 subRun: 1 event: 47890 at 15-Jan-2026 19:48:57 UTC
Begin processing the 91st record. run: 1413 subRun: 1 event: 47891 at 15-Jan-2026 19:49:13 UTC
Begin processing the 92nd record. run: 1413 subRun: 1 event: 47892 at 15-Jan-2026 19:49:23 UTC
Begin processing the 93rd record. run: 1413 subRun: 1 event: 47893 at 15-Jan-2026 19:49:28 UTC
Begin processing the 94th record. run: 1413 subRun: 1 event: 47894 at 15-Jan-2026 19:49:33 UTC
Begin processing the 95th record. run: 1413 subRun: 1 event: 47895 at 15-Jan-2026 19:49:39 UTC
Begin processing the 96th record. run: 1413 subRun: 1 event: 47896 at 15-Jan-2026 19:49:46 UTC
Begin processing the 97th record. run: 1413 subRun: 1 event: 47897 at 15-Jan-2026 19:49:51 UTC
Begin processing the 98th record. run: 1413 subRun: 1 event: 47898 at 15-Jan-2026 19:49:55 UTC
Begin processing the 99th record. run: 1413 subRun: 1 event: 47899 at 15-Jan-2026 19:50:01 UTC
Begin processing the 100th record. run: 1413 subRun: 1 event: 47900 at 15-Jan-2026 19:50:08 UTC
15-Jan-2026 19:50:15 UTC  Closed output file "nu_dune10kt_1x2x6_1413_478_20230826T183336Z_gen_g4_detsim_hitreco__20240229T191223Z_reco2_reco2.root"
15-Jan-2026 19:50:15 UTC  Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/fardet-hd/03/e3/nu_dune10kt_1x2x6_1413_478_20230826T183336Z_gen_g4_detsim_hitreco__20240229T191223Z_reco2.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      3.74766       5.91411       20.1253       5.00255       2.78968        100    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                         0.0127778     0.216005       2.05796      0.190194      0.197443        100    
reco:pandora:StandardPandora                    3.55499       5.67937       19.7188       4.80088       2.76357        100    
[art]:TriggerResults:TriggerResultInserter    1.3505e-05    2.84882e-05   0.00013273    2.45115e-05   1.52119e-05      100    
end_path:out1:RootOutput                       2.575e-06    3.80823e-06   2.4106e-05    3.1105e-06    2.34829e-06      100    
end_path:out1:RootOutput(write)               0.00169396     0.0184911     0.0812715     0.0141164     0.0157649       100    
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 2209.5 MB
  Peak resident set size usage (VmHWM): 1222.91 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===