Jobsub ID 242199.47@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
EOS at call=45 with 8
[07:34:47.622] D [ glue ] frame sink sees EOS
[07:34:47.622] D [ pgraph ] <Pgrapher:> graph execution complete
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.43 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.78 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.12 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.53 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.44 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.18 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.01 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.96 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.06 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:34:47.622] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:34:47.622] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:34:47.622] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[07:34:47.622] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[07:34:47.622] I [ timer ] Timer: Total node execution : 92.77999981306493 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 47680 traces tagged "gauss"
FrameSaver: q=1.19976e+07 n=1067512 tag=gauss
wclsFrameSaver: saving 59919 traces tagged "wiener"
FrameSaver: q=1.30888e+07 n=1025712 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 372 XUs and 567 XVs -> 24 XUVs
C:0 T:1 809 XUs and 1210 XVs -> 51 XUVs
C:0 T:2 63 XUs and 63 XVs -> 0 XUVs
C:0 T:3 735 XUs and 1034 XVs -> 71 XUVs
C:0 T:4 12119 XUs and 12252 XVs -> 640 XUVs
C:0 T:5 6660 XUs and 5874 XVs -> 474 XUVs
C:0 T:6 356 XUs and 519 XVs -> 7 XUVs
C:0 T:7 846 XUs and 791 XVs -> 31 XUVs
C:0 T:8 288 XUs and 355 XVs -> 17 XUVs
C:0 T:9 538 XUs and 601 XVs -> 33 XUVs
C:0 T:10 1092 XUs and 1787 XVs -> 115 XUVs
C:0 T:11 834 XUs and 901 XVs -> 49 XUVs
C:0 T:12 997 XUs and 1185 XVs -> 62 XUVs
C:0 T:13 5899 XUs and 10768 XVs -> 1751 XUVs
C:0 T:14 10495 XUs and 26507 XVs -> 3971 XUVs
C:0 T:15 13488 XUs and 26444 XVs -> 4183 XUVs
11479 XUVs total
1720 collection wire objects
11479 potential space points
Neighbour search...
1664393 tests to find 698170 neighbours
Iterating with no regularization...
Begin: 3.4449e+09
0 3.27194e+09
1 3.2658e+09
2 3.26542e+09
Now with regularization...
Begin: 3.18903e+09
0 3.18861e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 07:35:57 GMT Closed output file "np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543_reco_stage1_20251103T073557_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 187.572 279.892 531.759 259.235 86.8019 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.6734e-05 0.00010606 0.000179706 9.6248e-05 2.71475e-05 23
produce:tpcrawdecoder:PDVDTPCReader 40.6031 63.2463 93.4634 59.9937 12.132 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.493765 0.614824 0.861319 0.576659 0.0993047 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000450787 0.000591353 0.00147394 0.000525713 0.000205348 23
produce:ophit:OpHitFinder 8.6317e-05 0.000163819 0.000959403 0.000125755 0.000171197 23
produce:opflash:OpFlashFinderVerticalDrift 6.3254e-05 0.000109438 0.000556966 7.84e-05 9.82279e-05 23
produce:wclsdatavd:WireCellToolkit 62.4757 119.323 211.951 112.021 34.624 23
produce:gaushit:GausHitFinder 1.2782 1.97997 2.85021 1.99 0.380713 23
produce:nhitsfilter:NumberOfHitsFilter 0.000368434 0.00062203 0.000925977 0.000625594 0.000110198 23
produce:reco3d:SpacePointSolver 8.71789 15.2687 23.3579 15.3175 3.78955 23
produce:hitpdune:DisambigFromSpacePoints 0.162732 0.39929 0.746114 0.392846 0.15192 23
produce:pandora:StandardPandora 21.8521 68.5501 277.896 54.8092 56.2616 23
produce:pandoraTrack:LArPandoraTrackCreation 1.4602 4.12639 7.60275 3.87461 1.68967 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.028041 0.0576842 0.0961654 0.0569109 0.0164599 23
[art]:TriggerResults:TriggerResultInserter 3.8008e-05 7.04182e-05 0.000161742 6.8477e-05 2.23737e-05 23
end_path:out1:RootOutput 4.764e-06 1.68356e-05 5.2943e-05 1.4743e-05 8.83174e-06 23
end_path:out1:RootOutput(write) 5.09685 6.22754 8.1984 6.08131 0.803184 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6344.33 MB
Peak resident set size usage (VmHWM): 4323.45 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543_reco_stage1_20251103T073557_keepup.root
\tHists: np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543_reco_stage1_20251103T073558_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543_reco_stage1_20251103T073557_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543_reco_stage1_20251103T073557_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762155359.0,
"core.end_time": 1762155359.0,
"core.events": [
270639,
270643,
270647,
270651,
270655,
270659,
270663,
270667,
270671,
270675,
270679,
270683,
270687,
270691,
270695,
270699,
270703,
270707,
270711,
270715,
270719,
270723,
270727
],
"core.event_count": 23,
"core.first_event_number": 270639,
"core.last_event_number": 270727,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543_reco_stage1_20251103T073558_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_11_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": 1762155359.0,
"core.end_time": 1762155359.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_2932_df-s04-d3_dw_0_20251103T050543.hdf5"
}
]
} 