Jobsub ID 228590.151@dunegpschedd02.fnal.gov
| Jobsub ID | 228590.151@dunegpschedd02.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 04:48:58 |
| Site | CERN |
| Entry | CMSHTPC_T2_CH_CERN_ce509 |
| Last heartbeat | 2025-10-15 05:26:29 |
| 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 05:01:04 |
| 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 05:26:29 |
| Saved logs | justin-logs:228590.151-dunegpschedd02.fnal.gov.logs.tgz |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
i6":52 [0] "wiener6":210 [210] ] cmm:[ ]
[07:25:08.306] W [ glue ] <ChannelSelector:chsel5> Untagged summary not supported, summary will be dropped.
[07:25:08.306] 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:[ ]
[07:25:08.307] 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:[ ]
[07:25:08.307] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 init nticks=256 tbinmin=0 tbinmax=256
[07:25:08.325] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 0, ntraces=1536, input bad regions: 0
[07:25:08.361] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 1, ntraces=1536, input bad regions: 0
[07:25:08.398] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 load plane index: 2, ntraces=1536, input bad regions: 0
[07:25:08.458] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 0, Qtot=7119271 Qloss=-750698, 153 indices spanning [879,1031] "wiener"
[07:25:08.460] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 0, Qtot=6824012 Qloss=-523276, 117 indices spanning [1032,1148] "gauss"
[07:25:08.474] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 1, Qtot=8756818 Qloss=-897721, 254 indices spanning [1149,1402] "wiener"
[07:25:08.476] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 1, Qtot=8056425 Qloss=-477118, 194 indices spanning [1403,1596] "gauss"
[07:25:08.483] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 2, Qtot=7822262 Qloss=-850565, 248 indices spanning [1597,1844] "wiener"
[07:25:08.489] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 save plane index: 2, Qtot=7362790 Qloss=-396312, 186 indices spanning [1845,2030] "gauss"
[07:25:08.489] D [sigproc ] <OmnibusSigProc:anode5sigproc5> call=14 produce 2031 traces: 655 wiener5, 0 decon_charge5, 497 gauss5, frame tag: sigproc
[07:25:08.490] 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:[ ]
[07:25:08.492] W [ glue ] <ChannelSelector:chsel4> Untagged summary not supported, summary will be dropped.
[07:25:08.492] 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:[ ]
[07:25:08.493] 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:[ ]
[07:25:08.493] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 init nticks=256 tbinmin=0 tbinmax=256
[07:25:08.511] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 0, ntraces=1536, input bad regions: 0
[07:25:08.547] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 1, ntraces=1536, input bad regions: 0
[07:25:08.585] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 load plane index: 2, ntraces=1536, input bad regions: 0
[07:25:08.635] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 0, Qtot=3264488 Qloss=-412896, 89 indices spanning [451,539] "wiener"
[07:25:08.638] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 0, Qtot=3100137 Qloss=-267528, 68 indices spanning [540,607] "gauss"
[07:25:08.647] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 1, Qtot=5581689 Qloss=-1112289, 151 indices spanning [608,758] "wiener"
[07:25:08.650] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 1, Qtot=5286141 Qloss=-810696, 123 indices spanning [759,881] "gauss"
[07:25:08.656] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 2, Qtot=4072369 Qloss=-297785, 121 indices spanning [882,1002] "wiener"
[07:25:08.663] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 save plane index: 2, Qtot=3917937 Qloss=-148418, 102 indices spanning [1003,1104] "gauss"
[07:25:08.663] D [sigproc ] <OmnibusSigProc:anode4sigproc4> call=14 produce 1105 traces: 361 wiener4, 0 decon_charge4, 293 gauss4, frame tag: sigproc
[07:25:08.663] 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:[ ]
[07:25:08.664] W [ glue ] <ChannelSelector:chsel3> Untagged summary not supported, summary will be dropped.
[07:25:08.665] 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:[ ]
[07:25:08.666] D [ aux ] <Resampler:resmp3> first ch=4539 Ts=512 Ns=204 Ns_pad=250 Nrat=125 Tr=500 Nr=256 Nout=208 padding:linear
[07:25:08.674] D [ aux ] <Resampler:resmp3> resample 1536 traces at call=14
[07:25:08.674] 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:[ ]
[07:25:08.674] D [sigproc ] <OmnibusSigProc:anode3sigproc3> call=14 init nticks=208 tbinmin=0 tbinmax=208
[07:25:08.693] 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 113.951 200.597 375.765 158.694 94.1033 7
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.6571e-05 0.000101196 0.000233603 8.5041e-05 5.17378e-05 8
produce:tpcrawdecoder:PDVDTPCReader 0.454063 7.73646 13.5625 7.76089 3.40309 8
produce:triggerrawdecoder:PDVDTriggerReader4 0.0111289 0.0409724 0.0955556 0.0266853 0.0305878 8
produce:pdvddaphne:DAPHNEReaderPDVD 0.000324895 1.99134 2.44462 2.32157 0.770459 8
produce:ophit:OpHitFinder 7.3371e-05 0.0309481 0.0411715 0.034491 0.0120267 8
produce:opflash:OpFlashFinderVerticalDrift 5.357e-05 0.00336517 0.00610965 0.0034722 0.00184126 8
produce:wclsdatavd:WireCellToolkit 57.1987 73.651 124.689 64.3793 21.386 7
produce:gaushit:GausHitFinder 0.683001 1.34365 2.04871 1.42292 0.398784 7
produce:nhitsfilter:NumberOfHitsFilter 0.000161182 0.000337635 0.000474476 0.000353685 9.52991e-05 7
produce:reco3d:SpacePointSolver 8.57255 15.749 19.701 16.82 3.57234 7
produce:hitpdune:DisambigFromSpacePoints 0.0795807 0.207716 0.315999 0.204497 0.0747904 7
produce:pandora:StandardPandora 24.4725 91.6791 268.451 61.9667 80.0523 7
produce:pandoraTrack:LArPandoraTrackCreation 0.434329 1.35975 2.16656 1.40524 0.498124 7
produce:pandoraGnocalo:GnocchiCalorimetry 0.0150632 0.0273592 0.0413236 0.024994 0.0076527 7
[art]:TriggerResults:TriggerResultInserter 1.8031e-05 2.75646e-05 5.6601e-05 2.438e-05 1.22389e-05 7
end_path:out1:RootOutput 5.64e-06 9.76457e-06 2.55e-05 7.29e-06 6.52633e-06 7
end_path:out1:RootOutput(write) 3.80351 5.40849 9.42087 5.12514 1.72442 7
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6078.39 MB
Peak resident set size usage (VmHWM): 4125.66 MB
Details saved in: 'mem.db'
====================================================================================================
%MSG-s ArtException: PostEndJob 15-Oct-2025 07:25:09 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 