justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 234173.6@dunegpschedd01.fnal.gov

Jobsub ID234173.6@dunegpschedd01.fnal.gov
Workflow ID9003
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune.prod_mcsim
RequestedProcessors1
GPUNo
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2025-10-15 06:55:03
SiteCERN
EntryCMSHTPC_T2_CH_CERN_ce505
Last heartbeat2025-10-15 07:44:23
From worker nodeHostnameb9p04p4865.cern.ch
cpuinfoAMD EPYC 7543 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit343800 (95 hours)
GPU
Inner Apptainer?True
Job statejobscript_error
Started2025-10-15 07:12:53
Input filesvd-protodune:np02vd_raw_run040073_0255_df-s03-d3_dw_0_20251015T034900.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-10-15 07:44:23
Saved logsjustin-logs:234173.6-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:[ ] 
[09:43:45.344] W [  glue  ] <ChannelSelector:chsel5> Untagged summary not supported, summary will be dropped. 
[09:43:45.345] 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:[ ] 
[09:43:45.345] 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:[ ] 
[09:43:45.345] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 init nticks=256 tbinmin=0 tbinmax=256 
[09:43:45.365] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 0, ntraces=1536, input bad regions: 0 
[09:43:45.406] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 1, ntraces=1536, input bad regions: 0 
[09:43:45.446] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 2, ntraces=1536, input bad regions: 0 
[09:43:45.511] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 0, Qtot=7119271 Qloss=-750698, 153 indices spanning [879,1031] "wiener" 
[09:43:45.513] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 0, Qtot=6824012 Qloss=-523276, 117 indices spanning [1032,1148] "gauss" 
[09:43:45.528] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 1, Qtot=8756818 Qloss=-897721, 254 indices spanning [1149,1402] "wiener" 
[09:43:45.531] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 1, Qtot=8056425 Qloss=-477118, 194 indices spanning [1403,1596] "gauss" 
[09:43:45.539] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 2, Qtot=7822262 Qloss=-850565, 248 indices spanning [1597,1844] "wiener" 
[09:43:45.546] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 2, Qtot=7362790 Qloss=-396312, 186 indices spanning [1845,2030] "gauss" 
[09:43:45.546] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 produce 2031 traces: 655 wiener5, 0 decon_charge5, 497 gauss5, frame tag: sigproc 
[09:43:45.546] 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:[ ] 
[09:43:45.548] W [  glue  ] <ChannelSelector:chsel4> Untagged summary not supported, summary will be dropped. 
[09:43:45.549] 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:[ ] 
[09:43:45.549] 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:[ ] 
[09:43:45.549] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 init nticks=256 tbinmin=0 tbinmax=256 
[09:43:45.568] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 0, ntraces=1536, input bad regions: 0 
[09:43:45.609] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 1, ntraces=1536, input bad regions: 0 
[09:43:45.650] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 2, ntraces=1536, input bad regions: 0 
[09:43:45.703] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 0, Qtot=3264488 Qloss=-412896, 89 indices spanning [451,539] "wiener" 
[09:43:45.706] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 0, Qtot=3100137 Qloss=-267528, 68 indices spanning [540,607] "gauss" 
[09:43:45.716] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 1, Qtot=5581689 Qloss=-1112289, 151 indices spanning [608,758] "wiener" 
[09:43:45.718] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 1, Qtot=5286141 Qloss=-810696, 123 indices spanning [759,881] "gauss" 
[09:43:45.726] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 2, Qtot=4072369 Qloss=-297785, 121 indices spanning [882,1002] "wiener" 
[09:43:45.733] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 2, Qtot=3917937 Qloss=-148418, 102 indices spanning [1003,1104] "gauss" 
[09:43:45.733] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 produce 1105 traces: 361 wiener4, 0 decon_charge4, 293 gauss4, frame tag: sigproc 
[09:43:45.733] 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:[ ] 
[09:43:45.734] W [  glue  ] <ChannelSelector:chsel3> Untagged summary not supported, summary will be dropped. 
[09:43:45.735] 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:[ ] 
[09:43:45.736] D [  aux   ] <Resampler:resmp3> first ch=4539 Ts=512 Ns=204 Ns_pad=250 Nrat=125 Tr=500 Nr=256 Nout=208 padding:linear 
[09:43:45.744] D [  aux   ] <Resampler:resmp3> resample 1536 traces at call=14 
[09:43:45.744] 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:[ ] 
[09:43:45.744] D [sigproc ] <OmnibusSigProc:anode3sigproc3> call=14 init nticks=208 tbinmin=0 tbinmax=208 
[09:43:45.763] 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                                        128.129       258.431       494.566       191.482       133.954         7     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      6.2921e-05    0.000119552   0.000324025   9.1221e-05    7.9995e-05        8     
produce:tpcrawdecoder:PDVDTPCReader              0.615325       8.00436       17.6062       7.31482       4.34765         8     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00640507     0.0222591     0.103312      0.0112364     0.0308107        8     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000345676     2.09132       2.74951       2.32763      0.814315         8     
produce:ophit:OpHitFinder                       0.000100651    0.0333379     0.0439858     0.0370709     0.0130367        8     
produce:opflash:OpFlashFinderVerticalDrift      5.8241e-05    0.00372055    0.00758099    0.00318414    0.00243027        8     
produce:wclsdatavd:WireCellToolkit                73.9532       92.5249       172.765       79.932        32.9742         7     
produce:gaushit:GausHitFinder                     1.14337       1.83097       2.17632       1.94004      0.315052         7     
produce:nhitsfilter:NumberOfHitsFilter          0.000255594   0.000416891   0.000795364   0.000388417   0.000163751       7     
produce:reco3d:SpacePointSolver                   9.80779       19.192        25.1066       19.9938       4.51252         7     
produce:hitpdune:DisambigFromSpacePoints         0.150348      0.342243      0.518604      0.366017       0.10511         7     
produce:pandora:StandardPandora                   26.3452       124.401       364.241       72.2637       110.608         7     
produce:pandoraTrack:LArPandoraTrackCreation     0.689024       1.93696       3.39552       1.9435       0.795584         7     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0305048     0.0426684     0.0520795     0.0456864    0.00741552        7     
[art]:TriggerResults:TriggerResultInserter      2.7621e-05    3.67736e-05   6.5511e-05    3.3141e-05    1.23025e-05       7     
end_path:out1:RootOutput                         4.34e-06     1.0676e-05    2.8051e-05     8.99e-06     7.40903e-06       7     
end_path:out1:RootOutput(write)                   5.09731       6.58691       10.9975       5.8944        1.87839         7     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6351.61 MB
  Peak resident set size usage (VmHWM): 4403.94 MB
  Details saved in: 'mem.db'
====================================================================================================
%MSG-s ArtException:  PostEndJob 15-Oct-2025 09:43:46 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
justIN time: 2025-11-04 12:31:21 UTC       justIN version: 01.05.01