Jobsub ID 264523.1@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ng the 4th record. run: 20250623 subRun: 1 event: 33 at 07-Dec-2025 03:03:12 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:03:20 CET run: 20250623 subRun: 1 event: 33
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 895453 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 895453 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 895453 depos spanning: [-3186.37, 3073.91]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: 34 at 07-Dec-2025 03:09:57 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:10:04 CET run: 20250623 subRun: 1 event: 34
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 908470 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 908470 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 908470 depos spanning: [-3292.38, 3094.42]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: 35 at 07-Dec-2025 03:17:01 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:17:09 CET run: 20250623 subRun: 1 event: 35
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1582203 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1582203 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1582203 depos spanning: [-3261.99, 3107.16]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: 36 at 07-Dec-2025 03:29:21 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:29:33 CET run: 20250623 subRun: 1 event: 36
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1183676 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1183676 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1183676 depos spanning: [-3267.65, 3116.91]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 8th record. run: 20250623 subRun: 1 event: 37 at 07-Dec-2025 03:38:46 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:38:55 CET run: 20250623 subRun: 1 event: 37
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1018291 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1018291 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1018291 depos spanning: [-3286.5, 3031.78]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 9th record. run: 20250623 subRun: 1 event: 38 at 07-Dec-2025 03:47:00 CET
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:47:11 CET run: 20250623 subRun: 1 event: 38
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1201147 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1201147 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1201147 depos spanning: [-3288.04, 2997.08]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 10th record. run: 20250623 subRun: 1 event: 39 at 07-Dec-2025 03:56:19 CET
R__unzip: error -3 in inflate (zlib)
%MSG-w ParticleInventory: ProcessEvent 07-Dec-2025 03:56:24 CET run: 20250623 subRun: 1 event: 39
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
R__unzip: error -3 in inflate (zlib)
%MSG-w ParticleInventory: ProcessEvent 07-Dec-2025 03:56:27 CET run: 20250623 subRun: 1 event: 39
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
R__unzip: error -3 in inflate (zlib)
%MSG-w ParticleInventory: ProcessEvent 07-Dec-2025 03:56:31 CET run: 20250623 subRun: 1 event: 39
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
%MSG-w PhotonBackTrackerService: ProcessEvent 07-Dec-2025 03:56:31 CET run: 20250623 subRun: 1 event: 39
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1480759 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1480759 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1480759 depos spanning: [-3320.13, 3101.08]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
R__unzip: error -3 in inflate (zlib)
07-Dec-2025 04:07:34 CET Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune/5c/52/pdhd_prod_beam__248356_112_1_20251113T031152Z_gen_g4_IonScintPDExt.root_247897_127_1_20251118T032516Z_PDInt.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 0.0096752 455.749 730.213 459.266 179.559 10
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00833221 0.0290246 0.11258 0.00962189 0.0391884 10
simulate:rns:RandomNumberSaver 5.3162e-05 0.000148544 0.000921868 6.0295e-05 0.000257924 10
simulate:tpcrawdecoder:WireCellToolkit 366.789 486.334 682.097 482.23 100.343 10
simulate:opdigi:OpDetDigitizerProtoDUNEHD 23.5982 31.5762 42.9837 30.7916 6.16635 10
simulate:crt:CRTSimRefac 0.0536999 0.0808592 0.114183 0.0773736 0.02155 9
[art]:TriggerResults:TriggerResultInserter 2.9145e-05 4.37791e-05 0.000129587 3.2141e-05 3.04797e-05 9
end_path:out1:RootOutput 5.801e-06 9.00722e-06 3.141e-05 6.222e-06 7.92387e-06 9
end_path:out1:RootOutput(write) 3.29161 3.81059 4.6976 3.78704 0.410002 9
================================================================================================================================
%MSG-i NuRandomService: RootOutput:out1@EndJob 07-Dec-2025 04:07:34 CET ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'random'
master seed: 672612872
seed within: [ 1 ; 900000000 ]
%MSG
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 10192.5 MB
Peak resident set size usage (VmHWM): 8487.41 MB
Details saved in: 'mem.db'
====================================================================================================
TrigReport ---------- Event summary -------------
TrigReport Events total = 10 passed = 9 failed = 1
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 9 9 0 out1
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 5091.460557 Real = 5517.146898
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 10192.5 VmHWM = 8487.41
%MSG-s ArtException: PostEndJob 07-Dec-2025 04:07:35 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 = 94403549, fKeylen = 109, fObjlen = 401334326, noutot = 201326580, nout=0, nin=5617716, nbuf=16777215
ROOT severity: 3000
---- FatalRootError END
The above exception was thrown while processing module CRTSimRefac/crt run: 20250623 subRun: 1 event: 39
---- 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 9 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