Jobsub ID 234188.0@dunegpschedd01.fnal.gov
| Jobsub ID | 234188.0@dunegpschedd01.fnal.gov | 
| Workflow ID | 9003 | 
| Stage ID | 1 | 
| User name | ykermaid@fnal.gov | 
| HTCondor Group | group_dune.prod_mcsim | 
| Requested | Processors | 1 | 
| GPU | No | 
| RSS bytes | 4193255424 (3999 MiB) | 
| Wall seconds limit | 18000 (5 hours) | 
| Submitted time | 2025-10-15 07:45:06 | 
| Site | CERN | 
| Entry | CMSHTPC_T2_CH_CERN_ce509 | 
| Last heartbeat | 2025-10-15 08:12:38 | 
| From worker node | Hostname | b9p06p4022.cern.ch | 
| cpuinfo | AMD EPYC 7543 32-Core Processor | 
| OS release | Scientific Linux release 7.9 (Nitrogen) | 
| Processors | 1 | 
| RSS bytes | 4194304000 (4000 MiB) | 
| Wall seconds limit | 343800 (95 hours) | 
| GPU |  | 
| Inner Apptainer? | True | 
| Job state | jobscript_error | 
| Started | 2025-10-15 07:45:45 | 
| Input files | vd-protodune:np02vd_raw_run040073_0255_df-s03-d3_dw_0_20251015T034900.hdf5
  | 
| 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-10-15 08:12:38 | 
| Saved logs | justin-logs:234188.0-dunegpschedd01.fnal.gov.logs.tgz | 
| List job events     Cached HTCondor job logs | 
Jobscript log (last 10,000 characters)
i6":52 [0] "wiener6":210 [210] ] cmm:[ ] 
[10:12:07.474] W [  glue  ] <ChannelSelector:chsel5> Untagged summary not supported, summary will be dropped. 
[10:12:07.475] D [  glue  ] <ChannelSelector:chsel5> input frame: ident=44555 time=35 tick=512 with 12288 traces.  frame tags:[ "orig5" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=44555 time=35 tick=512 with 1536 traces.  frame tags:[ "orig5" ] 0 tagged trace sets:[ ] cmm:[ ] 
[10:12:07.475] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 input frame: frame: ident=44555 time=35 tick=512 with 1536 traces.  frame tags:[ "orig5" ] 0 tagged trace sets:[ ] cmm:[ ] 
[10:12:07.476] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 init nticks=256 tbinmin=0 tbinmax=256 
[10:12:07.494] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 0, ntraces=1536, input bad regions: 0 
[10:12:07.533] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 1, ntraces=1536, input bad regions: 0 
[10:12:07.572] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 2, ntraces=1536, input bad regions: 0 
[10:12:07.636] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 0, Qtot=7119271 Qloss=-750698, 153 indices spanning [879,1031] "wiener" 
[10:12:07.639] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 0, Qtot=6824012 Qloss=-523276, 117 indices spanning [1032,1148] "gauss" 
[10:12:07.653] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 1, Qtot=8756818 Qloss=-897721, 254 indices spanning [1149,1402] "wiener" 
[10:12:07.656] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 1, Qtot=8056425 Qloss=-477118, 194 indices spanning [1403,1596] "gauss" 
[10:12:07.663] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 2, Qtot=7822262 Qloss=-850565, 248 indices spanning [1597,1844] "wiener" 
[10:12:07.670] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 2, Qtot=7362790 Qloss=-396312, 186 indices spanning [1845,2030] "gauss" 
[10:12:07.670] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 produce 2031 traces: 655 wiener5, 0 decon_charge5, 497 gauss5, frame tag: sigproc 
[10:12:07.670] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 output frame: frame: ident=44555 time=35 tick=512 with 2031 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss5":497 [0] "mp2_roi5":669 [0] "mp3_roi5":210 [0] "wiener5":655 [655] ] cmm:[ ] 
[10:12:07.672] W [  glue  ] <ChannelSelector:chsel4> Untagged summary not supported, summary will be dropped. 
[10:12:07.673] D [  glue  ] <ChannelSelector:chsel4> input frame: ident=44555 time=35 tick=512 with 12288 traces.  frame tags:[ "orig4" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=44555 time=35 tick=512 with 1536 traces.  frame tags:[ "orig4" ] 0 tagged trace sets:[ ] cmm:[ ] 
[10:12:07.673] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 input frame: frame: ident=44555 time=35 tick=512 with 1536 traces.  frame tags:[ "orig4" ] 0 tagged trace sets:[ ] cmm:[ ] 
[10:12:07.674] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 init nticks=256 tbinmin=0 tbinmax=256 
[10:12:07.693] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 0, ntraces=1536, input bad regions: 0 
[10:12:07.732] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 1, ntraces=1536, input bad regions: 0 
[10:12:07.773] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 2, ntraces=1536, input bad regions: 0 
[10:12:07.827] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 0, Qtot=3264488 Qloss=-412896, 89 indices spanning [451,539] "wiener" 
[10:12:07.829] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 0, Qtot=3100137 Qloss=-267528, 68 indices spanning [540,607] "gauss" 
[10:12:07.838] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 1, Qtot=5581689 Qloss=-1112289, 151 indices spanning [608,758] "wiener" 
[10:12:07.841] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 1, Qtot=5286141 Qloss=-810696, 123 indices spanning [759,881] "gauss" 
[10:12:07.848] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 2, Qtot=4072369 Qloss=-297785, 121 indices spanning [882,1002] "wiener" 
[10:12:07.855] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 2, Qtot=3917937 Qloss=-148418, 102 indices spanning [1003,1104] "gauss" 
[10:12:07.855] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 produce 1105 traces: 361 wiener4, 0 decon_charge4, 293 gauss4, frame tag: sigproc 
[10:12:07.855] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 output frame: frame: ident=44555 time=35 tick=512 with 1105 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss4":293 [0] "mp2_roi4":361 [0] "mp3_roi4":90 [0] "wiener4":361 [361] ] cmm:[ ] 
[10:12:07.857] W [  glue  ] <ChannelSelector:chsel3> Untagged summary not supported, summary will be dropped. 
[10:12:07.857] D [  glue  ] <ChannelSelector:chsel3> input frame: ident=44555 time=35 tick=512 with 12288 traces.  frame tags:[ "orig3" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=44555 time=35 tick=512 with 1536 traces.  frame tags:[ "orig3" ] 0 tagged trace sets:[ ] cmm:[ ] 
[10:12:07.858] D [  aux   ] <Resampler:resmp3> first ch=4539 Ts=512 Ns=204 Ns_pad=250 Nrat=125 Tr=500 Nr=256 Nout=208 padding:linear 
[10:12:07.866] D [  aux   ] <Resampler:resmp3> resample 1536 traces at call=14 
[10:12:07.866] D [sigproc ] <OmnibusSigProc:anode3sigproc3> call=14 input frame: frame: ident=44555 time=35 tick=500 with 1536 traces.  frame tags:[ "orig3" ] 0 tagged trace sets:[ ] cmm:[ ] 
[10:12:07.866] D [sigproc ] <OmnibusSigProc:anode3sigproc3> call=14 init nticks=208 tbinmin=0 tbinmax=208 
[10:12:07.885] D [sigproc ] <OmnibusSigProc:anode3sigproc3> call=14 load plane index: 0, ntraces=1536, input bad regions: 0 
==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        130.326       220.245       438.282       166.822       115.389         7     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      7.2181e-05    0.000110503   0.000240994   8.6561e-05    5.27482e-05       8     
produce:tpcrawdecoder:PDVDTPCReader              0.533449       7.23668       13.1936       7.46262       3.24284         8     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00745032     0.0275474     0.0654537     0.0188413     0.0181824        8     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000320365     1.94511       2.53616       2.19905      0.754101         8     
produce:ophit:OpHitFinder                       9.9712e-05     0.0312735     0.0419683     0.0347385     0.0123212        8     
produce:opflash:OpFlashFinderVerticalDrift      6.0911e-05    0.00350546    0.00759693    0.00319802    0.00215319        8     
produce:wclsdatavd:WireCellToolkit                56.817        81.0475       147.307       70.7829       28.4398         7     
produce:gaushit:GausHitFinder                     1.01364       1.48464       2.20343       1.26567      0.431961         7     
produce:nhitsfilter:NumberOfHitsFilter          0.000230863   0.000329577   0.000474876   0.000289664   8.95717e-05       7     
produce:reco3d:SpacePointSolver                   9.96037       16.4238       23.4933       14.8767       4.67615         7     
produce:hitpdune:DisambigFromSpacePoints         0.0816829     0.288809      0.569648      0.196111      0.168034         7     
produce:pandora:StandardPandora                   29.1019       103.451       308.943       61.9199       92.7007         7     
produce:pandoraTrack:LArPandoraTrackCreation     0.433413       1.51754       2.30427       1.45813      0.609718         7     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0149311     0.0322967     0.0475298     0.0336596     0.0108435        7     
[art]:TriggerResults:TriggerResultInserter      1.9871e-05    3.03446e-05   6.6611e-05     2.159e-05    1.56944e-05       7     
end_path:out1:RootOutput                         3.58e-06     9.83571e-06    3.207e-05     7.09e-06     9.19577e-06       7     
end_path:out1:RootOutput(write)                   4.12945       5.46248       7.16238       5.49736       1.0152          7     
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
  Peak virtual memory usage (VmPeak)  : 6304.82 MB
  Peak resident set size usage (VmHWM): 4358.46 MB
  Details saved in: 'mem.db'
====================================================================================================
%MSG-s ArtException:  PostEndJob 15-Oct-2025 10:12:08 CEST ModuleEndJob
---- EventProcessorFailure BEGIN
  EventProcessor: an exception occurred during current event processing
  ---- ScheduleExecutionFailure BEGIN
    Path: ProcessingStopped.
    ---- BadAlloc BEGIN
      A bad_alloc exception was thrown while processing module WireCellToolkit/wclsdatavd run: 40073 subRun: 1 event: 44555
      The job has probably exhausted the virtual memory available to the process.
    ---- BadAlloc END
    Exception going through path produce
  ---- ScheduleExecutionFailure END
---- EventProcessorFailure END
%MSG
Art has completed and will exit with status 1.
Error in reco1