Jobsub ID 40378.188@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
8
[13:37:34.194] D [ glue ] frame sink sees EOS
[13:37:34.194] D [ pgraph ] <Pgrapher:> graph execution complete
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.85 sec
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.78 sec
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.61 sec
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.69 sec
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.62 sec
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.5 sec
[13:37:34.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.26 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.67 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Aux::Resampler : 0.65 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Aux::Resampler : 0.64 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Aux::Resampler : 0.64 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Aux::Resampler : 0.61 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[13:37:34.195] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:37:34.195] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:37:34.195] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:37:34.195] I [ timer ] Timer: Total node execution : 61.559999980032444 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 69228 traces tagged "gauss"
FrameSaver: q=1.28828e+07 n=1551329 tag=gauss
wclsFrameSaver: saving 86208 traces tagged "wiener"
FrameSaver: q=1.38851e+07 n=1484252 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1234 XUs and 1370 XVs -> 94 XUVs
C:0 T:1 1692 XUs and 1990 XVs -> 62 XUVs
C:0 T:2 586 XUs and 553 XVs -> 8 XUVs
C:0 T:3 1225 XUs and 1498 XVs -> 63 XUVs
C:0 T:4 403 XUs and 377 XVs -> 21 XUVs
C:0 T:5 719 XUs and 942 XVs -> 43 XUVs
C:0 T:6 729 XUs and 939 XVs -> 46 XUVs
C:0 T:7 3852 XUs and 4262 XVs -> 261 XUVs
C:0 T:8 29990 XUs and 47370 XVs -> 9630 XUVs
C:0 T:9 4535 XUs and 7282 XVs -> 1772 XUVs
C:0 T:10 11498 XUs and 22308 XVs -> 4487 XUVs
C:0 T:11 14597 XUs and 29784 XVs -> 2880 XUVs
C:0 T:12 20383 XUs and 50374 XVs -> 5780 XUVs
C:0 T:13 10048 XUs and 11574 XVs -> 1181 XUVs
C:0 T:14 3089 XUs and 4709 XVs -> 725 XUVs
C:0 T:15 1635 XUs and 2347 XVs -> 94 XUVs
27147 XUVs total
3111 collection wire objects
27147 potential space points
Neighbour search...
4296439 tests to find 1734764 neighbours
Iterating with no regularization...
Begin: 1.16662e+09
0 1.02468e+09
1 1.0141e+09
2 1.01281e+09
3 1.01239e+09
Now with regularization...
Begin: 9.95898e+08
0 9.95509e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
16-Sep-2025 13:41:24 CEST Closed output file "np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005_reco_stage1_20250916T114124_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 91.3611 147.584 380.872 121.5 70.6908 19
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.6251e-05 8.78008e-05 0.000167451 7.8071e-05 3.05071e-05 19
produce:tpcrawdecoder:PDVDTPCReader 6.6534 7.04854 7.45637 7.03402 0.191904 19
produce:triggerrawdecoder:PDVDTriggerReader4 0.00577994 0.0084831 0.0168362 0.00729294 0.00309214 19
produce:pdvddaphne:DAPHNEReaderPDVD 0.000263322 0.00035277 0.00103829 0.000318432 0.000164207 19
produce:ophit:OpHitFinder 0.000139891 0.000318806 0.00129464 0.000255002 0.000252571 19
produce:opflash:OpFlashFinderVerticalDrift 5.093e-05 0.000101241 0.000556154 7.48e-05 0.000108491 19
produce:wclsdatavd:WireCellToolkit 51.0308 59.759 71.3698 58.9184 5.45251 19
produce:gaushit:GausHitFinder 0.722742 1.18197 2.14046 1.06353 0.35962 19
produce:nhitsfilter:NumberOfHitsFilter 0.000192491 0.000313038 0.000589495 0.000316042 0.000100787 19
produce:reco3d:SpacePointSolver 7.95811 14.2792 34.901 12.7162 6.12072 19
produce:hitpdune:DisambigFromSpacePoints 0.0858245 0.241851 0.737684 0.167346 0.183315 19
produce:pandora:StandardPandora 18.5402 59.6407 258.37 37.5215 60.6575 19
produce:pandoraTrack:LArPandoraTrackCreation 0.476092 0.947064 1.72901 0.800952 0.395758 19
produce:pandoraGnocalo:GnocchiCalorimetry 0.0169822 0.0286444 0.0499658 0.0282394 0.00971539 19
[art]:TriggerResults:TriggerResultInserter 1.625e-05 3.5345e-05 0.000219692 2.307e-05 4.49644e-05 19
end_path:out1:RootOutput 5.05e-06 1.09647e-05 3.791e-05 9.07e-06 6.66928e-06 19
end_path:out1:RootOutput(write) 3.77429 4.41985 6.45231 4.10293 0.744449 19
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4921.89 MB
Peak resident set size usage (VmHWM): 2965.18 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005_reco_stage1_20250916T114124_keepup.root
\tHists: np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005_reco_stage1_20250916T114124_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005_reco_stage1_20250916T114124_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005_reco_stage1_20250916T114124_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": 1758022885.0,
"core.end_time": 1758022885.0,
"core.events": [
68906,
68910,
68914,
68918,
68922,
68926,
68930,
68934,
68938,
68942,
68946,
68950,
68954,
68958,
68962,
68966,
68970,
68974,
68978
],
"core.event_count": 19,
"core.first_event_number": 68906,
"core.last_event_number": 68978,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39353
],
"core.runs_subruns": [
3935300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005_reco_stage1_20250916T114124_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": 1758022885.0,
"core.end_time": 1758022885.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39353
],
"core.runs_subruns": [
3935300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039353_0894_df-s05-d2_dw_0_20250916T095005.hdf5"
}
]
} 