Jobsub ID 47429.80@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
oc::OmnibusSigProc : 8.13 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.74 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Aux::Resampler : 0.83 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Aux::Resampler : 0.8 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Aux::Resampler : 0.79 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Aux::Resampler : 0.77 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:25.325] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:11:25.325] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:11:25.325] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:11:25.325] I [ timer ] Timer: Total node execution : 74.47000144608319 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 72355 traces tagged "gauss"
FrameSaver: q=1.38816e+07 n=1621263 tag=gauss
wclsFrameSaver: saving 89755 traces tagged "wiener"
FrameSaver: q=1.47291e+07 n=1556193 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1631 XUs and 2337 XVs -> 62 XUVs
C:0 T:1 3027 XUs and 4301 XVs -> 196 XUVs
C:0 T:2 1649 XUs and 1178 XVs -> 57 XUVs
C:0 T:3 8217 XUs and 8801 XVs -> 401 XUVs
C:0 T:4 2427 XUs and 2743 XVs -> 152 XUVs
C:0 T:5 756 XUs and 1188 XVs -> 41 XUVs
C:0 T:6 2447 XUs and 3020 XVs -> 135 XUVs
C:0 T:7 337 XUs and 387 XVs -> 22 XUVs
C:0 T:8 9368 XUs and 18181 XVs -> 1820 XUVs
C:0 T:9 9378 XUs and 20804 XVs -> 1990 XUVs
C:0 T:10 18038 XUs and 37987 XVs -> 6456 XUVs
C:0 T:11 7511 XUs and 12353 XVs -> 751 XUVs
C:0 T:12 10270 XUs and 15484 XVs -> 2132 XUVs
C:0 T:13 843 XUs and 1050 XVs -> 54 XUVs
C:0 T:14 2948 XUs and 2868 XVs -> 217 XUVs
C:0 T:15 4141 XUs and 2717 XVs -> 205 XUVs
14691 XUVs total
2993 collection wire objects
14691 potential space points
Neighbour search...
2002885 tests to find 802536 neighbours
Iterating with no regularization...
Begin: 9.21945e+08
0 8.53431e+08
1 8.48496e+08
2 8.47524e+08
3 8.47196e+08
Now with regularization...
Begin: 8.30365e+08
0 8.30074e+08
this->SetupTestBeamPfo(pNuPfo, pTestBeamPfo, testBeamStartVertex) return STATUS_CODE_NOT_FOUND
in function: Run
in file: /scratch/workspace/build-larsoft/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/ALMA9/build/larpandoracontent/v04_16_00-buildFW/src/larpandoracontent/LArThreeDReco/LArEventBuilding/TestBeamParticleCreationAlgorithm.cc line#: 43
iter->second->Run() throw STATUS_CODE_NOT_FOUND
in function: RunAlgorithm
in file: /scratch/workspace/build-larbase/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/SLF7/build/pandora/v03_16_00l/src/pandora-v03-16-00/PandoraSDK-v03-04-01/src/Api/PandoraContentApiImpl.cc line#: 235
Failure in algorithm Alg0079, LArTestBeamParticleCreation, STATUS_CODE_NOT_FOUND
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Sep-2025 13:14:55 UTC Closed output file "np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041_reco_stage1_20250917T131455_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 367.11 470.245 611.303 456.865 70.8546 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.4805e-05 8.43453e-05 0.000195304 7.8245e-05 3.09701e-05 18
produce:tpcrawdecoder:PDVDTPCReader 181.547 252.474 370.537 225.804 56.0304 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.0366609 0.0456589 0.0728888 0.0373538 0.0118522 18
produce:pdvddaphne:DAPHNEReaderPDVD 12.5219 28.2932 42.0548 26.8159 7.97228 18
produce:ophit:OpHitFinder 0.0458291 0.0545906 0.061266 0.0558588 0.00438623 18
produce:opflash:OpFlashFinderVerticalDrift 0.0103952 0.0136145 0.0182315 0.0133998 0.00201915 18
produce:wclsdatavd:WireCellToolkit 63.2115 73.3693 95.464 70.7463 9.21455 18
produce:gaushit:GausHitFinder 1.08022 1.43622 2.01351 1.40465 0.22933 18
produce:nhitsfilter:NumberOfHitsFilter 0.000342827 0.000481539 0.0013497 0.000434647 0.000216787 18
produce:reco3d:SpacePointSolver 14.7618 20.953 28.6282 20.5401 4.22416 18
produce:hitpdune:DisambigFromSpacePoints 0.221509 0.32821 0.459433 0.319299 0.0716856 18
produce:pandora:StandardPandora 42.5599 86.0529 181.424 80.7728 37.7488 18
produce:pandoraTrack:LArPandoraTrackCreation 0.742226 1.59998 4.15791 1.54037 0.735998 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.0308775 0.0399837 0.050237 0.0397828 0.00628796 18
[art]:TriggerResults:TriggerResultInserter 1.4037e-05 1.91784e-05 4.2782e-05 1.6271e-05 7.0706e-06 18
end_path:out1:RootOutput 3.106e-06 4.49422e-06 1.6151e-05 3.722e-06 2.87081e-06 18
end_path:out1:RootOutput(write) 4.74928 5.53428 9.81069 5.34402 1.07683 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5167.87 MB
Peak resident set size usage (VmHWM): 3192.14 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041_reco_stage1_20250917T131455_keepup.root
\tHists: np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041_reco_stage1_20250917T131457_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041_reco_stage1_20250917T131455_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041_reco_stage1_20250917T131455_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": 1758114897.0,
"core.end_time": 1758114897.0,
"core.events": [
12970,
12990,
13010,
13030,
13050,
13070,
13090,
13110,
13130,
13150,
13170,
13190,
13210,
13230,
13250,
13270,
13290,
13310
],
"core.event_count": 18,
"core.first_event_number": 12970,
"core.last_event_number": 13310,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39276
],
"core.runs_subruns": [
3927600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041_reco_stage1_20250917T131457_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": 1758114897.0,
"core.end_time": 1758114897.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39276
],
"core.runs_subruns": [
3927600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039276_0036_df-s04-d0_dw_0_20250902T113041.hdf5"
}
]
} 