blob: 4d50cb5e5147c5686959807f82991d90637f19de [file] [log] [blame]
Darin Petkov65b01462010-04-14 13:32:20 -07001// Copyright (c) 2010 The Chromium OS Authors. All rights reserved.
2// Use of this source code is governed by a BSD-style license that can be
3// found in the LICENSE file.
4
5#include "metrics_daemon.h"
Darin Petkov65b01462010-04-14 13:32:20 -07006
Ken Mixter4c5daa42010-08-26 18:35:06 -07007#include <string.h>
Darin Petkov65b01462010-04-14 13:32:20 -07008
Darin Petkov38d5cb02010-06-24 12:10:26 -07009#include <base/file_util.h>
Darin Petkov65b01462010-04-14 13:32:20 -070010#include <base/logging.h>
Ken Mixter4c5daa42010-08-26 18:35:06 -070011#include <dbus/dbus-glib-lowlevel.h>
Darin Petkov65b01462010-04-14 13:32:20 -070012
Darin Petkovf1e85e42010-06-10 15:59:53 -070013#include "counter.h"
14
Darin Petkovf27f0362010-06-04 13:14:19 -070015using base::Time;
16using base::TimeDelta;
17using base::TimeTicks;
Darin Petkov38d5cb02010-06-24 12:10:26 -070018using std::string;
Darin Petkovf27f0362010-06-04 13:14:19 -070019
Darin Petkov703ec972010-04-27 11:02:18 -070020#define SAFE_MESSAGE(e) (e.message ? e.message : "unknown error")
Darin Petkov1bb904e2010-06-16 15:58:06 -070021#define DBUS_IFACE_CRASH_REPORTER "org.chromium.CrashReporter"
Darin Petkove3348402010-06-04 14:07:41 -070022#define DBUS_IFACE_FLIMFLAM_MANAGER "org.chromium.flimflam.Manager"
David James6bf6e252010-06-06 18:52:40 -070023#define DBUS_IFACE_POWER_MANAGER "org.chromium.PowerManager"
Darin Petkov41e06232010-05-03 16:45:37 -070024#define DBUS_IFACE_SESSION_MANAGER "org.chromium.SessionManagerInterface"
25
Darin Petkov41e06232010-05-03 16:45:37 -070026static const int kSecondsPerMinute = 60;
27static const int kMinutesPerHour = 60;
28static const int kHoursPerDay = 24;
29static const int kMinutesPerDay = kHoursPerDay * kMinutesPerHour;
Darin Petkov1bb904e2010-06-16 15:58:06 -070030static const int kSecondsPerDay = kSecondsPerMinute * kMinutesPerDay;
31static const int kDaysPerWeek = 7;
32static const int kSecondsPerWeek = kSecondsPerDay * kDaysPerWeek;
Darin Petkov41e06232010-05-03 16:45:37 -070033
34// The daily use monitor is scheduled to a 1-minute interval after
35// initial user activity and then it's exponentially backed off to
36// 10-minute intervals. Although not required, the back off is
37// implemented because the histogram buckets are spaced exponentially
38// anyway and to avoid too frequent metrics daemon process wake-ups
39// and file I/O.
40static const int kUseMonitorIntervalInit = 1 * kSecondsPerMinute;
41static const int kUseMonitorIntervalMax = 10 * kSecondsPerMinute;
Darin Petkov65b01462010-04-14 13:32:20 -070042
Ken Mixterccd84c02010-08-16 19:57:13 -070043const char kKernelCrashDetectedFile[] = "/tmp/kernel-crash-detected";
44static const char kUncleanShutdownDetectedFile[] =
45 "/tmp/unclean-shutdown-detected";
46
Ken Mixter4c5daa42010-08-26 18:35:06 -070047// static metrics parameters
Darin Petkov2ccef012010-05-05 16:06:37 -070048const char MetricsDaemon::kMetricDailyUseTimeName[] =
49 "Logging.DailyUseTime";
50const int MetricsDaemon::kMetricDailyUseTimeMin = 1;
51const int MetricsDaemon::kMetricDailyUseTimeMax = kMinutesPerDay;
52const int MetricsDaemon::kMetricDailyUseTimeBuckets = 50;
53
54const char MetricsDaemon::kMetricTimeToNetworkDropName[] =
55 "Network.TimeToDrop";
56const int MetricsDaemon::kMetricTimeToNetworkDropMin = 1;
57const int MetricsDaemon::kMetricTimeToNetworkDropMax =
58 8 /* hours */ * kMinutesPerHour * kSecondsPerMinute;
59const int MetricsDaemon::kMetricTimeToNetworkDropBuckets = 50;
60
Ken Mixterccd84c02010-08-16 19:57:13 -070061// crash interval metrics
62const char MetricsDaemon::kMetricKernelCrashIntervalName[] =
63 "Logging.KernelCrashInterval";
64const char MetricsDaemon::kMetricUncleanShutdownIntervalName[] =
65 "Logging.UncleanShutdownInterval";
Darin Petkov1bb904e2010-06-16 15:58:06 -070066const char MetricsDaemon::kMetricUserCrashIntervalName[] =
67 "Logging.UserCrashInterval";
Ken Mixterccd84c02010-08-16 19:57:13 -070068
69const int MetricsDaemon::kMetricCrashIntervalMin = 1;
70const int MetricsDaemon::kMetricCrashIntervalMax =
71 4 * kSecondsPerWeek;
72const int MetricsDaemon::kMetricCrashIntervalBuckets = 50;
73
74// crash frequency metrics
75const char MetricsDaemon::kMetricAnyCrashesDailyName[] =
76 "Logging.AnyCrashesDaily";
Ken Mixter4c5daa42010-08-26 18:35:06 -070077const char MetricsDaemon::kMetricAnyCrashesWeeklyName[] =
78 "Logging.AnyCrashesWeekly";
Ken Mixterccd84c02010-08-16 19:57:13 -070079const char MetricsDaemon::kMetricKernelCrashesDailyName[] =
80 "Logging.KernelCrashesDaily";
Ken Mixter4c5daa42010-08-26 18:35:06 -070081const char MetricsDaemon::kMetricKernelCrashesWeeklyName[] =
82 "Logging.KernelCrashesWeekly";
Ken Mixterccd84c02010-08-16 19:57:13 -070083const char MetricsDaemon::kMetricUncleanShutdownsDailyName[] =
84 "Logging.UncleanShutdownsDaily";
Ken Mixter4c5daa42010-08-26 18:35:06 -070085const char MetricsDaemon::kMetricUncleanShutdownsWeeklyName[] =
86 "Logging.UncleanShutdownsWeekly";
Ken Mixterccd84c02010-08-16 19:57:13 -070087const char MetricsDaemon::kMetricUserCrashesDailyName[] =
88 "Logging.UserCrashesDaily";
Ken Mixter4c5daa42010-08-26 18:35:06 -070089const char MetricsDaemon::kMetricUserCrashesWeeklyName[] =
90 "Logging.UserCrashesWeekly";
91const char MetricsDaemon::kMetricCrashFrequencyMin = 1;
92const char MetricsDaemon::kMetricCrashFrequencyMax = 100;
93const char MetricsDaemon::kMetricCrashFrequencyBuckets = 50;
Ken Mixterccd84c02010-08-16 19:57:13 -070094
Ken Mixter4c5daa42010-08-26 18:35:06 -070095// persistent metrics path
96const char MetricsDaemon::kMetricsPath[] = "/var/log/metrics";
Ken Mixterccd84c02010-08-16 19:57:13 -070097
Darin Petkov1bb904e2010-06-16 15:58:06 -070098
Darin Petkov703ec972010-04-27 11:02:18 -070099// static
Darin Petkov41e06232010-05-03 16:45:37 -0700100const char* MetricsDaemon::kDBusMatches_[] = {
Darin Petkov703ec972010-04-27 11:02:18 -0700101 "type='signal',"
Darin Petkov1bb904e2010-06-16 15:58:06 -0700102 "interface='" DBUS_IFACE_CRASH_REPORTER "',"
103 "path='/',"
104 "member='UserCrash'",
105
106 "type='signal',"
Darin Petkove3348402010-06-04 14:07:41 -0700107 "sender='org.chromium.flimflam',"
108 "interface='" DBUS_IFACE_FLIMFLAM_MANAGER "',"
Darin Petkov703ec972010-04-27 11:02:18 -0700109 "path='/',"
110 "member='StateChanged'",
111
112 "type='signal',"
113 "interface='" DBUS_IFACE_POWER_MANAGER "',"
Benson Leung53faeb02010-06-08 15:59:13 -0700114 "path='/'",
Darin Petkov41e06232010-05-03 16:45:37 -0700115
116 "type='signal',"
117 "sender='org.chromium.SessionManager',"
118 "interface='" DBUS_IFACE_SESSION_MANAGER "',"
119 "path='/org/chromium/SessionManager',"
120 "member='SessionStateChanged'",
Darin Petkov703ec972010-04-27 11:02:18 -0700121};
122
123// static
Darin Petkov41e06232010-05-03 16:45:37 -0700124const char* MetricsDaemon::kNetworkStates_[] = {
Darin Petkov703ec972010-04-27 11:02:18 -0700125#define STATE(name, capname) #name,
Darin Petkov65b01462010-04-14 13:32:20 -0700126#include "network_states.h"
127};
128
Darin Petkov703ec972010-04-27 11:02:18 -0700129// static
Darin Petkov41e06232010-05-03 16:45:37 -0700130const char* MetricsDaemon::kPowerStates_[] = {
Darin Petkov703ec972010-04-27 11:02:18 -0700131#define STATE(name, capname) #name,
132#include "power_states.h"
133};
134
Darin Petkov41e06232010-05-03 16:45:37 -0700135// static
Darin Petkov41e06232010-05-03 16:45:37 -0700136const char* MetricsDaemon::kSessionStates_[] = {
137#define STATE(name, capname) #name,
138#include "session_states.h"
139};
140
Darin Petkov38d5cb02010-06-24 12:10:26 -0700141// Invokes a remote method over D-Bus that takes no input arguments
142// and returns a string result. The method call is issued with a 2
143// second blocking timeout. Returns an empty string on failure or
144// timeout.
145static string DBusGetString(DBusConnection* connection,
146 const string& destination,
147 const string& path,
148 const string& interface,
149 const string& method) {
150 DBusMessage* message =
151 dbus_message_new_method_call(destination.c_str(),
152 path.c_str(),
153 interface.c_str(),
154 method.c_str());
155 if (!message) {
156 DLOG(WARNING) << "DBusGetString: unable to allocate a message";
157 return "";
158 }
159
160 DBusError error;
161 dbus_error_init(&error);
162 const int kTimeout = 2000; // ms
163 DLOG(INFO) << "DBusGetString: dest=" << destination << " path=" << path
164 << " iface=" << interface << " method=" << method;
165 DBusMessage* reply =
166 dbus_connection_send_with_reply_and_block(connection, message, kTimeout,
167 &error);
168 dbus_message_unref(message);
169 if (dbus_error_is_set(&error) || !reply) {
170 DLOG(WARNING) << "DBusGetString: call failed";
171 return "";
172 }
173 DBusMessageIter iter;
174 dbus_message_iter_init(reply, &iter);
175 if (dbus_message_iter_get_arg_type(&iter) != DBUS_TYPE_STRING) {
176 NOTREACHED();
177 dbus_message_unref(reply);
178 return "";
179 }
180 const char* c_result = "";
181 dbus_message_iter_get_basic(&iter, &c_result);
182 string result = c_result;
183 DLOG(INFO) << "DBusGetString: result=" << result;
184 dbus_message_unref(reply);
185 return result;
186}
187
Darin Petkovf1e85e42010-06-10 15:59:53 -0700188MetricsDaemon::MetricsDaemon()
189 : network_state_(kUnknownNetworkState),
190 power_state_(kUnknownPowerState),
191 session_state_(kUnknownSessionState),
192 user_active_(false),
193 usemon_interval_(0),
194 usemon_source_(NULL) {}
195
Ken Mixter4c5daa42010-08-26 18:35:06 -0700196MetricsDaemon::~MetricsDaemon() {
197 DeleteFrequencyCounters();
198}
199
200void MetricsDaemon::DeleteFrequencyCounters() {
201 for (FrequencyCounters::iterator i = frequency_counters_.begin();
202 i != frequency_counters_.end(); ++i) {
203 delete i->second;
204 i->second = NULL;
205 }
206}
Darin Petkovf1e85e42010-06-10 15:59:53 -0700207
Darin Petkov2ccef012010-05-05 16:06:37 -0700208void MetricsDaemon::Run(bool run_as_daemon) {
Darin Petkov38d5cb02010-06-24 12:10:26 -0700209 if (run_as_daemon && daemon(0, 0) != 0)
210 return;
211
Ken Mixterccd84c02010-08-16 19:57:13 -0700212 if (CheckSystemCrash(kKernelCrashDetectedFile)) {
213 ProcessKernelCrash();
214 }
215
216 if (CheckSystemCrash(kUncleanShutdownDetectedFile)) {
217 ProcessUncleanShutdown();
218 }
219
Darin Petkov38d5cb02010-06-24 12:10:26 -0700220 Loop();
Darin Petkov65b01462010-04-14 13:32:20 -0700221}
222
Ken Mixter4c5daa42010-08-26 18:35:06 -0700223FilePath MetricsDaemon::GetHistogramPath(const char* histogram_name) {
224 return FilePath(kMetricsPath).Append(histogram_name);
225}
226
227void MetricsDaemon::ConfigureCrashIntervalReporter(
228 const char* histogram_name,
229 scoped_ptr<chromeos_metrics::TaggedCounterReporter>* reporter) {
230 reporter->reset(new chromeos_metrics::TaggedCounterReporter());
231 FilePath file_path = GetHistogramPath(histogram_name);
232 (*reporter)->Init(file_path.value().c_str(),
233 histogram_name,
234 kMetricCrashIntervalMin,
235 kMetricCrashIntervalMax,
236 kMetricCrashIntervalBuckets);
237}
238
239void MetricsDaemon::ConfigureCrashFrequencyReporter(
240 const char* histogram_name) {
241 scoped_ptr<chromeos_metrics::TaggedCounterReporter> reporter(
242 new chromeos_metrics::TaggedCounterReporter());
243 FilePath file_path = GetHistogramPath(histogram_name);
244 reporter->Init(file_path.value().c_str(),
245 histogram_name,
246 kMetricCrashFrequencyMin,
247 kMetricCrashFrequencyMax,
248 kMetricCrashFrequencyBuckets);
249 scoped_ptr<chromeos_metrics::FrequencyCounter> new_counter(
250 new chromeos_metrics::FrequencyCounter());
251 time_t cycle_duration = strstr(histogram_name, "Weekly") != NULL ?
252 chromeos_metrics::kSecondsPerWeek :
253 chromeos_metrics::kSecondsPerDay;
254 new_counter->Init(
255 static_cast<chromeos_metrics::TaggedCounterInterface*>(
256 reporter.release()),
257 cycle_duration);
258 frequency_counters_[histogram_name] = new_counter.release();
259}
260
Darin Petkovfc91b422010-05-12 13:05:45 -0700261void MetricsDaemon::Init(bool testing, MetricsLibraryInterface* metrics_lib) {
Darin Petkov65b01462010-04-14 13:32:20 -0700262 testing_ = testing;
Darin Petkovfc91b422010-05-12 13:05:45 -0700263 DCHECK(metrics_lib != NULL);
264 metrics_lib_ = metrics_lib;
Ken Mixter4c5daa42010-08-26 18:35:06 -0700265 chromeos_metrics::TaggedCounterReporter::
266 SetMetricsLibraryInterface(metrics_lib);
Darin Petkov38d5cb02010-06-24 12:10:26 -0700267
268 static const char kDailyUseRecordFile[] = "/var/log/metrics/daily-usage";
Darin Petkovf1e85e42010-06-10 15:59:53 -0700269 daily_use_.reset(new chromeos_metrics::TaggedCounter());
Ken Mixterccd84c02010-08-16 19:57:13 -0700270 daily_use_->Init(kDailyUseRecordFile, &ReportDailyUse, this);
Darin Petkov38d5cb02010-06-24 12:10:26 -0700271
Ken Mixter4c5daa42010-08-26 18:35:06 -0700272 ConfigureCrashIntervalReporter(kMetricKernelCrashIntervalName,
273 &kernel_crash_interval_);
274 ConfigureCrashIntervalReporter(kMetricUncleanShutdownIntervalName,
275 &unclean_shutdown_interval_);
276 ConfigureCrashIntervalReporter(kMetricUserCrashIntervalName,
277 &user_crash_interval_);
Darin Petkov2ccef012010-05-05 16:06:37 -0700278
Ken Mixter4c5daa42010-08-26 18:35:06 -0700279 DeleteFrequencyCounters();
280 ConfigureCrashFrequencyReporter(kMetricAnyCrashesDailyName);
281 ConfigureCrashFrequencyReporter(kMetricAnyCrashesDailyName);
282 ConfigureCrashFrequencyReporter(kMetricAnyCrashesWeeklyName);
283 ConfigureCrashFrequencyReporter(kMetricKernelCrashesDailyName);
284 ConfigureCrashFrequencyReporter(kMetricKernelCrashesWeeklyName);
285 ConfigureCrashFrequencyReporter(kMetricUncleanShutdownsDailyName);
286 ConfigureCrashFrequencyReporter(kMetricUncleanShutdownsWeeklyName);
287 ConfigureCrashFrequencyReporter(kMetricUserCrashesDailyName);
288 ConfigureCrashFrequencyReporter(kMetricUserCrashesWeeklyName);
Darin Petkov38d5cb02010-06-24 12:10:26 -0700289
Darin Petkov2ccef012010-05-05 16:06:37 -0700290 // Don't setup D-Bus and GLib in test mode.
291 if (testing)
292 return;
Darin Petkov65b01462010-04-14 13:32:20 -0700293
Darin Petkov703ec972010-04-27 11:02:18 -0700294 g_thread_init(NULL);
295 g_type_init();
296 dbus_g_thread_init();
Darin Petkov65b01462010-04-14 13:32:20 -0700297
Darin Petkov703ec972010-04-27 11:02:18 -0700298 DBusError error;
299 dbus_error_init(&error);
Darin Petkov65b01462010-04-14 13:32:20 -0700300
David James3b3add52010-06-04 15:01:19 -0700301 DBusConnection* connection = dbus_bus_get(DBUS_BUS_SYSTEM, &error);
Darin Petkov703ec972010-04-27 11:02:18 -0700302 LOG_IF(FATAL, dbus_error_is_set(&error)) <<
303 "No D-Bus connection: " << SAFE_MESSAGE(error);
Darin Petkov65b01462010-04-14 13:32:20 -0700304
Darin Petkov703ec972010-04-27 11:02:18 -0700305 dbus_connection_setup_with_g_main(connection, NULL);
Darin Petkov65b01462010-04-14 13:32:20 -0700306
Darin Petkov703ec972010-04-27 11:02:18 -0700307 // Registers D-Bus matches for the signals we would like to catch.
Darin Petkov1bb904e2010-06-16 15:58:06 -0700308 for (unsigned int m = 0; m < arraysize(kDBusMatches_); m++) {
Darin Petkov41e06232010-05-03 16:45:37 -0700309 const char* match = kDBusMatches_[m];
310 DLOG(INFO) << "adding dbus match: " << match;
Darin Petkov703ec972010-04-27 11:02:18 -0700311 dbus_bus_add_match(connection, match, &error);
312 LOG_IF(FATAL, dbus_error_is_set(&error)) <<
313 "unable to add a match: " << SAFE_MESSAGE(error);
314 }
315
316 // Adds the D-Bus filter routine to be called back whenever one of
317 // the registered D-Bus matches is successful. The daemon is not
318 // activated for D-Bus messages that don't match.
319 CHECK(dbus_connection_add_filter(connection, MessageFilter, this, NULL));
Darin Petkov38d5cb02010-06-24 12:10:26 -0700320
321 // Initializes the current network state by retrieving it from flimflam.
322 string state_name = DBusGetString(connection, "org.chromium.flimflam", "/",
323 DBUS_IFACE_FLIMFLAM_MANAGER, "GetState");
324 NetStateChanged(state_name.c_str(), TimeTicks::Now());
Darin Petkov65b01462010-04-14 13:32:20 -0700325}
326
327void MetricsDaemon::Loop() {
Darin Petkov703ec972010-04-27 11:02:18 -0700328 GMainLoop* loop = g_main_loop_new(NULL, false);
329 g_main_loop_run(loop);
Darin Petkov65b01462010-04-14 13:32:20 -0700330}
331
Darin Petkov703ec972010-04-27 11:02:18 -0700332// static
333DBusHandlerResult MetricsDaemon::MessageFilter(DBusConnection* connection,
334 DBusMessage* message,
335 void* user_data) {
Darin Petkovf27f0362010-06-04 13:14:19 -0700336 Time now = Time::Now();
337 TimeTicks ticks = TimeTicks::Now();
338 DLOG(INFO) << "message intercepted @ " << now.ToInternalValue();
Darin Petkov703ec972010-04-27 11:02:18 -0700339
340 int message_type = dbus_message_get_type(message);
341 if (message_type != DBUS_MESSAGE_TYPE_SIGNAL) {
Darin Petkov41e06232010-05-03 16:45:37 -0700342 DLOG(WARNING) << "unexpected message type " << message_type;
Darin Petkov703ec972010-04-27 11:02:18 -0700343 return DBUS_HANDLER_RESULT_NOT_YET_HANDLED;
344 }
345
346 // Signal messages always have interfaces.
347 const char* interface = dbus_message_get_interface(message);
348 CHECK(interface != NULL);
349
350 MetricsDaemon* daemon = static_cast<MetricsDaemon*>(user_data);
351
352 DBusMessageIter iter;
353 dbus_message_iter_init(message, &iter);
Darin Petkov1bb904e2010-06-16 15:58:06 -0700354 if (strcmp(interface, DBUS_IFACE_CRASH_REPORTER) == 0) {
355 CHECK(strcmp(dbus_message_get_member(message),
356 "UserCrash") == 0);
357 daemon->ProcessUserCrash();
358 } else if (strcmp(interface, DBUS_IFACE_FLIMFLAM_MANAGER) == 0) {
Darin Petkov41e06232010-05-03 16:45:37 -0700359 CHECK(strcmp(dbus_message_get_member(message),
360 "StateChanged") == 0);
Darin Petkov703ec972010-04-27 11:02:18 -0700361
David James3b3add52010-06-04 15:01:19 -0700362 char* state_name;
Darin Petkov703ec972010-04-27 11:02:18 -0700363 dbus_message_iter_get_basic(&iter, &state_name);
Darin Petkovf27f0362010-06-04 13:14:19 -0700364 daemon->NetStateChanged(state_name, ticks);
Darin Petkov703ec972010-04-27 11:02:18 -0700365 } else if (strcmp(interface, DBUS_IFACE_POWER_MANAGER) == 0) {
David James6bf6e252010-06-06 18:52:40 -0700366 const char* member = dbus_message_get_member(message);
367 if (strcmp(member, "ScreenIsLocked") == 0) {
368 daemon->SetUserActiveState(false, now);
369 } else if (strcmp(member, "ScreenIsUnlocked") == 0) {
370 daemon->SetUserActiveState(true, now);
371 } else if (strcmp(member, "PowerStateChanged") == 0) {
372 char* state_name;
373 dbus_message_iter_get_basic(&iter, &state_name);
374 daemon->PowerStateChanged(state_name, now);
375 }
Darin Petkov41e06232010-05-03 16:45:37 -0700376 } else if (strcmp(interface, DBUS_IFACE_SESSION_MANAGER) == 0) {
377 CHECK(strcmp(dbus_message_get_member(message),
378 "SessionStateChanged") == 0);
379
David James3b3add52010-06-04 15:01:19 -0700380 char* state_name;
Darin Petkov41e06232010-05-03 16:45:37 -0700381 dbus_message_iter_get_basic(&iter, &state_name);
382 daemon->SessionStateChanged(state_name, now);
Darin Petkov703ec972010-04-27 11:02:18 -0700383 } else {
Darin Petkov41e06232010-05-03 16:45:37 -0700384 DLOG(WARNING) << "unexpected interface: " << interface;
Darin Petkov703ec972010-04-27 11:02:18 -0700385 return DBUS_HANDLER_RESULT_NOT_YET_HANDLED;
386 }
387
388 return DBUS_HANDLER_RESULT_HANDLED;
Darin Petkov65b01462010-04-14 13:32:20 -0700389}
390
Darin Petkovf27f0362010-06-04 13:14:19 -0700391void MetricsDaemon::NetStateChanged(const char* state_name, TimeTicks ticks) {
Darin Petkov41e06232010-05-03 16:45:37 -0700392 DLOG(INFO) << "network state: " << state_name;
Darin Petkov703ec972010-04-27 11:02:18 -0700393
Darin Petkov703ec972010-04-27 11:02:18 -0700394 NetworkState state = LookupNetworkState(state_name);
395
396 // Logs the time in seconds between the network going online to
Darin Petkov2ccef012010-05-05 16:06:37 -0700397 // going offline (or, more precisely, going not online) in order to
398 // measure the mean time to network dropping. Going offline as part
399 // of suspend-to-RAM is not logged as network drop -- the assumption
400 // is that the message for suspend-to-RAM comes before the network
401 // offline message which seems to and should be the case.
402 if (state != kNetworkStateOnline &&
Darin Petkov703ec972010-04-27 11:02:18 -0700403 network_state_ == kNetworkStateOnline &&
404 power_state_ != kPowerStateMem) {
Darin Petkovf27f0362010-06-04 13:14:19 -0700405 TimeDelta since_online = ticks - network_state_last_;
406 int online_time = static_cast<int>(since_online.InSeconds());
Darin Petkov11b8eb32010-05-18 11:00:59 -0700407 SendMetric(kMetricTimeToNetworkDropName, online_time,
408 kMetricTimeToNetworkDropMin,
409 kMetricTimeToNetworkDropMax,
410 kMetricTimeToNetworkDropBuckets);
Darin Petkov65b01462010-04-14 13:32:20 -0700411 }
412
Darin Petkov703ec972010-04-27 11:02:18 -0700413 network_state_ = state;
Darin Petkovf27f0362010-06-04 13:14:19 -0700414 network_state_last_ = ticks;
Darin Petkov65b01462010-04-14 13:32:20 -0700415}
416
Darin Petkov703ec972010-04-27 11:02:18 -0700417MetricsDaemon::NetworkState
418MetricsDaemon::LookupNetworkState(const char* state_name) {
Darin Petkov65b01462010-04-14 13:32:20 -0700419 for (int i = 0; i < kNumberNetworkStates; i++) {
Darin Petkov41e06232010-05-03 16:45:37 -0700420 if (strcmp(state_name, kNetworkStates_[i]) == 0) {
Darin Petkov703ec972010-04-27 11:02:18 -0700421 return static_cast<NetworkState>(i);
Darin Petkov65b01462010-04-14 13:32:20 -0700422 }
423 }
Darin Petkov41e06232010-05-03 16:45:37 -0700424 DLOG(WARNING) << "unknown network connection state: " << state_name;
Darin Petkov703ec972010-04-27 11:02:18 -0700425 return kUnknownNetworkState;
426}
427
Darin Petkovf27f0362010-06-04 13:14:19 -0700428void MetricsDaemon::PowerStateChanged(const char* state_name, Time now) {
Darin Petkov41e06232010-05-03 16:45:37 -0700429 DLOG(INFO) << "power state: " << state_name;
Darin Petkov703ec972010-04-27 11:02:18 -0700430 power_state_ = LookupPowerState(state_name);
Darin Petkov41e06232010-05-03 16:45:37 -0700431
432 if (power_state_ != kPowerStateOn)
433 SetUserActiveState(false, now);
Darin Petkov703ec972010-04-27 11:02:18 -0700434}
435
436MetricsDaemon::PowerState
437MetricsDaemon::LookupPowerState(const char* state_name) {
438 for (int i = 0; i < kNumberPowerStates; i++) {
Darin Petkov41e06232010-05-03 16:45:37 -0700439 if (strcmp(state_name, kPowerStates_[i]) == 0) {
Darin Petkov703ec972010-04-27 11:02:18 -0700440 return static_cast<PowerState>(i);
441 }
442 }
Darin Petkov41e06232010-05-03 16:45:37 -0700443 DLOG(WARNING) << "unknown power state: " << state_name;
Darin Petkov703ec972010-04-27 11:02:18 -0700444 return kUnknownPowerState;
Darin Petkov65b01462010-04-14 13:32:20 -0700445}
446
Darin Petkovf27f0362010-06-04 13:14:19 -0700447void MetricsDaemon::SessionStateChanged(const char* state_name, Time now) {
Darin Petkov41e06232010-05-03 16:45:37 -0700448 DLOG(INFO) << "user session state: " << state_name;
449 session_state_ = LookupSessionState(state_name);
450 SetUserActiveState(session_state_ == kSessionStateStarted, now);
451}
452
453MetricsDaemon::SessionState
454MetricsDaemon::LookupSessionState(const char* state_name) {
455 for (int i = 0; i < kNumberSessionStates; i++) {
456 if (strcmp(state_name, kSessionStates_[i]) == 0) {
457 return static_cast<SessionState>(i);
458 }
459 }
460 DLOG(WARNING) << "unknown user session state: " << state_name;
461 return kUnknownSessionState;
462}
463
Darin Petkovf27f0362010-06-04 13:14:19 -0700464void MetricsDaemon::SetUserActiveState(bool active, Time now) {
Darin Petkov41e06232010-05-03 16:45:37 -0700465 DLOG(INFO) << "user: " << (active ? "active" : "inactive");
466
467 // Calculates the seconds of active use since the last update and
Darin Petkovf27f0362010-06-04 13:14:19 -0700468 // the day since Epoch, and logs the usage data. Guards against the
469 // time jumping back and forth due to the user changing it by
470 // discarding the new use time.
471 int seconds = 0;
472 if (user_active_ && now > user_active_last_) {
473 TimeDelta since_active = now - user_active_last_;
474 if (since_active < TimeDelta::FromSeconds(
475 kUseMonitorIntervalMax + kSecondsPerMinute)) {
476 seconds = static_cast<int>(since_active.InSeconds());
477 }
478 }
479 TimeDelta since_epoch = now - Time();
480 int day = since_epoch.InDays();
Darin Petkovf1e85e42010-06-10 15:59:53 -0700481 daily_use_->Update(day, seconds);
Darin Petkov1bb904e2010-06-16 15:58:06 -0700482 user_crash_interval_->Update(0, seconds);
Darin Petkov38d5cb02010-06-24 12:10:26 -0700483 kernel_crash_interval_->Update(0, seconds);
Darin Petkov41e06232010-05-03 16:45:37 -0700484
Ken Mixter4c5daa42010-08-26 18:35:06 -0700485 // Flush finished cycles of all frequency counters.
486 for (FrequencyCounters::iterator i = frequency_counters_.begin();
487 i != frequency_counters_.end(); ++i) {
488 i->second->FlushFinishedCycles();
489 }
490
Darin Petkov41e06232010-05-03 16:45:37 -0700491 // Schedules a use monitor on inactive->active transitions and
492 // unschedules it on active->inactive transitions.
493 if (!user_active_ && active)
494 ScheduleUseMonitor(kUseMonitorIntervalInit, /* backoff */ false);
495 else if (user_active_ && !active)
496 UnscheduleUseMonitor();
497
498 // Remembers the current active state and the time of the last
499 // activity update.
500 user_active_ = active;
501 user_active_last_ = now;
502}
503
Darin Petkov1bb904e2010-06-16 15:58:06 -0700504void MetricsDaemon::ProcessUserCrash() {
505 // Counts the active use time up to now.
506 SetUserActiveState(user_active_, Time::Now());
507
508 // Reports the active use time since the last crash and resets it.
509 user_crash_interval_->Flush();
Ken Mixterccd84c02010-08-16 19:57:13 -0700510
Ken Mixter4c5daa42010-08-26 18:35:06 -0700511 frequency_counters_[kMetricUserCrashesDailyName]->Update(1);
512 frequency_counters_[kMetricUserCrashesWeeklyName]->Update(1);
513 frequency_counters_[kMetricAnyCrashesDailyName]->Update(1);
514 frequency_counters_[kMetricAnyCrashesWeeklyName]->Update(1);
Darin Petkov1bb904e2010-06-16 15:58:06 -0700515}
516
Darin Petkov38d5cb02010-06-24 12:10:26 -0700517void MetricsDaemon::ProcessKernelCrash() {
518 // Counts the active use time up to now.
519 SetUserActiveState(user_active_, Time::Now());
520
521 // Reports the active use time since the last crash and resets it.
522 kernel_crash_interval_->Flush();
Ken Mixterccd84c02010-08-16 19:57:13 -0700523
Ken Mixter4c5daa42010-08-26 18:35:06 -0700524 frequency_counters_[kMetricKernelCrashesDailyName]->Update(1);
525 frequency_counters_[kMetricKernelCrashesWeeklyName]->Update(1);
526 frequency_counters_[kMetricAnyCrashesDailyName]->Update(1);
527 frequency_counters_[kMetricAnyCrashesWeeklyName]->Update(1);
Darin Petkov38d5cb02010-06-24 12:10:26 -0700528}
529
Ken Mixterccd84c02010-08-16 19:57:13 -0700530void MetricsDaemon::ProcessUncleanShutdown() {
531 // Counts the active use time up to now.
532 SetUserActiveState(user_active_, Time::Now());
533
534 // Reports the active use time since the last crash and resets it.
535 unclean_shutdown_interval_->Flush();
536
Ken Mixter4c5daa42010-08-26 18:35:06 -0700537 frequency_counters_[kMetricUncleanShutdownsDailyName]->Update(1);
538 frequency_counters_[kMetricUncleanShutdownsWeeklyName]->Update(1);
539 frequency_counters_[kMetricAnyCrashesDailyName]->Update(1);
540 frequency_counters_[kMetricAnyCrashesWeeklyName]->Update(1);
Ken Mixterccd84c02010-08-16 19:57:13 -0700541}
542
543bool MetricsDaemon::CheckSystemCrash(const std::string& crash_file) {
Darin Petkov38d5cb02010-06-24 12:10:26 -0700544 FilePath crash_detected(crash_file);
545 if (!file_util::PathExists(crash_detected))
Ken Mixterccd84c02010-08-16 19:57:13 -0700546 return false;
Darin Petkov38d5cb02010-06-24 12:10:26 -0700547
548 // Deletes the crash-detected file so that the daemon doesn't report
549 // another kernel crash in case it's restarted.
550 file_util::Delete(crash_detected,
551 false); // recursive
Ken Mixterccd84c02010-08-16 19:57:13 -0700552 return true;
Darin Petkov38d5cb02010-06-24 12:10:26 -0700553}
554
Darin Petkov41e06232010-05-03 16:45:37 -0700555// static
556gboolean MetricsDaemon::UseMonitorStatic(gpointer data) {
557 return static_cast<MetricsDaemon*>(data)->UseMonitor() ? TRUE : FALSE;
558}
559
560bool MetricsDaemon::UseMonitor() {
Darin Petkovf27f0362010-06-04 13:14:19 -0700561 SetUserActiveState(user_active_, Time::Now());
Darin Petkov41e06232010-05-03 16:45:37 -0700562
563 // If a new monitor source/instance is scheduled, returns false to
564 // tell GLib to destroy this monitor source/instance. Returns true
565 // otherwise to keep calling back this monitor.
566 return !ScheduleUseMonitor(usemon_interval_ * 2, /* backoff */ true);
567}
568
569bool MetricsDaemon::ScheduleUseMonitor(int interval, bool backoff)
570{
Darin Petkov2ccef012010-05-05 16:06:37 -0700571 if (testing_)
572 return false;
573
Darin Petkov41e06232010-05-03 16:45:37 -0700574 // Caps the interval -- the bigger the interval, the more active use
575 // time will be potentially dropped on system shutdown.
576 if (interval > kUseMonitorIntervalMax)
577 interval = kUseMonitorIntervalMax;
578
579 if (backoff) {
580 // Back-off mode is used by the use monitor to reschedule itself
581 // with exponential back-off in time. This mode doesn't create a
582 // new timeout source if the new interval is the same as the old
583 // one. Also, if a new timeout source is created, the old one is
584 // not destroyed explicitly here -- it will be destroyed by GLib
585 // when the monitor returns FALSE (see UseMonitor and
586 // UseMonitorStatic).
587 if (interval == usemon_interval_)
588 return false;
589 } else {
590 UnscheduleUseMonitor();
591 }
592
593 // Schedules a new use monitor for |interval| seconds from now.
594 DLOG(INFO) << "scheduling use monitor in " << interval << " seconds";
595 usemon_source_ = g_timeout_source_new_seconds(interval);
596 g_source_set_callback(usemon_source_, UseMonitorStatic, this,
597 NULL); // No destroy notification.
598 g_source_attach(usemon_source_,
599 NULL); // Default context.
600 usemon_interval_ = interval;
601 return true;
602}
603
604void MetricsDaemon::UnscheduleUseMonitor() {
605 // If there's a use monitor scheduled already, destroys it.
606 if (usemon_source_ == NULL)
607 return;
608
609 DLOG(INFO) << "destroying use monitor";
610 g_source_destroy(usemon_source_);
611 usemon_source_ = NULL;
612 usemon_interval_ = 0;
613}
614
Darin Petkovf1e85e42010-06-10 15:59:53 -0700615// static
Ken Mixterccd84c02010-08-16 19:57:13 -0700616void MetricsDaemon::ReportDailyUse(void* handle, int tag, int count) {
Darin Petkov1bb904e2010-06-16 15:58:06 -0700617 if (count <= 0)
618 return;
619
Darin Petkovf1e85e42010-06-10 15:59:53 -0700620 MetricsDaemon* daemon = static_cast<MetricsDaemon*>(handle);
621 int minutes = (count + kSecondsPerMinute / 2) / kSecondsPerMinute;
622 daemon->SendMetric(kMetricDailyUseTimeName, minutes,
623 kMetricDailyUseTimeMin,
624 kMetricDailyUseTimeMax,
625 kMetricDailyUseTimeBuckets);
626}
627
Darin Petkov38d5cb02010-06-24 12:10:26 -0700628void MetricsDaemon::SendMetric(const string& name, int sample,
Darin Petkov11b8eb32010-05-18 11:00:59 -0700629 int min, int max, int nbuckets) {
Darin Petkovfc91b422010-05-12 13:05:45 -0700630 DLOG(INFO) << "received metric: " << name << " " << sample << " "
631 << min << " " << max << " " << nbuckets;
632 metrics_lib_->SendToUMA(name, sample, min, max, nbuckets);
Darin Petkov65b01462010-04-14 13:32:20 -0700633}