Jobsub ID 41739.92@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
ec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.14 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.84 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Aux::Resampler : 0.52 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Aux::Resampler : 0.51 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:58:54.165] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:58:54.165] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:58:54.165] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:58:54.165] I [ timer ] Timer: Total node execution : 30.670000173151493 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21530 traces tagged "gauss"
FrameSaver: q=3.97923e+06 n=483250 tag=gauss
wclsFrameSaver: saving 27589 traces tagged "wiener"
FrameSaver: q=4.24546e+06 n=465584 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 482 XUs and 746 XVs -> 39 XUVs
C:0 T:1 2110 XUs and 2618 XVs -> 84 XUVs
C:0 T:2 616 XUs and 551 XVs -> 36 XUVs
C:0 T:3 5582 XUs and 6236 XVs -> 517 XUVs
C:0 T:4 1285 XUs and 1049 XVs -> 55 XUVs
C:0 T:5 685 XUs and 707 XVs -> 66 XUVs
C:0 T:6 7866 XUs and 8109 XVs -> 968 XUVs
C:0 T:7 1139 XUs and 1491 XVs -> 98 XUVs
1863 XUVs total
741 collection wire objects
1863 potential space points
Neighbour search...
58479 tests to find 17286 neighbours
Iterating with no regularization...
Begin: 9.16386e+08
0 8.53629e+08
1 8.49362e+08
2 8.49055e+08
Now with regularization...
Begin: 8.13636e+08
0 8.1347e+08
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
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 11:59:31 CEST Closed output file "np02vd_raw_run039433_0388_df-s05-d3_dw_0_20250918T071742_reco_stage1_20250918T095931_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 39.9228 138.129 2227.73 75.5852 309.165 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.5081e-05 0.000154178 0.000748064 0.000116136 0.000122248 48
produce:tpcrawdecoder:PDVDTPCReader 4.31016 6.41035 12.6189 5.82986 1.99747 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.171541 0.185857 0.398075 0.17907 0.0324482 48
produce:pdvddaphne:DAPHNEReaderPDVD 3.01016 4.1307 14.2647 3.66637 1.77497 48
produce:ophit:OpHitFinder 0.0322677 0.062247 0.220811 0.060465 0.024808 48
produce:opflash:OpFlashFinderVerticalDrift 0.00350132 0.0143529 0.0925316 0.0133599 0.0121591 48
produce:wclsdatavd:WireCellToolkit 21.1647 44.3429 165.006 33.8022 26.2087 48
produce:gaushit:GausHitFinder 0.301691 0.805014 2.28944 0.724159 0.340482 48
produce:nhitsfilter:NumberOfHitsFilter 0.000100531 0.000227292 0.000517942 0.000206681 9.86126e-05 48
produce:reco3d:SpacePointSolver 1.91742 6.5529 29.2431 5.94057 4.37469 48
produce:hitpdune:DisambigFromSpacePoints 0.025245 0.126294 0.901589 0.0772108 0.156167 48
produce:pandora:StandardPandora 3.95063 71.4028 2123.5 18.7084 300.702 48
produce:pandoraTrack:LArPandoraTrackCreation 0.0745678 0.606061 6.09782 0.337893 1.00526 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00803968 0.0221075 0.0911828 0.0204772 0.0129009 48
[art]:TriggerResults:TriggerResultInserter 1.8061e-05 3.52592e-05 9.421e-05 3.13905e-05 1.34131e-05 48
end_path:out1:RootOutput 5.13e-06 8.88869e-06 3.355e-05 8.3255e-06 4.54486e-06 48
end_path:out1:RootOutput(write) 1.92372 3.44281 13.972 2.94541 2.01018 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4895.06 MB
Peak resident set size usage (VmHWM): 3005.22 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0388_df-s05-d3_dw_0_20250918T071742_reco_stage1_20250918T095931_keepup.root
\tHists: np02vd_raw_run039433_0388_df-s05-d3_dw_0_20250918T071742_reco_stage1_20250918T095932_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0388_df-s05-d3_dw_0_20250918T071742_reco_stage1_20250918T095931_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0388_df-s05-d3_dw_0_20250918T071742_reco_stage1_20250918T095931_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": 1758189573.0,
"core.end_time": 1758189573.0,
"core.events": [
111417,
111423,
111429,
111435,
111441,
111447,
111453,
111459,
111465,
111471,
111477,
111483,
111489,
111495,
111501,
111507,
111513,
111519,
111525,
111531,
111537,
111543,
111549,
111555,
111561,
111567,
111573,
111579,
111585,
111591,
111597,
111603,
111609,
111615,
111621,
111627,
111633,
111639,
111645,
111651,
111657,
111663,
111669,
111675,
111681,
111687,
111693,
111699
],
"core.event_count": 48,
"core.first_event_number": 111417,
"core.last_event_number": 111699,
"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_0388_df-s05-d3_dw_0_20250918T071742.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0388_df-s05-d3_dw_0_20250918T071742_reco_stage1_20250918T095932_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": 1758189573.0,
"core.end_time": 1758189573.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_0388_df-s05-d3_dw_0_20250918T071742.hdf5"
}
]
} 