Jobsub ID 36363.149@dunegpschedd02.fnal.gov
| Jobsub ID | 36363.149@dunegpschedd02.fnal.gov |
| Workflow ID | 2326 |
| 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-09-08 12:51:55 |
| Site | CZ_FZU |
| Entry | Nova_CZ_FZU_osgce1 |
| Last heartbeat | 2025-09-08 15:52:42 |
| From worker node | Hostname | milan08.farm.particle.cz |
| 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 | 172800 (48 hours) |
| GPU | |
| Inner Apptainer? | True |
| Job state | outputting_failed |
| Started | 2025-09-08 14:45:39 |
| Input files | vd-protodune:np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915.hdf5
|
| Jobscript | Exit code | 0 |
| Real time | 1h (3834s) |
| CPU time | 53m (3236s = 84%) |
| Max RSS bytes | 3129802752 (2984 MiB) |
| Outputting started | 2025-09-08 15:49:34 |
| Output files | |
| Finished | 2025-09-08 15:52:42 |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
sink sees EOS
[17:44:14.756] D [ pgraph ] <Pgrapher:> graph execution complete
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.28 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.11 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.77 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.39 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.38 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.37 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.24 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.86 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Aux::Resampler : 0.41 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[17:44:14.756] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:44:14.756] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[17:44:14.756] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[17:44:14.756] I [ timer ] Timer: Total node execution : 48.909999588504434 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 39047 traces tagged "gauss"
FrameSaver: q=7.09688e+06 n=889221 tag=gauss
wclsFrameSaver: saving 48826 traces tagged "wiener"
FrameSaver: q=7.58011e+06 n=852602 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1434 XUs and 1176 XVs -> 42 XUVs
C:0 T:1 127 XUs and 157 XVs -> 6 XUVs
C:0 T:2 149 XUs and 254 XVs -> 5 XUVs
C:0 T:3 910 XUs and 1188 XVs -> 62 XUVs
C:0 T:4 798 XUs and 793 XVs -> 35 XUVs
C:0 T:5 1180 XUs and 1117 XVs -> 96 XUVs
C:0 T:6 1207 XUs and 1306 XVs -> 73 XUVs
C:0 T:7 294 XUs and 188 XVs -> 9 XUVs
C:0 T:8 25311 XUs and 46213 XVs -> 8884 XUVs
C:0 T:9 9908 XUs and 14148 XVs -> 1524 XUVs
C:0 T:10 9256 XUs and 11254 XVs -> 2264 XUVs
C:0 T:11 2181 XUs and 1598 XVs -> 77 XUVs
C:0 T:12 887 XUs and 744 XVs -> 80 XUVs
C:0 T:13 279 XUs and 362 XVs -> 26 XUVs
C:0 T:14 1436 XUs and 1406 XVs -> 119 XUVs
C:0 T:15 2715 XUs and 3070 XVs -> 137 XUVs
13439 XUVs total
1579 collection wire objects
13439 potential space points
Neighbour search...
2191639 tests to find 911028 neighbours
Iterating with no regularization...
Begin: 5.12704e+08
0 4.6533e+08
1 4.62593e+08
2 4.62105e+08
3 4.61914e+08
Now with regularization...
Begin: 4.52322e+08
0 4.52103e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
08-Sep-2025 17:45:17 CEST Closed output file "np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915_reco_stage1_20250908T154517_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 83.7536 135.996 224.782 127.317 39.2589 26
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.8e-05 9.42132e-05 0.00019823 9.2315e-05 2.93218e-05 26
produce:tpcrawdecoder:PDVDTPCReader 10.3633 13.1806 18.7224 12.9407 2.2144 26
produce:triggerrawdecoder:PDVDTriggerReader4 0.0314927 0.0327968 0.0456812 0.0320801 0.00269985 26
produce:pdvddaphne:DAPHNEReaderPDVD 2.64355 3.27342 3.73817 3.35316 0.312894 26
produce:ophit:OpHitFinder 0.0355286 0.0504326 0.0663375 0.0512597 0.00814284 26
produce:opflash:OpFlashFinderVerticalDrift 0.00611676 0.0121412 0.0188906 0.011073 0.00401695 26
produce:wclsdatavd:WireCellToolkit 35.1532 47.5949 70.822 46.1496 9.51145 26
produce:gaushit:GausHitFinder 0.669716 1.11226 1.95986 1.14191 0.302571 26
produce:nhitsfilter:NumberOfHitsFilter 0.000152769 0.000283858 0.000493228 0.000275749 8.01654e-05 26
produce:reco3d:SpacePointSolver 5.53685 11.8562 20.923 11.9714 3.77729 26
produce:hitpdune:DisambigFromSpacePoints 0.0697972 0.192943 0.309141 0.197115 0.0699364 26
produce:pandora:StandardPandora 15.9227 53.5428 122.513 42.9659 29.8251 26
produce:pandoraTrack:LArPandoraTrackCreation 0.247101 0.630425 1.4631 0.600504 0.282686 26
produce:pandoraGnocalo:GnocchiCalorimetry 0.0105286 0.0263887 0.0504712 0.0240952 0.0102982 26
[art]:TriggerResults:TriggerResultInserter 1.6469e-05 2.50463e-05 7.531e-05 2.24e-05 1.09965e-05 26
end_path:out1:RootOutput 3.53e-06 6.82038e-06 3.105e-05 5.22e-06 5.2433e-06 26
end_path:out1:RootOutput(write) 3.36428 4.4575 6.90897 4.38573 0.848188 26
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5107.5 MB
Peak resident set size usage (VmHWM): 3129.8 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915_reco_stage1_20250908T154517_keepup.root
\tHists: np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915_reco_stage1_20250908T154517_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915_reco_stage1_20250908T154517_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915_reco_stage1_20250908T154517_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1757346317.0,
"core.end_time": 1757346317.0,
"core.events": [
1170110,
1170130,
1170150,
1170170,
1170190,
1170210,
1170230,
1170250,
1170270,
1170290,
1170310,
1170330,
1170350,
1170370,
1170390,
1170410,
1170430,
1170450,
1170470,
1170490,
1170510,
1170530,
1170550,
1170570,
1170590,
1170610
],
"core.event_count": 26,
"core.first_event_number": 1170110,
"core.last_event_number": 1170610,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39324
],
"core.runs_subruns": [
3932400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915_reco_stage1_20250908T154517_keepup_hists.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "root",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_00d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1757346317.0,
"core.end_time": 1757346317.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39324
],
"core.runs_subruns": [
3932400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039324_2230_df-s04-d0_dw_0_20250907T145915.hdf5"
}
]
} 