Jobsub ID 237218.182@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[14:04:24.866] D [ glue ] frame sink sees EOS
[14:04:24.867] D [ pgraph ] <Pgrapher:> graph execution complete
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.83 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.44 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.4 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.34 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.8 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.71 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.61 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.49 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:04:24.867] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:24.867] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:04:24.867] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:04:24.867] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:04:24.867] I [ timer ] Timer: Total node execution : 66.72999939881265 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 45860 traces tagged "gauss"
FrameSaver: q=1.31352e+07 n=1021923 tag=gauss
wclsFrameSaver: saving 57361 traces tagged "wiener"
FrameSaver: q=1.38902e+07 n=976230 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1162 XUs and 1486 XVs -> 56 XUVs
C:0 T:1 1380 XUs and 1536 XVs -> 91 XUVs
C:0 T:2 572 XUs and 413 XVs -> 15 XUVs
C:0 T:3 11451 XUs and 15024 XVs -> 807 XUVs
C:0 T:4 977 XUs and 1446 XVs -> 56 XUVs
C:0 T:5 806 XUs and 800 XVs -> 36 XUVs
C:0 T:6 636 XUs and 698 XVs -> 22 XUVs
C:0 T:7 405 XUs and 631 XVs -> 16 XUVs
C:0 T:8 1150 XUs and 2178 XVs -> 68 XUVs
C:0 T:9 559 XUs and 764 XVs -> 36 XUVs
C:0 T:10 347 XUs and 350 XVs -> 26 XUVs
C:0 T:11 207 XUs and 325 XVs -> 20 XUVs
C:0 T:12 1582 XUs and 1404 XVs -> 132 XUVs
C:0 T:13 1837 XUs and 1372 XVs -> 225 XUVs
C:0 T:14 593 XUs and 782 XVs -> 36 XUVs
C:0 T:15 939 XUs and 911 XVs -> 64 XUVs
1706 XUVs total
1326 collection wire objects
1706 potential space points
Neighbour search...
26954 tests to find 7966 neighbours
Iterating with no regularization...
Begin: 1.18967e+10
0 1.1817e+10
1 1.18148e+10
Now with regularization...
Begin: 1.16269e+10
0 1.16268e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
04-Nov-2025 14:05:29 GMT Closed output file "np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613_reco_stage1_20251104T140529_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 105.345 191.121 504.899 166.713 88.3076 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.1281e-05 0.000123939 0.000227335 0.000103414 4.17619e-05 24
produce:tpcrawdecoder:PDVDTPCReader 13.4918 15.9797 38.726 14.3013 5.8203 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.532532 0.5522 0.611326 0.544658 0.0223293 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000425536 0.000531874 0.000968153 0.000502451 0.000121919 24
produce:ophit:OpHitFinder 8.0481e-05 0.00016093 0.000755895 0.000136861 0.000130536 24
produce:opflash:OpFlashFinderVerticalDrift 6.4641e-05 0.000102712 0.000409927 8.5099e-05 6.71476e-05 24
produce:wclsdatavd:WireCellToolkit 57.7888 88.8552 151.291 74.2774 25.0968 24
produce:gaushit:GausHitFinder 1.08736 1.71731 2.72038 1.70076 0.422645 24
produce:nhitsfilter:NumberOfHitsFilter 0.000258364 0.000374282 0.000725018 0.00035293 0.000104969 24
produce:reco3d:SpacePointSolver 8.30927 16.0224 35.1933 14.4507 5.5572 24
produce:hitpdune:DisambigFromSpacePoints 0.140407 0.284827 0.721679 0.249343 0.126425 24
produce:pandora:StandardPandora 14.7911 60.1832 300.605 35.2974 63.7356 24
produce:pandoraTrack:LArPandoraTrackCreation 0.561169 1.54658 5.45826 1.28546 0.943562 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0232059 0.0470022 0.0821021 0.0438374 0.0143669 24
[art]:TriggerResults:TriggerResultInserter 2.4727e-05 4.50573e-05 9.7733e-05 3.8362e-05 1.73018e-05 24
end_path:out1:RootOutput 7.695e-06 1.17691e-05 3.4856e-05 1.064e-05 5.12695e-06 24
end_path:out1:RootOutput(write) 4.59986 5.84724 7.21759 5.84766 0.530895 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6188.95 MB
Peak resident set size usage (VmHWM): 4201.59 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613_reco_stage1_20251104T140529_keepup.root
\tHists: np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613_reco_stage1_20251104T140529_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613_reco_stage1_20251104T140529_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613_reco_stage1_20251104T140529_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": 1762265130.0,
"core.end_time": 1762265130.0,
"core.events": [
113831,
113835,
113839,
113843,
113847,
113851,
113855,
113859,
113863,
113867,
113871,
113875,
113879,
113883,
113887,
113891,
113895,
113899,
113903,
113907,
113911,
113915,
113919,
113923
],
"core.event_count": 24,
"core.first_event_number": 113831,
"core.last_event_number": 113923,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613_reco_stage1_20251104T140529_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": 1762265130.0,
"core.end_time": 1762265130.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1232_df-s04-d3_dw_0_20251104T121613.hdf5"
}
]
} 