Jobsub ID 240262.123@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[15:20:17.811] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[15:20:17.811] D [ glue ] frame sink sees EOS
[15:20:17.811] D [ pgraph ] <Pgrapher:> graph execution complete
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.49 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.12 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.99 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.97 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.13 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.67 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.52 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.13 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[15:20:17.811] I [ timer ] Timer: wcls::FrameSaver : 0.01 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:20:17.811] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:20:17.811] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:20:17.811] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:20:17.811] I [ timer ] Timer: Total node execution : 84.36000048369169 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 47447 traces tagged "gauss"
FrameSaver: q=1.12674e+07 n=1013190 tag=gauss
wclsFrameSaver: saving 58842 traces tagged "wiener"
FrameSaver: q=1.22038e+07 n=969716 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 921 XUs and 820 XVs -> 47 XUVs
C:0 T:1 110 XUs and 147 XVs -> 4 XUVs
C:0 T:2 193 XUs and 285 XVs -> 6 XUVs
C:0 T:3 395 XUs and 419 XVs -> 21 XUVs
C:0 T:4 192 XUs and 151 XVs -> 18 XUVs
C:0 T:5 21742 XUs and 34553 XVs -> 2017 XUVs
C:0 T:6 1532 XUs and 1527 XVs -> 38 XUVs
C:0 T:7 1430 XUs and 1616 XVs -> 87 XUVs
C:0 T:8 4638 XUs and 4580 XVs -> 454 XUVs
C:0 T:9 1781 XUs and 2353 XVs -> 99 XUVs
C:0 T:10 1128 XUs and 1896 XVs -> 67 XUVs
C:0 T:11 831 XUs and 1247 XVs -> 57 XUVs
C:0 T:12 552 XUs and 780 XVs -> 25 XUVs
C:0 T:13 351 XUs and 345 XVs -> 29 XUVs
C:0 T:14 766 XUs and 906 XVs -> 26 XUVs
C:0 T:15 1778 XUs and 1777 XVs -> 79 XUVs
3074 XUVs total
1629 collection wire objects
3074 potential space points
Neighbour search...
131494 tests to find 30498 neighbours
Iterating with no regularization...
Begin: 6.20878e+09
0 5.79083e+09
1 5.75903e+09
2 5.75772e+09
Now with regularization...
Begin: 5.65255e+09
0 5.65219e+09
29-Oct-2025 15:21:14 GMT Closed output file "np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834_reco_stage1_20251029T152114_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 273.15 357.699 559.858 345.301 70.8268 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.5862e-05 9.65164e-05 0.000126549 9.6524e-05 1.47805e-05 23
produce:tpcrawdecoder:PDVDTPCReader 125.822 170.382 264.293 150.615 37.7083 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.516251 0.772462 1.33197 0.68271 0.234167 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000432238 0.000469811 0.000804502 0.000451329 7.2892e-05 23
produce:ophit:OpHitFinder 6.3635e-05 9.39028e-05 0.000575399 7.2052e-05 0.000102927 23
produce:opflash:OpFlashFinderVerticalDrift 5.177e-05 6.79275e-05 0.000332905 5.5452e-05 5.65828e-05 23
produce:wclsdatavd:WireCellToolkit 81.1848 109.026 193.637 91.4328 31.2937 23
produce:gaushit:GausHitFinder 1.02028 1.67769 2.44592 1.56872 0.383124 23
produce:nhitsfilter:NumberOfHitsFilter 0.000193027 0.000331359 0.000573156 0.000324363 8.14986e-05 23
produce:reco3d:SpacePointSolver 7.7351 15.935 25.9352 15.2026 4.34213 23
produce:hitpdune:DisambigFromSpacePoints 0.119876 0.302025 0.632463 0.280194 0.122191 23
produce:pandora:StandardPandora 20.3463 52.3504 190.988 45.7033 34.0289 23
produce:pandoraTrack:LArPandoraTrackCreation 0.66277 1.66879 3.18869 1.58201 0.509424 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0285798 0.0471707 0.0639112 0.0466335 0.00824907 23
[art]:TriggerResults:TriggerResultInserter 2.0489e-05 2.62726e-05 5.7162e-05 2.3102e-05 8.99705e-06 23
end_path:out1:RootOutput 5.851e-06 8.60626e-06 2.2172e-05 8.484e-06 3.21099e-06 23
end_path:out1:RootOutput(write) 4.75639 5.49511 8.46104 5.20421 0.92571 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7291.56 MB
Peak resident set size usage (VmHWM): 5389.11 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834_reco_stage1_20251029T152114_keepup.root
\tHists: np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834_reco_stage1_20251029T152116_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834_reco_stage1_20251029T152114_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834_reco_stage1_20251029T152114_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1761751276.0,
"core.end_time": 1761751276.0,
"core.events": [
65697,
65705,
65713,
65721,
65729,
65737,
65745,
65753,
65761,
65769,
65777,
65785,
65793,
65801,
65809,
65817,
65825,
65833,
65841,
65849,
65857,
65865,
65873
],
"core.event_count": 23,
"core.first_event_number": 65697,
"core.last_event_number": 65873,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40267
],
"core.runs_subruns": [
4026700001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834_reco_stage1_20251029T152116_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_11_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": 1761751276.0,
"core.end_time": 1761751276.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40267
],
"core.runs_subruns": [
4026700001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040267_0355_df-s03-d1_dw_0_20251026T042834.hdf5"
}
]
} 