Jobsub ID 262214.0@dunegpschedd01.fnal.gov

Jobsub ID	262214.0@dunegpschedd01.fnal.gov
Workflow ID	10925
Stage ID	1
User name	higuera@fnal.gov
HTCondor Group	group_dune.prod_mcsim
Requested	Processors	1
	GPU	No
	RSS bytes	9437184000 (9000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2025-12-02 14:32:04
Site	NL_SURFsara
Entry	DUNE_SurfSARA_arc03
Last heartbeat	2025-12-02 15:40:18
From worker node	Hostname	wn-la-25.gina.surf.nl
	cpuinfo	AMD EPYC 9754 128-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	10485760000 (10000 MiB)
	Wall seconds limit	129600 (36 hours)
	GPU
	Inner Apptainer?	True
Job state	jobscript_error
Started	2025-12-02 14:32:28
Input files	hd-protodune:pdhd_prod_beam__238980_152_1_20251106T221101Z_gen_g4_IonScintPDExt.root_250208_14_1_20251123T231451Z_PDInt.root
Jobscript	Exit code	1
	Real time	0m (0s)
	CPU time	0m (0s = 0%)
	Max RSS bytes	0 (0 MiB)
Outputting started
Output files
Finished	2025-12-02 15:40:18
Saved logs	justin-logs:262214.0-dunegpschedd01.fnal.gov.logs.tgz
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1120206 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1120206 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1120206 depos spanning: [-3253.58, 3119.77]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
02-Dec-2025 15:43:16 CET  Opened output file with pattern "temp_detsim.root"
%MSG-i FastCloning:  PostProcessEvent 02-Dec-2025 15:43:16 CET  run: 20250614 subRun: 1 event: 90
Fast cloning event data products from input file.
%MSG
Begin processing the 2nd record. run: 20250614 subRun: 1 event: 91 at 02-Dec-2025 15:45:53 CET
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 15:46:00 CET  run: 20250614 subRun: 1 event: 91
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1301517 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1301517 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1301517 depos spanning: [-3320.13, 3102.16]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 3rd record. run: 20250614 subRun: 1 event: 92 at 02-Dec-2025 15:57:20 CET
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 15:57:28 CET  run: 20250614 subRun: 1 event: 92
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1087402 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1087402 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1087402 depos spanning: [-3270.06, 3107.8]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 4th record. run: 20250614 subRun: 1 event: 93 at 02-Dec-2025 16:06:46 CET
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 16:06:53 CET  run: 20250614 subRun: 1 event: 93
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 747810 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 747810 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 747810 depos spanning: [-3318.28, 2998.32]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 5th record. run: 20250614 subRun: 1 event: 94 at 02-Dec-2025 16:13:38 CET
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 16:13:45 CET  run: 20250614 subRun: 1 event: 94
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1092112 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1092112 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1092112 depos spanning: [-3310.18, 2985.27]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 6th record. run: 20250614 subRun: 1 event: 95 at 02-Dec-2025 16:23:05 CET
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 16:23:12 CET  run: 20250614 subRun: 1 event: 95
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 1066175 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 1066175 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 1066175 depos spanning: [-3310.73, 3000.03]us
Retagger: tagging trace set: daq with 10240 traces, 0 summary
wclsFrameSaver: saving raw::RawDigits tagged "daq"
Begin processing the 7th record. run: 20250614 subRun: 1 event: 96 at 02-Dec-2025 16:31:57 CET
R__unzip: error -3 in inflate (zlib)
%MSG-w ParticleInventory:  ProcessEvent 02-Dec-2025 16:32:00 CET  run: 20250614 subRun: 1 event: 96
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 02-Dec-2025 16:32:01 CET  run: 20250614 subRun: 1 event: 96
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 02-Dec-2025 16:32:03 CET  run: 20250614 subRun: 1 event: 96
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 16:32:03 CET  run: 20250614 subRun: 1 event: 96
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
SimDepoSource got 877137 depos from art tag "InputTag: label = 'IonAndScint', instance = ''" returns: okay
Larwirecell::SimDepoSource got 877137 associated depos from InputTag: label = 'IonAndScint', instance = 'priorSCE'
SimDepoSource: ready with 877137 depos spanning: [-3192.97, 2993.95]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:39:37 CET  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/hd-protodune/cc/73/pdhd_prod_beam__238980_152_1_20251106T221101Z_gen_g4_IonScintPDExt.root_250208_14_1_20251123T231451Z_PDInt.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.010327       471.164       678.528       556.852       206.332         7     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00966543     0.0251698     0.113729      0.0101153     0.0361669        7     
simulate:rns:RandomNumberSaver                5.4482e-05    9.84094e-05   0.000342832   5.6943e-05    9.98257e-05       7     
simulate:tpcrawdecoder:WireCellToolkit          379.574       502.137       638.34        520.98        76.8515         7     
simulate:opdigi:OpDetDigitizerProtoDUNEHD       20.8467       29.4852       35.1074       31.0346       4.70237         7     
simulate:crt:CRTSimRefac                       0.0431669     0.0622965     0.0887732     0.060154      0.0167936        6     
[art]:TriggerResults:TriggerResultInserter    2.9173e-05    4.59755e-05   9.9038e-05    3.09865e-05   2.55184e-05       6     
end_path:out1:RootOutput                       5.388e-06    8.79467e-06   2.4707e-05    5.6685e-06    7.11749e-06       6     
end_path:out1:RootOutput(write)                 3.99162       4.4115        4.77497       4.43561      0.263744         6     
================================================================================================================================
%MSG-i NuRandomService:  RootOutput:out1@EndJob 02-Dec-2025 16:39:37 CET  ModuleEndJob

Summary of seeds computed by the NuRandomService
Random policy: 'random'
  master seed: 611679369
  seed within: [ 1 ; 900000000 ]

%MSG

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

  Peak virtual memory usage (VmPeak)  : 8724.6 MB
  Peak resident set size usage (VmHWM): 7014.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 = 3680.082247 Real = 3970.125192

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 8724.6 VmHWM = 7014.09

%MSG-s ArtException:  PostEndJob 02-Dec-2025 16:39:38 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 = 66460764, fKeylen = 109, fObjlen = 283492189, noutot = 16777215, nout=0, nin=5617334, nbuf=16777215
        ROOT severity: 3000
      ---- FatalRootError END
      
      The above exception was thrown while processing module CRTSimRefac/crt run: 20250614 subRun: 1 event: 96
    ---- 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