Print accessors execution time in test-cpu-profiler/NativeAccessorNameInProfile1

test/cctest/test-cpu-profiler.cc

 // Copyright 2010 the V8 project authors. All rights reserved.
 // Redistribution and use in source and binary forms, with or without
 // modification, are permitted provided that the following conditions are
 // met:
 //
 //     * Redistributions of source code must retain the above copyright
 //       notice, this list of conditions and the following disclaimer.
 //     * Redistributions in binary form must reproduce the above
 //       copyright notice, this list of conditions and the following
 //       disclaimer in the documentation and/or other materials provided
@@ skipping 633 matching lines @@
 "  for (var i = 0; i < count; i++) {\n"
 "    var o = instance.foo;\n"
 "    instance.foo = o + 1;\n"
 "  }\n"
 "}\n";
 
 
 class FooAccessorsData {
  public:
   explicit FooAccessorsData(int min_duration_ms)
-      : min_duration_ms_(min_duration_ms) {}
+      : min_duration_ms_(min_duration_ms),
+        getter_duration_(0),
+        setter_duration_(0) {}
 
   static v8::Handle<v8::Value> Getter(v8::Local<v8::String> name,
                                       const v8::AccessorInfo& info) {
     FooAccessorsData* data = fromInfo(info);
-    data->Wait();
+    data->getter_duration_ = data->Wait();
     return v8::Int32::New(2013);
   }
 
   static void Setter(v8::Local<v8::String> name,
                      v8::Local<v8::Value> value,
                      const v8::AccessorInfo& info) {
     FooAccessorsData* data = fromInfo(info);
-    data->Wait();
+    data->setter_duration_ = data->Wait();
+  }
+
+  void PrintAccessorTime() {
+    i::OS::Print("getter: %f ms; setter: %f ms\n", getter_duration_,
+        setter_duration_);
   }
 
  private:
-  void Wait() {
+  double Wait() {
     double start = i::OS::TimeCurrentMillis();
-    for (double duration = 0; duration < min_duration_ms_; ) {
+    double duration = 0;
+    while (duration < min_duration_ms_) {
       duration = i::OS::TimeCurrentMillis() - start;
     }
+    return duration;
   }
 
   static FooAccessorsData* fromInfo(const v8::AccessorInfo& info) {
     void* data = v8::External::Cast(*info.Data())->Value();
     return reinterpret_cast<FooAccessorsData*>(data);
   }
 
   int min_duration_ms_;
+  double getter_duration_;
+  double setter_duration_;
 };
 
 
 // Test that native accessors are properly reported in the CPU profile.
 // This test checks the case when the long-running accessors are called
 // only once and the optimizer doesn't have chance to change the invocation
 // code.
 TEST(NativeAccessorNameInProfile1) {
   LocalContext env;
   v8::HandleScope scope(env->GetIsolate());
@@ skipping 22 matching lines @@
   cpu_profiler->StartCpuProfiling(profile_name);
   int32_t repeat_count = 1;
   v8::Handle<v8::Value> args[] = { v8::Integer::New(repeat_count) };
   function->Call(env->Global(), ARRAY_SIZE(args), args);
   const v8::CpuProfile* profile = cpu_profiler->StopCpuProfiling(profile_name);
 
   CHECK_NE(NULL, profile);
   // Dump collected profile to have a better diagnostic in case of failure.
   reinterpret_cast<i::CpuProfile*>(
       const_cast<v8::CpuProfile*>(profile))->Print();
+  accessors.PrintAccessorTime();
 
   const v8::CpuProfileNode* root = profile->GetTopDownRoot();
   const v8::CpuProfileNode* startNode = GetChild(root, "start");
   GetChild(startNode, "get foo");
   GetChild(startNode, "set foo");
 
   cpu_profiler->DeleteAllCpuProfiles();
 }
 
 
@@ skipping 38 matching lines @@
 
   const v8::CpuProfileNode* root = profile->GetTopDownRoot();
   const v8::CpuProfileNode* startNode = GetChild(root, "start");
   // TODO(yurys): in LoadIC should be changed to report external callback
   // invocation. See r13768 where it was LoadCallbackProperty was removed.
   // GetChild(startNode, "get foo");
   GetChild(startNode, "set foo");
 
   cpu_profiler->DeleteAllCpuProfiles();
 }