Jobsub ID 40523.70@dunegpschedd01.fnal.gov

Jobsub ID	40523.70@dunegpschedd01.fnal.gov
Workflow ID	1980
Stage ID	1
User name	hsouza@fnal.gov
HTCondor Group	group_dune
Requested	Processors	1
	GPU	No
	RSS bytes	3145728000 (3000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-09-04 23:40:48
Site	NL_NIKHEF
Entry	VIRGO_NL_NIKHEF_klomp
Last heartbeat	2025-09-04 23:44:28
From worker node	Hostname	wn-choc-005.farm.nikhef.nl
	cpuinfo	Intel(R) Xeon(R) CPU E5-2650 v4 @ 2.20GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	3145728000 (3000 MiB)
	Wall seconds limit	129600 (36 hours)
	GPU
	Inner Apptainer?	True
Job state	outputting_failed
Started	2025-09-04 23:42:21
Input files	fardet-hd:atmnu_max_weighted_randompolicy_dune10kt_1x2x6_1498_148_20231027T230359Z_gen_g4_detsim_hitreco__20240503T042018Z_reco2.root
Jobscript	Exit code	0
	Real time	1m (110s)
	CPU time	1m (82s = 74%)
	Max RSS bytes	1746284544 (1665 MiB)
Outputting started	2025-09-04 23:44:12
Output files
Finished	2025-09-04 23:44:28
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

n processing the 129th record. run: 1498 subRun: 1 event: 29729 at 05-Sep-2025 01:43:50 CEST
Begin processing the 130th record. run: 1498 subRun: 1 event: 29730 at 05-Sep-2025 01:43:51 CEST
Begin processing the 131st record. run: 1498 subRun: 1 event: 29731 at 05-Sep-2025 01:43:51 CEST
Begin processing the 132nd record. run: 1498 subRun: 1 event: 29732 at 05-Sep-2025 01:43:51 CEST
Begin processing the 133rd record. run: 1498 subRun: 1 event: 29733 at 05-Sep-2025 01:43:51 CEST
Begin processing the 134th record. run: 1498 subRun: 1 event: 29734 at 05-Sep-2025 01:43:51 CEST
Begin processing the 135th record. run: 1498 subRun: 1 event: 29735 at 05-Sep-2025 01:43:51 CEST
Begin processing the 136th record. run: 1498 subRun: 1 event: 29736 at 05-Sep-2025 01:43:52 CEST
Begin processing the 137th record. run: 1498 subRun: 1 event: 29737 at 05-Sep-2025 01:43:52 CEST
Begin processing the 138th record. run: 1498 subRun: 1 event: 29738 at 05-Sep-2025 01:43:52 CEST
Begin processing the 139th record. run: 1498 subRun: 1 event: 29739 at 05-Sep-2025 01:43:53 CEST
Begin processing the 140th record. run: 1498 subRun: 1 event: 29740 at 05-Sep-2025 01:43:53 CEST
Begin processing the 141st record. run: 1498 subRun: 1 event: 29741 at 05-Sep-2025 01:43:53 CEST
Begin processing the 142nd record. run: 1498 subRun: 1 event: 29742 at 05-Sep-2025 01:43:53 CEST
Begin processing the 143rd record. run: 1498 subRun: 1 event: 29743 at 05-Sep-2025 01:43:53 CEST
Begin processing the 144th record. run: 1498 subRun: 1 event: 29744 at 05-Sep-2025 01:43:53 CEST
Begin processing the 145th record. run: 1498 subRun: 1 event: 29745 at 05-Sep-2025 01:43:54 CEST
Begin processing the 146th record. run: 1498 subRun: 1 event: 29746 at 05-Sep-2025 01:43:54 CEST
Begin processing the 147th record. run: 1498 subRun: 1 event: 29747 at 05-Sep-2025 01:43:54 CEST
Begin processing the 148th record. run: 1498 subRun: 1 event: 29748 at 05-Sep-2025 01:43:54 CEST
Begin processing the 149th record. run: 1498 subRun: 1 event: 29749 at 05-Sep-2025 01:43:54 CEST
Begin processing the 150th record. run: 1498 subRun: 1 event: 29750 at 05-Sep-2025 01:43:55 CEST
Begin processing the 151st record. run: 1498 subRun: 1 event: 29751 at 05-Sep-2025 01:43:55 CEST
Begin processing the 152nd record. run: 1498 subRun: 1 event: 29752 at 05-Sep-2025 01:43:56 CEST
Begin processing the 153rd record. run: 1498 subRun: 1 event: 29753 at 05-Sep-2025 01:43:56 CEST
Begin processing the 154th record. run: 1498 subRun: 1 event: 29754 at 05-Sep-2025 01:43:56 CEST
Begin processing the 155th record. run: 1498 subRun: 1 event: 29755 at 05-Sep-2025 01:43:56 CEST
Begin processing the 156th record. run: 1498 subRun: 1 event: 29756 at 05-Sep-2025 01:43:56 CEST
Begin processing the 157th record. run: 1498 subRun: 1 event: 29757 at 05-Sep-2025 01:43:56 CEST
Begin processing the 158th record. run: 1498 subRun: 1 event: 29758 at 05-Sep-2025 01:43:56 CEST
Begin processing the 159th record. run: 1498 subRun: 1 event: 29759 at 05-Sep-2025 01:43:57 CEST
Begin processing the 160th record. run: 1498 subRun: 1 event: 29760 at 05-Sep-2025 01:43:57 CEST
Begin processing the 161st record. run: 1498 subRun: 1 event: 29761 at 05-Sep-2025 01:43:57 CEST
Begin processing the 162nd record. run: 1498 subRun: 1 event: 29762 at 05-Sep-2025 01:43:57 CEST
Begin processing the 163rd record. run: 1498 subRun: 1 event: 29763 at 05-Sep-2025 01:43:57 CEST
Begin processing the 164th record. run: 1498 subRun: 1 event: 29764 at 05-Sep-2025 01:43:57 CEST
Begin processing the 165th record. run: 1498 subRun: 1 event: 29765 at 05-Sep-2025 01:43:57 CEST
Begin processing the 166th record. run: 1498 subRun: 1 event: 29766 at 05-Sep-2025 01:43:58 CEST
Begin processing the 167th record. run: 1498 subRun: 1 event: 29767 at 05-Sep-2025 01:43:58 CEST
Begin processing the 168th record. run: 1498 subRun: 1 event: 29768 at 05-Sep-2025 01:43:58 CEST
Begin processing the 169th record. run: 1498 subRun: 1 event: 29769 at 05-Sep-2025 01:43:58 CEST
Begin processing the 170th record. run: 1498 subRun: 1 event: 29770 at 05-Sep-2025 01:44:00 CEST
Begin processing the 171st record. run: 1498 subRun: 1 event: 29771 at 05-Sep-2025 01:44:02 CEST
Begin processing the 172nd record. run: 1498 subRun: 1 event: 29772 at 05-Sep-2025 01:44:03 CEST
Begin processing the 173rd record. run: 1498 subRun: 1 event: 29773 at 05-Sep-2025 01:44:03 CEST
Begin processing the 174th record. run: 1498 subRun: 1 event: 29774 at 05-Sep-2025 01:44:03 CEST
Begin processing the 175th record. run: 1498 subRun: 1 event: 29775 at 05-Sep-2025 01:44:03 CEST
Begin processing the 176th record. run: 1498 subRun: 1 event: 29776 at 05-Sep-2025 01:44:04 CEST
Begin processing the 177th record. run: 1498 subRun: 1 event: 29777 at 05-Sep-2025 01:44:04 CEST
Begin processing the 178th record. run: 1498 subRun: 1 event: 29778 at 05-Sep-2025 01:44:04 CEST
Begin processing the 179th record. run: 1498 subRun: 1 event: 29779 at 05-Sep-2025 01:44:04 CEST
Begin processing the 180th record. run: 1498 subRun: 1 event: 29780 at 05-Sep-2025 01:44:04 CEST
Begin processing the 181st record. run: 1498 subRun: 1 event: 29781 at 05-Sep-2025 01:44:04 CEST
Begin processing the 182nd record. run: 1498 subRun: 1 event: 29782 at 05-Sep-2025 01:44:04 CEST
Begin processing the 183rd record. run: 1498 subRun: 1 event: 29783 at 05-Sep-2025 01:44:04 CEST
Begin processing the 184th record. run: 1498 subRun: 1 event: 29784 at 05-Sep-2025 01:44:05 CEST
Begin processing the 185th record. run: 1498 subRun: 1 event: 29785 at 05-Sep-2025 01:44:05 CEST
Begin processing the 186th record. run: 1498 subRun: 1 event: 29786 at 05-Sep-2025 01:44:05 CEST
Begin processing the 187th record. run: 1498 subRun: 1 event: 29787 at 05-Sep-2025 01:44:05 CEST
Begin processing the 188th record. run: 1498 subRun: 1 event: 29788 at 05-Sep-2025 01:44:05 CEST
Begin processing the 189th record. run: 1498 subRun: 1 event: 29789 at 05-Sep-2025 01:44:06 CEST
Begin processing the 190th record. run: 1498 subRun: 1 event: 29790 at 05-Sep-2025 01:44:06 CEST
Begin processing the 191st record. run: 1498 subRun: 1 event: 29791 at 05-Sep-2025 01:44:07 CEST
Begin processing the 192nd record. run: 1498 subRun: 1 event: 29792 at 05-Sep-2025 01:44:07 CEST
Begin processing the 193rd record. run: 1498 subRun: 1 event: 29793 at 05-Sep-2025 01:44:07 CEST
Begin processing the 194th record. run: 1498 subRun: 1 event: 29794 at 05-Sep-2025 01:44:07 CEST
Begin processing the 195th record. run: 1498 subRun: 1 event: 29795 at 05-Sep-2025 01:44:09 CEST
Begin processing the 196th record. run: 1498 subRun: 1 event: 29796 at 05-Sep-2025 01:44:09 CEST
Begin processing the 197th record. run: 1498 subRun: 1 event: 29797 at 05-Sep-2025 01:44:09 CEST
Begin processing the 198th record. run: 1498 subRun: 1 event: 29798 at 05-Sep-2025 01:44:09 CEST
Begin processing the 199th record. run: 1498 subRun: 1 event: 29799 at 05-Sep-2025 01:44:10 CEST
Begin processing the 200th record. run: 1498 subRun: 1 event: 29800 at 05-Sep-2025 01:44:10 CEST
05-Sep-2025 01:44:11 CEST  Closed input file "root://dune.dcache.nikhef.nl:1094/pnfs/nikhef.nl/data/dune/generic/rucio/fardet-hd/88/f6/atmnu_max_weighted_randompolicy_dune10kt_1x2x6_1498_148_20231027T230359Z_gen_g4_detsim_hitreco__20240503T042018Z_reco2.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.0257548     0.286859       6.80384      0.111921      0.728162        200    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.000551592    0.0119257     0.0367985     0.0138914     0.0085721       200    
prod:pandorapid:Chi2ParticleID                0.000940023    0.0240379     0.101348      0.0255727     0.0183274       200    
prod:energyreconue:EnergyReco                  0.0049756     0.035574      0.856356      0.0128169     0.0823339       200    
prod:energyreconumu:EnergyReco                0.00157087     0.0811781      3.79124      0.0114557     0.349252        200    
prod:energyreconc:EnergyReco                   0.0034796     0.0286942      0.67155      0.0143982     0.0600064       200    
[art]:TriggerResults:TriggerResultInserter    1.1285e-05    1.43314e-05   6.6674e-05    1.38365e-05   4.10927e-06      200    
end_path:ana:PIDASelectionAna                 0.00363415     0.105099       2.66406      0.0297376     0.282756        200    
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6134.15 MB
  Peak resident set size usage (VmHWM): 1746.28 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
.:
total 80
-rw-r--r--. 1 dune012 dune 28771 Sep  5 01:44 atmnu_max_weighted_randompolicy_dune10kt_1x2x6_1498_148_20231027T230359Z_gen_g4_detsim_hitreco__20240503T042018Z_reco2_pida_2025-09-04T_234225Z.root
-rw-r--r--. 1 dune012 dune 23699 Sep  5 01:44 atmnu_max_weighted_randompolicy_dune10kt_1x2x6_1498_148_20231027T230359Z_gen_g4_detsim_hitreco__20240503T042018Z_reco2_reco_2025-09-04T_234225Z.log
-rw-r--r--. 1 dune012 dune 11871 Sep  5 01:44 jobscript.log
-rw-r--r--. 1 dune012 dune   213 Sep  5 01:44 justin-processed-pfns.txt
-rw-r--r--. 1 dune012 dune   134 Sep  5 01:42 all-input-dids.txt
-rw-r--r--. 1 dune012 dune     0 Sep  5 01:42 debugprod.log