Jobsub ID 36312.96@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
oc0> EOS at call=47 anode=0
[15:04:19.889] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[15:04:19.889] D [ glue ] frame sink sees EOS
[15:04:19.889] D [ pgraph ] <Pgrapher:> graph execution complete
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.08 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.92 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.88 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.55 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.45 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.42 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.39 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.23 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Aux::Resampler : 0.55 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Aux::Resampler : 0.55 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:04:19.889] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:04:19.889] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:04:19.889] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[15:04:19.889] I [ timer ] Timer: Total node execution : 55.11000009253621 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 56270 traces tagged "gauss"
FrameSaver: q=8.97948e+06 n=1157685 tag=gauss
wclsFrameSaver: saving 68737 traces tagged "wiener"
FrameSaver: q=9.41412e+06 n=1110794 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 248 XUs and 278 XVs -> 16 XUVs
C:0 T:1 440 XUs and 598 XVs -> 39 XUVs
C:0 T:2 1146 XUs and 1426 XVs -> 50 XUVs
C:0 T:3 1276 XUs and 1544 XVs -> 65 XUVs
C:0 T:4 545 XUs and 432 XVs -> 16 XUVs
C:0 T:5 2590 XUs and 1850 XVs -> 109 XUVs
C:0 T:6 2296 XUs and 2464 XVs -> 138 XUVs
C:0 T:7 2120 XUs and 1350 XVs -> 83 XUVs
C:0 T:8 2787 XUs and 4193 XVs -> 140 XUVs
C:0 T:9 3398 XUs and 3047 XVs -> 185 XUVs
C:0 T:10 1568 XUs and 1364 XVs -> 79 XUVs
C:0 T:11 3306 XUs and 2176 XVs -> 165 XUVs
C:0 T:12 2356 XUs and 3560 XVs -> 156 XUVs
C:0 T:13 3766 XUs and 4279 XVs -> 197 XUVs
C:0 T:14 1382 XUs and 1383 XVs -> 72 XUVs
C:0 T:15 2628 XUs and 3679 XVs -> 256 XUVs
1766 XUVs total
1491 collection wire objects
1766 potential space points
Neighbour search...
12810 tests to find 6728 neighbours
Iterating with no regularization...
Begin: 4.95459e+08
0 4.79714e+08
1 4.79486e+08
Now with regularization...
Begin: 4.73435e+08
0 4.73428e+08
08-Sep-2025 15:05:27 BST Closed output file "np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716_reco_stage1_20250908T140527_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 87.958 129.688 251.323 118.65 35.3636 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.5354e-05 7.05965e-05 0.000218389 6.4644e-05 3.13403e-05 24
produce:tpcrawdecoder:PDVDTPCReader 19.6239 41.199 70.0838 37.372 14.0587 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.049291 0.0533074 0.0667616 0.0510968 0.00442961 24
produce:pdvddaphne:DAPHNEReaderPDVD 2.68439 4.86987 9.66364 4.65792 1.66149 24
produce:ophit:OpHitFinder 0.0231678 0.0288324 0.0314326 0.0294971 0.00228237 24
produce:opflash:OpFlashFinderVerticalDrift 0.00494559 0.00773262 0.0109057 0.00743519 0.00192305 24
produce:wclsdatavd:WireCellToolkit 31.084 41.4396 61.9895 37.4958 8.90045 24
produce:gaushit:GausHitFinder 0.504922 0.702249 1.18145 0.675352 0.171846 24
produce:nhitsfilter:NumberOfHitsFilter 0.000244713 0.000364461 0.000550015 0.000356131 7.59338e-05 24
produce:reco3d:SpacePointSolver 4.51265 8.52243 15.2622 8.10405 2.49951 24
produce:hitpdune:DisambigFromSpacePoints 0.0586661 0.10727 0.216381 0.107376 0.0336232 24
produce:pandora:StandardPandora 12.5518 29.2473 139.404 23.6184 25.0755 24
produce:pandoraTrack:LArPandoraTrackCreation 0.19501 0.64329 1.94393 0.583267 0.355598 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.00983896 0.0152843 0.0240643 0.0154457 0.00315476 24
[art]:TriggerResults:TriggerResultInserter 1.9452e-05 2.8497e-05 6.6206e-05 2.68655e-05 9.0424e-06 24
end_path:out1:RootOutput 5.339e-06 1.28895e-05 2.9447e-05 1.2497e-05 3.81861e-06 24
end_path:out1:RootOutput(write) 2.27089 2.80082 3.82878 2.51344 0.51735 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5046.76 MB
Peak resident set size usage (VmHWM): 3148.86 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716_reco_stage1_20250908T140527_keepup.root
\tHists: np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716_reco_stage1_20250908T140527_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716_reco_stage1_20250908T140527_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716_reco_stage1_20250908T140527_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_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": 1757340328.0,
"core.end_time": 1757340328.0,
"core.events": [
1564758,
1564778,
1564798,
1564818,
1564838,
1564858,
1564878,
1564898,
1564918,
1564938,
1564958,
1564978,
1564998,
1565018,
1565038,
1565058,
1565078,
1565098,
1565118,
1565138,
1565158,
1565178,
1565198,
1565218
],
"core.event_count": 24,
"core.first_event_number": 1564758,
"core.last_event_number": 1565218,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39324
],
"core.runs_subruns": [
3932400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716_reco_stage1_20250908T140527_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_10_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": 1757340328.0,
"core.end_time": 1757340328.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39324
],
"core.runs_subruns": [
3932400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039324_2989_df-s05-d4_dw_0_20250908T075716.hdf5"
}
]
} 