More logs to debug AVPrerollVideoEndsWhilePrerolling on bot

media/base/android/media_codec_decoder.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 "media/base/android/media_codec_decoder.h"
 
 #include "base/bind.h"
 #include "base/bind_helpers.h"
 #include "base/callback_helpers.h"
 #include "base/logging.h"
@@ skipping 373 matching lines @@
       break;
     default:
       NOTREACHED();
       break;
   }
 }
 
 void MediaCodecDecoder::OnLastFrameRendered(bool eos_encountered) {
   DCHECK(media_task_runner_->BelongsToCurrentThread());
 
-  DVLOG(1) << class_name() << "::" << __FUNCTION__
+  // http://crbug.com/526755
+  DVLOG(0) << class_name() << "::" << __FUNCTION__
            << " eos_encountered:" << eos_encountered;
 
   decoder_thread_.Stop();  // synchronous
 
   SetState(kStopped);
   completed_ = (eos_encountered && !drain_decoder_);
 
   if (completed_ && !preroll_done_cb_.is_null()) {
     // http://crbug.com/526755
     DVLOG(0) << class_name() << "::" << __FUNCTION__
@@ skipping 70 matching lines @@
 void MediaCodecDecoder::SetAlwaysReconfigureForTests() {
   // UI task runner.
   always_reconfigure_for_tests_ = true;
 }
 
 void MediaCodecDecoder::SetCodecCreatedCallbackForTests(base::Closure cb) {
   // UI task runner.
   codec_created_for_tests_cb_ = cb;
 }
 
+// http://crbug.com/526755
+void MediaCodecDecoder::SetVerboseForTests(bool value) {
+  // UI task runner.
+  verbose_ = value;
+}
+
 int MediaCodecDecoder::NumDelayedRenderTasks() const {
   return 0;
 }
 
 void MediaCodecDecoder::DoEmergencyStop() {
   DCHECK(media_task_runner_->BelongsToCurrentThread());
   DVLOG(1) << class_name() << "::" << __FUNCTION__;
 
   // After this method returns, decoder thread will not be running.
 
   // Set [kInEmergencyStop| state to block already posted ProcessNextFrame().
   SetState(kInEmergencyStop);
 
   decoder_thread_.Stop();  // synchronous
 
   SetState(kStopped);
 }
 
 void MediaCodecDecoder::CheckLastFrame(bool eos_encountered,
                                        bool has_delayed_tasks) {
   DCHECK(decoder_thread_.task_runner()->BelongsToCurrentThread());
 
   bool last_frame_when_stopping = GetState() == kStopping && !has_delayed_tasks;
 
   if (last_frame_when_stopping || eos_encountered) {
+    if (verbose_) {
+      DVLOG(0) << class_name() << "::" << __FUNCTION__
+               << " last_frame_when_stopping:" << last_frame_when_stopping
+               << " eos_encountered:" << eos_encountered
+               << ", posting MediaCodecDecoder::OnLastFrameRendered";
+    }
+
     media_task_runner_->PostTask(
         FROM_HERE, base::Bind(&MediaCodecDecoder::OnLastFrameRendered,
                               weak_factory_.GetWeakPtr(), eos_encountered));
     last_frame_posted_ = true;
   }
 }
 
 void MediaCodecDecoder::OnCodecError() {
   DCHECK(media_task_runner_->BelongsToCurrentThread());
 
@@ skipping 46 matching lines @@
 
   DecoderState state = GetState();
 
   if (state != kPrerolling && state != kRunning && state != kStopping) {
     DVLOG(1) << class_name() << "::" << __FUNCTION__ << ": not running";
     return;
   }
 
   if (state == kStopping) {
     if (NumDelayedRenderTasks() == 0 && !last_frame_posted_) {
-      DVLOG(1) << class_name() << "::" << __FUNCTION__
-               << ": kStopping, posting OnLastFrameRendered";
+      // http://crbug.com/526755
+      DVLOG(0) << class_name() << "::" << __FUNCTION__
+               << ": kStopping, no delayed tasks, posting OnLastFrameRendered";
       media_task_runner_->PostTask(
           FROM_HERE, base::Bind(&MediaCodecDecoder::OnLastFrameRendered,
                                 weak_factory_.GetWeakPtr(), false));
       last_frame_posted_ = true;
     }
 
     // We can stop processing, the |au_queue_| and MediaCodec queues can freeze.
     // We only need to let finish the delayed rendering tasks.
     return;
   }
@@ skipping 76 matching lines @@
   DVLOG(2) << class_name() << ":: DequeueInputBuffer index:" << index;
 
   switch (status) {
     case MEDIA_CODEC_ERROR:
       DVLOG(0) << class_name() << "::" << __FUNCTION__
                << ": MEDIA_CODEC_ERROR DequeueInputBuffer failed";
       media_task_runner_->PostTask(FROM_HERE, internal_error_cb_);
       return false;
 
     case MEDIA_CODEC_DEQUEUE_INPUT_AGAIN_LATER:
-      DVLOG(0)
+      DVLOG(2)
           << class_name() << "::" << __FUNCTION__
           << ": DequeueInputBuffer returned MediaCodec.INFO_TRY_AGAIN_LATER.";
       return true;
 
     default:
       break;
   }
 
   // We got the buffer
   DCHECK_EQ(status, MEDIA_CODEC_OK);
@@ skipping 205 matching lines @@
     RETURN_STRING(kStopping);
     RETURN_STRING(kInEmergencyStop);
     RETURN_STRING(kError);
   }
   return nullptr;  // crash early
 }
 
 #undef RETURN_STRING
 
 }  // namespace media