Jobsub ID 242278.99@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
9.828] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[13:09:49.828] D [ glue ] frame sink sees EOS
[13:09:49.828] D [ pgraph ] <Pgrapher:> graph execution complete
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.87 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.19 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.11 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.77 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.65 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.38 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.36 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.02 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:09:49.828] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:09:49.828] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:09:49.828] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:09:49.828] I [ timer ] Timer: Total node execution : 63.36999941058457 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46039 traces tagged "gauss"
FrameSaver: q=1.15586e+07 n=991689 tag=gauss
wclsFrameSaver: saving 56290 traces tagged "wiener"
FrameSaver: q=1.25174e+07 n=961719 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 365 XUs and 378 XVs -> 12 XUVs
C:0 T:1 411 XUs and 519 XVs -> 18 XUVs
C:0 T:2 223 XUs and 260 XVs -> 6 XUVs
C:0 T:3 67 XUs and 78 XVs -> 0 XUVs
C:0 T:4 822 XUs and 1141 XVs -> 43 XUVs
C:0 T:5 22973 XUs and 20753 XVs -> 1815 XUVs
C:0 T:6 1776 XUs and 1556 XVs -> 55 XUVs
C:0 T:7 1053 XUs and 1218 XVs -> 56 XUVs
C:0 T:8 274 XUs and 344 XVs -> 15 XUVs
C:0 T:9 1228 XUs and 998 XVs -> 48 XUVs
C:0 T:10 398 XUs and 457 XVs -> 42 XUVs
C:0 T:11 594 XUs and 761 XVs -> 41 XUVs
C:0 T:12 2232 XUs and 2704 XVs -> 154 XUVs
C:0 T:13 522 XUs and 594 XVs -> 39 XUVs
C:0 T:14 690 XUs and 636 XVs -> 51 XUVs
C:0 T:15 1344 XUs and 1427 XVs -> 93 XUVs
2488 XUVs total
1279 collection wire objects
2488 potential space points
Neighbour search...
96248 tests to find 22070 neighbours
Iterating with no regularization...
Begin: 6.77428e+09
0 6.26472e+09
1 6.24272e+09
2 6.24204e+09
Now with regularization...
Begin: 6.12485e+09
0 6.12467e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 13:10:32 CET Closed output file "np02vd_raw_run040346_3283_df-s04-d1_dw_0_20251103T100314_reco_stage1_20251103T121032_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 143.196 220.457 581.755 196.699 92.812 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.3571e-05 6.87902e-05 0.000184167 6.0895e-05 2.62915e-05 23
produce:tpcrawdecoder:PDVDTPCReader 45.6564 79.7551 213.009 75.7265 33.8184 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.511972 0.676379 1.39349 0.613495 0.205553 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000365167 0.000397591 0.00072353 0.000379754 7.05984e-05 23
produce:ophit:OpHitFinder 5.869e-05 8.9027e-05 0.000546688 6.7246e-05 9.78555e-05 23
produce:opflash:OpFlashFinderVerticalDrift 4.7018e-05 6.44498e-05 0.000326203 5.1777e-05 5.59492e-05 23
produce:wclsdatavd:WireCellToolkit 57.1455 74.0964 113.325 63.9257 16.8604 23
produce:gaushit:GausHitFinder 0.893881 1.30045 2.39958 1.22181 0.316794 23
produce:nhitsfilter:NumberOfHitsFilter 0.000225354 0.000352271 0.00061164 0.000338827 8.76173e-05 23
produce:reco3d:SpacePointSolver 7.65386 13.4242 28.7915 12.474 4.59544 23
produce:hitpdune:DisambigFromSpacePoints 0.0991184 0.216711 0.634071 0.169427 0.118438 23
produce:pandora:StandardPandora 14.1479 44.4268 324.923 26.1901 62.5908 23
produce:pandoraTrack:LArPandoraTrackCreation 0.578117 1.41724 5.79085 1.12031 1.12156 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0236074 0.0373739 0.0477817 0.0373748 0.00731528 23
[art]:TriggerResults:TriggerResultInserter 1.7232e-05 3.12318e-05 0.000140575 2.1431e-05 2.59195e-05 23
end_path:out1:RootOutput 4.008e-06 9.9553e-06 4.6116e-05 7.855e-06 8.20459e-06 23
end_path:out1:RootOutput(write) 4.51026 5.05919 6.08986 4.97724 0.396676 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7060.57 MB
Peak resident set size usage (VmHWM): 5039.07 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3283_df-s04-d1_dw_0_20251103T100314_reco_stage1_20251103T121032_keepup.root
\tHists: np02vd_raw_run040346_3283_df-s04-d1_dw_0_20251103T100314_reco_stage1_20251103T121032_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3283_df-s04-d1_dw_0_20251103T100314_reco_stage1_20251103T121032_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3283_df-s04-d1_dw_0_20251103T100314_reco_stage1_20251103T121032_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": 1762171833.0,
"core.end_time": 1762171833.0,
"core.events": [
303169,
303173,
303177,
303181,
303185,
303189,
303193,
303197,
303201,
303205,
303209,
303213,
303217,
303221,
303225,
303229,
303233,
303237,
303241,
303245,
303249,
303253,
303257
],
"core.event_count": 23,
"core.first_event_number": 303169,
"core.last_event_number": 303257,
"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_3283_df-s04-d1_dw_0_20251103T100314.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3283_df-s04-d1_dw_0_20251103T100314_reco_stage1_20251103T121032_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": 1762171833.0,
"core.end_time": 1762171833.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_3283_df-s04-d1_dw_0_20251103T100314.hdf5"
}
]
} 