Jobsub ID 47676.69@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] <FrameFanin:nfsp> EOS at call=37 with 8
[20:50:49.309] D [ glue ] frame sink sees EOS
[20:50:49.309] D [ pgraph ] <Pgrapher:> graph execution complete
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.16 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.66 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.57 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.2 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.09 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.96 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.92 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.87 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Aux::Resampler : 0.73 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Aux::Resampler : 0.73 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Aux::Resampler : 0.73 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Aux::Resampler : 0.72 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:50:49.309] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:50:49.309] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:50:49.309] I [ timer ] Timer: Total node execution : 53.37999991327524 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 54880 traces tagged "gauss"
FrameSaver: q=8.56013e+06 n=1085309 tag=gauss
wclsFrameSaver: saving 66276 traces tagged "wiener"
FrameSaver: q=8.94367e+06 n=1040524 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 817 XUs and 793 XVs -> 28 XUVs
C:0 T:1 751 XUs and 802 XVs -> 52 XUVs
C:0 T:2 877 XUs and 809 XVs -> 34 XUVs
C:0 T:3 230 XUs and 240 XVs -> 3 XUVs
C:0 T:4 1315 XUs and 1143 XVs -> 42 XUVs
C:0 T:5 1330 XUs and 976 XVs -> 56 XUVs
C:0 T:6 959 XUs and 1127 XVs -> 32 XUVs
C:0 T:7 1216 XUs and 1694 XVs -> 50 XUVs
C:0 T:8 2902 XUs and 1989 XVs -> 84 XUVs
C:0 T:9 4305 XUs and 4347 XVs -> 212 XUVs
C:0 T:10 2306 XUs and 1734 XVs -> 91 XUVs
C:0 T:11 2919 XUs and 1578 XVs -> 91 XUVs
C:0 T:12 1480 XUs and 1663 XVs -> 106 XUVs
C:0 T:13 5960 XUs and 5465 XVs -> 483 XUVs
C:0 T:14 3175 XUs and 3735 XVs -> 341 XUVs
C:0 T:15 1259 XUs and 2091 XVs -> 149 XUVs
1854 XUVs total
1419 collection wire objects
1854 potential space points
Neighbour search...
27472 tests to find 13324 neighbours
Iterating with no regularization...
Begin: 3.96147e+08
0 3.63676e+08
1 3.62315e+08
2 3.6213e+08
Now with regularization...
Begin: 3.53821e+08
0 3.53744e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Sep-2025 20:51:49 CEST Closed output file "np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709_reco_stage1_20250917T185149_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 173.97 270.009 358.358 282.895 54.3648 19
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 3.8893e-05 5.70063e-05 0.000107362 5.338e-05 1.55805e-05 19
produce:tpcrawdecoder:PDVDTPCReader 45.532 150.023 212.03 168.711 56.1271 19
produce:triggerrawdecoder:PDVDTriggerReader4 0.290596 0.397956 0.671131 0.383131 0.0858696 19
produce:pdvddaphne:DAPHNEReaderPDVD 0.000188703 0.000292188 0.000617488 0.000238026 0.000123162 19
produce:ophit:OpHitFinder 8.1463e-05 0.00142928 0.0247978 9.2914e-05 0.00550957 19
produce:opflash:OpFlashFinderVerticalDrift 3.0167e-05 5.62903e-05 0.00028232 3.2511e-05 6.96029e-05 19
produce:wclsdatavd:WireCellToolkit 51.6107 57.7024 67.2952 57.4039 4.01153 19
produce:gaushit:GausHitFinder 0.585783 1.01354 1.31191 1.02437 0.175539 19
produce:nhitsfilter:NumberOfHitsFilter 0.000158528 0.000366479 0.00112908 0.000315171 0.000206412 19
produce:reco3d:SpacePointSolver 6.86564 13.4811 19.0802 13.5862 3.03515 19
produce:hitpdune:DisambigFromSpacePoints 0.0714472 0.184867 0.252296 0.193355 0.0464093 19
produce:pandora:StandardPandora 18.7048 42.1204 66.409 38.1811 13.5967 19
produce:pandoraTrack:LArPandoraTrackCreation 0.407028 0.821464 1.1497 0.878879 0.196549 19
produce:pandoraGnocalo:GnocchiCalorimetry 0.0188509 0.0279324 0.0348472 0.0291899 0.00472303 19
[art]:TriggerResults:TriggerResultInserter 1.0399e-05 1.48421e-05 4.7669e-05 1.3205e-05 7.81895e-06 19
end_path:out1:RootOutput 3.106e-06 4.54853e-06 2.2322e-05 3.497e-06 4.19561e-06 19
end_path:out1:RootOutput(write) 3.84581 4.20277 4.54118 4.20161 0.174663 19
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4647.04 MB
Peak resident set size usage (VmHWM): 2765.73 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709_reco_stage1_20250917T185149_keepup.root
\tHists: np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709_reco_stage1_20250917T185149_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709_reco_stage1_20250917T185149_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709_reco_stage1_20250917T185149_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": 1758135110.0,
"core.end_time": 1758135110.0,
"core.events": [
13815,
13819,
13823,
13827,
13831,
13835,
13839,
13843,
13847,
13851,
13855,
13859,
13863,
13867,
13871,
13875,
13879,
13883,
13887
],
"core.event_count": 19,
"core.first_event_number": 13815,
"core.last_event_number": 13887,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39388
],
"core.runs_subruns": [
3938800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709_reco_stage1_20250917T185149_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": 1758135110.0,
"core.end_time": 1758135110.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39388
],
"core.runs_subruns": [
3938800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039388_0179_df-s05-d3_dw_0_20250916T165709.hdf5"
}
]
} 