Jobsub ID 47428.91@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
7
[14:54:45.258] D [ aux ] <Resampler:resmp0> EOS at call=35
[14:54:45.258] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0
[14:54:45.258] D [ glue ] <FrameFanin:nfsp> EOS at call=35 with 8
[14:54:45.258] D [ glue ] frame sink sees EOS
[14:54:45.258] D [ pgraph ] <Pgrapher:> graph execution complete
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.28 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.08 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.91 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.9 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.86 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.86 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.2 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.14 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Aux::Resampler : 0.8 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Aux::Resampler : 0.79 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Aux::Resampler : 0.79 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Aux::Resampler : 0.78 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:54:45.258] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:54:45.258] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:54:45.258] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:54:45.258] I [ timer ] Timer: Total node execution : 66.40999956801534 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 39513 traces tagged "gauss"
FrameSaver: q=6.27144e+06 n=845930 tag=gauss
wclsFrameSaver: saving 48184 traces tagged "wiener"
FrameSaver: q=6.73189e+06 n=816074 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 579 XUs and 703 XVs -> 28 XUVs
C:0 T:1 1095 XUs and 1364 XVs -> 34 XUVs
C:0 T:2 405 XUs and 503 XVs -> 20 XUVs
C:0 T:3 540 XUs and 856 XVs -> 66 XUVs
C:0 T:4 151 XUs and 128 XVs -> 4 XUVs
C:0 T:5 199 XUs and 274 XVs -> 9 XUVs
C:0 T:6 868 XUs and 695 XVs -> 28 XUVs
C:0 T:7 1413 XUs and 1291 XVs -> 121 XUVs
C:0 T:8 16247 XUs and 31210 XVs -> 5069 XUVs
C:0 T:9 503 XUs and 672 XVs -> 35 XUVs
C:0 T:10 740 XUs and 829 XVs -> 57 XUVs
C:0 T:11 659 XUs and 799 XVs -> 44 XUVs
C:0 T:12 521 XUs and 682 XVs -> 50 XUVs
C:0 T:13 1038 XUs and 1523 XVs -> 72 XUVs
C:0 T:14 1311 XUs and 1283 XVs -> 84 XUVs
C:0 T:15 3595 XUs and 6845 XVs -> 671 XUVs
6392 XUVs total
1166 collection wire objects
6392 potential space points
Neighbour search...
929032 tests to find 399834 neighbours
Iterating with no regularization...
Begin: 2.12112e+08
0 1.89358e+08
1 1.87701e+08
2 1.87393e+08
3 1.87274e+08
Now with regularization...
Begin: 1.82042e+08
0 1.81906e+08
17-Sep-2025 14:55:28 CEST Closed output file "np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749_reco_stage1_20250917T125528_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 313.314 428.32 534.817 428.986 60.3443 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.7808e-05 7.78751e-05 0.00016012 7.40995e-05 2.08238e-05 18
produce:tpcrawdecoder:PDVDTPCReader 171.854 229.531 325.872 208.09 49.0283 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.0341127 0.121464 1.45284 0.0442333 0.323039 18
produce:pdvddaphne:DAPHNEReaderPDVD 18.1746 25.6375 32.4995 24.0029 4.70211 18
produce:ophit:OpHitFinder 0.0717147 0.0839257 0.0920423 0.0865359 0.00663032 18
produce:opflash:OpFlashFinderVerticalDrift 0.0188562 0.0239192 0.0318623 0.023651 0.00320888 18
produce:wclsdatavd:WireCellToolkit 53.0402 71.7673 84.7177 72.58 8.11903 18
produce:gaushit:GausHitFinder 0.989553 1.26626 1.71669 1.19918 0.210565 18
produce:nhitsfilter:NumberOfHitsFilter 0.000278001 0.000694827 0.00112004 0.000731571 0.000249644 18
produce:reco3d:SpacePointSolver 10.3594 18.2302 27.0646 17.5977 4.79468 18
produce:hitpdune:DisambigFromSpacePoints 0.179675 0.278719 0.4671 0.252271 0.0783176 18
produce:pandora:StandardPandora 25.7325 75.1605 147.126 67.4843 33.1753 18
produce:pandoraTrack:LArPandoraTrackCreation 0.563497 1.23591 2.36875 1.13054 0.484365 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.0235214 0.0331399 0.0451403 0.0321386 0.00608927 18
[art]:TriggerResults:TriggerResultInserter 1.2794e-05 1.86234e-05 4.266e-05 1.76935e-05 6.11112e-06 18
end_path:out1:RootOutput 3.216e-06 5.05933e-06 1.4988e-05 4.633e-06 2.45496e-06 18
end_path:out1:RootOutput(write) 4.5103 4.89838 5.24788 4.88138 0.235235 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5208.66 MB
Peak resident set size usage (VmHWM): 3320.67 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749_reco_stage1_20250917T125528_keepup.root
\tHists: np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749_reco_stage1_20250917T125528_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749_reco_stage1_20250917T125528_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749_reco_stage1_20250917T125528_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": 1758113729.0,
"core.end_time": 1758113729.0,
"core.events": [
22698,
22718,
22738,
22758,
22778,
22798,
22818,
22838,
22858,
22878,
22898,
22918,
22938,
22958,
22978,
22998,
23018,
23038
],
"core.event_count": 18,
"core.first_event_number": 22698,
"core.last_event_number": 23038,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39276
],
"core.runs_subruns": [
3927600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749_reco_stage1_20250917T125528_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": 1758113729.0,
"core.end_time": 1758113729.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39276
],
"core.runs_subruns": [
3927600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039276_0063_df-s05-d4_dw_0_20250902T131749.hdf5"
}
]
} 