Jobsub ID 256218.60@dunegpschedd02.fnal.gov

Jobsub ID	256218.60@dunegpschedd02.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 09:00:49
Site	UK_Lancaster
Entry	UBoone_UK_Lancaster_HEC_grendel_ce02
Last heartbeat	2025-12-02 11:06:35
From worker node	Hostname	comp23-26
	cpuinfo	Intel(R) Xeon(R) Gold 6248 CPU @ 2.50GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	10485760000 (10000 MiB)
	Wall seconds limit	257400 (71 hours)
	GPU
	Inner Apptainer?	True
Job state	jobscript_error
Started	2025-12-02 09:49:38
Input files	hd-protodune:pdhd_prod_beam__239224_189_1_20251107T011402Z_gen_g4_IonScintPDExt.root_250309_15_1_20251124T035225Z_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 11:06:35
Saved logs	justin-logs:256218.60-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

4T035225Z_PDInt.root"
%MSG-i Root_Information:  TriggerResultInserter:TriggerResults@Construction  TClass::Init() 02-Dec-2025 09:50:19 GMT ModuleConstruction
no dictionary for class sim::IDE is available
ROOT severity: 2000
%MSG
02-Dec-2025 09:50:19 GMT  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 09:50:30 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 09:50:39 GMT  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 09:56:42 GMT  Opened output file with pattern "temp_detsim.root"
%MSG-i FastCloning:  PostProcessEvent 02-Dec-2025 09:56:42 GMT  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 10:28:29 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 10:28:42 GMT  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 10:35:11 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 10:35:41 GMT  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 10:41:38 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 10:42:39 GMT  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 10:48:21 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 10:48:31 GMT  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 10:52:02 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 10:52:11 GMT  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 11:00:29 GMT
%MSG-w PhotonBackTrackerService:  ProcessEvent 02-Dec-2025 11:00:41 GMT  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 11:05:40 GMT  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.00489076      308.186       496.022       356.159       147.784         7     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00428929     0.0218943     0.124807     0.00453507     0.0420174        7     
simulate:rns:RandomNumberSaver                 3.618e-05    7.37416e-05   0.000275017   3.9516e-05    8.22606e-05       7     
simulate:tpcrawdecoder:WireCellToolkit          176.718       290.04        451.319       299.294       80.6713         7     
simulate:opdigi:OpDetDigitizerProtoDUNEHD       30.5541       57.4984       126.772       41.4739       32.8556         6     
simulate:crt:CRTSimRefac                       0.0817747     0.140245      0.174111      0.155496      0.0355132        6     
[art]:TriggerResults:TriggerResultInserter    2.2723e-05     3.229e-05    6.7209e-05    2.60695e-05   1.56793e-05       6     
end_path:out1:RootOutput                       8.153e-06    1.08628e-05   2.1017e-05    8.7065e-06    4.58513e-06       6     
end_path:out1:RootOutput(write)                 2.82661       3.36626       4.01441       3.29478      0.359381         6     
================================================================================================================================
%MSG-i NuRandomService:  RootOutput:out1@EndJob 02-Dec-2025 11:05:42 GMT  ModuleEndJob

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

%MSG

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

  Peak virtual memory usage (VmPeak)  : 8897.33 MB
  Peak resident set size usage (VmHWM): 7177.83 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 = 2045.586731 Real = 4522.147148

MemReport  ---------- Memory summary [base-10 MB] ------
MemReport  VmPeak = 8897.33 VmHWM = 7177.83

%MSG-s ArtException:  PostEndJob 02-Dec-2025 11:05:45 GMT 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