Jobsub ID 236756.103@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[18:20:46.504] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[18:20:46.504] D [ glue ] frame sink sees EOS
[18:20:46.505] D [ pgraph ] <Pgrapher:> graph execution complete
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.97 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.23 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.89 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.36 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.2 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.86 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.56 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.39 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:20:46.505] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:20:46.505] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:20:46.505] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[18:20:46.505] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[18:20:46.505] I [ timer ] Timer: Total node execution : 76.79000001773238 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46372 traces tagged "gauss"
FrameSaver: q=1.14292e+07 n=943080 tag=gauss
wclsFrameSaver: saving 59182 traces tagged "wiener"
FrameSaver: q=1.24468e+07 n=895209 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 400 XUs and 596 XVs -> 18 XUVs
C:0 T:1 704 XUs and 1137 XVs -> 25 XUVs
C:0 T:2 32 XUs and 35 XVs -> 0 XUVs
C:0 T:3 1602 XUs and 1819 XVs -> 100 XUVs
C:0 T:4 20181 XUs and 15563 XVs -> 1476 XUVs
C:0 T:5 2240 XUs and 2114 XVs -> 108 XUVs
C:0 T:6 2200 XUs and 2470 XVs -> 124 XUVs
C:0 T:7 786 XUs and 667 XVs -> 32 XUVs
C:0 T:8 556 XUs and 437 XVs -> 23 XUVs
C:0 T:9 1347 XUs and 1052 XVs -> 53 XUVs
C:0 T:10 562 XUs and 725 XVs -> 46 XUVs
C:0 T:11 1189 XUs and 1384 XVs -> 74 XUVs
C:0 T:12 460 XUs and 640 XVs -> 29 XUVs
C:0 T:13 203 XUs and 389 XVs -> 12 XUVs
C:0 T:14 2023 XUs and 3261 XVs -> 209 XUVs
C:0 T:15 1167 XUs and 1475 XVs -> 62 XUVs
2391 XUVs total
1463 collection wire objects
2391 potential space points
Neighbour search...
97013 tests to find 22858 neighbours
Iterating with no regularization...
Begin: 4.04376e+09
0 3.68948e+09
1 3.67206e+09
2 3.6718e+09
Now with regularization...
Begin: 3.59565e+09
0 3.59541e+09
03-Nov-2025 18:21:37 UTC Closed output file "np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950_reco_stage1_20251103T182137_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 128.209 179.349 397.754 162.26 57.4106 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.3679e-05 0.000127177 0.000511954 8.8597e-05 0.000100459 23
produce:tpcrawdecoder:PDVDTPCReader 13.533 14.9114 24.0383 14.2451 2.11095 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.581924 0.59459 0.615963 0.59086 0.0106875 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000386919 0.000482682 0.000898112 0.000439648 0.000130668 23
produce:ophit:OpHitFinder 7.6524e-05 0.000137476 0.000688597 0.000109617 0.000121637 23
produce:opflash:OpFlashFinderVerticalDrift 6.2338e-05 9.78442e-05 0.000404782 7.7156e-05 6.98639e-05 23
produce:wclsdatavd:WireCellToolkit 66.1136 94.2209 143.656 84.2681 21.709 23
produce:gaushit:GausHitFinder 1.27362 1.63126 2.40867 1.60458 0.255488 23
produce:nhitsfilter:NumberOfHitsFilter 0.000236956 0.000347982 0.000687875 0.000333108 8.81461e-05 23
produce:reco3d:SpacePointSolver 7.71723 12.9455 18.3787 13.2939 2.62995 23
produce:hitpdune:DisambigFromSpacePoints 0.117051 0.218238 0.348554 0.210585 0.0632164 23
produce:pandora:StandardPandora 21.5814 47.2421 239.311 34.2628 45.568 23
produce:pandoraTrack:LArPandoraTrackCreation 0.716013 1.39684 2.72784 1.2227 0.532704 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0269444 0.045659 0.0671758 0.0451878 0.0100629 23
[art]:TriggerResults:TriggerResultInserter 2.2993e-05 3.28665e-05 7.0643e-05 2.8634e-05 1.21622e-05 23
end_path:out1:RootOutput 4.008e-06 8.75961e-06 2.7332e-05 8.476e-06 4.3358e-06 23
end_path:out1:RootOutput(write) 5.2213 6.08287 12.3157 5.7032 1.38931 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5770.62 MB
Peak resident set size usage (VmHWM): 3661.47 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950_reco_stage1_20251103T182137_keepup.root
\tHists: np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950_reco_stage1_20251103T182138_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950_reco_stage1_20251103T182137_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950_reco_stage1_20251103T182137_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": 1762194098.0,
"core.end_time": 1762194098.0,
"core.events": [
323992,
323996,
324000,
324004,
324008,
324012,
324016,
324020,
324024,
324028,
324032,
324036,
324040,
324044,
324048,
324052,
324056,
324060,
324064,
324068,
324072,
324076,
324080
],
"core.event_count": 23,
"core.first_event_number": 323992,
"core.last_event_number": 324080,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950_reco_stage1_20251103T182138_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": 1762194098.0,
"core.end_time": 1762194098.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3510_df-s04-d0_dw_0_20251103T131950.hdf5"
}
]
} 