justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 47052.2@dunegpschedd01.fnal.gov

Jobsub ID47052.2@dunegpschedd01.fnal.gov
Workflow ID2332
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-16 16:42:07
SiteNL_NIKHEF
EntryVIRGO_NL_NIKHEF_juk
Last heartbeat2025-09-16 17:15:06
From worker nodeHostnamewn-snel-022.farm.nikhef.nl
cpuinfoAMD EPYC 7H12 64-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-16 16:42:58
Input filesvd-protodune:np02vd_raw_run039343_0032_df-s02-d0_dw_0_20250908T125035.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-16 17:15:06
Saved logsjustin-logs:47052.2-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

OpHitFinder:  OpHitFinder:ophit@BeginModule 16-Sep-2025 19:12:23 CEST  run: 39343 subRun: 1 event: 17122
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 16-Sep-2025 19:12:23 CEST  run: 39343 subRun: 1 event: 17122
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 16-Sep-2025 19:12:23 CEST  run: 39343 subRun: 1 event: 17122
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 16-Sep-2025 19:12:23 CEST  run: 39343 subRun: 1 event: 17122
Error! unrecognized channel number -1. Ignoring pulse
%MSG
%MSG-e OpHitFinder:  OpHitFinder:ophit@BeginModule 16-Sep-2025 19:12:23 CEST  run: 39343 subRun: 1 event: 17122
Error! unrecognized channel number -1. Ignoring pulse
%MSG
RawFrameSource: got 12288 raw::RawDigit objects
	input nticks=6400 keeping as is
[19:12:23.671] D [  main  ] executing 1 apps, thread limit 0:
[19:12:23.671] D [  main  ] executing 1 apps, thread limit 0:
[19:12:23.671] D [  main  ] executing app: "Pgrapher"
[19:12:23.671] D [ pgraph ] <Pgrapher:> executing graph 
[19:12:23.671] D [ pgraph ] executing with 26 nodes
[19:12:23.673] D [  glue  ] <FrameFanout:nfsp> call=10: input: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig" ] 0 tagged trace sets:[ ] cmm:[ ] output 0: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig0" ] 0 tagged trace sets:[ ] cmm:[ ] output 1: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig1" ] 0 tagged trace sets:[ ] cmm:[ ] output 2: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig2" ] 0 tagged trace sets:[ ] cmm:[ ] output 3: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig3" ] 0 tagged trace sets:[ ] cmm:[ ] output 4: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig4" ] 0 tagged trace sets:[ ] cmm:[ ] output 5: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig5" ] 0 tagged trace sets:[ ] cmm:[ ] output 6: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] output 7: frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ]  
[19:12:23.673] W [  glue  ] <ChannelSelector:chsel7> Untagged summary not supported, summary will be dropped. 
[19:12:23.673] D [  glue  ] <ChannelSelector:chsel7> input frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=17122 time=18 tick=512 with 1536 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:12:23.674] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 input frame: frame: ident=17122 time=18 tick=512 with 1536 traces.  frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:12:23.674] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 init nticks=6400 tbinmin=0 tbinmax=6400 
[19:12:23.709] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 load plane index: 0, ntraces=1536, input bad regions: 0 
[19:12:25.189] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 load plane index: 1, ntraces=1536, input bad regions: 0 
[19:12:26.523] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 load plane index: 2, ntraces=1536, input bad regions: 0 
[19:13:10.967] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 save plane index: 0, Qtot=74975412240 Qloss=-5726414939, 3893 indices spanning [14689,18581] "wiener" 
[19:13:11.118] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 save plane index: 0, Qtot=73395012679 Qloss=-5797995178, 3673 indices spanning [18582,22254] "gauss" 
[19:13:12.313] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 save plane index: 1, Qtot=90036031148 Qloss=-6709332296, 12679 indices spanning [22255,34933] "wiener" 
[19:13:12.473] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 save plane index: 1, Qtot=82868001708 Qloss=-7339796876, 11764 indices spanning [34934,46697] "gauss" 
[19:13:12.764] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 save plane index: 2, Qtot=2455891623 Qloss=-3909872311, 11266 indices spanning [46698,57963] "wiener" 
[19:13:13.057] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 save plane index: 2, Qtot=1905327210 Qloss=-3361864078, 12632 indices spanning [57964,70595] "gauss" 
[19:13:13.057] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 produce 70596 traces: 27838 wiener7, 0 decon_charge7, 28069 gauss7, frame tag: sigproc 
[19:13:13.057] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=10 output frame: frame: ident=17122 time=18 tick=512 with 70596 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":28069 [0] "mp2_roi7":10107 [0] "mp3_roi7":4582 [0] "wiener7":27838 [27838] ] cmm:[ ] 
[19:13:22.391] W [  glue  ] <ChannelSelector:chsel6> Untagged summary not supported, summary will be dropped. 
[19:13:22.392] D [  glue  ] <ChannelSelector:chsel6> input frame: ident=17122 time=18 tick=512 with 12288 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=17122 time=18 tick=512 with 1536 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:13:22.392] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=10 input frame: frame: ident=17122 time=18 tick=512 with 1536 traces.  frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] 
[19:13:22.392] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=10 init nticks=6400 tbinmin=0 tbinmax=6400 
[19:13:22.416] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=10 load plane index: 0, ntraces=1536, input bad regions: 0 
[19:13:23.799] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=10 load plane index: 1, ntraces=1536, input bad regions: 0 
[19:13:25.196] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=10 load plane index: 2, ntraces=1536, input bad regions: 0 

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                      6.6094e-05      277.165       766.22        218.798       235.523         6     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      5.6466e-05    8.7461e-05    0.000184917    7.178e-05    4.39683e-05       6     
produce:tpcrawdecoder:PDVDTPCReader               52.5736       78.9213       114.102       72.2679       22.0561         6     
produce:triggerrawdecoder:PDVDTriggerReader4      0.53859      0.570265      0.618803      0.571769      0.0273899        6     
produce:pdvddaphne:DAPHNEReaderPDVD               7.13282       10.5441       12.1946       10.8842       1.62542         6     
produce:ophit:OpHitFinder                        0.0460958     0.060063      0.0690159     0.0620148    0.00768627        6     
produce:opflash:OpFlashFinderVerticalDrift      0.00838749     0.015498      0.0202366     0.0160247    0.00365762        6     
produce:wclsdatavd:WireCellToolkit                39.5392       58.9896       81.6108       65.0819       16.3233         5     
produce:gaushit:GausHitFinder                    0.882298       1.54264       3.36673       1.10783      0.926134         5     
produce:nhitsfilter:NumberOfHitsFilter          0.000254859   0.000353069   0.000434926   0.000347282   7.04524e-05       5     
produce:reco3d:SpacePointSolver                   9.66409       14.1417       21.7052       12.3323       4.25707         5     
produce:hitpdune:DisambigFromSpacePoints         0.116249      0.187863      0.305079      0.174586      0.0638942        5     
produce:pandora:StandardPandora                   30.708        158.583       589.916       65.1363       216.223         5     
produce:pandoraTrack:LArPandoraTrackCreation     0.593109      0.722035      0.840849      0.744124      0.0986871        5     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0216386     0.0241154     0.0261906     0.0238574    0.00163406        5     
[art]:TriggerResults:TriggerResultInserter      1.4247e-05    2.0831e-05    4.4413e-05    1.5549e-05    1.18064e-05       5     
end_path:out1:RootOutput                         3.306e-06    6.4382e-06    1.7503e-05     3.687e-06    5.53699e-06       5     
end_path:out1:RootOutput(write)                   3.20714       3.83185       4.67241       3.57903      0.611933         5     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 8589.91 MB
  Peak resident set size usage (VmHWM): 6710.17 MB
  Details saved in: 'mem.db'
====================================================================================================
%MSG-s ArtException:  PostEndJob 16-Sep-2025 19:14:47 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: 39343 subRun: 1 event: 17122
      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:59:17 UTC       justIN version: 01.05.00