Jobsub ID 43078.75@dunegpschedd01.fnal.gov
| Jobsub ID | 43078.75@dunegpschedd01.fnal.gov |
| Workflow ID | 2323 |
| 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:07:53 |
| Site | CZ_FZU |
| Entry | Nova_CZ_FZU_osgce2 |
| Last heartbeat | 2025-09-08 15:52:34 |
| From worker node | Hostname | milan07.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:42:52 |
| Input files | vd-protodune:np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221.hdf5
|
| Jobscript | Exit code | 0 |
| Real time | 58m (3515s) |
| CPU time | 51m (3106s = 88%) |
| Max RSS bytes | 2929106944 (2793 MiB) |
| Outputting started | 2025-09-08 15:41:28 |
| Output files | |
| Finished | 2025-09-08 15:52:34 |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
mer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.24 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.11 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.85 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.75 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.2 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:39:07.138] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:39:07.138] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[17:39:07.138] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[17:39:07.138] I [ timer ] Timer: Total node execution : 45.50999969616532 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 47964 traces tagged "gauss"
FrameSaver: q=8.30236e+06 n=1072822 tag=gauss
wclsFrameSaver: saving 60109 traces tagged "wiener"
FrameSaver: q=8.97855e+06 n=1022879 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 87 XUs and 112 XVs -> 2 XUVs
C:0 T:1 786 XUs and 669 XVs -> 33 XUVs
C:0 T:2 882 XUs and 826 XVs -> 39 XUVs
C:0 T:3 1673 XUs and 1857 XVs -> 82 XUVs
C:0 T:4 828 XUs and 1738 XVs -> 56 XUVs
C:0 T:5 3394 XUs and 2141 XVs -> 653 XUVs
C:0 T:6 1642 XUs and 2061 XVs -> 89 XUVs
C:0 T:7 3295 XUs and 3414 XVs -> 249 XUVs
C:0 T:8 13755 XUs and 27452 XVs -> 4190 XUVs
C:0 T:9 9731 XUs and 19973 XVs -> 3054 XUVs
C:0 T:10 8924 XUs and 14646 XVs -> 2458 XUVs
C:0 T:11 12817 XUs and 27713 XVs -> 5647 XUVs
C:0 T:12 14338 XUs and 23554 XVs -> 3259 XUVs
C:0 T:13 7985 XUs and 9103 XVs -> 1073 XUVs
C:0 T:14 7176 XUs and 16760 XVs -> 1958 XUVs
C:0 T:15 614 XUs and 434 XVs -> 48 XUVs
22890 XUVs total
2393 collection wire objects
22890 potential space points
Neighbour search...
3457488 tests to find 1432254 neighbours
Iterating with no regularization...
Begin: 3.12623e+08
0 2.43693e+08
1 2.40163e+08
2 2.39626e+08
3 2.39425e+08
Now with regularization...
Begin: 2.31151e+08
0 2.30881e+08
1 2.30684e+08
PcaShowerParticleBuildingAlgorithm::OpeningAngle - principal eigenvalue less than or equal to 0.
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
08-Sep-2025 17:40:19 CEST Closed output file "np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221_reco_stage1_20250908T154019_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 85.458 131.077 304.127 117.78 47.4679 26
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.1789e-05 0.000117098 0.000460358 0.00010321 7.17754e-05 26
produce:tpcrawdecoder:PDVDTPCReader 10.5661 12.5634 20.6571 11.2115 2.56841 26
produce:triggerrawdecoder:PDVDTriggerReader4 0.0310821 0.0316826 0.0335721 0.031544 0.000562079 26
produce:pdvddaphne:DAPHNEReaderPDVD 2.64566 3.24566 3.74845 3.31472 0.277319 26
produce:ophit:OpHitFinder 0.0293423 0.051236 0.0667374 0.0490898 0.0104995 26
produce:opflash:OpFlashFinderVerticalDrift 0.00373657 0.0114123 0.019088 0.0103605 0.00364401 26
produce:wclsdatavd:WireCellToolkit 32.7631 44.9053 65.4444 42.3041 7.88953 26
produce:gaushit:GausHitFinder 0.478448 1.07793 1.55236 1.11784 0.270552 26
produce:nhitsfilter:NumberOfHitsFilter 0.00011249 0.000260104 0.000420179 0.000267694 6.8941e-05 26
produce:reco3d:SpacePointSolver 5.8615 10.8858 16.9175 10.7546 2.96838 26
produce:hitpdune:DisambigFromSpacePoints 0.0851207 0.173425 0.368378 0.151851 0.0681506 26
produce:pandora:StandardPandora 18.105 53.3912 221.92 41.8788 43.4427 26
produce:pandoraTrack:LArPandoraTrackCreation 0.274942 0.556118 1.03065 0.50408 0.200825 26
produce:pandoraGnocalo:GnocchiCalorimetry 0.0131463 0.0224239 0.0406692 0.0204152 0.00775061 26
[art]:TriggerResults:TriggerResultInserter 1.979e-05 2.86953e-05 4.726e-05 2.7385e-05 7.16815e-06 26
end_path:out1:RootOutput 4.31e-06 8.63923e-06 2.309e-05 8.205e-06 3.31226e-06 26
end_path:out1:RootOutput(write) 3.20508 4.12151 5.78948 4.00748 0.617421 26
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4883.98 MB
Peak resident set size usage (VmHWM): 2929.11 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221_reco_stage1_20250908T154019_keepup.root
\tHists: np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221_reco_stage1_20250908T154020_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221_reco_stage1_20250908T154019_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221_reco_stage1_20250908T154019_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": 1757346020.0,
"core.end_time": 1757346020.0,
"core.events": [
898684,
898704,
898724,
898744,
898764,
898784,
898804,
898824,
898844,
898864,
898884,
898904,
898924,
898944,
898964,
898984,
899004,
899024,
899044,
899064,
899084,
899104,
899124,
899144,
899164,
899184
],
"core.event_count": 26,
"core.first_event_number": 898684,
"core.last_event_number": 899184,
"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_1715_df-s02-d2_dw_0_20250907T042221.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039324_1715_df-s02-d2_dw_0_20250907T042221_reco_stage1_20250908T154020_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": 1757346020.0,
"core.end_time": 1757346020.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_1715_df-s02-d2_dw_0_20250907T042221.hdf5"
}
]
} 