Jobsub ID 41395.124@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
OmnibusSigProc:anode0sigproc0> EOS at call=95 anode=0
[05:03:09.817] D [ glue ] <FrameFanin:nfsp> EOS at call=95 with 8
[05:03:09.817] D [ glue ] frame sink sees EOS
[05:03:09.817] D [ pgraph ] <Pgrapher:> graph execution complete
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.68 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.19 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.1 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.86 sec
[05:03:09.817] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[05:03:09.817] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[05:03:09.817] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[05:03:09.817] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[05:03:09.817] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:03:09.817] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.818] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.818] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[05:03:09.818] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:03:09.818] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[05:03:09.818] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[05:03:09.818] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[05:03:09.818] I [ timer ] Timer: Total node execution : 26.369999894872308 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 22353 traces tagged "gauss"
FrameSaver: q=3.73195e+06 n=496163 tag=gauss
wclsFrameSaver: saving 27491 traces tagged "wiener"
FrameSaver: q=3.9381e+06 n=476837 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 6940 XUs and 12300 XVs -> 523 XUVs
C:0 T:1 758 XUs and 1227 XVs -> 31 XUVs
C:0 T:2 2139 XUs and 2151 XVs -> 80 XUVs
C:0 T:3 323 XUs and 518 XVs -> 12 XUVs
C:0 T:4 168 XUs and 306 XVs -> 5 XUVs
C:0 T:5 534 XUs and 777 XVs -> 21 XUVs
C:0 T:6 628 XUs and 855 XVs -> 29 XUVs
C:0 T:7 1946 XUs and 2323 XVs -> 59 XUVs
760 XUVs total
568 collection wire objects
760 potential space points
Neighbour search...
10994 tests to find 2600 neighbours
Iterating with no regularization...
Begin: 3.19475e+09
0 3.15408e+09
1 3.1536e+09
Now with regularization...
Begin: 3.11703e+09
0 3.11701e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 05:03:32 CEST Closed output file "np02vd_raw_run039433_0216_df-s05-d3_dw_0_20250918T002001_reco_stage1_20250918T030332_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 44.966 171.601 4066.22 60.1357 580.003 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.343e-05 0.000112176 0.00035098 9.82355e-05 4.5825e-05 48
produce:tpcrawdecoder:PDVDTPCReader 5.63624 6.43357 8.83123 6.12333 0.765125 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.266292 0.275306 0.327551 0.269922 0.013864 48
produce:pdvddaphne:DAPHNEReaderPDVD 2.98512 3.30174 3.63921 3.32973 0.19176 48
produce:ophit:OpHitFinder 0.0358472 0.0528794 0.0788956 0.0517471 0.00811303 48
produce:opflash:OpFlashFinderVerticalDrift 0.00138054 0.0113849 0.026305 0.010938 0.00412333 48
produce:wclsdatavd:WireCellToolkit 24.7486 33.6017 59.729 27.7327 9.84135 48
produce:gaushit:GausHitFinder 0.369194 0.682329 2.3958 0.587038 0.306542 48
produce:nhitsfilter:NumberOfHitsFilter 0.00010493 0.000174924 0.00042768 0.000154895 6.03413e-05 48
produce:reco3d:SpacePointSolver 2.07393 6.42659 58.3262 4.23351 8.14767 48
produce:hitpdune:DisambigFromSpacePoints 0.0200988 0.105757 1.28319 0.054901 0.186018 48
produce:pandora:StandardPandora 3.97049 117.536 3939.14 11.6301 568.078 48
produce:pandoraTrack:LArPandoraTrackCreation 0.0807164 0.415299 6.87239 0.217114 0.960718 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00793548 0.0178407 0.0643668 0.0155751 0.00903574 48
[art]:TriggerResults:TriggerResultInserter 2.165e-05 3.46796e-05 0.00010204 2.9245e-05 1.42091e-05 48
end_path:out1:RootOutput 3.73e-06 9.96231e-06 3.6141e-05 8.315e-06 5.60487e-06 48
end_path:out1:RootOutput(write) 2.29717 2.72109 3.70903 2.59134 0.381584 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5246.81 MB
Peak resident set size usage (VmHWM): 3338.44 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0216_df-s05-d3_dw_0_20250918T002001_reco_stage1_20250918T030332_keepup.root
\tHists: np02vd_raw_run039433_0216_df-s05-d3_dw_0_20250918T002001_reco_stage1_20250918T030333_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0216_df-s05-d3_dw_0_20250918T002001_reco_stage1_20250918T030332_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0216_df-s05-d3_dw_0_20250918T002001_reco_stage1_20250918T030332_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": 1758164613.0,
"core.end_time": 1758164613.0,
"core.events": [
62043,
62049,
62055,
62061,
62067,
62073,
62079,
62085,
62091,
62097,
62103,
62109,
62115,
62121,
62127,
62133,
62139,
62145,
62151,
62157,
62163,
62169,
62175,
62181,
62187,
62193,
62199,
62205,
62211,
62217,
62223,
62229,
62235,
62241,
62247,
62253,
62259,
62265,
62271,
62277,
62283,
62289,
62295,
62301,
62307,
62313,
62319,
62325
],
"core.event_count": 48,
"core.first_event_number": 62043,
"core.last_event_number": 62325,
"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_0216_df-s05-d3_dw_0_20250918T002001.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0216_df-s05-d3_dw_0_20250918T002001_reco_stage1_20250918T030333_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": 1758164613.0,
"core.end_time": 1758164613.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_0216_df-s05-d3_dw_0_20250918T002001.hdf5"
}
]
} 