Jobsub ID 40383.0@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
all=35 anode=0
[14:43:32.538] D [ glue ] <FrameFanin:nfsp> EOS at call=35 with 8
[14:43:32.538] D [ glue ] frame sink sees EOS
[14:43:32.538] D [ pgraph ] <Pgrapher:> graph execution complete
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.92 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.18 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.33 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.72 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.48 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.16 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.31 sec
[14:43:32.538] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.94 sec
[14:43:32.538] I [ timer ] Timer: WireCell::Aux::Resampler : 1.04 sec
[14:43:32.538] I [ timer ] Timer: WireCell::Aux::Resampler : 0.85 sec
[14:43:32.538] I [ timer ] Timer: WireCell::Aux::Resampler : 0.81 sec
[14:43:32.538] I [ timer ] Timer: WireCell::Aux::Resampler : 0.76 sec
[14:43:32.539] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[14:43:32.539] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:43:32.539] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:43:32.539] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:43:32.539] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:43:32.539] I [ timer ] Timer: Total node execution : 73.56999993883073 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 58746 traces tagged "gauss"
FrameSaver: q=1.14775e+07 n=1386658 tag=gauss
wclsFrameSaver: saving 74056 traces tagged "wiener"
FrameSaver: q=1.23168e+07 n=1331199 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 289 XUs and 388 XVs -> 9 XUVs
C:0 T:1 493 XUs and 524 XVs -> 16 XUVs
C:0 T:2 1104 XUs and 1171 XVs -> 47 XUVs
C:0 T:3 363 XUs and 477 XVs -> 28 XUVs
C:0 T:4 511 XUs and 701 XVs -> 26 XUVs
C:0 T:5 1684 XUs and 1673 XVs -> 47 XUVs
C:0 T:6 21 XUs and 20 XVs -> 0 XUVs
C:0 T:7 378 XUs and 422 XVs -> 21 XUVs
C:0 T:8 14466 XUs and 21278 XVs -> 2798 XUVs
C:0 T:9 11179 XUs and 32563 XVs -> 3232 XUVs
C:0 T:10 10707 XUs and 21404 XVs -> 1897 XUVs
C:0 T:11 10918 XUs and 13185 XVs -> 1030 XUVs
C:0 T:12 16355 XUs and 26453 XVs -> 3379 XUVs
C:0 T:13 5217 XUs and 9723 XVs -> 646 XUVs
C:0 T:14 2555 XUs and 4278 XVs -> 849 XUVs
C:0 T:15 11107 XUs and 18393 XVs -> 2293 XUVs
16318 XUVs total
2913 collection wire objects
16318 potential space points
Neighbour search...
1587816 tests to find 717360 neighbours
Iterating with no regularization...
Begin: 7.3192e+08
0 6.30308e+08
1 6.2327e+08
2 6.2231e+08
3 6.2193e+08
Now with regularization...
Begin: 6.07446e+08
0 6.07118e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
16-Sep-2025 14:46:07 CEST Closed output file "np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041_reco_stage1_20250916T124607_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 113.866 210.632 421.968 210.016 74.1533 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.8121e-05 9.31291e-05 0.000209423 8.4811e-05 3.43764e-05 18
produce:tpcrawdecoder:PDVDTPCReader 5.80165 6.50711 7.05501 6.48336 0.291628 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.000888342 0.0017664 0.0110434 0.00119265 0.00225713 18
produce:pdvddaphne:DAPHNEReaderPDVD 3.85742 4.39713 4.96594 4.38294 0.264794 18
produce:ophit:OpHitFinder 0.0427992 0.0527925 0.0750233 0.0520731 0.00823873 18
produce:opflash:OpFlashFinderVerticalDrift 0.00715571 0.0132165 0.026345 0.0125226 0.00449936 18
produce:wclsdatavd:WireCellToolkit 63.0301 71.7751 82.126 71.0331 5.19379 18
produce:gaushit:GausHitFinder 0.719753 1.45819 2.14909 1.60389 0.3557 18
produce:nhitsfilter:NumberOfHitsFilter 0.000272724 0.000469193 0.000845441 0.000469821 0.000126098 18
produce:reco3d:SpacePointSolver 7.88283 19.9867 30.0406 22.0681 6.94289 18
produce:hitpdune:DisambigFromSpacePoints 0.120196 0.332979 0.657557 0.319862 0.127923 18
produce:pandora:StandardPandora 19.014 99.2262 298.928 93.163 66.0768 18
produce:pandoraTrack:LArPandoraTrackCreation 0.276169 1.46192 2.86255 1.42538 0.619406 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.0198218 0.0362483 0.056585 0.0343056 0.00888508 18
[art]:TriggerResults:TriggerResultInserter 1.685e-05 2.52197e-05 6.0641e-05 2.09955e-05 1.19464e-05 18
end_path:out1:RootOutput 3.28e-06 7.52672e-06 2.498e-05 6.8205e-06 4.60819e-06 18
end_path:out1:RootOutput(write) 4.83164 5.33206 5.99674 5.26292 0.349339 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5393.37 MB
Peak resident set size usage (VmHWM): 3469.24 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041_reco_stage1_20250916T124607_keepup.root
\tHists: np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041_reco_stage1_20250916T124607_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041_reco_stage1_20250916T124607_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041_reco_stage1_20250916T124607_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": 1758026768.0,
"core.end_time": 1758026768.0,
"core.events": [
122420,
122440,
122460,
122480,
122500,
122520,
122540,
122560,
122580,
122600,
122620,
122640,
122660,
122680,
122700,
122720,
122740,
122760
],
"core.event_count": 18,
"core.first_event_number": 122420,
"core.last_event_number": 122760,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39275
],
"core.runs_subruns": [
3927500001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041_reco_stage1_20250916T124607_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": 1758026768.0,
"core.end_time": 1758026768.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39275
],
"core.runs_subruns": [
3927500001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039275_0340_df-s01-d2_dw_0_20250902T023041.hdf5"
}
]
} 