Jobsub ID 42777.96@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
9
[04:54:37.765] D [ aux ] <Resampler:resmp0> EOS at call=79
[04:54:37.765] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[04:54:37.765] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[04:54:37.765] D [ glue ] frame sink sees EOS
[04:54:37.765] D [ pgraph ] <Pgrapher:> graph execution complete
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.05 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.19 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.02 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.52 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Aux::Resampler : 0.15 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Aux::Resampler : 0.15 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[04:54:37.765] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[04:54:37.765] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:54:37.765] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[04:54:37.766] I [ timer ] Timer: Total node execution : 21.46000023558736 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 24553 traces tagged "gauss"
FrameSaver: q=3.97201e+06 n=538749 tag=gauss
wclsFrameSaver: saving 31320 traces tagged "wiener"
FrameSaver: q=4.21284e+06 n=513230 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 718 XUs and 1276 XVs -> 78 XUVs
C:0 T:1 1126 XUs and 1582 XVs -> 78 XUVs
C:0 T:2 1743 XUs and 1787 XVs -> 82 XUVs
C:0 T:3 793 XUs and 928 XVs -> 31 XUVs
C:0 T:4 1325 XUs and 2818 XVs -> 147 XUVs
C:0 T:5 559 XUs and 707 XVs -> 25 XUVs
C:0 T:6 1269 XUs and 1593 XVs -> 57 XUVs
C:0 T:7 10685 XUs and 11488 XVs -> 794 XUVs
1292 XUVs total
878 collection wire objects
1292 potential space points
Neighbour search...
27484 tests to find 8638 neighbours
Iterating with no regularization...
Begin: 3.08518e+09
0 3.00718e+09
1 3.00498e+09
Now with regularization...
Begin: 2.94337e+09
0 2.94333e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 04:55:01 UTC Closed output file "np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009_reco_stage1_20250919T045501_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 36.4488 62.141 144.64 53.8548 24.6572 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.8178e-05 0.000309975 0.00782082 9.84085e-05 0.00120383 40
produce:tpcrawdecoder:PDVDTPCReader 6.5662 7.52347 9.6709 7.07595 0.972898 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.3878 0.394382 0.418515 0.392052 0.00655959 40
produce:pdvddaphne:DAPHNEReaderPDVD 2.35249 2.59992 3.21609 2.58267 0.180478 40
produce:ophit:OpHitFinder 0.0251374 0.0357255 0.0486762 0.034678 0.00534351 40
produce:opflash:OpFlashFinderVerticalDrift 0.00405955 0.00791961 0.0149672 0.00732847 0.00239857 40
produce:wclsdatavd:WireCellToolkit 20.1227 27.321 52.6135 23.4552 7.97701 40
produce:gaushit:GausHitFinder 0.20859 0.427057 0.891374 0.398394 0.145733 40
produce:nhitsfilter:NumberOfHitsFilter 7.5804e-05 0.000166319 0.000364511 0.000143567 7.21913e-05 40
produce:reco3d:SpacePointSolver 1.62966 4.77384 11.2478 4.28697 2.2587 40
produce:hitpdune:DisambigFromSpacePoints 0.0197105 0.0563196 0.257107 0.0493253 0.043002 40
produce:pandora:StandardPandora 2.46005 16.8177 93.8814 10.3512 19.6118 40
produce:pandoraTrack:LArPandoraTrackCreation 0.0658992 0.251544 0.914378 0.206647 0.174074 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.00498757 0.0124629 0.0268833 0.01263 0.00467344 40
[art]:TriggerResults:TriggerResultInserter 1.4131e-05 2.81708e-05 0.000103366 2.45915e-05 1.51322e-05 40
end_path:out1:RootOutput 3.355e-06 6.9738e-06 3.2238e-05 5.919e-06 5.26571e-06 40
end_path:out1:RootOutput(write) 1.52091 1.89454 2.41281 1.88341 0.229298 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4723.2 MB
Peak resident set size usage (VmHWM): 2408 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009_reco_stage1_20250919T045501_keepup.root
\tHists: np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009_reco_stage1_20250919T045501_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009_reco_stage1_20250919T045501_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009_reco_stage1_20250919T045501_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": 1758257702.0,
"core.end_time": 1758257702.0,
"core.events": [
48381,
48387,
48393,
48399,
48405,
48411,
48417,
48423,
48429,
48435,
48441,
48447,
48453,
48459,
48465,
48471,
48477,
48483,
48489,
48495,
48501,
48507,
48513,
48519,
48525,
48531,
48537,
48543,
48549,
48555,
48561,
48567,
48573,
48579,
48585,
48591,
48597,
48603,
48609,
48615
],
"core.event_count": 40,
"core.first_event_number": 48381,
"core.last_event_number": 48615,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009_reco_stage1_20250919T045501_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": 1758257702.0,
"core.end_time": 1758257702.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0198_df-s05-d3_dw_0_20250919T002009.hdf5"
}
]
} 