Jobsub ID 242471.124@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
proc0> EOS at call=45 anode=0
[19:55:46.889] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[19:55:46.889] D [ glue ] frame sink sees EOS
[19:55:46.889] D [ pgraph ] <Pgrapher:> graph execution complete
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 27.22 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 25.23 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.2 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.03 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.88 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.59 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.21 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.67 sec
[19:55:46.889] I [ timer ] Timer: WireCell::Aux::Resampler : 1.22 sec
[19:55:46.889] I [ timer ] Timer: WireCell::Aux::Resampler : 1.12 sec
[19:55:46.889] I [ timer ] Timer: WireCell::Aux::Resampler : 1.12 sec
[19:55:46.889] I [ timer ] Timer: WireCell::Aux::Resampler : 1.01 sec
[19:55:46.889] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:55:46.889] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:55:46.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:55:46.890] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:55:46.890] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:55:46.890] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[19:55:46.890] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:55:46.890] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[19:55:46.890] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:55:46.890] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[19:55:46.890] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[19:55:46.890] I [ timer ] Timer: Total node execution : 152.58000071533024 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 91587 traces tagged "gauss"
FrameSaver: q=3.2639e+07 n=2208374 tag=gauss
wclsFrameSaver: saving 115030 traces tagged "wiener"
FrameSaver: q=3.46794e+07 n=2121738 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1939 XUs and 1930 XVs -> 75 XUVs
C:0 T:1 8181 XUs and 7435 XVs -> 290 XUVs
C:0 T:2 13993 XUs and 13570 XVs -> 940 XUVs
C:0 T:3 62867 XUs and 62106 XVs -> 5296 XUVs
C:0 T:4 1174 XUs and 1632 XVs -> 75 XUVs
C:0 T:5 3611 XUs and 3040 XVs -> 124 XUVs
C:0 T:6 879 XUs and 646 XVs -> 29 XUVs
C:0 T:7 1168 XUs and 1533 XVs -> 64 XUVs
C:0 T:8 1142 XUs and 875 XVs -> 32 XUVs
C:0 T:9 2123 XUs and 2030 XVs -> 97 XUVs
C:0 T:10 2073 XUs and 1838 XVs -> 108 XUVs
C:0 T:11 12335 XUs and 15942 XVs -> 737 XUVs
C:0 T:12 3414 XUs and 3228 XVs -> 209 XUVs
C:0 T:13 1077 XUs and 1253 XVs -> 51 XUVs
C:0 T:14 1530 XUs and 2443 XVs -> 150 XUVs
C:0 T:15 3235 XUs and 3376 XVs -> 95 XUVs
8372 XUVs total
3865 collection wire objects
8372 potential space points
Neighbour search...
276298 tests to find 76332 neighbours
Iterating with no regularization...
Begin: 5.0935e+10
0 4.87968e+10
1 4.86784e+10
2 4.8676e+10
Now with regularization...
Begin: 4.81698e+10
0 4.81691e+10
03-Nov-2025 20:04:39 GMT Closed output file "np02vd_raw_run040346_3758_df-s04-d0_dw_0_20251103T170418_reco_stage1_20251103T200439_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 220.357 334.568 827.252 309.396 116.748 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000101372 0.000119259 0.000308471 0.000107753 4.18176e-05 23
produce:tpcrawdecoder:PDVDTPCReader 78.3083 127.329 269.661 127.254 37.479 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.795821 1.04645 1.52515 0.993556 0.182325 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000472231 0.000569523 0.0012128 0.000537664 0.000143759 23
produce:ophit:OpHitFinder 7.6434e-05 0.00013298 0.000908233 9.2454e-05 0.000166558 23
produce:opflash:OpFlashFinderVerticalDrift 5.6918e-05 9.85738e-05 0.000559876 7.1195e-05 0.00010187 23
produce:wclsdatavd:WireCellToolkit 77.1532 107.99 158.806 95.8828 23.476 23
produce:gaushit:GausHitFinder 0.839711 1.88322 3.34318 1.85912 0.574513 23
produce:nhitsfilter:NumberOfHitsFilter 0.00039227 0.000749675 0.00143999 0.000676637 0.000265144 23
produce:reco3d:SpacePointSolver 8.63848 18.6277 50.4357 18.3247 7.95178 23
produce:hitpdune:DisambigFromSpacePoints 0.0818514 0.343672 1.3772 0.28744 0.247 23
produce:pandora:StandardPandora 12.745 67.6587 447.577 47.2081 84.3292 23
produce:pandoraTrack:LArPandoraTrackCreation 0.528963 2.75078 18.6506 1.81435 3.541 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0299341 0.0602762 0.105216 0.0611313 0.019598 23
[art]:TriggerResults:TriggerResultInserter 2.67e-05 6.50295e-05 0.00014882 6.1576e-05 2.68064e-05 23
end_path:out1:RootOutput 4.148e-06 1.0294e-05 3.9846e-05 7.014e-06 7.22638e-06 23
end_path:out1:RootOutput(write) 5.25128 6.77228 8.96079 6.72706 1.05179 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5496.89 MB
Peak resident set size usage (VmHWM): 3433.99 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3758_df-s04-d0_dw_0_20251103T170418_reco_stage1_20251103T200439_keepup.root
\tHists: np02vd_raw_run040346_3758_df-s04-d0_dw_0_20251103T170418_reco_stage1_20251103T200439_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3758_df-s04-d0_dw_0_20251103T170418_reco_stage1_20251103T200439_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3758_df-s04-d0_dw_0_20251103T170418_reco_stage1_20251103T200439_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": 1762200280.0,
"core.end_time": 1762200280.0,
"core.events": [
346908,
346912,
346916,
346920,
346924,
346928,
346932,
346936,
346940,
346944,
346948,
346952,
346956,
346960,
346964,
346968,
346972,
346976,
346980,
346984,
346988,
346992,
346996
],
"core.event_count": 23,
"core.first_event_number": 346908,
"core.last_event_number": 346996,
"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_3758_df-s04-d0_dw_0_20251103T170418.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3758_df-s04-d0_dw_0_20251103T170418_reco_stage1_20251103T200439_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": 1762200280.0,
"core.end_time": 1762200280.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_3758_df-s04-d0_dw_0_20251103T170418.hdf5"
}
]
} 