Log active use time between kernel crashes.

metrics_daemon.cc

 // Copyright (c) 2010 The Chromium OS 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 "metrics_daemon.h"
 
 #include <dbus/dbus-glib-lowlevel.h>
 
+#include <base/file_util.h>
 #include <base/logging.h>
 
 #include "counter.h"
 
 using base::Time;
 using base::TimeDelta;
 using base::TimeTicks;
+using std::string;
 
 #define SAFE_MESSAGE(e) (e.message ? e.message : "unknown error")
 #define DBUS_IFACE_CRASH_REPORTER "org.chromium.CrashReporter"
 #define DBUS_IFACE_FLIMFLAM_MANAGER "org.chromium.flimflam.Manager"
 #define DBUS_IFACE_POWER_MANAGER "org.chromium.PowerManager"
 #define DBUS_IFACE_SESSION_MANAGER "org.chromium.SessionManagerInterface"
 
-// File to aggregate daily usage before sending to UMA.
-// TODO(petkov): This file should probably live in a user-specific stateful
-// location, e.g., /home/chronos/user.
-static const char kDailyUseRecordFile[] = "/var/log/metrics/daily-usage";
-static const char kUserCrashIntervalRecordFile[] =
-    "/var/log/metrics/user-crash-interval";
-
 static const int kSecondsPerMinute = 60;
 static const int kMinutesPerHour = 60;
 static const int kHoursPerDay = 24;
 static const int kMinutesPerDay = kHoursPerDay * kMinutesPerHour;
 static const int kSecondsPerDay = kSecondsPerMinute * kMinutesPerDay;
 static const int kDaysPerWeek = 7;
 static const int kSecondsPerWeek = kSecondsPerDay * kDaysPerWeek;
 
 // The daily use monitor is scheduled to a 1-minute interval after
 // initial user activity and then it's exponentially backed off to
 // 10-minute intervals. Although not required, the back off is
 // implemented because the histogram buckets are spaced exponentially
 // anyway and to avoid too frequent metrics daemon process wake-ups
 // and file I/O.
 static const int kUseMonitorIntervalInit = 1 * kSecondsPerMinute;
 static const int kUseMonitorIntervalMax = 10 * kSecondsPerMinute;
 
 // static metrics parameters.
 const char MetricsDaemon::kMetricDailyUseTimeName[] =
     "Logging.DailyUseTime";
 const int MetricsDaemon::kMetricDailyUseTimeMin = 1;
 const int MetricsDaemon::kMetricDailyUseTimeMax = kMinutesPerDay;
 const int MetricsDaemon::kMetricDailyUseTimeBuckets = 50;
 
+const char MetricsDaemon::kMetricKernelCrashIntervalName[] =
+    "Logging.KernelCrashInterval";
+const int MetricsDaemon::kMetricKernelCrashIntervalMin = 1;
+const int MetricsDaemon::kMetricKernelCrashIntervalMax = 4 * kSecondsPerWeek;
+const int MetricsDaemon::kMetricKernelCrashIntervalBuckets = 50;
+
 const char MetricsDaemon::kMetricTimeToNetworkDropName[] =
     "Network.TimeToDrop";
 const int MetricsDaemon::kMetricTimeToNetworkDropMin = 1;
 const int MetricsDaemon::kMetricTimeToNetworkDropMax =
     8 /* hours */ * kMinutesPerHour * kSecondsPerMinute;
 const int MetricsDaemon::kMetricTimeToNetworkDropBuckets = 50;
 
 const char MetricsDaemon::kMetricUserCrashIntervalName[] =
     "Logging.UserCrashInterval";
 const int MetricsDaemon::kMetricUserCrashIntervalMin = 1;
@@ skipping 35 matching lines @@
 #define STATE(name, capname) #name,
 #include "power_states.h"
 };
 
 // static
 const char* MetricsDaemon::kSessionStates_[] = {
 #define STATE(name, capname) #name,
 #include "session_states.h"
 };
 
+// Invokes a remote method over D-Bus that takes no input arguments
+// and returns a string result. The method call is issued with a 2
+// second blocking timeout. Returns an empty string on failure or
+// timeout.
+static string DBusGetString(DBusConnection* connection,
+                            const string& destination,
+                            const string& path,
+                            const string& interface,
+                            const string& method) {
+  DBusMessage* message =
+      dbus_message_new_method_call(destination.c_str(),
+                                   path.c_str(),
+                                   interface.c_str(),
+                                   method.c_str());
+  if (!message) {
+    DLOG(WARNING) << "DBusGetString: unable to allocate a message";
+    return "";
+  }
+
+  DBusError error;
+  dbus_error_init(&error);
+  const int kTimeout = 2000;  // ms
+  DLOG(INFO) << "DBusGetString: dest=" << destination << " path=" << path
+             << " iface=" << interface << " method=" << method;
+  DBusMessage* reply =
+      dbus_connection_send_with_reply_and_block(connection, message, kTimeout,
+                                                &error);
+  dbus_message_unref(message);
+  if (dbus_error_is_set(&error) || !reply) {
+    DLOG(WARNING) << "DBusGetString: call failed";
+    return "";
+  }
+  DBusMessageIter iter;
+  dbus_message_iter_init(reply, &iter);
+  if (dbus_message_iter_get_arg_type(&iter) != DBUS_TYPE_STRING) {
+    NOTREACHED();
+    dbus_message_unref(reply);
+    return "";
+  }
+  const char* c_result = "";
+  dbus_message_iter_get_basic(&iter, &c_result);
+  string result = c_result;
+  DLOG(INFO) << "DBusGetString: result=" << result;
+  dbus_message_unref(reply);
+  return result;
+}
+
 MetricsDaemon::MetricsDaemon()
     : network_state_(kUnknownNetworkState),
       power_state_(kUnknownPowerState),
       session_state_(kUnknownSessionState),
       user_active_(false),
       usemon_interval_(0),
       usemon_source_(NULL) {}
 
 MetricsDaemon::~MetricsDaemon() {}
 
 void MetricsDaemon::Run(bool run_as_daemon) {
-  if (!run_as_daemon || daemon(0, 0) == 0) {
-    Loop();
-  }
+  if (run_as_daemon && daemon(0, 0) != 0)
+    return;
+
+  static const char kKernelCrashDetectedFile[] = "/tmp/kernel-crash-detected";
+  CheckKernelCrash(kKernelCrashDetectedFile);
+  Loop();
 }
 
 void MetricsDaemon::Init(bool testing, MetricsLibraryInterface* metrics_lib) {
   testing_ = testing;
   DCHECK(metrics_lib != NULL);
   metrics_lib_ = metrics_lib;
+
+  static const char kDailyUseRecordFile[] = "/var/log/metrics/daily-usage";
   daily_use_.reset(new chromeos_metrics::TaggedCounter());
   daily_use_->Init(kDailyUseRecordFile, &DailyUseReporter, this);
+
+  static const char kUserCrashIntervalRecordFile[] =
+      "/var/log/metrics/user-crash-interval";
   user_crash_interval_.reset(new chromeos_metrics::TaggedCounter());
   user_crash_interval_->Init(kUserCrashIntervalRecordFile,
                              &UserCrashIntervalReporter, this);
 
+  static const char kKernelCrashIntervalRecordFile[] =
+      "/var/log/metrics/kernel-crash-interval";
+  kernel_crash_interval_.reset(new chromeos_metrics::TaggedCounter());
+  kernel_crash_interval_->Init(kKernelCrashIntervalRecordFile,
+                               &KernelCrashIntervalReporter, this);
+
   // Don't setup D-Bus and GLib in test mode.
   if (testing)
     return;
 
   g_thread_init(NULL);
   g_type_init();
   dbus_g_thread_init();
 
   DBusError error;
   dbus_error_init(&error);
@@ skipping 10 matching lines @@
     DLOG(INFO) << "adding dbus match: " << match;
     dbus_bus_add_match(connection, match, &error);
     LOG_IF(FATAL, dbus_error_is_set(&error)) <<
         "unable to add a match: " << SAFE_MESSAGE(error);
   }
 
   // Adds the D-Bus filter routine to be called back whenever one of
   // the registered D-Bus matches is successful. The daemon is not
   // activated for D-Bus messages that don't match.
   CHECK(dbus_connection_add_filter(connection, MessageFilter, this, NULL));
+
+  // Initializes the current network state by retrieving it from flimflam.
+  string state_name = DBusGetString(connection, "org.chromium.flimflam", "/",
+                                    DBUS_IFACE_FLIMFLAM_MANAGER, "GetState");
+  NetStateChanged(state_name.c_str(), TimeTicks::Now());
 }
 
 void MetricsDaemon::Loop() {
   GMainLoop* loop = g_main_loop_new(NULL, false);
   g_main_loop_run(loop);
 }
 
 // static
 DBusHandlerResult MetricsDaemon::MessageFilter(DBusConnection* connection,
                                                DBusMessage* message,
@@ skipping 138 matching lines @@
     TimeDelta since_active = now - user_active_last_;
     if (since_active < TimeDelta::FromSeconds(
             kUseMonitorIntervalMax + kSecondsPerMinute)) {
       seconds = static_cast<int>(since_active.InSeconds());
     }
   }
   TimeDelta since_epoch = now - Time();
   int day = since_epoch.InDays();
   daily_use_->Update(day, seconds);
   user_crash_interval_->Update(0, seconds);
+  kernel_crash_interval_->Update(0, seconds);
 
   // Schedules a use monitor on inactive->active transitions and
   // unschedules it on active->inactive transitions.
   if (!user_active_ && active)
     ScheduleUseMonitor(kUseMonitorIntervalInit, /* backoff */ false);
   else if (user_active_ && !active)
     UnscheduleUseMonitor();
 
   // Remembers the current active state and the time of the last
   // activity update.
   user_active_ = active;
   user_active_last_ = now;
 }
 
 void MetricsDaemon::ProcessUserCrash() {
   // Counts the active use time up to now.
   SetUserActiveState(user_active_, Time::Now());
 
   // Reports the active use time since the last crash and resets it.
   user_crash_interval_->Flush();
 }
 
+void MetricsDaemon::ProcessKernelCrash() {
+  // Counts the active use time up to now.
+  SetUserActiveState(user_active_, Time::Now());
+
+  // Reports the active use time since the last crash and resets it.
+  kernel_crash_interval_->Flush();
+}
+
+void MetricsDaemon::CheckKernelCrash(const std::string& crash_file) {
+  FilePath crash_detected(crash_file);
+  if (!file_util::PathExists(crash_detected))
+    return;
+
+  ProcessKernelCrash();
+
+  // Deletes the crash-detected file so that the daemon doesn't report
+  // another kernel crash in case it's restarted.
+  file_util::Delete(crash_detected,
+                    false);  // recursive
+}
+
 // static
 gboolean MetricsDaemon::UseMonitorStatic(gpointer data) {
   return static_cast<MetricsDaemon*>(data)->UseMonitor() ? TRUE : FALSE;
 }
 
 bool MetricsDaemon::UseMonitor() {
   SetUserActiveState(user_active_, Time::Now());
 
   // If a new monitor source/instance is scheduled, returns false to
   // tell GLib to destroy this monitor source/instance. Returns true
@@ skipping 63 matching lines @@
 // static
 void MetricsDaemon::UserCrashIntervalReporter(void* handle,
                                               int tag, int count) {
   MetricsDaemon* daemon = static_cast<MetricsDaemon*>(handle);
   daemon->SendMetric(kMetricUserCrashIntervalName, count,
                      kMetricUserCrashIntervalMin,
                      kMetricUserCrashIntervalMax,
                      kMetricUserCrashIntervalBuckets);
 }
 
-void MetricsDaemon::SendMetric(const std::string& name, int sample,
+// static
+void MetricsDaemon::KernelCrashIntervalReporter(void* handle,
+                                                int tag, int count) {
+  MetricsDaemon* daemon = static_cast<MetricsDaemon*>(handle);
+  daemon->SendMetric(kMetricKernelCrashIntervalName, count,
+                     kMetricKernelCrashIntervalMin,
+                     kMetricKernelCrashIntervalMax,
+                     kMetricKernelCrashIntervalBuckets);
+}
+
+void MetricsDaemon::SendMetric(const string& name, int sample,
                                int min, int max, int nbuckets) {
   DLOG(INFO) << "received metric: " << name << " " << sample << " "
              << min << " " << max << " " << nbuckets;
   metrics_lib_->SendToUMA(name, sample, min, max, nbuckets);
 }