Jobsub ID 47958.39@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
igproc0> EOS at call=95 anode=0
[04:48:03.331] D [ glue ] <FrameFanin:nfsp> EOS at call=95 with 8
[04:48:03.331] D [ glue ] frame sink sees EOS
[04:48:03.331] D [ pgraph ] <Pgrapher:> graph execution complete
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.09 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.87 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.74 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.5 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:48:03.331] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[04:48:03.331] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[04:48:03.331] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[04:48:03.331] I [ timer ] Timer: Total node execution : 24.689999798312783 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 18890 traces tagged "gauss"
FrameSaver: q=2.74398e+06 n=408392 tag=gauss
wclsFrameSaver: saving 24310 traces tagged "wiener"
FrameSaver: q=2.92597e+06 n=389155 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1442 XUs and 1665 XVs -> 79 XUVs
C:0 T:1 1594 XUs and 1377 XVs -> 86 XUVs
C:0 T:2 1321 XUs and 1039 XVs -> 46 XUVs
C:0 T:3 358 XUs and 412 XVs -> 18 XUVs
C:0 T:4 7603 XUs and 8642 XVs -> 785 XUVs
C:0 T:5 1152 XUs and 1179 XVs -> 95 XUVs
C:0 T:6 1218 XUs and 1558 XVs -> 61 XUVs
C:0 T:7 214 XUs and 298 XVs -> 27 XUVs
1197 XUVs total
634 collection wire objects
1197 potential space points
Neighbour search...
40013 tests to find 11276 neighbours
Iterating with no regularization...
Begin: 3.90068e+08
0 3.64983e+08
1 3.6402e+08
2 3.63973e+08
Now with regularization...
Begin: 3.49092e+08
0 3.49069e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 04:48:24 CEST Closed output file "np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118_reco_stage1_20250918T024824_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 37.3443 75.1998 293.672 63.0591 47.7797 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.038e-05 0.000116441 0.000234521 0.000102985 4.36765e-05 48
produce:tpcrawdecoder:PDVDTPCReader 5.62718 6.41806 9.10336 6.13971 0.815719 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.266822 0.273441 0.324685 0.270636 0.00930209 48
produce:pdvddaphne:DAPHNEReaderPDVD 2.77479 3.34142 3.73265 3.33593 0.206204 48
produce:ophit:OpHitFinder 0.0223366 0.0523935 0.0836555 0.0523074 0.0109379 48
produce:opflash:OpFlashFinderVerticalDrift 0.0021777 0.0102523 0.0193707 0.0101946 0.00359007 48
produce:wclsdatavd:WireCellToolkit 21.2149 32.3412 63.4151 26.83 9.96656 48
produce:gaushit:GausHitFinder 0.274558 0.640494 1.40226 0.634628 0.199458 48
produce:nhitsfilter:NumberOfHitsFilter 8.349e-05 0.000153777 0.0004749 0.000139355 5.68632e-05 48
produce:reco3d:SpacePointSolver 0.910539 5.07566 20.0772 4.25925 3.12146 48
produce:hitpdune:DisambigFromSpacePoints 0.014864 0.0849803 0.591291 0.0607224 0.0912015 48
produce:pandora:StandardPandora 2.03743 24.0245 222.092 12.8463 38.2405 48
produce:pandoraTrack:LArPandoraTrackCreation 0.0437841 0.288604 1.47197 0.221358 0.234204 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00489082 0.0150826 0.0338027 0.0143328 0.00482504 48
[art]:TriggerResults:TriggerResultInserter 1.782e-05 2.6144e-05 5.947e-05 2.4265e-05 7.33425e-06 48
end_path:out1:RootOutput 4.16e-06 8.96438e-06 2.727e-05 8.5e-06 3.61043e-06 48
end_path:out1:RootOutput(write) 2.07216 2.61524 3.89685 2.53571 0.339793 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5281.73 MB
Peak resident set size usage (VmHWM): 3378.56 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118_reco_stage1_20250918T024824_keepup.root
\tHists: np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118_reco_stage1_20250918T024824_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118_reco_stage1_20250918T024824_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118_reco_stage1_20250918T024824_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": 1758163705.0,
"core.end_time": 1758163705.0,
"core.events": [
66899,
66905,
66911,
66917,
66923,
66929,
66935,
66941,
66947,
66953,
66959,
66965,
66971,
66977,
66983,
66989,
66995,
67001,
67007,
67013,
67019,
67025,
67031,
67037,
67043,
67049,
67055,
67061,
67067,
67073,
67079,
67085,
67091,
67097,
67103,
67109,
67115,
67121,
67127,
67133,
67139,
67145,
67151,
67157,
67163,
67169,
67175,
67181
],
"core.event_count": 48,
"core.first_event_number": 66899,
"core.last_event_number": 67181,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118_reco_stage1_20250918T024824_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": 1758163705.0,
"core.end_time": 1758163705.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0233_df-s05-d5_dw_0_20250918T010118.hdf5"
}
]
} 