blob: 3ac7afa7eaf90335f12c61f5668657b5ff5ca7bf [file] [log] [blame]
Erik Språngd05edec2019-08-14 10:43:47 +02001/*
2 * Copyright (c) 2019 The WebRTC project authors. All Rights Reserved.
3 *
4 * Use of this source code is governed by a BSD-style license
5 * that can be found in the LICENSE file in the root of the source
6 * tree. An additional intellectual property rights grant can be found
7 * in the file PATENTS. All contributing project authors may
8 * be found in the AUTHORS file in the root of the source tree.
9 */
10
11#include "modules/pacing/pacing_controller.h"
12
13#include <algorithm>
Mirko Bonadei317a1f02019-09-17 17:06:18 +020014#include <memory>
Erik Språngd05edec2019-08-14 10:43:47 +020015#include <utility>
16#include <vector>
17
Mirko Bonadei06d35592020-04-01 13:43:08 +020018#include "absl/strings/match.h"
Erik Språngd05edec2019-08-14 10:43:47 +020019#include "modules/pacing/bitrate_prober.h"
20#include "modules/pacing/interval_budget.h"
21#include "modules/utility/include/process_thread.h"
22#include "rtc_base/checks.h"
Erik Språng9acc18d2020-04-16 19:41:07 +020023#include "rtc_base/experiments/field_trial_parser.h"
Erik Språngd05edec2019-08-14 10:43:47 +020024#include "rtc_base/logging.h"
25#include "rtc_base/time_utils.h"
26#include "system_wrappers/include/clock.h"
27
28namespace webrtc {
29namespace {
30// Time limit in milliseconds between packet bursts.
Danil Chapovalov55284022020-02-07 14:53:52 +010031constexpr TimeDelta kDefaultMinPacketLimit = TimeDelta::Millis(5);
32constexpr TimeDelta kCongestedPacketInterval = TimeDelta::Millis(500);
Erik Språngeb487992019-11-14 14:15:15 +010033// TODO(sprang): Consider dropping this limit.
34// The maximum debt level, in terms of time, capped when sending packets.
Danil Chapovalov55284022020-02-07 14:53:52 +010035constexpr TimeDelta kMaxDebtInTime = TimeDelta::Millis(500);
36constexpr TimeDelta kMaxElapsedTime = TimeDelta::Seconds(2);
Erik Språngd05edec2019-08-14 10:43:47 +020037
38// Upper cap on process interval, in case process has not been called in a long
Erik Språng9acc18d2020-04-16 19:41:07 +020039// time. Applies only to periodic mode.
Danil Chapovalov55284022020-02-07 14:53:52 +010040constexpr TimeDelta kMaxProcessingInterval = TimeDelta::Millis(30);
Erik Språngd05edec2019-08-14 10:43:47 +020041
Erik Språng7d0cde52020-07-19 12:48:16 +020042// Allow probes to be processed slightly ahead of inteded send time. Currently
43// set to 1ms as this is intended to allow times be rounded down to the nearest
44// millisecond.
45constexpr TimeDelta kMaxEarlyProbeProcessing = TimeDelta::Millis(1);
46
Erik Språng78c82a42019-10-03 18:46:04 +020047constexpr int kFirstPriority = 0;
48
Erik Språngd05edec2019-08-14 10:43:47 +020049bool IsDisabled(const WebRtcKeyValueConfig& field_trials,
50 absl::string_view key) {
Mirko Bonadei06d35592020-04-01 13:43:08 +020051 return absl::StartsWith(field_trials.Lookup(key), "Disabled");
Erik Språngd05edec2019-08-14 10:43:47 +020052}
53
54bool IsEnabled(const WebRtcKeyValueConfig& field_trials,
55 absl::string_view key) {
Mirko Bonadei06d35592020-04-01 13:43:08 +020056 return absl::StartsWith(field_trials.Lookup(key), "Enabled");
Erik Språngd05edec2019-08-14 10:43:47 +020057}
58
Erik Språng9acc18d2020-04-16 19:41:07 +020059TimeDelta GetDynamicPaddingTarget(const WebRtcKeyValueConfig& field_trials) {
60 FieldTrialParameter<TimeDelta> padding_target("timedelta",
61 TimeDelta::Millis(5));
62 ParseFieldTrial({&padding_target},
63 field_trials.Lookup("WebRTC-Pacer-DynamicPaddingTarget"));
64 return padding_target.Get();
65}
66
Björn Terelius31d0f7c2020-02-06 16:35:46 +010067int GetPriorityForType(RtpPacketMediaType type) {
Erik Språng78c82a42019-10-03 18:46:04 +020068 // Lower number takes priority over higher.
Erik Språngd05edec2019-08-14 10:43:47 +020069 switch (type) {
Björn Terelius31d0f7c2020-02-06 16:35:46 +010070 case RtpPacketMediaType::kAudio:
Erik Språngd05edec2019-08-14 10:43:47 +020071 // Audio is always prioritized over other packet types.
Erik Språng78c82a42019-10-03 18:46:04 +020072 return kFirstPriority + 1;
Björn Terelius31d0f7c2020-02-06 16:35:46 +010073 case RtpPacketMediaType::kRetransmission:
Erik Språngd05edec2019-08-14 10:43:47 +020074 // Send retransmissions before new media.
Erik Språng78c82a42019-10-03 18:46:04 +020075 return kFirstPriority + 2;
Björn Terelius31d0f7c2020-02-06 16:35:46 +010076 case RtpPacketMediaType::kVideo:
77 case RtpPacketMediaType::kForwardErrorCorrection:
Erik Språng78c82a42019-10-03 18:46:04 +020078 // Video has "normal" priority, in the old speak.
Erik Språngd05edec2019-08-14 10:43:47 +020079 // Send redundancy concurrently to video. If it is delayed it might have a
80 // lower chance of being useful.
Erik Språng78c82a42019-10-03 18:46:04 +020081 return kFirstPriority + 3;
Björn Terelius31d0f7c2020-02-06 16:35:46 +010082 case RtpPacketMediaType::kPadding:
Erik Språngd05edec2019-08-14 10:43:47 +020083 // Packets that are in themselves likely useless, only sent to keep the
84 // BWE high.
Erik Språng78c82a42019-10-03 18:46:04 +020085 return kFirstPriority + 4;
Erik Språngd05edec2019-08-14 10:43:47 +020086 }
Karl Wibergc95b9392020-11-08 00:49:37 +010087 RTC_CHECK_NOTREACHED();
Erik Språngd05edec2019-08-14 10:43:47 +020088}
89
90} // namespace
91
92const TimeDelta PacingController::kMaxExpectedQueueLength =
Danil Chapovalov55284022020-02-07 14:53:52 +010093 TimeDelta::Millis(2000);
Erik Språngd05edec2019-08-14 10:43:47 +020094const float PacingController::kDefaultPaceMultiplier = 2.5f;
95const TimeDelta PacingController::kPausedProcessInterval =
96 kCongestedPacketInterval;
Danil Chapovalov55284022020-02-07 14:53:52 +010097const TimeDelta PacingController::kMinSleepTime = TimeDelta::Millis(1);
Erik Språngd05edec2019-08-14 10:43:47 +020098
99PacingController::PacingController(Clock* clock,
100 PacketSender* packet_sender,
101 RtcEventLog* event_log,
Erik Språngeb487992019-11-14 14:15:15 +0100102 const WebRtcKeyValueConfig* field_trials,
103 ProcessMode mode)
104 : mode_(mode),
105 clock_(clock),
Erik Språngd05edec2019-08-14 10:43:47 +0200106 packet_sender_(packet_sender),
107 fallback_field_trials_(
Mirko Bonadei317a1f02019-09-17 17:06:18 +0200108 !field_trials ? std::make_unique<FieldTrialBasedConfig>() : nullptr),
Erik Språngd05edec2019-08-14 10:43:47 +0200109 field_trials_(field_trials ? field_trials : fallback_field_trials_.get()),
110 drain_large_queues_(
111 !IsDisabled(*field_trials_, "WebRTC-Pacer-DrainQueue")),
112 send_padding_if_silent_(
113 IsEnabled(*field_trials_, "WebRTC-Pacer-PadInSilence")),
Evan Shrubsole6ef59d12020-01-08 16:45:08 +0100114 pace_audio_(IsEnabled(*field_trials_, "WebRTC-Pacer-BlockAudio")),
Mirko Bonadeie7bc3a32020-01-29 18:45:00 +0000115 ignore_transport_overhead_(
Sebastian Janssonbef818d2020-01-30 14:09:48 +0100116 IsEnabled(*field_trials_, "WebRTC-Pacer-IgnoreTransportOverhead")),
Erik Språng9acc18d2020-04-16 19:41:07 +0200117 padding_target_duration_(GetDynamicPaddingTarget(*field_trials_)),
Erik Språngd05edec2019-08-14 10:43:47 +0200118 min_packet_limit_(kDefaultMinPacketLimit),
Mirko Bonadeie7bc3a32020-01-29 18:45:00 +0000119 transport_overhead_per_packet_(DataSize::Zero()),
Erik Språngd05edec2019-08-14 10:43:47 +0200120 last_timestamp_(clock_->CurrentTime()),
121 paused_(false),
122 media_budget_(0),
123 padding_budget_(0),
Erik Språngeb487992019-11-14 14:15:15 +0100124 media_debt_(DataSize::Zero()),
125 padding_debt_(DataSize::Zero()),
126 media_rate_(DataRate::Zero()),
127 padding_rate_(DataRate::Zero()),
Erik Språngd05edec2019-08-14 10:43:47 +0200128 prober_(*field_trials_),
129 probing_send_failure_(false),
Erik Språngd05edec2019-08-14 10:43:47 +0200130 pacing_bitrate_(DataRate::Zero()),
Erik Språngeb487992019-11-14 14:15:15 +0100131 last_process_time_(clock->CurrentTime()),
132 last_send_time_(last_process_time_),
Erik Språngdca14492019-11-19 18:14:30 +0100133 packet_queue_(last_process_time_, field_trials_),
Erik Språngd05edec2019-08-14 10:43:47 +0200134 packet_counter_(0),
135 congestion_window_size_(DataSize::PlusInfinity()),
136 outstanding_data_(DataSize::Zero()),
137 queue_time_limit(kMaxExpectedQueueLength),
Sebastian Janssonc3eb9fd2020-01-29 17:42:52 +0100138 account_for_audio_(false),
139 include_overhead_(false) {
Erik Språngd05edec2019-08-14 10:43:47 +0200140 if (!drain_large_queues_) {
141 RTC_LOG(LS_WARNING) << "Pacer queues will not be drained,"
142 "pushback experiment must be enabled.";
143 }
144 FieldTrialParameter<int> min_packet_limit_ms("", min_packet_limit_.ms());
145 ParseFieldTrial({&min_packet_limit_ms},
146 field_trials_->Lookup("WebRTC-Pacer-MinPacketLimitMs"));
Danil Chapovalov55284022020-02-07 14:53:52 +0100147 min_packet_limit_ = TimeDelta::Millis(min_packet_limit_ms.Get());
Erik Språngd05edec2019-08-14 10:43:47 +0200148 UpdateBudgetWithElapsedTime(min_packet_limit_);
149}
150
151PacingController::~PacingController() = default;
152
153void PacingController::CreateProbeCluster(DataRate bitrate, int cluster_id) {
Erik Språngb210eeb2019-11-05 11:21:48 +0100154 prober_.CreateProbeCluster(bitrate, CurrentTime(), cluster_id);
Erik Språngd05edec2019-08-14 10:43:47 +0200155}
156
157void PacingController::Pause() {
158 if (!paused_)
159 RTC_LOG(LS_INFO) << "PacedSender paused.";
160 paused_ = true;
161 packet_queue_.SetPauseState(true, CurrentTime());
162}
163
164void PacingController::Resume() {
165 if (paused_)
166 RTC_LOG(LS_INFO) << "PacedSender resumed.";
167 paused_ = false;
168 packet_queue_.SetPauseState(false, CurrentTime());
169}
170
171bool PacingController::IsPaused() const {
172 return paused_;
173}
174
175void PacingController::SetCongestionWindow(DataSize congestion_window_size) {
Erik Språngeb487992019-11-14 14:15:15 +0100176 const bool was_congested = Congested();
Erik Språngd05edec2019-08-14 10:43:47 +0200177 congestion_window_size_ = congestion_window_size;
Erik Språngeb487992019-11-14 14:15:15 +0100178 if (was_congested && !Congested()) {
179 TimeDelta elapsed_time = UpdateTimeAndGetElapsed(CurrentTime());
180 UpdateBudgetWithElapsedTime(elapsed_time);
181 }
Erik Språngd05edec2019-08-14 10:43:47 +0200182}
183
184void PacingController::UpdateOutstandingData(DataSize outstanding_data) {
Erik Språngeb487992019-11-14 14:15:15 +0100185 const bool was_congested = Congested();
Erik Språngd05edec2019-08-14 10:43:47 +0200186 outstanding_data_ = outstanding_data;
Erik Språngeb487992019-11-14 14:15:15 +0100187 if (was_congested && !Congested()) {
188 TimeDelta elapsed_time = UpdateTimeAndGetElapsed(CurrentTime());
189 UpdateBudgetWithElapsedTime(elapsed_time);
190 }
Erik Språngd05edec2019-08-14 10:43:47 +0200191}
192
193bool PacingController::Congested() const {
194 if (congestion_window_size_.IsFinite()) {
195 return outstanding_data_ >= congestion_window_size_;
196 }
197 return false;
198}
199
Erik Språng4ab61cb2020-05-19 17:40:58 +0200200bool PacingController::IsProbing() const {
201 return prober_.is_probing();
202}
203
Erik Språngd05edec2019-08-14 10:43:47 +0200204Timestamp PacingController::CurrentTime() const {
205 Timestamp time = clock_->CurrentTime();
206 if (time < last_timestamp_) {
207 RTC_LOG(LS_WARNING)
208 << "Non-monotonic clock behavior observed. Previous timestamp: "
209 << last_timestamp_.ms() << ", new timestamp: " << time.ms();
210 RTC_DCHECK_GE(time, last_timestamp_);
211 time = last_timestamp_;
212 }
213 last_timestamp_ = time;
214 return time;
215}
216
217void PacingController::SetProbingEnabled(bool enabled) {
218 RTC_CHECK_EQ(0, packet_counter_);
219 prober_.SetEnabled(enabled);
220}
221
222void PacingController::SetPacingRates(DataRate pacing_rate,
223 DataRate padding_rate) {
224 RTC_DCHECK_GT(pacing_rate, DataRate::Zero());
Erik Språngeb487992019-11-14 14:15:15 +0100225 media_rate_ = pacing_rate;
226 padding_rate_ = padding_rate;
Erik Språngd05edec2019-08-14 10:43:47 +0200227 pacing_bitrate_ = pacing_rate;
228 padding_budget_.set_target_rate_kbps(padding_rate.kbps());
229
230 RTC_LOG(LS_VERBOSE) << "bwe:pacer_updated pacing_kbps="
231 << pacing_bitrate_.kbps()
232 << " padding_budget_kbps=" << padding_rate.kbps();
233}
234
Erik Språngd05edec2019-08-14 10:43:47 +0200235void PacingController::EnqueuePacket(std::unique_ptr<RtpPacketToSend> packet) {
236 RTC_DCHECK(pacing_bitrate_ > DataRate::Zero())
237 << "SetPacingRate must be called before InsertPacket.";
Erik Språngd05edec2019-08-14 10:43:47 +0200238 RTC_CHECK(packet->packet_type());
Erik Språng78c82a42019-10-03 18:46:04 +0200239 // Get priority first and store in temporary, to avoid chance of object being
240 // moved before GetPriorityForType() being called.
241 const int priority = GetPriorityForType(*packet->packet_type());
242 EnqueuePacketInternal(std::move(packet), priority);
Erik Språngd05edec2019-08-14 10:43:47 +0200243}
244
245void PacingController::SetAccountForAudioPackets(bool account_for_audio) {
246 account_for_audio_ = account_for_audio;
247}
248
Sebastian Janssonc3eb9fd2020-01-29 17:42:52 +0100249void PacingController::SetIncludeOverhead() {
250 include_overhead_ = true;
251 packet_queue_.SetIncludeOverhead();
252}
253
Mirko Bonadeie7bc3a32020-01-29 18:45:00 +0000254void PacingController::SetTransportOverhead(DataSize overhead_per_packet) {
255 if (ignore_transport_overhead_)
256 return;
257 transport_overhead_per_packet_ = overhead_per_packet;
258 packet_queue_.SetTransportOverhead(overhead_per_packet);
259}
260
Erik Språngd05edec2019-08-14 10:43:47 +0200261TimeDelta PacingController::ExpectedQueueTime() const {
262 RTC_DCHECK_GT(pacing_bitrate_, DataRate::Zero());
Danil Chapovalov55284022020-02-07 14:53:52 +0100263 return TimeDelta::Millis(
Erik Språngd05edec2019-08-14 10:43:47 +0200264 (QueueSizeData().bytes() * 8 * rtc::kNumMillisecsPerSec) /
265 pacing_bitrate_.bps());
266}
267
268size_t PacingController::QueueSizePackets() const {
269 return packet_queue_.SizeInPackets();
270}
271
272DataSize PacingController::QueueSizeData() const {
273 return packet_queue_.Size();
274}
275
Erik Språng4314a492019-11-26 17:48:49 +0100276DataSize PacingController::CurrentBufferLevel() const {
277 return std::max(media_debt_, padding_debt_);
278}
279
Erik Språngd05edec2019-08-14 10:43:47 +0200280absl::optional<Timestamp> PacingController::FirstSentPacketTime() const {
281 return first_sent_packet_time_;
282}
283
284TimeDelta PacingController::OldestPacketWaitTime() const {
285 Timestamp oldest_packet = packet_queue_.OldestEnqueueTime();
286 if (oldest_packet.IsInfinite()) {
287 return TimeDelta::Zero();
288 }
289
290 return CurrentTime() - oldest_packet;
291}
292
Erik Språng78c82a42019-10-03 18:46:04 +0200293void PacingController::EnqueuePacketInternal(
294 std::unique_ptr<RtpPacketToSend> packet,
295 int priority) {
Erik Språngb9d38092020-07-17 12:06:12 +0200296 prober_.OnIncomingPacket(DataSize::Bytes(packet->payload_size()));
Erik Språng78c82a42019-10-03 18:46:04 +0200297
298 // TODO(sprang): Make sure tests respect this, replace with DCHECK.
Erik Språngb210eeb2019-11-05 11:21:48 +0100299 Timestamp now = CurrentTime();
Erik Språng78c82a42019-10-03 18:46:04 +0200300 if (packet->capture_time_ms() < 0) {
301 packet->set_capture_time_ms(now.ms());
302 }
303
Erik Språngeb487992019-11-14 14:15:15 +0100304 if (mode_ == ProcessMode::kDynamic && packet_queue_.Empty() &&
Erik Språngbe152f52020-04-06 16:30:23 +0200305 NextSendTime() <= now) {
Erik Språng0920d5d2020-03-30 17:14:08 +0200306 TimeDelta elapsed_time = UpdateTimeAndGetElapsed(now);
307 UpdateBudgetWithElapsedTime(elapsed_time);
Erik Språngeb487992019-11-14 14:15:15 +0100308 }
Erik Språng78c82a42019-10-03 18:46:04 +0200309 packet_queue_.Push(priority, now, packet_counter_++, std::move(packet));
310}
311
Erik Språngd05edec2019-08-14 10:43:47 +0200312TimeDelta PacingController::UpdateTimeAndGetElapsed(Timestamp now) {
Erik Språng7d0cde52020-07-19 12:48:16 +0200313 // If no previous processing, or last process was "in the future" because of
314 // early probe processing, then there is no elapsed time to add budget for.
315 if (last_process_time_.IsMinusInfinity() || now < last_process_time_) {
Erik Språngeb487992019-11-14 14:15:15 +0100316 return TimeDelta::Zero();
317 }
Erik Språngb1ccae22019-11-25 18:22:09 +0100318 RTC_DCHECK_GE(now, last_process_time_);
Erik Språngeb487992019-11-14 14:15:15 +0100319 TimeDelta elapsed_time = now - last_process_time_;
320 last_process_time_ = now;
Erik Språngd05edec2019-08-14 10:43:47 +0200321 if (elapsed_time > kMaxElapsedTime) {
322 RTC_LOG(LS_WARNING) << "Elapsed time (" << elapsed_time.ms()
323 << " ms) longer than expected, limiting to "
324 << kMaxElapsedTime.ms();
325 elapsed_time = kMaxElapsedTime;
326 }
327 return elapsed_time;
328}
329
330bool PacingController::ShouldSendKeepalive(Timestamp now) const {
Erik Språngeb487992019-11-14 14:15:15 +0100331 if (send_padding_if_silent_ || paused_ || Congested() ||
332 packet_counter_ == 0) {
Erik Språngd05edec2019-08-14 10:43:47 +0200333 // We send a padding packet every 500 ms to ensure we won't get stuck in
334 // congested state due to no feedback being received.
335 TimeDelta elapsed_since_last_send = now - last_send_time_;
336 if (elapsed_since_last_send >= kCongestedPacketInterval) {
Erik Språngeb487992019-11-14 14:15:15 +0100337 return true;
Erik Språngd05edec2019-08-14 10:43:47 +0200338 }
339 }
340 return false;
341}
342
Erik Språngeb487992019-11-14 14:15:15 +0100343Timestamp PacingController::NextSendTime() const {
Erik Språngb9d38092020-07-17 12:06:12 +0200344 const Timestamp now = CurrentTime();
Erik Språngeb487992019-11-14 14:15:15 +0100345
Erik Språngae100292019-12-17 17:49:49 +0100346 if (paused_) {
347 return last_send_time_ + kPausedProcessInterval;
348 }
349
Erik Språngeb487992019-11-14 14:15:15 +0100350 // If probing is active, that always takes priority.
Erik Språng4bdd8732020-03-26 10:37:09 +0100351 if (prober_.is_probing()) {
Erik Språngeb487992019-11-14 14:15:15 +0100352 Timestamp probe_time = prober_.NextProbeTime(now);
353 // |probe_time| == PlusInfinity indicates no probe scheduled.
354 if (probe_time != Timestamp::PlusInfinity() && !probing_send_failure_) {
355 return probe_time;
356 }
Erik Språngd05edec2019-08-14 10:43:47 +0200357 }
358
Erik Språngeb487992019-11-14 14:15:15 +0100359 if (mode_ == ProcessMode::kPeriodic) {
Erik Språngae100292019-12-17 17:49:49 +0100360 // In periodic non-probing mode, we just have a fixed interval.
Erik Språngeb487992019-11-14 14:15:15 +0100361 return last_process_time_ + min_packet_limit_;
Erik Språngb210eeb2019-11-05 11:21:48 +0100362 }
363
Erik Språngeb487992019-11-14 14:15:15 +0100364 // In dynamic mode, figure out when the next packet should be sent,
365 // given the current conditions.
Erik Språngd05edec2019-08-14 10:43:47 +0200366
Erik Språngb571ff42020-04-04 17:20:37 +0200367 if (!pace_audio_) {
368 // Not pacing audio, if leading packet is audio its target send
369 // time is the time at which it was enqueued.
370 absl::optional<Timestamp> audio_enqueue_time =
371 packet_queue_.LeadingAudioPacketEnqueueTime();
372 if (audio_enqueue_time.has_value()) {
373 return *audio_enqueue_time;
374 }
Erik Språng9cb58d52020-03-28 17:15:54 +0100375 }
Erik Språngeb487992019-11-14 14:15:15 +0100376
Erik Språng9cb58d52020-03-28 17:15:54 +0100377 if (Congested() || packet_counter_ == 0) {
Erik Språngeb487992019-11-14 14:15:15 +0100378 // We need to at least send keep-alive packets with some interval.
379 return last_send_time_ + kCongestedPacketInterval;
380 }
381
Erik Språngbe152f52020-04-06 16:30:23 +0200382 // Check how long until we can send the next media packet.
383 if (media_rate_ > DataRate::Zero() && !packet_queue_.Empty()) {
Erik Språngeb487992019-11-14 14:15:15 +0100384 return std::min(last_send_time_ + kPausedProcessInterval,
385 last_process_time_ + media_debt_ / media_rate_);
386 }
387
388 // If we _don't_ have pending packets, check how long until we have
Erik Språngbe152f52020-04-06 16:30:23 +0200389 // bandwidth for padding packets. Both media and padding debts must
390 // have been drained to do this.
Erik Språngeb487992019-11-14 14:15:15 +0100391 if (padding_rate_ > DataRate::Zero() && packet_queue_.Empty()) {
Erik Språngbe152f52020-04-06 16:30:23 +0200392 TimeDelta drain_time =
393 std::max(media_debt_ / media_rate_, padding_debt_ / padding_rate_);
Erik Språngeb487992019-11-14 14:15:15 +0100394 return std::min(last_send_time_ + kPausedProcessInterval,
Erik Språngbe152f52020-04-06 16:30:23 +0200395 last_process_time_ + drain_time);
Erik Språngeb487992019-11-14 14:15:15 +0100396 }
397
Erik Språngb1ccae22019-11-25 18:22:09 +0100398 if (send_padding_if_silent_) {
399 return last_send_time_ + kPausedProcessInterval;
400 }
401 return last_process_time_ + kPausedProcessInterval;
Erik Språngd05edec2019-08-14 10:43:47 +0200402}
403
404void PacingController::ProcessPackets() {
405 Timestamp now = CurrentTime();
Erik Språngeb487992019-11-14 14:15:15 +0100406 Timestamp target_send_time = now;
407 if (mode_ == ProcessMode::kDynamic) {
408 target_send_time = NextSendTime();
Erik Språng7d0cde52020-07-19 12:48:16 +0200409 TimeDelta early_execute_margin =
410 prober_.is_probing() ? kMaxEarlyProbeProcessing : TimeDelta::Zero();
Erik Språngeb487992019-11-14 14:15:15 +0100411 if (target_send_time.IsMinusInfinity()) {
412 target_send_time = now;
Erik Språng7d0cde52020-07-19 12:48:16 +0200413 } else if (now < target_send_time - early_execute_margin) {
Erik Språng998524a2020-05-29 16:13:32 +0200414 // We are too early, but if queue is empty still allow draining some debt.
Erik Språng7d0cde52020-07-19 12:48:16 +0200415 // Probing is allowed to be sent up to kMinSleepTime early.
Erik Språng998524a2020-05-29 16:13:32 +0200416 TimeDelta elapsed_time = UpdateTimeAndGetElapsed(now);
417 UpdateBudgetWithElapsedTime(elapsed_time);
Erik Språngeb487992019-11-14 14:15:15 +0100418 return;
Erik Språngd05edec2019-08-14 10:43:47 +0200419 }
Erik Språngb1ccae22019-11-25 18:22:09 +0100420
421 if (target_send_time < last_process_time_) {
422 // After the last process call, at time X, the target send time
423 // shifted to be earlier than X. This should normally not happen
424 // but we want to make sure rounding errors or erratic behavior
425 // of NextSendTime() does not cause issue. In particular, if the
426 // buffer reduction of
427 // rate * (target_send_time - previous_process_time)
428 // in the main loop doesn't clean up the existing debt we may not
429 // be able to send again. We don't want to check this reordering
430 // there as it is the normal exit condtion when the buffer is
431 // exhausted and there are packets in the queue.
432 UpdateBudgetWithElapsedTime(last_process_time_ - target_send_time);
433 target_send_time = last_process_time_;
434 }
Erik Språngd05edec2019-08-14 10:43:47 +0200435 }
436
Erik Språngeb487992019-11-14 14:15:15 +0100437 Timestamp previous_process_time = last_process_time_;
438 TimeDelta elapsed_time = UpdateTimeAndGetElapsed(now);
439
440 if (ShouldSendKeepalive(now)) {
441 // We can not send padding unless a normal packet has first been sent. If
442 // we do, timestamps get messed up.
443 if (packet_counter_ == 0) {
444 last_send_time_ = now;
445 } else {
446 DataSize keepalive_data_sent = DataSize::Zero();
447 std::vector<std::unique_ptr<RtpPacketToSend>> keepalive_packets =
Danil Chapovalovcad3e0e2020-02-17 18:46:07 +0100448 packet_sender_->GeneratePadding(DataSize::Bytes(1));
Erik Språngeb487992019-11-14 14:15:15 +0100449 for (auto& packet : keepalive_packets) {
450 keepalive_data_sent +=
Danil Chapovalovcad3e0e2020-02-17 18:46:07 +0100451 DataSize::Bytes(packet->payload_size() + packet->padding_size());
Erik Språnged1fb192020-06-30 11:53:37 +0000452 packet_sender_->SendPacket(std::move(packet), PacedPacketInfo());
Erik Språng1d50cb62020-07-02 17:41:32 +0200453 for (auto& packet : packet_sender_->FetchFec()) {
454 EnqueuePacket(std::move(packet));
455 }
Erik Språngeb487992019-11-14 14:15:15 +0100456 }
457 OnPaddingSent(keepalive_data_sent);
458 }
459 }
460
461 if (paused_) {
Erik Språngd05edec2019-08-14 10:43:47 +0200462 return;
Erik Språngeb487992019-11-14 14:15:15 +0100463 }
Erik Språngd05edec2019-08-14 10:43:47 +0200464
465 if (elapsed_time > TimeDelta::Zero()) {
466 DataRate target_rate = pacing_bitrate_;
467 DataSize queue_size_data = packet_queue_.Size();
468 if (queue_size_data > DataSize::Zero()) {
469 // Assuming equal size packets and input/output rate, the average packet
470 // has avg_time_left_ms left to get queue_size_bytes out of the queue, if
471 // time constraint shall be met. Determine bitrate needed for that.
Erik Språngeb487992019-11-14 14:15:15 +0100472 packet_queue_.UpdateQueueTime(now);
Erik Språngd05edec2019-08-14 10:43:47 +0200473 if (drain_large_queues_) {
474 TimeDelta avg_time_left =
Danil Chapovalov55284022020-02-07 14:53:52 +0100475 std::max(TimeDelta::Millis(1),
Erik Språngd05edec2019-08-14 10:43:47 +0200476 queue_time_limit - packet_queue_.AverageQueueTime());
477 DataRate min_rate_needed = queue_size_data / avg_time_left;
478 if (min_rate_needed > target_rate) {
479 target_rate = min_rate_needed;
480 RTC_LOG(LS_VERBOSE) << "bwe:large_pacing_queue pacing_rate_kbps="
481 << target_rate.kbps();
482 }
483 }
484 }
485
Erik Språngeb487992019-11-14 14:15:15 +0100486 if (mode_ == ProcessMode::kPeriodic) {
487 // In periodic processing mode, the IntevalBudget allows positive budget
488 // up to (process interval duration) * (target rate), so we only need to
489 // update it once before the packet sending loop.
490 media_budget_.set_target_rate_kbps(target_rate.kbps());
491 UpdateBudgetWithElapsedTime(elapsed_time);
492 } else {
493 media_rate_ = target_rate;
494 }
Erik Språngd05edec2019-08-14 10:43:47 +0200495 }
496
Erik Språng78c82a42019-10-03 18:46:04 +0200497 bool first_packet_in_probe = false;
Erik Språngd05edec2019-08-14 10:43:47 +0200498 PacedPacketInfo pacing_info;
Erik Språngb9d38092020-07-17 12:06:12 +0200499 DataSize recommended_probe_size = DataSize::Zero();
500 bool is_probing = prober_.is_probing();
Erik Språngd05edec2019-08-14 10:43:47 +0200501 if (is_probing) {
Erik Språngb9d38092020-07-17 12:06:12 +0200502 // Probe timing is sensitive, and handled explicitly by BitrateProber, so
503 // use actual send time rather than target.
504 pacing_info = prober_.CurrentCluster(now).value_or(PacedPacketInfo());
505 if (pacing_info.probe_cluster_id != PacedPacketInfo::kNotAProbe) {
506 first_packet_in_probe = pacing_info.probe_cluster_bytes_sent == 0;
507 recommended_probe_size = prober_.RecommendedMinProbeSize();
508 RTC_DCHECK_GT(recommended_probe_size, DataSize::Zero());
509 } else {
510 // No valid probe cluster returned, probe might have timed out.
511 is_probing = false;
512 }
Erik Språngd05edec2019-08-14 10:43:47 +0200513 }
514
515 DataSize data_sent = DataSize::Zero();
Erik Språngeb487992019-11-14 14:15:15 +0100516
Erik Språngd05edec2019-08-14 10:43:47 +0200517 // The paused state is checked in the loop since it leaves the critical
518 // section allowing the paused state to be changed from other code.
519 while (!paused_) {
Erik Språngd7086772020-12-03 16:34:05 +0100520 if (first_packet_in_probe) {
Erik Språng78c82a42019-10-03 18:46:04 +0200521 // If first packet in probe, insert a small padding packet so we have a
522 // more reliable start window for the rate estimation.
Danil Chapovalovcad3e0e2020-02-17 18:46:07 +0100523 auto padding = packet_sender_->GeneratePadding(DataSize::Bytes(1));
Erik Språng78c82a42019-10-03 18:46:04 +0200524 // If no RTP modules sending media are registered, we may not get a
525 // padding packet back.
526 if (!padding.empty()) {
527 // Insert with high priority so larger media packets don't preempt it.
528 EnqueuePacketInternal(std::move(padding[0]), kFirstPriority);
529 // We should never get more than one padding packets with a requested
530 // size of 1 byte.
531 RTC_DCHECK_EQ(padding.size(), 1u);
532 }
533 first_packet_in_probe = false;
534 }
535
Erik Språngeb487992019-11-14 14:15:15 +0100536 if (mode_ == ProcessMode::kDynamic &&
537 previous_process_time < target_send_time) {
538 // Reduce buffer levels with amount corresponding to time between last
539 // process and target send time for the next packet.
540 // If the process call is late, that may be the time between the optimal
541 // send times for two packets we should already have sent.
542 UpdateBudgetWithElapsedTime(target_send_time - previous_process_time);
543 previous_process_time = target_send_time;
544 }
545
546 // Fetch the next packet, so long as queue is not empty or budget is not
547 // exhausted.
Erik Språngb0df5932019-11-18 13:40:24 +0100548 std::unique_ptr<RtpPacketToSend> rtp_packet =
549 GetPendingPacket(pacing_info, target_send_time, now);
550
551 if (rtp_packet == nullptr) {
Erik Språngd05edec2019-08-14 10:43:47 +0200552 // No packet available to send, check if we should send padding.
Erik Språngf5815fa2019-08-21 14:27:31 +0200553 DataSize padding_to_add = PaddingToAdd(recommended_probe_size, data_sent);
554 if (padding_to_add > DataSize::Zero()) {
555 std::vector<std::unique_ptr<RtpPacketToSend>> padding_packets =
556 packet_sender_->GeneratePadding(padding_to_add);
557 if (padding_packets.empty()) {
558 // No padding packets were generated, quite send loop.
559 break;
Erik Språngd05edec2019-08-14 10:43:47 +0200560 }
Erik Språngf5815fa2019-08-21 14:27:31 +0200561 for (auto& packet : padding_packets) {
562 EnqueuePacket(std::move(packet));
563 }
564 // Continue loop to send the padding that was just added.
565 continue;
Erik Språngd05edec2019-08-14 10:43:47 +0200566 }
567
568 // Can't fetch new packet and no padding to send, exit send loop.
569 break;
570 }
571
Erik Språngf5815fa2019-08-21 14:27:31 +0200572 RTC_DCHECK(rtp_packet);
Erik Språngb0df5932019-11-18 13:40:24 +0100573 RTC_DCHECK(rtp_packet->packet_type().has_value());
Björn Terelius31d0f7c2020-02-06 16:35:46 +0100574 const RtpPacketMediaType packet_type = *rtp_packet->packet_type();
Danil Chapovalovcad3e0e2020-02-17 18:46:07 +0100575 DataSize packet_size = DataSize::Bytes(rtp_packet->payload_size() +
Mirko Bonadeie7bc3a32020-01-29 18:45:00 +0000576 rtp_packet->padding_size());
577
578 if (include_overhead_) {
Danil Chapovalovcad3e0e2020-02-17 18:46:07 +0100579 packet_size += DataSize::Bytes(rtp_packet->headers_size()) +
Mirko Bonadeie7bc3a32020-01-29 18:45:00 +0000580 transport_overhead_per_packet_;
581 }
Erik Språnga1888ae2020-07-02 12:02:36 +0000582
Erik Språng1d50cb62020-07-02 17:41:32 +0200583 packet_sender_->SendPacket(std::move(rtp_packet), pacing_info);
584 for (auto& packet : packet_sender_->FetchFec()) {
585 EnqueuePacket(std::move(packet));
586 }
Erik Språngb0df5932019-11-18 13:40:24 +0100587 data_sent += packet_size;
588
589 // Send done, update send/process time to the target send time.
590 OnPacketSent(packet_type, packet_size, target_send_time);
Erik Språngb9d38092020-07-17 12:06:12 +0200591
592 // If we are currently probing, we need to stop the send loop when we have
593 // reached the send target.
Erik Språng7d0cde52020-07-19 12:48:16 +0200594 if (is_probing && data_sent >= recommended_probe_size) {
Erik Språngd05edec2019-08-14 10:43:47 +0200595 break;
Erik Språngb9d38092020-07-17 12:06:12 +0200596 }
Erik Språngeb487992019-11-14 14:15:15 +0100597
598 if (mode_ == ProcessMode::kDynamic) {
599 // Update target send time in case that are more packets that we are late
600 // in processing.
601 Timestamp next_send_time = NextSendTime();
602 if (next_send_time.IsMinusInfinity()) {
603 target_send_time = now;
604 } else {
605 target_send_time = std::min(now, next_send_time);
606 }
607 }
Erik Språngd05edec2019-08-14 10:43:47 +0200608 }
609
Erik Språngb571ff42020-04-04 17:20:37 +0200610 last_process_time_ = std::max(last_process_time_, previous_process_time);
611
Erik Språngd05edec2019-08-14 10:43:47 +0200612 if (is_probing) {
613 probing_send_failure_ = data_sent == DataSize::Zero();
614 if (!probing_send_failure_) {
Erik Språngb9d38092020-07-17 12:06:12 +0200615 prober_.ProbeSent(CurrentTime(), data_sent);
Erik Språngd05edec2019-08-14 10:43:47 +0200616 }
617 }
618}
619
Erik Språngb9d38092020-07-17 12:06:12 +0200620DataSize PacingController::PaddingToAdd(DataSize recommended_probe_size,
621 DataSize data_sent) const {
Erik Språngd05edec2019-08-14 10:43:47 +0200622 if (!packet_queue_.Empty()) {
623 // Actual payload available, no need to add padding.
624 return DataSize::Zero();
625 }
626
627 if (Congested()) {
628 // Don't add padding if congested, even if requested for probing.
629 return DataSize::Zero();
630 }
631
632 if (packet_counter_ == 0) {
633 // We can not send padding unless a normal packet has first been sent. If we
634 // do, timestamps get messed up.
635 return DataSize::Zero();
636 }
637
Erik Språngb9d38092020-07-17 12:06:12 +0200638 if (!recommended_probe_size.IsZero()) {
639 if (recommended_probe_size > data_sent) {
640 return recommended_probe_size - data_sent;
Erik Språngd05edec2019-08-14 10:43:47 +0200641 }
642 return DataSize::Zero();
643 }
644
Erik Språngeb487992019-11-14 14:15:15 +0100645 if (mode_ == ProcessMode::kPeriodic) {
Danil Chapovalovcad3e0e2020-02-17 18:46:07 +0100646 return DataSize::Bytes(padding_budget_.bytes_remaining());
Erik Språngeb487992019-11-14 14:15:15 +0100647 } else if (padding_rate_ > DataRate::Zero() &&
648 padding_debt_ == DataSize::Zero()) {
Erik Språng9acc18d2020-04-16 19:41:07 +0200649 return padding_target_duration_ * padding_rate_;
Erik Språngeb487992019-11-14 14:15:15 +0100650 }
651 return DataSize::Zero();
Erik Språngd05edec2019-08-14 10:43:47 +0200652}
653
Erik Språngb0df5932019-11-18 13:40:24 +0100654std::unique_ptr<RtpPacketToSend> PacingController::GetPendingPacket(
Erik Språngeb487992019-11-14 14:15:15 +0100655 const PacedPacketInfo& pacing_info,
656 Timestamp target_send_time,
657 Timestamp now) {
Erik Språngf660e812019-09-01 12:26:44 +0000658 if (packet_queue_.Empty()) {
659 return nullptr;
660 }
661
Erik Språngeb487992019-11-14 14:15:15 +0100662 // First, check if there is any reason _not_ to send the next queued packet.
663
664 // Unpaced audio packets and probes are exempted from send checks.
Erik Språngb571ff42020-04-04 17:20:37 +0200665 bool unpaced_audio_packet =
666 !pace_audio_ && packet_queue_.LeadingAudioPacketEnqueueTime().has_value();
Erik Språngeb487992019-11-14 14:15:15 +0100667 bool is_probe = pacing_info.probe_cluster_id != PacedPacketInfo::kNotAProbe;
668 if (!unpaced_audio_packet && !is_probe) {
669 if (Congested()) {
Evan Shrubsole6ef59d12020-01-08 16:45:08 +0100670 // Don't send anything if congested.
Erik Språngeb487992019-11-14 14:15:15 +0100671 return nullptr;
672 }
673
674 if (mode_ == ProcessMode::kPeriodic) {
675 if (media_budget_.bytes_remaining() <= 0) {
676 // Not enough budget.
677 return nullptr;
678 }
679 } else {
Erik Språngb1ccae22019-11-25 18:22:09 +0100680 // Dynamic processing mode.
Erik Språngeb487992019-11-14 14:15:15 +0100681 if (now <= target_send_time) {
682 // We allow sending slightly early if we think that we would actually
683 // had been able to, had we been right on time - i.e. the current debt
684 // is not more than would be reduced to zero at the target sent time.
685 TimeDelta flush_time = media_debt_ / media_rate_;
686 if (now + flush_time > target_send_time) {
687 return nullptr;
688 }
Erik Språngeb487992019-11-14 14:15:15 +0100689 }
690 }
Erik Språngf660e812019-09-01 12:26:44 +0000691 }
Erik Språngeb487992019-11-14 14:15:15 +0100692
Erik Språng4995f872019-11-21 18:34:38 +0100693 return packet_queue_.Pop();
Erik Språngf660e812019-09-01 12:26:44 +0000694}
695
Björn Terelius31d0f7c2020-02-06 16:35:46 +0100696void PacingController::OnPacketSent(RtpPacketMediaType packet_type,
Erik Språngb0df5932019-11-18 13:40:24 +0100697 DataSize packet_size,
Erik Språngeb487992019-11-14 14:15:15 +0100698 Timestamp send_time) {
Erik Språngf660e812019-09-01 12:26:44 +0000699 if (!first_sent_packet_time_) {
Erik Språngeb487992019-11-14 14:15:15 +0100700 first_sent_packet_time_ = send_time;
Erik Språngf660e812019-09-01 12:26:44 +0000701 }
Björn Terelius31d0f7c2020-02-06 16:35:46 +0100702 bool audio_packet = packet_type == RtpPacketMediaType::kAudio;
Erik Språngf660e812019-09-01 12:26:44 +0000703 if (!audio_packet || account_for_audio_) {
704 // Update media bytes sent.
Erik Språngb0df5932019-11-18 13:40:24 +0100705 UpdateBudgetWithSentData(packet_size);
Erik Språngf660e812019-09-01 12:26:44 +0000706 }
Erik Språngeb487992019-11-14 14:15:15 +0100707 last_send_time_ = send_time;
708 last_process_time_ = send_time;
Erik Språngf660e812019-09-01 12:26:44 +0000709}
710
Erik Språngd05edec2019-08-14 10:43:47 +0200711void PacingController::OnPaddingSent(DataSize data_sent) {
712 if (data_sent > DataSize::Zero()) {
713 UpdateBudgetWithSentData(data_sent);
Erik Språngd05edec2019-08-14 10:43:47 +0200714 }
Erik Språng7d0cde52020-07-19 12:48:16 +0200715 Timestamp now = CurrentTime();
716 last_send_time_ = now;
717 last_process_time_ = now;
Erik Språngd05edec2019-08-14 10:43:47 +0200718}
719
720void PacingController::UpdateBudgetWithElapsedTime(TimeDelta delta) {
Erik Språngeb487992019-11-14 14:15:15 +0100721 if (mode_ == ProcessMode::kPeriodic) {
722 delta = std::min(kMaxProcessingInterval, delta);
723 media_budget_.IncreaseBudget(delta.ms());
724 padding_budget_.IncreaseBudget(delta.ms());
725 } else {
726 media_debt_ -= std::min(media_debt_, media_rate_ * delta);
727 padding_debt_ -= std::min(padding_debt_, padding_rate_ * delta);
728 }
Erik Språngd05edec2019-08-14 10:43:47 +0200729}
730
731void PacingController::UpdateBudgetWithSentData(DataSize size) {
732 outstanding_data_ += size;
Erik Språngeb487992019-11-14 14:15:15 +0100733 if (mode_ == ProcessMode::kPeriodic) {
734 media_budget_.UseBudget(size.bytes());
735 padding_budget_.UseBudget(size.bytes());
736 } else {
737 media_debt_ += size;
738 media_debt_ = std::min(media_debt_, media_rate_ * kMaxDebtInTime);
739 padding_debt_ += size;
740 padding_debt_ = std::min(padding_debt_, padding_rate_ * kMaxDebtInTime);
741 }
Erik Språngd05edec2019-08-14 10:43:47 +0200742}
743
744void PacingController::SetQueueTimeLimit(TimeDelta limit) {
745 queue_time_limit = limit;
746}
747
748} // namespace webrtc