Jobsub ID 42218.109@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
in:nfsp> EOS at call=51 with 8
[13:13:06.061] D [ glue ] frame sink sees EOS
[13:13:06.061] D [ pgraph ] <Pgrapher:> graph execution complete
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.79 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.4 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.35 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.86 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.67 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.51 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.11 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[13:13:06.061] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:13:06.061] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:13:06.061] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:13:06.061] I [ timer ] Timer: Total node execution : 40.09000052884221 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 32796 traces tagged "gauss"
FrameSaver: q=5.38019e+06 n=677091 tag=gauss
wclsFrameSaver: saving 40031 traces tagged "wiener"
FrameSaver: q=5.69879e+06 n=647626 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 237 XUs and 428 XVs -> 6 XUVs
C:0 T:1 1107 XUs and 1133 XVs -> 43 XUVs
C:0 T:2 675 XUs and 562 XVs -> 37 XUVs
C:0 T:3 337 XUs and 328 XVs -> 13 XUVs
C:0 T:4 67 XUs and 78 XVs -> 6 XUVs
C:0 T:5 22 XUs and 23 XVs -> 0 XUVs
C:0 T:6 348 XUs and 359 XVs -> 14 XUVs
C:0 T:7 37 XUs and 35 XVs -> 1 XUVs
C:0 T:8 2154 XUs and 3035 XVs -> 413 XUVs
C:0 T:9 6706 XUs and 7168 XVs -> 903 XUVs
C:0 T:10 7873 XUs and 13383 XVs -> 2451 XUVs
C:0 T:11 1593 XUs and 1595 XVs -> 75 XUVs
C:0 T:12 6127 XUs and 8100 XVs -> 505 XUVs
C:0 T:13 223 XUs and 223 XVs -> 13 XUVs
C:0 T:14 718 XUs and 662 XVs -> 51 XUVs
C:0 T:15 628 XUs and 463 XVs -> 51 XUVs
4582 XUVs total
1079 collection wire objects
4582 potential space points
Neighbour search...
456892 tests to find 205394 neighbours
Iterating with no regularization...
Begin: 3.46742e+08
0 3.07041e+08
1 3.04454e+08
2 3.04165e+08
Now with regularization...
Begin: 2.97333e+08
0 2.9722e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
07-Sep-2025 13:13:47 CEST Closed output file "np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347_reco_stage1_20250907T111347_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 73.9947 125.385 231.088 116.592 39.2048 26
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.652e-05 0.000110499 0.000249215 0.000100912 3.92744e-05 26
produce:tpcrawdecoder:PDVDTPCReader 5.04249 6.14742 8.98959 5.82758 0.971351 26
produce:triggerrawdecoder:PDVDTriggerReader4 0.00572475 0.00778886 0.0157239 0.00608147 0.00343951 26
produce:pdvddaphne:DAPHNEReaderPDVD 2.25122 2.79042 3.17577 2.75953 0.244555 26
produce:ophit:OpHitFinder 0.0382491 0.0502903 0.0839244 0.048553 0.0091675 26
produce:opflash:OpFlashFinderVerticalDrift 0.00619646 0.0132578 0.0207628 0.0129558 0.00445543 26
produce:wclsdatavd:WireCellToolkit 37.5748 50.418 80.1721 47.8631 10.0737 26
produce:gaushit:GausHitFinder 0.640205 1.29566 1.95673 1.33419 0.32203 26
produce:nhitsfilter:NumberOfHitsFilter 0.000191048 0.000426685 0.00118157 0.000324222 0.000251603 26
produce:reco3d:SpacePointSolver 5.11738 11.2266 19.9799 10.6918 3.57472 26
produce:hitpdune:DisambigFromSpacePoints 0.0730897 0.206603 0.461973 0.191334 0.0945444 26
produce:pandora:StandardPandora 13.9435 47.5995 134.485 38.9648 28.8698 26
produce:pandoraTrack:LArPandoraTrackCreation 0.211719 0.86506 2.8073 0.680514 0.647841 26
produce:pandoraGnocalo:GnocchiCalorimetry 0.015028 0.0294905 0.0543372 0.0269694 0.00917619 26
[art]:TriggerResults:TriggerResultInserter 2.4306e-05 5.37778e-05 0.00012618 5.2524e-05 2.15842e-05 26
end_path:out1:RootOutput 8.062e-06 1.27108e-05 3.955e-05 1.16325e-05 5.71784e-06 26
end_path:out1:RootOutput(write) 3.98858 4.65877 7.03822 4.40124 0.754165 26
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4951.67 MB
Peak resident set size usage (VmHWM): 3041.98 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347_reco_stage1_20250907T111347_keepup.root
\tHists: np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347_reco_stage1_20250907T111347_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347_reco_stage1_20250907T111347_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347_reco_stage1_20250907T111347_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": 1757243627.0,
"core.end_time": 1757243627.0,
"core.events": [
927741,
927761,
927781,
927801,
927821,
927841,
927861,
927881,
927901,
927921,
927941,
927961,
927981,
928001,
928021,
928041,
928061,
928081,
928101,
928121,
928141,
928161,
928181,
928201,
928221,
928241
],
"core.event_count": 26,
"core.first_event_number": 927741,
"core.last_event_number": 928241,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39324
],
"core.runs_subruns": [
3932400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347_reco_stage1_20250907T111347_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": 1757243627.0,
"core.end_time": 1757243627.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39324
],
"core.runs_subruns": [
3932400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039324_1771_df-s01-d3_dw_0_20250907T052347.hdf5"
}
]
} 