Jobsub ID 43638.123@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
51 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.29 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.16 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.81 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.72 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.58 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.92 sec
[11:17:58.076] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.84 sec
[11:17:58.076] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[11:17:58.076] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[11:17:58.076] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[11:17:58.076] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[11:17:58.077] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:17:58.077] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:17:58.077] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:17:58.077] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:17:58.077] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:17:58.077] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:17:58.077] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:17:58.077] I [ timer ] Timer: Total node execution : 66.469999903813 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 41831 traces tagged "gauss"
FrameSaver: q=9.48546e+06 n=1068440 tag=gauss
wclsFrameSaver: saving 51596 traces tagged "wiener"
FrameSaver: q=1.01004e+07 n=1031379 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1552 XUs and 1387 XVs -> 42 XUVs
C:0 T:1 392 XUs and 338 XVs -> 9 XUVs
C:0 T:2 130 XUs and 125 XVs -> 5 XUVs
C:0 T:3 565 XUs and 790 XVs -> 19 XUVs
C:0 T:4 155 XUs and 274 XVs -> 7 XUVs
C:0 T:5 494 XUs and 416 XVs -> 25 XUVs
C:0 T:6 1697 XUs and 2505 XVs -> 150 XUVs
C:0 T:7 239 XUs and 375 XVs -> 16 XUVs
C:0 T:8 12522 XUs and 23641 XVs -> 3712 XUVs
C:0 T:9 3948 XUs and 3630 XVs -> 358 XUVs
C:0 T:10 555 XUs and 741 XVs -> 36 XUVs
C:0 T:11 13267 XUs and 26843 XVs -> 5334 XUVs
C:0 T:12 20313 XUs and 34663 XVs -> 4509 XUVs
C:0 T:13 4664 XUs and 15215 XVs -> 1682 XUVs
C:0 T:14 5949 XUs and 8418 XVs -> 1245 XUVs
C:0 T:15 2329 XUs and 1665 XVs -> 137 XUVs
17286 XUVs total
1930 collection wire objects
17286 potential space points
Neighbour search...
2936106 tests to find 1165094 neighbours
Iterating with no regularization...
Begin: 1.97687e+09
0 1.86083e+09
1 1.85038e+09
2 1.85001e+09
Now with regularization...
Begin: 1.83337e+09
0 1.83309e+09
PcaShowerParticleBuildingAlgorithm::OpeningAngle - principal eigenvalue less than or equal to 0.
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
09-Sep-2025 11:20:17 BST Closed output file "np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016_reco_stage1_20250909T102017_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 148.504 215.56 377.705 201.83 54.3474 26
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.6849e-05 8.99217e-05 0.000227062 8.21565e-05 3.39311e-05 26
produce:tpcrawdecoder:PDVDTPCReader 28.6068 53.1778 96.8697 50.9798 13.6671 26
produce:triggerrawdecoder:PDVDTriggerReader4 0.0353502 0.0465467 0.0702642 0.0465211 0.0114182 26
produce:pdvddaphne:DAPHNEReaderPDVD 5.78068 7.95385 11.1169 7.95731 1.55044 26
produce:ophit:OpHitFinder 0.0362998 0.0427791 0.0497258 0.0427719 0.00349249 26
produce:opflash:OpFlashFinderVerticalDrift 0.00652649 0.0128202 0.0173179 0.013403 0.002479 26
produce:wclsdatavd:WireCellToolkit 49.5901 74.2484 123.572 69.4535 18.543 26
produce:gaushit:GausHitFinder 0.743442 1.37526 2.56432 1.3132 0.417433 26
produce:nhitsfilter:NumberOfHitsFilter 0.000148978 0.000308384 0.000532515 0.000296538 0.000112438 26
produce:reco3d:SpacePointSolver 6.29531 11.7879 29.9047 10.7635 4.9676 26
produce:hitpdune:DisambigFromSpacePoints 0.0941423 0.201248 0.417678 0.178617 0.0850007 26
produce:pandora:StandardPandora 16.2416 60.5829 149.315 52.4476 32.9941 26
produce:pandoraTrack:LArPandoraTrackCreation 0.286019 0.901601 2.61952 0.782687 0.493721 26
produce:pandoraGnocalo:GnocchiCalorimetry 0.0167267 0.0264357 0.0512012 0.0242159 0.00770221 26
[art]:TriggerResults:TriggerResultInserter 1.4656e-05 2.73623e-05 5.6884e-05 2.44175e-05 1.00278e-05 26
end_path:out1:RootOutput 3.175e-06 8.14346e-06 1.9454e-05 7.8085e-06 4.21122e-06 26
end_path:out1:RootOutput(write) 4.34907 5.16578 8.82445 4.88191 0.983934 26
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5010.15 MB
Peak resident set size usage (VmHWM): 3102.24 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016_reco_stage1_20250909T102017_keepup.root
\tHists: np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016_reco_stage1_20250909T102018_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016_reco_stage1_20250909T102017_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016_reco_stage1_20250909T102017_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": 1757413218.0,
"core.end_time": 1757413218.0,
"core.events": [
808508,
808528,
808548,
808568,
808588,
808608,
808628,
808648,
808668,
808688,
808708,
808728,
808748,
808768,
808788,
808808,
808828,
808848,
808868,
808888,
808908,
808928,
808948,
808968,
808988,
809008
],
"core.event_count": 26,
"core.first_event_number": 808508,
"core.last_event_number": 809008,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39344
],
"core.runs_subruns": [
3934400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016_reco_stage1_20250909T102018_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": 1757413218.0,
"core.end_time": 1757413218.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39344
],
"core.runs_subruns": [
3934400001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039344_1492_df-s02-d3_dw_0_20250909T075016.hdf5"
}
]
} 