Jobsub ID 237218.137@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[13:36:40.720] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[13:36:40.720] D [ glue ] frame sink sees EOS
[13:36:40.720] D [ pgraph ] <Pgrapher:> graph execution complete
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.09 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.52 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.81 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.97 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.8 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.31 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.7 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.38 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:36:40.720] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:36:40.720] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:36:40.720] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:36:40.720] I [ timer ] Timer: Total node execution : 74.53000135347247 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 65672 traces tagged "gauss"
FrameSaver: q=2.73731e+07 n=1538833 tag=gauss
wclsFrameSaver: saving 82643 traces tagged "wiener"
FrameSaver: q=2.91739e+07 n=1473884 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 541 XUs and 344 XVs -> 27 XUVs
C:0 T:1 5107 XUs and 3168 XVs -> 157 XUVs
C:0 T:2 556 XUs and 662 XVs -> 31 XUVs
C:0 T:3 10834 XUs and 18869 XVs -> 884 XUVs
C:0 T:4 25876 XUs and 27980 XVs -> 2172 XUVs
C:0 T:5 1113 XUs and 1095 XVs -> 57 XUVs
C:0 T:6 3221 XUs and 2770 XVs -> 154 XUVs
C:0 T:7 1610 XUs and 1457 XVs -> 114 XUVs
C:0 T:8 418 XUs and 499 XVs -> 34 XUVs
C:0 T:9 298 XUs and 434 XVs -> 24 XUVs
C:0 T:10 431 XUs and 637 XVs -> 43 XUVs
C:0 T:11 16582 XUs and 16970 XVs -> 1178 XUVs
C:0 T:12 1990 XUs and 2352 XVs -> 156 XUVs
C:0 T:13 3195 XUs and 3178 XVs -> 155 XUVs
C:0 T:14 1128 XUs and 1458 XVs -> 80 XUVs
C:0 T:15 2651 XUs and 1906 XVs -> 153 XUVs
5419 XUVs total
3242 collection wire objects
5419 potential space points
Neighbour search...
168371 tests to find 39984 neighbours
Iterating with no regularization...
Begin: 3.41489e+11
0 3.36842e+11
1 3.36681e+11
Now with regularization...
Begin: 3.34901e+11
0 3.34898e+11
04-Nov-2025 13:38:26 UTC Closed output file "np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352_reco_stage1_20251104T133826_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 86.3242 131.581 381.418 119.508 57.6413 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.0581e-05 0.000120232 0.000225821 0.000101413 3.74549e-05 23
produce:tpcrawdecoder:PDVDTPCReader 14.7278 15.3124 16.8767 15.1775 0.53049 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.683784 0.696349 0.746892 0.689168 0.0168843 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000325702 0.000392144 0.000781248 0.000373414 9.20208e-05 23
produce:ophit:OpHitFinder 7.2059e-05 0.000137153 0.000662409 0.000114843 0.000114977 23
produce:opflash:OpFlashFinderVerticalDrift 4.8153e-05 8.38854e-05 0.000338481 7.0206e-05 5.60122e-05 23
produce:wclsdatavd:WireCellToolkit 43.8061 71.2132 349.53 57.8821 60.2757 23
produce:gaushit:GausHitFinder 0.549589 1.49582 9.01323 1.18047 1.62203 23
produce:nhitsfilter:NumberOfHitsFilter 0.000128384 0.000283652 0.00134719 0.000253902 0.000233619 23
produce:reco3d:SpacePointSolver 5.91958 11.0311 14.4983 11.9925 2.30417 22
produce:hitpdune:DisambigFromSpacePoints 0.107449 0.201677 0.297055 0.208091 0.0523338 22
produce:pandora:StandardPandora 12.0479 27.7797 80.1573 23.763 14.1515 22
produce:pandoraTrack:LArPandoraTrackCreation 0.478857 1.07086 3.85224 0.969514 0.684796 22
produce:pandoraGnocalo:GnocchiCalorimetry 0.0202684 0.0322365 0.0541121 0.0304476 0.00869601 22
[art]:TriggerResults:TriggerResultInserter 1.964e-05 4.06434e-05 0.0001128 3.3901e-05 1.91386e-05 23
end_path:out1:RootOutput 5.478e-06 9.85135e-06 2.8042e-05 9.084e-06 4.12826e-06 23
end_path:out1:RootOutput(write) 3.31891 4.42458 7.35948 4.29273 0.92606 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 13132.6 MB
Peak resident set size usage (VmHWM): 10952.1 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352_reco_stage1_20251104T133826_keepup.root
\tHists: np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352_reco_stage1_20251104T133827_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352_reco_stage1_20251104T133826_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352_reco_stage1_20251104T133826_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": 1762263508.0,
"core.end_time": 1762263508.0,
"core.events": [
111386,
111390,
111394,
111398,
111402,
111406,
111410,
111414,
111418,
111422,
111426,
111430,
111434,
111438,
111442,
111446,
111450,
111454,
111458,
111462,
111466,
111470,
111474
],
"core.event_count": 23,
"core.first_event_number": 111386,
"core.last_event_number": 111474,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352_reco_stage1_20251104T133827_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": 1762263508.0,
"core.end_time": 1762263508.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1205_df-s04-d2_dw_0_20251104T115352.hdf5"
}
]
} 