Jobsub ID 242471.68@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
proc0> EOS at call=47 anode=0
[20:16:21.940] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[20:16:21.940] D [ glue ] frame sink sees EOS
[20:16:21.940] D [ pgraph ] <Pgrapher:> graph execution complete
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 34.07 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.75 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.25 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.92 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.62 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.42 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.24 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.74 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Aux::Resampler : 2.12 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Aux::Resampler : 2.09 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Aux::Resampler : 2.06 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Aux::Resampler : 2.01 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:16:21.940] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:16:21.940] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:16:21.941] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:16:21.941] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:16:21.941] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:16:21.941] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:16:21.941] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:16:21.941] I [ timer ] Timer: Total node execution : 174.3699999600649 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 51291 traces tagged "gauss"
FrameSaver: q=2.78578e+07 n=1237313 tag=gauss
wclsFrameSaver: saving 64113 traces tagged "wiener"
FrameSaver: q=2.97435e+07 n=1193099 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 108 XUs and 113 XVs -> 3 XUVs
C:0 T:1 476 XUs and 619 XVs -> 14 XUVs
C:0 T:2 26814 XUs and 38000 XVs -> 2604 XUVs
C:0 T:3 19055 XUs and 21200 XVs -> 1623 XUVs
C:0 T:4 205 XUs and 221 XVs -> 14 XUVs
C:0 T:5 995 XUs and 1031 XVs -> 72 XUVs
C:0 T:6 614 XUs and 642 XVs -> 41 XUVs
C:0 T:7 3689 XUs and 3422 XVs -> 293 XUVs
C:0 T:8 1436 XUs and 1705 XVs -> 93 XUVs
C:0 T:9 1294 XUs and 1168 XVs -> 92 XUVs
C:0 T:10 557 XUs and 747 XVs -> 51 XUVs
C:0 T:11 1384 XUs and 1596 XVs -> 37 XUVs
C:0 T:12 291 XUs and 276 XVs -> 39 XUVs
C:0 T:13 2459 XUs and 2550 XVs -> 242 XUVs
C:0 T:14 590 XUs and 600 XVs -> 30 XUVs
C:0 T:15 2389 XUs and 2563 XVs -> 152 XUVs
5400 XUVs total
2644 collection wire objects
5400 potential space points
Neighbour search...
226704 tests to find 49360 neighbours
Iterating with no regularization...
Begin: 1.90021e+11
0 1.83127e+11
1 1.82705e+11
2 1.827e+11
Now with regularization...
Begin: 1.81355e+11
0 1.81351e+11
03-Nov-2025 20:18:57 GMT Closed output file "np02vd_raw_run040346_3741_df-s04-d2_dw_0_20251103T165007_reco_stage1_20251103T201857_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 246.332 357.593 717.919 325.953 99.4756 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.9738e-05 0.000133735 0.000522134 0.000112347 8.20411e-05 24
produce:tpcrawdecoder:PDVDTPCReader 78.9548 139.622 228.887 136.738 33.7808 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.763409 1.11909 2.6635 1.04387 0.363641 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000481277 0.000616869 0.00114268 0.000569052 0.000156256 24
produce:ophit:OpHitFinder 7.8608e-05 0.000152771 0.00085435 0.000109467 0.000152066 24
produce:opflash:OpFlashFinderVerticalDrift 6.1196e-05 0.000107459 0.000519979 7.52815e-05 9.06032e-05 24
produce:wclsdatavd:WireCellToolkit 80.9896 116.402 179.564 102.302 27.5074 24
produce:gaushit:GausHitFinder 1.13879 2.175 2.85953 2.25767 0.473693 24
produce:nhitsfilter:NumberOfHitsFilter 0.000390556 0.000739431 0.00122017 0.000728703 0.000194302 24
produce:reco3d:SpacePointSolver 11.267 19.8516 29.6585 19.7435 5.20086 24
produce:hitpdune:DisambigFromSpacePoints 0.134042 0.340066 0.613451 0.334357 0.116674 24
produce:pandora:StandardPandora 20.1624 67.8907 349.593 55.7802 63.5694 24
produce:pandoraTrack:LArPandoraTrackCreation 1.21799 2.81136 6.86854 2.76397 1.31495 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0366422 0.067153 0.0940837 0.0664217 0.0159842 24
[art]:TriggerResults:TriggerResultInserter 3.6309e-05 7.18493e-05 0.000180691 6.9316e-05 2.78595e-05 24
end_path:out1:RootOutput 5.33e-06 1.21704e-05 5.7098e-05 1.059e-05 1.0153e-05 24
end_path:out1:RootOutput(write) 5.36855 7.19019 9.4261 7.22902 0.791004 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5318.89 MB
Peak resident set size usage (VmHWM): 3226.51 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3741_df-s04-d2_dw_0_20251103T165007_reco_stage1_20251103T201857_keepup.root
\tHists: np02vd_raw_run040346_3741_df-s04-d2_dw_0_20251103T165007_reco_stage1_20251103T201858_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3741_df-s04-d2_dw_0_20251103T165007_reco_stage1_20251103T201857_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3741_df-s04-d2_dw_0_20251103T165007_reco_stage1_20251103T201857_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": 1762201139.0,
"core.end_time": 1762201139.0,
"core.events": [
345422,
345426,
345430,
345434,
345438,
345442,
345446,
345450,
345454,
345458,
345462,
345466,
345470,
345474,
345478,
345482,
345486,
345490,
345494,
345498,
345502,
345506,
345510,
345514
],
"core.event_count": 24,
"core.first_event_number": 345422,
"core.last_event_number": 345514,
"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_3741_df-s04-d2_dw_0_20251103T165007.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3741_df-s04-d2_dw_0_20251103T165007_reco_stage1_20251103T201858_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": 1762201139.0,
"core.end_time": 1762201139.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_3741_df-s04-d2_dw_0_20251103T165007.hdf5"
}
]
} 