Jobsub ID 41739.34@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
roc ] <OmnibusSigProc:anode0sigproc0> EOS at call=97 anode=0
[11:46:15.340] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[11:46:15.340] D [ glue ] frame sink sees EOS
[11:46:15.340] D [ pgraph ] <Pgrapher:> graph execution complete
[11:46:15.340] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.64 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.15 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.47 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.46 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Aux::Resampler : 0.52 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:46:15.341] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:46:15.341] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:46:15.341] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:46:15.341] I [ timer ] Timer: Total node execution : 37.869999684393406 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 17338 traces tagged "gauss"
FrameSaver: q=2.71812e+06 n=386317 tag=gauss
wclsFrameSaver: saving 21807 traces tagged "wiener"
FrameSaver: q=2.92121e+06 n=372469 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2893 XUs and 2837 XVs -> 131 XUVs
C:0 T:1 669 XUs and 961 XVs -> 19 XUVs
C:0 T:2 860 XUs and 299 XVs -> 16 XUVs
C:0 T:3 581 XUs and 682 XVs -> 39 XUVs
C:0 T:4 336 XUs and 402 XVs -> 15 XUVs
C:0 T:5 30 XUs and 47 XVs -> 2 XUVs
C:0 T:6 1780 XUs and 2276 XVs -> 163 XUVs
C:0 T:7 4167 XUs and 4535 XVs -> 339 XUVs
724 XUVs total
490 collection wire objects
724 potential space points
Neighbour search...
18204 tests to find 4962 neighbours
Iterating with no regularization...
Begin: 1.209e+09
0 1.18087e+09
1 1.17979e+09
Now with regularization...
Begin: 1.15257e+09
0 1.1525e+09
18-Sep-2025 11:46:37 CEST Closed output file "np02vd_raw_run039433_0378_df-s05-d4_dw_0_20250918T065138_reco_stage1_20250918T094637_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 39.2156 141.689 2276.87 77.1364 315.697 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.6471e-05 0.00012748 0.000329311 0.00011205 5.79884e-05 49
produce:tpcrawdecoder:PDVDTPCReader 3.89788 8.43084 18.6765 7.71389 3.33499 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.171203 0.192254 0.40754 0.17971 0.0388455 49
produce:pdvddaphne:DAPHNEReaderPDVD 2.28892 4.61001 12.6541 3.99584 1.9909 49
produce:ophit:OpHitFinder 0.0375498 0.0642836 0.225738 0.0587258 0.036099 49
produce:opflash:OpFlashFinderVerticalDrift 0.00565449 0.0147028 0.0505067 0.0123247 0.00828526 49
produce:wclsdatavd:WireCellToolkit 20.4812 40.9637 92.6257 38.1207 17.0051 49
produce:gaushit:GausHitFinder 0.323954 0.817667 3.24047 0.677442 0.556431 49
produce:nhitsfilter:NumberOfHitsFilter 8.113e-05 0.00021021 0.000539422 0.000181121 0.000100307 49
produce:reco3d:SpacePointSolver 2.17629 7.34285 60.2319 5.23713 8.59481 49
produce:hitpdune:DisambigFromSpacePoints 0.0276214 0.142536 2.24288 0.070539 0.317187 49
produce:pandora:StandardPandora 3.33774 75.758 2102.05 14.5757 297.324 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0907618 0.467635 3.34659 0.286146 0.564141 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00736807 0.0193855 0.0536473 0.0186726 0.00851501 49
[art]:TriggerResults:TriggerResultInserter 1.7631e-05 3.37883e-05 7.481e-05 3.125e-05 1.3258e-05 49
end_path:out1:RootOutput 4.02e-06 7.61329e-06 2.54e-05 6.58e-06 3.81644e-06 49
end_path:out1:RootOutput(write) 1.91667 2.84153 4.71426 2.86533 0.616055 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5991.23 MB
Peak resident set size usage (VmHWM): 4100.01 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0378_df-s05-d4_dw_0_20250918T065138_reco_stage1_20250918T094637_keepup.root
\tHists: np02vd_raw_run039433_0378_df-s05-d4_dw_0_20250918T065138_reco_stage1_20250918T094639_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0378_df-s05-d4_dw_0_20250918T065138_reco_stage1_20250918T094637_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0378_df-s05-d4_dw_0_20250918T065138_reco_stage1_20250918T094637_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": 1758188800.0,
"core.end_time": 1758188800.0,
"core.events": [
108298,
108304,
108310,
108316,
108322,
108328,
108334,
108340,
108346,
108352,
108358,
108364,
108370,
108376,
108382,
108388,
108394,
108400,
108406,
108412,
108418,
108424,
108430,
108436,
108442,
108448,
108454,
108460,
108466,
108472,
108478,
108484,
108490,
108496,
108502,
108508,
108514,
108520,
108526,
108532,
108538,
108544,
108550,
108556,
108562,
108568,
108574,
108580,
108586
],
"core.event_count": 49,
"core.first_event_number": 108298,
"core.last_event_number": 108586,
"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_0378_df-s05-d4_dw_0_20250918T065138.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0378_df-s05-d4_dw_0_20250918T065138_reco_stage1_20250918T094639_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": 1758188800.0,
"core.end_time": 1758188800.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_0378_df-s05-d4_dw_0_20250918T065138.hdf5"
}
]
} 