Jobsub ID 36987.32@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
mnibusSigProc:anode0sigproc0> EOS at call=51 anode=0
[17:25:08.460] D [ glue ] <FrameFanin:nfsp> EOS at call=51 with 8
[17:25:08.460] D [ glue ] frame sink sees EOS
[17:25:08.460] D [ pgraph ] <Pgrapher:> graph execution complete
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.98 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.93 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.23 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.17 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.73 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.59 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.58 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.54 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Aux::Resampler : 0.51 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Aux::Resampler : 0.51 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:25:08.461] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:25:08.461] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:25:08.461] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[17:25:08.461] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[17:25:08.461] I [ timer ] Timer: Total node execution : 71.83999952115119 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 30173 traces tagged "gauss"
FrameSaver: q=7.83102e+06 n=704928 tag=gauss
wclsFrameSaver: saving 36589 traces tagged "wiener"
FrameSaver: q=8.14302e+06 n=678193 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 194 XUs and 287 XVs -> 6 XUVs
C:0 T:1 224 XUs and 363 XVs -> 15 XUVs
C:0 T:2 116 XUs and 111 XVs -> 8 XUVs
C:0 T:3 69 XUs and 83 XVs -> 2 XUVs
C:0 T:4 436 XUs and 552 XVs -> 36 XUVs
C:0 T:5 166 XUs and 186 XVs -> 13 XUVs
C:0 T:6 285 XUs and 391 XVs -> 19 XUVs
C:0 T:7 149 XUs and 233 XVs -> 10 XUVs
C:0 T:8 1920 XUs and 1459 XVs -> 101 XUVs
C:0 T:9 405 XUs and 469 XVs -> 75 XUVs
C:0 T:10 949 XUs and 844 XVs -> 33 XUVs
C:0 T:11 591 XUs and 533 XVs -> 33 XUVs
C:0 T:12 12460 XUs and 28032 XVs -> 1205 XUVs
C:0 T:13 1756 XUs and 1279 XVs -> 53 XUVs
C:0 T:14 530 XUs and 672 XVs -> 48 XUVs
C:0 T:15 2622 XUs and 2346 XVs -> 119 XUVs
1776 XUVs total
1120 collection wire objects
1776 potential space points
Neighbour search...
37920 tests to find 12740 neighbours
Iterating with no regularization...
Begin: 6.77086e+09
0 6.47213e+09
1 6.46281e+09
2 6.46247e+09
Now with regularization...
Begin: 6.44157e+09
0 6.44146e+09
09-Sep-2025 17:26:39 BST Closed output file "np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124_reco_stage1_20250909T162639_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 160.776 255.998 399.28 232.561 67.7349 26
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.6638e-05 0.000139471 0.000376696 0.000123256 5.70542e-05 26
produce:tpcrawdecoder:PDVDTPCReader 21.7534 41.5133 80.5317 39.4513 14.2957 26
produce:triggerrawdecoder:PDVDTriggerReader4 0.0332586 0.0404993 0.119414 0.0343572 0.0168958 26
produce:pdvddaphne:DAPHNEReaderPDVD 7.01483 9.64264 13.5688 8.89169 1.81622 26
produce:ophit:OpHitFinder 0.0580874 0.0744704 0.0913026 0.0745666 0.00841108 26
produce:opflash:OpFlashFinderVerticalDrift 0.0104475 0.0199959 0.0304622 0.0197113 0.00515539 26
produce:wclsdatavd:WireCellToolkit 63.0968 82.1332 150.082 75.1001 21.6435 26
produce:gaushit:GausHitFinder 1.19679 1.88502 2.67486 1.70672 0.445813 26
produce:nhitsfilter:NumberOfHitsFilter 0.000497131 0.000771941 0.00120405 0.000777281 0.000161992 26
produce:reco3d:SpacePointSolver 8.04604 14.6107 23.7279 13.0932 4.77589 26
produce:hitpdune:DisambigFromSpacePoints 0.160585 0.356929 0.657341 0.319073 0.131145 26
produce:pandora:StandardPandora 38.8629 96.5031 162.064 93.69 37.5205 26
produce:pandoraTrack:LArPandoraTrackCreation 0.68099 3.04009 5.97784 2.45096 1.51516 26
produce:pandoraGnocalo:GnocchiCalorimetry 0.0224374 0.0494059 0.0760454 0.0469857 0.0138152 26
[art]:TriggerResults:TriggerResultInserter 4.86e-05 9.01963e-05 0.000210631 8.93755e-05 2.97882e-05 26
end_path:out1:RootOutput 6.634e-06 2.15634e-05 6.5888e-05 2.01125e-05 1.20594e-05 26
end_path:out1:RootOutput(write) 4.726 6.0312 13.349 5.56862 1.66017 26
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5137.59 MB
Peak resident set size usage (VmHWM): 3251.97 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124_reco_stage1_20250909T162639_keepup.root
\tHists: np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124_reco_stage1_20250909T162640_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124_reco_stage1_20250909T162639_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124_reco_stage1_20250909T162639_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": 1757435201.0,
"core.end_time": 1757435201.0,
"core.events": [
87639,
87659,
87679,
87699,
87719,
87739,
87759,
87779,
87799,
87819,
87839,
87859,
87879,
87899,
87919,
87939,
87959,
87979,
87999,
88019,
88039,
88059,
88079,
88099,
88119,
88139
],
"core.event_count": 26,
"core.first_event_number": 87639,
"core.last_event_number": 88139,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39344
],
"core.runs_subruns": [
3934400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124_reco_stage1_20250909T162640_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": 1757435201.0,
"core.end_time": 1757435201.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39344
],
"core.runs_subruns": [
3934400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039344_0164_df-s05-d5_dw_0_20250908T153124.hdf5"
}
]
} 