Add a command line flag for dumping trace events to VLOG

base/debug/trace_event_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 "base/debug/trace_event_impl.h"
 
 #include <algorithm>
 
 #include "base/bind.h"
 #include "base/debug/leak_annotations.h"
@@ skipping 1043 matching lines @@
     int thread_id,
     const TimeTicks& timestamp,
     int num_args,
     const char** arg_names,
     const unsigned char* arg_types,
     const unsigned long long* arg_values,
     scoped_ptr<ConvertableToTraceFormat> convertable_values[],
     unsigned char flags) {
   DCHECK(name);
 
+  TimeDelta duration;
+  if (phase == TRACE_EVENT_PHASE_END && trace_options_ & ECHO_TO_STDERR) {
+    duration = timestamp - thread_event_start_times_[thread_id].back();
+    thread_event_start_times_[thread_id].pop_back();
+  }
+
   if (flags & TRACE_EVENT_FLAG_MANGLE_ID)
     id ^= process_id_hash_;
 
 #if defined(OS_ANDROID)
   SendToATrace(phase, GetCategoryGroupName(category_group_enabled), name, id,
                num_args, arg_names, arg_types, arg_values, flags);
 #endif
 
   TimeTicks now = timestamp - time_offset_;
   EventCallback event_callback_copy;
@@ skipping 30 matching lines @@
         bool found = std::find(existing_names.begin(),
                                existing_names.end(),
                                new_name) != existing_names.end();
         if (!found) {
           existing_name->second.push_back(',');
           existing_name->second.append(new_name);
         }
       }
     }
 
+    if (trace_options_ & ECHO_TO_STDERR) {
+      std::string thread_name = thread_names_[thread_id];
+      if (thread_colors_.find(thread_name) == thread_colors_.end())
+        thread_colors_[thread_name] = (thread_colors_.size() % 6) + 1;
+
+      fprintf(stderr,
+              "TraceLog (%lu) %s:",
+              timestamp.ToInternalValue(),
+              thread_name.c_str());
+
+      // Set the text color. With magic.
+      fprintf(stderr, "\e[0;3%dm", thread_colors_[thread_name]);
+
+      size_t depth = 0;
+      if (thread_event_start_times_.find(thread_id) !=
+            thread_event_start_times_.end())
+        depth = thread_event_start_times_[thread_id].size();
+
+      for (size_t i = 0; i < depth; ++i)
+        fprintf(stderr, "| ");
+
+      fprintf(stderr, "'%c', %s", phase, name);
+
+      if (phase == TRACE_EVENT_PHASE_END)
+        fprintf(stderr, " (%.3f ms)", duration.InMillisecondsF());
+
+      // Resets the text color.
+      fprintf(stderr, "\n\e[0;m");
+    }
+
     logged_events_->AddEvent(TraceEvent(thread_id,
         now, phase, category_group_enabled, name, id,
         num_args, arg_names, arg_types, arg_values,
         convertable_values, flags));
 
     if (logged_events_->IsFull())
       notifier.AddNotificationWhileLocked(TRACE_BUFFER_FULL);
 
     if (watch_category_ == category_group_enabled && watch_event_name_ == name)
       notifier.AddNotificationWhileLocked(EVENT_WATCH_NOTIFICATION);
 
     event_callback_copy = event_callback_;
   }  // release lock
 
+  if (phase == TRACE_EVENT_PHASE_BEGIN && trace_options_ & ECHO_TO_STDERR)
+    thread_event_start_times_[thread_id].push_back(timestamp);
+
   notifier.SendNotificationIfAny();
   if (event_callback_copy != NULL) {
     event_callback_copy(phase, category_group_enabled, name, id,
         num_args, arg_names, arg_types, arg_values,
         flags);
   }
 }
 
 void TraceLog::AddTraceEventEtw(char phase,
                                 const char* name,
@@ skipping 281 matching lines @@
         NULL,                    // arg_names
         NULL,                    // arg_types
         NULL,                    // arg_values
         NULL,                    // convertable values
         TRACE_EVENT_FLAG_NONE);  // flags
   }
 }
 
 }  // namespace trace_event_internal