[Chromecast] Log sample rate changes and tweak audio logging output.

chromecast/media/cma/backend/alsa/stream_mixer_alsa_input_impl.cc

 // Copyright 2015 The Chromium Authors. All rights reserved.
 // Use of this source code is governed by a BSD-style license that can be
 // found in the LICENSE file.
 
 #include "chromecast/media/cma/backend/alsa/stream_mixer_alsa_input_impl.h"
 
 #include <algorithm>
 
 #include "base/bind.h"
 #include "base/bind_helpers.h"
@@ skipping 58 matching lines @@
       caller_task_runner_(base::ThreadTaskRunnerHandle::Get()),
       state_(kStateUninitialized),
       volume_multiplier_(1.0f),
       queued_frames_(0),
       queued_frames_including_resampler_(0),
       current_buffer_offset_(0),
       max_queued_frames_(kMaxInputQueueUs * input_samples_per_second /
                          base::Time::kMicrosecondsPerSecond),
       fade_frames_remaining_(0),
       fade_out_frames_total_(0),
+      zeroed_frames_(0),
       weak_factory_(this) {
   DCHECK(delegate_);
   DCHECK(mixer_);
   weak_this_ = weak_factory_.GetWeakPtr();
 }
 
 StreamMixerAlsaInputImpl::~StreamMixerAlsaInputImpl() {
   DCHECK(mixer_task_runner_->BelongsToCurrentThread());
 }
 
@@ skipping 267 matching lines @@
       const float* buffer_samples =
           reinterpret_cast<const float*>(buffer->data());
       for (int i = 0; i < kNumOutputChannels; ++i) {
         const float* buffer_channel = buffer_samples + (buffer_frames * i);
         memcpy(output->channel(i) + frames_filled,
                buffer_channel + buffer_offset,
                frames_to_copy * sizeof(float));
       }
       frames_left -= frames_to_copy;
       frames_filled += frames_to_copy;
+      LOG_IF(WARNING, zeroed_frames_ > 0)
+          << "Filled a total of " << zeroed_frames_ << " frames with 0";
+      zeroed_frames_ = 0;
     } else {
       // No data left in queue; fill remaining frames with zeros.
-      LOG(WARNING) << "Filling " << frames_left << " frames with 0";
+      LOG_IF(WARNING, zeroed_frames_ == 0) << "Starting to fill frames with 0";
+      zeroed_frames_ += frames_left;
       output->ZeroFramesPartial(frames_filled, frames_left);
       frames_filled += frames_left;
       frames_left = 0;
       break;
     }
   }
 
   DCHECK_EQ(0, frames_left);
   DCHECK_EQ(frames, frames_filled);
 }
@@ skipping 64 matching lines @@
   DCHECK(!IsDeleting());
   if (paused) {
     if (state_ == kStateUninitialized) {
       state_ = kStatePaused;
     } else if (state_ == kStateNormalPlayback) {
       fade_frames_remaining_ = NormalFadeFrames() - fade_frames_remaining_;
       state_ = (fade_frames_remaining_ ? kStateFadingOut : kStatePaused);
     } else {
       return;
     }
-    LOG(INFO) << "Pausing";
+    LOG(INFO) << "Pausing " << this;
   } else {
     if (state_ == kStateFadingOut) {
       fade_frames_remaining_ = NormalFadeFrames() - fade_frames_remaining_;
     } else if (state_ == kStatePaused) {
       fade_frames_remaining_ = NormalFadeFrames();
     } else {
       return;
     }
-    LOG(INFO) << "Unpausing";
+    LOG(INFO) << "Unpausing " << this;
     state_ = kStateNormalPlayback;
   }
   DCHECK_GE(fade_frames_remaining_, 0);
 
   if (state_ == kStateFadingOut) {
     // Tell the mixer that some more data might be available (since when fading
     // out, we can drain the queue completely).
     mixer_->OnFramesQueued();
   }
 }
 
 void StreamMixerAlsaInputImpl::SetVolumeMultiplier(float multiplier) {
   RUN_ON_MIXER_THREAD(SetVolumeMultiplier, multiplier);
   DCHECK(!IsDeleting());
   if (multiplier > 1.0f)
     multiplier = 1.0f;
   if (multiplier < 0.0f)
     multiplier = 0.0f;
   volume_multiplier_ = multiplier;
 }
 
 }  // namespace media
 }  // namespace chromecast