Jobsub ID 237693.39@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
.738] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[11:21:35.738] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[11:21:35.738] D [ glue ] frame sink sees EOS
[11:21:35.738] D [ pgraph ] <Pgrapher:> graph execution complete
[11:21:35.738] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 31.57 sec
[11:21:35.738] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 26.65 sec
[11:21:35.738] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 26.58 sec
[11:21:35.738] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 26.17 sec
[11:21:35.738] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 26.08 sec
[11:21:35.738] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 26.06 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 25.84 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 24.76 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Aux::Resampler : 0.73 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Aux::Resampler : 0.71 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Aux::Resampler : 0.71 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Aux::Resampler : 0.69 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:21:35.739] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:21:35.739] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:21:35.739] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:21:35.739] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:21:35.739] I [ timer ] Timer: Total node execution : 216.6699990555644 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 45136 traces tagged "gauss"
FrameSaver: q=1.26404e+07 n=1009868 tag=gauss
wclsFrameSaver: saving 57896 traces tagged "wiener"
FrameSaver: q=1.39512e+07 n=967703 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 4045 XUs and 2994 XVs -> 231 XUVs
C:0 T:1 619 XUs and 409 XVs -> 31 XUVs
C:0 T:2 1609 XUs and 1205 XVs -> 135 XUVs
C:0 T:3 341 XUs and 407 XVs -> 22 XUVs
C:0 T:4 27381 XUs and 46133 XVs -> 2799 XUVs
C:0 T:5 1852 XUs and 2379 XVs -> 89 XUVs
C:0 T:6 441 XUs and 617 XVs -> 28 XUVs
C:0 T:7 7396 XUs and 11770 XVs -> 717 XUVs
C:0 T:8 637 XUs and 1065 XVs -> 38 XUVs
C:0 T:9 268 XUs and 271 XVs -> 31 XUVs
C:0 T:10 98 XUs and 117 XVs -> 9 XUVs
C:0 T:11 88 XUs and 148 XVs -> 11 XUVs
C:0 T:12 1738 XUs and 1719 XVs -> 119 XUVs
C:0 T:13 471 XUs and 665 XVs -> 32 XUVs
C:0 T:14 320 XUs and 262 XVs -> 23 XUVs
C:0 T:15 858 XUs and 1062 XVs -> 76 XUVs
4391 XUVs total
2258 collection wire objects
4391 potential space points
Neighbour search...
211601 tests to find 49354 neighbours
Iterating with no regularization...
Begin: 1.54237e+10
0 1.45997e+10
1 1.4448e+10
2 1.44469e+10
Now with regularization...
Begin: 1.42358e+10
0 1.42351e+10
05-Nov-2025 11:23:16 UTC Closed output file "np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347_reco_stage1_20251105T112316_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 213.022 318.571 609.993 313.932 89.0676 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.3785e-05 0.000115394 0.000282095 0.000102946 4.01427e-05 23
produce:tpcrawdecoder:PDVDTPCReader 91.552 116.977 202.575 106.813 26.895 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.586387 0.803355 1.41943 0.761734 0.189845 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.00054777 0.000648034 0.0011681 0.00060981 0.000122834 23
produce:ophit:OpHitFinder 9.7073e-05 0.000154815 0.000804021 0.000118144 0.00013958 23
produce:opflash:OpFlashFinderVerticalDrift 7.2787e-05 0.000110703 0.000500256 9.331e-05 8.37828e-05 23
produce:wclsdatavd:WireCellToolkit 76.5702 125.051 267.636 103.931 45.9076 23
produce:gaushit:GausHitFinder 0.824104 1.76112 2.83049 1.76304 0.462582 23
produce:nhitsfilter:NumberOfHitsFilter 0.000241238 0.000460099 0.000857724 0.000466418 0.000124165 23
produce:reco3d:SpacePointSolver 5.41064 14.7408 24.6303 15.505 4.56561 23
produce:hitpdune:DisambigFromSpacePoints 0.111115 0.355128 0.978147 0.352694 0.181577 23
produce:pandora:StandardPandora 13.5398 50.0033 198.184 38.8553 40.6585 23
produce:pandoraTrack:LArPandoraTrackCreation 0.498694 2.72936 6.77904 2.70317 1.6115 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0182883 0.0545243 0.115686 0.0505918 0.0212381 23
[art]:TriggerResults:TriggerResultInserter 3.6774e-05 5.72613e-05 0.000135171 5.6385e-05 1.98507e-05 23
end_path:out1:RootOutput 4.252e-06 1.00017e-05 3.6904e-05 7.7e-06 6.96395e-06 23
end_path:out1:RootOutput(write) 4.10676 6.01634 8.55139 6.54308 1.40057 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5541.2 MB
Peak resident set size usage (VmHWM): 3409.9 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347_reco_stage1_20251105T112316_keepup.root
\tHists: np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347_reco_stage1_20251105T112316_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347_reco_stage1_20251105T112316_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347_reco_stage1_20251105T112316_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": 1762341797.0,
"core.end_time": 1762341797.0,
"core.events": [
53287,
53291,
53295,
53299,
53303,
53307,
53311,
53315,
53319,
53323,
53327,
53331,
53335,
53339,
53343,
53347,
53351,
53355,
53359,
53363,
53367,
53371,
53375
],
"core.event_count": 23,
"core.first_event_number": 53287,
"core.last_event_number": 53375,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40361
],
"core.runs_subruns": [
4036100001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347_reco_stage1_20251105T112316_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": 1762341797.0,
"core.end_time": 1762341797.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40361
],
"core.runs_subruns": [
4036100001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040361_0575_df-s04-d3_dw_0_20251105T045347.hdf5"
}
]
} 