Jobsub ID 242251.0@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
S at call=47 anode=0
[11:46:38.307] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[11:46:38.307] D [ glue ] frame sink sees EOS
[11:46:38.307] D [ pgraph ] <Pgrapher:> graph execution complete
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.23 sec
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.74 sec
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.04 sec
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.87 sec
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.48 sec
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.46 sec
[11:46:38.307] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.16 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.308] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.308] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:46:38.308] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:46:38.308] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:46:38.308] I [ timer ] Timer: Total node execution : 82.4699985831976 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 44625 traces tagged "gauss"
FrameSaver: q=1.14167e+07 n=922804 tag=gauss
wclsFrameSaver: saving 58348 traces tagged "wiener"
FrameSaver: q=1.28726e+07 n=876596 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 860 XUs and 1462 XVs -> 80 XUVs
C:0 T:1 788 XUs and 658 XVs -> 41 XUVs
C:0 T:2 1267 XUs and 1671 XVs -> 196 XUVs
C:0 T:3 1042 XUs and 737 XVs -> 45 XUVs
C:0 T:4 5330 XUs and 3865 XVs -> 393 XUVs
C:0 T:5 930 XUs and 606 XVs -> 46 XUVs
C:0 T:6 1331 XUs and 776 XVs -> 55 XUVs
C:0 T:7 19114 XUs and 17493 XVs -> 1867 XUVs
C:0 T:8 20386 XUs and 17270 XVs -> 6091 XUVs
C:0 T:9 7076 XUs and 9932 XVs -> 3120 XUVs
C:0 T:10 918 XUs and 1201 XVs -> 82 XUVs
C:0 T:11 723 XUs and 1165 XVs -> 47 XUVs
C:0 T:12 238 XUs and 500 XVs -> 28 XUVs
C:0 T:13 2217 XUs and 4030 XVs -> 313 XUVs
C:0 T:14 128 XUs and 191 XVs -> 10 XUVs
C:0 T:15 10726 XUs and 25124 XVs -> 3952 XUVs
16366 XUVs total
2166 collection wire objects
16366 potential space points
Neighbour search...
2962656 tests to find 1144420 neighbours
Iterating with no regularization...
Begin: 6.13816e+09
0 5.56049e+09
1 5.51354e+09
2 5.51168e+09
Now with regularization...
Begin: 5.4046e+09
0 5.4037e+09
03-Nov-2025 11:47:46 GMT Closed output file "np02vd_raw_run040346_3067_df-s04-d1_dw_0_20251103T065940_reco_stage1_20251103T114746_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 194.172 467.298 3386.69 267.785 675.646 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.0915e-05 0.000116546 0.000403377 0.000100684 6.10877e-05 24
produce:tpcrawdecoder:PDVDTPCReader 55.4313 92.2544 171.327 84.3694 29.2735 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.611593 0.812437 1.62448 0.71769 0.2371 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000315968 0.000581495 0.00153232 0.000556271 0.000208501 24
produce:ophit:OpHitFinder 7.0516e-05 0.000152597 0.00108189 0.000107963 0.000195295 24
produce:opflash:OpFlashFinderVerticalDrift 5.4134e-05 0.000123139 0.000678947 9.65255e-05 0.000118226 24
produce:wclsdatavd:WireCellToolkit 69.8358 109.034 238.908 92.2741 38.7991 24
produce:gaushit:GausHitFinder 1.31773 2.44387 5.38546 2.21747 0.98922 24
produce:nhitsfilter:NumberOfHitsFilter 0.000303314 0.000594706 0.00103478 0.000559843 0.000180282 24
produce:reco3d:SpacePointSolver 11.5057 23.7193 80.8435 19.1843 17.2791 24
produce:hitpdune:DisambigFromSpacePoints 0.22017 0.518436 2.73745 0.330428 0.60146 24
produce:pandora:StandardPandora 24.3109 227.517 3017.82 48.6177 631.176 24
produce:pandoraTrack:LArPandoraTrackCreation 0.700641 3.45981 21.4022 2.13299 5.31572 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0322616 0.0669892 0.129866 0.0635564 0.0208035 24
[art]:TriggerResults:TriggerResultInserter 3.6831e-05 5.99627e-05 9.8169e-05 5.62835e-05 1.52516e-05 24
end_path:out1:RootOutput 5.971e-06 1.08291e-05 2.2423e-05 9.4525e-06 3.93428e-06 24
end_path:out1:RootOutput(write) 5.3579 7.35575 9.25991 7.15279 0.776178 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8420.66 MB
Peak resident set size usage (VmHWM): 6326.93 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3067_df-s04-d1_dw_0_20251103T065940_reco_stage1_20251103T114746_keepup.root
\tHists: np02vd_raw_run040346_3067_df-s04-d1_dw_0_20251103T065940_reco_stage1_20251103T114747_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3067_df-s04-d1_dw_0_20251103T065940_reco_stage1_20251103T114746_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3067_df-s04-d1_dw_0_20251103T065940_reco_stage1_20251103T114746_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": 1762170468.0,
"core.end_time": 1762170468.0,
"core.events": [
283257,
283261,
283265,
283269,
283273,
283277,
283281,
283285,
283289,
283293,
283297,
283301,
283305,
283309,
283313,
283317,
283321,
283325,
283329,
283333,
283337,
283341,
283345,
283349
],
"core.event_count": 24,
"core.first_event_number": 283257,
"core.last_event_number": 283349,
"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_3067_df-s04-d1_dw_0_20251103T065940.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3067_df-s04-d1_dw_0_20251103T065940_reco_stage1_20251103T114747_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": 1762170468.0,
"core.end_time": 1762170468.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_3067_df-s04-d1_dw_0_20251103T065940.hdf5"
}
]
} 