Add UMA/Telemetry stats for touch event latency

content/browser/renderer_host/render_widget_host_impl.cc

 // Copyright (c) 2012 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 "content/browser/renderer_host/render_widget_host_impl.h"
 
 #include <math.h>
 #include <utility>
 
 #include "base/auto_reset.h"
@@ skipping 1096 matching lines @@
     const WebKit::WebGestureEvent& gesture_event,
     const ui::LatencyInfo& ui_latency) {
   TRACE_EVENT0("input", "RenderWidgetHostImpl::ForwardGestureEvent");
   if (ignore_input_events_ || process_->IgnoreInputEvents())
     return;
 
   ui::LatencyInfo latency_info;
   // In Aura, gesture event will carry its original touch event's
   // INPUT_EVENT_LATENCY_RWH_COMPONENT. For non-aura platform, we add the
   // INPUT_EVENT_LATENCY_RWH_COMPONENT right here.
-  if (!ui_latency.HasLatencyComponent(ui::INPUT_EVENT_LATENCY_RWH_COMPONENT,
-                                      GetLatencyComponentId()))
+  if (!ui_latency.FindLatency(ui::INPUT_EVENT_LATENCY_RWH_COMPONENT,
+                              GetLatencyComponentId(),
+                              NULL))
     latency_info = NewInputLatencyInfo();
 
   latency_info.AddLatencyNumber(ui::INPUT_EVENT_LATENCY_INJECTED_RWH_COMPONENT,
                                 GetLatencyComponentId(),
                                 ++last_input_number_);
   latency_info.MergeWith(ui_latency);
 
   if (!IsInOverscrollGesture() &&
       !gesture_event_filter_->ShouldForward(
           GestureEventWithLatencyInfo(gesture_event, latency_info))) {
@@ skipping 160 matching lines @@
   info.AddLatencyNumber(ui::INPUT_EVENT_LATENCY_RWH_COMPONENT,
                         GetLatencyComponentId(),
                         ++last_input_number_);
   return info;
 }
 
 void RenderWidgetHostImpl::SendInputEvent(const WebInputEvent& input_event,
                                           int event_size,
                                           const ui::LatencyInfo& latency_info,
                                           bool is_keyboard_shortcut) {
-  DCHECK(latency_info.HasLatencyComponent(ui::INPUT_EVENT_LATENCY_RWH_COMPONENT,
-                                          GetLatencyComponentId()));
+  DCHECK(latency_info.FindLatency(ui::INPUT_EVENT_LATENCY_RWH_COMPONENT,
+                                  GetLatencyComponentId(),
+                                  NULL));
   input_event_start_time_ = TimeTicks::Now();
   Send(new InputMsg_HandleInputEvent(
       routing_id_, &input_event, latency_info, is_keyboard_shortcut));
   increment_in_flight_event_count();
 }
 
 void RenderWidgetHostImpl::ForwardInputEvent(
     const WebInputEvent& input_event, int event_size,
     const ui::LatencyInfo& latency_info, bool is_keyboard_shortcut) {
   TRACE_EVENT0("input", "RenderWidgetHostImpl::ForwardInputEvent");
@@ skipping 1266 matching lines @@
   if (!should_auto_resize_)
     return;
 
   OnRenderAutoResized(new_size);
 }
 
 void RenderWidgetHostImpl::DetachDelegate() {
   delegate_ = NULL;
 }
 
+void RenderWidgetHostImpl::ComputeTouchLatency(
+    const ui::LatencyInfo& latency_info) {
+  ui::LatencyInfo::LatencyComponent ui_component;
+  ui::LatencyInfo::LatencyComponent rwh_component;
+  ui::LatencyInfo::LatencyComponent acked_component;
+
+  if (!latency_info.FindLatency(ui::INPUT_EVENT_LATENCY_UI_COMPONENT,
+                                0,
+                                &ui_component) ||
+      !latency_info.FindLatency(ui::INPUT_EVENT_LATENCY_RWH_COMPONENT,
+                                GetLatencyComponentId(),
+                                &rwh_component))
+    return;
+
+  DCHECK(ui_component.event_count == 1);
+  DCHECK(rwh_component.event_count == 1);
+
+  base::TimeDelta ui_delta =
+      rwh_component.event_time - ui_component.event_time;
+  rendering_stats_.touch_ui_count++;
+  rendering_stats_.total_touch_ui_latency += ui_delta;
+  UMA_HISTOGRAM_CUSTOM_COUNTS(
+      "Event.Latency.Browser.TouchUI",
+      ui_delta.InMicroseconds(),
+      0,
+      20000,
+      100);
+
+  if(!latency_info.FindLatency(ui::INPUT_EVENT_LATENCY_ACKED_COMPONENT,

[sadrul, 2013/06/27 22:40:12]

ifspace(

[Yufeng Shen (Slow to review), 2013/06/27 23:44:07]

Done.

+                               0,
+                               &acked_component))
+    return;

[sadrul, 2013/06/27 22:40:12]

Do you not want to send the IPC below in this case (because you are changing
some bits of |rendering_stats_| above)?

[Yufeng Shen (Slow to review), 2013/06/27 23:44:07]

right, done.

+  DCHECK(acked_component.event_count == 1);
+  base::TimeDelta acked_delta =
+      acked_component.event_time - rwh_component.event_time;
+  rendering_stats_.touch_acked_count++;
+  rendering_stats_.total_touch_acked_latency += acked_delta;
+  UMA_HISTOGRAM_CUSTOM_COUNTS(
+      "Event.Latency.Browser.TouchAcked",
+      acked_delta.InMicroseconds(),
+      0,
+      1000000,
+      100);
+
+  if (CommandLine::ForCurrentProcess()->HasSwitch(
+          switches::kEnableGpuBenchmarking))
+    Send(new ViewMsg_SetBrowserRenderingStats(routing_id_, rendering_stats_));

[sadrul, 2013/06/27 22:40:12]

I don't know what this does. I will leave this part for jbauman@ to review.

+}
+
 void RenderWidgetHostImpl::FrameSwapped(const ui::LatencyInfo& latency_info) {
   ui::LatencyInfo::LatencyMap::const_iterator l =
       latency_info.latency_components.find(std::make_pair(
           ui::INPUT_EVENT_LATENCY_RWH_COMPONENT, GetLatencyComponentId()));
   if (l == latency_info.latency_components.end())
     return;
 
   rendering_stats_.input_event_count += l->second.event_count;
   rendering_stats_.total_input_latency +=
       l->second.event_count *
@@ skipping 18 matching lines @@
     int process_id = (b->first.second >> 32) & 0xffffffff;
     RenderWidgetHost* rwh =
         RenderWidgetHost::FromID(process_id, routing_id);
     if (!rwh)
       continue;
     RenderWidgetHostImpl::From(rwh)->FrameSwapped(latency_info);
   }
 }
 
 }  // namespace content