Jobsub ID 262213.0@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
4T035225Z_PDInt.root"
%MSG-i Root_Information: TriggerResultInserter:TriggerResults@Construction TClass::Init() 02-Dec-2025 15:27:40 CET ModuleConstruction
no dictionary for class sim::IDE is available
ROOT severity: 2000
%MSG
02-Dec-2025 15:27:40 CET Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune/67/2f/pdhd_prod_beam__239224_189_1_20251107T011402Z_gen_g4_IonScintPDExt.root_250309_15_1_20251124T035225Z_PDInt.root"
Begin processing the 1st record. run: 20250623 subRun: 1 event: 130 at 02-Dec-2025 15:27:50 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 15:27:58 CET run: 20250623 subRun: 1 event: 130
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 963593 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 963593 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 963593 depos spanning: [-3112.57, 3124.69]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
02-Dec-2025 15:36:40 CET Opened output file with pattern "temp_detsim.root"
%MSG-i FastCloning: PostProcessEvent 02-Dec-2025 15:36:40 CET run: 20250623 subRun: 1 event: 130
Fast cloning event data products from input file.
%MSG
Begin processing the 2nd record. run: 20250623 subRun: 1 event: 131 at 02-Dec-2025 15:39:50 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 15:39:58 CET run: 20250623 subRun: 1 event: 131
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1345073 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1345073 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1345073 depos spanning: [-3267.82, 3090.19]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 3rd record. run: 20250623 subRun: 1 event: 132 at 02-Dec-2025 15:52:08 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 15:52:17 CET run: 20250623 subRun: 1 event: 132
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1218843 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1218843 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1218843 depos spanning: [-3323.78, 2998.23]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 4th record. run: 20250623 subRun: 1 event: 133 at 02-Dec-2025 16:03:08 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 16:03:18 CET run: 20250623 subRun: 1 event: 133
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1283416 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1283416 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1283416 depos spanning: [-3223.91, 3070.77]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 5th record. run: 20250623 subRun: 1 event: 134 at 02-Dec-2025 16:13:14 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 16:13:22 CET run: 20250623 subRun: 1 event: 134
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 617002 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 617002 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 617002 depos spanning: [-3236.9, 3040.38]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 6th record. run: 20250623 subRun: 1 event: 135 at 02-Dec-2025 16:19:38 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 16:19:47 CET run: 20250623 subRun: 1 event: 135
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1456962 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1456962 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1456962 depos spanning: [-3299.17, 2996.68]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 7th record. run: 20250623 subRun: 1 event: 136 at 02-Dec-2025 16:31:01 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 02-Dec-2025 16:31:08 CET run: 20250623 subRun: 1 event: 136
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 970846 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 970846 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 970846 depos spanning: [-3255.92, 3101.1]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
R__unzip: error -3 in inflate (zlib)
02-Dec-2025 16:37:07 CET Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune/67/2f/pdhd_prod_beam__239224_189_1_20251107T011402Z_gen_g4_IonScintPDExt.root_250309_15_1_20251124T035225Z_PDInt.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 0.00864798 507.045 729.005 595.653 232.952 7
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00864798 0.022664 0.0977928 0.0110587 0.0306902 7
simulate:rns:RandomNumberSaver 5.6956e-05 0.000105869 0.00034599 6.1733e-05 9.84959e-05 7
simulate:tpcrawdecoder:WireCellToolkit 343.502 524.505 687.108 559.199 125.937 7
simulate:opdigi:OpDetDigitizerProtoDUNEHD 21.7407 32.1537 38.812 32.6178 5.42061 6
simulate:crt:CRTSimRefac 0.0481075 0.0751862 0.0925592 0.0808754 0.0156727 6
[art]:TriggerResults:TriggerResultInserter 2.9454e-05 4.63343e-05 0.000121152 3.16775e-05 3.34985e-05 6
end_path:out1:RootOutput 5.157e-06 9.06983e-06 2.5738e-05 5.8235e-06 7.46283e-06 6
end_path:out1:RootOutput(write) 3.95209 4.42787 4.98514 4.35362 0.351918 6
================================================================================================================================
%MSG-i NuRandomService: RootOutput:out1@EndJob 02-Dec-2025 16:37:07 CET ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'random'
master seed: 196764529
seed within: [ 1 ; 900000000 ]
%MSG
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8888.03 MB
Peak resident set size usage (VmHWM): 7186.09 MB
Details saved in: 'mem.db'
====================================================================================================
TrigReport ---------- Event summary -------------
TrigReport Events total = 7 passed = 6 failed = 1
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 6 6 0 out1
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 3817.388661 Real = 4165.011740
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 8888.03 VmHWM = 7186.09
%MSG-s ArtException: PostEndJob 02-Dec-2025 16:37:08 CET ModuleEndJob
---- EventProcessorFailure BEGIN
EventProcessor: an exception occurred during current event processing
---- ScheduleExecutionFailure BEGIN
Path: ProcessingStopped.
---- FileReadError BEGIN
---- FatalRootError BEGIN
Fatal Root Error: TBasket::ReadBasketBuffers
fNbytes = 71944017, fKeylen = 131, fObjlen = 128774332, noutot = 0, nout=0, nin=9303696, nbuf=16777215
ROOT severity: 3000
---- FatalRootError END
The above exception was thrown while processing module OpDetDigitizerProtoDUNEHD/opdigi run: 20250623 subRun: 1 event: 136
---- FileReadError END
Exception going through path simulate
---- ScheduleExecutionFailure END
---- EventProcessorFailure END
---- FatalRootError BEGIN
Fatal Root Error: TTree::SetEntries
Tree branches have different numbers of entries, eg EventAuxiliary has 6 entries while art::RNGsnapshots_rns__IonScintPDExt. has 10 entries.
ROOT severity: 2000
---- FatalRootError END
%MSG
Art has completed and will exit with status 1.
Detsim returns 1