Jobsub ID 41739.86@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
Timer: WireCell::SigProc::OmnibusSigProc : 36.52 sec
[11:54:01.866] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.36 sec
[11:54:01.866] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.67 sec
[11:54:01.866] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.51 sec
[11:54:01.866] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[11:54:01.866] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[11:54:01.866] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[11:54:01.866] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[11:54:01.867] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.06 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:54:01.867] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:54:01.867] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:54:01.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:54:01.867] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:54:01.867] I [ timer ] Timer: Total node execution : 82.32000137120485 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 66536 traces tagged "gauss"
FrameSaver: q=1.38961e+07 n=1630075 tag=gauss
wclsFrameSaver: saving 88373 traces tagged "wiener"
FrameSaver: q=1.503e+07 n=1541508 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1149 XUs and 1522 XVs -> 48 XUVs
C:0 T:1 8626 XUs and 10075 XVs -> 367 XUVs
C:0 T:2 3856 XUs and 4618 XVs -> 125 XUVs
C:0 T:3 1615 XUs and 2350 XVs -> 111 XUVs
C:0 T:4 129593 XUs and 181829 XVs -> 10787 XUVs
C:0 T:5 47436 XUs and 51325 XVs -> 3522 XUVs
C:0 T:6 10994 XUs and 13181 XVs -> 547 XUVs
C:0 T:7 5122 XUs and 6212 XVs -> 387 XUVs
15894 XUVs total
7115 collection wire objects
15894 potential space points
Neighbour search...
394898 tests to find 101452 neighbours
Iterating with no regularization...
Begin: 1.78378e+10
0 1.64285e+10
1 1.63362e+10
2 1.63349e+10
Now with regularization...
Begin: 1.61988e+10
0 1.61984e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 13:48:31 CEST Closed output file "np02vd_raw_run039433_0387_df-s05-d0_dw_0_20250918T071513_reco_stage1_20250918T114831_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 48.2084 281.893 6969.28 94.96 993.424 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.8831e-05 0.000156472 0.000731756 0.000121631 0.000124246 47
produce:tpcrawdecoder:PDVDTPCReader 5.44926 10.929 27.0665 10.2628 4.1752 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.175715 0.19804 0.398867 0.183993 0.0364447 47
produce:pdvddaphne:DAPHNEReaderPDVD 3.27374 5.33948 19.5669 4.35354 3.27192 47
produce:ophit:OpHitFinder 0.0345483 0.0774658 0.397319 0.0600788 0.0641541 47
produce:opflash:OpFlashFinderVerticalDrift 0.00470448 0.0160793 0.0755149 0.0125539 0.0136244 47
produce:wclsdatavd:WireCellToolkit 26.514 45.8806 87.5194 39.4509 15.6575 47
produce:gaushit:GausHitFinder 0.210359 0.912255 2.65008 0.790794 0.506856 47
produce:nhitsfilter:NumberOfHitsFilter 9.105e-05 0.000271495 0.00102085 0.000244082 0.000154243 47
produce:reco3d:SpacePointSolver 1.18617 8.67976 56.8562 6.39063 8.70795 47
produce:hitpdune:DisambigFromSpacePoints 0.0111177 0.153671 1.02168 0.0860731 0.183765 47
produce:pandora:StandardPandora 1.70671 205.73 6795 20.8414 978.094 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0689615 0.791941 8.28573 0.368617 1.30357 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00756068 0.021454 0.0773479 0.0181661 0.0116029 47
[art]:TriggerResults:TriggerResultInserter 2.168e-05 3.33071e-05 9.669e-05 2.9121e-05 1.56053e-05 47
end_path:out1:RootOutput 4.07e-06 9.83543e-06 4.315e-05 8.82e-06 6.89376e-06 47
end_path:out1:RootOutput(write) 2.11777 3.13681 13.1353 2.95996 1.56914 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5389.55 MB
Peak resident set size usage (VmHWM): 3497.65 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0387_df-s05-d0_dw_0_20250918T071513_reco_stage1_20250918T114831_keepup.root
\tHists: np02vd_raw_run039433_0387_df-s05-d0_dw_0_20250918T071513_reco_stage1_20250918T114831_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0387_df-s05-d0_dw_0_20250918T071513_reco_stage1_20250918T114831_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0387_df-s05-d0_dw_0_20250918T071513_reco_stage1_20250918T114831_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": 1758196111.0,
"core.end_time": 1758196112.0,
"core.events": [
111108,
111114,
111120,
111126,
111132,
111138,
111144,
111150,
111156,
111162,
111168,
111174,
111180,
111186,
111192,
111198,
111204,
111210,
111216,
111222,
111228,
111234,
111240,
111246,
111252,
111258,
111264,
111270,
111276,
111282,
111288,
111294,
111300,
111306,
111312,
111318,
111324,
111330,
111336,
111342,
111348,
111354,
111360,
111366,
111372,
111378,
111384
],
"core.event_count": 47,
"core.first_event_number": 111108,
"core.last_event_number": 111384,
"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_0387_df-s05-d0_dw_0_20250918T071513.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0387_df-s05-d0_dw_0_20250918T071513_reco_stage1_20250918T114831_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": 1758196111.0,
"core.end_time": 1758196112.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_0387_df-s05-d0_dw_0_20250918T071513.hdf5"
}
]
} 