blob: deb445bf2d651660a09540dcc4f28495bc632fd7 [file] [log] [blame]
// Copyright 2014 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 "update_engine/metrics.h"
#include <string>
#include <base/logging.h>
#include "update_engine/clock_interface.h"
#include "update_engine/constants.h"
#include "update_engine/prefs_interface.h"
#include "update_engine/system_state.h"
#include "update_engine/utils.h"
using std::string;
namespace chromeos_update_engine {
namespace metrics {
// UpdateEngine.Daily.* metrics.
const char kMetricDailyOSAgeDays[] = "UpdateEngine.Daily.OSAgeDays";
// UpdateEngine.Check.* metrics.
const char kMetricCheckDownloadErrorCode[] =
"UpdateEngine.Check.DownloadErrorCode";
const char kMetricCheckReaction[] = "UpdateEngine.Check.Reaction";
const char kMetricCheckResult[] = "UpdateEngine.Check.Result";
const char kMetricCheckTimeSinceLastCheckMinutes[] =
"UpdateEngine.Check.TimeSinceLastCheckMinutes";
const char kMetricCheckTimeSinceLastCheckUptimeMinutes[] =
"UpdateEngine.Check.TimeSinceLastCheckUptimeMinutes";
// UpdateEngine.Attempt.* metrics.
const char kMetricAttemptNumber[] = "UpdateEngine.Attempt.Number";
const char kMetricAttemptPayloadType[] =
"UpdateEngine.Attempt.PayloadType";
const char kMetricAttemptPayloadSizeMiB[] =
"UpdateEngine.Attempt.PayloadSizeMiB";
const char kMetricAttemptConnectionType[] =
"UpdateEngine.Attempt.ConnectionType";
const char kMetricAttemptDurationMinutes[] =
"UpdateEngine.Attempt.DurationMinutes";
const char kMetricAttemptDurationUptimeMinutes[] =
"UpdateEngine.Attempt.DurationUptimeMinutes";
const char kMetricAttemptTimeSinceLastAttemptMinutes[] =
"UpdateEngine.Attempt.TimeSinceLastAttemptMinutes";
const char kMetricAttemptTimeSinceLastAttemptUptimeMinutes[] =
"UpdateEngine.Attempt.TimeSinceLastAttemptUptimeMinutes";
const char kMetricAttemptPayloadBytesDownloadedMiB[] =
"UpdateEngine.Attempt.PayloadBytesDownloadedMiB";
const char kMetricAttemptPayloadDownloadSpeedKBps[] =
"UpdateEngine.Attempt.PayloadDownloadSpeedKBps";
const char kMetricAttemptDownloadSource[] =
"UpdateEngine.Attempt.DownloadSource";
const char kMetricAttemptResult[] =
"UpdateEngine.Attempt.Result";
const char kMetricAttemptInternalErrorCode[] =
"UpdateEngine.Attempt.InternalErrorCode";
const char kMetricAttemptDownloadErrorCode[] =
"UpdateEngine.Attempt.DownloadErrorCode";
// UpdateEngine.SuccessfulUpdate.* metrics.
const char kMetricSuccessfulUpdateAttemptCount[] =
"UpdateEngine.SuccessfulUpdate.AttemptCount";
const char kMetricSuccessfulUpdateBytesDownloadedMiB[] =
"UpdateEngine.SuccessfulUpdate.BytesDownloadedMiB";
const char kMetricSuccessfulUpdateDownloadOverheadPercentage[] =
"UpdateEngine.SuccessfulUpdate.DownloadOverheadPercentage";
const char kMetricSuccessfulUpdateDownloadSourcesUsed[] =
"UpdateEngine.SuccessfulUpdate.DownloadSourcesUsed";
const char kMetricSuccessfulUpdatePayloadType[] =
"UpdateEngine.SuccessfulUpdate.PayloadType";
const char kMetricSuccessfulUpdatePayloadSizeMiB[] =
"UpdateEngine.SuccessfulUpdate.PayloadSizeMiB";
const char kMetricSuccessfulUpdateRebootCount[] =
"UpdateEngine.SuccessfulUpdate.RebootCount";
const char kMetricSuccessfulUpdateTotalDurationMinutes[] =
"UpdateEngine.SuccessfulUpdate.TotalDurationMinutes";
const char kMetricSuccessfulUpdateUpdatesAbandonedCount[] =
"UpdateEngine.SuccessfulUpdate.UpdatesAbandonedCount";
const char kMetricSuccessfulUpdateUrlSwitchCount[] =
"UpdateEngine.SuccessfulUpdate.UrlSwitchCount";
// UpdateEngine.Rollback.* metric.
const char kMetricRollbackResult[] = "UpdateEngine.Rollback.Result";
// UpdateEngine.* metrics.
const char kMetricFailedUpdateCount[] = "UpdateEngine.FailedUpdateCount";
const char kMetricInstallDateProvisioningSource[] =
"UpdateEngine.InstallDateProvisioningSource";
const char kMetricTimeToRebootMinutes[] =
"UpdateEngine.TimeToRebootMinutes";
void ReportDailyMetrics(SystemState *system_state,
base::TimeDelta os_age) {
string metric = metrics::kMetricDailyOSAgeDays;
LOG(INFO) << "Uploading " << utils::FormatTimeDelta(os_age)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
static_cast<int>(os_age.InDays()),
0, // min: 0 days
6*30, // max: 6 months (approx)
50); // num_buckets
}
void ReportUpdateCheckMetrics(SystemState *system_state,
CheckResult result,
CheckReaction reaction,
DownloadErrorCode download_error_code) {
string metric;
int value;
int max_value;
if (result != metrics::CheckResult::kUnset) {
metric = metrics::kMetricCheckResult;
value = static_cast<int>(result);
max_value = static_cast<int>(metrics::CheckResult::kNumConstants) - 1;
LOG(INFO) << "Sending " << value << " for metric " << metric << " (enum)";
system_state->metrics_lib()->SendEnumToUMA(metric, value, max_value);
}
if (reaction != metrics::CheckReaction::kUnset) {
metric = metrics::kMetricCheckReaction;
value = static_cast<int>(reaction);
max_value = static_cast<int>(metrics::CheckReaction::kNumConstants) - 1;
LOG(INFO) << "Sending " << value << " for metric " << metric << " (enum)";
system_state->metrics_lib()->SendEnumToUMA(metric, value, max_value);
}
if (download_error_code != metrics::DownloadErrorCode::kUnset) {
metric = metrics::kMetricCheckDownloadErrorCode;
value = static_cast<int>(download_error_code);
LOG(INFO) << "Sending " << value << " for metric " << metric << " (sparse)";
system_state->metrics_lib()->SendSparseToUMA(metric, value);
}
base::TimeDelta time_since_last;
if (utils::WallclockDurationHelper(system_state,
kPrefsMetricsCheckLastReportingTime,
&time_since_last)) {
metric = kMetricCheckTimeSinceLastCheckMinutes;
LOG(INFO) << "Sending " << utils::FormatTimeDelta(time_since_last)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
time_since_last.InMinutes(),
0, // min: 0 min
30*24*60, // max: 30 days
50); // num_buckets
}
base::TimeDelta uptime_since_last;
static int64_t uptime_since_last_storage = 0;
if (utils::MonotonicDurationHelper(system_state,
&uptime_since_last_storage,
&uptime_since_last)) {
metric = kMetricCheckTimeSinceLastCheckUptimeMinutes;
LOG(INFO) << "Sending " << utils::FormatTimeDelta(uptime_since_last)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
uptime_since_last.InMinutes(),
0, // min: 0 min
30*24*60, // max: 30 days
50); // num_buckets
}
}
void ReportAbnormallyTerminatedUpdateAttemptMetrics(
SystemState *system_state) {
string metric = metrics::kMetricAttemptResult;
AttemptResult attempt_result = AttemptResult::kAbnormalTermination;
LOG(INFO) << "Uploading " << static_cast<int>(attempt_result)
<< " for metric " << metric;
system_state->metrics_lib()->SendEnumToUMA(
metric,
static_cast<int>(attempt_result),
static_cast<int>(AttemptResult::kNumConstants));
}
void ReportUpdateAttemptMetrics(
SystemState *system_state,
int attempt_number,
PayloadType payload_type,
base::TimeDelta duration,
base::TimeDelta duration_uptime,
int64_t payload_size,
int64_t payload_bytes_downloaded,
int64_t payload_download_speed_bps,
DownloadSource download_source,
AttemptResult attempt_result,
ErrorCode internal_error_code,
DownloadErrorCode payload_download_error_code,
ConnectionType connection_type) {
string metric;
metric = metrics::kMetricAttemptNumber;
LOG(INFO) << "Uploading " << attempt_number << " for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
attempt_number,
0, // min: 0 attempts
49, // max: 49 attempts
50); // num_buckets
metric = metrics::kMetricAttemptPayloadType;
LOG(INFO) << "Uploading " << utils::ToString(payload_type)
<< " for metric " << metric;
system_state->metrics_lib()->SendEnumToUMA(metric,
payload_type,
kNumPayloadTypes);
metric = metrics::kMetricAttemptDurationMinutes;
LOG(INFO) << "Uploading " << utils::FormatTimeDelta(duration)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
duration.InMinutes(),
0, // min: 0 min
10*24*60, // max: 10 days
50); // num_buckets
metric = metrics::kMetricAttemptDurationUptimeMinutes;
LOG(INFO) << "Uploading " << utils::FormatTimeDelta(duration_uptime)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
duration_uptime.InMinutes(),
0, // min: 0 min
10*24*60, // max: 10 days
50); // num_buckets
metric = metrics::kMetricAttemptPayloadSizeMiB;
int64_t payload_size_mib = payload_size / kNumBytesInOneMiB;
LOG(INFO) << "Uploading " << payload_size_mib << " for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
payload_size_mib,
0, // min: 0 MiB
1024, // max: 1024 MiB = 1 GiB
50); // num_buckets
metric = metrics::kMetricAttemptPayloadBytesDownloadedMiB;
int64_t payload_bytes_downloaded_mib =
payload_bytes_downloaded / kNumBytesInOneMiB;
LOG(INFO) << "Uploading " << payload_bytes_downloaded_mib
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
payload_bytes_downloaded_mib,
0, // min: 0 MiB
1024, // max: 1024 MiB = 1 GiB
50); // num_buckets
metric = metrics::kMetricAttemptPayloadDownloadSpeedKBps;
int64_t payload_download_speed_kbps = payload_download_speed_bps / 1000;
LOG(INFO) << "Uploading " << payload_download_speed_kbps
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
payload_download_speed_kbps,
0, // min: 0 kB/s
10*1000, // max: 10000 kB/s = 10 MB/s
50); // num_buckets
metric = metrics::kMetricAttemptDownloadSource;
LOG(INFO) << "Uploading " << download_source
<< " for metric " << metric;
system_state->metrics_lib()->SendEnumToUMA(metric,
download_source,
kNumDownloadSources);
metric = metrics::kMetricAttemptResult;
LOG(INFO) << "Uploading " << static_cast<int>(attempt_result)
<< " for metric " << metric;
system_state->metrics_lib()->SendEnumToUMA(
metric,
static_cast<int>(attempt_result),
static_cast<int>(AttemptResult::kNumConstants));
if (internal_error_code != ErrorCode::kSuccess) {
metric = metrics::kMetricAttemptInternalErrorCode;
LOG(INFO) << "Uploading " << internal_error_code
<< " for metric " << metric;
system_state->metrics_lib()->SendEnumToUMA(
metric,
static_cast<int>(internal_error_code),
static_cast<int>(ErrorCode::kUmaReportedMax));
}
if (payload_download_error_code != DownloadErrorCode::kUnset) {
metric = metrics::kMetricAttemptDownloadErrorCode;
LOG(INFO) << "Uploading " << static_cast<int>(payload_download_error_code)
<< " for metric " << metric << " (sparse)";
system_state->metrics_lib()->SendSparseToUMA(
metric,
static_cast<int>(payload_download_error_code));
}
base::TimeDelta time_since_last;
if (utils::WallclockDurationHelper(system_state,
kPrefsMetricsAttemptLastReportingTime,
&time_since_last)) {
metric = kMetricAttemptTimeSinceLastAttemptMinutes;
LOG(INFO) << "Sending " << utils::FormatTimeDelta(time_since_last)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
time_since_last.InMinutes(),
0, // min: 0 min
30*24*60, // max: 30 days
50); // num_buckets
}
static int64_t uptime_since_last_storage = 0;
base::TimeDelta uptime_since_last;
if (utils::MonotonicDurationHelper(system_state,
&uptime_since_last_storage,
&uptime_since_last)) {
metric = kMetricAttemptTimeSinceLastAttemptUptimeMinutes;
LOG(INFO) << "Sending " << utils::FormatTimeDelta(uptime_since_last)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
uptime_since_last.InMinutes(),
0, // min: 0 min
30*24*60, // max: 30 days
50); // num_buckets
}
metric = metrics::kMetricAttemptConnectionType;
LOG(INFO) << "Uploading " << static_cast<int>(connection_type)
<< " for metric " << metric;
system_state->metrics_lib()->SendEnumToUMA(
metric,
static_cast<int>(connection_type),
static_cast<int>(ConnectionType::kNumConstants));
}
void ReportSuccessfulUpdateMetrics(
SystemState *system_state,
int attempt_count,
int updates_abandoned_count,
PayloadType payload_type,
int64_t payload_size,
int64_t num_bytes_downloaded[kNumDownloadSources],
int download_overhead_percentage,
base::TimeDelta total_duration,
int reboot_count,
int url_switch_count) {
string metric;
int64_t mbs;
metric = kMetricSuccessfulUpdatePayloadSizeMiB;
mbs = payload_size / kNumBytesInOneMiB;
LOG(INFO) << "Uploading " << mbs << " (MiBs) for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
mbs,
0, // min: 0 MiB
1024, // max: 1024 MiB = 1 GiB
50); // num_buckets
int64_t total_bytes = 0;
int download_sources_used = 0;
for (int i = 0; i < kNumDownloadSources + 1; i++) {
DownloadSource source = static_cast<DownloadSource>(i);
// Only consider this download source (and send byte counts) as
// having been used if we downloaded a non-trivial amount of bytes
// (e.g. at least 1 MiB) that contributed to the
// update. Otherwise we're going to end up with a lot of zero-byte
// events in the histogram.
metric = metrics::kMetricSuccessfulUpdateBytesDownloadedMiB;
if (i < kNumDownloadSources) {
metric += utils::ToString(source);
mbs = num_bytes_downloaded[i] / kNumBytesInOneMiB;
total_bytes += num_bytes_downloaded[i];
if (mbs > 0)
download_sources_used |= (1 << i);
} else {
mbs = total_bytes / kNumBytesInOneMiB;
}
if (mbs > 0) {
LOG(INFO) << "Uploading " << mbs << " (MiBs) for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
mbs,
0, // min: 0 MiB
1024, // max: 1024 MiB = 1 GiB
50); // num_buckets
}
}
metric = metrics::kMetricSuccessfulUpdateDownloadSourcesUsed;
LOG(INFO) << "Uploading 0x" << std::hex << download_sources_used
<< " (bit flags) for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
download_sources_used,
0, // min
(1 << kNumDownloadSources) - 1, // max
1 << kNumDownloadSources); // num_buckets
metric = metrics::kMetricSuccessfulUpdateDownloadOverheadPercentage;
LOG(INFO) << "Uploading " << download_overhead_percentage
<< "% for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
download_overhead_percentage,
0, // min: 0% overhead
1000, // max: 1000% overhead
50); // num_buckets
metric = metrics::kMetricSuccessfulUpdateUrlSwitchCount;
LOG(INFO) << "Uploading " << url_switch_count
<< " (count) for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
url_switch_count,
0, // min: 0 URL switches
49, // max: 49 URL switches
50); // num_buckets
metric = metrics::kMetricSuccessfulUpdateTotalDurationMinutes;
LOG(INFO) << "Uploading " << utils::FormatTimeDelta(total_duration)
<< " for metric " << metric;
system_state->metrics_lib()->SendToUMA(
metric,
static_cast<int>(total_duration.InMinutes()),
0, // min: 0 min
365*24*60, // max: 365 days ~= 1 year
50); // num_buckets
metric = metrics::kMetricSuccessfulUpdateRebootCount;
LOG(INFO) << "Uploading reboot count of " << reboot_count << " for metric "
<< metric;
system_state->metrics_lib()->SendToUMA(metric,
reboot_count,
0, // min: 0 reboots
49, // max: 49 reboots
50); // num_buckets
metric = metrics::kMetricSuccessfulUpdatePayloadType;
system_state->metrics_lib()->SendEnumToUMA(metric,
payload_type,
kNumPayloadTypes);
LOG(INFO) << "Uploading " << utils::ToString(payload_type)
<< " for metric " << metric;
metric = metrics::kMetricSuccessfulUpdateAttemptCount;
system_state->metrics_lib()->SendToUMA(metric,
attempt_count,
1, // min: 1 attempt
50, // max: 50 attempts
50); // num_buckets
LOG(INFO) << "Uploading " << attempt_count
<< " for metric " << metric;
metric = metrics::kMetricSuccessfulUpdateUpdatesAbandonedCount;
LOG(INFO) << "Uploading " << updates_abandoned_count
<< " (count) for metric " << metric;
system_state->metrics_lib()->SendToUMA(metric,
updates_abandoned_count,
0, // min: 0 counts
49, // max: 49 counts
50); // num_buckets
}
void ReportRollbackMetrics(SystemState *system_state,
RollbackResult result) {
string metric;
int value;
metric = metrics::kMetricRollbackResult;
value = static_cast<int>(result);
LOG(INFO) << "Sending " << value << " for metric " << metric << " (enum)";
system_state->metrics_lib()->SendEnumToUMA(
metric,
value,
static_cast<int>(metrics::RollbackResult::kNumConstants));
}
} // namespace metrics
} // namespace chromeos_update_engine