Jobsub ID 241045.0@dunegpschedd01.fnal.gov
| Jobsub ID | 241045.0@dunegpschedd01.fnal.gov |
| Workflow ID | 9404 |
| 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-30 17:05:54 |
| Site | NL_NIKHEF |
| Entry | VIRGO_NL_NIKHEF_juk |
| Last heartbeat | 2025-10-30 17:12:15 |
| From worker node | Hostname | wn-pep-012.farm.nikhef.nl |
| cpuinfo | Intel(R) Xeon(R) Gold 6148 CPU @ 2.40GHz |
| OS release | Scientific Linux release 7.9 (Nitrogen) |
| Processors | 1 |
| RSS bytes | 4194304000 (4000 MiB) |
| Wall seconds limit | 129600 (36 hours) |
| GPU | |
| Inner Apptainer? | True |
| Job state | jobscript_error |
| Started | 2025-10-30 17:07:55 |
| Input files | vd-protodune:np02vd_raw_run040270_0001_df-s03-d3_dw_0_20251027T123041.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-30 17:12:15 |
| Saved logs | justin-logs:241045.0-dunegpschedd01.fnal.gov.logs.tgz |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
ell::Aux::Resampler cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::Aux::Resampler cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::SigProc::ChannelSelector cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::Aux::Resampler cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::Aux::Resampler cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::SigProc::ChannelSelector cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::Aux::Resampler cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::Aux::Resampler cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::SigProc::ChannelSelector cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::SigProc::ChannelSelector cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.365] D [ pgraph ] connect <Node type:WireCell::SigProc::ChannelSelector cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
[18:08:27.366] D [ pgraph ] connect <Node type:WireCell::SigProc::ChannelSelector cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ]:(WireCell::IFrame:0) -> <Node type:WireCell::SigProc::OmnibusSigProc cat:3 sig:WireCell::IFrameFilter inputs:[ WireCell::IFrame ] outputs:[ WireCell::IFrame ](WireCell::IFrame:0)
Inputer: "wclsRawFrameSource"
Outputer: "wclsFrameSaver:spsaver"
wclsFrameSaver: promising to produce recob::Wires named "gauss"
wclsFrameSaver: promising to produce recob::Wires named "wiener"
db: runtime1761568239
Scale HitLimit based on readout window size 10000
HitLimit = 33333
RawFrameSource: got 12288 raw::RawDigit objects
input nticks=8000 keeping as is
[18:08:56.988] D [ main ] executing 1 apps, thread limit 0:
[18:08:56.989] D [ main ] executing 1 apps, thread limit 0:
[18:08:56.989] D [ main ] executing app: "Pgrapher"
[18:08:56.989] D [ pgraph ] <Pgrapher:> executing graph
[18:08:56.989] D [ pgraph ] executing with 26 nodes
[18:08:56.992] D [ glue ] <FrameFanout:nfsp> call=0: input: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig" ] 0 tagged trace sets:[ ] cmm:[ ] output 0: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig0" ] 0 tagged trace sets:[ ] cmm:[ ] output 1: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig1" ] 0 tagged trace sets:[ ] cmm:[ ] output 2: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig2" ] 0 tagged trace sets:[ ] cmm:[ ] output 3: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig3" ] 0 tagged trace sets:[ ] cmm:[ ] output 4: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig4" ] 0 tagged trace sets:[ ] cmm:[ ] output 5: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig5" ] 0 tagged trace sets:[ ] cmm:[ ] output 6: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] output 7: frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ]
[18:08:56.993] W [ glue ] <ChannelSelector:chsel7> Untagged summary not supported, summary will be dropped.
[18:08:56.995] D [ glue ] <ChannelSelector:chsel7> input frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=195 time=0 tick=512 with 1536 traces. frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ]
[18:08:56.995] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 input frame: frame: ident=195 time=0 tick=512 with 1536 traces. frame tags:[ "orig7" ] 0 tagged trace sets:[ ] cmm:[ ]
[18:08:56.995] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 init nticks=8000 tbinmin=0 tbinmax=8000
[18:08:57.066] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 load plane index: 0, ntraces=1536, input bad regions: 0
[18:08:58.751] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 load plane index: 1, ntraces=1536, input bad regions: 0
[18:09:00.483] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 load plane index: 2, ntraces=1536, input bad regions: 0
[18:10:09.210] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 save plane index: 0, Qtot=102727753203 Qloss=-96410327616, 7834 indices spanning [18882,26715] "wiener"
[18:10:09.452] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 save plane index: 0, Qtot=100294081004 Qloss=-97022574301, 7132 indices spanning [26716,33847] "gauss"
[18:10:11.033] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 save plane index: 1, Qtot=46138087470 Qloss=-12940778029, 16858 indices spanning [33848,50705] "wiener"
[18:10:11.269] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 save plane index: 1, Qtot=39393973406 Qloss=-13292886031, 16438 indices spanning [50706,67143] "gauss"
[18:10:11.701] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 save plane index: 2, Qtot=5752356979 Qloss=-2965006043, 14489 indices spanning [67144,81632] "wiener"
[18:10:12.131] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 save plane index: 2, Qtot=5070275437 Qloss=-2282881527, 16531 indices spanning [81633,98163] "gauss"
[18:10:12.132] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 produce 98164 traces: 39181 wiener7, 0 decon_charge7, 40101 gauss7, frame tag: sigproc
[18:10:12.132] D [sigproc ] <OmnibusSigProc:anode7sigproc7> call=0 output frame: frame: ident=195 time=0 tick=512 with 98164 traces. frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":40101 [0] "mp2_roi7":12363 [0] "mp3_roi7":6519 [0] "wiener7":39181 [39181] ] cmm:[ ]
[18:10:25.123] W [ glue ] <ChannelSelector:chsel6> Untagged summary not supported, summary will be dropped.
[18:10:25.124] D [ glue ] <ChannelSelector:chsel6> input frame: ident=195 time=0 tick=512 with 12288 traces. frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ] output: frame: ident=195 time=0 tick=512 with 1536 traces. frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ]
[18:10:25.125] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=0 input frame: frame: ident=195 time=0 tick=512 with 1536 traces. frame tags:[ "orig6" ] 0 tagged trace sets:[ ] cmm:[ ]
[18:10:25.125] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=0 init nticks=8000 tbinmin=0 tbinmax=8000
[18:10:25.173] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=0 load plane index: 0, ntraces=1536, input bad regions: 0
[18:10:27.405] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=0 load plane index: 1, ntraces=1536, input bad regions: 0
[18:10:29.692] D [sigproc ] <OmnibusSigProc:anode6sigproc6> call=0 load plane index: 2, ntraces=1536, input bad regions: 0
30-Oct-2025 18:11:43 CET Opened output file with pattern "%ifb_reco_stage1_%tc_keepup.root"
Malformed TimeTracker database. The TimeEvent table is empty, but
the TimeModule table is not. This can happen if an exception has
been thrown from a module while processing the first event. Any
saved database file is suspect and should not be used.
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8589.46 MB
Peak resident set size usage (VmHWM): 6670.55 MB
Details saved in: 'mem.db'
====================================================================================================
%MSG-s ArtException: PostEndJob 30-Oct-2025 18:11:45 CET 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: 40270 subRun: 1 event: 195
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