Jobsub ID 235781.5@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
vice::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).
31-Oct-2025 10:42:26 CDT Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/e5/50/prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim.root"
31-Oct-2025 10:42:28 CDT Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/e5/50/prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim.root"
Begin processing the 1st record. run: 8106 subRun: 0 event: 46601 at 31-Oct-2025 10:42:29 CDT
31-Oct-2025 10:42:35 CDT Opened output file with pattern "prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root"
Begin processing the 2nd record. run: 8106 subRun: 0 event: 46602 at 31-Oct-2025 10:43:03 CDT
Begin processing the 3rd record. run: 8106 subRun: 0 event: 46603 at 31-Oct-2025 10:43:10 CDT
Begin processing the 4th record. run: 8106 subRun: 0 event: 46604 at 31-Oct-2025 10:43:17 CDT
Begin processing the 5th record. run: 8106 subRun: 0 event: 46605 at 31-Oct-2025 10:43:23 CDT
Begin processing the 6th record. run: 8106 subRun: 0 event: 46606 at 31-Oct-2025 10:43:30 CDT
Begin processing the 7th record. run: 8106 subRun: 0 event: 46607 at 31-Oct-2025 10:43:36 CDT
Begin processing the 8th record. run: 8106 subRun: 0 event: 46608 at 31-Oct-2025 10:43:43 CDT
Begin processing the 9th record. run: 8106 subRun: 0 event: 46609 at 31-Oct-2025 10:43:49 CDT
Begin processing the 10th record. run: 8106 subRun: 0 event: 46610 at 31-Oct-2025 10:43:56 CDT
Begin processing the 11th record. run: 8106 subRun: 0 event: 46611 at 31-Oct-2025 10:44:03 CDT
Begin processing the 12th record. run: 8106 subRun: 0 event: 46612 at 31-Oct-2025 10:44:09 CDT
Begin processing the 13th record. run: 8106 subRun: 0 event: 46613 at 31-Oct-2025 10:44:16 CDT
Begin processing the 14th record. run: 8106 subRun: 0 event: 46614 at 31-Oct-2025 10:44:22 CDT
Begin processing the 15th record. run: 8106 subRun: 0 event: 46615 at 31-Oct-2025 10:44:29 CDT
Begin processing the 16th record. run: 8106 subRun: 0 event: 46616 at 31-Oct-2025 10:44:36 CDT
Begin processing the 17th record. run: 8106 subRun: 0 event: 46617 at 31-Oct-2025 10:44:43 CDT
Begin processing the 18th record. run: 8106 subRun: 0 event: 46618 at 31-Oct-2025 10:44:49 CDT
Begin processing the 19th record. run: 8106 subRun: 0 event: 46619 at 31-Oct-2025 10:44:55 CDT
Begin processing the 20th record. run: 8106 subRun: 0 event: 46620 at 31-Oct-2025 10:45:02 CDT
Begin processing the 21st record. run: 8106 subRun: 0 event: 46621 at 31-Oct-2025 10:45:09 CDT
Begin processing the 22nd record. run: 8106 subRun: 0 event: 46622 at 31-Oct-2025 10:45:15 CDT
Begin processing the 23rd record. run: 8106 subRun: 0 event: 46623 at 31-Oct-2025 10:45:22 CDT
Begin processing the 24th record. run: 8106 subRun: 0 event: 46624 at 31-Oct-2025 10:45:28 CDT
Begin processing the 25th record. run: 8106 subRun: 0 event: 46625 at 31-Oct-2025 10:45:34 CDT
Begin processing the 26th record. run: 8106 subRun: 0 event: 46626 at 31-Oct-2025 10:45:41 CDT
Begin processing the 27th record. run: 8106 subRun: 0 event: 46627 at 31-Oct-2025 10:45:47 CDT
Begin processing the 28th record. run: 8106 subRun: 0 event: 46628 at 31-Oct-2025 10:45:54 CDT
Begin processing the 29th record. run: 8106 subRun: 0 event: 46629 at 31-Oct-2025 10:46:00 CDT
Begin processing the 30th record. run: 8106 subRun: 0 event: 46630 at 31-Oct-2025 10:46:06 CDT
Begin processing the 31st record. run: 8106 subRun: 0 event: 46631 at 31-Oct-2025 10:46:13 CDT
Begin processing the 32nd record. run: 8106 subRun: 0 event: 46632 at 31-Oct-2025 10:46:20 CDT
Begin processing the 33rd record. run: 8106 subRun: 0 event: 46633 at 31-Oct-2025 10:46:26 CDT
Begin processing the 34th record. run: 8106 subRun: 0 event: 46634 at 31-Oct-2025 10:46:33 CDT
Begin processing the 35th record. run: 8106 subRun: 0 event: 46635 at 31-Oct-2025 10:46:39 CDT
Begin processing the 36th record. run: 8106 subRun: 0 event: 46636 at 31-Oct-2025 10:46:46 CDT
Begin processing the 37th record. run: 8106 subRun: 0 event: 46637 at 31-Oct-2025 10:46:52 CDT
Begin processing the 38th record. run: 8106 subRun: 0 event: 46638 at 31-Oct-2025 10:46:59 CDT
Begin processing the 39th record. run: 8106 subRun: 0 event: 46639 at 31-Oct-2025 10:47:06 CDT
Begin processing the 40th record. run: 8106 subRun: 0 event: 46640 at 31-Oct-2025 10:47:12 CDT
Begin processing the 41st record. run: 8106 subRun: 0 event: 46641 at 31-Oct-2025 10:47:19 CDT
Begin processing the 42nd record. run: 8106 subRun: 0 event: 46642 at 31-Oct-2025 10:47:25 CDT
Begin processing the 43rd record. run: 8106 subRun: 0 event: 46643 at 31-Oct-2025 10:47:32 CDT
Begin processing the 44th record. run: 8106 subRun: 0 event: 46644 at 31-Oct-2025 10:47:38 CDT
Begin processing the 45th record. run: 8106 subRun: 0 event: 46645 at 31-Oct-2025 10:47:45 CDT
Begin processing the 46th record. run: 8106 subRun: 0 event: 46646 at 31-Oct-2025 10:47:51 CDT
Begin processing the 47th record. run: 8106 subRun: 0 event: 46647 at 31-Oct-2025 10:47:58 CDT
Begin processing the 48th record. run: 8106 subRun: 0 event: 46648 at 31-Oct-2025 10:48:05 CDT
Begin processing the 49th record. run: 8106 subRun: 0 event: 46649 at 31-Oct-2025 10:48:11 CDT
Begin processing the 50th record. run: 8106 subRun: 0 event: 46650 at 31-Oct-2025 10:48:18 CDT
31-Oct-2025 10:48:26 CDT Closed output file "prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root"
31-Oct-2025 10:48:26 CDT Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/e5/50/prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim.root"
=========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=========================================================================================================================================
Full event 6.07758 6.50695 6.87114 6.52762 0.186478 50
-----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000317468 0.000892258 0.00656077 0.000436953 0.00121275 50
makers:tpmakerTPCsimpleThr:TriggerPrimitiveMakerTPC 3.1999 3.41578 3.70682 3.43099 0.106549 50
makers:tpmakerTPCabsRS:TriggerPrimitiveMakerTPC 2.79624 3.08666 3.30908 3.10805 0.123557 50
[art]:TriggerResults:TriggerResultInserter 1.9977e-05 3.52193e-05 0.000139402 3.25515e-05 1.68673e-05 50
end_path:out1:RootOutput 4.368e-06 9.67716e-06 5.791e-05 7.875e-06 8.0238e-06 50
end_path:out1:RootOutput(write) 0.00155641 0.00281364 0.0061874 0.00201209 0.00129388 50
=========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1609.4 MB
Peak resident set size usage (VmHWM): 868.508 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_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root
Extracted metadata
checking file====== fardet-hd:prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_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_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root
prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root.ext.json
prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root.json
prodmarley_nue_flat_es_dune10kt_1x2x2_20250926T164636Z_gen_000933_supernova_g4_detsim_20251031T154207Z_reco.root.temp.json
time.db
triggersim_hist.root