Jobsub ID 42178.25@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
apher:> graph execution complete
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.73 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.58 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.79 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.53 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.36 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.11 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.03 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.97 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:56:40.768] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:56:40.768] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:56:40.768] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:56:40.768] I [ timer ] Timer: Total node execution : 57.549999909475446 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 43872 traces tagged "gauss"
FrameSaver: q=2.0457e+07 n=1191275 tag=gauss
wclsFrameSaver: saving 55186 traces tagged "wiener"
FrameSaver: q=2.12718e+07 n=1152323 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 749 XUs and 536 XVs -> 20 XUVs
C:0 T:1 128 XUs and 152 XVs -> 4 XUVs
C:0 T:2 1872 XUs and 1556 XVs -> 51 XUVs
C:0 T:3 617 XUs and 591 XVs -> 36 XUVs
C:0 T:4 290 XUs and 320 XVs -> 32 XUVs
C:0 T:5 437 XUs and 505 XVs -> 9 XUVs
C:0 T:6 452 XUs and 634 XVs -> 11 XUVs
C:0 T:7 386 XUs and 425 XVs -> 10 XUVs
C:0 T:8 31357 XUs and 67305 XVs -> 6783 XUVs
C:0 T:9 5731 XUs and 11478 XVs -> 1495 XUVs
C:0 T:10 2397 XUs and 4762 XVs -> 781 XUVs
C:0 T:11 2880 XUs and 3371 XVs -> 188 XUVs
C:0 T:12 5906 XUs and 17300 XVs -> 992 XUVs
C:0 T:13 3052 XUs and 2729 XVs -> 162 XUVs
C:0 T:14 936 XUs and 951 XVs -> 88 XUVs
C:0 T:15 1458 XUs and 1342 XVs -> 96 XUVs
10758 XUVs total
2470 collection wire objects
10758 potential space points
Neighbour search...
1202220 tests to find 469942 neighbours
Iterating with no regularization...
Begin: 2.44971e+10
0 2.3748e+10
1 2.36886e+10
2 2.36842e+10
Now with regularization...
Begin: 2.36297e+10
0 2.36287e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
07-Sep-2025 11:59:03 CEST Closed output file "np02vd_raw_run039324_0980_df-s03-d1_dw_0_20250906T115202_reco_stage1_20250907T095903_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 86.341 176.974 683.94 126.053 129.765 26
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.3255e-05 0.000117009 0.000277327 0.000102254 3.92318e-05 26
produce:tpcrawdecoder:PDVDTPCReader 4.44353 5.79412 9.14228 5.42699 1.19219 26
produce:triggerrawdecoder:PDVDTriggerReader4 0.00602207 0.00809931 0.026851 0.006384 0.0048963 26
produce:pdvddaphne:DAPHNEReaderPDVD 2.31764 2.78977 3.46776 2.75483 0.275295 26
produce:ophit:OpHitFinder 0.0415977 0.0484369 0.0597396 0.0487861 0.00417856 26
produce:opflash:OpFlashFinderVerticalDrift 0.0059737 0.0135057 0.0207578 0.0126781 0.00371198 26
produce:wclsdatavd:WireCellToolkit 38.3505 54.5263 93.8331 49.5533 12.5864 26
produce:gaushit:GausHitFinder 0.643958 1.38457 2.39146 1.36179 0.478848 26
produce:nhitsfilter:NumberOfHitsFilter 0.000180502 0.000419437 0.00108562 0.000354327 0.000219573 26
produce:reco3d:SpacePointSolver 6.45872 13.27 23.1647 11.7126 4.94124 26
produce:hitpdune:DisambigFromSpacePoints 0.0922869 0.229813 0.438187 0.197322 0.102367 26
produce:pandora:StandardPandora 18.3347 92.8868 586.007 47.5555 119.927 26
produce:pandoraTrack:LArPandoraTrackCreation 0.26279 1.01862 2.93075 0.763367 0.681006 26
produce:pandoraGnocalo:GnocchiCalorimetry 0.0160075 0.0315402 0.0563224 0.0305969 0.0107957 26
[art]:TriggerResults:TriggerResultInserter 2.4206e-05 5.29102e-05 0.00019272 4.8177e-05 3.11886e-05 26
end_path:out1:RootOutput 4.797e-06 1.37803e-05 6.1092e-05 1.2078e-05 9.96864e-06 26
end_path:out1:RootOutput(write) 3.72833 4.88384 7.78543 4.60223 0.889291 26
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5306.05 MB
Peak resident set size usage (VmHWM): 3372.4 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039324_0980_df-s03-d1_dw_0_20250906T115202_reco_stage1_20250907T095903_keepup.root
\tHists: np02vd_raw_run039324_0980_df-s03-d1_dw_0_20250906T115202_reco_stage1_20250907T095904_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039324_0980_df-s03-d1_dw_0_20250906T115202_reco_stage1_20250907T095903_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039324_0980_df-s03-d1_dw_0_20250906T115202_reco_stage1_20250907T095903_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": 1757239145.0,
"core.end_time": 1757239145.0,
"core.events": [
513347,
513367,
513387,
513407,
513427,
513447,
513467,
513487,
513507,
513527,
513547,
513567,
513587,
513607,
513627,
513647,
513667,
513687,
513707,
513727,
513747,
513767,
513787,
513807,
513827,
513847
],
"core.event_count": 26,
"core.first_event_number": 513347,
"core.last_event_number": 513847,
"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_0980_df-s03-d1_dw_0_20250906T115202.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039324_0980_df-s03-d1_dw_0_20250906T115202_reco_stage1_20250907T095904_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": 1757239145.0,
"core.end_time": 1757239145.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_0980_df-s03-d1_dw_0_20250906T115202.hdf5"
}
]
} 