justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 40112.1@dunegpschedd02.fnal.gov

Jobsub ID40112.1@dunegpschedd02.fnal.gov
Workflow ID2521
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune.prod_mcsim
RequestedProcessors1
GPUNo
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2025-09-15 16:20:56
SiteNL_NIKHEF
EntryVIRGO_NL_NIKHEF_dissel
Last heartbeat2025-09-15 17:46:18
From worker nodeHostnamewn-sate-048.farm.nikhef.nl
cpuinfoAMD EPYC 7551P 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit129600 (36 hours)
GPU
Inner Apptainer?True
Job statejobscript_error
Started2025-09-15 16:21:23
Input filesvd-protodune:np02vd_raw_run039273_0661_df-s05-d5_dw_0_20250831T182242.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-15 17:46:18
Saved logsjustin-logs:40112.1-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

OpHitFinder:  OpHitFinder:ophit@BeginModule 15-Sep-2025 19:44:32 CEST  run: 39273 subRun: 1 event: 238279
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 15-Sep-2025 19:44:32 CEST  run: 39273 subRun: 1 event: 238279
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 15-Sep-2025 19:44:32 CEST  run: 39273 subRun: 1 event: 238279
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 15-Sep-2025 19:44:32 CEST  run: 39273 subRun: 1 event: 238279
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 15-Sep-2025 19:44:32 CEST  run: 39273 subRun: 1 event: 238279
Error! unrecognized channel number -1. Ignoring pulse
%MSG
RawFrameSource: got 12288 raw::RawDigit objects
	input nticks=10048 keeping as is
[19:44:32.253] D [  main  ] executing 1 apps, thread limit 0:
[19:44:32.253] D [  main  ] executing 1 apps, thread limit 0:
[19:44:32.253] D [  main  ] executing app: "Pgrapher"
[19:44:32.253] D [ pgraph ] <Pgrapher:> executing graph 
[19:44:32.253] D [ pgraph ] executing with 26 nodes
[19:44:32.255] D [  glue  ] <FrameFanout:nfsp> call=30: input: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig" ] 0 tagged trace sets:[ ] cmm:[ ] output 0: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig0" ] 0 tagged trace sets:[ ] cmm:[ ] output 1: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig1" ] 0 tagged trace sets:[ ] cmm:[ ] output 2: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig2" ] 0 tagged trace sets:[ ] cmm:[ ] output 3: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig3" ] 0 tagged trace sets:[ ] cmm:[ ] output 4: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig4" ] 0 tagged trace sets:[ ] cmm:[ ] output 5: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig5" ] 0 tagged trace sets:[ ] cmm:[ ] output 6: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] output 7: frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ]  
[19:44:32.255] W [  glue  ] <ChannelSelector:chsel7> Untagged summary not supported, summary will be dropped. 
[19:44:32.255] D [  glue  ] <ChannelSelector:chsel7> input frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=238279 time=43 tick=512 with 1536 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:44:32.256] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 input frame: frame: ident=238279 time=43 tick=512 with 1536 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:44:32.256] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 init nticks=10048 tbinmin=0 tbinmax=10048 
[19:44:32.293] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 load plane index: 0, ntraces=1536, input bad regions: 0 
[19:44:35.417] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 load plane index: 1, ntraces=1536, input bad regions: 0 
[19:44:38.666] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 load plane index: 2, ntraces=1536, input bad regions: 0 
[19:44:47.250] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 save plane index: 0, Qtot=715460582 Qloss=-23342060, 5244 indices spanning [28990,34233] "wiener" 
[19:44:47.543] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 save plane index: 0, Qtot=704359220 Qloss=-19672147, 4119 indices spanning [34234,38352] "gauss" 
[19:44:48.151] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 save plane index: 1, Qtot=177887468 Qloss=-26310308, 5371 indices spanning [38353,43723] "wiener" 
[19:44:48.438] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 save plane index: 1, Qtot=165215210 Qloss=-20806021, 4248 indices spanning [43724,47971] "gauss" 
[19:44:48.923] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 save plane index: 2, Qtot=170000602 Qloss=-14470481, 7187 indices spanning [47972,55158] "wiener" 
[19:44:49.402] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 save plane index: 2, Qtot=162612594 Qloss=-6910272, 5950 indices spanning [55159,61108] "gauss" 
[19:44:49.402] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 produce 61109 traces: 17802 wiener7, 0 decon_charge7, 14317 gauss7, frame tag: sigproc 
[19:44:49.402] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=30 output frame: frame: ident=238279 time=43 tick=512 with 61109 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":14317 [0] "mp2_roi7":23370 [0] "mp3_roi7":5620 [0] "wiener7":17802 [17802] ] cmm:[ ] 
[19:44:49.652] W [  glue  ] <ChannelSelector:chsel6> Untagged summary not supported, summary will be dropped. 
[19:44:49.652] D [  glue  ] <ChannelSelector:chsel6> input frame: ident=238279 time=43 tick=512 with 12288 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=238279 time=43 tick=512 with 1536 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:44:49.653] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=30 input frame: frame: ident=238279 time=43 tick=512 with 1536 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:44:49.653] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=30 init nticks=10048 tbinmin=0 tbinmax=10048 
[19:44:49.688] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=30 load plane index: 0, ntraces=1536, input bad regions: 0 
[19:44:52.697] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=30 load plane index: 1, ntraces=1536, input bad regions: 0 
[19:44:55.865] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=30 load plane index: 2, ntraces=1536, input bad regions: 0 

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                      6.7717e-05      303.27        431.597       329.143       95.0247        16     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      6.3119e-05    8.43632e-05   0.000242725   7.0738e-05    4.1754e-05       16     
produce:tpcrawdecoder:PDVDTPCReader               51.0347       73.9299       94.5677       77.5563       12.6134        16     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0346633     0.0376265     0.0540072     0.0349673    0.00574867       16     
produce:pdvddaphne:DAPHNEReaderPDVD               7.56849       10.1354       15.0984       10.0334       1.95264        16     
produce:ophit:OpHitFinder                        0.0443308     0.0607187     0.0725483     0.0620441    0.00709528       16     
produce:opflash:OpFlashFinderVerticalDrift      0.00947169     0.0163661     0.0261175     0.0161142    0.00481104       16     
produce:wclsdatavd:WireCellToolkit                73.5005       87.8526       100.597       86.7703       6.31553        15     
produce:gaushit:GausHitFinder                     1.13062       1.83481       2.35398       1.97733      0.354153        15     
produce:nhitsfilter:NumberOfHitsFilter          0.000290616   0.000414835   0.000717667   0.000376527   9.9396e-05       15     
produce:reco3d:SpacePointSolver                   12.6503       23.896        37.9995       24.8098       7.03872        15     
produce:hitpdune:DisambigFromSpacePoints         0.182574      0.410377      0.697495      0.420334      0.141439        15     
produce:pandora:StandardPandora                   38.7759       117.098       200.271       114.034       46.1741        15     
produce:pandoraTrack:LArPandoraTrackCreation      1.08509       2.23833       3.49943       2.24395      0.727869        15     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0290891     0.0453937     0.057441      0.0446532    0.00887668       15     
[art]:TriggerResults:TriggerResultInserter      2.5758e-05    3.76413e-05   8.0231e-05    3.5377e-05    1.32685e-05      15     
end_path:out1:RootOutput                         4.328e-06    8.81973e-06   2.5598e-05     7.684e-06    4.66223e-06      15     
end_path:out1:RootOutput(write)                   5.92542       6.43772       6.91054       6.42224      0.281304        15     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 8589.91 MB
  Peak resident set size usage (VmHWM): 6711.97 MB
  Details saved in: 'mem.db'
====================================================================================================
%MSG-s ArtException:  PostEndJob 15-Sep-2025 19:45:59 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: 39273 subRun: 1 event: 238279
      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-09-18 19:26:42 UTC       justIN version: 01.05.00