Jobsub ID 242471.131@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
igProc:anode0sigproc0> EOS at call=47 anode=0
[19:59:55.747] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[19:59:55.747] D [ glue ] frame sink sees EOS
[19:59:55.747] D [ pgraph ] <Pgrapher:> graph execution complete
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.46 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.31 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.29 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.98 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.97 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.08 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.03 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.89 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Aux::Resampler : 0.37 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.03 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[19:59:55.747] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:59:55.747] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[19:59:55.747] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[19:59:55.747] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[19:59:55.747] I [ timer ] Timer: Total node execution : 103.64000021666288 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 34569 traces tagged "gauss"
FrameSaver: q=8.06719e+06 n=708237 tag=gauss
wclsFrameSaver: saving 43599 traces tagged "wiener"
FrameSaver: q=8.80129e+06 n=678842 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1065 XUs and 989 XVs -> 58 XUVs
C:0 T:1 11674 XUs and 9335 XVs -> 855 XUVs
C:0 T:2 815 XUs and 633 XVs -> 41 XUVs
C:0 T:3 269 XUs and 291 XVs -> 19 XUVs
C:0 T:4 1191 XUs and 1269 XVs -> 59 XUVs
C:0 T:5 411 XUs and 527 XVs -> 28 XUVs
C:0 T:6 58 XUs and 69 XVs -> 0 XUVs
C:0 T:7 367 XUs and 521 XVs -> 42 XUVs
C:0 T:8 205 XUs and 279 XVs -> 10 XUVs
C:0 T:9 508 XUs and 608 XVs -> 48 XUVs
C:0 T:10 752 XUs and 927 XVs -> 42 XUVs
C:0 T:11 284 XUs and 563 XVs -> 20 XUVs
C:0 T:12 650 XUs and 708 XVs -> 61 XUVs
C:0 T:13 848 XUs and 762 XVs -> 36 XUVs
C:0 T:14 435 XUs and 770 XVs -> 47 XUVs
C:0 T:15 226 XUs and 678 XVs -> 13 XUVs
1379 XUVs total
857 collection wire objects
1379 potential space points
Neighbour search...
29259 tests to find 8262 neighbours
Iterating with no regularization...
Begin: 9.21907e+09
0 9.03839e+09
1 9.02484e+09
2 9.02471e+09
Now with regularization...
Begin: 8.97846e+09
0 8.97839e+09
03-Nov-2025 20:00:38 GMT Closed output file "np02vd_raw_run040346_3743_df-s04-d1_dw_0_20251103T165234_reco_stage1_20251103T200038_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 194.298 312.338 700.031 269.453 110.994 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.7519e-05 0.000156018 0.000320148 0.000142997 4.6951e-05 24
produce:tpcrawdecoder:PDVDTPCReader 54.267 86.1959 161.208 85.2354 21.4964 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.556566 0.740216 1.18773 0.69422 0.167727 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000506977 0.000725723 0.00160925 0.000679261 0.000205039 24
produce:ophit:OpHitFinder 0.000141575 0.000244171 0.00126924 0.000189072 0.00021848 24
produce:opflash:OpFlashFinderVerticalDrift 6.8367e-05 0.000165827 0.000706435 0.000138945 0.000121713 24
produce:wclsdatavd:WireCellToolkit 77.5471 133.535 248.168 114.432 44.1629 24
produce:gaushit:GausHitFinder 0.61436 2.04608 4.1973 1.96681 0.646912 24
produce:nhitsfilter:NumberOfHitsFilter 0.000358544 0.000780216 0.00136641 0.000805441 0.000239372 24
produce:reco3d:SpacePointSolver 4.32781 15.0513 37.5235 13.7478 5.99535 24
produce:hitpdune:DisambigFromSpacePoints 0.0880806 0.398519 1.54403 0.340674 0.271971 24
produce:pandora:StandardPandora 9.65887 62.4975 375.194 44.7468 68.6929 24
produce:pandoraTrack:LArPandoraTrackCreation 1.078 5.22539 26.0894 4.55147 4.73437 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0201659 0.0661688 0.124334 0.0617107 0.0208361 24
[art]:TriggerResults:TriggerResultInserter 5.5681e-05 8.9851e-05 0.00016307 9.0477e-05 1.8128e-05 24
end_path:out1:RootOutput 1.3858e-05 2.51224e-05 4.5129e-05 2.49555e-05 6.14224e-06 24
end_path:out1:RootOutput(write) 3.23169 6.46077 7.32965 6.5329 0.771584 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6398.02 MB
Peak resident set size usage (VmHWM): 4366.09 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3743_df-s04-d1_dw_0_20251103T165234_reco_stage1_20251103T200038_keepup.root
\tHists: np02vd_raw_run040346_3743_df-s04-d1_dw_0_20251103T165234_reco_stage1_20251103T200039_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3743_df-s04-d1_dw_0_20251103T165234_reco_stage1_20251103T200038_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3743_df-s04-d1_dw_0_20251103T165234_reco_stage1_20251103T200038_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": 1762200040.0,
"core.end_time": 1762200040.0,
"core.events": [
345685,
345689,
345693,
345697,
345701,
345705,
345709,
345713,
345717,
345721,
345725,
345729,
345733,
345737,
345741,
345745,
345749,
345753,
345757,
345761,
345765,
345769,
345773,
345777
],
"core.event_count": 24,
"core.first_event_number": 345685,
"core.last_event_number": 345777,
"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_3743_df-s04-d1_dw_0_20251103T165234.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3743_df-s04-d1_dw_0_20251103T165234_reco_stage1_20251103T200039_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": 1762200040.0,
"core.end_time": 1762200040.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_3743_df-s04-d1_dw_0_20251103T165234.hdf5"
}
]
} 