Jobsub ID 41739.98@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
call=97 with 8
[11:35:04.785] D [ glue ] frame sink sees EOS
[11:35:04.785] D [ pgraph ] <Pgrapher:> graph execution complete
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.74 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.5 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.82 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.56 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:35:04.785] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:35:04.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:35:04.785] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:35:04.785] I [ timer ] Timer: Total node execution : 32.27999987080693 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25187 traces tagged "gauss"
FrameSaver: q=4.64981e+06 n=605238 tag=gauss
wclsFrameSaver: saving 32656 traces tagged "wiener"
FrameSaver: q=5.03082e+06 n=577654 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 805 XUs and 878 XVs -> 22 XUVs
C:0 T:1 2063 XUs and 1828 XVs -> 85 XUVs
C:0 T:2 351 XUs and 525 XVs -> 20 XUVs
C:0 T:3 1372 XUs and 1116 XVs -> 50 XUVs
C:0 T:4 58285 XUs and 44034 XVs -> 3650 XUVs
C:0 T:5 1269 XUs and 338 XVs -> 16 XUVs
C:0 T:6 2382 XUs and 1949 XVs -> 129 XUVs
C:0 T:7 184 XUs and 137 XVs -> 2 XUVs
3974 XUVs total
1499 collection wire objects
3974 potential space points
Neighbour search...
220790 tests to find 46708 neighbours
Iterating with no regularization...
Begin: 3.00734e+09
0 2.48648e+09
1 2.44524e+09
2 2.44269e+09
3 2.44232e+09
Now with regularization...
Begin: 2.39179e+09
0 2.39138e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 11:35:41 CEST Closed output file "np02vd_raw_run039433_0389_df-s05-d3_dw_0_20250918T072017_reco_stage1_20250918T093541_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 64.1981 102.777 598.429 85.2497 78.7377 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.2462e-05 8.41185e-05 0.000282433 7.4822e-05 3.22233e-05 49
produce:tpcrawdecoder:PDVDTPCReader 18.9271 30.577 49.2795 29.5405 6.72595 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.303837 0.367617 0.617438 0.345514 0.0646474 49
produce:pdvddaphne:DAPHNEReaderPDVD 5.51032 8.7161 18.8287 8.31083 2.14251 49
produce:ophit:OpHitFinder 0.0284048 0.0407009 0.0592728 0.040041 0.00701209 49
produce:opflash:OpFlashFinderVerticalDrift 0.0028669 0.00738338 0.01862 0.00734346 0.00232054 49
produce:wclsdatavd:WireCellToolkit 20.8586 30.8536 62.6357 28.1143 8.5925 49
produce:gaushit:GausHitFinder 0.217915 0.464387 1.13099 0.437243 0.154272 49
produce:nhitsfilter:NumberOfHitsFilter 7.5002e-05 0.000193512 0.00068169 0.000184205 9.95459e-05 49
produce:reco3d:SpacePointSolver 1.33054 4.3883 16.3093 3.95436 2.34209 49
produce:hitpdune:DisambigFromSpacePoints 0.0184332 0.0623638 0.551399 0.0519661 0.0753045 49
produce:pandora:StandardPandora 3.17473 24.9981 485.958 9.66859 72.4454 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0815622 0.284415 1.77299 0.213022 0.265506 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00583431 0.0117709 0.0238792 0.0113039 0.00422077 49
[art]:TriggerResults:TriggerResultInserter 1.5343e-05 2.99204e-05 9.7867e-05 2.3506e-05 1.63524e-05 49
end_path:out1:RootOutput 4.427e-06 6.38924e-06 2.0811e-05 5.528e-06 3.06901e-06 49
end_path:out1:RootOutput(write) 1.63428 1.96747 2.6509 1.84913 0.265051 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5311.07 MB
Peak resident set size usage (VmHWM): 3423.88 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0389_df-s05-d3_dw_0_20250918T072017_reco_stage1_20250918T093541_keepup.root
\tHists: np02vd_raw_run039433_0389_df-s05-d3_dw_0_20250918T072017_reco_stage1_20250918T093542_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0389_df-s05-d3_dw_0_20250918T072017_reco_stage1_20250918T093541_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0389_df-s05-d3_dw_0_20250918T072017_reco_stage1_20250918T093541_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": 1758188143.0,
"core.end_time": 1758188143.0,
"core.events": [
111705,
111711,
111717,
111723,
111729,
111735,
111741,
111747,
111753,
111759,
111765,
111771,
111777,
111783,
111789,
111795,
111801,
111807,
111813,
111819,
111825,
111831,
111837,
111843,
111849,
111855,
111861,
111867,
111873,
111879,
111885,
111891,
111897,
111903,
111909,
111915,
111921,
111927,
111933,
111939,
111945,
111951,
111957,
111963,
111969,
111975,
111981,
111987,
111993
],
"core.event_count": 49,
"core.first_event_number": 111705,
"core.last_event_number": 111993,
"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_0389_df-s05-d3_dw_0_20250918T072017.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0389_df-s05-d3_dw_0_20250918T072017_reco_stage1_20250918T093542_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": 1758188143.0,
"core.end_time": 1758188143.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_0389_df-s05-d3_dw_0_20250918T072017.hdf5"
}
]
} 