blob: 6869d404acf1e497aa9b16b7c24f20ee6b9fa473 [file] [log] [blame]
isherman@chromium.org2e4cd1a2012-01-12 08:51:031// Copyright (c) 2012 The Chromium Authors. All rights reserved.
license.botbf09a502008-08-24 00:55:552// Use of this source code is governed by a BSD-style license that can be
3// found in the LICENSE file.
initial.commit09911bf2008-07-26 23:55:294
initial.commit09911bf2008-07-26 23:55:295//------------------------------------------------------------------------------
6// Description of the life cycle of a instance of MetricsService.
7//
8// OVERVIEW
9//
isherman@chromium.orge3eb0c42013-04-18 06:18:5810// A MetricsService instance is typically created at application startup. It is
11// the central controller for the acquisition of log data, and the automatic
initial.commit09911bf2008-07-26 23:55:2912// transmission of that log data to an external server. Its major job is to
13// manage logs, grouping them for transmission, and transmitting them. As part
14// of its grouping, MS finalizes logs by including some just-in-time gathered
15// memory statistics, snapshotting the current stats of numerous histograms,
isherman@chromium.orge3eb0c42013-04-18 06:18:5816// closing the logs, translating to protocol buffer format, and compressing the
17// results for transmission. Transmission includes submitting a compressed log
18// as data in a URL-post, and retransmitting (or retaining at process
19// termination) if the attempted transmission failed. Retention across process
20// terminations is done using the the PrefServices facilities. The retained logs
21// (the ones that never got transmitted) are compressed and base64-encoded
22// before being persisted.
initial.commit09911bf2008-07-26 23:55:2923//
jar@chromium.org281d2882009-01-20 20:32:4224// Logs fall into one of two categories: "initial logs," and "ongoing logs."
asvitkine@chromium.org80a8f312013-12-16 18:00:3025// There is at most one initial log sent for each complete run of Chrome (from
26// startup, to browser shutdown). An initial log is generally transmitted some
27// short time (1 minute?) after startup, and includes stats such as recent crash
28// info, the number and types of plugins, etc. The external server's response
29// to the initial log conceptually tells this MS if it should continue
30// transmitting logs (during this session). The server response can actually be
31// much more detailed, and always includes (at a minimum) how often additional
32// ongoing logs should be sent.
initial.commit09911bf2008-07-26 23:55:2933//
34// After the above initial log, a series of ongoing logs will be transmitted.
35// The first ongoing log actually begins to accumulate information stating when
36// the MS was first constructed. Note that even though the initial log is
37// commonly sent a full minute after startup, the initial log does not include
38// much in the way of user stats. The most common interlog period (delay)
asharif@chromium.org3a668152013-06-21 23:56:4239// is 30 minutes. That time period starts when the first user action causes a
initial.commit09911bf2008-07-26 23:55:2940// logging event. This means that if there is no user action, there may be long
jar@chromium.org281d2882009-01-20 20:32:4241// periods without any (ongoing) log transmissions. Ongoing logs typically
initial.commit09911bf2008-07-26 23:55:2942// contain very detailed records of user activities (ex: opened tab, closed
43// tab, fetched URL, maximized window, etc.) In addition, just before an
44// ongoing log is closed out, a call is made to gather memory statistics. Those
45// memory statistics are deposited into a histogram, and the log finalization
46// code is then called. In the finalization, a call to a Histogram server
47// acquires a list of all local histograms that have been flagged for upload
asvitkine@chromium.org80a8f312013-12-16 18:00:3048// to the UMA server. The finalization also acquires the most recent number
jar@chromium.org281d2882009-01-20 20:32:4249// of page loads, along with any counts of renderer or plugin crashes.
initial.commit09911bf2008-07-26 23:55:2950//
51// When the browser shuts down, there will typically be a fragment of an ongoing
asvitkine@chromium.org80a8f312013-12-16 18:00:3052// log that has not yet been transmitted. At shutdown time, that fragment is
53// closed (including snapshotting histograms), and persisted, for potential
54// transmission during a future run of the product.
initial.commit09911bf2008-07-26 23:55:2955//
56// There are two slightly abnormal shutdown conditions. There is a
57// "disconnected scenario," and a "really fast startup and shutdown" scenario.
58// In the "never connected" situation, the user has (during the running of the
59// process) never established an internet connection. As a result, attempts to
60// transmit the initial log have failed, and a lot(?) of data has accumulated in
61// the ongoing log (which didn't yet get closed, because there was never even a
62// contemplation of sending it). There is also a kindred "lost connection"
63// situation, where a loss of connection prevented an ongoing log from being
64// transmitted, and a (still open) log was stuck accumulating a lot(?) of data,
65// while the earlier log retried its transmission. In both of these
66// disconnected situations, two logs need to be, and are, persistently stored
67// for future transmission.
68//
69// The other unusual shutdown condition, termed "really fast startup and
70// shutdown," involves the deliberate user termination of the process before
71// the initial log is even formed or transmitted. In that situation, no logging
72// is done, but the historical crash statistics remain (unlogged) for inclusion
73// in a future run's initial log. (i.e., we don't lose crash stats).
74//
75// With the above overview, we can now describe the state machine's various
asvitkine@chromium.org80a8f312013-12-16 18:00:3076// states, based on the State enum specified in the state_ member. Those states
initial.commit09911bf2008-07-26 23:55:2977// are:
78//
asvitkine@chromium.org80a8f312013-12-16 18:00:3079// INITIALIZED, // Constructor was called.
80// INIT_TASK_SCHEDULED, // Waiting for deferred init tasks to finish.
81// INIT_TASK_DONE, // Waiting for timer to send initial log.
82// SENDING_INITIAL_STABILITY_LOG, // Initial stability log being sent.
83// SENDING_INITIAL_METRICS_LOG, // Initial metrics log being sent.
84// SENDING_OLD_LOGS, // Sending unsent logs from previous session.
85// SENDING_CURRENT_LOGS, // Sending ongoing logs as they acrue.
initial.commit09911bf2008-07-26 23:55:2986//
87// In more detail, we have:
88//
89// INITIALIZED, // Constructor was called.
90// The MS has been constructed, but has taken no actions to compose the
91// initial log.
92//
asvitkine@chromium.org80a8f312013-12-16 18:00:3093// INIT_TASK_SCHEDULED, // Waiting for deferred init tasks to finish.
initial.commit09911bf2008-07-26 23:55:2994// Typically about 30 seconds after startup, a task is sent to a second thread
zelidrag@chromium.org85ed9d42010-06-08 22:37:4495// (the file thread) to perform deferred (lower priority and slower)
96// initialization steps such as getting the list of plugins. That task will
97// (when complete) make an async callback (via a Task) to indicate the
98// completion.
initial.commit09911bf2008-07-26 23:55:2999//
zelidrag@chromium.org85ed9d42010-06-08 22:37:44100// INIT_TASK_DONE, // Waiting for timer to send initial log.
initial.commit09911bf2008-07-26 23:55:29101// The callback has arrived, and it is now possible for an initial log to be
102// created. This callback typically arrives back less than one second after
zelidrag@chromium.org85ed9d42010-06-08 22:37:44103// the deferred init task is dispatched.
initial.commit09911bf2008-07-26 23:55:29104//
asvitkine@chromium.org80a8f312013-12-16 18:00:30105// SENDING_INITIAL_STABILITY_LOG, // Initial stability log being sent.
106// During initialization, if a crash occurred during the previous session, an
107// initial stability log will be generated and registered with the log manager.
108// This state will be entered if a stability log was prepared during metrics
109// service initialization (in InitializeMetricsRecordingState()) and is waiting
110// to be transmitted when it's time to send up the first log (per the reporting
111// scheduler). If there is no initial stability log (e.g. there was no previous
112// crash), then this state will be skipped and the state will advance to
113// SENDING_INITIAL_METRICS_LOG.
114//
115// SENDING_INITIAL_METRICS_LOG, // Initial metrics log being sent.
116// This state is entered after the initial metrics log has been composed, and
117// prepared for transmission. This happens after SENDING_INITIAL_STABILITY_LOG
118// if there was an initial stability log (see above). It is also the case that
119// any previously unsent logs have been loaded into instance variables for
120// possible transmission.
initial.commit09911bf2008-07-26 23:55:29121//
initial.commit09911bf2008-07-26 23:55:29122// SENDING_OLD_LOGS, // Sending unsent logs from previous session.
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10123// This state indicates that the initial log for this session has been
124// successfully sent and it is now time to send any logs that were
125// saved from previous sessions. All such logs will be transmitted before
126// exiting this state, and proceeding with ongoing logs from the current session
127// (see next state).
initial.commit09911bf2008-07-26 23:55:29128//
129// SENDING_CURRENT_LOGS, // Sending standard current logs as they accrue.
jar@google.com0b33f80b2008-12-17 21:34:36130// Current logs are being accumulated. Typically every 20 minutes a log is
initial.commit09911bf2008-07-26 23:55:29131// closed and finalized for transmission, at the same time as a new log is
132// started.
133//
134// The progression through the above states is simple, and sequential, in the
135// most common use cases. States proceed from INITIAL to SENDING_CURRENT_LOGS,
136// and remain in the latter until shutdown.
137//
138// The one unusual case is when the user asks that we stop logging. When that
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10139// happens, any staged (transmission in progress) log is persisted, and any log
stuartmorgan@chromium.org410938e02012-10-24 16:33:59140// that is currently accumulating is also finalized and persisted. We then
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10141// regress back to the SEND_OLD_LOGS state in case the user enables log
142// recording again during this session. This way anything we have persisted
143// will be sent automatically if/when we progress back to SENDING_CURRENT_LOG
144// state.
initial.commit09911bf2008-07-26 23:55:29145//
stuartmorgan@chromium.org410938e02012-10-24 16:33:59146// Another similar case is on mobile, when the application is backgrounded and
147// then foregrounded again. Backgrounding created new "old" stored logs, so the
148// state drops back from SENDING_CURRENT_LOGS to SENDING_OLD_LOGS so those logs
149// will be sent.
150//
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10151// Also note that whenever we successfully send an old log, we mirror the list
152// of logs into the PrefService. This ensures that IF we crash, we won't start
153// up and retransmit our old logs again.
initial.commit09911bf2008-07-26 23:55:29154//
155// Due to race conditions, it is always possible that a log file could be sent
156// twice. For example, if a log file is sent, but not yet acknowledged by
157// the external server, and the user shuts down, then a copy of the log may be
158// saved for re-transmission. These duplicates could be filtered out server
jar@chromium.org281d2882009-01-20 20:32:42159// side, but are not expected to be a significant problem.
initial.commit09911bf2008-07-26 23:55:29160//
161//
162//------------------------------------------------------------------------------
163
maruel@chromium.org40bcc302009-03-02 20:50:39164#include "chrome/browser/metrics/metrics_service.h"
165
eroman@chromium.orgd7c1fa62012-06-15 23:35:30166#include <algorithm>
167
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16168#include "base/bind.h"
169#include "base/callback.h"
brettw@chromium.org835d7c82010-10-14 04:38:38170#include "base/metrics/histogram.h"
isherman@chromium.orgacc2ce5512014-05-22 18:29:13171#include "base/metrics/histogram_base.h"
172#include "base/metrics/histogram_samples.h"
eroman@chromium.org1026afd2013-03-20 14:28:54173#include "base/metrics/sparse_histogram.h"
kaiwang@chromium.org567d30e2012-07-13 21:48:29174#include "base/metrics/statistics_recorder.h"
joi@chromium.org3853a4c2013-02-11 17:15:57175#include "base/prefs/pref_registry_simple.h"
176#include "base/prefs/pref_service.h"
brettw@chromium.org3ea1b182013-02-08 22:38:41177#include "base/strings/string_number_conversions.h"
avi@chromium.org112158af2013-06-07 23:46:18178#include "base/strings/utf_string_conversions.h"
brettw@chromium.orgce072a72010-12-31 20:02:16179#include "base/threading/platform_thread.h"
tfarina@chromium.orgb3841c502011-03-09 01:21:31180#include "base/threading/thread.h"
jam@chromium.org3a7b66d2012-04-26 16:34:16181#include "base/threading/thread_restrictions.h"
isherman@chromium.orged0fd002012-04-25 23:10:34182#include "base/tracked_objects.h"
erg@google.com679082052010-07-21 21:30:13183#include "base/values.h"
blundell@chromium.org29de18f2014-05-23 07:16:41184#include "chrome/browser/metrics/chrome_stability_metrics_provider.h"
blundell@chromium.org2d8c6e62014-05-23 11:02:25185#include "chrome/browser/metrics/gpu_metrics_provider.h"
isherman@chromium.org536324e2014-05-22 18:50:19186#include "chrome/browser/metrics/network_metrics_provider.h"
asvitkine@chromium.orgcf4dc612014-05-21 12:33:43187#include "chrome/browser/metrics/omnibox_metrics_provider.h"
blundell@chromium.org83d09f92014-06-03 14:58:26188#include "chrome/browser/metrics/profiler_metrics_provider.h"
isherman@chromium.orged0fd002012-04-25 23:10:34189#include "chrome/browser/metrics/tracking_synchronizer.h"
initial.commit09911bf2008-07-26 23:55:29190#include "chrome/common/pref_names.h"
blundell@chromium.org91b1d912014-06-05 10:52:08191#include "components/metrics/metrics_log.h"
bsimonnet@chromium.org064107e2014-05-02 00:59:06192#include "components/metrics/metrics_log_manager.h"
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34193#include "components/metrics/metrics_log_uploader.h"
holte@chromium.org7f07db62014-05-15 01:12:45194#include "components/metrics/metrics_pref_names.h"
motek@chromium.org14bb46692014-05-20 17:16:45195#include "components/metrics/metrics_reporting_scheduler.h"
asvitkine@chromium.org73929422014-05-22 08:19:05196#include "components/metrics/metrics_service_client.h"
blundell@chromium.org16a30912014-06-04 00:20:04197#include "components/metrics/metrics_state_manager.h"
asvitkine@chromium.org50ae9f12013-08-29 18:03:22198#include "components/variations/entropy_provider.h"
asvitkine@chromium.orgbfb77b52014-06-07 01:54:01199#include "content/public/browser/browser_thread.h"
initial.commit09911bf2008-07-26 23:55:29200
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45201#if defined(ENABLE_PLUGINS)
202// TODO(asvitkine): Move this out of MetricsService.
203#include "chrome/browser/metrics/plugin_metrics_provider.h"
204#endif
205
eroman@chromium.orgd7c1fa62012-06-15 23:35:30206#if defined(OS_WIN)
asvitkine@chromium.org68e679232014-05-22 07:49:15207#include "chrome/browser/metrics/google_update_metrics_provider_win.h"
eroman@chromium.orgd7c1fa62012-06-15 23:35:30208#endif
209
asvitkine@chromium.org6ba11eb2014-05-22 08:17:46210#if defined(OS_ANDROID)
211// TODO(asvitkine): Move this out of MetricsService.
212#include "chrome/browser/metrics/android_metrics_provider.h"
vitalybuka@chromium.orga3079832013-10-24 20:29:36213#endif
214
dsh@google.come1acf6f2008-10-27 20:43:33215using base::Time;
bsimonnet@chromium.org064107e2014-05-02 00:59:06216using metrics::MetricsLogManager;
dsh@google.come1acf6f2008-10-27 20:43:33217
isherman@chromium.orgfe58acc22012-02-29 01:29:58218namespace {
isherman@chromium.orgb2a4812d2012-02-28 05:31:31219
isherman@chromium.orgfe58acc22012-02-29 01:29:58220// Check to see that we're being called on only one thread.
221bool IsSingleThreaded() {
222 static base::PlatformThreadId thread_id = 0;
223 if (!thread_id)
224 thread_id = base::PlatformThread::CurrentId();
225 return base::PlatformThread::CurrentId() == thread_id;
226}
227
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16228// The delay, in seconds, after starting recording before doing expensive
229// initialization work.
dfalcantara@chromium.org12180f82012-10-10 21:13:30230#if defined(OS_ANDROID) || defined(OS_IOS)
231// On mobile devices, a significant portion of sessions last less than a minute.
232// Use a shorter timer on these platforms to avoid losing data.
233// TODO(dfalcantara): To avoid delaying startup, tighten up initialization so
234// that it occurs after the user gets their initial page.
235const int kInitializationDelaySeconds = 5;
236#else
isherman@chromium.orgfe58acc22012-02-29 01:29:58237const int kInitializationDelaySeconds = 30;
dfalcantara@chromium.org12180f82012-10-10 21:13:30238#endif
petersont@google.com252873ef2008-08-04 21:59:45239
stuartmorgan@chromium.org54702c92011-04-15 15:06:43240// The maximum number of events in a log uploaded to the UMA server.
isherman@chromium.orgfe58acc22012-02-29 01:29:58241const int kEventLimit = 2400;
jar@google.com68475e602008-08-22 03:21:15242
243// If an upload fails, and the transmission was over this byte count, then we
244// will discard the log, and not try to retransmit it. We also don't persist
245// the log to the prefs for transmission during the next chrome session if this
246// limit is exceeded.
isherman@chromium.orgfe58acc22012-02-29 01:29:58247const size_t kUploadLogAvoidRetransmitSize = 50000;
initial.commit09911bf2008-07-26 23:55:29248
tedvessenes@gmail.comfc4252a72012-01-12 21:58:47249// Interval, in minutes, between state saves.
isherman@chromium.orgfe58acc22012-02-29 01:29:58250const int kSaveStateIntervalMinutes = 5;
251
isherman@chromium.orgacc2ce5512014-05-22 18:29:13252// The metrics server's URL.
253const char kServerUrl[] = "https://clients4.google.com/uma/v2";
254
255// The MIME type for the uploaded metrics data.
256const char kMimeType[] = "application/vnd.chrome.uma";
257
isherman@chromium.org4266def22012-05-17 01:02:40258enum ResponseStatus {
259 UNKNOWN_FAILURE,
260 SUCCESS,
261 BAD_REQUEST, // Invalid syntax or log too large.
isherman@chromium.org9f5c1ce82012-05-23 23:11:28262 NO_RESPONSE,
isherman@chromium.org4266def22012-05-17 01:02:40263 NUM_RESPONSE_STATUSES
264};
265
266ResponseStatus ResponseCodeToStatus(int response_code) {
267 switch (response_code) {
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34268 case -1:
269 return NO_RESPONSE;
isherman@chromium.org4266def22012-05-17 01:02:40270 case 200:
271 return SUCCESS;
272 case 400:
273 return BAD_REQUEST;
274 default:
275 return UNKNOWN_FAILURE;
276 }
277}
278
blundell@chromium.org24f81ca2014-05-26 15:59:34279void MarkAppCleanShutdownAndCommit(PrefService* local_state) {
280 local_state->SetBoolean(prefs::kStabilityExitedCleanly, true);
281 local_state->SetInteger(prefs::kStabilityExecutionPhase,
282 MetricsService::SHUTDOWN_COMPLETE);
nileshagrawal@chromium.org84c384e2013-03-01 23:20:19283 // Start writing right away (write happens on a different thread).
blundell@chromium.org24f81ca2014-05-26 15:59:34284 local_state->CommitPendingWrite();
nileshagrawal@chromium.org84c384e2013-03-01 23:20:19285}
286
asvitkine@chromium.org20f999b52012-08-24 22:32:59287} // namespace
initial.commit09911bf2008-07-26 23:55:29288
bengr@chromium.org60677562013-11-17 15:52:55289
asvitkine@chromium.org7a5c07812014-02-26 11:45:41290SyntheticTrialGroup::SyntheticTrialGroup(uint32 trial, uint32 group) {
bengr@chromium.org60677562013-11-17 15:52:55291 id.name = trial;
292 id.group = group;
293}
294
295SyntheticTrialGroup::~SyntheticTrialGroup() {
296}
297
jar@chromium.orgc0c55e92011-09-10 18:47:30298// static
299MetricsService::ShutdownCleanliness MetricsService::clean_shutdown_status_ =
300 MetricsService::CLEANLY_SHUTDOWN;
301
rtenneti@chromium.org6a6d0d12013-10-28 15:58:19302MetricsService::ExecutionPhase MetricsService::execution_phase_ =
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21303 MetricsService::UNINITIALIZED_PHASE;
rtenneti@chromium.org6a6d0d12013-10-28 15:58:19304
initial.commit09911bf2008-07-26 23:55:29305// static
joi@chromium.orgb1de2c72013-02-06 02:45:47306void MetricsService::RegisterPrefs(PrefRegistrySimple* registry) {
initial.commit09911bf2008-07-26 23:55:29307 DCHECK(IsSingleThreaded());
miu@chromium.org39076642014-05-05 20:32:55308 metrics::MetricsStateManager::RegisterPrefs(registry);
blundell@chromium.org91b1d912014-06-05 10:52:08309 MetricsLog::RegisterPrefs(registry);
miu@chromium.org39076642014-05-05 20:32:55310
joi@chromium.orgb1de2c72013-02-06 02:45:47311 registry->RegisterInt64Pref(prefs::kStabilityLaunchTimeSec, 0);
312 registry->RegisterInt64Pref(prefs::kStabilityLastTimestampSec, 0);
dcheng@chromium.org007b3f82013-04-09 08:46:45313 registry->RegisterStringPref(prefs::kStabilityStatsVersion, std::string());
joi@chromium.orgb1de2c72013-02-06 02:45:47314 registry->RegisterInt64Pref(prefs::kStabilityStatsBuildTime, 0);
315 registry->RegisterBooleanPref(prefs::kStabilityExitedCleanly, true);
rtenneti@chromium.org6a6d0d12013-10-28 15:58:19316 registry->RegisterIntegerPref(prefs::kStabilityExecutionPhase,
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21317 UNINITIALIZED_PHASE);
joi@chromium.orgb1de2c72013-02-06 02:45:47318 registry->RegisterBooleanPref(prefs::kStabilitySessionEndCompleted, true);
blundell@chromium.org16a30912014-06-04 00:20:04319 registry->RegisterIntegerPref(metrics::prefs::kMetricsSessionID, -1);
asvitkine@chromium.org0f2f7792013-11-28 16:09:14320
holte@chromium.org7f07db62014-05-15 01:12:45321 registry->RegisterListPref(metrics::prefs::kMetricsInitialLogs);
322 registry->RegisterListPref(metrics::prefs::kMetricsOngoingLogs);
robertshield@google.com0bb1a622009-03-04 03:22:32323
isherman@chromium.org5c181552013-02-07 09:12:52324 registry->RegisterInt64Pref(prefs::kInstallDate, 0);
joi@chromium.orgb1de2c72013-02-06 02:45:47325 registry->RegisterInt64Pref(prefs::kUninstallLaunchCount, 0);
joi@chromium.orgb1de2c72013-02-06 02:45:47326 registry->RegisterInt64Pref(prefs::kUninstallMetricsUptimeSec, 0);
327 registry->RegisterInt64Pref(prefs::kUninstallLastLaunchTimeSec, 0);
328 registry->RegisterInt64Pref(prefs::kUninstallLastObservedRunTimeSec, 0);
kkimlabs@chromium.orgc778687a2014-02-11 14:46:45329
asvitkine@chromium.org6f0a65b32014-05-24 01:48:09330 // TODO(asvitkine): Move this out of here.
331 ChromeStabilityMetricsProvider::RegisterPrefs(registry);
332
kkimlabs@chromium.orgc778687a2014-02-11 14:46:45333#if defined(OS_ANDROID)
asvitkine@chromium.org6ba11eb2014-05-22 08:17:46334 // TODO(asvitkine): Move this out of here.
335 AndroidMetricsProvider::RegisterPrefs(registry);
kkimlabs@chromium.orgc778687a2014-02-11 14:46:45336#endif // defined(OS_ANDROID)
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45337
338#if defined(ENABLE_PLUGINS)
339 // TODO(asvitkine): Move this out of here.
340 PluginMetricsProvider::RegisterPrefs(registry);
341#endif
initial.commit09911bf2008-07-26 23:55:29342}
343
isherman@chromium.org728de072014-05-21 09:20:32344MetricsService::MetricsService(metrics::MetricsStateManager* state_manager,
blundell@chromium.org24f81ca2014-05-26 15:59:34345 metrics::MetricsServiceClient* client,
346 PrefService* local_state)
347 : log_manager_(local_state, kUploadLogAvoidRetransmitSize),
isherman@chromium.orgacc2ce5512014-05-22 18:29:13348 histogram_snapshot_manager_(this),
holte@chromium.org7f07db62014-05-15 01:12:45349 state_manager_(state_manager),
isherman@chromium.org728de072014-05-21 09:20:32350 client_(client),
blundell@chromium.org24f81ca2014-05-26 15:59:34351 local_state_(local_state),
jwd@chromium.org37d4709a2014-03-29 03:07:40352 recording_active_(false),
petersont@google.comd01b8732008-10-16 02:18:07353 reporting_active_(false),
stuartmorgan@chromium.org410938e02012-10-24 16:33:59354 test_mode_active_(false),
petersont@google.comd01b8732008-10-16 02:18:07355 state_(INITIALIZED),
asvitkine@chromium.org80a8f312013-12-16 18:00:30356 has_initial_stability_log_(false),
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34357 log_upload_in_progress_(false),
petersont@google.comd01b8732008-10-16 02:18:07358 idle_since_last_transmission_(false),
asvitkine@chromium.org80a8f312013-12-16 18:00:30359 session_id_(-1),
tfarina@chromium.org9c009092013-05-01 03:14:09360 self_ptr_factory_(this),
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34361 state_saver_factory_(this) {
initial.commit09911bf2008-07-26 23:55:29362 DCHECK(IsSingleThreaded());
miu@chromium.org39076642014-05-05 20:32:55363 DCHECK(state_manager_);
isherman@chromium.org728de072014-05-21 09:20:32364 DCHECK(client_);
blundell@chromium.org24f81ca2014-05-26 15:59:34365 DCHECK(local_state_);
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16366
asvitkine@chromium.org6ba11eb2014-05-22 08:17:46367#if defined(OS_ANDROID)
368 // TODO(asvitkine): Move this out of MetricsService.
369 RegisterMetricsProvider(
370 scoped_ptr<metrics::MetricsProvider>(new AndroidMetricsProvider(
blundell@chromium.org24f81ca2014-05-26 15:59:34371 local_state_)));
asvitkine@chromium.org6ba11eb2014-05-22 08:17:46372#endif // defined(OS_ANDROID)
373
isherman@chromium.org536324e2014-05-22 18:50:19374 // TODO(asvitkine): Move these out of MetricsService.
375 RegisterMetricsProvider(
376 scoped_ptr<metrics::MetricsProvider>(new NetworkMetricsProvider));
asvitkine@chromium.orgcf4dc612014-05-21 12:33:43377 RegisterMetricsProvider(
378 scoped_ptr<metrics::MetricsProvider>(new OmniboxMetricsProvider));
blundell@chromium.org29de18f2014-05-23 07:16:41379 RegisterMetricsProvider(
380 scoped_ptr<metrics::MetricsProvider>(new ChromeStabilityMetricsProvider));
blundell@chromium.org2d8c6e62014-05-23 11:02:25381 RegisterMetricsProvider(
382 scoped_ptr<metrics::MetricsProvider>(new GPUMetricsProvider()));
blundell@chromium.org83d09f92014-06-03 14:58:26383 profiler_metrics_provider_ = new ProfilerMetricsProvider;
384 RegisterMetricsProvider(
385 scoped_ptr<metrics::MetricsProvider>(profiler_metrics_provider_));
asvitkine@chromium.orgcf4dc612014-05-21 12:33:43386
asvitkine@chromium.org68e679232014-05-22 07:49:15387#if defined(OS_WIN)
388 google_update_metrics_provider_ = new GoogleUpdateMetricsProviderWin;
389 RegisterMetricsProvider(scoped_ptr<metrics::MetricsProvider>(
390 google_update_metrics_provider_));
391#endif
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45392
393#if defined(ENABLE_PLUGINS)
blundell@chromium.org24f81ca2014-05-26 15:59:34394 plugin_metrics_provider_ = new PluginMetricsProvider(local_state_);
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45395 RegisterMetricsProvider(scoped_ptr<metrics::MetricsProvider>(
396 plugin_metrics_provider_));
397#endif
blundell@chromium.org8304f61a2014-05-24 12:17:33398
initial.commit09911bf2008-07-26 23:55:29399}
400
401MetricsService::~MetricsService() {
stuartmorgan@chromium.org410938e02012-10-24 16:33:59402 DisableRecording();
initial.commit09911bf2008-07-26 23:55:29403}
404
miu@chromium.org39076642014-05-05 20:32:55405void MetricsService::InitializeMetricsRecordingState() {
406 InitializeMetricsState();
asvitkine@chromium.org80a8f312013-12-16 18:00:30407
408 base::Closure callback = base::Bind(&MetricsService::StartScheduledUpload,
409 self_ptr_factory_.GetWeakPtr());
410 scheduler_.reset(new MetricsReportingScheduler(callback));
411}
412
petersont@google.comd01b8732008-10-16 02:18:07413void MetricsService::Start() {
stuartmorgan@chromium.orgb1c8dc02011-04-13 18:32:04414 HandleIdleSinceLastTransmission(false);
stuartmorgan@chromium.org410938e02012-10-24 16:33:59415 EnableRecording();
416 EnableReporting();
petersont@google.comd01b8732008-10-16 02:18:07417}
418
miu@chromium.org39076642014-05-05 20:32:55419bool MetricsService::StartIfMetricsReportingEnabled() {
420 const bool enabled = state_manager_->IsMetricsReportingEnabled();
421 if (enabled)
422 Start();
423 return enabled;
424}
425
stuartmorgan@chromium.org410938e02012-10-24 16:33:59426void MetricsService::StartRecordingForTests() {
427 test_mode_active_ = true;
428 EnableRecording();
429 DisableReporting();
petersont@google.comd01b8732008-10-16 02:18:07430}
431
432void MetricsService::Stop() {
stuartmorgan@chromium.orgb1c8dc02011-04-13 18:32:04433 HandleIdleSinceLastTransmission(false);
stuartmorgan@chromium.org410938e02012-10-24 16:33:59434 DisableReporting();
435 DisableRecording();
436}
437
438void MetricsService::EnableReporting() {
439 if (reporting_active_)
440 return;
441 reporting_active_ = true;
442 StartSchedulerIfNecessary();
443}
444
445void MetricsService::DisableReporting() {
446 reporting_active_ = false;
petersont@google.comd01b8732008-10-16 02:18:07447}
448
joi@chromium.orgedafd4c2011-05-10 17:18:53449std::string MetricsService::GetClientId() {
miu@chromium.org39076642014-05-05 20:32:55450 return state_manager_->client_id();
joi@chromium.orgedafd4c2011-05-10 17:18:53451}
452
asvitkine@chromium.org20f999b52012-08-24 22:32:59453scoped_ptr<const base::FieldTrial::EntropyProvider>
miu@chromium.org39076642014-05-05 20:32:55454MetricsService::CreateEntropyProvider() {
455 // TODO(asvitkine): Refactor the code so that MetricsService does not expose
456 // this method.
457 return state_manager_->CreateEntropyProvider();
jam@chromium.org5cbeeef72012-02-08 02:05:18458}
459
stuartmorgan@chromium.org410938e02012-10-24 16:33:59460void MetricsService::EnableRecording() {
initial.commit09911bf2008-07-26 23:55:29461 DCHECK(IsSingleThreaded());
462
stuartmorgan@chromium.org410938e02012-10-24 16:33:59463 if (recording_active_)
initial.commit09911bf2008-07-26 23:55:29464 return;
stuartmorgan@chromium.org410938e02012-10-24 16:33:59465 recording_active_ = true;
initial.commit09911bf2008-07-26 23:55:29466
miu@chromium.org39076642014-05-05 20:32:55467 state_manager_->ForceClientIdCreation();
isherman@chromium.org7d000322014-05-23 07:16:02468 client_->SetClientID(state_manager_->client_id());
stuartmorgan@chromium.org410938e02012-10-24 16:33:59469 if (!log_manager_.current_log())
470 OpenNewLog();
pkasting@chromium.org005ef3e2009-05-22 20:55:46471
asvitkine@chromium.org85791b0b2014-05-20 15:18:58472 for (size_t i = 0; i < metrics_providers_.size(); ++i)
473 metrics_providers_[i]->OnRecordingEnabled();
474
ben@chromium.orge6e30ac2014-01-13 21:24:39475 base::RemoveActionCallback(action_callback_);
phajdan.jr@chromium.orgdd98f392013-02-04 13:03:22476 action_callback_ = base::Bind(&MetricsService::OnUserAction,
477 base::Unretained(this));
ben@chromium.orge6e30ac2014-01-13 21:24:39478 base::AddActionCallback(action_callback_);
stuartmorgan@chromium.org410938e02012-10-24 16:33:59479}
480
481void MetricsService::DisableRecording() {
482 DCHECK(IsSingleThreaded());
483
484 if (!recording_active_)
485 return;
486 recording_active_ = false;
487
ben@chromium.orge6e30ac2014-01-13 21:24:39488 base::RemoveActionCallback(action_callback_);
asvitkine@chromium.org85791b0b2014-05-20 15:18:58489
490 for (size_t i = 0; i < metrics_providers_.size(); ++i)
491 metrics_providers_[i]->OnRecordingDisabled();
492
stuartmorgan@chromium.org410938e02012-10-24 16:33:59493 PushPendingLogsToPersistentStorage();
494 DCHECK(!log_manager_.has_staged_log());
initial.commit09911bf2008-07-26 23:55:29495}
496
petersont@google.comd01b8732008-10-16 02:18:07497bool MetricsService::recording_active() const {
initial.commit09911bf2008-07-26 23:55:29498 DCHECK(IsSingleThreaded());
petersont@google.comd01b8732008-10-16 02:18:07499 return recording_active_;
initial.commit09911bf2008-07-26 23:55:29500}
501
petersont@google.comd01b8732008-10-16 02:18:07502bool MetricsService::reporting_active() const {
503 DCHECK(IsSingleThreaded());
504 return reporting_active_;
initial.commit09911bf2008-07-26 23:55:29505}
506
isherman@chromium.orgacc2ce5512014-05-22 18:29:13507void MetricsService::RecordDelta(const base::HistogramBase& histogram,
508 const base::HistogramSamples& snapshot) {
509 log_manager_.current_log()->RecordHistogramDelta(histogram.histogram_name(),
510 snapshot);
511}
512
513void MetricsService::InconsistencyDetected(
514 base::HistogramBase::Inconsistency problem) {
515 UMA_HISTOGRAM_ENUMERATION("Histogram.InconsistenciesBrowser",
516 problem, base::HistogramBase::NEVER_EXCEEDED_VALUE);
517}
518
519void MetricsService::UniqueInconsistencyDetected(
520 base::HistogramBase::Inconsistency problem) {
521 UMA_HISTOGRAM_ENUMERATION("Histogram.InconsistenciesBrowserUnique",
522 problem, base::HistogramBase::NEVER_EXCEEDED_VALUE);
523}
524
525void MetricsService::InconsistencyDetectedInLoggedCount(int amount) {
526 UMA_HISTOGRAM_COUNTS("Histogram.InconsistentSnapshotBrowser",
527 std::abs(amount));
528}
529
petersont@google.comd01b8732008-10-16 02:18:07530void MetricsService::HandleIdleSinceLastTransmission(bool in_idle) {
531 // If there wasn't a lot of action, maybe the computer was asleep, in which
532 // case, the log transmissions should have stopped. Here we start them up
533 // again.
pkasting@chromium.orgcac78842008-11-27 01:02:20534 if (!in_idle && idle_since_last_transmission_)
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16535 StartSchedulerIfNecessary();
pkasting@chromium.orgcac78842008-11-27 01:02:20536 idle_since_last_transmission_ = in_idle;
initial.commit09911bf2008-07-26 23:55:29537}
538
isherman@chromium.orgd7ea39e2014-05-22 03:59:18539void MetricsService::OnApplicationNotIdle() {
540 if (recording_active_)
541 HandleIdleSinceLastTransmission(false);
542}
543
initial.commit09911bf2008-07-26 23:55:29544void MetricsService::RecordStartOfSessionEnd() {
stuartmorgan@chromium.org466f3c12011-03-23 21:20:38545 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29546 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, false);
547}
548
549void MetricsService::RecordCompletedSessionEnd() {
stuartmorgan@chromium.org466f3c12011-03-23 21:20:38550 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29551 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, true);
552}
553
stuartmorgan@chromium.org410938e02012-10-24 16:33:59554#if defined(OS_ANDROID) || defined(OS_IOS)
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39555void MetricsService::OnAppEnterBackground() {
556 scheduler_->Stop();
557
blundell@chromium.org24f81ca2014-05-26 15:59:34558 MarkAppCleanShutdownAndCommit(local_state_);
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39559
560 // At this point, there's no way of knowing when the process will be
561 // killed, so this has to be treated similar to a shutdown, closing and
562 // persisting all logs. Unlinke a shutdown, the state is primed to be ready
563 // to continue logging and uploading if the process does return.
asvitkine@chromium.org80a8f312013-12-16 18:00:30564 if (recording_active() && state_ >= SENDING_INITIAL_STABILITY_LOG) {
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39565 PushPendingLogsToPersistentStorage();
stuartmorgan@chromium.org410938e02012-10-24 16:33:59566 // Persisting logs closes the current log, so start recording a new log
567 // immediately to capture any background work that might be done before the
568 // process is killed.
569 OpenNewLog();
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39570 }
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39571}
572
573void MetricsService::OnAppEnterForeground() {
blundell@chromium.org24f81ca2014-05-26 15:59:34574 local_state_->SetBoolean(prefs::kStabilityExitedCleanly, false);
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39575 StartSchedulerIfNecessary();
576}
nileshagrawal@chromium.org84c384e2013-03-01 23:20:19577#else
blundell@chromium.org24f81ca2014-05-26 15:59:34578void MetricsService::LogNeedForCleanShutdown(PrefService* local_state) {
579 local_state->SetBoolean(prefs::kStabilityExitedCleanly, false);
nileshagrawal@chromium.org84c384e2013-03-01 23:20:19580 // Redundant setting to be sure we call for a clean shutdown.
581 clean_shutdown_status_ = NEED_TO_SHUTDOWN;
582}
583#endif // defined(OS_ANDROID) || defined(OS_IOS)
dfalcantara@chromium.org117fbdf22012-06-26 18:36:39584
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21585// static
blundell@chromium.org24f81ca2014-05-26 15:59:34586void MetricsService::SetExecutionPhase(ExecutionPhase execution_phase,
587 PrefService* local_state) {
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21588 execution_phase_ = execution_phase;
blundell@chromium.org24f81ca2014-05-26 15:59:34589 local_state->SetInteger(prefs::kStabilityExecutionPhase, execution_phase_);
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21590}
591
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16592void MetricsService::RecordBreakpadRegistration(bool success) {
jar@google.com68475e602008-08-22 03:21:15593 if (!success)
blundell@chromium.org91b1d912014-06-05 10:52:08594 IncrementPrefValue(metrics::prefs::kStabilityBreakpadRegistrationFail);
cpu@google.come73c01972008-08-13 00:18:24595 else
blundell@chromium.org91b1d912014-06-05 10:52:08596 IncrementPrefValue(metrics::prefs::kStabilityBreakpadRegistrationSuccess);
cpu@google.come73c01972008-08-13 00:18:24597}
598
599void MetricsService::RecordBreakpadHasDebugger(bool has_debugger) {
600 if (!has_debugger)
blundell@chromium.org91b1d912014-06-05 10:52:08601 IncrementPrefValue(metrics::prefs::kStabilityDebuggerNotPresent);
cpu@google.come73c01972008-08-13 00:18:24602 else
blundell@chromium.org91b1d912014-06-05 10:52:08603 IncrementPrefValue(metrics::prefs::kStabilityDebuggerPresent);
cpu@google.come73c01972008-08-13 00:18:24604}
605
initial.commit09911bf2008-07-26 23:55:29606//------------------------------------------------------------------------------
607// private methods
608//------------------------------------------------------------------------------
609
610
611//------------------------------------------------------------------------------
612// Initialization methods
613
miu@chromium.org39076642014-05-05 20:32:55614void MetricsService::InitializeMetricsState() {
blundell@chromium.org24f81ca2014-05-26 15:59:34615 local_state_->SetString(prefs::kStabilityStatsVersion,
616 client_->GetVersionString());
617 local_state_->SetInt64(prefs::kStabilityStatsBuildTime,
618 MetricsLog::GetBuildTime());
initial.commit09911bf2008-07-26 23:55:29619
blundell@chromium.org16a30912014-06-04 00:20:04620 session_id_ = local_state_->GetInteger(metrics::prefs::kMetricsSessionID);
jar@chromium.org541f77922009-02-23 21:14:38621
blundell@chromium.org24f81ca2014-05-26 15:59:34622 if (!local_state_->GetBoolean(prefs::kStabilityExitedCleanly)) {
blundell@chromium.org91b1d912014-06-05 10:52:08623 IncrementPrefValue(metrics::prefs::kStabilityCrashCount);
jar@chromium.orgc0c55e92011-09-10 18:47:30624 // Reset flag, and wait until we call LogNeedForCleanShutdown() before
625 // monitoring.
blundell@chromium.org24f81ca2014-05-26 15:59:34626 local_state_->SetBoolean(prefs::kStabilityExitedCleanly, true);
rtenneti@chromium.org6a6d0d12013-10-28 15:58:19627
628 // TODO(rtenneti): On windows, consider saving/getting execution_phase from
629 // the registry.
blundell@chromium.org24f81ca2014-05-26 15:59:34630 int execution_phase =
631 local_state_->GetInteger(prefs::kStabilityExecutionPhase);
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21632 UMA_HISTOGRAM_SPARSE_SLOWLY("Chrome.Browser.CrashedExecutionPhase",
rtenneti@chromium.org6a6d0d12013-10-28 15:58:19633 execution_phase);
asvitkine@chromium.org80a8f312013-12-16 18:00:30634
635 // If the previous session didn't exit cleanly, then prepare an initial
636 // stability log if UMA is enabled.
miu@chromium.org39076642014-05-05 20:32:55637 if (state_manager_->IsMetricsReportingEnabled())
asvitkine@chromium.org80a8f312013-12-16 18:00:30638 PrepareInitialStabilityLog();
initial.commit09911bf2008-07-26 23:55:29639 }
asvitkine@chromium.org80a8f312013-12-16 18:00:30640
641 // Update session ID.
642 ++session_id_;
blundell@chromium.org16a30912014-06-04 00:20:04643 local_state_->SetInteger(metrics::prefs::kMetricsSessionID, session_id_);
asvitkine@chromium.org80a8f312013-12-16 18:00:30644
645 // Stability bookkeeping
blundell@chromium.org91b1d912014-06-05 10:52:08646 IncrementPrefValue(metrics::prefs::kStabilityLaunchCount);
asvitkine@chromium.org80a8f312013-12-16 18:00:30647
rtenneti@chromium.org6d67ea0d2013-11-14 11:02:21648 DCHECK_EQ(UNINITIALIZED_PHASE, execution_phase_);
blundell@chromium.org24f81ca2014-05-26 15:59:34649 SetExecutionPhase(START_METRICS_RECORDING, local_state_);
cpu@google.come73c01972008-08-13 00:18:24650
blundell@chromium.org24f81ca2014-05-26 15:59:34651 if (!local_state_->GetBoolean(prefs::kStabilitySessionEndCompleted)) {
blundell@chromium.org91b1d912014-06-05 10:52:08652 IncrementPrefValue(metrics::prefs::kStabilityIncompleteSessionEndCount);
jar@chromium.orgc9abf242009-07-18 06:00:38653 // This is marked false when we get a WM_ENDSESSION.
blundell@chromium.org24f81ca2014-05-26 15:59:34654 local_state_->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
initial.commit09911bf2008-07-26 23:55:29655 }
initial.commit09911bf2008-07-26 23:55:29656
mpearson@chromium.org076961c2014-03-12 22:23:56657 // Call GetUptimes() for the first time, thus allowing all later calls
658 // to record incremental uptimes accurately.
659 base::TimeDelta ignored_uptime_parameter;
660 base::TimeDelta startup_uptime;
blundell@chromium.org24f81ca2014-05-26 15:59:34661 GetUptimes(local_state_, &startup_uptime, &ignored_uptime_parameter);
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36662 DCHECK_EQ(0, startup_uptime.InMicroseconds());
jar@chromium.org9165f742010-03-10 22:55:01663 // For backwards compatibility, leave this intact in case Omaha is checking
664 // them. prefs::kStabilityLastTimestampSec may also be useless now.
665 // TODO(jar): Delete these if they have no uses.
blundell@chromium.org24f81ca2014-05-26 15:59:34666 local_state_->SetInt64(prefs::kStabilityLaunchTimeSec, Time::Now().ToTimeT());
robertshield@google.com0bb1a622009-03-04 03:22:32667
668 // Bookkeeping for the uninstall metrics.
669 IncrementLongPrefsValue(prefs::kUninstallLaunchCount);
initial.commit09911bf2008-07-26 23:55:29670
initial.commit09911bf2008-07-26 23:55:29671 // Kick off the process of saving the state (so the uptime numbers keep
672 // getting updated) every n minutes.
673 ScheduleNextStateSave();
674}
675
blundell@chromium.org51994b22014-05-30 13:24:21676void MetricsService::OnInitTaskGotHardwareClass() {
isherman@chromium.orged0fd002012-04-25 23:10:34677 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
rsesek@chromium.orgd33e7cc2011-09-23 01:43:56678
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45679 const base::Closure got_plugin_info_callback =
rsesek@chromium.orgd33e7cc2011-09-23 01:43:56680 base::Bind(&MetricsService::OnInitTaskGotPluginInfo,
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45681 self_ptr_factory_.GetWeakPtr());
682
683#if defined(ENABLE_PLUGINS)
684 plugin_metrics_provider_->GetPluginInformation(got_plugin_info_callback);
nileshagrawal@chromium.orgebd71962012-12-20 02:56:55685#else
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45686 got_plugin_info_callback.Run();
687#endif
rsesek@chromium.orgd33e7cc2011-09-23 01:43:56688}
689
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45690void MetricsService::OnInitTaskGotPluginInfo() {
isherman@chromium.orged0fd002012-04-25 23:10:34691 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
rsesek@chromium.orgd33e7cc2011-09-23 01:43:56692
asvitkine@chromium.org68e679232014-05-22 07:49:15693 const base::Closure got_metrics_callback =
694 base::Bind(&MetricsService::OnInitTaskGotGoogleUpdateData,
695 self_ptr_factory_.GetWeakPtr());
ryanmyers@chromium.org197c0772012-05-14 23:50:51696
697#if defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
asvitkine@chromium.org68e679232014-05-22 07:49:15698 google_update_metrics_provider_->GetGoogleUpdateData(got_metrics_callback);
699#else
700 got_metrics_callback.Run();
701#endif
ryanmyers@chromium.org197c0772012-05-14 23:50:51702}
703
asvitkine@chromium.org68e679232014-05-22 07:49:15704void MetricsService::OnInitTaskGotGoogleUpdateData() {
ryanmyers@chromium.org197c0772012-05-14 23:50:51705 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
706
isherman@chromium.orged0fd002012-04-25 23:10:34707 // Start the next part of the init task: fetching performance data. This will
708 // call into |FinishedReceivingProfilerData()| when the task completes.
709 chrome_browser_metrics::TrackingSynchronizer::FetchProfilerDataAsynchronously(
710 self_ptr_factory_.GetWeakPtr());
711}
712
phajdan.jr@chromium.orgdd98f392013-02-04 13:03:22713void MetricsService::OnUserAction(const std::string& action) {
stuartmorgan@chromium.orge5ad60a2014-03-11 03:54:04714 if (!ShouldLogEvents())
phajdan.jr@chromium.orgdd98f392013-02-04 13:03:22715 return;
716
isherman@chromium.org4426d2d2014-04-09 12:33:00717 log_manager_.current_log()->RecordUserAction(action);
phajdan.jr@chromium.orgdd98f392013-02-04 13:03:22718 HandleIdleSinceLastTransmission(false);
719}
720
isherman@chromium.orged0fd002012-04-25 23:10:34721void MetricsService::ReceivedProfilerData(
722 const tracked_objects::ProcessDataSnapshot& process_data,
jam@chromium.orgf3b357692013-03-22 05:16:13723 int process_type) {
isherman@chromium.orged0fd002012-04-25 23:10:34724 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
725
blundell@chromium.org83d09f92014-06-03 14:58:26726 profiler_metrics_provider_->RecordProfilerData(process_data, process_type);
isherman@chromium.orged0fd002012-04-25 23:10:34727}
728
729void MetricsService::FinishedReceivingProfilerData() {
730 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36731 state_ = INIT_TASK_DONE;
blundell@chromium.org83d09f92014-06-03 14:58:26732
733 // Create the initial log.
734 if (!initial_metrics_log_.get()) {
735 initial_metrics_log_ = CreateLog(MetricsLog::ONGOING_LOG);
736 NotifyOnDidCreateMetricsLog();
737 }
738
asvitkine@chromium.org70886cd2013-12-04 05:53:42739 scheduler_->InitTaskComplete();
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36740}
741
mpearson@chromium.org076961c2014-03-12 22:23:56742void MetricsService::GetUptimes(PrefService* pref,
743 base::TimeDelta* incremental_uptime,
744 base::TimeDelta* uptime) {
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36745 base::TimeTicks now = base::TimeTicks::Now();
mpearson@chromium.org076961c2014-03-12 22:23:56746 // If this is the first call, init |first_updated_time_| and
747 // |last_updated_time_|.
748 if (last_updated_time_.is_null()) {
749 first_updated_time_ = now;
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36750 last_updated_time_ = now;
mpearson@chromium.org076961c2014-03-12 22:23:56751 }
752 *incremental_uptime = now - last_updated_time_;
753 *uptime = now - first_updated_time_;
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36754 last_updated_time_ = now;
755
mpearson@chromium.org076961c2014-03-12 22:23:56756 const int64 incremental_time_secs = incremental_uptime->InSeconds();
asvitkine@chromium.orgc68a2b9b2013-10-09 18:16:36757 if (incremental_time_secs > 0) {
758 int64 metrics_uptime = pref->GetInt64(prefs::kUninstallMetricsUptimeSec);
759 metrics_uptime += incremental_time_secs;
760 pref->SetInt64(prefs::kUninstallMetricsUptimeSec, metrics_uptime);
761 }
initial.commit09911bf2008-07-26 23:55:29762}
763
bolian@chromium.org2a321de32014-05-10 19:59:06764void MetricsService::AddObserver(MetricsServiceObserver* observer) {
765 DCHECK(thread_checker_.CalledOnValidThread());
766 observers_.AddObserver(observer);
767}
768
769void MetricsService::RemoveObserver(MetricsServiceObserver* observer) {
770 DCHECK(thread_checker_.CalledOnValidThread());
771 observers_.RemoveObserver(observer);
772}
773
774void MetricsService::NotifyOnDidCreateMetricsLog() {
775 DCHECK(thread_checker_.CalledOnValidThread());
776 FOR_EACH_OBSERVER(
777 MetricsServiceObserver, observers_, OnDidCreateMetricsLog());
blundell@chromium.org8304f61a2014-05-24 12:17:33778 for (size_t i = 0; i < metrics_providers_.size(); ++i)
779 metrics_providers_[i]->OnDidCreateMetricsLog();
bolian@chromium.org2a321de32014-05-10 19:59:06780}
781
initial.commit09911bf2008-07-26 23:55:29782//------------------------------------------------------------------------------
783// State save methods
784
785void MetricsService::ScheduleNextStateSave() {
isherman@chromium.org8454aeb2011-11-19 23:38:20786 state_saver_factory_.InvalidateWeakPtrs();
initial.commit09911bf2008-07-26 23:55:29787
xhwang@chromium.orgb3a25092013-05-28 22:08:16788 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
isherman@chromium.org8454aeb2011-11-19 23:38:20789 base::Bind(&MetricsService::SaveLocalState,
790 state_saver_factory_.GetWeakPtr()),
tedvessenes@gmail.comfc4252a72012-01-12 21:58:47791 base::TimeDelta::FromMinutes(kSaveStateIntervalMinutes));
initial.commit09911bf2008-07-26 23:55:29792}
793
794void MetricsService::SaveLocalState() {
blundell@chromium.org24f81ca2014-05-26 15:59:34795 RecordCurrentState(local_state_);
initial.commit09911bf2008-07-26 23:55:29796
tedvessenes@gmail.comfc4252a72012-01-12 21:58:47797 // TODO(jar):110021 Does this run down the batteries????
initial.commit09911bf2008-07-26 23:55:29798 ScheduleNextStateSave();
799}
800
801
802//------------------------------------------------------------------------------
803// Recording control methods
804
stuartmorgan@chromium.org410938e02012-10-24 16:33:59805void MetricsService::OpenNewLog() {
806 DCHECK(!log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:29807
asvitkine@chromium.orgbfb77b52014-06-07 01:54:01808 log_manager_.BeginLoggingWithLog(CreateLog(MetricsLog::ONGOING_LOG));
bolian@chromium.org2a321de32014-05-10 19:59:06809 NotifyOnDidCreateMetricsLog();
initial.commit09911bf2008-07-26 23:55:29810 if (state_ == INITIALIZED) {
811 // We only need to schedule that run once.
zelidrag@chromium.org85ed9d42010-06-08 22:37:44812 state_ = INIT_TASK_SCHEDULED;
initial.commit09911bf2008-07-26 23:55:29813
blundell@chromium.org51994b22014-05-30 13:24:21814 content::BrowserThread::PostDelayedTask(
815 content::BrowserThread::UI,
joi@chromium.orged10dd12011-12-07 12:03:42816 FROM_HERE,
blundell@chromium.org51994b22014-05-30 13:24:21817 base::Bind(&MetricsService::StartGatheringMetrics,
818 self_ptr_factory_.GetWeakPtr()),
tedvessenes@gmail.com7e560102012-03-08 20:58:42819 base::TimeDelta::FromSeconds(kInitializationDelaySeconds));
initial.commit09911bf2008-07-26 23:55:29820 }
821}
822
blundell@chromium.org51994b22014-05-30 13:24:21823void MetricsService::StartGatheringMetrics() {
824 // TODO(blundell): Move all initial metrics gathering to
825 // ChromeMetricsServiceClient.
826 client_->StartGatheringMetrics(
827 base::Bind(&MetricsService::OnInitTaskGotHardwareClass,
828 self_ptr_factory_.GetWeakPtr()));
829}
830
stuartmorgan@chromium.org410938e02012-10-24 16:33:59831void MetricsService::CloseCurrentLog() {
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10832 if (!log_manager_.current_log())
initial.commit09911bf2008-07-26 23:55:29833 return;
834
jar@google.com68475e602008-08-22 03:21:15835 // TODO(jar): Integrate bounds on log recording more consistently, so that we
836 // can stop recording logs that are too big much sooner.
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10837 if (log_manager_.current_log()->num_events() > kEventLimit) {
dsh@google.com553dba62009-02-24 19:08:23838 UMA_HISTOGRAM_COUNTS("UMA.Discarded Log Events",
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10839 log_manager_.current_log()->num_events());
840 log_manager_.DiscardCurrentLog();
stuartmorgan@chromium.org410938e02012-10-24 16:33:59841 OpenNewLog(); // Start trivial log to hold our histograms.
jar@google.com68475e602008-08-22 03:21:15842 }
843
jar@google.com0b33f80b2008-12-17 21:34:36844 // Put incremental data (histogram deltas, and realtime stats deltas) at the
jar@google.com147bbc0b2009-01-06 19:37:40845 // end of all log transmissions (initial log handles this separately).
stuartmorgan@chromium.org024b5cd2011-05-27 03:29:38846 // RecordIncrementalStabilityElements only exists on the derived
847 // MetricsLog class.
isherman@chromium.org279703f2012-01-20 22:23:26848 MetricsLog* current_log =
849 static_cast<MetricsLog*>(log_manager_.current_log());
stuartmorgan@chromium.org024b5cd2011-05-27 03:29:38850 DCHECK(current_log);
asvitkine@chromium.orgb3610d42014-05-19 18:07:23851 std::vector<variations::ActiveGroupId> synthetic_trials;
bengr@chromium.org60677562013-11-17 15:52:55852 GetCurrentSyntheticFieldTrials(&synthetic_trials);
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:45853 current_log->RecordEnvironment(metrics_providers_.get(), synthetic_trials);
mpearson@chromium.org076961c2014-03-12 22:23:56854 base::TimeDelta incremental_uptime;
855 base::TimeDelta uptime;
blundell@chromium.org24f81ca2014-05-26 15:59:34856 GetUptimes(local_state_, &incremental_uptime, &uptime);
asvitkine@chromium.org85791b0b2014-05-20 15:18:58857 current_log->RecordStabilityMetrics(metrics_providers_.get(),
858 incremental_uptime, uptime);
bengr@chromium.org60677562013-11-17 15:52:55859
stuartmorgan@chromium.org024b5cd2011-05-27 03:29:38860 RecordCurrentHistograms();
asvitkine@chromium.org85791b0b2014-05-20 15:18:58861 current_log->RecordGeneralMetrics(metrics_providers_.get());
initial.commit09911bf2008-07-26 23:55:29862
stuartmorgan@chromium.org29948262012-03-01 12:15:08863 log_manager_.FinishCurrentLog();
initial.commit09911bf2008-07-26 23:55:29864}
865
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10866void MetricsService::PushPendingLogsToPersistentStorage() {
asvitkine@chromium.org80a8f312013-12-16 18:00:30867 if (state_ < SENDING_INITIAL_STABILITY_LOG)
avi@google.com28ab7f92009-01-06 21:39:04868 return; // We didn't and still don't have time to get plugin list etc.
initial.commit09911bf2008-07-26 23:55:29869
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10870 if (log_manager_.has_staged_log()) {
stuartmorgan@chromium.org7d41ae6d2012-06-26 08:53:03871 // We may race here, and send second copy of the log later.
holte@chromium.org7f07db62014-05-15 01:12:45872 metrics::PersistedLogs::StoreType store_type;
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34873 if (log_upload_in_progress_)
holte@chromium.org7f07db62014-05-15 01:12:45874 store_type = metrics::PersistedLogs::PROVISIONAL_STORE;
isherman@chromium.orgdc61fe92012-06-12 00:13:50875 else
holte@chromium.org7f07db62014-05-15 01:12:45876 store_type = metrics::PersistedLogs::NORMAL_STORE;
stuartmorgan@chromium.orge7508d82012-05-03 15:59:53877 log_manager_.StoreStagedLogAsUnsent(store_type);
initial.commit09911bf2008-07-26 23:55:29878 }
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:10879 DCHECK(!log_manager_.has_staged_log());
stuartmorgan@chromium.org410938e02012-10-24 16:33:59880 CloseCurrentLog();
asvitkine@chromium.org80a8f312013-12-16 18:00:30881 log_manager_.PersistUnsentLogs();
stuartmorgan@chromium.org7d41ae6d2012-06-26 08:53:03882
883 // If there was a staged and/or current log, then there is now at least one
884 // log waiting to be uploaded.
885 if (log_manager_.has_unsent_logs())
886 state_ = SENDING_OLD_LOGS;
initial.commit09911bf2008-07-26 23:55:29887}
888
889//------------------------------------------------------------------------------
890// Transmission of logs methods
891
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16892void MetricsService::StartSchedulerIfNecessary() {
stuartmorgan@chromium.org410938e02012-10-24 16:33:59893 // Never schedule cutting or uploading of logs in test mode.
894 if (test_mode_active_)
895 return;
896
897 // Even if reporting is disabled, the scheduler is needed to trigger the
898 // creation of the initial log, which must be done in order for any logs to be
899 // persisted on shutdown or backgrounding.
asvitkine@chromium.org80a8f312013-12-16 18:00:30900 if (recording_active() &&
901 (reporting_active() || state_ < SENDING_INITIAL_STABILITY_LOG)) {
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16902 scheduler_->Start();
asvitkine@chromium.org80a8f312013-12-16 18:00:30903 }
initial.commit09911bf2008-07-26 23:55:29904}
905
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16906void MetricsService::StartScheduledUpload() {
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47907 // If we're getting no notifications, then the log won't have much in it, and
908 // it's possible the computer is about to go to sleep, so don't upload and
909 // stop the scheduler.
stuartmorgan@chromium.org410938e02012-10-24 16:33:59910 // If recording has been turned off, the scheduler doesn't need to run.
911 // If reporting is off, proceed if the initial log hasn't been created, since
912 // that has to happen in order for logs to be cut and stored when persisting.
isherman@chromium.orgd7ea39e2014-05-22 03:59:18913 // TODO(stuartmorgan): Call Stop() on the scheduler when reporting and/or
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47914 // recording are turned off instead of letting it fire and then aborting.
915 if (idle_since_last_transmission_ ||
stuartmorgan@chromium.org410938e02012-10-24 16:33:59916 !recording_active() ||
asvitkine@chromium.org80a8f312013-12-16 18:00:30917 (!reporting_active() && state_ >= SENDING_INITIAL_STABILITY_LOG)) {
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16918 scheduler_->Stop();
919 scheduler_->UploadCancelled();
920 return;
921 }
922
stuartmorgan@chromium.orgc15faf372012-07-11 06:01:34923 // If the callback was to upload an old log, but there no longer is one,
924 // just report success back to the scheduler to begin the ongoing log
925 // callbacks.
926 // TODO(stuartmorgan): Consider removing the distinction between
927 // SENDING_OLD_LOGS and SENDING_CURRENT_LOGS to simplify the state machine
928 // now that the log upload flow is the same for both modes.
929 if (state_ == SENDING_OLD_LOGS && !log_manager_.has_unsent_logs()) {
930 state_ = SENDING_CURRENT_LOGS;
931 scheduler_->UploadFinished(true /* healthy */, false /* no unsent logs */);
932 return;
933 }
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47934 // If there are unsent logs, send the next one. If not, start the asynchronous
935 // process of finalizing the current log for upload.
936 if (state_ == SENDING_OLD_LOGS) {
937 DCHECK(log_manager_.has_unsent_logs());
938 log_manager_.StageNextLogForUpload();
939 SendStagedLog();
940 } else {
asvitkine@chromium.org4b4892b2014-05-22 15:06:15941 client_->CollectFinalMetrics(
942 base::Bind(&MetricsService::OnFinalLogInfoCollectionDone,
943 self_ptr_factory_.GetWeakPtr()));
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47944 }
stuartmorgan@chromium.org29948262012-03-01 12:15:08945}
946
stuartmorgan@chromium.org29948262012-03-01 12:15:08947void MetricsService::OnFinalLogInfoCollectionDone() {
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34948 // If somehow there is a log upload in progress, we return and hope things
949 // work out. The scheduler isn't informed since if this happens, the scheduler
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16950 // will get a response from the upload.
asvitkine@chromium.org0d5a61a82014-05-31 22:28:34951 DCHECK(!log_upload_in_progress_);
952 if (log_upload_in_progress_)
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16953 return;
954
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47955 // Abort if metrics were turned off during the final info gathering.
stuartmorgan@chromium.org410938e02012-10-24 16:33:59956 if (!recording_active()) {
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:16957 scheduler_->Stop();
958 scheduler_->UploadCancelled();
petersont@google.comd01b8732008-10-16 02:18:07959 return;
960 }
961
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47962 StageNewLog();
stuartmorgan@chromium.org410938e02012-10-24 16:33:59963
964 // If logs shouldn't be uploaded, stop here. It's important that this check
965 // be after StageNewLog(), otherwise the previous logs will never be loaded,
966 // and thus the open log won't be persisted.
967 // TODO(stuartmorgan): This is unnecessarily complicated; restructure loading
968 // of previous logs to not require running part of the upload logic.
969 // http://crbug.com/157337
970 if (!reporting_active()) {
971 scheduler_->Stop();
972 scheduler_->UploadCancelled();
973 return;
974 }
975
stuartmorgan@chromium.org29948262012-03-01 12:15:08976 SendStagedLog();
977}
978
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:47979void MetricsService::StageNewLog() {
stuartmorgan@chromium.org29948262012-03-01 12:15:08980 if (log_manager_.has_staged_log())
981 return;
982
983 switch (state_) {
984 case INITIALIZED:
985 case INIT_TASK_SCHEDULED: // We should be further along by now.
isherman@chromium.orgdc61fe92012-06-12 00:13:50986 NOTREACHED();
stuartmorgan@chromium.org29948262012-03-01 12:15:08987 return;
988
989 case INIT_TASK_DONE:
asvitkine@chromium.org80a8f312013-12-16 18:00:30990 if (has_initial_stability_log_) {
991 // There's an initial stability log, ready to send.
992 log_manager_.StageNextLogForUpload();
993 has_initial_stability_log_ = false;
asvitkine@chromium.orgf61eb842014-01-22 10:59:13994 // Note: No need to call LoadPersistedUnsentLogs() here because unsent
995 // logs have already been loaded by PrepareInitialStabilityLog().
asvitkine@chromium.org80a8f312013-12-16 18:00:30996 state_ = SENDING_INITIAL_STABILITY_LOG;
997 } else {
asvitkine@chromium.orgb58b8b22014-04-08 22:40:33998 PrepareInitialMetricsLog();
asvitkine@chromium.orgf61eb842014-01-22 10:59:13999 // Load unsent logs (if any) from local state.
1000 log_manager_.LoadPersistedUnsentLogs();
asvitkine@chromium.org80a8f312013-12-16 18:00:301001 state_ = SENDING_INITIAL_METRICS_LOG;
1002 }
stuartmorgan@chromium.org29948262012-03-01 12:15:081003 break;
1004
1005 case SENDING_OLD_LOGS:
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:471006 NOTREACHED(); // Shouldn't be staging a new log during old log sending.
1007 return;
stuartmorgan@chromium.org29948262012-03-01 12:15:081008
1009 case SENDING_CURRENT_LOGS:
stuartmorgan@chromium.org410938e02012-10-24 16:33:591010 CloseCurrentLog();
1011 OpenNewLog();
stuartmorgan@chromium.org29948262012-03-01 12:15:081012 log_manager_.StageNextLogForUpload();
1013 break;
1014
1015 default:
1016 NOTREACHED();
1017 return;
1018 }
1019
1020 DCHECK(log_manager_.has_staged_log());
1021}
1022
asvitkine@chromium.org80a8f312013-12-16 18:00:301023void MetricsService::PrepareInitialStabilityLog() {
1024 DCHECK_EQ(INITIALIZED, state_);
blundell@chromium.org91b1d912014-06-05 10:52:081025 DCHECK_NE(0, local_state_->GetInteger(metrics::prefs::kStabilityCrashCount));
stuartmorgan@chromium.org29948262012-03-01 12:15:081026
asvitkine@chromium.org80a8f312013-12-16 18:00:301027 scoped_ptr<MetricsLog> initial_stability_log(
isherman@chromium.org09dee82d2014-05-22 14:00:531028 CreateLog(MetricsLog::INITIAL_STABILITY_LOG));
bolian@chromium.org2a321de32014-05-10 19:59:061029
1030 // Do not call NotifyOnDidCreateMetricsLog here because the stability
1031 // log describes stats from the _previous_ session.
1032
asvitkine@chromium.org80a8f312013-12-16 18:00:301033 if (!initial_stability_log->LoadSavedEnvironmentFromPrefs())
1034 return;
asvitkine@chromium.org85791b0b2014-05-20 15:18:581035
asvitkine@chromium.org80a8f312013-12-16 18:00:301036 log_manager_.LoadPersistedUnsentLogs();
1037
1038 log_manager_.PauseCurrentLog();
asvitkine@chromium.orgbfb77b52014-06-07 01:54:011039 log_manager_.BeginLoggingWithLog(initial_stability_log.Pass());
asvitkine@chromium.org85791b0b2014-05-20 15:18:581040
1041 // Note: Some stability providers may record stability stats via histograms,
1042 // so this call has to be after BeginLoggingWithLog().
asvitkine@chromium.orgbfb77b52014-06-07 01:54:011043 log_manager_.current_log()->RecordStabilityMetrics(
1044 metrics_providers_.get(), base::TimeDelta(), base::TimeDelta());
kkimlabs@chromium.orgc778687a2014-02-11 14:46:451045 RecordCurrentStabilityHistograms();
asvitkine@chromium.org85791b0b2014-05-20 15:18:581046
1047 // Note: RecordGeneralMetrics() intentionally not called since this log is for
1048 // stability stats from a previous session only.
1049
asvitkine@chromium.org80a8f312013-12-16 18:00:301050 log_manager_.FinishCurrentLog();
1051 log_manager_.ResumePausedLog();
1052
1053 // Store unsent logs, including the stability log that was just saved, so
1054 // that they're not lost in case of a crash before upload time.
1055 log_manager_.PersistUnsentLogs();
1056
1057 has_initial_stability_log_ = true;
1058}
1059
asvitkine@chromium.orgb58b8b22014-04-08 22:40:331060void MetricsService::PrepareInitialMetricsLog() {
asvitkine@chromium.org80a8f312013-12-16 18:00:301061 DCHECK(state_ == INIT_TASK_DONE || state_ == SENDING_INITIAL_STABILITY_LOG);
asvitkine@chromium.org0edf8762013-11-21 18:33:301062
asvitkine@chromium.orgb3610d42014-05-19 18:07:231063 std::vector<variations::ActiveGroupId> synthetic_trials;
bengr@chromium.org60677562013-11-17 15:52:551064 GetCurrentSyntheticFieldTrials(&synthetic_trials);
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:451065 initial_metrics_log_->RecordEnvironment(metrics_providers_.get(),
asvitkine@chromium.org80a8f312013-12-16 18:00:301066 synthetic_trials);
mpearson@chromium.org076961c2014-03-12 22:23:561067 base::TimeDelta incremental_uptime;
1068 base::TimeDelta uptime;
blundell@chromium.org24f81ca2014-05-26 15:59:341069 GetUptimes(local_state_, &incremental_uptime, &uptime);
stuartmorgan@chromium.org29948262012-03-01 12:15:081070
1071 // Histograms only get written to the current log, so make the new log current
1072 // before writing them.
1073 log_manager_.PauseCurrentLog();
asvitkine@chromium.orgbfb77b52014-06-07 01:54:011074 log_manager_.BeginLoggingWithLog(initial_metrics_log_.Pass());
asvitkine@chromium.org85791b0b2014-05-20 15:18:581075
1076 // Note: Some stability providers may record stability stats via histograms,
1077 // so this call has to be after BeginLoggingWithLog().
1078 MetricsLog* current_log =
1079 static_cast<MetricsLog*>(log_manager_.current_log());
1080 current_log->RecordStabilityMetrics(metrics_providers_.get(),
1081 base::TimeDelta(), base::TimeDelta());
stuartmorgan@chromium.org29948262012-03-01 12:15:081082 RecordCurrentHistograms();
asvitkine@chromium.org85791b0b2014-05-20 15:18:581083
1084 current_log->RecordGeneralMetrics(metrics_providers_.get());
1085
stuartmorgan@chromium.org29948262012-03-01 12:15:081086 log_manager_.FinishCurrentLog();
1087 log_manager_.ResumePausedLog();
1088
1089 DCHECK(!log_manager_.has_staged_log());
1090 log_manager_.StageNextLogForUpload();
1091}
1092
stuartmorgan@chromium.org29948262012-03-01 12:15:081093void MetricsService::SendStagedLog() {
1094 DCHECK(log_manager_.has_staged_log());
asvitkine@chromium.org0d5a61a82014-05-31 22:28:341095 if (!log_manager_.has_staged_log())
1096 return;
stuartmorgan@chromium.org29948262012-03-01 12:15:081097
asvitkine@chromium.org0d5a61a82014-05-31 22:28:341098 DCHECK(!log_upload_in_progress_);
1099 log_upload_in_progress_ = true;
petersont@google.comd01b8732008-10-16 02:18:071100
asvitkine@chromium.org0d5a61a82014-05-31 22:28:341101 if (!log_uploader_) {
1102 log_uploader_ = client_->CreateUploader(
1103 kServerUrl, kMimeType,
1104 base::Bind(&MetricsService::OnLogUploadComplete,
1105 self_ptr_factory_.GetWeakPtr()));
1106 }
1107
1108 const std::string hash =
1109 base::HexEncode(log_manager_.staged_log_hash().data(),
1110 log_manager_.staged_log_hash().size());
1111 bool success = log_uploader_->UploadLog(log_manager_.staged_log(), hash);
1112 UMA_HISTOGRAM_BOOLEAN("UMA.UploadCreation", success);
1113 if (!success) {
isherman@chromium.orgdc61fe92012-06-12 00:13:501114 // Skip this upload and hope things work out next time.
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:101115 log_manager_.DiscardStagedLog();
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:161116 scheduler_->UploadCancelled();
asvitkine@chromium.org0d5a61a82014-05-31 22:28:341117 log_upload_in_progress_ = false;
petersont@google.comd01b8732008-10-16 02:18:071118 return;
1119 }
1120
petersont@google.comd01b8732008-10-16 02:18:071121 HandleIdleSinceLastTransmission(true);
1122}
1123
pkasting@chromium.orgcac78842008-11-27 01:02:201124
asvitkine@chromium.org0d5a61a82014-05-31 22:28:341125void MetricsService::OnLogUploadComplete(int response_code) {
1126 DCHECK(log_upload_in_progress_);
1127 log_upload_in_progress_ = false;
isherman@chromium.orgfe58acc22012-02-29 01:29:581128
isherman@chromium.orgdc61fe92012-06-12 00:13:501129 // Log a histogram to track response success vs. failure rates.
isherman@chromium.orge3eb0c42013-04-18 06:18:581130 UMA_HISTOGRAM_ENUMERATION("UMA.UploadResponseStatus.Protobuf",
1131 ResponseCodeToStatus(response_code),
1132 NUM_RESPONSE_STATUSES);
isherman@chromium.orgfe58acc22012-02-29 01:29:581133
stuartmorgan@chromium.orge7508d82012-05-03 15:59:531134 // If the upload was provisionally stored, drop it now that the upload is
1135 // known to have gone through.
1136 log_manager_.DiscardLastProvisionalStore();
initial.commit09911bf2008-07-26 23:55:291137
isherman@chromium.orgdc61fe92012-06-12 00:13:501138 bool upload_succeeded = response_code == 200;
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:161139
jar@chromium.org0eb34fee2009-01-21 08:04:381140 // Provide boolean for error recovery (allow us to ignore response_code).
paul@chromium.orgdc6f4962009-02-13 01:25:501141 bool discard_log = false;
holte@chromium.org7f07db62014-05-15 01:12:451142 const size_t log_size = log_manager_.staged_log().length();
isherman@chromium.orgdc61fe92012-06-12 00:13:501143 if (!upload_succeeded && log_size > kUploadLogAvoidRetransmitSize) {
1144 UMA_HISTOGRAM_COUNTS("UMA.Large Rejected Log was Discarded",
1145 static_cast<int>(log_size));
jar@chromium.org0eb34fee2009-01-21 08:04:381146 discard_log = true;
isherman@chromium.orgdc61fe92012-06-12 00:13:501147 } else if (response_code == 400) {
jar@chromium.org0eb34fee2009-01-21 08:04:381148 // Bad syntax. Retransmission won't work.
jar@chromium.org0eb34fee2009-01-21 08:04:381149 discard_log = true;
jar@google.com68475e602008-08-22 03:21:151150 }
1151
isherman@chromium.orge3eb0c42013-04-18 06:18:581152 if (upload_succeeded || discard_log)
isherman@chromium.org5f3e1642013-05-05 03:37:341153 log_manager_.DiscardStagedLog();
isherman@chromium.orgdc61fe92012-06-12 00:13:501154
isherman@chromium.orgdc61fe92012-06-12 00:13:501155 if (!log_manager_.has_staged_log()) {
initial.commit09911bf2008-07-26 23:55:291156 switch (state_) {
asvitkine@chromium.org80a8f312013-12-16 18:00:301157 case SENDING_INITIAL_STABILITY_LOG:
1158 // Store the updated list to disk now that the removed log is uploaded.
1159 log_manager_.PersistUnsentLogs();
asvitkine@chromium.orgb58b8b22014-04-08 22:40:331160 PrepareInitialMetricsLog();
asvitkine@chromium.org80a8f312013-12-16 18:00:301161 SendStagedLog();
1162 state_ = SENDING_INITIAL_METRICS_LOG;
1163 break;
1164
1165 case SENDING_INITIAL_METRICS_LOG:
1166 // The initial metrics log never gets persisted to local state, so it's
1167 // not necessary to call log_manager_.PersistUnsentLogs() here.
1168 // TODO(asvitkine): It should be persisted like the initial stability
1169 // log and old unsent logs. http://crbug.com/328417
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:471170 state_ = log_manager_.has_unsent_logs() ? SENDING_OLD_LOGS
1171 : SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291172 break;
1173
initial.commit09911bf2008-07-26 23:55:291174 case SENDING_OLD_LOGS:
stuartmorgan@chromium.orgd53e2232011-06-30 15:54:571175 // Store the updated list to disk now that the removed log is uploaded.
asvitkine@chromium.org80a8f312013-12-16 18:00:301176 log_manager_.PersistUnsentLogs();
stuartmorgan@chromium.orgcd1ac712012-06-26 08:26:471177 if (!log_manager_.has_unsent_logs())
1178 state_ = SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291179 break;
1180
1181 case SENDING_CURRENT_LOGS:
1182 break;
1183
1184 default:
jar@chromium.orga063c102010-07-22 22:20:191185 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291186 break;
1187 }
petersont@google.comd01b8732008-10-16 02:18:071188
stuartmorgan@chromium.orgcac267c2011-09-29 15:18:101189 if (log_manager_.has_unsent_logs())
isherman@chromium.orged0fd002012-04-25 23:10:341190 DCHECK_LT(state_, SENDING_CURRENT_LOGS);
initial.commit09911bf2008-07-26 23:55:291191 }
petersont@google.com252873ef2008-08-04 21:59:451192
stuartmorgan@chromium.org7f7f1962011-04-20 15:58:161193 // Error 400 indicates a problem with the log, not with the server, so
1194 // don't consider that a sign that the server is in trouble.
isherman@chromium.orgdc61fe92012-06-12 00:13:501195 bool server_is_healthy = upload_succeeded || response_code == 400;
asvitkine@chromium.org80a8f312013-12-16 18:00:301196 // Don't notify the scheduler that the upload is finished if we've only sent
1197 // the initial stability log, but not yet the initial metrics log (treat the
1198 // two as a single unit of work as far as the scheduler is concerned).
1199 if (state_ != SENDING_INITIAL_METRICS_LOG) {
1200 scheduler_->UploadFinished(server_is_healthy,
1201 log_manager_.has_unsent_logs());
1202 }
rtenneti@chromium.orgd67d1052011-06-09 05:11:411203
asvitkine@chromium.org73929422014-05-22 08:19:051204 if (server_is_healthy)
1205 client_->OnLogUploadComplete();
initial.commit09911bf2008-07-26 23:55:291206}
1207
viettrungluu@chromium.org57ecc4b2010-08-11 03:02:511208void MetricsService::IncrementPrefValue(const char* path) {
blundell@chromium.org24f81ca2014-05-26 15:59:341209 int value = local_state_->GetInteger(path);
1210 local_state_->SetInteger(path, value + 1);
cpu@google.come73c01972008-08-13 00:18:241211}
1212
viettrungluu@chromium.org57ecc4b2010-08-11 03:02:511213void MetricsService::IncrementLongPrefsValue(const char* path) {
blundell@chromium.org24f81ca2014-05-26 15:59:341214 int64 value = local_state_->GetInt64(path);
1215 local_state_->SetInt64(path, value + 1);
robertshield@google.com0bb1a622009-03-04 03:22:321216}
1217
jar@chromium.orgc0c55e92011-09-10 18:47:301218bool MetricsService::UmaMetricsProperlyShutdown() {
1219 CHECK(clean_shutdown_status_ == CLEANLY_SHUTDOWN ||
1220 clean_shutdown_status_ == NEED_TO_SHUTDOWN);
1221 return clean_shutdown_status_ == CLEANLY_SHUTDOWN;
1222}
1223
bengr@chromium.org60677562013-11-17 15:52:551224void MetricsService::RegisterSyntheticFieldTrial(
1225 const SyntheticTrialGroup& trial) {
1226 for (size_t i = 0; i < synthetic_trial_groups_.size(); ++i) {
1227 if (synthetic_trial_groups_[i].id.name == trial.id.name) {
1228 if (synthetic_trial_groups_[i].id.group != trial.id.group) {
1229 synthetic_trial_groups_[i].id.group = trial.id.group;
asvitkine@chromium.org7a5c07812014-02-26 11:45:411230 synthetic_trial_groups_[i].start_time = base::TimeTicks::Now();
bengr@chromium.org60677562013-11-17 15:52:551231 }
1232 return;
1233 }
1234 }
1235
asvitkine@chromium.org7a5c07812014-02-26 11:45:411236 SyntheticTrialGroup trial_group = trial;
1237 trial_group.start_time = base::TimeTicks::Now();
bengr@chromium.org60677562013-11-17 15:52:551238 synthetic_trial_groups_.push_back(trial_group);
1239}
1240
asvitkine@chromium.org85791b0b2014-05-20 15:18:581241void MetricsService::RegisterMetricsProvider(
1242 scoped_ptr<metrics::MetricsProvider> provider) {
1243 DCHECK_EQ(INITIALIZED, state_);
1244 metrics_providers_.push_back(provider.release());
1245}
1246
blundell@chromium.org61b0d482014-05-20 14:49:101247void MetricsService::CheckForClonedInstall(
1248 scoped_refptr<base::SingleThreadTaskRunner> task_runner) {
1249 state_manager_->CheckForClonedInstall(task_runner);
jwd@chromium.org99c892d2014-03-24 18:11:211250}
1251
bengr@chromium.org60677562013-11-17 15:52:551252void MetricsService::GetCurrentSyntheticFieldTrials(
asvitkine@chromium.orgb3610d42014-05-19 18:07:231253 std::vector<variations::ActiveGroupId>* synthetic_trials) {
bengr@chromium.org60677562013-11-17 15:52:551254 DCHECK(synthetic_trials);
1255 synthetic_trials->clear();
1256 const MetricsLog* current_log =
1257 static_cast<const MetricsLog*>(log_manager_.current_log());
1258 for (size_t i = 0; i < synthetic_trial_groups_.size(); ++i) {
1259 if (synthetic_trial_groups_[i].start_time <= current_log->creation_time())
1260 synthetic_trials->push_back(synthetic_trial_groups_[i].id);
1261 }
1262}
1263
isherman@chromium.org09dee82d2014-05-22 14:00:531264scoped_ptr<MetricsLog> MetricsService::CreateLog(MetricsLog::LogType log_type) {
blundell@chromium.org24f81ca2014-05-26 15:59:341265 return make_scoped_ptr(new MetricsLog(state_manager_->client_id(),
1266 session_id_,
1267 log_type,
1268 client_,
1269 local_state_));
isherman@chromium.org09dee82d2014-05-22 14:00:531270}
1271
isherman@chromium.orgacc2ce5512014-05-22 18:29:131272void MetricsService::RecordCurrentHistograms() {
1273 DCHECK(log_manager_.current_log());
1274 histogram_snapshot_manager_.PrepareDeltas(
1275 base::Histogram::kNoFlags, base::Histogram::kUmaTargetedHistogramFlag);
1276}
1277
1278void MetricsService::RecordCurrentStabilityHistograms() {
1279 DCHECK(log_manager_.current_log());
1280 histogram_snapshot_manager_.PrepareDeltas(
1281 base::Histogram::kNoFlags, base::Histogram::kUmaStabilityHistogramFlag);
1282}
1283
stuartmorgan@chromium.org466f3c12011-03-23 21:20:381284void MetricsService::LogCleanShutdown() {
jar@chromium.orgacd55b32011-09-05 17:35:311285 // Redundant hack to write pref ASAP.
blundell@chromium.org24f81ca2014-05-26 15:59:341286 MarkAppCleanShutdownAndCommit(local_state_);
nileshagrawal@chromium.org84c384e2013-03-01 23:20:191287
jar@chromium.orgc0c55e92011-09-10 18:47:301288 // Redundant setting to assure that we always reset this value at shutdown
1289 // (and that we don't use some alternate path, and not call LogCleanShutdown).
1290 clean_shutdown_status_ = CLEANLY_SHUTDOWN;
jar@chromium.orgacd55b32011-09-05 17:35:311291
stuartmorgan@chromium.org466f3c12011-03-23 21:20:381292 RecordBooleanPrefValue(prefs::kStabilityExitedCleanly, true);
blundell@chromium.org24f81ca2014-05-26 15:59:341293 local_state_->SetInteger(prefs::kStabilityExecutionPhase,
1294 MetricsService::SHUTDOWN_COMPLETE);
stuartmorgan@chromium.org466f3c12011-03-23 21:20:381295}
1296
brettw@chromium.org650b2d52013-02-10 03:41:451297void MetricsService::LogPluginLoadingError(const base::FilePath& plugin_path) {
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:451298#if defined(ENABLE_PLUGINS)
asvitkine@chromium.org48ff2c7f2014-05-23 09:57:451299 plugin_metrics_provider_->LogPluginLoadingError(plugin_path);
1300#endif
initial.commit09911bf2008-07-26 23:55:291301}
1302
stuartmorgan@chromium.orge5ad60a2014-03-11 03:54:041303bool MetricsService::ShouldLogEvents() {
1304 // We simply don't log events to UMA if there is a single incognito
initial.commit09911bf2008-07-26 23:55:291305 // session visible. The problem is that we always notify using the orginal
1306 // profile in order to simplify notification processing.
isherman@chromium.org7d000322014-05-23 07:16:021307 return !client_->IsOffTheRecordSessionActive();
initial.commit09911bf2008-07-26 23:55:291308}
1309
viettrungluu@chromium.org57ecc4b2010-08-11 03:02:511310void MetricsService::RecordBooleanPrefValue(const char* path, bool value) {
initial.commit09911bf2008-07-26 23:55:291311 DCHECK(IsSingleThreaded());
blundell@chromium.org24f81ca2014-05-26 15:59:341312 local_state_->SetBoolean(path, value);
1313 RecordCurrentState(local_state_);
initial.commit09911bf2008-07-26 23:55:291314}
1315
1316void MetricsService::RecordCurrentState(PrefService* pref) {
robertshield@google.com0bb1a622009-03-04 03:22:321317 pref->SetInt64(prefs::kStabilityLastTimestampSec, Time::Now().ToTimeT());
initial.commit09911bf2008-07-26 23:55:291318
blundell@chromium.org544246e2014-06-06 11:22:281319 for (size_t i = 0; i < metrics_providers_.size(); ++i)
1320 metrics_providers_[i]->RecordCurrentState();
initial.commit09911bf2008-07-26 23:55:291321}