Jobsub ID 242267.38@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
sSigProc:anode0sigproc0> EOS at call=43 anode=0
[11:59:35.113] D [ glue ] <FrameFanin:nfsp> EOS at call=43 with 8
[11:59:35.113] D [ glue ] frame sink sees EOS
[11:59:35.113] D [ pgraph ] <Pgrapher:> graph execution complete
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 47.84 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.8 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.39 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.69 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.21 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.25 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.01 sec
[11:59:35.113] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.13 sec
[11:59:35.113] I [ timer ] Timer: WireCell::Aux::Resampler : 0.61 sec
[11:59:35.113] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[11:59:35.113] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[11:59:35.113] I [ timer ] Timer: WireCell::Aux::Resampler : 0.57 sec
[11:59:35.114] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.06 sec
[11:59:35.114] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:59:35.114] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:59:35.114] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:59:35.114] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:59:35.114] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:59:35.114] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:59:35.114] I [ timer ] Timer: Total node execution : 145.83999879285693 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 82195 traces tagged "gauss"
FrameSaver: q=3.78768e+07 n=2214955 tag=gauss
wclsFrameSaver: saving 105765 traces tagged "wiener"
FrameSaver: q=4.05835e+07 n=2127041 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 9132 XUs and 12274 XVs -> 656 XUVs
C:0 T:1 22599 XUs and 25229 XVs -> 1246 XUVs
C:0 T:2 1238 XUs and 884 XVs -> 27 XUVs
C:0 T:3 36274 XUs and 41529 XVs -> 2765 XUVs
C:0 T:4 1224 XUs and 915 XVs -> 92 XUVs
C:0 T:5 1910 XUs and 1677 XVs -> 106 XUVs
C:0 T:6 1057 XUs and 628 XVs -> 27 XUVs
C:0 T:7 975 XUs and 1083 XVs -> 39 XUVs
C:0 T:8 1191 XUs and 1130 XVs -> 51 XUVs
C:0 T:9 5056 XUs and 4668 XVs -> 348 XUVs
C:0 T:10 2243 XUs and 2026 XVs -> 75 XUVs
C:0 T:11 117933 XUs and 116920 XVs -> 7854 XUVs
C:0 T:12 251 XUs and 355 XVs -> 35 XUVs
C:0 T:13 183 XUs and 216 XVs -> 13 XUVs
C:0 T:14 1119 XUs and 1169 XVs -> 109 XUVs
C:0 T:15 122 XUs and 175 XVs -> 6 XUVs
13449 XUVs total
6560 collection wire objects
13449 potential space points
Neighbour search...
402129 tests to find 99192 neighbours
Iterating with no regularization...
Begin: 5.54136e+11
0 5.48779e+11
1 5.48427e+11
Now with regularization...
Begin: 5.47147e+11
0 5.47142e+11
03-Nov-2025 12:08:28 GMT Closed output file "np02vd_raw_run040346_3228_df-s04-d0_dw_0_20251103T091318_reco_stage1_20251103T120828_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 162.567 306.697 762.858 224.981 179.235 22
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.8544e-05 0.000137037 0.000345846 0.000121357 5.13795e-05 22
produce:tpcrawdecoder:PDVDTPCReader 55.2209 90.0108 204.26 79.884 36.6142 22
produce:triggerrawdecoder:PDVDTriggerReader4 0.571661 0.803684 2.33858 0.712338 0.355207 22
produce:pdvddaphne:DAPHNEReaderPDVD 0.000388777 0.000503413 0.00109359 0.000477177 0.000139181 22
produce:ophit:OpHitFinder 7.6182e-05 0.000160479 0.000918467 0.000125379 0.000167502 22
produce:opflash:OpFlashFinderVerticalDrift 5.9972e-05 0.000111534 0.000555398 7.79255e-05 0.000100459 22
produce:wclsdatavd:WireCellToolkit 68.3852 110.821 395.638 78.4882 73.1743 22
produce:gaushit:GausHitFinder 1.24137 2.23552 8.40965 1.76996 1.45306 22
produce:nhitsfilter:NumberOfHitsFilter 0.000366916 0.000850227 0.00298589 0.000700278 0.00054969 22
produce:reco3d:SpacePointSolver 8.29968 17.9524 53.6699 14.1149 9.81509 21
produce:hitpdune:DisambigFromSpacePoints 0.113583 0.353043 1.77244 0.249465 0.350465 21
produce:pandora:StandardPandora 17.791 79.5882 482.789 33.4491 128.397 21
produce:pandoraTrack:LArPandoraTrackCreation 0.73294 2.75854 17.0387 1.4918 3.70161 21
produce:pandoraGnocalo:GnocchiCalorimetry 0.0353655 0.0569988 0.0969432 0.0520833 0.0184031 21
[art]:TriggerResults:TriggerResultInserter 3.5366e-05 6.67416e-05 0.000159318 5.88955e-05 2.68606e-05 22
end_path:out1:RootOutput 5.59e-06 1.41546e-05 4.4673e-05 1.14865e-05 8.63807e-06 22
end_path:out1:RootOutput(write) 5.47782 6.58523 11.3658 6.06091 1.30316 22
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 10671.1 MB
Peak resident set size usage (VmHWM): 8461.16 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3228_df-s04-d0_dw_0_20251103T091318_reco_stage1_20251103T120828_keepup.root
\tHists: np02vd_raw_run040346_3228_df-s04-d0_dw_0_20251103T091318_reco_stage1_20251103T120829_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3228_df-s04-d0_dw_0_20251103T091318_reco_stage1_20251103T120828_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3228_df-s04-d0_dw_0_20251103T091318_reco_stage1_20251103T120828_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": 1762171710.0,
"core.end_time": 1762171711.0,
"core.events": [
297948,
297952,
297956,
297960,
297964,
297968,
297972,
297976,
297980,
297984,
297988,
297992,
297996,
298000,
298004,
298008,
298012,
298016,
298020,
298024,
298028,
298032
],
"core.event_count": 22,
"core.first_event_number": 297948,
"core.last_event_number": 298032,
"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_3228_df-s04-d0_dw_0_20251103T091318.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3228_df-s04-d0_dw_0_20251103T091318_reco_stage1_20251103T120829_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": 1762171710.0,
"core.end_time": 1762171711.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_3228_df-s04-d0_dw_0_20251103T091318.hdf5"
}
]
} 