Jobsub ID 242471.177@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
n:nfsp> EOS at call=45 with 8
[21:04:11.032] D [ glue ] frame sink sees EOS
[21:04:11.032] D [ pgraph ] <Pgrapher:> graph execution complete
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 25.6 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.65 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.59 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.51 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.36 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.49 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.37 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.91 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:04:11.032] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:04:11.032] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:04:11.033] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:04:11.033] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:04:11.033] I [ timer ] Timer: Total node execution : 154.06000148691237 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 61419 traces tagged "gauss"
FrameSaver: q=1.98734e+07 n=1431175 tag=gauss
wclsFrameSaver: saving 75846 traces tagged "wiener"
FrameSaver: q=2.12928e+07 n=1385324 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 10550 XUs and 11164 XVs -> 779 XUVs
C:0 T:1 26196 XUs and 27740 XVs -> 2273 XUVs
C:0 T:2 541 XUs and 785 XVs -> 43 XUVs
C:0 T:3 908 XUs and 1165 XVs -> 58 XUVs
C:0 T:4 2237 XUs and 2338 XVs -> 65 XUVs
C:0 T:5 2377 XUs and 2946 XVs -> 134 XUVs
C:0 T:6 437 XUs and 553 XVs -> 22 XUVs
C:0 T:7 1011 XUs and 716 XVs -> 39 XUVs
C:0 T:8 1500 XUs and 1466 XVs -> 66 XUVs
C:0 T:9 761 XUs and 638 XVs -> 19 XUVs
C:0 T:10 2921 XUs and 2956 XVs -> 116 XUVs
C:0 T:11 1366 XUs and 1681 XVs -> 57 XUVs
C:0 T:12 332 XUs and 626 XVs -> 33 XUVs
C:0 T:13 1125 XUs and 1009 XVs -> 69 XUVs
C:0 T:14 269 XUs and 319 XVs -> 20 XUVs
C:0 T:15 367 XUs and 396 XVs -> 12 XUVs
3805 XUVs total
1980 collection wire objects
3805 potential space points
Neighbour search...
141387 tests to find 31566 neighbours
Iterating with no regularization...
Begin: 8.52488e+10
0 8.40637e+10
1 8.39542e+10
2 8.39468e+10
Now with regularization...
Begin: 8.2205e+10
0 8.22031e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 21:07:33 GMT Closed output file "np02vd_raw_run040346_3755_df-s04-d2_dw_0_20251103T170229_reco_stage1_20251103T210733_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 230.808 498.66 2569.34 309.768 577.805 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000103987 0.000129842 0.000353568 0.000112412 5.63307e-05 23
produce:tpcrawdecoder:PDVDTPCReader 69.3515 123.06 191.424 123.508 28.0372 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.733507 0.960403 1.83098 0.926923 0.22828 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000459107 0.000622538 0.00135727 0.000575578 0.000181511 23
produce:ophit:OpHitFinder 9.1653e-05 0.00016206 0.000993347 0.000111491 0.000182507 23
produce:opflash:OpFlashFinderVerticalDrift 6.6376e-05 0.00010756 0.000622857 8.2035e-05 0.000111033 23
produce:wclsdatavd:WireCellToolkit 95.9139 118.158 178.928 106.598 23.9524 23
produce:gaushit:GausHitFinder 1.13903 2.07937 3.18165 2.04995 0.564908 23
produce:nhitsfilter:NumberOfHitsFilter 0.00047602 0.000795729 0.00173929 0.000722655 0.000291072 23
produce:reco3d:SpacePointSolver 7.97161 20.4468 49.3463 18.1772 8.88316 23
produce:hitpdune:DisambigFromSpacePoints 0.175985 0.400677 1.71385 0.264995 0.32768 23
produce:pandora:StandardPandora 19.2076 222.534 2203.31 45.5969 550.476 23
produce:pandoraTrack:LArPandoraTrackCreation 1.22664 3.52118 9.78929 2.75254 2.42238 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0409575 0.0680597 0.108136 0.0673726 0.0164656 23
[art]:TriggerResults:TriggerResultInserter 5.8e-05 8.71377e-05 0.000196381 8.3608e-05 2.54597e-05 23
end_path:out1:RootOutput 6.873e-06 1.65351e-05 5.8911e-05 1.4157e-05 1.13498e-05 23
end_path:out1:RootOutput(write) 6.59132 7.29193 9.08864 7.04825 0.69473 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5728.04 MB
Peak resident set size usage (VmHWM): 3667.38 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3755_df-s04-d2_dw_0_20251103T170229_reco_stage1_20251103T210733_keepup.root
\tHists: np02vd_raw_run040346_3755_df-s04-d2_dw_0_20251103T170229_reco_stage1_20251103T210733_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3755_df-s04-d2_dw_0_20251103T170229_reco_stage1_20251103T210733_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3755_df-s04-d2_dw_0_20251103T170229_reco_stage1_20251103T210733_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": 1762204054.0,
"core.end_time": 1762204054.0,
"core.events": [
346718,
346722,
346726,
346730,
346734,
346738,
346742,
346746,
346750,
346754,
346758,
346762,
346766,
346770,
346774,
346778,
346782,
346786,
346790,
346794,
346798,
346802,
346806
],
"core.event_count": 23,
"core.first_event_number": 346718,
"core.last_event_number": 346806,
"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_3755_df-s04-d2_dw_0_20251103T170229.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3755_df-s04-d2_dw_0_20251103T170229_reco_stage1_20251103T210733_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": 1762204054.0,
"core.end_time": 1762204054.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_3755_df-s04-d2_dw_0_20251103T170229.hdf5"
}
]
} 