Jobsub ID 48196.8@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
imer: WireCell::SigProc::OmnibusSigProc : 19.25 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.53 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.94 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.53 sec
[10:16:20.766] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[10:16:20.766] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[10:16:20.766] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[10:16:20.766] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[10:16:20.766] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:16:20.766] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:16:20.766] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:16:20.767] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:16:20.767] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:16:20.767] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:16:20.767] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:16:20.767] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:16:20.767] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:16:20.767] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:16:20.767] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:16:20.767] I [ timer ] Timer: Total node execution : 70.47000094875693 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 22334 traces tagged "gauss"
FrameSaver: q=4.33748e+06 n=525334 tag=gauss
wclsFrameSaver: saving 27901 traces tagged "wiener"
FrameSaver: q=4.69455e+06 n=506061 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 414 XUs and 360 XVs -> 19 XUVs
C:0 T:1 1150 XUs and 1212 XVs -> 60 XUVs
C:0 T:2 684 XUs and 594 XVs -> 53 XUVs
C:0 T:3 1072 XUs and 1197 XVs -> 41 XUVs
C:0 T:4 542 XUs and 856 XVs -> 19 XUVs
C:0 T:5 14300 XUs and 16962 XVs -> 1201 XUVs
C:0 T:6 113 XUs and 109 XVs -> 6 XUVs
C:0 T:7 770 XUs and 828 XVs -> 40 XUVs
1439 XUVs total
851 collection wire objects
1439 potential space points
Neighbour search...
54233 tests to find 12608 neighbours
Iterating with no regularization...
Begin: 5.85389e+09
0 5.58175e+09
1 5.55839e+09
2 5.55819e+09
Now with regularization...
Begin: 5.48998e+09
0 5.48977e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 10:17:15 BST Closed output file "np02vd_raw_run039433_0360_df-s05-d4_dw_0_20250918T060716_reco_stage1_20250918T091715_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 107.139 171.937 682.829 146.39 93.5193 50
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.3495e-05 0.000102649 0.000256251 9.906e-05 2.66276e-05 50
produce:tpcrawdecoder:PDVDTPCReader 26.5207 43.82 95.5852 42.3781 12.5468 50
produce:triggerrawdecoder:PDVDTriggerReader4 0.280461 0.408729 0.946485 0.3506 0.155628 50
produce:pdvddaphne:DAPHNEReaderPDVD 8.74627 12.9924 21.6185 12.3775 2.78118 50
produce:ophit:OpHitFinder 0.0515661 0.0707754 0.0864307 0.0715954 0.00639713 50
produce:opflash:OpFlashFinderVerticalDrift 0.0106922 0.0231601 0.0385122 0.0232599 0.00673411 50
produce:wclsdatavd:WireCellToolkit 39.2576 56.254 103.919 49.5178 15.4068 50
produce:gaushit:GausHitFinder 0.627037 1.17594 2.63033 1.12658 0.365639 50
produce:nhitsfilter:NumberOfHitsFilter 0.000173935 0.000291774 0.000564507 0.000274267 8.50994e-05 50
produce:reco3d:SpacePointSolver 2.94141 7.8724 26.4084 6.90326 4.22103 50
produce:hitpdune:DisambigFromSpacePoints 0.0371177 0.163224 1.0299 0.132979 0.15393 50
produce:pandora:StandardPandora 6.62611 44.9635 459.468 25.5746 76.2529 50
produce:pandoraTrack:LArPandoraTrackCreation 0.177545 1.03785 7.41606 0.745839 1.25601 50
produce:pandoraGnocalo:GnocchiCalorimetry 0.0145928 0.0304241 0.0676705 0.0283026 0.0108167 50
[art]:TriggerResults:TriggerResultInserter 2.8443e-05 4.15377e-05 0.000115138 3.8889e-05 1.26183e-05 50
end_path:out1:RootOutput 3.91e-06 9.99694e-06 3.2948e-05 9.7625e-06 4.14413e-06 50
end_path:out1:RootOutput(write) 2.59693 3.08291 4.182 2.967 0.37648 50
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4511.96 MB
Peak resident set size usage (VmHWM): 2627.87 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0360_df-s05-d4_dw_0_20250918T060716_reco_stage1_20250918T091715_keepup.root
\tHists: np02vd_raw_run039433_0360_df-s05-d4_dw_0_20250918T060716_reco_stage1_20250918T091716_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0360_df-s05-d4_dw_0_20250918T060716_reco_stage1_20250918T091715_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0360_df-s05-d4_dw_0_20250918T060716_reco_stage1_20250918T091715_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": 1758187037.0,
"core.end_time": 1758187037.0,
"core.events": [
103144,
103150,
103156,
103162,
103168,
103174,
103180,
103186,
103192,
103198,
103204,
103210,
103216,
103222,
103228,
103234,
103240,
103246,
103252,
103258,
103264,
103270,
103276,
103282,
103288,
103294,
103300,
103306,
103312,
103318,
103324,
103330,
103336,
103342,
103348,
103354,
103360,
103366,
103372,
103378,
103384,
103390,
103396,
103402,
103408,
103414,
103420,
103426,
103432,
103438
],
"core.event_count": 50,
"core.first_event_number": 103144,
"core.last_event_number": 103438,
"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_0360_df-s05-d4_dw_0_20250918T060716.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0360_df-s05-d4_dw_0_20250918T060716_reco_stage1_20250918T091716_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": 1758187037.0,
"core.end_time": 1758187037.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_0360_df-s05-d4_dw_0_20250918T060716.hdf5"
}
]
} 