Jobsub ID 237252.1@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
6:55.158] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[15:46:55.158] D [ glue ] frame sink sees EOS
[15:46:55.158] D [ pgraph ] <Pgrapher:> graph execution complete
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.32 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.28 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.64 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.56 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.1 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.89 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.73 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.62 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:55.158] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:46:55.158] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:46:55.158] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[15:46:55.158] I [ timer ] Timer: Total node execution : 70.11000033840537 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 56841 traces tagged "gauss"
FrameSaver: q=2.20113e+07 n=1316223 tag=gauss
wclsFrameSaver: saving 71486 traces tagged "wiener"
FrameSaver: q=2.31308e+07 n=1261523 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 3048 XUs and 2251 XVs -> 721 XUVs
C:0 T:1 13148 XUs and 7618 XVs -> 2653 XUVs
C:0 T:2 11897 XUs and 8035 XVs -> 297 XUVs
C:0 T:3 1984 XUs and 1997 XVs -> 60 XUVs
C:0 T:4 1013 XUs and 856 XVs -> 48 XUVs
C:0 T:5 1356 XUs and 1132 XVs -> 61 XUVs
C:0 T:6 2063 XUs and 2038 XVs -> 99 XUVs
C:0 T:7 457 XUs and 553 XVs -> 19 XUVs
C:0 T:8 1091 XUs and 902 XVs -> 58 XUVs
C:0 T:9 257 XUs and 319 XVs -> 8 XUVs
C:0 T:10 1600 XUs and 2075 XVs -> 71 XUVs
C:0 T:11 1205 XUs and 1417 XVs -> 102 XUVs
C:0 T:12 1276 XUs and 1821 XVs -> 95 XUVs
C:0 T:13 2275 XUs and 2116 XVs -> 88 XUVs
C:0 T:14 1758 XUs and 1702 XVs -> 119 XUVs
C:0 T:15 4209 XUs and 4068 XVs -> 287 XUVs
4786 XUVs total
1407 collection wire objects
4786 potential space points
Neighbour search...
572970 tests to find 260418 neighbours
Iterating with no regularization...
Begin: 1.99983e+10
0 1.95487e+10
1 1.95327e+10
Now with regularization...
Begin: 1.94021e+10
0 1.94008e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
04-Nov-2025 15:48:09 CET Closed output file "np02vd_raw_run040348_0761_df-s04-d0_dw_0_20251104T054159_reco_stage1_20251104T144809_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 118.517 251.823 877.402 186.737 166.307 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.7037e-05 8.7117e-05 0.000241564 8.12725e-05 3.44816e-05 24
produce:tpcrawdecoder:PDVDTPCReader 17.9929 69.4536 223.931 56.3566 48.824 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.508474 0.797762 3.14005 0.679553 0.513666 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000287801 0.000500847 0.000884372 0.000441223 0.000152617 24
produce:ophit:OpHitFinder 5.2659e-05 9.4192e-05 0.000594167 6.8379e-05 0.000105326 24
produce:opflash:OpFlashFinderVerticalDrift 4.4374e-05 7.23155e-05 0.000355037 5.9387e-05 5.98219e-05 24
produce:wclsdatavd:WireCellToolkit 51.5492 83.6413 222.146 66.4058 41.6538 24
produce:gaushit:GausHitFinder 0.792914 1.50429 6.24161 1.20738 1.08623 24
produce:nhitsfilter:NumberOfHitsFilter 0.000194505 0.000458609 0.00164441 0.000388304 0.000275121 24
produce:reco3d:SpacePointSolver 6.96879 14.3083 36.1992 11.9228 6.85458 23
produce:hitpdune:DisambigFromSpacePoints 0.0992757 0.229223 0.657664 0.17905 0.150091 23
produce:pandora:StandardPandora 14.4003 77.494 581.693 28.7213 131.86 23
produce:pandoraTrack:LArPandoraTrackCreation 0.434456 1.33469 4.22898 0.894167 1.00949 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0220451 0.0360293 0.0648637 0.0322906 0.0104646 23
[art]:TriggerResults:TriggerResultInserter 1.4588e-05 2.6195e-05 5.8951e-05 2.0829e-05 1.22307e-05 24
end_path:out1:RootOutput 3.596e-06 5.50625e-06 1.7272e-05 4.559e-06 3.08473e-06 24
end_path:out1:RootOutput(write) 4.66557 6.86217 10.3718 6.67296 1.18615 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8615.69 MB
Peak resident set size usage (VmHWM): 6647.3 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_0761_df-s04-d0_dw_0_20251104T054159_reco_stage1_20251104T144809_keepup.root
\tHists: np02vd_raw_run040348_0761_df-s04-d0_dw_0_20251104T054159_reco_stage1_20251104T144809_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_0761_df-s04-d0_dw_0_20251104T054159_reco_stage1_20251104T144809_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_0761_df-s04-d0_dw_0_20251104T054159_reco_stage1_20251104T144809_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": 1762267690.0,
"core.end_time": 1762267690.0,
"core.events": [
70292,
70296,
70300,
70304,
70308,
70312,
70316,
70320,
70324,
70328,
70332,
70336,
70340,
70344,
70348,
70352,
70356,
70360,
70364,
70368,
70372,
70376,
70380,
70384
],
"core.event_count": 24,
"core.first_event_number": 70292,
"core.last_event_number": 70384,
"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_0761_df-s04-d0_dw_0_20251104T054159.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_0761_df-s04-d0_dw_0_20251104T054159_reco_stage1_20251104T144809_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": 1762267690.0,
"core.end_time": 1762267690.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_0761_df-s04-d0_dw_0_20251104T054159.hdf5"
}
]
} 