Jobsub ID 40876.90@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
] D [ aux ] <Resampler:resmp0> EOS at call=35
[13:11:45.832] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0
[13:11:45.832] D [ glue ] <FrameFanin:nfsp> EOS at call=35 with 8
[13:11:45.832] D [ glue ] frame sink sees EOS
[13:11:45.832] D [ pgraph ] <Pgrapher:> graph execution complete
[13:11:45.832] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.81 sec
[13:11:45.832] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.34 sec
[13:11:45.832] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.97 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.71 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.18 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.15 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.12 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.06 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Aux::Resampler : 1.18 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Aux::Resampler : 1.15 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Aux::Resampler : 1.15 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Aux::Resampler : 1.12 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:45.833] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:11:45.833] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:11:45.833] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:11:45.833] I [ timer ] Timer: Total node execution : 89.9800010100007 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 44444 traces tagged "gauss"
FrameSaver: q=8.38274e+06 n=937571 tag=gauss
wclsFrameSaver: saving 55145 traces tagged "wiener"
FrameSaver: q=9.11747e+06 n=895398 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 134 XUs and 223 XVs -> 5 XUVs
C:0 T:1 786 XUs and 424 XVs -> 42 XUVs
C:0 T:2 100 XUs and 77 XVs -> 9 XUVs
C:0 T:3 456 XUs and 294 XVs -> 19 XUVs
C:0 T:4 341 XUs and 553 XVs -> 41 XUVs
C:0 T:5 1024 XUs and 1118 XVs -> 21 XUVs
C:0 T:6 3596 XUs and 4791 XVs -> 283 XUVs
C:0 T:7 2357 XUs and 3139 XVs -> 227 XUVs
C:0 T:8 10815 XUs and 32076 XVs -> 6276 XUVs
C:0 T:9 6207 XUs and 33632 XVs -> 1615 XUVs
C:0 T:10 7186 XUs and 20823 XVs -> 1936 XUVs
C:0 T:11 1113 XUs and 1462 XVs -> 153 XUVs
C:0 T:12 10161 XUs and 11544 XVs -> 2279 XUVs
C:0 T:13 1465 XUs and 2831 XVs -> 114 XUVs
C:0 T:14 4781 XUs and 4708 XVs -> 251 XUVs
C:0 T:15 4506 XUs and 6377 XVs -> 783 XUVs
14054 XUVs total
1962 collection wire objects
14054 potential space points
Neighbour search...
2004410 tests to find 792142 neighbours
Iterating with no regularization...
Begin: 6.30324e+08
0 5.54002e+08
1 5.49448e+08
2 5.48732e+08
3 5.4852e+08
Now with regularization...
Begin: 5.36824e+08
0 5.36667e+08
17-Sep-2025 13:13:30 CEST Closed output file "np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906_reco_stage1_20250917T111330_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 193.163 286.144 436.897 275.845 65.5504 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.9879e-05 7.51046e-05 0.000113809 7.07695e-05 1.64687e-05 18
produce:tpcrawdecoder:PDVDTPCReader 14.9641 16.919 35.0706 15.5132 4.48335 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.034359 0.0345928 0.0353353 0.0345862 0.000211052 18
produce:pdvddaphne:DAPHNEReaderPDVD 7.41254 7.92624 8.3974 8.03465 0.26875 18
produce:ophit:OpHitFinder 0.0500771 0.0622697 0.069215 0.0637883 0.00482329 18
produce:opflash:OpFlashFinderVerticalDrift 0.00941516 0.0174854 0.0226966 0.0181545 0.00373563 18
produce:wclsdatavd:WireCellToolkit 90.5769 102.139 119.082 100.321 7.80935 18
produce:gaushit:GausHitFinder 1.45549 2.10794 3.01613 2.06235 0.409523 18
produce:nhitsfilter:NumberOfHitsFilter 0.000316895 0.000468605 0.000720209 0.00046722 9.49185e-05 18
produce:reco3d:SpacePointSolver 14.0436 24.7482 35.0177 24.8954 5.54512 18
produce:hitpdune:DisambigFromSpacePoints 0.256817 0.464091 0.772518 0.469633 0.13104 18
produce:pandora:StandardPandora 54.0672 122.962 252.996 111.543 54.0092 18
produce:pandoraTrack:LArPandoraTrackCreation 1.28459 2.26636 3.69988 2.15087 0.608686 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.034953 0.0455675 0.0569057 0.0443729 0.00625657 18
[art]:TriggerResults:TriggerResultInserter 1.6948e-05 2.54737e-05 5.6736e-05 2.3739e-05 8.01884e-06 18
end_path:out1:RootOutput 4.207e-06 8.48728e-06 2.4321e-05 8.1965e-06 4.10087e-06 18
end_path:out1:RootOutput(write) 5.94056 6.41552 6.8905 6.41151 0.242659 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5070.55 MB
Peak resident set size usage (VmHWM): 3181.02 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906_reco_stage1_20250917T111330_keepup.root
\tHists: np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906_reco_stage1_20250917T111330_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906_reco_stage1_20250917T111330_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906_reco_stage1_20250917T111330_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": 1758107611.0,
"core.end_time": 1758107611.0,
"core.events": [
2529,
2549,
2569,
2589,
2609,
2629,
2649,
2669,
2689,
2709,
2729,
2749,
2769,
2789,
2809,
2829,
2849,
2869
],
"core.event_count": 18,
"core.first_event_number": 2529,
"core.last_event_number": 2869,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39277
],
"core.runs_subruns": [
3927700001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906_reco_stage1_20250917T111330_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": 1758107611.0,
"core.end_time": 1758107611.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39277
],
"core.runs_subruns": [
3927700001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039277_0007_df-s03-d3_dw_0_20250902T141906.hdf5"
}
]
} 