ServiceWorker: Insert TRACE_EVENT to watch a breakdown of the ServiceWorker's performance on chome:…

content/renderer/service_worker/embedded_worker_context_client.cc

 // Copyright 2013 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/renderer/service_worker/embedded_worker_context_client.h"
 
 #include <map>
 #include <string>
 
+#include "base/debug/trace_event.h"
 #include "base/lazy_instance.h"
 #include "base/message_loop/message_loop_proxy.h"
 #include "base/pickle.h"
 #include "base/strings/string16.h"
 #include "base/strings/utf_string_conversions.h"
 #include "base/threading/thread_local.h"
 #include "content/child/request_extra_data.h"
 #include "content/child/service_worker/service_worker_network_provider.h"
 #include "content/child/thread_safe_sender.h"
 #include "content/child/worker_task_runner.h"
@@ skipping 72 matching lines @@
     const GURL& script_url,
     int worker_devtools_agent_route_id)
     : embedded_worker_id_(embedded_worker_id),
       service_worker_version_id_(service_worker_version_id),
       service_worker_scope_(service_worker_scope),
       script_url_(script_url),
       worker_devtools_agent_route_id_(worker_devtools_agent_route_id),
       sender_(ChildThread::current()->thread_safe_sender()),
       main_thread_proxy_(base::MessageLoopProxy::current()),
       weak_factory_(this) {
+  TRACE_EVENT_ASYNC_BEGIN1("ServiceWorker",
+                           "EmbeddedWorkerContextClient",
+                           this,
+                           "Info",
+                           "Major component are the load time of SW script "

[horo, 2014/09/05 11:02:32]

I think we don't need this info.

[shimazu, 2014/09/08 07:04:46]

Done.

+                           "and preparation of execution environments.");
+  TRACE_EVENT_ASYNC_STEP_INTO0("ServiceWorker",
+                               "EmbeddedWorkerContextClient",
+                               this,
+                               "PrepareWorker");
 }
 
 EmbeddedWorkerContextClient::~EmbeddedWorkerContextClient() {
 }
 
 bool EmbeddedWorkerContextClient::OnMessageReceived(
     const IPC::Message& msg) {
   bool handled = true;
   IPC_BEGIN_MESSAGE_MAP(EmbeddedWorkerContextClient, msg)
     IPC_MESSAGE_HANDLER(EmbeddedWorkerContextMsg_MessageToWorker,
@@ skipping 57 matching lines @@
       embedded_worker_id_,
       WorkerTaskRunner::Instance()->CurrentWorkerId()));
 
   // Schedule a task to send back WorkerStarted asynchronously,
   // so that at the time we send it we can be sure that the worker
   // script has been evaluated and worker run loop has been started.
   worker_task_runner_->PostTask(
       FROM_HERE,
       base::Bind(&EmbeddedWorkerContextClient::SendWorkerStarted,
                  weak_factory_.GetWeakPtr()));
+  TRACE_EVENT_ASYNC_STEP_INTO1(
+      "ServiceWorker",
+      "EmbeddedWorkerContextClient",
+      this,
+      "ExecuteScript",
+      "Info", "Major component is the execution time of SW script.");

[horo, 2014/09/05 11:02:32]

I think we don't need this info.

[shimazu, 2014/09/08 07:04:46]

Done.

 }
 
 void EmbeddedWorkerContextClient::willDestroyWorkerContext() {
   // At this point OnWorkerRunLoopStopped is already called, so
   // worker_task_runner_->RunsTasksOnCurrentThread() returns false
   // (while we're still on the worker thread).
   script_context_.reset();
 
   // This also lets the message filter stop dispatching messages to
   // this client.
@@ skipping 134 matching lines @@
     int embedded_worker_id,
     const IPC::Message& message) {
   if (!script_context_)
     return;
   DCHECK_EQ(embedded_worker_id_, embedded_worker_id);
   script_context_->OnMessageReceived(message);
 }
 
 void EmbeddedWorkerContextClient::SendWorkerStarted() {
   DCHECK(worker_task_runner_->RunsTasksOnCurrentThread());
+  TRACE_EVENT_ASYNC_END0("ServiceWorker",
+                         "EmbeddedWorkerContextClient",

[horo, 2014/09/05 11:02:32]

EmbeddedWorkerContextClien will keep alive after this point.
So I think "StartingWorkerContext" is better.

[shimazu, 2014/09/08 07:04:46]

I think this should contain the class name for understandability and
searchability because the appearance on the chrome:tracing is "NAME:STEP"
format. But if someone add the more ASYNC trace flow, this name is conflicting.
So I changed the name to "Class::Event" format:
"EmbeddedWorkerContextClient::StartingWorkerContext" in this case.

+                         this);
   Send(new EmbeddedWorkerHostMsg_WorkerStarted(embedded_worker_id_));
 }
 
 }  // namespace content