blob: f83c5d42c723d98e37b8d4360b9c022cf06faa47 [file] [log] [blame]
Bertrand SIMONNET52e5b992015-08-10 15:18:00 -07001/*
2 * Copyright (C) 2015 The Android Open Source Project
3 *
4 * Licensed under the Apache License, Version 2.0 (the "License");
5 * you may not use this file except in compliance with the License.
6 * You may obtain a copy of the License at
7 *
8 * http://www.apache.org/licenses/LICENSE-2.0
9 *
10 * Unless required by applicable law or agreed to in writing, software
11 * distributed under the License is distributed on an "AS IS" BASIS,
12 * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
13 * See the License for the specific language governing permissions and
14 * limitations under the License.
15 */
Darin Petkov65b01462010-04-14 13:32:20 -070016
Bertrand SIMONNET4b915ae2015-07-28 15:38:14 -070017#include "metrics_daemon.h"
Darin Petkov65b01462010-04-14 13:32:20 -070018
Steve Funge86591e2014-12-01 13:38:21 -080019#include <sysexits.h>
Luigi Semenzato8accd332011-05-17 16:37:18 -070020#include <time.h>
Darin Petkov65b01462010-04-14 13:32:20 -070021
Bertrand SIMONNET4b915ae2015-07-28 15:38:14 -070022#include <base/bind.h>
Luigi Semenzato859b3f02014-02-05 15:33:19 -080023#include <base/files/file_path.h>
Ben Chan51bf92a2014-09-05 08:21:06 -070024#include <base/files/file_util.h>
Luigi Semenzato859b3f02014-02-05 15:33:19 -080025#include <base/hash.h>
Darin Petkov65b01462010-04-14 13:32:20 -070026#include <base/logging.h>
Ben Chan2e6543d2014-02-05 23:26:25 -080027#include <base/strings/string_number_conversions.h>
28#include <base/strings/string_split.h>
29#include <base/strings/string_util.h>
30#include <base/strings/stringprintf.h>
Bertrand SIMONNET26993622015-08-20 14:08:41 -070031#include <cutils/properties.h>
Steve Funge86591e2014-12-01 13:38:21 -080032#include <dbus/dbus.h>
33#include <dbus/message.h>
Bertrand SIMONNETbae5dcc2015-08-04 14:12:10 -070034
35#include "constants.h"
Bertrand SIMONNET46b49da2014-06-25 14:38:07 -070036#include "uploader/upload_service.h"
Darin Petkov65b01462010-04-14 13:32:20 -070037
Ben Chan2e6543d2014-02-05 23:26:25 -080038using base::FilePath;
39using base::StringPrintf;
Darin Petkovf27f0362010-06-04 13:14:19 -070040using base::Time;
41using base::TimeDelta;
42using base::TimeTicks;
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -080043using chromeos_metrics::PersistentInteger;
Bertrand SIMONNET59890e22015-10-02 16:45:18 -070044using com::android::Weave::CommandProxy;
45using com::android::Weave::ManagerProxy;
Luigi Semenzato8accd332011-05-17 16:37:18 -070046using std::map;
Darin Petkov38d5cb02010-06-24 12:10:26 -070047using std::string;
Luigi Semenzato8accd332011-05-17 16:37:18 -070048using std::vector;
49
Daniel Eratc83975a2014-04-04 08:53:44 -070050namespace {
Darin Petkovf27f0362010-06-04 13:14:19 -070051
Daniel Eratc83975a2014-04-04 08:53:44 -070052const char kCrashReporterInterface[] = "org.chromium.CrashReporter";
53const char kCrashReporterUserCrashSignal[] = "UserCrash";
Steve Funge86591e2014-12-01 13:38:21 -080054const char kCrashReporterMatchRule[] =
55 "type='signal',interface='%s',path='/',member='%s'";
Darin Petkov41e06232010-05-03 16:45:37 -070056
Daniel Eratc83975a2014-04-04 08:53:44 -070057const int kSecondsPerMinute = 60;
58const int kMinutesPerHour = 60;
59const int kHoursPerDay = 24;
60const int kMinutesPerDay = kHoursPerDay * kMinutesPerHour;
61const int kSecondsPerDay = kSecondsPerMinute * kMinutesPerDay;
62const int kDaysPerWeek = 7;
63const int kSecondsPerWeek = kSecondsPerDay * kDaysPerWeek;
Darin Petkov41e06232010-05-03 16:45:37 -070064
Daniel Eratc83975a2014-04-04 08:53:44 -070065// Interval between calls to UpdateStats().
Steve Funge86591e2014-12-01 13:38:21 -080066const uint32_t kUpdateStatsIntervalMs = 300000;
Darin Petkov65b01462010-04-14 13:32:20 -070067
Luigi Semenzatoc5a92342014-02-14 15:05:51 -080068const char kKernelCrashDetectedFile[] = "/var/run/kernel-crash-detected";
Daniel Eratc83975a2014-04-04 08:53:44 -070069const char kUncleanShutdownDetectedFile[] =
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -080070 "/var/run/unclean-shutdown-detected";
Ken Mixterccd84c02010-08-16 19:57:13 -070071
Bertrand SIMONNETebbe35c2015-09-08 10:13:35 -070072const int kMetricMeminfoInterval = 30; // seconds
73
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -070074const char kMetricsProcStatFileName[] = "/proc/stat";
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -070075const char kMeminfoFileName[] = "/proc/meminfo";
Bertrand SIMONNET7a964052015-09-29 11:07:24 -070076const char kVmStatFileName[] = "/proc/vmstat";
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -070077const int kMetricsProcStatFirstLineItemsCount = 11;
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -070078
Luigi Semenzatofb3a8212013-05-07 16:55:00 -070079// Thermal CPU throttling.
80
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -070081const char kMetricScaledCpuFrequencyName[] =
Luigi Semenzatofb3a8212013-05-07 16:55:00 -070082 "Platform.CpuFrequencyThermalScaling";
83
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -070084} // namespace
85
Luigi Semenzato96360192014-06-04 10:53:35 -070086// Zram sysfs entries.
87
88const char MetricsDaemon::kComprDataSizeName[] = "compr_data_size";
89const char MetricsDaemon::kOrigDataSizeName[] = "orig_data_size";
90const char MetricsDaemon::kZeroPagesName[] = "zero_pages";
91
Luigi Semenzato8accd332011-05-17 16:37:18 -070092// Memory use stats collection intervals. We collect some memory use interval
93// at these intervals after boot, and we stop collecting after the last one,
94// with the assumption that in most cases the memory use won't change much
95// after that.
96static const int kMemuseIntervals[] = {
97 1 * kSecondsPerMinute, // 1 minute mark
98 4 * kSecondsPerMinute, // 5 minute mark
99 25 * kSecondsPerMinute, // 0.5 hour mark
100 120 * kSecondsPerMinute, // 2.5 hour mark
101 600 * kSecondsPerMinute, // 12.5 hour mark
102};
103
Darin Petkovf1e85e42010-06-10 15:59:53 -0700104MetricsDaemon::MetricsDaemon()
Steve Funge86591e2014-12-01 13:38:21 -0800105 : memuse_final_time_(0),
Luigi Semenzato8accd332011-05-17 16:37:18 -0700106 memuse_interval_index_(0),
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700107 ticks_per_second_(0),
108 latest_cpu_use_ticks_(0) {}
Darin Petkovf1e85e42010-06-10 15:59:53 -0700109
Ken Mixter4c5daa42010-08-26 18:35:06 -0700110MetricsDaemon::~MetricsDaemon() {
Ken Mixter4c5daa42010-08-26 18:35:06 -0700111}
112
Bertrand SIMONNET7a964052015-09-29 11:07:24 -0700113// static
Luigi Semenzato8accd332011-05-17 16:37:18 -0700114double MetricsDaemon::GetActiveTime() {
115 struct timespec ts;
116 int r = clock_gettime(CLOCK_MONOTONIC, &ts);
117 if (r < 0) {
118 PLOG(WARNING) << "clock_gettime(CLOCK_MONOTONIC) failed";
119 return 0;
120 } else {
Luigi Semenzato4a6c9422014-06-30 18:12:28 -0700121 return ts.tv_sec + static_cast<double>(ts.tv_nsec) / (1000 * 1000 * 1000);
Luigi Semenzato8accd332011-05-17 16:37:18 -0700122 }
123}
124
Steve Funge86591e2014-12-01 13:38:21 -0800125int MetricsDaemon::Run() {
Ken Mixterccd84c02010-08-16 19:57:13 -0700126 if (CheckSystemCrash(kKernelCrashDetectedFile)) {
127 ProcessKernelCrash();
128 }
129
130 if (CheckSystemCrash(kUncleanShutdownDetectedFile)) {
131 ProcessUncleanShutdown();
132 }
133
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800134 // On OS version change, clear version stats (which are reported daily).
Ben Chanf05ab402014-08-07 00:54:59 -0700135 int32_t version = GetOsVersionHash();
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800136 if (version_cycle_->Get() != version) {
137 version_cycle_->Set(version);
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800138 kernel_crashes_version_count_->Set(0);
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700139 version_cumulative_active_use_->Set(0);
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700140 version_cumulative_cpu_use_->Set(0);
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800141 }
142
Steve Funge86591e2014-12-01 13:38:21 -0800143 return chromeos::DBusDaemon::Run();
Darin Petkov65b01462010-04-14 13:32:20 -0700144}
145
Bertrand SIMONNET46b49da2014-06-25 14:38:07 -0700146void MetricsDaemon::RunUploaderTest() {
Bertrand SIMONNET12531862015-08-31 11:11:57 -0700147 upload_service_.reset(new UploadService(
Bertrand SIMONNET2765d0a2015-09-09 10:38:20 -0700148 new SystemProfileCache(true, metrics_directory_),
Bertrand SIMONNET12531862015-08-31 11:11:57 -0700149 metrics_lib_,
150 server_));
Bertrand SIMONNET2765d0a2015-09-09 10:38:20 -0700151 upload_service_->Init(upload_interval_, metrics_directory_);
Bertrand SIMONNET46b49da2014-06-25 14:38:07 -0700152 upload_service_->UploadEvent();
153}
154
Ben Chanf05ab402014-08-07 00:54:59 -0700155uint32_t MetricsDaemon::GetOsVersionHash() {
156 static uint32_t cached_version_hash = 0;
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800157 static bool version_hash_is_cached = false;
158 if (version_hash_is_cached)
159 return cached_version_hash;
160 version_hash_is_cached = true;
Bertrand SIMONNET26993622015-08-20 14:08:41 -0700161
162 char version[PROPERTY_VALUE_MAX];
Bertrand SIMONNETbae5dcc2015-08-04 14:12:10 -0700163 // The version might not be set for development devices. In this case, use the
164 // zero version.
Bertrand SIMONNET26993622015-08-20 14:08:41 -0700165 property_get(metrics::kProductVersionProperty, version,
166 metrics::kDefaultVersion);
167
Bertrand SIMONNETbae5dcc2015-08-04 14:12:10 -0700168 cached_version_hash = base::Hash(version);
169 if (testing_) {
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800170 cached_version_hash = 42; // return any plausible value for the hash
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800171 }
172 return cached_version_hash;
173}
174
Bertrand SIMONNET46b49da2014-06-25 14:38:07 -0700175void MetricsDaemon::Init(bool testing,
176 bool uploader_active,
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700177 bool dbus_enabled,
Bertrand SIMONNET46b49da2014-06-25 14:38:07 -0700178 MetricsLibraryInterface* metrics_lib,
Bertrand SIMONNETebbe35c2015-09-08 10:13:35 -0700179 const string& diskstats_path,
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700180 const string& scaling_max_freq_path,
Steve Fung67906c62014-10-06 15:15:30 -0700181 const string& cpuinfo_max_freq_path,
Bertrand SIMONNETcac74e12014-10-09 10:14:13 -0700182 const base::TimeDelta& upload_interval,
Steve Fung67906c62014-10-06 15:15:30 -0700183 const string& server,
Bertrand SIMONNET2765d0a2015-09-09 10:38:20 -0700184 const base::FilePath& metrics_directory) {
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -0700185 CHECK(metrics_lib);
Darin Petkov65b01462010-04-14 13:32:20 -0700186 testing_ = testing;
Steve Funge86591e2014-12-01 13:38:21 -0800187 uploader_active_ = uploader_active;
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700188 dbus_enabled_ = dbus_enabled;
Bertrand SIMONNET2765d0a2015-09-09 10:38:20 -0700189 metrics_directory_ = metrics_directory;
Darin Petkovfc91b422010-05-12 13:05:45 -0700190 metrics_lib_ = metrics_lib;
Darin Petkov38d5cb02010-06-24 12:10:26 -0700191
Bertrand SIMONNETcac74e12014-10-09 10:14:13 -0700192 upload_interval_ = upload_interval;
Steve Fung67906c62014-10-06 15:15:30 -0700193 server_ = server;
Steve Fung67906c62014-10-06 15:15:30 -0700194
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700195 // Get ticks per second (HZ) on this system.
196 // Sysconf cannot fail, so no sanity checks are needed.
197 ticks_per_second_ = sysconf(_SC_CLK_TCK);
198
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700199 daily_active_use_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700200 new PersistentInteger("Platform.UseTime.PerDay"));
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700201 version_cumulative_active_use_.reset(
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700202 new PersistentInteger("Platform.CumulativeUseTime"));
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700203 version_cumulative_cpu_use_.reset(
Luigi Semenzatodc865892015-07-09 08:28:08 -0700204 new PersistentInteger("Platform.CumulativeCpuTime"));
Darin Petkov38d5cb02010-06-24 12:10:26 -0700205
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800206 kernel_crash_interval_.reset(
Luigi Semenzatodc865892015-07-09 08:28:08 -0700207 new PersistentInteger("Platform.KernelCrashInterval"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800208 unclean_shutdown_interval_.reset(
Luigi Semenzatodc865892015-07-09 08:28:08 -0700209 new PersistentInteger("Platform.UncleanShutdownInterval"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800210 user_crash_interval_.reset(
Luigi Semenzatodc865892015-07-09 08:28:08 -0700211 new PersistentInteger("Platform.UserCrashInterval"));
Darin Petkov2ccef012010-05-05 16:06:37 -0700212
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800213 any_crashes_daily_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700214 new PersistentInteger("Platform.AnyCrashes.PerDay"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800215 any_crashes_weekly_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700216 new PersistentInteger("Platform.AnyCrashes.PerWeek"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800217 user_crashes_daily_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700218 new PersistentInteger("Platform.UserCrashes.PerDay"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800219 user_crashes_weekly_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700220 new PersistentInteger("Platform.UserCrashes.PerWeek"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800221 kernel_crashes_daily_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700222 new PersistentInteger("Platform.KernelCrashes.PerDay"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800223 kernel_crashes_weekly_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700224 new PersistentInteger("Platform.KernelCrashes.PerWeek"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800225 kernel_crashes_version_count_.reset(
Luigi Semenzatodc865892015-07-09 08:28:08 -0700226 new PersistentInteger("Platform.KernelCrashesSinceUpdate"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800227 unclean_shutdowns_daily_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700228 new PersistentInteger("Platform.UncleanShutdown.PerDay"));
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800229 unclean_shutdowns_weekly_count_.reset(
Bertrand SIMONNET5066a452015-09-25 15:38:42 -0700230 new PersistentInteger("Platform.UncleanShutdowns.PerWeek"));
Darin Petkov38d5cb02010-06-24 12:10:26 -0700231
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800232 daily_cycle_.reset(new PersistentInteger("daily.cycle"));
233 weekly_cycle_.reset(new PersistentInteger("weekly.cycle"));
234 version_cycle_.reset(new PersistentInteger("version.cycle"));
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800235
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700236 scaling_max_freq_path_ = scaling_max_freq_path;
237 cpuinfo_max_freq_path_ = cpuinfo_max_freq_path;
Bertrand SIMONNET5658dc52015-09-18 13:38:10 -0700238 disk_usage_collector_.reset(new DiskUsageCollector(metrics_lib_));
Bertrand SIMONNET7a964052015-09-29 11:07:24 -0700239 averaged_stats_collector_.reset(
240 new AveragedStatisticsCollector(metrics_lib_, diskstats_path,
241 kVmStatFileName));
Steve Funge86591e2014-12-01 13:38:21 -0800242}
243
244int MetricsDaemon::OnInit() {
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700245 int return_code = dbus_enabled_ ? chromeos::DBusDaemon::OnInit() :
246 chromeos::Daemon::OnInit();
Steve Funge86591e2014-12-01 13:38:21 -0800247 if (return_code != EX_OK)
248 return return_code;
249
Bertrand SIMONNETebbe35c2015-09-08 10:13:35 -0700250 StatsReporterInit();
251
252 // Start collecting meminfo stats.
253 ScheduleMeminfoCallback(kMetricMeminfoInterval);
254 memuse_final_time_ = GetActiveTime() + kMemuseIntervals[0];
255 ScheduleMemuseCallback(kMemuseIntervals[0]);
256
Steve Funge86591e2014-12-01 13:38:21 -0800257 if (testing_)
258 return EX_OK;
Darin Petkov65b01462010-04-14 13:32:20 -0700259
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700260 if (dbus_enabled_) {
261 bus_->AssertOnDBusThread();
262 CHECK(bus_->SetUpAsyncOperations());
Darin Petkov65b01462010-04-14 13:32:20 -0700263
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700264 if (bus_->is_connected()) {
265 const std::string match_rule =
266 base::StringPrintf(kCrashReporterMatchRule,
267 kCrashReporterInterface,
268 kCrashReporterUserCrashSignal);
Darin Petkov65b01462010-04-14 13:32:20 -0700269
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700270 bus_->AddFilterFunction(&MetricsDaemon::MessageFilter, this);
Darin Petkov65b01462010-04-14 13:32:20 -0700271
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700272 DBusError error;
273 dbus_error_init(&error);
274 bus_->AddMatch(match_rule, &error);
Darin Petkov65b01462010-04-14 13:32:20 -0700275
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700276 if (dbus_error_is_set(&error)) {
277 LOG(ERROR) << "Failed to add match rule \"" << match_rule << "\". Got "
278 << error.name << ": " << error.message;
279 return EX_SOFTWARE;
280 }
281 } else {
282 LOG(ERROR) << "DBus isn't connected.";
283 return EX_UNAVAILABLE;
Steve Funge86591e2014-12-01 13:38:21 -0800284 }
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700285
286 weaved_object_mgr_.reset(new com::android::Weave::ObjectManagerProxy{bus_});
287 weaved_object_mgr_->SetCommandAddedCallback(
288 base::Bind(&MetricsDaemon::OnWeaveCommand, base::Unretained(this)));
289 weaved_object_mgr_->SetManagerAddedCallback(
290 base::Bind(&MetricsDaemon::UpdateWeaveState, base::Unretained(this)));
Darin Petkov703ec972010-04-27 11:02:18 -0700291 }
292
Bertrand SIMONNETebbe35c2015-09-08 10:13:35 -0700293 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
294 base::Bind(&MetricsDaemon::HandleUpdateStatsTimeout,
295 base::Unretained(this)),
296 base::TimeDelta::FromMilliseconds(kUpdateStatsIntervalMs));
297
Steve Funge86591e2014-12-01 13:38:21 -0800298 if (uploader_active_) {
Bertrand SIMONNETbae5dcc2015-08-04 14:12:10 -0700299 upload_service_.reset(
300 new UploadService(new SystemProfileCache(), metrics_lib_, server_));
Bertrand SIMONNET2765d0a2015-09-09 10:38:20 -0700301 upload_service_->Init(upload_interval_, metrics_directory_);
Bertrand SIMONNET46b49da2014-06-25 14:38:07 -0700302 }
Steve Funge86591e2014-12-01 13:38:21 -0800303
304 return EX_OK;
Darin Petkov65b01462010-04-14 13:32:20 -0700305}
306
Steve Funge86591e2014-12-01 13:38:21 -0800307void MetricsDaemon::OnShutdown(int* return_code) {
Bertrand SIMONNETfec4d2c2015-08-05 16:04:14 -0700308 if (!testing_ && dbus_enabled_ && bus_->is_connected()) {
Steve Funge86591e2014-12-01 13:38:21 -0800309 const std::string match_rule =
310 base::StringPrintf(kCrashReporterMatchRule,
311 kCrashReporterInterface,
312 kCrashReporterUserCrashSignal);
313
314 bus_->RemoveFilterFunction(&MetricsDaemon::MessageFilter, this);
315
316 DBusError error;
317 dbus_error_init(&error);
318 bus_->RemoveMatch(match_rule, &error);
319
320 if (dbus_error_is_set(&error)) {
321 LOG(ERROR) << "Failed to remove match rule \"" << match_rule << "\". Got "
322 << error.name << ": " << error.message;
323 }
324 }
325 chromeos::DBusDaemon::OnShutdown(return_code);
Darin Petkov65b01462010-04-14 13:32:20 -0700326}
327
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700328void MetricsDaemon::OnWeaveCommand(CommandProxy* command) {
Alex Vakulenko35f89632015-10-09 08:18:35 -0700329 if (command->state() != "queued") {
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700330 return;
331 }
332
333 VLOG(1) << "received weave command: " << command->name();
334 if (command->name() == "_metrics._enableAnalyticsReporting") {
335 OnEnableMetrics(command);
336 } else if (command->name() == "_metrics._disableAnalyticsReporting") {
337 OnDisableMetrics(command);
338 }
339}
340
341void MetricsDaemon::OnEnableMetrics(CommandProxy* command) {
342 if (base::WriteFile(metrics_directory_.Append(metrics::kConsentFileName),
343 "", 0) != 0) {
344 PLOG(ERROR) << "Could not create the consent file.";
Alex Vakulenko35f89632015-10-09 08:18:35 -0700345 command->Abort("metrics_error", "Could not create the consent file",
346 nullptr);
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700347 return;
348 }
349
350 NotifyStateChanged();
Alex Vakulenko35f89632015-10-09 08:18:35 -0700351 command->Complete({}, nullptr);
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700352}
353
354void MetricsDaemon::OnDisableMetrics(CommandProxy* command) {
355 if (!base::DeleteFile(metrics_directory_.Append(metrics::kConsentFileName),
356 false)) {
Alex Vakulenko35f89632015-10-09 08:18:35 -0700357 PLOG(ERROR) << "Could not delete the consent file.";
358 command->Abort("metrics_error", "Could not delete the consent file",
359 nullptr);
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700360 return;
361 }
362
363 NotifyStateChanged();
Alex Vakulenko35f89632015-10-09 08:18:35 -0700364 command->Complete({}, nullptr);
Bertrand SIMONNET59890e22015-10-02 16:45:18 -0700365}
366
367void MetricsDaemon::NotifyStateChanged() {
368 ManagerProxy* manager = weaved_object_mgr_->GetManagerProxy();
369 if (manager)
370 UpdateWeaveState(manager);
371}
372
373void MetricsDaemon::UpdateWeaveState(ManagerProxy* manager) {
374 chromeos::VariantDictionary state_change{
375 { "_metrics._AnalyticsReportingState",
376 metrics_lib_->AreMetricsEnabled() ? "enabled" : "disabled" }
377 };
378
379 if (!manager->UpdateState(state_change, nullptr)) {
380 LOG(ERROR) << "failed to update weave's state";
381 }
382}
383
Darin Petkov703ec972010-04-27 11:02:18 -0700384// static
385DBusHandlerResult MetricsDaemon::MessageFilter(DBusConnection* connection,
386 DBusMessage* message,
387 void* user_data) {
Darin Petkov703ec972010-04-27 11:02:18 -0700388 int message_type = dbus_message_get_type(message);
389 if (message_type != DBUS_MESSAGE_TYPE_SIGNAL) {
Darin Petkov41e06232010-05-03 16:45:37 -0700390 DLOG(WARNING) << "unexpected message type " << message_type;
Darin Petkov703ec972010-04-27 11:02:18 -0700391 return DBUS_HANDLER_RESULT_NOT_YET_HANDLED;
392 }
393
394 // Signal messages always have interfaces.
Daniel Eratc83975a2014-04-04 08:53:44 -0700395 const std::string interface(dbus_message_get_interface(message));
396 const std::string member(dbus_message_get_member(message));
397 DLOG(INFO) << "Got " << interface << "." << member << " D-Bus signal";
Darin Petkov703ec972010-04-27 11:02:18 -0700398
399 MetricsDaemon* daemon = static_cast<MetricsDaemon*>(user_data);
400
401 DBusMessageIter iter;
402 dbus_message_iter_init(message, &iter);
Daniel Eratc83975a2014-04-04 08:53:44 -0700403 if (interface == kCrashReporterInterface) {
404 CHECK_EQ(member, kCrashReporterUserCrashSignal);
Darin Petkov1bb904e2010-06-16 15:58:06 -0700405 daemon->ProcessUserCrash();
Darin Petkov703ec972010-04-27 11:02:18 -0700406 } else {
Daniel Eratc83975a2014-04-04 08:53:44 -0700407 // Ignore messages from the bus itself.
Darin Petkov703ec972010-04-27 11:02:18 -0700408 return DBUS_HANDLER_RESULT_NOT_YET_HANDLED;
409 }
410
411 return DBUS_HANDLER_RESULT_HANDLED;
Darin Petkov65b01462010-04-14 13:32:20 -0700412}
413
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700414// One might argue that parts of this should go into
415// chromium/src/base/sys_info_chromeos.c instead, but put it here for now.
416
417TimeDelta MetricsDaemon::GetIncrementalCpuUse() {
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700418 FilePath proc_stat_path = FilePath(kMetricsProcStatFileName);
419 std::string proc_stat_string;
420 if (!base::ReadFileToString(proc_stat_path, &proc_stat_string)) {
421 LOG(WARNING) << "cannot open " << kMetricsProcStatFileName;
422 return TimeDelta();
423 }
424
425 std::vector<std::string> proc_stat_lines;
426 base::SplitString(proc_stat_string, '\n', &proc_stat_lines);
427 if (proc_stat_lines.empty()) {
428 LOG(WARNING) << "cannot parse " << kMetricsProcStatFileName
429 << ": " << proc_stat_string;
430 return TimeDelta();
431 }
432 std::vector<std::string> proc_stat_totals;
433 base::SplitStringAlongWhitespace(proc_stat_lines[0], &proc_stat_totals);
434
Ben Chanf05ab402014-08-07 00:54:59 -0700435 uint64_t user_ticks, user_nice_ticks, system_ticks;
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700436 if (proc_stat_totals.size() != kMetricsProcStatFirstLineItemsCount ||
437 proc_stat_totals[0] != "cpu" ||
438 !base::StringToUint64(proc_stat_totals[1], &user_ticks) ||
439 !base::StringToUint64(proc_stat_totals[2], &user_nice_ticks) ||
440 !base::StringToUint64(proc_stat_totals[3], &system_ticks)) {
441 LOG(WARNING) << "cannot parse first line: " << proc_stat_lines[0];
442 return TimeDelta(base::TimeDelta::FromSeconds(0));
443 }
444
Ben Chanf05ab402014-08-07 00:54:59 -0700445 uint64_t total_cpu_use_ticks = user_ticks + user_nice_ticks + system_ticks;
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700446
447 // Sanity check.
448 if (total_cpu_use_ticks < latest_cpu_use_ticks_) {
449 LOG(WARNING) << "CPU time decreasing from " << latest_cpu_use_ticks_
450 << " to " << total_cpu_use_ticks;
451 return TimeDelta();
452 }
453
Ben Chanf05ab402014-08-07 00:54:59 -0700454 uint64_t diff = total_cpu_use_ticks - latest_cpu_use_ticks_;
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700455 latest_cpu_use_ticks_ = total_cpu_use_ticks;
456 // Use microseconds to avoid significant truncations.
457 return base::TimeDelta::FromMicroseconds(
458 diff * 1000 * 1000 / ticks_per_second_);
459}
460
Darin Petkov1bb904e2010-06-16 15:58:06 -0700461void MetricsDaemon::ProcessUserCrash() {
Daniel Eratc83975a2014-04-04 08:53:44 -0700462 // Counts the active time up to now.
463 UpdateStats(TimeTicks::Now(), Time::Now());
Darin Petkov1bb904e2010-06-16 15:58:06 -0700464
465 // Reports the active use time since the last crash and resets it.
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700466 SendAndResetCrashIntervalSample(user_crash_interval_);
Ken Mixterccd84c02010-08-16 19:57:13 -0700467
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800468 any_crashes_daily_count_->Add(1);
469 any_crashes_weekly_count_->Add(1);
470 user_crashes_daily_count_->Add(1);
471 user_crashes_weekly_count_->Add(1);
Darin Petkov1bb904e2010-06-16 15:58:06 -0700472}
473
Darin Petkov38d5cb02010-06-24 12:10:26 -0700474void MetricsDaemon::ProcessKernelCrash() {
Daniel Eratc83975a2014-04-04 08:53:44 -0700475 // Counts the active time up to now.
476 UpdateStats(TimeTicks::Now(), Time::Now());
Darin Petkov38d5cb02010-06-24 12:10:26 -0700477
478 // Reports the active use time since the last crash and resets it.
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700479 SendAndResetCrashIntervalSample(kernel_crash_interval_);
Ken Mixterccd84c02010-08-16 19:57:13 -0700480
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800481 any_crashes_daily_count_->Add(1);
482 any_crashes_weekly_count_->Add(1);
483 kernel_crashes_daily_count_->Add(1);
484 kernel_crashes_weekly_count_->Add(1);
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800485
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800486 kernel_crashes_version_count_->Add(1);
Darin Petkov38d5cb02010-06-24 12:10:26 -0700487}
488
Ken Mixterccd84c02010-08-16 19:57:13 -0700489void MetricsDaemon::ProcessUncleanShutdown() {
Daniel Eratc83975a2014-04-04 08:53:44 -0700490 // Counts the active time up to now.
491 UpdateStats(TimeTicks::Now(), Time::Now());
Ken Mixterccd84c02010-08-16 19:57:13 -0700492
493 // Reports the active use time since the last crash and resets it.
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700494 SendAndResetCrashIntervalSample(unclean_shutdown_interval_);
Ken Mixterccd84c02010-08-16 19:57:13 -0700495
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800496 unclean_shutdowns_daily_count_->Add(1);
497 unclean_shutdowns_weekly_count_->Add(1);
498 any_crashes_daily_count_->Add(1);
499 any_crashes_weekly_count_->Add(1);
Ken Mixterccd84c02010-08-16 19:57:13 -0700500}
501
Luigi Semenzato8accd332011-05-17 16:37:18 -0700502bool MetricsDaemon::CheckSystemCrash(const string& crash_file) {
Darin Petkov38d5cb02010-06-24 12:10:26 -0700503 FilePath crash_detected(crash_file);
Ben Chan2e6543d2014-02-05 23:26:25 -0800504 if (!base::PathExists(crash_detected))
Ken Mixterccd84c02010-08-16 19:57:13 -0700505 return false;
Darin Petkov38d5cb02010-06-24 12:10:26 -0700506
507 // Deletes the crash-detected file so that the daemon doesn't report
508 // another kernel crash in case it's restarted.
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800509 base::DeleteFile(crash_detected, false); // not recursive
Ken Mixterccd84c02010-08-16 19:57:13 -0700510 return true;
Darin Petkov38d5cb02010-06-24 12:10:26 -0700511}
512
Luigi Semenzato5bd764f2011-10-14 12:03:35 -0700513void MetricsDaemon::StatsReporterInit() {
Bertrand SIMONNET5658dc52015-09-18 13:38:10 -0700514 disk_usage_collector_->Schedule();
Bertrand SIMONNET7a964052015-09-29 11:07:24 -0700515
516 // Don't start a collection cycle during the first run to avoid delaying the
517 // boot.
518 averaged_stats_collector_->ScheduleWait();
Luigi Semenzatoc88e42d2011-02-17 10:21:16 -0800519}
520
Luigi Semenzatoc88e42d2011-02-17 10:21:16 -0800521
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700522bool MetricsDaemon::ReadFreqToInt(const string& sysfs_file_name, int* value) {
Luigi Semenzatod92d18c2013-06-04 13:24:21 -0700523 const FilePath sysfs_path(sysfs_file_name);
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700524 string value_string;
Ben Chan2e6543d2014-02-05 23:26:25 -0800525 if (!base::ReadFileToString(sysfs_path, &value_string)) {
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700526 LOG(WARNING) << "cannot read " << sysfs_path.value().c_str();
527 return false;
528 }
Ben Chan2e6543d2014-02-05 23:26:25 -0800529 if (!base::RemoveChars(value_string, "\n", &value_string)) {
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700530 LOG(WARNING) << "no newline in " << value_string;
531 // Continue even though the lack of newline is suspicious.
532 }
533 if (!base::StringToInt(value_string, value)) {
534 LOG(WARNING) << "cannot convert " << value_string << " to int";
535 return false;
536 }
537 return true;
538}
539
540void MetricsDaemon::SendCpuThrottleMetrics() {
541 // |max_freq| is 0 only the first time through.
542 static int max_freq = 0;
543 if (max_freq == -1)
544 // Give up, as sysfs did not report max_freq correctly.
545 return;
546 if (max_freq == 0 || testing_) {
547 // One-time initialization of max_freq. (Every time when testing.)
548 if (!ReadFreqToInt(cpuinfo_max_freq_path_, &max_freq)) {
549 max_freq = -1;
550 return;
551 }
552 if (max_freq == 0) {
553 LOG(WARNING) << "sysfs reports 0 max CPU frequency\n";
554 max_freq = -1;
555 return;
556 }
557 if (max_freq % 10000 == 1000) {
558 // Special case: system has turbo mode, and max non-turbo frequency is
559 // max_freq - 1000. This relies on "normal" (non-turbo) frequencies
560 // being multiples of (at least) 10 MHz. Although there is no guarantee
561 // of this, it seems a fairly reasonable assumption. Otherwise we should
562 // read scaling_available_frequencies, sort the frequencies, compare the
563 // two highest ones, and check if they differ by 1000 (kHz) (and that's a
564 // hack too, no telling when it will change).
565 max_freq -= 1000;
566 }
567 }
568 int scaled_freq = 0;
569 if (!ReadFreqToInt(scaling_max_freq_path_, &scaled_freq))
570 return;
571 // Frequencies are in kHz. If scaled_freq > max_freq, turbo is on, but
572 // scaled_freq is not the actual turbo frequency. We indicate this situation
573 // with a 101% value.
574 int percent = scaled_freq > max_freq ? 101 : scaled_freq / (max_freq / 100);
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800575 SendLinearSample(kMetricScaledCpuFrequencyName, percent, 101, 102);
Luigi Semenzatofb3a8212013-05-07 16:55:00 -0700576}
577
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700578void MetricsDaemon::ScheduleMeminfoCallback(int wait) {
579 if (testing_) {
580 return;
581 }
Steve Funge86591e2014-12-01 13:38:21 -0800582 base::TimeDelta waitDelta = base::TimeDelta::FromSeconds(wait);
583 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
584 base::Bind(&MetricsDaemon::MeminfoCallback, base::Unretained(this),
Steve Fung8ab89c52015-01-05 13:48:30 -0800585 waitDelta),
Steve Funge86591e2014-12-01 13:38:21 -0800586 waitDelta);
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700587}
588
Steve Funge86591e2014-12-01 13:38:21 -0800589void MetricsDaemon::MeminfoCallback(base::TimeDelta wait) {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700590 string meminfo_raw;
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -0700591 const FilePath meminfo_path(kMeminfoFileName);
Ben Chan2e6543d2014-02-05 23:26:25 -0800592 if (!base::ReadFileToString(meminfo_path, &meminfo_raw)) {
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700593 LOG(WARNING) << "cannot read " << meminfo_path.value().c_str();
Steve Funge86591e2014-12-01 13:38:21 -0800594 return;
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700595 }
Luigi Semenzato96360192014-06-04 10:53:35 -0700596 // Make both calls even if the first one fails.
Bertrand SIMONNETebbe35c2015-09-08 10:13:35 -0700597 if (ProcessMeminfo(meminfo_raw)) {
Steve Funge86591e2014-12-01 13:38:21 -0800598 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
599 base::Bind(&MetricsDaemon::MeminfoCallback, base::Unretained(this),
Steve Fung8ab89c52015-01-05 13:48:30 -0800600 wait),
Steve Funge86591e2014-12-01 13:38:21 -0800601 wait);
602 }
Luigi Semenzato96360192014-06-04 10:53:35 -0700603}
604
605// static
606bool MetricsDaemon::ReadFileToUint64(const base::FilePath& path,
Ben Chanf05ab402014-08-07 00:54:59 -0700607 uint64_t* value) {
Luigi Semenzato96360192014-06-04 10:53:35 -0700608 std::string content;
609 if (!base::ReadFileToString(path, &content)) {
610 PLOG(WARNING) << "cannot read " << path.MaybeAsASCII();
611 return false;
612 }
Luigi Semenzato4a6c9422014-06-30 18:12:28 -0700613 // Remove final newline.
614 base::TrimWhitespaceASCII(content, base::TRIM_TRAILING, &content);
Luigi Semenzato96360192014-06-04 10:53:35 -0700615 if (!base::StringToUint64(content, value)) {
616 LOG(WARNING) << "invalid integer: " << content;
617 return false;
618 }
619 return true;
620}
621
622bool MetricsDaemon::ReportZram(const base::FilePath& zram_dir) {
623 // Data sizes are in bytes. |zero_pages| is in number of pages.
Ben Chanf05ab402014-08-07 00:54:59 -0700624 uint64_t compr_data_size, orig_data_size, zero_pages;
Luigi Semenzato96360192014-06-04 10:53:35 -0700625 const size_t page_size = 4096;
626
627 if (!ReadFileToUint64(zram_dir.Append(kComprDataSizeName),
628 &compr_data_size) ||
629 !ReadFileToUint64(zram_dir.Append(kOrigDataSizeName), &orig_data_size) ||
630 !ReadFileToUint64(zram_dir.Append(kZeroPagesName), &zero_pages)) {
631 return false;
632 }
633
634 // |orig_data_size| does not include zero-filled pages.
635 orig_data_size += zero_pages * page_size;
636
637 const int compr_data_size_mb = compr_data_size >> 20;
638 const int savings_mb = (orig_data_size - compr_data_size) >> 20;
639 const int zero_ratio_percent = zero_pages * page_size * 100 / orig_data_size;
640
641 // Report compressed size in megabytes. 100 MB or less has little impact.
642 SendSample("Platform.ZramCompressedSize", compr_data_size_mb, 100, 4000, 50);
643 SendSample("Platform.ZramSavings", savings_mb, 100, 4000, 50);
644 // The compression ratio is multiplied by 100 for better resolution. The
645 // ratios of interest are between 1 and 6 (100% and 600% as reported). We
646 // don't want samples when very little memory is being compressed.
647 if (compr_data_size_mb >= 1) {
648 SendSample("Platform.ZramCompressionRatioPercent",
649 orig_data_size * 100 / compr_data_size, 100, 600, 50);
650 }
651 // The values of interest for zero_pages are between 1MB and 1GB. The units
652 // are number of pages.
653 SendSample("Platform.ZramZeroPages", zero_pages, 256, 256 * 1024, 50);
654 SendSample("Platform.ZramZeroRatioPercent", zero_ratio_percent, 1, 50, 50);
655
656 return true;
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700657}
658
Luigi Semenzato5bd764f2011-10-14 12:03:35 -0700659bool MetricsDaemon::ProcessMeminfo(const string& meminfo_raw) {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700660 static const MeminfoRecord fields_array[] = {
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700661 { "MemTotal", "MemTotal" }, // SPECIAL CASE: total system memory
662 { "MemFree", "MemFree" },
663 { "Buffers", "Buffers" },
664 { "Cached", "Cached" },
665 // { "SwapCached", "SwapCached" },
666 { "Active", "Active" },
667 { "Inactive", "Inactive" },
668 { "ActiveAnon", "Active(anon)" },
669 { "InactiveAnon", "Inactive(anon)" },
670 { "ActiveFile" , "Active(file)" },
671 { "InactiveFile", "Inactive(file)" },
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800672 { "Unevictable", "Unevictable", kMeminfoOp_HistLog },
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700673 // { "Mlocked", "Mlocked" },
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800674 { "SwapTotal", "SwapTotal", kMeminfoOp_SwapTotal },
675 { "SwapFree", "SwapFree", kMeminfoOp_SwapFree },
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700676 // { "Dirty", "Dirty" },
677 // { "Writeback", "Writeback" },
678 { "AnonPages", "AnonPages" },
679 { "Mapped", "Mapped" },
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800680 { "Shmem", "Shmem", kMeminfoOp_HistLog },
681 { "Slab", "Slab", kMeminfoOp_HistLog },
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700682 // { "SReclaimable", "SReclaimable" },
683 // { "SUnreclaim", "SUnreclaim" },
684 };
Luigi Semenzato8accd332011-05-17 16:37:18 -0700685 vector<MeminfoRecord> fields(fields_array,
686 fields_array + arraysize(fields_array));
687 if (!FillMeminfo(meminfo_raw, &fields)) {
688 return false;
689 }
690 int total_memory = fields[0].value;
691 if (total_memory == 0) {
692 // this "cannot happen"
693 LOG(WARNING) << "borked meminfo parser";
694 return false;
695 }
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800696 int swap_total = 0;
697 int swap_free = 0;
Luigi Semenzato8accd332011-05-17 16:37:18 -0700698 // Send all fields retrieved, except total memory.
699 for (unsigned int i = 1; i < fields.size(); i++) {
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800700 string metrics_name = base::StringPrintf("Platform.Meminfo%s",
701 fields[i].name);
Luigi Semenzato3ccca062013-02-04 19:50:45 -0800702 int percent;
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800703 switch (fields[i].op) {
704 case kMeminfoOp_HistPercent:
Luigi Semenzato3ccca062013-02-04 19:50:45 -0800705 // report value as percent of total memory
706 percent = fields[i].value * 100 / total_memory;
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800707 SendLinearSample(metrics_name, percent, 100, 101);
Luigi Semenzato3ccca062013-02-04 19:50:45 -0800708 break;
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800709 case kMeminfoOp_HistLog:
Luigi Semenzato3ccca062013-02-04 19:50:45 -0800710 // report value in kbytes, log scale, 4Gb max
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800711 SendSample(metrics_name, fields[i].value, 1, 4 * 1000 * 1000, 100);
Luigi Semenzato3ccca062013-02-04 19:50:45 -0800712 break;
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800713 case kMeminfoOp_SwapTotal:
714 swap_total = fields[i].value;
715 case kMeminfoOp_SwapFree:
716 swap_free = fields[i].value;
Luigi Semenzato3ccca062013-02-04 19:50:45 -0800717 break;
Luigi Semenzato8accd332011-05-17 16:37:18 -0700718 }
719 }
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800720 if (swap_total > 0) {
721 int swap_used = swap_total - swap_free;
722 int swap_used_percent = swap_used * 100 / swap_total;
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800723 SendSample("Platform.MeminfoSwapUsed", swap_used, 1, 8 * 1000 * 1000, 100);
Bertrand SIMONNET008fb7e2015-09-21 16:48:01 -0700724 SendLinearSample("Platform.MeminfoSwapUsed.Percent", swap_used_percent,
Luigi Semenzato942cbab2013-02-12 13:17:07 -0800725 100, 101);
726 }
Luigi Semenzato8accd332011-05-17 16:37:18 -0700727 return true;
728}
729
Luigi Semenzato5bd764f2011-10-14 12:03:35 -0700730bool MetricsDaemon::FillMeminfo(const string& meminfo_raw,
731 vector<MeminfoRecord>* fields) {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700732 vector<string> lines;
733 unsigned int nlines = Tokenize(meminfo_raw, "\n", &lines);
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700734
735 // Scan meminfo output and collect field values. Each field name has to
736 // match a meminfo entry (case insensitive) after removing non-alpha
737 // characters from the entry.
Luigi Semenzato8accd332011-05-17 16:37:18 -0700738 unsigned int ifield = 0;
739 for (unsigned int iline = 0;
740 iline < nlines && ifield < fields->size();
741 iline++) {
742 vector<string> tokens;
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700743 Tokenize(lines[iline], ": ", &tokens);
Luigi Semenzato8accd332011-05-17 16:37:18 -0700744 if (strcmp((*fields)[ifield].match, tokens[0].c_str()) == 0) {
745 // Name matches. Parse value and save.
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -0700746 if (!base::StringToInt(tokens[1], &(*fields)[ifield].value)) {
747 LOG(WARNING) << "Cound not convert " << tokens[1] << " to int";
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700748 return false;
749 }
Luigi Semenzato8accd332011-05-17 16:37:18 -0700750 ifield++;
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700751 }
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700752 }
Luigi Semenzato8accd332011-05-17 16:37:18 -0700753 if (ifield < fields->size()) {
754 // End of input reached while scanning.
755 LOG(WARNING) << "cannot find field " << (*fields)[ifield].match
756 << " and following";
757 return false;
758 }
759 return true;
760}
761
Luigi Semenzato0d9a9c92013-12-05 15:55:12 -0800762void MetricsDaemon::ScheduleMemuseCallback(double interval) {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700763 if (testing_) {
764 return;
765 }
Steve Funge86591e2014-12-01 13:38:21 -0800766 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
767 base::Bind(&MetricsDaemon::MemuseCallback, base::Unretained(this)),
768 base::TimeDelta::FromSeconds(interval));
Luigi Semenzato8accd332011-05-17 16:37:18 -0700769}
770
771void MetricsDaemon::MemuseCallback() {
772 // Since we only care about active time (i.e. uptime minus sleep time) but
773 // the callbacks are driven by real time (uptime), we check if we should
774 // reschedule this callback due to intervening sleep periods.
775 double now = GetActiveTime();
Luigi Semenzato0d9a9c92013-12-05 15:55:12 -0800776 // Avoid intervals of less than one second.
777 double remaining_time = ceil(memuse_final_time_ - now);
778 if (remaining_time > 0) {
779 ScheduleMemuseCallback(remaining_time);
Luigi Semenzato8accd332011-05-17 16:37:18 -0700780 } else {
Luigi Semenzato0d9a9c92013-12-05 15:55:12 -0800781 // Report stats and advance the measurement interval unless there are
782 // errors or we've completed the last interval.
Luigi Semenzato8accd332011-05-17 16:37:18 -0700783 if (MemuseCallbackWork() &&
Luigi Semenzato0d9a9c92013-12-05 15:55:12 -0800784 memuse_interval_index_ < arraysize(kMemuseIntervals)) {
785 double interval = kMemuseIntervals[memuse_interval_index_++];
786 memuse_final_time_ = now + interval;
787 ScheduleMemuseCallback(interval);
Luigi Semenzato8accd332011-05-17 16:37:18 -0700788 }
789 }
790}
791
Luigi Semenzato5bd764f2011-10-14 12:03:35 -0700792bool MetricsDaemon::MemuseCallbackWork() {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700793 string meminfo_raw;
Bertrand SIMONNET675a10c2015-08-25 14:11:43 -0700794 const FilePath meminfo_path(kMeminfoFileName);
Ben Chan2e6543d2014-02-05 23:26:25 -0800795 if (!base::ReadFileToString(meminfo_path, &meminfo_raw)) {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700796 LOG(WARNING) << "cannot read " << meminfo_path.value().c_str();
797 return false;
798 }
799 return ProcessMemuse(meminfo_raw);
800}
801
Luigi Semenzato5bd764f2011-10-14 12:03:35 -0700802bool MetricsDaemon::ProcessMemuse(const string& meminfo_raw) {
Luigi Semenzato8accd332011-05-17 16:37:18 -0700803 static const MeminfoRecord fields_array[] = {
804 { "MemTotal", "MemTotal" }, // SPECIAL CASE: total system memory
805 { "ActiveAnon", "Active(anon)" },
806 { "InactiveAnon", "Inactive(anon)" },
807 };
808 vector<MeminfoRecord> fields(fields_array,
809 fields_array + arraysize(fields_array));
810 if (!FillMeminfo(meminfo_raw, &fields)) {
811 return false;
812 }
813 int total = fields[0].value;
814 int active_anon = fields[1].value;
815 int inactive_anon = fields[2].value;
816 if (total == 0) {
817 // this "cannot happen"
818 LOG(WARNING) << "borked meminfo parser";
819 return false;
820 }
Luigi Semenzato859b3f02014-02-05 15:33:19 -0800821 string metrics_name = base::StringPrintf("Platform.MemuseAnon%d",
822 memuse_interval_index_);
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800823 SendLinearSample(metrics_name, (active_anon + inactive_anon) * 100 / total,
Luigi Semenzato8accd332011-05-17 16:37:18 -0700824 100, 101);
825 return true;
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700826}
827
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800828void MetricsDaemon::SendSample(const string& name, int sample,
Darin Petkov11b8eb32010-05-18 11:00:59 -0700829 int min, int max, int nbuckets) {
Darin Petkovfc91b422010-05-12 13:05:45 -0700830 metrics_lib_->SendToUMA(name, sample, min, max, nbuckets);
Darin Petkov65b01462010-04-14 13:32:20 -0700831}
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700832
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700833void MetricsDaemon::SendKernelCrashesCumulativeCountStats() {
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800834 // Report the number of crashes for this OS version, but don't clear the
835 // counter. It is cleared elsewhere on version change.
Ben Chanf05ab402014-08-07 00:54:59 -0700836 int64_t crashes_count = kernel_crashes_version_count_->Get();
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800837 SendSample(kernel_crashes_version_count_->Name(),
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700838 crashes_count,
839 1, // value of first bucket
840 500, // value of last bucket
841 100); // number of buckets
842
843
Ben Chanf05ab402014-08-07 00:54:59 -0700844 int64_t cpu_use_ms = version_cumulative_cpu_use_->Get();
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700845 SendSample(version_cumulative_cpu_use_->Name(),
846 cpu_use_ms / 1000, // stat is in seconds
847 1, // device may be used very little...
848 8 * 1000 * 1000, // ... or a lot (a little over 90 days)
849 100);
850
851 // On the first run after an autoupdate, cpu_use_ms and active_use_seconds
852 // can be zero. Avoid division by zero.
853 if (cpu_use_ms > 0) {
854 // Send the crash frequency since update in number of crashes per CPU year.
855 SendSample("Logging.KernelCrashesPerCpuYear",
856 crashes_count * kSecondsPerDay * 365 * 1000 / cpu_use_ms,
857 1,
858 1000 * 1000, // about one crash every 30s of CPU time
859 100);
860 }
861
Ben Chanf05ab402014-08-07 00:54:59 -0700862 int64_t active_use_seconds = version_cumulative_active_use_->Get();
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700863 if (active_use_seconds > 0) {
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700864 SendSample(version_cumulative_active_use_->Name(),
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700865 active_use_seconds,
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700866 1, // device may be used very little...
867 8 * 1000 * 1000, // ... or a lot (about 90 days)
868 100);
Luigi Semenzatoba0c65d2014-03-17 12:28:38 -0700869 // Same as above, but per year of active time.
870 SendSample("Logging.KernelCrashesPerActiveYear",
871 crashes_count * kSecondsPerDay * 365 / active_use_seconds,
872 1,
873 1000 * 1000, // about one crash every 30s of active time
874 100);
875 }
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800876}
877
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700878void MetricsDaemon::SendAndResetDailyUseSample(
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700879 const scoped_ptr<PersistentInteger>& use) {
880 SendSample(use->Name(),
881 use->GetAndClear(),
882 1, // value of first bucket
883 kSecondsPerDay, // value of last bucket
884 50); // number of buckets
885}
886
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700887void MetricsDaemon::SendAndResetCrashIntervalSample(
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800888 const scoped_ptr<PersistentInteger>& interval) {
889 SendSample(interval->Name(),
890 interval->GetAndClear(),
891 1, // value of first bucket
892 4 * kSecondsPerWeek, // value of last bucket
893 50); // number of buckets
894}
895
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700896void MetricsDaemon::SendAndResetCrashFrequencySample(
Luigi Semenzato2fd51cc2014-02-26 11:53:16 -0800897 const scoped_ptr<PersistentInteger>& frequency) {
898 SendSample(frequency->Name(),
899 frequency->GetAndClear(),
900 1, // value of first bucket
901 100, // value of last bucket
902 50); // number of buckets
903}
904
905void MetricsDaemon::SendLinearSample(const string& name, int sample,
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700906 int max, int nbuckets) {
Luigi Semenzato29c7ef92011-04-12 14:12:35 -0700907 // TODO(semenzato): add a proper linear histogram to the Chrome external
908 // metrics API.
909 LOG_IF(FATAL, nbuckets != max + 1) << "unsupported histogram scale";
910 metrics_lib_->SendEnumToUMA(name, sample, max);
911}
Daniel Eratc83975a2014-04-04 08:53:44 -0700912
913void MetricsDaemon::UpdateStats(TimeTicks now_ticks,
914 Time now_wall_time) {
915 const int elapsed_seconds = (now_ticks - last_update_stats_time_).InSeconds();
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700916 daily_active_use_->Add(elapsed_seconds);
917 version_cumulative_active_use_->Add(elapsed_seconds);
Daniel Eratc83975a2014-04-04 08:53:44 -0700918 user_crash_interval_->Add(elapsed_seconds);
919 kernel_crash_interval_->Add(elapsed_seconds);
920 version_cumulative_cpu_use_->Add(GetIncrementalCpuUse().InMilliseconds());
921 last_update_stats_time_ = now_ticks;
922
923 const TimeDelta since_epoch = now_wall_time - Time::UnixEpoch();
924 const int day = since_epoch.InDays();
925 const int week = day / 7;
926
927 if (daily_cycle_->Get() != day) {
928 daily_cycle_->Set(day);
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700929 SendAndResetDailyUseSample(daily_active_use_);
930 SendAndResetCrashFrequencySample(any_crashes_daily_count_);
931 SendAndResetCrashFrequencySample(user_crashes_daily_count_);
932 SendAndResetCrashFrequencySample(kernel_crashes_daily_count_);
933 SendAndResetCrashFrequencySample(unclean_shutdowns_daily_count_);
Luigi Semenzatoe5883fa2014-04-18 17:00:35 -0700934 SendKernelCrashesCumulativeCountStats();
Daniel Eratc83975a2014-04-04 08:53:44 -0700935 }
936
937 if (weekly_cycle_->Get() != week) {
938 weekly_cycle_->Set(week);
Bertrand SIMONNET2d037832015-09-16 17:11:51 -0700939 SendAndResetCrashFrequencySample(any_crashes_weekly_count_);
940 SendAndResetCrashFrequencySample(user_crashes_weekly_count_);
941 SendAndResetCrashFrequencySample(kernel_crashes_weekly_count_);
942 SendAndResetCrashFrequencySample(unclean_shutdowns_weekly_count_);
Daniel Eratc83975a2014-04-04 08:53:44 -0700943 }
944}
945
Steve Funge86591e2014-12-01 13:38:21 -0800946void MetricsDaemon::HandleUpdateStatsTimeout() {
947 UpdateStats(TimeTicks::Now(), Time::Now());
948 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
949 base::Bind(&MetricsDaemon::HandleUpdateStatsTimeout,
950 base::Unretained(this)),
951 base::TimeDelta::FromMilliseconds(kUpdateStatsIntervalMs));
Daniel Eratc83975a2014-04-04 08:53:44 -0700952}