Jobsub ID 41697.132@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
EOS at call=91
[08:16:52.162] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=91 anode=0
[08:16:52.162] D [ glue ] <FrameFanin:nfsp> EOS at call=91 with 8
[08:16:52.162] D [ glue ] frame sink sees EOS
[08:16:52.162] D [ pgraph ] <Pgrapher:> graph execution complete
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.83 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.9 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.7 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.55 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:16:52.162] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[08:16:52.162] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[08:16:52.162] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[08:16:52.162] I [ timer ] Timer: Total node execution : 33.91000001132488 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 18198 traces tagged "gauss"
FrameSaver: q=3.25189e+06 n=431918 tag=gauss
wclsFrameSaver: saving 23959 traces tagged "wiener"
FrameSaver: q=3.5056e+06 n=412237 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 500 XUs and 541 XVs -> 29 XUVs
C:0 T:1 233 XUs and 244 XVs -> 20 XUVs
C:0 T:2 601 XUs and 903 XVs -> 33 XUVs
C:0 T:3 28458 XUs and 29757 XVs -> 1819 XUVs
C:0 T:4 916 XUs and 783 XVs -> 79 XUVs
C:0 T:5 464 XUs and 509 XVs -> 34 XUVs
C:0 T:6 362 XUs and 514 XVs -> 17 XUVs
C:0 T:7 273 XUs and 320 XVs -> 16 XUVs
2047 XUVs total
1256 collection wire objects
2047 potential space points
Neighbour search...
46293 tests to find 10718 neighbours
Iterating with no regularization...
Begin: 1.89782e+09
0 1.80267e+09
1 1.79991e+09
2 1.79987e+09
Now with regularization...
Begin: 1.77875e+09
0 1.77873e+09
18-Sep-2025 08:17:09 UTC Closed output file "np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753_reco_stage1_20250918T081709_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 37.3729 67.6872 134.267 64.6875 21.8539 46
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.4933e-05 0.000116033 0.000313163 0.000100491 4.28658e-05 46
produce:tpcrawdecoder:PDVDTPCReader 7.02999 7.76563 10.6119 7.50393 0.726831 46
produce:triggerrawdecoder:PDVDTriggerReader4 0.389407 0.396606 0.422028 0.393229 0.007794 46
produce:pdvddaphne:DAPHNEReaderPDVD 2.76129 3.14034 3.5478 3.10756 0.167383 46
produce:ophit:OpHitFinder 0.0229224 0.0416176 0.0547233 0.0408135 0.00648661 46
produce:opflash:OpFlashFinderVerticalDrift 0.00188507 0.00825644 0.014291 0.00816834 0.00255139 46
produce:wclsdatavd:WireCellToolkit 19.8522 30.8729 47.0624 31.8139 7.93809 46
produce:gaushit:GausHitFinder 0.268816 0.582594 1.02414 0.560722 0.166171 46
produce:nhitsfilter:NumberOfHitsFilter 8.9946e-05 0.000152229 0.000270077 0.000147998 3.53383e-05 46
produce:reco3d:SpacePointSolver 1.29735 4.64474 10.2097 4.09945 1.86069 46
produce:hitpdune:DisambigFromSpacePoints 0.0178463 0.065494 0.153949 0.0585314 0.0357714 46
produce:pandora:StandardPandora 2.49665 17.5575 71.8598 11.4055 15.9253 46
produce:pandoraTrack:LArPandoraTrackCreation 0.0837712 0.267016 0.686452 0.209713 0.159966 46
produce:pandoraGnocalo:GnocchiCalorimetry 0.00658674 0.0138854 0.0239233 0.0131687 0.00379304 46
[art]:TriggerResults:TriggerResultInserter 2.1923e-05 3.90098e-05 7.4543e-05 3.53735e-05 1.26874e-05 46
end_path:out1:RootOutput 4.066e-06 8.34841e-06 2.4778e-05 8.1325e-06 3.94263e-06 46
end_path:out1:RootOutput(write) 1.52905 2.30857 3.04614 2.30897 0.34581 46
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4546.57 MB
Peak resident set size usage (VmHWM): 2377.38 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753_reco_stage1_20250918T081709_keepup.root
\tHists: np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753_reco_stage1_20250918T081710_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753_reco_stage1_20250918T081709_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753_reco_stage1_20250918T081709_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": 1758183430.0,
"core.end_time": 1758183430.0,
"core.events": [
106712,
106718,
106724,
106730,
106736,
106742,
106748,
106754,
106760,
106766,
106772,
106778,
106784,
106790,
106796,
106802,
106808,
106814,
106820,
106826,
106832,
106838,
106844,
106850,
106856,
106862,
106868,
106874,
106880,
106886,
106892,
106898,
106904,
106910,
106916,
106922,
106928,
106934,
106940,
106946,
106952,
106958,
106964,
106970,
106976,
106982
],
"core.event_count": 46,
"core.first_event_number": 106712,
"core.last_event_number": 106982,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753_reco_stage1_20250918T081710_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": 1758183430.0,
"core.end_time": 1758183430.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0372_df-s05-d2_dw_0_20250918T063753.hdf5"
}
]
} 