Jobsub ID 41655.81@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
0> EOS at call=95 anode=0
[09:06:00.030] D [ glue ] <FrameFanin:nfsp> EOS at call=95 with 8
[09:06:00.030] D [ glue ] frame sink sees EOS
[09:06:00.030] D [ pgraph ] <Pgrapher:> graph execution complete
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.15 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.04 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.72 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.28 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Aux::Resampler : 0.37 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[09:06:00.030] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[09:06:00.030] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:06:00.030] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[09:06:00.030] I [ timer ] Timer: Total node execution : 24.69000007584691 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 22074 traces tagged "gauss"
FrameSaver: q=3.18871e+06 n=440086 tag=gauss
wclsFrameSaver: saving 28416 traces tagged "wiener"
FrameSaver: q=3.54703e+06 n=424583 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 363 XUs and 534 XVs -> 26 XUVs
C:0 T:1 242 XUs and 376 XVs -> 12 XUVs
C:0 T:2 499 XUs and 780 XVs -> 27 XUVs
C:0 T:3 2577 XUs and 2982 XVs -> 193 XUVs
C:0 T:4 9401 XUs and 13954 XVs -> 1116 XUVs
C:0 T:5 2506 XUs and 3492 XVs -> 287 XUVs
C:0 T:6 1077 XUs and 1302 XVs -> 93 XUVs
C:0 T:7 1848 XUs and 2269 XVs -> 97 XUVs
1851 XUVs total
980 collection wire objects
1851 potential space points
Neighbour search...
73819 tests to find 24304 neighbours
Iterating with no regularization...
Begin: 4.70587e+08
0 4.20534e+08
1 4.18598e+08
2 4.18515e+08
Now with regularization...
Begin: 4.02616e+08
0 4.02572e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 09:06:24 BST Closed output file "np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813_reco_stage1_20250918T080624_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 35.4788 132.495 3268.16 61.0655 458.177 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.0021e-05 0.000138441 0.000784709 9.2526e-05 0.000126983 48
produce:tpcrawdecoder:PDVDTPCReader 5.44504 5.89837 7.00412 5.72633 0.390107 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.299187 0.304922 0.326557 0.303196 0.0050546 48
produce:pdvddaphne:DAPHNEReaderPDVD 2.43288 2.87369 3.34931 2.85871 0.200612 48
produce:ophit:OpHitFinder 0.0182766 0.0445722 0.0561444 0.0454682 0.00676759 48
produce:opflash:OpFlashFinderVerticalDrift 0.000960841 0.009554 0.0305462 0.00893941 0.00400533 48
produce:wclsdatavd:WireCellToolkit 20.8245 30.5123 58.6978 27.9579 8.2691 48
produce:gaushit:GausHitFinder 0.298026 0.637451 2.06218 0.608732 0.270863 48
produce:nhitsfilter:NumberOfHitsFilter 6.7191e-05 0.000152383 0.000379005 0.000143376 4.88059e-05 48
produce:reco3d:SpacePointSolver 1.23128 5.39685 38.3834 4.53777 5.21438 48
produce:hitpdune:DisambigFromSpacePoints 0.0142645 0.0916075 0.981345 0.0669461 0.140081 48
produce:pandora:StandardPandora 2.38381 83.988 3150.79 12.5211 447.884 48
produce:pandoraTrack:LArPandoraTrackCreation 0.0455883 0.335756 4.0679 0.242466 0.564787 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00421539 0.0147104 0.044959 0.0140384 0.00608529 48
[art]:TriggerResults:TriggerResultInserter 1.98e-05 3.23693e-05 8.8711e-05 3.06205e-05 1.04663e-05 48
end_path:out1:RootOutput 4.56e-06 9.19573e-06 2.979e-05 8.81e-06 3.21503e-06 48
end_path:out1:RootOutput(write) 1.60637 2.36415 3.59384 2.30191 0.359702 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5272.21 MB
Peak resident set size usage (VmHWM): 3275.58 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813_reco_stage1_20250918T080624_keepup.root
\tHists: np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813_reco_stage1_20250918T080624_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813_reco_stage1_20250918T080624_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813_reco_stage1_20250918T080624_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": 1758182784.0,
"core.end_time": 1758182784.0,
"core.events": [
98505,
98511,
98517,
98523,
98529,
98535,
98541,
98547,
98553,
98559,
98565,
98571,
98577,
98583,
98589,
98595,
98601,
98607,
98613,
98619,
98625,
98631,
98637,
98643,
98649,
98655,
98661,
98667,
98673,
98679,
98685,
98691,
98697,
98703,
98709,
98715,
98721,
98727,
98733,
98739,
98745,
98751,
98757,
98763,
98769,
98775,
98781,
98787
],
"core.event_count": 48,
"core.first_event_number": 98505,
"core.last_event_number": 98787,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813_reco_stage1_20250918T080624_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": 1758182784.0,
"core.end_time": 1758182784.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0343_df-s05-d3_dw_0_20250918T052813.hdf5"
}
]
} 