Jobsub ID 223125.188@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sink sees EOS
[10:27:12.527] D [ pgraph ] <Pgrapher:> graph execution complete
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 34.59 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 27.27 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 21.86 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.58 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.45 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.46 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.57 sec
[10:27:12.527] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.09 sec
[10:27:12.527] I [ timer ] Timer: WireCell::Aux::Resampler : 0.52 sec
[10:27:12.527] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[10:27:12.527] I [ timer ] Timer: WireCell::Aux::Resampler : 0.48 sec
[10:27:12.527] I [ timer ] Timer: WireCell::Aux::Resampler : 0.43 sec
[10:27:12.528] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:27:12.528] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:27:12.528] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:27:12.528] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:27:12.528] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:27:12.528] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:27:12.528] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:27:12.528] I [ timer ] Timer: Total node execution : 171.87000082433224 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 77353 traces tagged "gauss"
FrameSaver: q=2.20252e+07 n=2091233 tag=gauss
wclsFrameSaver: saving 102837 traces tagged "wiener"
FrameSaver: q=2.44157e+07 n=1993550 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 3021 XUs and 3854 XVs -> 177 XUVs
C:0 T:1 9274 XUs and 14492 XVs -> 695 XUVs
C:0 T:2 933 XUs and 836 XVs -> 37 XUVs
C:0 T:3 9586 XUs and 12756 XVs -> 925 XUVs
C:0 T:4 358 XUs and 559 XVs -> 24 XUVs
C:0 T:5 696 XUs and 1141 XVs -> 53 XUVs
C:0 T:6 2047 XUs and 3166 XVs -> 137 XUVs
C:0 T:7 1474 XUs and 1862 XVs -> 145 XUVs
C:0 T:8 60873 XUs and 173582 XVs -> 23114 XUVs
C:0 T:9 26668 XUs and 72973 XVs -> 7137 XUVs
C:0 T:10 29109 XUs and 72459 XVs -> 10950 XUVs
C:0 T:11 34308 XUs and 47674 XVs -> 8108 XUVs
C:0 T:12 34382 XUs and 77014 XVs -> 10720 XUVs
C:0 T:13 14425 XUs and 27935 XVs -> 3387 XUVs
C:0 T:14 13738 XUs and 24276 XVs -> 3432 XUVs
C:0 T:15 2464 XUs and 3848 XVs -> 676 XUVs
69717 XUVs total
6816 collection wire objects
69717 potential space points
Neighbour search...
9562577 tests to find 3959764 neighbours
Iterating with no regularization...
Begin: 6.16813e+10
0 6.08692e+10
1 6.08412e+10
Now with regularization...
Begin: 6.07088e+10
0 6.07038e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Oct-2025 10:35:19 CEST Closed output file "np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753_reco_stage1_20251003T083519_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 181.168 482.834 1654.22 322.519 331.001 21
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.4092e-05 0.000104225 0.00027792 9.0146e-05 4.11504e-05 21
produce:tpcrawdecoder:PDVDTPCReader 55.919 95.5533 143.083 92.2567 23.603 21
produce:triggerrawdecoder:PDVDTriggerReader4 0.300812 0.390418 0.626931 0.37593 0.0749286 21
produce:pdvddaphne:DAPHNEReaderPDVD 5.51801 11.5738 19.1497 11.5973 3.44132 21
produce:ophit:OpHitFinder 0.0560336 0.0959153 0.29106 0.0863245 0.0491223 21
produce:opflash:OpFlashFinderVerticalDrift 0.00788205 0.0178172 0.032816 0.0180938 0.00514588 21
produce:wclsdatavd:WireCellToolkit 64.168 96.1607 172.424 89.3283 27.3388 21
produce:gaushit:GausHitFinder 0.88642 2.15901 3.76204 2.09812 0.793293 21
produce:nhitsfilter:NumberOfHitsFilter 0.000299944 0.00104455 0.00248605 0.000971168 0.000646437 21
produce:reco3d:SpacePointSolver 10.2702 26.4673 54.6308 20.9999 14.2216 21
produce:hitpdune:DisambigFromSpacePoints 0.175753 0.558708 1.38899 0.324075 0.398702 21
produce:pandora:StandardPandora 17.4249 230.196 1386.43 74.1329 309.326 21
produce:pandoraTrack:LArPandoraTrackCreation 0.586251 5.24238 13.5693 4.15558 3.47073 21
produce:pandoraGnocalo:GnocchiCalorimetry 0.0210439 0.0590392 0.106946 0.0510385 0.024194 21
[art]:TriggerResults:TriggerResultInserter 3.1678e-05 6.0718e-05 0.000187723 5.3852e-05 3.09808e-05 21
end_path:out1:RootOutput 5.018e-06 1.09093e-05 6.0992e-05 7.791e-06 1.16212e-05 21
end_path:out1:RootOutput(write) 11.4582 14.2348 19.0489 14.0277 1.67995 21
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6035.17 MB
Peak resident set size usage (VmHWM): 4119.73 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753_reco_stage1_20251003T083519_keepup.root
\tHists: np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753_reco_stage1_20251003T083520_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753_reco_stage1_20251003T083519_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753_reco_stage1_20251003T083519_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": 1759480521.0,
"core.end_time": 1759480521.0,
"core.events": [
51793,
51801,
51809,
51817,
51825,
51833,
51841,
51849,
51857,
51865,
51873,
51881,
51889,
51897,
51905,
51913,
51921,
51929,
51937,
51945,
51953
],
"core.event_count": 21,
"core.first_event_number": 51793,
"core.last_event_number": 51953,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39812
],
"core.runs_subruns": [
3981200001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753_reco_stage1_20251003T083520_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": 1759480521.0,
"core.end_time": 1759480521.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39812
],
"core.runs_subruns": [
3981200001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039812_0305_df-s03-d1_dw_0_20251003T005753.hdf5"
}
]
} 