Jobsub ID 36894.27@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.83 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.5 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.49 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.46 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.29 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.7 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.45 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Aux::Resampler : 0.41 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:43:10.474] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:43:10.474] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:43:10.474] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:43:10.474] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:43:10.474] I [ timer ] Timer: Total node execution : 75.3599991351366 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 39775 traces tagged "gauss"
FrameSaver: q=1.16912e+07 n=1152120 tag=gauss
wclsFrameSaver: saving 49370 traces tagged "wiener"
FrameSaver: q=1.22145e+07 n=1114144 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 260 XUs and 334 XVs -> 15 XUVs
C:0 T:1 875 XUs and 911 XVs -> 33 XUVs
C:0 T:2 125 XUs and 144 XVs -> 4 XUVs
C:0 T:3 803 XUs and 905 XVs -> 27 XUVs
C:0 T:4 529 XUs and 661 XVs -> 19 XUVs
C:0 T:5 237 XUs and 184 XVs -> 14 XUVs
C:0 T:6 193 XUs and 248 XVs -> 11 XUVs
C:0 T:7 178 XUs and 232 XVs -> 6 XUVs
C:0 T:8 17708 XUs and 39428 XVs -> 7950 XUVs
C:0 T:9 9454 XUs and 19203 XVs -> 2326 XUVs
C:0 T:10 11645 XUs and 21512 XVs -> 4263 XUVs
C:0 T:11 1607 XUs and 1050 XVs -> 54 XUVs
C:0 T:12 11658 XUs and 19189 XVs -> 733 XUVs
C:0 T:13 2160 XUs and 3376 XVs -> 131 XUVs
C:0 T:14 976 XUs and 1059 XVs -> 101 XUVs
C:0 T:15 1746 XUs and 1864 XVs -> 121 XUVs
15808 XUVs total
1781 collection wire objects
15808 potential space points
Neighbour search...
2815048 tests to find 1087930 neighbours
Iterating with no regularization...
Begin: 4.56951e+09
0 4.38779e+09
1 4.37632e+09
2 4.37575e+09
Now with regularization...
Begin: 4.35479e+09
0 4.35444e+09
TestBeamParticleCreationAlgorithm::SetupTestBeamVertex - Test beam particle has no initial vertex
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
09-Sep-2025 13:45:22 BST Closed output file "np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913_reco_stage1_20250909T124522_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 172.759 273.559 460.184 262.221 64.3514 27
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.0354e-05 8.41521e-05 0.000150248 7.9482e-05 2.33696e-05 27
produce:tpcrawdecoder:PDVDTPCReader 42.6062 77.9351 171.115 67.4161 28.9861 27
produce:triggerrawdecoder:PDVDTriggerReader4 0.0350914 0.0482322 0.0789214 0.0483342 0.00948284 27
produce:pdvddaphne:DAPHNEReaderPDVD 7.44863 11.2821 21.9718 10.5966 3.53679 27
produce:ophit:OpHitFinder 0.0357548 0.043973 0.0561273 0.0433454 0.00435886 27
produce:opflash:OpFlashFinderVerticalDrift 0.00997681 0.0140294 0.0203876 0.0138707 0.00206824 27
produce:wclsdatavd:WireCellToolkit 50.1544 76.5591 115.082 76.3768 13.0827 27
produce:gaushit:GausHitFinder 1.03795 1.61243 2.08033 1.65319 0.337986 27
produce:nhitsfilter:NumberOfHitsFilter 0.000177612 0.000350585 0.00075153 0.000353654 0.00011622 27
produce:reco3d:SpacePointSolver 7.43261 13.8968 21.0689 12.831 4.1191 27
produce:hitpdune:DisambigFromSpacePoints 0.120848 0.24549 0.446801 0.214081 0.0940679 27
produce:pandora:StandardPandora 30.2591 85.3875 275.643 75.1771 49.726 27
produce:pandoraTrack:LArPandoraTrackCreation 0.429475 1.07721 2.05952 1.02555 0.389058 27
produce:pandoraGnocalo:GnocchiCalorimetry 0.0174268 0.0294132 0.0484564 0.028323 0.00690369 27
[art]:TriggerResults:TriggerResultInserter 1.5855e-05 2.17918e-05 4.5476e-05 2.088e-05 5.84035e-06 27
end_path:out1:RootOutput 4.231e-06 7.81737e-06 1.7122e-05 7.109e-06 3.08272e-06 27
end_path:out1:RootOutput(write) 4.5428 5.38426 7.99566 5.25013 0.771091 27
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4864.03 MB
Peak resident set size usage (VmHWM): 2926.06 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913_reco_stage1_20250909T124522_keepup.root
\tHists: np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913_reco_stage1_20250909T124524_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913_reco_stage1_20250909T124522_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913_reco_stage1_20250909T124522_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": 1757421925.0,
"core.end_time": 1757421925.0,
"core.events": [
111489,
111509,
111529,
111549,
111569,
111589,
111609,
111629,
111649,
111669,
111689,
111709,
111729,
111749,
111769,
111789,
111809,
111829,
111849,
111869,
111889,
111909,
111929,
111949,
111969,
111989,
112009
],
"core.event_count": 27,
"core.first_event_number": 111489,
"core.last_event_number": 112009,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39344
],
"core.runs_subruns": [
3934400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913_reco_stage1_20250909T124524_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": 1757421925.0,
"core.end_time": 1757421925.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39344
],
"core.runs_subruns": [
3934400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039344_0209_df-s03-d3_dw_0_20250908T160913.hdf5"
}
]
} 