base: Print sub-second resolution timestamps

base/logging.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/logging.h"
 
 #include <limits.h>
 #include <stdint.h>
 
 #include "base/macros.h"
@@ skipping 747 matching lines @@
     filename.remove_prefix(last_slash_pos + 1);
 
   // TODO(darin): It might be nice if the columns were fixed width.
 
   stream_ <<  '[';
   if (g_log_process_id)
     stream_ << CurrentProcessId() << ':';
   if (g_log_thread_id)
     stream_ << base::PlatformThread::CurrentId() << ':';
   if (g_log_timestamp) {
-    time_t t = time(nullptr);
+    struct timespec tp = {0};

[Mark Mentovai, 2016/11/23 14:33:06]

No need to initialize (same goes for local_time a few lines below).

+    clock_gettime(CLOCK_REALTIME, &tp);

[Mark Mentovai, 2016/11/23 14:33:06]

Not available on WIndows! Take a look at how we solved this in mini_chromium:
https://chromium.googlesource.com/chromium/mini_chromium/+/9f129335dbe57ae9a3...

[Daniel Kurtz, 2016/11/23 16:31:02]

Any idea why mini_chromium already uses usec timestamps, but chromium does not?

I looked in the git log, and it has been that way since Initial release of
mini_chromium.

[Daniel Kurtz, 2016/11/24 03:09:53]

Done.

+    time_t t = tp.tv_sec;
     struct tm local_time = {0};
 #ifdef _MSC_VER
     localtime_s(&local_time, &t);
 #else
     localtime_r(&t, &local_time);
 #endif
     struct tm* tm_time = &local_time;
     stream_ << std::setfill('0')
             << std::setw(2) << 1 + tm_time->tm_mon
             << std::setw(2) << tm_time->tm_mday
             << '/'
             << std::setw(2) << tm_time->tm_hour
             << std::setw(2) << tm_time->tm_min
             << std::setw(2) << tm_time->tm_sec
+            << '.'
+            << std::setw(6) << (tp.tv_nsec / 1000)
             << ':';
   }
   if (g_log_tickcount)
     stream_ << TickCount() << ':';
   if (severity_ >= 0)
     stream_ << log_severity_name(severity_);
   else
     stream_ << "VERBOSE" << -severity_;
 
   stream_ << ":" << filename << "(" << line << ")] ";
@@ skipping 128 matching lines @@
 BASE_EXPORT void LogErrorNotReached(const char* file, int line) {
   LogMessage(file, line, LOG_ERROR).stream()
       << "NOTREACHED() hit.";
 }
 
 }  // namespace logging
 
 std::ostream& std::operator<<(std::ostream& out, const wchar_t* wstr) {
   return out << (wstr ? base::WideToUTF8(wstr) : std::string());
 }