// Copyright 2014 The Chromium Authors. All rights reserved. // Use of this source code is governed by a BSD-style license that can be // found in the LICENSE file. #include "components/metrics/metrics_log.h" #include <string> #include "base/base64.h" #include "base/basictypes.h" #include "base/metrics/bucket_ranges.h" #include "base/metrics/sample_vector.h" #include "base/prefs/pref_service.h" #include "base/prefs/testing_pref_service.h" #include "base/strings/string_number_conversions.h" #include "base/time/time.h" #include "components/metrics/metrics_pref_names.h" #include "components/metrics/metrics_state_manager.h" #include "components/metrics/proto/chrome_user_metrics_extension.pb.h" #include "components/metrics/test_metrics_service_client.h" #include "components/variations/active_field_trials.h" #include "testing/gtest/include/gtest/gtest.h" namespace metrics { namespace { const char kClientId[] = "bogus client ID"; const int64 kInstallDate = 1373051956; const int64 kInstallDateExpected = 1373050800; // Computed from kInstallDate. const int64 kEnabledDate = 1373001211; const int64 kEnabledDateExpected = 1373000400; // Computed from kEnabledDate. const int kSessionId = 127; const variations::ActiveGroupId kFieldTrialIds[] = { {37, 43}, {13, 47}, {23, 17} }; const variations::ActiveGroupId kSyntheticTrials[] = { {55, 15}, {66, 16} }; class TestMetricsLog : public MetricsLog { public: TestMetricsLog(const std::string& client_id, int session_id, LogType log_type, metrics::MetricsServiceClient* client, TestingPrefServiceSimple* prefs) : MetricsLog(client_id, session_id, log_type, client, prefs), prefs_(prefs) { InitPrefs(); } virtual ~TestMetricsLog() {} const metrics::ChromeUserMetricsExtension& uma_proto() const { return *MetricsLog::uma_proto(); } const metrics::SystemProfileProto& system_profile() const { return uma_proto().system_profile(); } private: void InitPrefs() { prefs_->SetString(metrics::prefs::kMetricsReportingEnabledTimestamp, base::Int64ToString(kEnabledDate)); } virtual void GetFieldTrialIds( std::vector<variations::ActiveGroupId>* field_trial_ids) const OVERRIDE { ASSERT_TRUE(field_trial_ids->empty()); for (size_t i = 0; i < arraysize(kFieldTrialIds); ++i) { field_trial_ids->push_back(kFieldTrialIds[i]); } } // Weak pointer to the PrefsService used by this log. TestingPrefServiceSimple* prefs_; DISALLOW_COPY_AND_ASSIGN(TestMetricsLog); }; } // namespace class MetricsLogTest : public testing::Test { public: MetricsLogTest() { MetricsLog::RegisterPrefs(prefs_.registry()); metrics::MetricsStateManager::RegisterPrefs(prefs_.registry()); } virtual ~MetricsLogTest() { } protected: // Check that the values in |system_values| correspond to the test data // defined at the top of this file. void CheckSystemProfile(const metrics::SystemProfileProto& system_profile) { EXPECT_EQ(kInstallDateExpected, system_profile.install_date()); EXPECT_EQ(kEnabledDateExpected, system_profile.uma_enabled_date()); ASSERT_EQ(arraysize(kFieldTrialIds) + arraysize(kSyntheticTrials), static_cast<size_t>(system_profile.field_trial_size())); for (size_t i = 0; i < arraysize(kFieldTrialIds); ++i) { const metrics::SystemProfileProto::FieldTrial& field_trial = system_profile.field_trial(i); EXPECT_EQ(kFieldTrialIds[i].name, field_trial.name_id()); EXPECT_EQ(kFieldTrialIds[i].group, field_trial.group_id()); } // Verify the right data is present for the synthetic trials. for (size_t i = 0; i < arraysize(kSyntheticTrials); ++i) { const metrics::SystemProfileProto::FieldTrial& field_trial = system_profile.field_trial(i + arraysize(kFieldTrialIds)); EXPECT_EQ(kSyntheticTrials[i].name, field_trial.name_id()); EXPECT_EQ(kSyntheticTrials[i].group, field_trial.group_id()); } EXPECT_EQ(metrics::TestMetricsServiceClient::kBrandForTesting, system_profile.brand_code()); const metrics::SystemProfileProto::Hardware& hardware = system_profile.hardware(); EXPECT_TRUE(hardware.has_cpu()); EXPECT_TRUE(hardware.cpu().has_vendor_name()); EXPECT_TRUE(hardware.cpu().has_signature()); // TODO(isherman): Verify other data written into the protobuf as a result // of this call. } protected: TestingPrefServiceSimple prefs_; private: DISALLOW_COPY_AND_ASSIGN(MetricsLogTest); }; TEST_F(MetricsLogTest, LogType) { TestMetricsServiceClient client; TestingPrefServiceSimple prefs; MetricsLog log1("id", 0, MetricsLog::ONGOING_LOG, &client, &prefs); EXPECT_EQ(MetricsLog::ONGOING_LOG, log1.log_type()); MetricsLog log2("id", 0, MetricsLog::INITIAL_STABILITY_LOG, &client, &prefs); EXPECT_EQ(MetricsLog::INITIAL_STABILITY_LOG, log2.log_type()); } TEST_F(MetricsLogTest, EmptyRecord) { TestMetricsServiceClient client; client.set_version_string("bogus version"); TestingPrefServiceSimple prefs; MetricsLog log("totally bogus client ID", 137, MetricsLog::ONGOING_LOG, &client, &prefs); log.CloseLog(); std::string encoded; log.GetEncodedLog(&encoded); // A couple of fields are hard to mock, so these will be copied over directly // for the expected output. ChromeUserMetricsExtension parsed; ASSERT_TRUE(parsed.ParseFromString(encoded)); ChromeUserMetricsExtension expected; expected.set_client_id(5217101509553811875); // Hashed bogus client ID expected.set_session_id(137); expected.mutable_system_profile()->set_build_timestamp( parsed.system_profile().build_timestamp()); expected.mutable_system_profile()->set_app_version("bogus version"); expected.mutable_system_profile()->set_channel(client.GetChannel()); EXPECT_EQ(expected.SerializeAsString(), encoded); } TEST_F(MetricsLogTest, HistogramBucketFields) { // Create buckets: 1-5, 5-7, 7-8, 8-9, 9-10, 10-11, 11-12. base::BucketRanges ranges(8); ranges.set_range(0, 1); ranges.set_range(1, 5); ranges.set_range(2, 7); ranges.set_range(3, 8); ranges.set_range(4, 9); ranges.set_range(5, 10); ranges.set_range(6, 11); ranges.set_range(7, 12); base::SampleVector samples(&ranges); samples.Accumulate(3, 1); // Bucket 1-5. samples.Accumulate(6, 1); // Bucket 5-7. samples.Accumulate(8, 1); // Bucket 8-9. (7-8 skipped) samples.Accumulate(10, 1); // Bucket 10-11. (9-10 skipped) samples.Accumulate(11, 1); // Bucket 11-12. TestMetricsServiceClient client; TestingPrefServiceSimple prefs; TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); log.RecordHistogramDelta("Test", samples); const ChromeUserMetricsExtension& uma_proto = log.uma_proto(); const HistogramEventProto& histogram_proto = uma_proto.histogram_event(uma_proto.histogram_event_size() - 1); // Buckets with samples: 1-5, 5-7, 8-9, 10-11, 11-12. // Should become: 1-/, 5-7, /-9, 10-/, /-12. ASSERT_EQ(5, histogram_proto.bucket_size()); // 1-5 becomes 1-/ (max is same as next min). EXPECT_TRUE(histogram_proto.bucket(0).has_min()); EXPECT_FALSE(histogram_proto.bucket(0).has_max()); EXPECT_EQ(1, histogram_proto.bucket(0).min()); // 5-7 stays 5-7 (no optimization possible). EXPECT_TRUE(histogram_proto.bucket(1).has_min()); EXPECT_TRUE(histogram_proto.bucket(1).has_max()); EXPECT_EQ(5, histogram_proto.bucket(1).min()); EXPECT_EQ(7, histogram_proto.bucket(1).max()); // 8-9 becomes /-9 (min is same as max - 1). EXPECT_FALSE(histogram_proto.bucket(2).has_min()); EXPECT_TRUE(histogram_proto.bucket(2).has_max()); EXPECT_EQ(9, histogram_proto.bucket(2).max()); // 10-11 becomes 10-/ (both optimizations apply, omit max is prioritized). EXPECT_TRUE(histogram_proto.bucket(3).has_min()); EXPECT_FALSE(histogram_proto.bucket(3).has_max()); EXPECT_EQ(10, histogram_proto.bucket(3).min()); // 11-12 becomes /-12 (last record must keep max, min is same as max - 1). EXPECT_FALSE(histogram_proto.bucket(4).has_min()); EXPECT_TRUE(histogram_proto.bucket(4).has_max()); EXPECT_EQ(12, histogram_proto.bucket(4).max()); } TEST_F(MetricsLogTest, RecordEnvironment) { TestMetricsServiceClient client; TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); std::vector<variations::ActiveGroupId> synthetic_trials; // Add two synthetic trials. synthetic_trials.push_back(kSyntheticTrials[0]); synthetic_trials.push_back(kSyntheticTrials[1]); log.RecordEnvironment(std::vector<MetricsProvider*>(), synthetic_trials, kInstallDate); // Check that the system profile on the log has the correct values set. CheckSystemProfile(log.system_profile()); // Check that the system profile has also been written to prefs. const std::string base64_system_profile = prefs_.GetString(prefs::kStabilitySavedSystemProfile); EXPECT_FALSE(base64_system_profile.empty()); std::string serialied_system_profile; EXPECT_TRUE(base::Base64Decode(base64_system_profile, &serialied_system_profile)); SystemProfileProto decoded_system_profile; EXPECT_TRUE(decoded_system_profile.ParseFromString(serialied_system_profile)); CheckSystemProfile(decoded_system_profile); } TEST_F(MetricsLogTest, LoadSavedEnvironmentFromPrefs) { const char* kSystemProfilePref = prefs::kStabilitySavedSystemProfile; const char* kSystemProfileHashPref = prefs::kStabilitySavedSystemProfileHash; TestMetricsServiceClient client; // The pref value is empty, so loading it from prefs should fail. { TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); EXPECT_FALSE(log.LoadSavedEnvironmentFromPrefs()); } // Do a RecordEnvironment() call and check whether the pref is recorded. { TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); log.RecordEnvironment(std::vector<MetricsProvider*>(), std::vector<variations::ActiveGroupId>(), kInstallDate); EXPECT_FALSE(prefs_.GetString(kSystemProfilePref).empty()); EXPECT_FALSE(prefs_.GetString(kSystemProfileHashPref).empty()); } { TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); EXPECT_TRUE(log.LoadSavedEnvironmentFromPrefs()); // Check some values in the system profile. EXPECT_EQ(kInstallDateExpected, log.system_profile().install_date()); EXPECT_EQ(kEnabledDateExpected, log.system_profile().uma_enabled_date()); // Ensure that the call cleared the prefs. EXPECT_TRUE(prefs_.GetString(kSystemProfilePref).empty()); EXPECT_TRUE(prefs_.GetString(kSystemProfileHashPref).empty()); } // Ensure that a non-matching hash results in the pref being invalid. { TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); // Call RecordEnvironment() to record the pref again. log.RecordEnvironment(std::vector<MetricsProvider*>(), std::vector<variations::ActiveGroupId>(), kInstallDate); } { // Set the hash to a bad value. prefs_.SetString(kSystemProfileHashPref, "deadbeef"); TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); EXPECT_FALSE(log.LoadSavedEnvironmentFromPrefs()); // Ensure that the prefs are cleared, even if the call failed. EXPECT_TRUE(prefs_.GetString(kSystemProfilePref).empty()); EXPECT_TRUE(prefs_.GetString(kSystemProfileHashPref).empty()); } } TEST_F(MetricsLogTest, InitialLogStabilityMetrics) { TestMetricsServiceClient client; TestMetricsLog log(kClientId, kSessionId, MetricsLog::INITIAL_STABILITY_LOG, &client, &prefs_); std::vector<MetricsProvider*> metrics_providers; log.RecordEnvironment(metrics_providers, std::vector<variations::ActiveGroupId>(), kInstallDate); log.RecordStabilityMetrics(metrics_providers, base::TimeDelta(), base::TimeDelta()); const SystemProfileProto_Stability& stability = log.system_profile().stability(); // Required metrics: EXPECT_TRUE(stability.has_launch_count()); EXPECT_TRUE(stability.has_crash_count()); // Initial log metrics: EXPECT_TRUE(stability.has_incomplete_shutdown_count()); EXPECT_TRUE(stability.has_breakpad_registration_success_count()); EXPECT_TRUE(stability.has_breakpad_registration_failure_count()); EXPECT_TRUE(stability.has_debugger_present_count()); EXPECT_TRUE(stability.has_debugger_not_present_count()); } TEST_F(MetricsLogTest, OngoingLogStabilityMetrics) { TestMetricsServiceClient client; TestMetricsLog log( kClientId, kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); std::vector<MetricsProvider*> metrics_providers; log.RecordEnvironment(metrics_providers, std::vector<variations::ActiveGroupId>(), kInstallDate); log.RecordStabilityMetrics(metrics_providers, base::TimeDelta(), base::TimeDelta()); const SystemProfileProto_Stability& stability = log.system_profile().stability(); // Required metrics: EXPECT_TRUE(stability.has_launch_count()); EXPECT_TRUE(stability.has_crash_count()); // Initial log metrics: EXPECT_FALSE(stability.has_incomplete_shutdown_count()); EXPECT_FALSE(stability.has_breakpad_registration_success_count()); EXPECT_FALSE(stability.has_breakpad_registration_failure_count()); EXPECT_FALSE(stability.has_debugger_present_count()); EXPECT_FALSE(stability.has_debugger_not_present_count()); } TEST_F(MetricsLogTest, ChromeChannelWrittenToProtobuf) { TestMetricsServiceClient client; TestMetricsLog log( "user@test.com", kSessionId, MetricsLog::ONGOING_LOG, &client, &prefs_); EXPECT_TRUE(log.uma_proto().system_profile().has_channel()); } } // namespace metrics