Jobsub ID 41739.121@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
OS at call=93 anode=0
[10:55:20.683] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[10:55:20.683] D [ glue ] frame sink sees EOS
[10:55:20.683] D [ pgraph ] <Pgrapher:> graph execution complete
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.4 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.89 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.79 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.65 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Aux::Resampler : 0.66 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Aux::Resampler : 0.65 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Aux::Resampler : 0.61 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Aux::Resampler : 0.58 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:55:20.684] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:55:20.684] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:55:20.684] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:55:20.684] I [ timer ] Timer: Total node execution : 44.23999954201281 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 15370 traces tagged "gauss"
FrameSaver: q=2.93065e+06 n=378821 tag=gauss
wclsFrameSaver: saving 19350 traces tagged "wiener"
FrameSaver: q=3.12205e+06 n=366343 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2195 XUs and 2658 XVs -> 105 XUVs
C:0 T:1 190 XUs and 184 XVs -> 5 XUVs
C:0 T:2 558 XUs and 782 XVs -> 15 XUVs
C:0 T:3 129 XUs and 242 XVs -> 12 XUVs
C:0 T:4 248 XUs and 467 XVs -> 9 XUVs
C:0 T:5 125 XUs and 110 XVs -> 5 XUVs
C:0 T:6 247 XUs and 383 XVs -> 11 XUVs
C:0 T:7 7047 XUs and 10400 XVs -> 685 XUVs
847 XUVs total
579 collection wire objects
847 potential space points
Neighbour search...
24549 tests to find 6290 neighbours
Iterating with no regularization...
Begin: 3.7622e+09
0 3.6328e+09
1 3.62991e+09
Now with regularization...
Begin: 3.59393e+09
0 3.59366e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 10:55:37 BST Closed output file "np02vd_raw_run039433_0393_df-s05-d0_dw_0_20250918T073015_reco_stage1_20250918T095537_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 80.234 123.669 510.959 107.877 66.3371 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.7932e-05 0.000109467 0.000228528 9.8949e-05 2.78041e-05 47
produce:tpcrawdecoder:PDVDTPCReader 12.3122 13.7828 17.6963 13.2442 1.52193 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.299112 0.309829 0.350856 0.302368 0.0131181 47
produce:pdvddaphne:DAPHNEReaderPDVD 5.72271 6.41371 8.58545 6.3835 0.411201 47
produce:ophit:OpHitFinder 0.0369165 0.0577183 0.0885902 0.0585989 0.00789632 47
produce:opflash:OpFlashFinderVerticalDrift 0.00496352 0.0158571 0.0254547 0.0160147 0.00493482 47
produce:wclsdatavd:WireCellToolkit 42.7478 57.422 102.722 48.8896 15.8085 47
produce:gaushit:GausHitFinder 0.611296 1.06153 2.30765 0.972082 0.361758 47
produce:nhitsfilter:NumberOfHitsFilter 0.000105228 0.000185619 0.000542826 0.000164906 7.94708e-05 47
produce:reco3d:SpacePointSolver 2.12732 6.99115 23.4597 6.19616 3.62609 47
produce:hitpdune:DisambigFromSpacePoints 0.0332995 0.1535 0.755637 0.119471 0.131465 47
produce:pandora:StandardPandora 7.01628 33.2596 387.144 21.6358 55.576 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0848614 0.590203 4.86318 0.416245 0.689675 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00991464 0.0250896 0.0568797 0.0245752 0.00850894 47
[art]:TriggerResults:TriggerResultInserter 2.6122e-05 3.46832e-05 6.8845e-05 3.3733e-05 7.18039e-06 47
end_path:out1:RootOutput 4.149e-06 8.8674e-06 3.2335e-05 8.591e-06 3.96252e-06 47
end_path:out1:RootOutput(write) 2.1028 3.55935 5.17779 3.45356 0.683733 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5922.62 MB
Peak resident set size usage (VmHWM): 4021.76 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0393_df-s05-d0_dw_0_20250918T073015_reco_stage1_20250918T095537_keepup.root
\tHists: np02vd_raw_run039433_0393_df-s05-d0_dw_0_20250918T073015_reco_stage1_20250918T095539_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0393_df-s05-d0_dw_0_20250918T073015_reco_stage1_20250918T095537_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0393_df-s05-d0_dw_0_20250918T073015_reco_stage1_20250918T095537_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": 1758189339.0,
"core.end_time": 1758189339.0,
"core.events": [
112824,
112830,
112836,
112842,
112848,
112854,
112860,
112866,
112872,
112878,
112884,
112890,
112896,
112902,
112908,
112914,
112920,
112926,
112932,
112938,
112944,
112950,
112956,
112962,
112968,
112974,
112980,
112986,
112992,
112998,
113004,
113010,
113016,
113022,
113028,
113034,
113040,
113046,
113052,
113058,
113064,
113070,
113076,
113082,
113088,
113094,
113100
],
"core.event_count": 47,
"core.first_event_number": 112824,
"core.last_event_number": 113100,
"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_0393_df-s05-d0_dw_0_20250918T073015.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0393_df-s05-d0_dw_0_20250918T073015_reco_stage1_20250918T095539_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": 1758189339.0,
"core.end_time": 1758189339.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_0393_df-s05-d0_dw_0_20250918T073015.hdf5"
}
]
} 