Add trace events to measure GPU process startup timing and time to first present.

content/gpu/gpu_main.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 <stdlib.h>
 
 #if defined(OS_WIN)
 #include <windows.h>
 #endif
 
+#include "base/debug/trace_event.h"
 #include "base/environment.h"
 #include "base/message_loop.h"
 #include "base/rand_util.h"
 #include "base/stringprintf.h"
 #include "base/threading/platform_thread.h"
 #include "base/win/scoped_com_initializer.h"
 #include "build/build_config.h"
 #include "content/common/gpu/gpu_config.h"
 #include "content/gpu/gpu_child_thread.h"
 #include "content/gpu/gpu_info_collector.h"
@@ skipping 13 matching lines @@
 #if defined(USE_X11)
 #include "ui/base/x/x11_util.h"
 #endif
 
 #if defined(OS_LINUX)
 #include "content/public/common/sandbox_init.h"
 #endif
 
 // Main function for starting the Gpu process.
 int GpuMain(const content::MainFunctionParams& parameters) {
+  TRACE_EVENT0("gpu", "GpuMain");
+
   base::Time start_time = base::Time::Now();
 
   const CommandLine& command_line = parameters.command_line;
   if (command_line.HasSwitch(switches::kGpuStartupDialog)) {
     ChildProcess::WaitForDebugger("Gpu");
   }
 
   if (!command_line.HasSwitch(switches::kSingleProcess)) {
 #if defined(OS_WIN)
     // Prevent Windows from displaying a modal dialog on failures like not being
@@ skipping 39 matching lines @@
 #endif
 
     // Set the GPU info even if it failed.
     content::GetContentClient()->SetGpuInfo(gpu_info);
   } else {
     LOG(INFO) << "gfx::GLSurface::InitializeOneOff failed";
     gpu_info.gpu_accessible = false;
     dead_on_arrival = true;
   }
 
-  // Warm up the random subsystem, which needs to be done pre-sandbox on all
-  // platforms.
-  (void) base::RandUint64();
-
-  // Warm up the crypto subsystem, which needs to done pre-sandbox on all
-  // platforms.
-  crypto::HMAC hmac(crypto::HMAC::SHA256);
-  unsigned char key = '\0';
-  bool ret = hmac.Init(&key, sizeof(key));
-  (void) ret;
+  {
+    TRACE_EVENT0("gpu", "Warm up rand");
+    // Warm up the random subsystem, which needs to be done pre-sandbox on all
+    // platforms.
+    (void) base::RandUint64();
+  }
+  {
+    TRACE_EVENT0("gpu", "Warm up HMAC");
+    // Warm up the crypto subsystem, which needs to done pre-sandbox on all
+    // platforms.
+    crypto::HMAC hmac(crypto::HMAC::SHA256);
+    unsigned char key = '\0';
+    bool ret = hmac.Init(&key, sizeof(key));
+    (void) ret;
+  }
 
 #if defined(OS_LINUX) && !defined(OS_CHROMEOS)
-  content::InitializeSandbox();
+  {
+    TRACE_EVENT0("gpu", "Initialize sandbox");
+    content::InitializeSandbox();
+  }
 #endif
 
-  base::win::ScopedCOMInitializer com_initializer;
+  {
+    TRACE_EVENT0("gpu", "Initialize COM");
+    base::win::ScopedCOMInitializer com_initializer;
+  }
 
 #if defined(OS_WIN)
-  // Preload this DLL because the sandbox prevents it from loading.
-  LoadLibrary(L"setupapi.dll");
+  {
+    TRACE_EVENT0("gpu", "Preload setupapi.dll");
+    // Preload this DLL because the sandbox prevents it from loading.
+    LoadLibrary(L"setupapi.dll");
+  }
 
-  sandbox::TargetServices* target_services =
-      parameters.sandbox_info->target_services;
-  // Initialize H/W video decoding stuff which fails in the sandbox.
-  DXVAVideoDecodeAccelerator::PreSandboxInitialization();
-  // For windows, if the target_services interface is not zero, the process
-  // is sandboxed and we must call LowerToken() before rendering untrusted
-  // content.
-  if (target_services)
-    target_services->LowerToken();
+  {
+    TRACE_EVENT0("gpu", "Initialize DXVA");
+    // Initialize H/W video decoding stuff which fails in the sandbox.
+    DXVAVideoDecodeAccelerator::PreSandboxInitialization();
+  }
+
+  {
+    TRACE_EVENT0("gpu", "Lower token");
+    // For windows, if the target_services interface is not zero, the process
+    // is sandboxed and we must call LowerToken() before rendering untrusted
+    // content.
+    sandbox::TargetServices* target_services =
+        parameters.sandbox_info->target_services;
+    if (target_services)
+      target_services->LowerToken();
+  }
 #endif
 
   MessageLoop::Type message_loop_type = MessageLoop::TYPE_UI;
 #if defined(OS_WIN)
   // Unless we're running on desktop GL, we don't need a UI message
   // loop, so avoid its use to work around apparent problems with some
   // third-party software.
   message_loop_type = MessageLoop::TYPE_IO;
   if (command_line.HasSwitch(switches::kUseGL) &&
       command_line.GetSwitchValueASCII(switches::kUseGL) ==
           gfx::kGLImplementationDesktopName) {
       message_loop_type = MessageLoop::TYPE_UI;
   }
 #elif defined(OS_LINUX)
   message_loop_type = MessageLoop::TYPE_DEFAULT;
 #endif
 
   MessageLoop main_message_loop(message_loop_type);
   base::PlatformThread::SetName("CrGpuMain");
 
   GpuProcess gpu_process;
 
   GpuChildThread* child_thread = new GpuChildThread(dead_on_arrival, gpu_info);
 
   child_thread->Init(start_time);
 
   gpu_process.set_main_thread(child_thread);
 
-  main_message_loop.Run();
+  {
+    TRACE_EVENT0("gpu", "Run Message Loop");
+    main_message_loop.Run();
+  }
 
   child_thread->StopWatchdog();
 
   return 0;
 }