Jobsub ID 303649.50@dunegpschedd01.fnal.gov
| Jobsub ID | 303649.50@dunegpschedd01.fnal.gov |
| Workflow ID | 12718 |
| Stage ID | 1 |
| User name | ykermaid@fnal.gov |
| Requested | Processors | 1 |
| GPU | No |
| RSS bytes | 4194304000 (4000 MiB) |
| Wall seconds limit | 86400 (24 hours) |
| Submitted time | 2026-02-03 22:48:26 |
| Site | NL_NIKHEF |
| Entry | VIRGO_NL_NIKHEF_juk_multicore |
| Last heartbeat | 2026-02-04 00:16:04 |
| From worker node | Hostname | wn-sate-028.farm.nikhef.nl |
| cpuinfo | AMD EPYC 7551P 32-Core Processor |
| 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 | finished |
| Started | 2026-02-03 22:59:11 |
| Input files | vd-protodune:np02vd_raw_run042422_0115_df-s02-d0_dw_0_20260203T161657.hdf5
|
| Jobscript | Exit code | 0 |
| Real time | 1h (4444s) |
| CPU time | 1h (4165s = 93%) |
| Max RSS bytes | 2864500736 (2731 MiB) |
| Outputting started | 2026-02-04 00:13:17 |
| Output files | |
| Finished | 2026-02-04 00:16:04 |
| Saved logs | justin-logs:303649.50-dunegpschedd01.fnal.gov.logs.tgz |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
e6sigproc6> EOS at call=55 anode=6
[01:12:48.307] D [ glue ] <ChannelSelector:chsel5> see EOS at call=27
[01:12:48.307] D [sigproc ] <OmnibusNoiseFilter:nf5> EOS at call=55
[01:12:48.307] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=55 anode=5
[01:12:48.307] D [ glue ] <ChannelSelector:chsel4> see EOS at call=27
[01:12:48.307] D [sigproc ] <OmnibusNoiseFilter:nf4> EOS at call=55
[01:12:48.307] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=55 anode=4
[01:12:48.307] D [ glue ] <ChannelSelector:chsel3> see EOS at call=27
[01:12:48.307] D [ aux ] <Resampler:resmp3> EOS at call=55
[01:12:48.307] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=55
[01:12:48.307] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=55 anode=3
[01:12:48.307] D [ glue ] <ChannelSelector:chsel2> see EOS at call=27
[01:12:48.307] D [ aux ] <Resampler:resmp2> EOS at call=55
[01:12:48.307] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=55
[01:12:48.307] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=55 anode=2
[01:12:48.308] D [ glue ] <ChannelSelector:chsel1> see EOS at call=27
[01:12:48.308] D [ aux ] <Resampler:resmp1> EOS at call=55
[01:12:48.308] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=55
[01:12:48.308] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=55 anode=1
[01:12:48.308] D [ glue ] <ChannelSelector:chsel0> see EOS at call=27
[01:12:48.308] D [ aux ] <Resampler:resmp0> EOS at call=55
[01:12:48.308] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=55
[01:12:48.308] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=55 anode=0
[01:12:48.308] D [ glue ] <FrameFanin:nfsp> EOS at call=55 with 8
[01:12:48.308] D [ glue ] frame sink sees EOS
[01:12:48.308] D [ pgraph ] <Pgrapher:> graph execution complete
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.14 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.97 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.87 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.85 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.13 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.03 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.93 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.86 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.87 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.7 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.64 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.61 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.35 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.14 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.04 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.98 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:12:48.308] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[01:12:48.308] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[01:12:48.308] I [ timer ] Timer: Total node execution : 92.01000108197331 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 12288 traces tagged "gauss"
FrameSaver: q=2.17587e+09 n=4949025 tag=gauss
wclsFrameSaver: saving 12288 traces tagged "wiener"
FrameSaver: q=2.43183e+09 n=5006371 tag=wiener
04-Feb-2026 01:13:05 CET Closed output file "np02vd_raw_run042422_0115_df-s02-d0_dw_0_20260203T161657_reco_stage0_20260204T001305_offline.root"
===================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
===================================================================================================================================
Full event 110.635 155.404 187.165 164.814 24.1561 28
-----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.5952e-05 0.000111672 0.000236564 9.11715e-05 4.20053e-05 28
produce:tpcrawdecoder:PDVDTPCReader 10.9158 11.5907 14.1881 11.5001 0.554085 28
produce:triggerrawdecoder:PDVDTriggerReader4 0.511146 0.530345 0.553764 0.53482 0.0112215 28
produce:timingrawdecoder:PDHDTimingRawDecoder 0.0171806 0.0176539 0.0218691 0.0175394 0.000830126 28
produce:ctbrawdecoder:PDHDCTBRawDecoder 0.0850942 0.0894028 0.104675 0.0866602 0.00665138 28
produce:beamevent:BeamEvent 8.6503e-05 0.000177098 0.000910531 0.000147472 0.000146596 28
produce:pdvddaphne:DAPHNEReaderPDVD 3.01621 3.44529 3.99305 3.4701 0.189894 28
produce:ophit:OpHitFinder 0.027931 0.0449699 0.0582613 0.0454754 0.0059158 28
produce:wclsdatavd:WireCellToolkit 83.8722 88.5983 97.2703 87.9935 3.04452 28
produce:gaushit:GausHitFinder 0.683344 2.37092 9.27703 1.67466 2.22081 28
produce:nhitsfilter:NumberOfHitsFilter 0.000361069 0.000597896 0.00182931 0.000473245 0.000391041 28
produce:reco3d:SpacePointSolver 5.67405 12.2925 25.269 12.4768 4.48527 25
produce:hitpdune:DisambigFromSpacePoints 0.0716275 0.246285 0.391764 0.265688 0.0875012 25
produce:cluster3d:Cluster3D 1.00494 35.6707 51.9686 46.7148 19.9665 25
[art]:TriggerResults:TriggerResultInserter 2.8934e-05 4.47936e-05 9.6451e-05 3.86825e-05 1.73033e-05 28
end_path:out1:RootOutput 6.062e-06 9.79407e-06 2.7552e-05 8.3705e-06 4.00561e-06 28
end_path:out1:RootOutput(write) 4.1755 5.63493 6.40641 5.91259 0.662882 28
===================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4785.36 MB
Peak resident set size usage (VmHWM): 2864.5 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run042422_0115_df-s02-d0_dw_0_20260203T161657_reco_stage0_20260204T001305_offline.root
Forming reco metadata
Successfully opened file np02vd_raw_run042422_0115_df-s02-d0_dw_0_20260203T161657_reco_stage0_20260204T001305_offline.root
Ran successfully
{
"name": "np02vd_raw_run042422_0115_df-s02-d0_dw_0_20260203T161657_reco_stage0_20260204T001305_offline.root",
"namespace": "usertests",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_17_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage0_protodunevd_offline.fcl",
"dune.campaign": "vd-protodune-det-reco-test",
"core.start_time": 1770159562.0,
"core.end_time": 1770163987.0,
"core.events": [
18648,
18654,
18660,
18666,
18672,
18678,
18684,
18690,
18696,
18702,
18708,
18714,
18720,
18726,
18732,
18738,
18744,
18750,
18756,
18762,
18768,
18774,
18780,
18786,
18792,
18798,
18804,
18810
],
"core.event_count": 28,
"core.first_event_number": 18648,
"core.last_event_number": 18810,
"core.data_stream": "cosmics",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
42422
],
"core.runs_subruns": [
4242200001
]
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run042422_0115_df-s02-d0_dw_0_20260203T161657.hdf5"
}
]
} 