blimp: Add logging for compositor and render widget feature.

cc/trees/remote_channel_impl.cc

 // Copyright 2016 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 "cc/trees/remote_channel_impl.h"
 
 #include "base/bind_helpers.h"
 #include "base/single_thread_task_runner.h"
 #include "cc/animation/animation_events.h"
 #include "cc/proto/compositor_message.pb.h"
@@ skipping 45 matching lines @@
 
 void RemoteChannelImpl::OnProtoReceived(
     scoped_ptr<proto::CompositorMessage> proto) {
   DCHECK(task_runner_provider_->IsMainThread());
   DCHECK(main().started);
   DCHECK(proto->has_to_impl());
 
   // If we don't have an output surface, queue the message and defer processing
   // it till we initialize a new output surface.
   if (main().waiting_for_output_surface_initialization) {
+    VLOG(1) << "Queueing message proto since output surface was released.";
     main().pending_messages.push(proto->to_impl());
   } else {
     HandleProto(proto->to_impl());
   }
 }
 
 void RemoteChannelImpl::HandleProto(
     const proto::CompositorMessageToImpl& proto) {
   DCHECK(task_runner_provider_->IsMainThread());
   DCHECK(proto.has_message_type());
   DCHECK(!main().waiting_for_output_surface_initialization);
 
   switch (proto.message_type()) {
     case proto::CompositorMessageToImpl::UNKNOWN:
       NOTIMPLEMENTED() << "Ignoring message of UNKNOWN type";
       break;
     case proto::CompositorMessageToImpl::INITIALIZE_IMPL:
       NOTREACHED() << "Should be handled by the embedder";
       break;
     case proto::CompositorMessageToImpl::CLOSE_IMPL:
       NOTREACHED() << "Should be handled by the embedder";
       break;
     case proto::CompositorMessageToImpl::
         MAIN_THREAD_HAS_STOPPED_FLINGING_ON_IMPL:
       ImplThreadTaskRunner()->PostTask(
           FROM_HERE, base::Bind(&ProxyImpl::MainThreadHasStoppedFlingingOnImpl,
                                 proxy_impl_weak_ptr_));
       break;
     case proto::CompositorMessageToImpl::SET_NEEDS_COMMIT:
+      VLOG(1) << "Received commit request from the engine.";
       ImplThreadTaskRunner()->PostTask(
           FROM_HERE,
           base::Bind(&ProxyImpl::SetNeedsCommitOnImpl, proxy_impl_weak_ptr_));
       break;
     case proto::CompositorMessageToImpl::SET_DEFER_COMMITS: {
       const proto::SetDeferCommits& defer_commits_message =
           proto.defer_commits_message();
       bool defer_commits = defer_commits_message.defer_commits();
+      VLOG(1) << "Received set defer commits to: " << defer_commits
+              << " from the engine.";
       ImplThreadTaskRunner()->PostTask(
           FROM_HERE, base::Bind(&ProxyImpl::SetDeferCommitsOnImpl,
                                 proxy_impl_weak_ptr_, defer_commits));
     } break;
     case proto::CompositorMessageToImpl::START_COMMIT: {
+      VLOG(1) << "Received commit proto from the engine.";
       base::TimeTicks main_thread_start_time = base::TimeTicks::Now();
       const proto::StartCommit& start_commit_message =
           proto.start_commit_message();
 
       main().layer_tree_host->FromProtobufForCommit(
           start_commit_message.layer_tree_host());
       {
         DebugScopedSetMainThreadBlocked main_thread_blocked(
             task_runner_provider_);
         CompletionEvent completion;
+        VLOG(1) << "Starting commit.";
         ImplThreadTaskRunner()->PostTask(
             FROM_HERE,
             base::Bind(&ProxyImpl::StartCommitOnImpl, proxy_impl_weak_ptr_,
                        &completion, main().layer_tree_host,
                        main_thread_start_time, false));
         completion.Wait();
       }
     } break;
     case proto::CompositorMessageToImpl::BEGIN_MAIN_FRAME_ABORTED: {
       base::TimeTicks main_thread_start_time = base::TimeTicks::Now();
       const proto::BeginMainFrameAborted& begin_main_frame_aborted_message =
           proto.begin_main_frame_aborted_message();
       CommitEarlyOutReason reason = CommitEarlyOutReasonFromProtobuf(
           begin_main_frame_aborted_message.reason());
+      VLOG(1) << "Received BeginMainFrameAborted from the engine with reason: "
+              << CommitEarlyOutReasonToString(reason);
       ImplThreadTaskRunner()->PostTask(
           FROM_HERE,
           base::Bind(&ProxyImpl::BeginMainFrameAbortedOnImpl,
                      proxy_impl_weak_ptr_, reason, main_thread_start_time));
     } break;
     case proto::CompositorMessageToImpl::SET_NEEDS_REDRAW: {
+      VLOG(1) << "Received redraw request from the engine.";
       const proto::SetNeedsRedraw& set_needs_redraw_message =
           proto.set_needs_redraw_message();
       gfx::Rect damaged_rect =
           ProtoToRect(set_needs_redraw_message.damaged_rect());
       PostSetNeedsRedrawToImpl(damaged_rect);
     } break;
   }
 }
 
 void RemoteChannelImpl::FinishAllRendering() {
@@ skipping 13 matching lines @@
   DCHECK(task_runner_provider_->IsMainThread());
 
   ImplThreadTaskRunner()->PostTask(
       FROM_HERE, base::Bind(&ProxyImpl::InitializeOutputSurfaceOnImpl,
                             proxy_impl_weak_ptr_, output_surface));
 }
 
 void RemoteChannelImpl::ReleaseOutputSurface() {
   DCHECK(task_runner_provider_->IsMainThread());
   DCHECK(!main().waiting_for_output_surface_initialization);
+  VLOG(1) << "Releasing Output Surface";
 
   {
     CompletionEvent completion;
     DebugScopedSetMainThreadBlocked main_thread_blocked(task_runner_provider_);
     ImplThreadTaskRunner()->PostTask(
         FROM_HERE, base::Bind(&ProxyImpl::ReleaseOutputSurfaceOnImpl,
                               proxy_impl_weak_ptr_, &completion));
     completion.Wait();
   }
 
   main().waiting_for_output_surface_initialization = true;
 }
 
 void RemoteChannelImpl::SetVisible(bool visible) {
   DCHECK(task_runner_provider_->IsMainThread());
+  VLOG(1) << "Setting visibility to: " << visible;
 
   ImplThreadTaskRunner()->PostTask(
       FROM_HERE,
       base::Bind(&ProxyImpl::SetVisibleOnImpl, proxy_impl_weak_ptr_, visible));
 }
 
 const RendererCapabilities& RemoteChannelImpl::GetRendererCapabilities() const {
   NOTREACHED() << "Should not be called on the remote client LayerTreeHost";
   return main().renderer_capabilities;
 }
@@ skipping 212 matching lines @@
 void RemoteChannelImpl::DidInitializeOutputSurfaceOnMain(
     bool success,
     const RendererCapabilities& capabilities) {
   DCHECK(task_runner_provider_->IsMainThread());
 
   if (!success) {
     main().layer_tree_host->DidFailToInitializeOutputSurface();
     return;
   }
 
+  VLOG(1) << "OutputSurface initialized successfully";
   main().renderer_capabilities = capabilities;
   main().layer_tree_host->DidInitializeOutputSurface();
 
   // If we were waiting for output surface initialization, we might have queued
   // some messages. Relay them now that a new output surface has been
   // initialized.
   main().waiting_for_output_surface_initialization = false;
   while (!main().pending_messages.empty()) {
+    VLOG(1) << "Handling queued message";
     HandleProto(main().pending_messages.front());
     main().pending_messages.pop();
   }
 
   // The commit after a new output surface can early out, in which case we will
   // never redraw. Schedule one just to be safe.
   PostSetNeedsRedrawToImpl(
       gfx::Rect(main().layer_tree_host->device_viewport_size()));
 }
 
 void RemoteChannelImpl::SendMessageProtoOnMain(
     scoped_ptr<proto::CompositorMessage> proto) {
   DCHECK(task_runner_provider_->IsMainThread());
+  VLOG(1) << "Sending BeginMainFrame request to the engine.";
 
   main().remote_proto_channel->SendCompositorProto(*proto);
 }
 
 void RemoteChannelImpl::PostSetNeedsRedrawToImpl(
     const gfx::Rect& damaged_rect) {
   DCHECK(task_runner_provider_->IsMainThread());
 
   ImplThreadTaskRunner()->PostTask(
       FROM_HERE, base::Bind(&ProxyImpl::SetNeedsRedrawOnImpl,
@@ skipping 71 matching lines @@
 
 RemoteChannelImpl::CompositorThreadOnly::CompositorThreadOnly(
     base::WeakPtr<RemoteChannelImpl> remote_channel_weak_ptr)
     : proxy_impl(nullptr),
       proxy_impl_weak_factory(nullptr),
       remote_channel_weak_ptr(remote_channel_weak_ptr) {}
 
 RemoteChannelImpl::CompositorThreadOnly::~CompositorThreadOnly() {}
 
 }  // namespace cc