Jobsub ID 236799.17@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
:57.988] D [ glue ] frame sink sees EOS
[21:00:57.988] D [ pgraph ] <Pgrapher:> graph execution complete
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.14 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.89 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.71 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.15 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.75 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.82 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.74 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.68 sec
[21:00:57.988] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[21:00:57.988] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[21:00:57.988] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[21:00:57.988] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[21:00:57.988] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:00:57.988] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:00:57.988] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[21:00:57.989] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:00:57.989] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:00:57.989] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:00:57.989] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:00:57.989] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:00:57.989] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:00:57.989] I [ timer ] Timer: Total node execution : 74.21000014059246 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 49777 traces tagged "gauss"
FrameSaver: q=1.31026e+07 n=1125444 tag=gauss
wclsFrameSaver: saving 65004 traces tagged "wiener"
FrameSaver: q=1.43811e+07 n=1081273 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 791 XUs and 982 XVs -> 53 XUVs
C:0 T:1 13281 XUs and 12725 XVs -> 1018 XUVs
C:0 T:2 102 XUs and 109 XVs -> 3 XUVs
C:0 T:3 1541 XUs and 2205 XVs -> 102 XUVs
C:0 T:4 672 XUs and 588 XVs -> 27 XUVs
C:0 T:5 1066 XUs and 697 XVs -> 25 XUVs
C:0 T:6 1065 XUs and 1286 XVs -> 95 XUVs
C:0 T:7 442 XUs and 488 XVs -> 38 XUVs
C:0 T:8 2436 XUs and 3155 XVs -> 551 XUVs
C:0 T:9 9521 XUs and 11913 XVs -> 1693 XUVs
C:0 T:10 3540 XUs and 7236 XVs -> 1183 XUVs
C:0 T:11 3692 XUs and 6135 XVs -> 1654 XUVs
C:0 T:12 3846 XUs and 5687 XVs -> 1107 XUVs
C:0 T:13 12187 XUs and 12341 XVs -> 2200 XUVs
C:0 T:14 18065 XUs and 37217 XVs -> 7392 XUVs
C:0 T:15 5322 XUs and 10330 XVs -> 1672 XUVs
18813 XUVs total
2505 collection wire objects
18813 potential space points
Neighbour search...
2726281 tests to find 1120548 neighbours
Iterating with no regularization...
Begin: 7.72328e+09
0 7.4204e+09
1 7.40688e+09
2 7.40613e+09
Now with regularization...
Begin: 7.23917e+09
0 7.23863e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 21:02:14 GMT Closed output file "np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720_reco_stage1_20251103T210214_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 111.5 457.163 5276.08 203.133 1031.02 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.1022e-05 0.000121091 0.000331985 0.000101772 5.12302e-05 24
produce:tpcrawdecoder:PDVDTPCReader 16.0673 55.0387 98.2572 67.3709 29.0974 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.660175 0.79059 1.38735 0.705039 0.187443 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000420121 0.000520358 0.00107002 0.000502626 0.000125395 24
produce:ophit:OpHitFinder 0.000113614 0.000202289 0.000900365 0.000170671 0.000151661 24
produce:opflash:OpFlashFinderVerticalDrift 7.6835e-05 0.000134985 0.000567057 0.000116354 9.41091e-05 24
produce:wclsdatavd:WireCellToolkit 59.222 79.7557 167.079 71.9734 24.6612 24
produce:gaushit:GausHitFinder 1.0976 1.77999 2.57532 1.77054 0.360986 24
produce:nhitsfilter:NumberOfHitsFilter 0.000266702 0.000444762 0.000747928 0.000431192 0.000108904 24
produce:reco3d:SpacePointSolver 7.37091 16.3906 34.0392 15.2606 6.29581 24
produce:hitpdune:DisambigFromSpacePoints 0.117548 0.321357 1.12075 0.250207 0.22248 24
produce:pandora:StandardPandora 15.4648 294.766 4984.23 37.1526 1000.75 24
produce:pandoraTrack:LArPandoraTrackCreation 0.587517 2.28794 8.27149 1.79761 1.7194 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0224648 0.0539006 0.0989075 0.0546311 0.0160875 24
[art]:TriggerResults:TriggerResultInserter 3.9996e-05 7.55247e-05 0.000161213 6.926e-05 2.42016e-05 24
end_path:out1:RootOutput 7.965e-06 1.81823e-05 5.7378e-05 1.559e-05 1.03319e-05 24
end_path:out1:RootOutput(write) 4.45158 5.87035 7.90513 5.7425 0.729444 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6492.84 MB
Peak resident set size usage (VmHWM): 4422.92 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720_reco_stage1_20251103T210214_keepup.root
\tHists: np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720_reco_stage1_20251103T210214_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720_reco_stage1_20251103T210214_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720_reco_stage1_20251103T210214_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762203735.0,
"core.end_time": 1762203735.0,
"core.events": [
348282,
348286,
348290,
348294,
348298,
348302,
348306,
348310,
348314,
348318,
348322,
348326,
348330,
348334,
348338,
348342,
348346,
348350,
348354,
348358,
348362,
348366,
348370,
348374
],
"core.event_count": 24,
"core.first_event_number": 348282,
"core.last_event_number": 348374,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720_reco_stage1_20251103T210214_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_11_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": 1762203735.0,
"core.end_time": 1762203735.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3772_df-s04-d2_dw_0_20251103T171720.hdf5"
}
]
} 