Jobsub ID 41739.23@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
<OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[11:30:02.514] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[11:30:02.514] D [ glue ] frame sink sees EOS
[11:30:02.514] D [ pgraph ] <Pgrapher:> graph execution complete
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.12 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.43 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.05 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.54 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:02.514] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:30:02.514] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:30:02.514] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:30:02.514] I [ timer ] Timer: Total node execution : 39.250000324100256 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 23992 traces tagged "gauss"
FrameSaver: q=7.77623e+06 n=724021 tag=gauss
wclsFrameSaver: saving 30957 traces tagged "wiener"
FrameSaver: q=8.25567e+06 n=703902 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 96 XUs and 129 XVs -> 6 XUVs
C:0 T:1 893 XUs and 1194 XVs -> 28 XUVs
C:0 T:2 232 XUs and 317 XVs -> 14 XUVs
C:0 T:3 621 XUs and 584 XVs -> 36 XUVs
C:0 T:4 1484 XUs and 1580 XVs -> 23 XUVs
C:0 T:5 47022 XUs and 40962 XVs -> 2909 XUVs
C:0 T:6 1247 XUs and 1026 XVs -> 69 XUVs
C:0 T:7 9060 XUs and 9717 XVs -> 566 XUVs
3651 XUVs total
1762 collection wire objects
3651 potential space points
Neighbour search...
128533 tests to find 30024 neighbours
Iterating with no regularization...
Begin: 2.26824e+10
0 2.14168e+10
1 2.13283e+10
2 2.13245e+10
Now with regularization...
Begin: 2.10493e+10
0 2.10485e+10
18-Sep-2025 11:31:47 CEST Closed output file "np02vd_raw_run039433_0376_df-s05-d2_dw_0_20250918T064759_reco_stage1_20250918T093147_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 74.8053 128.988 952.25 104.696 125.259 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.1983e-05 0.000163712 0.000883444 0.00012072 0.00012539 47
produce:tpcrawdecoder:PDVDTPCReader 19.6437 35.0495 75.5109 32.7761 10.5831 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.306866 0.397783 0.597938 0.369346 0.0745211 47
produce:pdvddaphne:DAPHNEReaderPDVD 6.36738 9.82486 14.3517 9.8327 1.85379 47
produce:ophit:OpHitFinder 0.02652 0.0430004 0.0640284 0.0427317 0.00807277 47
produce:opflash:OpFlashFinderVerticalDrift 0.00369871 0.00998261 0.021545 0.00926055 0.00385355 47
produce:wclsdatavd:WireCellToolkit 22.2242 40.9242 85.3663 38.0508 12.055 47
produce:gaushit:GausHitFinder 0.24807 0.490743 1.06013 0.457624 0.165006 47
produce:nhitsfilter:NumberOfHitsFilter 0.000195042 0.00055173 0.00248674 0.000500881 0.000349414 47
produce:reco3d:SpacePointSolver 1.71695 5.63836 23.8747 4.93915 3.55496 47
produce:hitpdune:DisambigFromSpacePoints 0.0150856 0.0746363 0.469766 0.0590678 0.0689071 47
produce:pandora:StandardPandora 3.26281 33.6182 803.07 11.3884 114.782 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0607418 0.822635 5.00302 0.617289 0.873301 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00600492 0.0175726 0.0447766 0.0157918 0.00743311 47
[art]:TriggerResults:TriggerResultInserter 2.1643e-05 5.70436e-05 0.000155153 5.0716e-05 2.67273e-05 47
end_path:out1:RootOutput 5.859e-06 1.1474e-05 3.4011e-05 8.884e-06 6.34002e-06 47
end_path:out1:RootOutput(write) 1.69822 1.9983 2.72297 1.91522 0.250472 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5101.51 MB
Peak resident set size usage (VmHWM): 3214.02 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0376_df-s05-d2_dw_0_20250918T064759_reco_stage1_20250918T093147_keepup.root
\tHists: np02vd_raw_run039433_0376_df-s05-d2_dw_0_20250918T064759_reco_stage1_20250918T093147_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0376_df-s05-d2_dw_0_20250918T064759_reco_stage1_20250918T093147_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0376_df-s05-d2_dw_0_20250918T064759_reco_stage1_20250918T093147_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": 1758187908.0,
"core.end_time": 1758187908.0,
"core.events": [
107852,
107858,
107864,
107870,
107876,
107882,
107888,
107894,
107900,
107906,
107912,
107918,
107924,
107930,
107936,
107942,
107948,
107954,
107960,
107966,
107972,
107978,
107984,
107990,
107996,
108002,
108008,
108014,
108020,
108026,
108032,
108038,
108044,
108050,
108056,
108062,
108068,
108074,
108080,
108086,
108092,
108098,
108104,
108110,
108116,
108122,
108128
],
"core.event_count": 47,
"core.first_event_number": 107852,
"core.last_event_number": 108128,
"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_0376_df-s05-d2_dw_0_20250918T064759.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0376_df-s05-d2_dw_0_20250918T064759_reco_stage1_20250918T093147_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": 1758187908.0,
"core.end_time": 1758187908.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_0376_df-s05-d2_dw_0_20250918T064759.hdf5"
}
]
} 