Jobsub ID 238975.4@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
ice::ctor: StandardRawDigitPrepService:
StandardRawDigitPrepService::ctor: LogLevel: 1
StandardRawDigitPrepService::ctor: SkipBad: 1
StandardRawDigitPrepService::ctor: SkipNoisy: 0
StandardRawDigitPrepService::ctor: ChannelStatusOnline: 0
StandardRawDigitPrepService::ctor: DoMitigation: 0
StandardRawDigitPrepService::ctor: DoEarlySignalFinding: 0
StandardRawDigitPrepService::ctor: DoNoiseRemoval: 0
StandardRawDigitPrepService::ctor: DoDeconvolution: 1
StandardRawDigitPrepService::ctor: DoPedestalAdjustment: 0
StandardRawDigitPrepService::ctor: DoROI: 1
StandardRawDigitPrepService::ctor: DoWires: 1
StandardRawDigitPrepService::ctor: DoDump: 0
StandardRawDigitPrepService::ctor: DoIntermediateStates: 0
StandardRawDigitPrepService::ctor: No display tools.
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_U (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_V (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_Y (Potential memory leak).
06-Nov-2025 16:08:38 EST Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/ab/db/prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim.root"
06-Nov-2025 16:08:40 EST Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/ab/db/prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim.root"
Begin processing the 1st record. run: 8145 subRun: 0 event: 12601 at 06-Nov-2025 16:08:41 EST
06-Nov-2025 16:08:50 EST Opened output file with pattern "prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root"
Begin processing the 2nd record. run: 8145 subRun: 0 event: 12602 at 06-Nov-2025 16:09:13 EST
Begin processing the 3rd record. run: 8145 subRun: 0 event: 12603 at 06-Nov-2025 16:09:20 EST
Begin processing the 4th record. run: 8145 subRun: 0 event: 12604 at 06-Nov-2025 16:09:26 EST
Begin processing the 5th record. run: 8145 subRun: 0 event: 12605 at 06-Nov-2025 16:09:32 EST
Begin processing the 6th record. run: 8145 subRun: 0 event: 12606 at 06-Nov-2025 16:09:39 EST
Begin processing the 7th record. run: 8145 subRun: 0 event: 12607 at 06-Nov-2025 16:09:45 EST
Begin processing the 8th record. run: 8145 subRun: 0 event: 12608 at 06-Nov-2025 16:09:52 EST
Begin processing the 9th record. run: 8145 subRun: 0 event: 12609 at 06-Nov-2025 16:09:59 EST
Begin processing the 10th record. run: 8145 subRun: 0 event: 12610 at 06-Nov-2025 16:10:05 EST
Begin processing the 11th record. run: 8145 subRun: 0 event: 12611 at 06-Nov-2025 16:10:12 EST
Begin processing the 12th record. run: 8145 subRun: 0 event: 12612 at 06-Nov-2025 16:10:18 EST
Begin processing the 13th record. run: 8145 subRun: 0 event: 12613 at 06-Nov-2025 16:10:25 EST
Begin processing the 14th record. run: 8145 subRun: 0 event: 12614 at 06-Nov-2025 16:10:31 EST
Begin processing the 15th record. run: 8145 subRun: 0 event: 12615 at 06-Nov-2025 16:10:37 EST
Begin processing the 16th record. run: 8145 subRun: 0 event: 12616 at 06-Nov-2025 16:10:44 EST
Begin processing the 17th record. run: 8145 subRun: 0 event: 12617 at 06-Nov-2025 16:10:50 EST
Begin processing the 18th record. run: 8145 subRun: 0 event: 12618 at 06-Nov-2025 16:10:57 EST
Begin processing the 19th record. run: 8145 subRun: 0 event: 12619 at 06-Nov-2025 16:11:03 EST
Begin processing the 20th record. run: 8145 subRun: 0 event: 12620 at 06-Nov-2025 16:11:08 EST
Begin processing the 21st record. run: 8145 subRun: 0 event: 12621 at 06-Nov-2025 16:11:14 EST
Begin processing the 22nd record. run: 8145 subRun: 0 event: 12622 at 06-Nov-2025 16:11:21 EST
Begin processing the 23rd record. run: 8145 subRun: 0 event: 12623 at 06-Nov-2025 16:11:27 EST
Begin processing the 24th record. run: 8145 subRun: 0 event: 12624 at 06-Nov-2025 16:11:33 EST
Begin processing the 25th record. run: 8145 subRun: 0 event: 12625 at 06-Nov-2025 16:11:40 EST
Begin processing the 26th record. run: 8145 subRun: 0 event: 12626 at 06-Nov-2025 16:11:46 EST
Begin processing the 27th record. run: 8145 subRun: 0 event: 12627 at 06-Nov-2025 16:11:52 EST
Begin processing the 28th record. run: 8145 subRun: 0 event: 12628 at 06-Nov-2025 16:11:59 EST
Begin processing the 29th record. run: 8145 subRun: 0 event: 12629 at 06-Nov-2025 16:12:05 EST
Begin processing the 30th record. run: 8145 subRun: 0 event: 12630 at 06-Nov-2025 16:12:11 EST
Begin processing the 31st record. run: 8145 subRun: 0 event: 12631 at 06-Nov-2025 16:12:18 EST
Begin processing the 32nd record. run: 8145 subRun: 0 event: 12632 at 06-Nov-2025 16:12:24 EST
Begin processing the 33rd record. run: 8145 subRun: 0 event: 12633 at 06-Nov-2025 16:12:30 EST
Begin processing the 34th record. run: 8145 subRun: 0 event: 12634 at 06-Nov-2025 16:12:35 EST
Begin processing the 35th record. run: 8145 subRun: 0 event: 12635 at 06-Nov-2025 16:12:42 EST
Begin processing the 36th record. run: 8145 subRun: 0 event: 12636 at 06-Nov-2025 16:12:48 EST
Begin processing the 37th record. run: 8145 subRun: 0 event: 12637 at 06-Nov-2025 16:12:54 EST
Begin processing the 38th record. run: 8145 subRun: 0 event: 12638 at 06-Nov-2025 16:13:00 EST
Begin processing the 39th record. run: 8145 subRun: 0 event: 12639 at 06-Nov-2025 16:13:07 EST
Begin processing the 40th record. run: 8145 subRun: 0 event: 12640 at 06-Nov-2025 16:13:13 EST
Begin processing the 41st record. run: 8145 subRun: 0 event: 12641 at 06-Nov-2025 16:13:19 EST
Begin processing the 42nd record. run: 8145 subRun: 0 event: 12642 at 06-Nov-2025 16:13:25 EST
Begin processing the 43rd record. run: 8145 subRun: 0 event: 12643 at 06-Nov-2025 16:13:31 EST
Begin processing the 44th record. run: 8145 subRun: 0 event: 12644 at 06-Nov-2025 16:13:37 EST
Begin processing the 45th record. run: 8145 subRun: 0 event: 12645 at 06-Nov-2025 16:13:43 EST
Begin processing the 46th record. run: 8145 subRun: 0 event: 12646 at 06-Nov-2025 16:13:49 EST
Begin processing the 47th record. run: 8145 subRun: 0 event: 12647 at 06-Nov-2025 16:13:55 EST
Begin processing the 48th record. run: 8145 subRun: 0 event: 12648 at 06-Nov-2025 16:14:01 EST
Begin processing the 49th record. run: 8145 subRun: 0 event: 12649 at 06-Nov-2025 16:14:06 EST
Begin processing the 50th record. run: 8145 subRun: 0 event: 12650 at 06-Nov-2025 16:14:13 EST
06-Nov-2025 16:14:19 EST Closed output file "prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root"
06-Nov-2025 16:14:19 EST Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/ab/db/prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim.root"
=========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=========================================================================================================================================
Full event 4.8982 6.18966 8.60227 6.29529 0.514856 50
-----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00020742 0.0020721 0.0583393 0.000384876 0.00819183 50
makers:tpmakerTPCsimpleThr:TriggerPrimitiveMakerTPC 2.52992 3.29787 5.48559 3.33104 0.369128 50
makers:tpmakerTPCabsRS:TriggerPrimitiveMakerTPC 2.31819 2.88692 3.14601 3.00301 0.244015 50
[art]:TriggerResults:TriggerResultInserter 1.5069e-05 2.91008e-05 0.000113994 2.39295e-05 1.62564e-05 50
end_path:out1:RootOutput 2.745e-06 7.03444e-06 3.0547e-05 5.736e-06 4.48089e-06 50
end_path:out1:RootOutput(write) 0.00126238 0.00215636 0.00374022 0.00199372 0.000580401 50
=========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1483.75 MB
Peak resident set size usage (VmHWM): 736.702 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Moving on to metadata extractor
mv: cannot stat 'triggerAnaTree_hist.root': No such file or directory
============OUTPUT FILE: prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root
Extracted metadata
checking file====== fardet-hd:prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim.root
MD conversion to MetaCat OK
all-input-dids.txt
debugprod.log
jobscript.log
justin-processed-pfns.txt
mem.db
old_md.json
prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root
prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root.ext.json
prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root.json
prodmarley_nue_flat_es_dune10kt_1x2x2_20250927T063210Z_gen_000253_supernova_g4_detsim_20251106T210822Z_reco.root.temp.json
time.db
triggersim_hist.root