blob: 7155c40925380cdcf794a80a7659067afcbe221d [file] [log] [blame]
[email protected]2e4cd1a2012-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//
[email protected]e3eb0c42013-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,
[email protected]e3eb0c42013-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//
[email protected]281d2882009-01-20 20:32:4224// Logs fall into one of two categories: "initial logs," and "ongoing logs."
[email protected]80a8f312013-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)
[email protected]3a668152013-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
[email protected]281d2882009-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
[email protected]80a8f312013-12-16 18:00:3048// to the UMA server. The finalization also acquires the most recent number
[email protected]281d2882009-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
[email protected]80a8f312013-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
[email protected]80a8f312013-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//
[email protected]80a8f312013-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//
[email protected]80a8f312013-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
[email protected]85ed9d42010-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//
[email protected]85ed9d42010-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
[email protected]85ed9d42010-06-08 22:37:44103// the deferred init task is dispatched.
initial.commit09911bf2008-07-26 23:55:29104//
[email protected]80a8f312013-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.
[email protected]cac267c2011-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.
[email protected]0b33f80b2008-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
[email protected]cac267c2011-09-29 15:18:10139// happens, any staged (transmission in progress) log is persisted, and any log
[email protected]410938e02012-10-24 16:33:59140// that is currently accumulating is also finalized and persisted. We then
[email protected]cac267c2011-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//
[email protected]410938e02012-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//
[email protected]cac267c2011-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
[email protected]281d2882009-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
[email protected]40bcc302009-03-02 20:50:39164#include "chrome/browser/metrics/metrics_service.h"
165
[email protected]d7c1fa62012-06-15 23:35:30166#include <algorithm>
167
[email protected]7f7f1962011-04-20 15:58:16168#include "base/bind.h"
169#include "base/callback.h"
[email protected]5d91c9e2010-07-28 17:25:28170#include "base/command_line.h"
[email protected]3dc1bc42012-06-19 08:20:53171#include "base/guid.h"
[email protected]46f89e142010-07-19 08:00:42172#include "base/md5.h"
[email protected]835d7c82010-10-14 04:38:38173#include "base/metrics/histogram.h"
[email protected]1026afd2013-03-20 14:28:54174#include "base/metrics/sparse_histogram.h"
[email protected]567d30e2012-07-13 21:48:29175#include "base/metrics/statistics_recorder.h"
[email protected]3853a4c2013-02-11 17:15:57176#include "base/prefs/pref_registry_simple.h"
177#include "base/prefs/pref_service.h"
[email protected]9eec53fe2013-10-30 20:21:17178#include "base/prefs/scoped_user_pref_update.h"
[email protected]e61003a2012-05-24 17:03:19179#include "base/rand_util.h"
[email protected]3ea1b182013-02-08 22:38:41180#include "base/strings/string_number_conversions.h"
[email protected]112158af2013-06-07 23:46:18181#include "base/strings/utf_string_conversions.h"
[email protected]ce072a72010-12-31 20:02:16182#include "base/threading/platform_thread.h"
[email protected]b3841c502011-03-09 01:21:31183#include "base/threading/thread.h"
[email protected]3a7b66d2012-04-26 16:34:16184#include "base/threading/thread_restrictions.h"
[email protected]ed0fd002012-04-25 23:10:34185#include "base/tracked_objects.h"
[email protected]679082052010-07-21 21:30:13186#include "base/values.h"
initial.commit09911bf2008-07-26 23:55:29187#include "chrome/browser/browser_process.h"
[email protected]9ea0cd32013-07-12 01:50:36188#include "chrome/browser/chrome_notification_types.h"
[email protected]b8ddb052012-04-19 02:36:06189#include "chrome/browser/io_thread.h"
[email protected]84c988a2011-04-19 17:56:33190#include "chrome/browser/memory_details.h"
[email protected]99c892d2014-03-24 18:11:21191#include "chrome/browser/metrics/cloned_install_detector.h"
[email protected]537c638d2013-07-04 00:49:19192#include "chrome/browser/metrics/compression_utils.h"
[email protected]99c892d2014-03-24 18:11:21193#include "chrome/browser/metrics/machine_id_provider.h"
[email protected]679082052010-07-21 21:30:13194#include "chrome/browser/metrics/metrics_log.h"
[email protected]cac267c2011-09-29 15:18:10195#include "chrome/browser/metrics/metrics_log_serializer.h"
[email protected]7f7f1962011-04-20 15:58:16196#include "chrome/browser/metrics/metrics_reporting_scheduler.h"
[email protected]aa312812013-04-30 19:46:05197#include "chrome/browser/metrics/time_ticks_experiment_win.h"
[email protected]ed0fd002012-04-25 23:10:34198#include "chrome/browser/metrics/tracking_synchronizer.h"
[email protected]60677562013-11-17 15:52:55199#include "chrome/common/metrics/variations/variations_util.h"
[email protected]adbb3762012-03-09 22:20:08200#include "chrome/browser/net/http_pipelining_compatibility_client.h"
[email protected]d67d1052011-06-09 05:11:41201#include "chrome/browser/net/network_stats.h"
[email protected]0fafc8d2013-06-01 00:09:50202#include "chrome/browser/omnibox/omnibox_log.h"
[email protected]71b73f02011-04-06 15:57:29203#include "chrome/browser/ui/browser_list.h"
[email protected]10b132b02012-07-27 20:46:18204#include "chrome/browser/ui/browser_otr_state.h"
[email protected]752a5262013-06-23 14:53:42205#include "chrome/browser/ui/search/search_tab_helper.h"
[email protected]261ab7c2013-08-19 15:04:58206#include "chrome/common/chrome_constants.h"
[email protected]d7c1fa62012-06-15 23:35:30207#include "chrome/common/chrome_result_codes.h"
[email protected]92745242009-06-12 16:52:21208#include "chrome/common/chrome_switches.h"
[email protected]264c0acac2013-10-01 13:33:30209#include "chrome/common/crash_keys.h"
[email protected]c277e2b2013-08-02 15:41:08210#include "chrome/common/metrics/caching_permuted_entropy_provider.h"
[email protected]2e4cd1a2012-01-12 08:51:03211#include "chrome/common/metrics/metrics_log_manager.h"
[email protected]b4a72d842012-03-22 20:09:09212#include "chrome/common/net/test_server_locations.h"
initial.commit09911bf2008-07-26 23:55:29213#include "chrome/common/pref_names.h"
[email protected]e09ba552009-02-05 03:26:29214#include "chrome/common/render_messages.h"
[email protected]50ae9f12013-08-29 18:03:22215#include "components/variations/entropy_provider.h"
[email protected]60677562013-11-17 15:52:55216#include "components/variations/metrics_util.h"
[email protected]4967f792012-01-20 22:14:40217#include "content/public/browser/child_process_data.h"
[email protected]83ab4a282012-07-12 18:19:45218#include "content/public/browser/histogram_fetcher.h"
[email protected]09d31d52012-03-11 22:30:27219#include "content/public/browser/load_notification_details.h"
[email protected]ad50def52011-10-19 23:17:07220#include "content/public/browser/notification_service.h"
[email protected]3a5180ae2011-12-21 02:39:38221#include "content/public/browser/plugin_service.h"
[email protected]f3b1a082011-11-18 00:34:30222#include "content/public/browser/render_process_host.h"
[email protected]5d490e42012-08-30 05:16:43223#include "content/public/browser/user_metrics.h"
[email protected]459f3502012-09-17 17:08:12224#include "content/public/browser/web_contents.h"
[email protected]d5d383252013-07-04 14:44:32225#include "content/public/common/process_type.h"
[email protected]d7bd3e52013-07-21 04:29:20226#include "content/public/common/webplugininfo.h"
[email protected]50de9aa22013-11-14 06:30:34227#include "extensions/browser/process_map.h"
[email protected]fe58acc22012-02-29 01:29:58228#include "net/base/load_flags.h"
[email protected]3dc1bc42012-06-19 08:20:53229#include "net/url_request/url_fetcher.h"
initial.commit09911bf2008-07-26 23:55:29230
[email protected]e06131d2010-02-10 18:40:33231// TODO(port): port browser_distribution.h.
232#if !defined(OS_POSIX)
[email protected]79bf0b72009-04-27 21:30:55233#include "chrome/installer/util/browser_distribution.h"
[email protected]dc6f4962009-02-13 01:25:50234#endif
235
[email protected]5ccaa412009-11-13 22:00:16236#if defined(OS_CHROMEOS)
237#include "chrome/browser/chromeos/external_metrics.h"
[email protected]1ef13cf2014-03-21 22:44:01238#include "chrome/browser/chromeos/settings/cros_settings.h"
[email protected]279690f82013-10-09 08:23:52239#include "chromeos/system/statistics_provider.h"
[email protected]5ccaa412009-11-13 22:00:16240#endif
241
[email protected]d7c1fa62012-06-15 23:35:30242#if defined(OS_WIN)
243#include <windows.h> // Needed for STATUS_* codes
[email protected]261ab7c2013-08-19 15:04:58244#include "base/win/registry.h"
[email protected]d7c1fa62012-06-15 23:35:30245#endif
246
[email protected]a3079832013-10-24 20:29:36247#if !defined(OS_ANDROID)
[email protected]cbf160aa2013-11-05 17:54:55248#include "chrome/browser/service_process/service_process_control.h"
[email protected]a3079832013-10-24 20:29:36249#endif
250
[email protected]e1acf6f2008-10-27 20:43:33251using base::Time;
[email protected]631bb742011-11-02 11:29:39252using content::BrowserThread;
[email protected]4967f792012-01-20 22:14:40253using content::ChildProcessData;
[email protected]09d31d52012-03-11 22:30:27254using content::LoadNotificationDetails;
[email protected]3a5180ae2011-12-21 02:39:38255using content::PluginService;
[email protected]e1acf6f2008-10-27 20:43:33256
[email protected]fe58acc22012-02-29 01:29:58257namespace {
[email protected]b2a4812d2012-02-28 05:31:31258
[email protected]fe58acc22012-02-29 01:29:58259// Check to see that we're being called on only one thread.
260bool IsSingleThreaded() {
261 static base::PlatformThreadId thread_id = 0;
262 if (!thread_id)
263 thread_id = base::PlatformThread::CurrentId();
264 return base::PlatformThread::CurrentId() == thread_id;
265}
266
[email protected]7f7f1962011-04-20 15:58:16267// The delay, in seconds, after starting recording before doing expensive
268// initialization work.
[email protected]12180f82012-10-10 21:13:30269#if defined(OS_ANDROID) || defined(OS_IOS)
270// On mobile devices, a significant portion of sessions last less than a minute.
271// Use a shorter timer on these platforms to avoid losing data.
272// TODO(dfalcantara): To avoid delaying startup, tighten up initialization so
273// that it occurs after the user gets their initial page.
274const int kInitializationDelaySeconds = 5;
275#else
[email protected]fe58acc22012-02-29 01:29:58276const int kInitializationDelaySeconds = 30;
[email protected]12180f82012-10-10 21:13:30277#endif
[email protected]252873ef2008-08-04 21:59:45278
[email protected]c9a3ef82009-05-28 22:02:46279// This specifies the amount of time to wait for all renderers to send their
280// data.
[email protected]fe58acc22012-02-29 01:29:58281const int kMaxHistogramGatheringWaitDuration = 60000; // 60 seconds.
[email protected]c9a3ef82009-05-28 22:02:46282
[email protected]54702c92011-04-15 15:06:43283// The maximum number of events in a log uploaded to the UMA server.
[email protected]fe58acc22012-02-29 01:29:58284const int kEventLimit = 2400;
[email protected]68475e602008-08-22 03:21:15285
286// If an upload fails, and the transmission was over this byte count, then we
287// will discard the log, and not try to retransmit it. We also don't persist
288// the log to the prefs for transmission during the next chrome session if this
289// limit is exceeded.
[email protected]fe58acc22012-02-29 01:29:58290const size_t kUploadLogAvoidRetransmitSize = 50000;
initial.commit09911bf2008-07-26 23:55:29291
[email protected]fc4252a72012-01-12 21:58:47292// Interval, in minutes, between state saves.
[email protected]fe58acc22012-02-29 01:29:58293const int kSaveStateIntervalMinutes = 5;
294
[email protected]4266def22012-05-17 01:02:40295enum ResponseStatus {
296 UNKNOWN_FAILURE,
297 SUCCESS,
298 BAD_REQUEST, // Invalid syntax or log too large.
[email protected]9f5c1ce82012-05-23 23:11:28299 NO_RESPONSE,
[email protected]4266def22012-05-17 01:02:40300 NUM_RESPONSE_STATUSES
301};
302
303ResponseStatus ResponseCodeToStatus(int response_code) {
304 switch (response_code) {
305 case 200:
306 return SUCCESS;
307 case 400:
308 return BAD_REQUEST;
[email protected]9f5c1ce82012-05-23 23:11:28309 case net::URLFetcher::RESPONSE_CODE_INVALID:
310 return NO_RESPONSE;
[email protected]4266def22012-05-17 01:02:40311 default:
312 return UNKNOWN_FAILURE;
313 }
314}
315
[email protected]e61003a2012-05-24 17:03:19316// The argument used to generate a non-identifying entropy source. We want no
[email protected]9556a892013-06-21 16:53:20317// more than 13 bits of entropy, so use this max to return a number in the range
318// [0, 7999] as the entropy source (12.97 bits of entropy).
319const int kMaxLowEntropySize = 8000;
[email protected]e61003a2012-05-24 17:03:19320
[email protected]e63a9ef2012-08-30 15:29:42321// Default prefs value for prefs::kMetricsLowEntropySource to indicate that the
322// value has not yet been set.
323const int kLowEntropySourceNotSet = -1;
324
[email protected]e61003a2012-05-24 17:03:19325// Generates a new non-identifying entropy source used to seed persistent
326// activities.
327int GenerateLowEntropySource() {
[email protected]20f999b52012-08-24 22:32:59328 return base::RandInt(0, kMaxLowEntropySize - 1);
[email protected]e61003a2012-05-24 17:03:19329}
330
[email protected]d7c1fa62012-06-15 23:35:30331// Converts an exit code into something that can be inserted into our
332// histograms (which expect non-negative numbers less than MAX_INT).
333int MapCrashExitCodeForHistogram(int exit_code) {
334#if defined(OS_WIN)
335 // Since |abs(STATUS_GUARD_PAGE_VIOLATION) == MAX_INT| it causes problems in
336 // histograms.cc. Solve this by remapping it to a smaller value, which
337 // hopefully doesn't conflict with other codes.
338 if (exit_code == STATUS_GUARD_PAGE_VIOLATION)
339 return 0x1FCF7EC3; // Randomly picked number.
340#endif
341
342 return std::abs(exit_code);
343}
344
[email protected]84c384e2013-03-01 23:20:19345void MarkAppCleanShutdownAndCommit() {
346 PrefService* pref = g_browser_process->local_state();
347 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
[email protected]6a6d0d12013-10-28 15:58:19348 pref->SetInteger(prefs::kStabilityExecutionPhase,
[email protected]6d67ea0d2013-11-14 11:02:21349 MetricsService::SHUTDOWN_COMPLETE);
[email protected]84c384e2013-03-01 23:20:19350 // Start writing right away (write happens on a different thread).
351 pref->CommitPendingWrite();
352}
353
[email protected]80a8f312013-12-16 18:00:30354// Returns whether initial stability metrics should be sent in a separate log.
355bool SendSeparateInitialStabilityLog() {
356 return base::FieldTrialList::FindFullName("UMAStability") == "SeparateLog";
357}
358
[email protected]20f999b52012-08-24 22:32:59359} // namespace
initial.commit09911bf2008-07-26 23:55:29360
[email protected]60677562013-11-17 15:52:55361
[email protected]7a5c07812014-02-26 11:45:41362SyntheticTrialGroup::SyntheticTrialGroup(uint32 trial, uint32 group) {
[email protected]60677562013-11-17 15:52:55363 id.name = trial;
364 id.group = group;
365}
366
367SyntheticTrialGroup::~SyntheticTrialGroup() {
368}
369
[email protected]c0c55e92011-09-10 18:47:30370// static
371MetricsService::ShutdownCleanliness MetricsService::clean_shutdown_status_ =
372 MetricsService::CLEANLY_SHUTDOWN;
373
[email protected]6a6d0d12013-10-28 15:58:19374MetricsService::ExecutionPhase MetricsService::execution_phase_ =
[email protected]6d67ea0d2013-11-14 11:02:21375 MetricsService::UNINITIALIZED_PHASE;
[email protected]6a6d0d12013-10-28 15:58:19376
[email protected]679082052010-07-21 21:30:13377// This is used to quickly log stats from child process related notifications in
378// MetricsService::child_stats_buffer_. The buffer's contents are transferred
379// out when Local State is periodically saved. The information is then
380// reported to the UMA server on next launch.
381struct MetricsService::ChildProcessStats {
382 public:
[email protected]f3b357692013-03-22 05:16:13383 explicit ChildProcessStats(int process_type)
[email protected]679082052010-07-21 21:30:13384 : process_launches(0),
385 process_crashes(0),
386 instances(0),
[email protected]cd937072012-07-02 09:00:29387 loading_errors(0),
[email protected]f3b357692013-03-22 05:16:13388 process_type(process_type) {}
[email protected]679082052010-07-21 21:30:13389
390 // This constructor is only used by the map to return some default value for
391 // an index for which no value has been assigned.
392 ChildProcessStats()
393 : process_launches(0),
[email protected]d88bf0a2011-08-30 23:55:57394 process_crashes(0),
395 instances(0),
[email protected]cd937072012-07-02 09:00:29396 loading_errors(0),
[email protected]bd5d6cf2011-12-01 00:39:12397 process_type(content::PROCESS_TYPE_UNKNOWN) {}
[email protected]679082052010-07-21 21:30:13398
399 // The number of times that the given child process has been launched
400 int process_launches;
401
402 // The number of times that the given child process has crashed
403 int process_crashes;
404
405 // The number of instances of this child process that have been created.
406 // An instance is a DOM object rendered by this child process during a page
407 // load.
408 int instances;
409
[email protected]cd937072012-07-02 09:00:29410 // The number of times there was an error loading an instance of this child
411 // process.
412 int loading_errors;
413
[email protected]f3b357692013-03-22 05:16:13414 int process_type;
[email protected]679082052010-07-21 21:30:13415};
initial.commit09911bf2008-07-26 23:55:29416
[email protected]84c988a2011-04-19 17:56:33417// Handles asynchronous fetching of memory details.
418// Will run the provided task after finished.
419class MetricsMemoryDetails : public MemoryDetails {
420 public:
[email protected]2226c222011-11-22 00:08:40421 explicit MetricsMemoryDetails(const base::Closure& callback)
422 : callback_(callback) {}
[email protected]84c988a2011-04-19 17:56:33423
[email protected]b94584a2013-02-07 03:02:08424 virtual void OnDetailsAvailable() OVERRIDE {
[email protected]b3a25092013-05-28 22:08:16425 base::MessageLoop::current()->PostTask(FROM_HERE, callback_);
[email protected]84c988a2011-04-19 17:56:33426 }
427
428 private:
[email protected]b94584a2013-02-07 03:02:08429 virtual ~MetricsMemoryDetails() {}
[email protected]84c988a2011-04-19 17:56:33430
[email protected]2226c222011-11-22 00:08:40431 base::Closure callback_;
[email protected]84c988a2011-04-19 17:56:33432 DISALLOW_COPY_AND_ASSIGN(MetricsMemoryDetails);
433};
434
initial.commit09911bf2008-07-26 23:55:29435// static
[email protected]b1de2c72013-02-06 02:45:47436void MetricsService::RegisterPrefs(PrefRegistrySimple* registry) {
initial.commit09911bf2008-07-26 23:55:29437 DCHECK(IsSingleThreaded());
[email protected]37d4709a2014-03-29 03:07:40438 registry->RegisterBooleanPref(prefs::kMetricsResetIds, false);
[email protected]007b3f82013-04-09 08:46:45439 registry->RegisterStringPref(prefs::kMetricsClientID, std::string());
[email protected]b1de2c72013-02-06 02:45:47440 registry->RegisterIntegerPref(prefs::kMetricsLowEntropySource,
441 kLowEntropySourceNotSet);
442 registry->RegisterInt64Pref(prefs::kMetricsClientIDTimestamp, 0);
443 registry->RegisterInt64Pref(prefs::kStabilityLaunchTimeSec, 0);
444 registry->RegisterInt64Pref(prefs::kStabilityLastTimestampSec, 0);
[email protected]007b3f82013-04-09 08:46:45445 registry->RegisterStringPref(prefs::kStabilityStatsVersion, std::string());
[email protected]b1de2c72013-02-06 02:45:47446 registry->RegisterInt64Pref(prefs::kStabilityStatsBuildTime, 0);
447 registry->RegisterBooleanPref(prefs::kStabilityExitedCleanly, true);
[email protected]6a6d0d12013-10-28 15:58:19448 registry->RegisterIntegerPref(prefs::kStabilityExecutionPhase,
[email protected]6d67ea0d2013-11-14 11:02:21449 UNINITIALIZED_PHASE);
[email protected]b1de2c72013-02-06 02:45:47450 registry->RegisterBooleanPref(prefs::kStabilitySessionEndCompleted, true);
451 registry->RegisterIntegerPref(prefs::kMetricsSessionID, -1);
452 registry->RegisterIntegerPref(prefs::kStabilityLaunchCount, 0);
453 registry->RegisterIntegerPref(prefs::kStabilityCrashCount, 0);
454 registry->RegisterIntegerPref(prefs::kStabilityIncompleteSessionEndCount, 0);
455 registry->RegisterIntegerPref(prefs::kStabilityPageLoadCount, 0);
456 registry->RegisterIntegerPref(prefs::kStabilityRendererCrashCount, 0);
457 registry->RegisterIntegerPref(prefs::kStabilityExtensionRendererCrashCount,
458 0);
459 registry->RegisterIntegerPref(prefs::kStabilityRendererHangCount, 0);
460 registry->RegisterIntegerPref(prefs::kStabilityChildProcessCrashCount, 0);
461 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationFail, 0);
462 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationSuccess,
463 0);
464 registry->RegisterIntegerPref(prefs::kStabilityDebuggerPresent, 0);
465 registry->RegisterIntegerPref(prefs::kStabilityDebuggerNotPresent, 0);
[email protected]c1834a92011-01-21 18:21:03466#if defined(OS_CHROMEOS)
[email protected]b1de2c72013-02-06 02:45:47467 registry->RegisterIntegerPref(prefs::kStabilityOtherUserCrashCount, 0);
468 registry->RegisterIntegerPref(prefs::kStabilityKernelCrashCount, 0);
469 registry->RegisterIntegerPref(prefs::kStabilitySystemUncleanShutdownCount, 0);
[email protected]c1834a92011-01-21 18:21:03470#endif // OS_CHROMEOS
[email protected]e73c01972008-08-13 00:18:24471
[email protected]0f2f7792013-11-28 16:09:14472 registry->RegisterStringPref(prefs::kStabilitySavedSystemProfile,
473 std::string());
474 registry->RegisterStringPref(prefs::kStabilitySavedSystemProfileHash,
475 std::string());
476
[email protected]5f3e1642013-05-05 03:37:34477 registry->RegisterListPref(prefs::kMetricsInitialLogs);
478 registry->RegisterListPref(prefs::kMetricsOngoingLogs);
[email protected]0bb1a622009-03-04 03:22:32479
[email protected]5c181552013-02-07 09:12:52480 registry->RegisterInt64Pref(prefs::kInstallDate, 0);
[email protected]b1de2c72013-02-06 02:45:47481 registry->RegisterInt64Pref(prefs::kUninstallMetricsPageLoadCount, 0);
482 registry->RegisterInt64Pref(prefs::kUninstallLaunchCount, 0);
[email protected]b1de2c72013-02-06 02:45:47483 registry->RegisterInt64Pref(prefs::kUninstallMetricsUptimeSec, 0);
484 registry->RegisterInt64Pref(prefs::kUninstallLastLaunchTimeSec, 0);
485 registry->RegisterInt64Pref(prefs::kUninstallLastObservedRunTimeSec, 0);
[email protected]c778687a2014-02-11 14:46:45486
487#if defined(OS_ANDROID)
488 RegisterPrefsAndroid(registry);
489#endif // defined(OS_ANDROID)
initial.commit09911bf2008-07-26 23:55:29490}
491
[email protected]541f77922009-02-23 21:14:38492// static
493void MetricsService::DiscardOldStabilityStats(PrefService* local_state) {
494 local_state->SetBoolean(prefs::kStabilityExitedCleanly, true);
[email protected]6d67ea0d2013-11-14 11:02:21495 local_state->SetInteger(prefs::kStabilityExecutionPhase, UNINITIALIZED_PHASE);
[email protected]c9abf242009-07-18 06:00:38496 local_state->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
[email protected]541f77922009-02-23 21:14:38497
498 local_state->SetInteger(prefs::kStabilityIncompleteSessionEndCount, 0);
499 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationSuccess, 0);
500 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationFail, 0);
501 local_state->SetInteger(prefs::kStabilityDebuggerPresent, 0);
502 local_state->SetInteger(prefs::kStabilityDebuggerNotPresent, 0);
503
504 local_state->SetInteger(prefs::kStabilityLaunchCount, 0);
505 local_state->SetInteger(prefs::kStabilityCrashCount, 0);
506
507 local_state->SetInteger(prefs::kStabilityPageLoadCount, 0);
508 local_state->SetInteger(prefs::kStabilityRendererCrashCount, 0);
509 local_state->SetInteger(prefs::kStabilityRendererHangCount, 0);
510
[email protected]9165f742010-03-10 22:55:01511 local_state->SetInt64(prefs::kStabilityLaunchTimeSec, 0);
512 local_state->SetInt64(prefs::kStabilityLastTimestampSec, 0);
[email protected]541f77922009-02-23 21:14:38513
514 local_state->ClearPref(prefs::kStabilityPluginStats);
[email protected]ae155cb92009-06-19 06:10:37515
[email protected]5f3e1642013-05-05 03:37:34516 local_state->ClearPref(prefs::kMetricsInitialLogs);
517 local_state->ClearPref(prefs::kMetricsOngoingLogs);
[email protected]c778687a2014-02-11 14:46:45518
519#if defined(OS_ANDROID)
520 DiscardOldStabilityStatsAndroid(local_state);
521#endif // defined(OS_ANDROID)
[email protected]541f77922009-02-23 21:14:38522}
523
initial.commit09911bf2008-07-26 23:55:29524MetricsService::MetricsService()
[email protected]37d4709a2014-03-29 03:07:40525 : metrics_ids_reset_check_performed_(false),
526 recording_active_(false),
[email protected]d01b8732008-10-16 02:18:07527 reporting_active_(false),
[email protected]410938e02012-10-24 16:33:59528 test_mode_active_(false),
[email protected]d01b8732008-10-16 02:18:07529 state_(INITIALIZED),
[email protected]80a8f312013-12-16 18:00:30530 has_initial_stability_log_(false),
[email protected]e88be472e2013-04-26 22:36:36531 low_entropy_source_(kLowEntropySourceNotSet),
[email protected]d01b8732008-10-16 02:18:07532 idle_since_last_transmission_(false),
[email protected]80a8f312013-12-16 18:00:30533 session_id_(-1),
initial.commit09911bf2008-07-26 23:55:29534 next_window_id_(0),
[email protected]9c009092013-05-01 03:14:09535 self_ptr_factory_(this),
536 state_saver_factory_(this),
[email protected]e5354322012-08-09 23:07:37537 waiting_for_asynchronous_reporting_step_(false),
[email protected]a3079832013-10-24 20:29:36538 num_async_histogram_fetches_in_progress_(0),
[email protected]e5354322012-08-09 23:07:37539 entropy_source_returned_(LAST_ENTROPY_NONE) {
initial.commit09911bf2008-07-26 23:55:29540 DCHECK(IsSingleThreaded());
[email protected]7f7f1962011-04-20 15:58:16541
[email protected]80a8f312013-12-16 18:00:30542 log_manager_.set_log_serializer(new MetricsLogSerializer);
[email protected]cac267c2011-09-29 15:18:10543 log_manager_.set_max_ongoing_log_store_size(kUploadLogAvoidRetransmitSize);
[email protected]f4eaf7b92013-02-28 22:00:40544
545 BrowserChildProcessObserver::Add(this);
initial.commit09911bf2008-07-26 23:55:29546}
547
548MetricsService::~MetricsService() {
[email protected]410938e02012-10-24 16:33:59549 DisableRecording();
[email protected]f4eaf7b92013-02-28 22:00:40550
551 BrowserChildProcessObserver::Remove(this);
initial.commit09911bf2008-07-26 23:55:29552}
553
[email protected]80a8f312013-12-16 18:00:30554void MetricsService::InitializeMetricsRecordingState(
555 ReportingState reporting_state) {
556 InitializeMetricsState(reporting_state);
557
558 base::Closure callback = base::Bind(&MetricsService::StartScheduledUpload,
559 self_ptr_factory_.GetWeakPtr());
560 scheduler_.reset(new MetricsReportingScheduler(callback));
561}
562
[email protected]d01b8732008-10-16 02:18:07563void MetricsService::Start() {
[email protected]b1c8dc02011-04-13 18:32:04564 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59565 EnableRecording();
566 EnableReporting();
[email protected]d01b8732008-10-16 02:18:07567}
568
[email protected]410938e02012-10-24 16:33:59569void MetricsService::StartRecordingForTests() {
570 test_mode_active_ = true;
571 EnableRecording();
572 DisableReporting();
[email protected]d01b8732008-10-16 02:18:07573}
574
575void MetricsService::Stop() {
[email protected]b1c8dc02011-04-13 18:32:04576 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59577 DisableReporting();
578 DisableRecording();
579}
580
581void MetricsService::EnableReporting() {
582 if (reporting_active_)
583 return;
584 reporting_active_ = true;
585 StartSchedulerIfNecessary();
586}
587
588void MetricsService::DisableReporting() {
589 reporting_active_ = false;
[email protected]d01b8732008-10-16 02:18:07590}
591
[email protected]edafd4c2011-05-10 17:18:53592std::string MetricsService::GetClientId() {
593 return client_id_;
594}
595
[email protected]20f999b52012-08-24 22:32:59596scoped_ptr<const base::FieldTrial::EntropyProvider>
[email protected]80a8f312013-12-16 18:00:30597MetricsService::CreateEntropyProvider(ReportingState reporting_state) {
[email protected]29d81ee02012-05-25 05:45:42598 // For metrics reporting-enabled users, we combine the client ID and low
599 // entropy source to get the final entropy source. Otherwise, only use the low
600 // entropy source.
601 // This has two useful properties:
[email protected]e61003a2012-05-24 17:03:19602 // 1) It makes the entropy source less identifiable for parties that do not
603 // know the low entropy source.
604 // 2) It makes the final entropy source resettable.
[email protected]d0a82c82013-06-13 16:31:18605 const int low_entropy_source_value = GetLowEntropySource();
606 UMA_HISTOGRAM_SPARSE_SLOWLY("UMA.LowEntropySourceValue",
607 low_entropy_source_value);
[email protected]80a8f312013-12-16 18:00:30608 if (reporting_state == REPORTING_ENABLED) {
[email protected]5fbffb72012-08-18 01:59:18609 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
610 entropy_source_returned_ = LAST_ENTROPY_HIGH;
611 DCHECK_EQ(LAST_ENTROPY_HIGH, entropy_source_returned_);
[email protected]20f999b52012-08-24 22:32:59612 const std::string high_entropy_source =
[email protected]d0a82c82013-06-13 16:31:18613 client_id_ + base::IntToString(low_entropy_source_value);
[email protected]20f999b52012-08-24 22:32:59614 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
615 new metrics::SHA1EntropyProvider(high_entropy_source));
[email protected]e5354322012-08-09 23:07:37616 }
[email protected]20f999b52012-08-24 22:32:59617
[email protected]5fbffb72012-08-18 01:59:18618 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
619 entropy_source_returned_ = LAST_ENTROPY_LOW;
620 DCHECK_EQ(LAST_ENTROPY_LOW, entropy_source_returned_);
[email protected]9d7c4a82013-05-07 12:10:49621
622#if defined(OS_ANDROID) || defined(OS_IOS)
623 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
624 new metrics::CachingPermutedEntropyProvider(
625 g_browser_process->local_state(),
[email protected]d0a82c82013-06-13 16:31:18626 low_entropy_source_value,
[email protected]9d7c4a82013-05-07 12:10:49627 kMaxLowEntropySize));
628#else
[email protected]20f999b52012-08-24 22:32:59629 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
[email protected]d0a82c82013-06-13 16:31:18630 new metrics::PermutedEntropyProvider(low_entropy_source_value,
[email protected]20f999b52012-08-24 22:32:59631 kMaxLowEntropySize));
[email protected]9d7c4a82013-05-07 12:10:49632#endif
[email protected]e61003a2012-05-24 17:03:19633}
634
[email protected]5cbeeef72012-02-08 02:05:18635void MetricsService::ForceClientIdCreation() {
636 if (!client_id_.empty())
637 return;
[email protected]37d4709a2014-03-29 03:07:40638
639 ResetMetricsIDsIfNecessary();
640
[email protected]5cbeeef72012-02-08 02:05:18641 PrefService* pref = g_browser_process->local_state();
642 client_id_ = pref->GetString(prefs::kMetricsClientID);
643 if (!client_id_.empty())
644 return;
645
646 client_id_ = GenerateClientID();
647 pref->SetString(prefs::kMetricsClientID, client_id_);
648
649 // Might as well make a note of how long this ID has existed
650 pref->SetString(prefs::kMetricsClientIDTimestamp,
651 base::Int64ToString(Time::Now().ToTimeT()));
652}
653
[email protected]410938e02012-10-24 16:33:59654void MetricsService::EnableRecording() {
initial.commit09911bf2008-07-26 23:55:29655 DCHECK(IsSingleThreaded());
656
[email protected]410938e02012-10-24 16:33:59657 if (recording_active_)
initial.commit09911bf2008-07-26 23:55:29658 return;
[email protected]410938e02012-10-24 16:33:59659 recording_active_ = true;
initial.commit09911bf2008-07-26 23:55:29660
[email protected]410938e02012-10-24 16:33:59661 ForceClientIdCreation();
[email protected]264c0acac2013-10-01 13:33:30662 crash_keys::SetClientID(client_id_);
[email protected]410938e02012-10-24 16:33:59663 if (!log_manager_.current_log())
664 OpenNewLog();
[email protected]005ef3e2009-05-22 20:55:46665
[email protected]410938e02012-10-24 16:33:59666 SetUpNotifications(&registrar_, this);
[email protected]e6e30ac2014-01-13 21:24:39667 base::RemoveActionCallback(action_callback_);
[email protected]dd98f392013-02-04 13:03:22668 action_callback_ = base::Bind(&MetricsService::OnUserAction,
669 base::Unretained(this));
[email protected]e6e30ac2014-01-13 21:24:39670 base::AddActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59671}
672
673void MetricsService::DisableRecording() {
674 DCHECK(IsSingleThreaded());
675
676 if (!recording_active_)
677 return;
678 recording_active_ = false;
679
[email protected]e6e30ac2014-01-13 21:24:39680 base::RemoveActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59681 registrar_.RemoveAll();
682 PushPendingLogsToPersistentStorage();
683 DCHECK(!log_manager_.has_staged_log());
initial.commit09911bf2008-07-26 23:55:29684}
685
[email protected]d01b8732008-10-16 02:18:07686bool MetricsService::recording_active() const {
initial.commit09911bf2008-07-26 23:55:29687 DCHECK(IsSingleThreaded());
[email protected]d01b8732008-10-16 02:18:07688 return recording_active_;
initial.commit09911bf2008-07-26 23:55:29689}
690
[email protected]d01b8732008-10-16 02:18:07691bool MetricsService::reporting_active() const {
692 DCHECK(IsSingleThreaded());
693 return reporting_active_;
initial.commit09911bf2008-07-26 23:55:29694}
695
[email protected]87ef9ea2011-02-26 03:15:15696// static
[email protected]6c2381d2011-10-19 02:52:53697void MetricsService::SetUpNotifications(
698 content::NotificationRegistrar* registrar,
699 content::NotificationObserver* observer) {
700 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_OPENED,
[email protected]ad50def52011-10-19 23:17:07701 content::NotificationService::AllBrowserContextsAndSources());
[email protected]6c2381d2011-10-19 02:52:53702 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_CLOSED,
[email protected]ad50def52011-10-19 23:17:07703 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42704 registrar->Add(observer, chrome::NOTIFICATION_TAB_PARENTED,
[email protected]ad50def52011-10-19 23:17:07705 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42706 registrar->Add(observer, chrome::NOTIFICATION_TAB_CLOSING,
[email protected]ad50def52011-10-19 23:17:07707 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53708 registrar->Add(observer, content::NOTIFICATION_LOAD_START,
[email protected]ad50def52011-10-19 23:17:07709 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53710 registrar->Add(observer, content::NOTIFICATION_LOAD_STOP,
[email protected]ad50def52011-10-19 23:17:07711 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53712 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_CLOSED,
[email protected]ad50def52011-10-19 23:17:07713 content::NotificationService::AllSources());
[email protected]42d8d7582013-11-09 01:24:38714 registrar->Add(observer, content::NOTIFICATION_RENDER_WIDGET_HOST_HANG,
[email protected]ad50def52011-10-19 23:17:07715 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53716 registrar->Add(observer, chrome::NOTIFICATION_OMNIBOX_OPENED_URL,
[email protected]ad50def52011-10-19 23:17:07717 content::NotificationService::AllSources());
[email protected]87ef9ea2011-02-26 03:15:15718}
719
[email protected]f4eaf7b92013-02-28 22:00:40720void MetricsService::BrowserChildProcessHostConnected(
721 const content::ChildProcessData& data) {
722 GetChildProcessStats(data).process_launches++;
723}
724
725void MetricsService::BrowserChildProcessCrashed(
726 const content::ChildProcessData& data) {
727 GetChildProcessStats(data).process_crashes++;
728 // Exclude plugin crashes from the count below because we report them via
729 // a separate UMA metric.
[email protected]f3b357692013-03-22 05:16:13730 if (!IsPluginProcess(data.process_type))
[email protected]f4eaf7b92013-02-28 22:00:40731 IncrementPrefValue(prefs::kStabilityChildProcessCrashCount);
732}
733
734void MetricsService::BrowserChildProcessInstanceCreated(
735 const content::ChildProcessData& data) {
736 GetChildProcessStats(data).instances++;
737}
738
[email protected]432115822011-07-10 15:52:27739void MetricsService::Observe(int type,
[email protected]6c2381d2011-10-19 02:52:53740 const content::NotificationSource& source,
741 const content::NotificationDetails& details) {
[email protected]cac267c2011-09-29 15:18:10742 DCHECK(log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:29743 DCHECK(IsSingleThreaded());
744
[email protected]e5ad60a2014-03-11 03:54:04745 // Check for notifications related to core stability metrics, or that are
746 // just triggers to end idle mode. Anything else should be added in the later
747 // switch statement, where they take effect only if general metrics should be
748 // logged.
749 bool handled = false;
[email protected]432115822011-07-10 15:52:27750 switch (type) {
[email protected]432115822011-07-10 15:52:27751 case chrome::NOTIFICATION_BROWSER_OPENED:
[email protected]46a0efc2013-07-17 15:40:47752 case chrome::NOTIFICATION_BROWSER_CLOSED:
753 case chrome::NOTIFICATION_TAB_PARENTED:
754 case chrome::NOTIFICATION_TAB_CLOSING:
[email protected]432115822011-07-10 15:52:27755 case content::NOTIFICATION_LOAD_STOP:
[email protected]e5ad60a2014-03-11 03:54:04756 // These notifications are used only to break out of idle mode.
757 handled = true;
initial.commit09911bf2008-07-26 23:55:29758 break;
759
[email protected]752a5262013-06-23 14:53:42760 case content::NOTIFICATION_LOAD_START: {
761 content::NavigationController* controller =
762 content::Source<content::NavigationController>(source).ptr();
763 content::WebContents* web_contents = controller->GetWebContents();
764 LogLoadStarted(web_contents);
[email protected]e5ad60a2014-03-11 03:54:04765 handled = true;
initial.commit09911bf2008-07-26 23:55:29766 break;
[email protected]752a5262013-06-23 14:53:42767 }
initial.commit09911bf2008-07-26 23:55:29768
[email protected]432115822011-07-10 15:52:27769 case content::NOTIFICATION_RENDERER_PROCESS_CLOSED: {
[email protected]e5ad60a2014-03-11 03:54:04770 content::RenderProcessHost::RendererClosedDetails* process_details =
771 content::Details<
772 content::RenderProcessHost::RendererClosedDetails>(
773 details).ptr();
774 content::RenderProcessHost* host =
775 content::Source<content::RenderProcessHost>(source).ptr();
776 LogRendererCrash(
777 host, process_details->status, process_details->exit_code);
778 handled = true;
initial.commit09911bf2008-07-26 23:55:29779 break;
[email protected]1226abb2010-06-10 18:01:28780 }
initial.commit09911bf2008-07-26 23:55:29781
[email protected]e5ad60a2014-03-11 03:54:04782 case content::NOTIFICATION_RENDER_WIDGET_HOST_HANG:
783 LogRendererHang();
784 handled = true;
initial.commit09911bf2008-07-26 23:55:29785 break;
[email protected]e5ad60a2014-03-11 03:54:04786
787 default:
788 // Everything else is handled after the early return check below.
789 break;
790 }
791
792 // If it wasn't one of the stability-related notifications, and event
793 // logging isn't suppressed, handle it.
794 if (!handled && ShouldLogEvents()) {
795 switch (type) {
796 case chrome::NOTIFICATION_OMNIBOX_OPENED_URL: {
797 MetricsLog* current_log =
798 static_cast<MetricsLog*>(log_manager_.current_log());
799 DCHECK(current_log);
800 current_log->RecordOmniboxOpenedURL(
801 *content::Details<OmniboxLog>(details).ptr());
802 break;
803 }
804
805 default:
806 NOTREACHED();
807 break;
808 }
initial.commit09911bf2008-07-26 23:55:29809 }
[email protected]d01b8732008-10-16 02:18:07810
811 HandleIdleSinceLastTransmission(false);
[email protected]d01b8732008-10-16 02:18:07812}
813
814void MetricsService::HandleIdleSinceLastTransmission(bool in_idle) {
815 // If there wasn't a lot of action, maybe the computer was asleep, in which
816 // case, the log transmissions should have stopped. Here we start them up
817 // again.
[email protected]cac78842008-11-27 01:02:20818 if (!in_idle && idle_since_last_transmission_)
[email protected]7f7f1962011-04-20 15:58:16819 StartSchedulerIfNecessary();
[email protected]cac78842008-11-27 01:02:20820 idle_since_last_transmission_ = in_idle;
initial.commit09911bf2008-07-26 23:55:29821}
822
initial.commit09911bf2008-07-26 23:55:29823void MetricsService::RecordStartOfSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38824 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29825 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, false);
826}
827
828void MetricsService::RecordCompletedSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38829 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29830 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, true);
831}
832
[email protected]410938e02012-10-24 16:33:59833#if defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39834void MetricsService::OnAppEnterBackground() {
835 scheduler_->Stop();
836
[email protected]84c384e2013-03-01 23:20:19837 MarkAppCleanShutdownAndCommit();
[email protected]117fbdf22012-06-26 18:36:39838
839 // At this point, there's no way of knowing when the process will be
840 // killed, so this has to be treated similar to a shutdown, closing and
841 // persisting all logs. Unlinke a shutdown, the state is primed to be ready
842 // to continue logging and uploading if the process does return.
[email protected]80a8f312013-12-16 18:00:30843 if (recording_active() && state_ >= SENDING_INITIAL_STABILITY_LOG) {
[email protected]117fbdf22012-06-26 18:36:39844 PushPendingLogsToPersistentStorage();
[email protected]410938e02012-10-24 16:33:59845 // Persisting logs closes the current log, so start recording a new log
846 // immediately to capture any background work that might be done before the
847 // process is killed.
848 OpenNewLog();
[email protected]117fbdf22012-06-26 18:36:39849 }
[email protected]117fbdf22012-06-26 18:36:39850}
851
852void MetricsService::OnAppEnterForeground() {
853 PrefService* pref = g_browser_process->local_state();
854 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
855
856 StartSchedulerIfNecessary();
857}
[email protected]84c384e2013-03-01 23:20:19858#else
859void MetricsService::LogNeedForCleanShutdown() {
860 PrefService* pref = g_browser_process->local_state();
861 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
862 // Redundant setting to be sure we call for a clean shutdown.
863 clean_shutdown_status_ = NEED_TO_SHUTDOWN;
864}
865#endif // defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39866
[email protected]6d67ea0d2013-11-14 11:02:21867// static
868void MetricsService::SetExecutionPhase(ExecutionPhase execution_phase) {
869 execution_phase_ = execution_phase;
870 PrefService* pref = g_browser_process->local_state();
871 pref->SetInteger(prefs::kStabilityExecutionPhase, execution_phase_);
872}
873
[email protected]7f7f1962011-04-20 15:58:16874void MetricsService::RecordBreakpadRegistration(bool success) {
[email protected]68475e602008-08-22 03:21:15875 if (!success)
[email protected]e73c01972008-08-13 00:18:24876 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationFail);
877 else
878 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationSuccess);
879}
880
881void MetricsService::RecordBreakpadHasDebugger(bool has_debugger) {
882 if (!has_debugger)
883 IncrementPrefValue(prefs::kStabilityDebuggerNotPresent);
884 else
[email protected]68475e602008-08-22 03:21:15885 IncrementPrefValue(prefs::kStabilityDebuggerPresent);
[email protected]e73c01972008-08-13 00:18:24886}
887
[email protected]261ab7c2013-08-19 15:04:58888#if defined(OS_WIN)
889void MetricsService::CountBrowserCrashDumpAttempts() {
[email protected]a5e0fe5e2013-09-16 06:15:45890 // Open the registry key for iteration.
[email protected]261ab7c2013-08-19 15:04:58891 base::win::RegKey regkey;
892 if (regkey.Open(HKEY_CURRENT_USER,
[email protected]a5e0fe5e2013-09-16 06:15:45893 chrome::kBrowserCrashDumpAttemptsRegistryPath,
[email protected]261ab7c2013-08-19 15:04:58894 KEY_ALL_ACCESS) != ERROR_SUCCESS) {
895 return;
896 }
897
[email protected]a5e0fe5e2013-09-16 06:15:45898 // The values we're interested in counting are all prefixed with the version.
899 base::string16 chrome_version(base::ASCIIToUTF16(chrome::kChromeVersion));
900
901 // Track a list of values to delete. We don't modify the registry key while
902 // we're iterating over its values.
903 typedef std::vector<base::string16> StringVector;
904 StringVector to_delete;
905
906 // Iterate over the values in the key counting dumps with and without crashes.
907 // We directly walk the values instead of using RegistryValueIterator in order
908 // to read all of the values as DWORDS instead of strings.
909 base::string16 name;
910 DWORD value = 0;
911 int dumps_with_crash = 0;
912 int dumps_with_no_crash = 0;
[email protected]261ab7c2013-08-19 15:04:58913 for (int i = regkey.GetValueCount() - 1; i >= 0; --i) {
[email protected]a5e0fe5e2013-09-16 06:15:45914 if (regkey.GetValueNameAt(i, &name) == ERROR_SUCCESS &&
915 StartsWith(name, chrome_version, false) &&
916 regkey.ReadValueDW(name.c_str(), &value) == ERROR_SUCCESS) {
917 to_delete.push_back(name);
918 if (value == 0)
919 ++dumps_with_no_crash;
920 else
921 ++dumps_with_crash;
[email protected]261ab7c2013-08-19 15:04:58922 }
923 }
[email protected]a5e0fe5e2013-09-16 06:15:45924
925 // Delete the registry keys we've just counted.
926 for (StringVector::iterator i = to_delete.begin(); i != to_delete.end(); ++i)
927 regkey.DeleteValue(i->c_str());
928
929 // Capture the histogram samples.
930 if (dumps_with_crash != 0)
931 UMA_HISTOGRAM_COUNTS("Chrome.BrowserDumpsWithCrash", dumps_with_crash);
932 if (dumps_with_no_crash != 0)
933 UMA_HISTOGRAM_COUNTS("Chrome.BrowserDumpsWithNoCrash", dumps_with_no_crash);
934 int total_dumps = dumps_with_crash + dumps_with_no_crash;
935 if (total_dumps != 0)
936 UMA_HISTOGRAM_COUNTS("Chrome.BrowserCrashDumpAttempts", total_dumps);
[email protected]261ab7c2013-08-19 15:04:58937}
938#endif // defined(OS_WIN)
939
initial.commit09911bf2008-07-26 23:55:29940//------------------------------------------------------------------------------
941// private methods
942//------------------------------------------------------------------------------
943
944
945//------------------------------------------------------------------------------
946// Initialization methods
947
[email protected]80a8f312013-12-16 18:00:30948void MetricsService::InitializeMetricsState(ReportingState reporting_state) {
[email protected]79bf0b72009-04-27 21:30:55949#if defined(OS_POSIX)
[email protected]b4a72d842012-03-22 20:09:09950 network_stats_server_ = chrome_common_net::kEchoTestServerLocation;
951 http_pipelining_test_server_ = chrome_common_net::kPipelineTestServerBaseUrl;
[email protected]79bf0b72009-04-27 21:30:55952#else
953 BrowserDistribution* dist = BrowserDistribution::GetDistribution();
[email protected]d67d1052011-06-09 05:11:41954 network_stats_server_ = dist->GetNetworkStatsServer();
[email protected]adbb3762012-03-09 22:20:08955 http_pipelining_test_server_ = dist->GetHttpPipeliningTestServer();
[email protected]79bf0b72009-04-27 21:30:55956#endif
957
initial.commit09911bf2008-07-26 23:55:29958 PrefService* pref = g_browser_process->local_state();
959 DCHECK(pref);
960
[email protected]80a8f312013-12-16 18:00:30961 // TODO(asvitkine): Kill this logic when SendSeparateInitialStabilityLog() is
962 // is made the default behavior.
[email protected]225c50842010-01-19 21:19:13963 if ((pref->GetInt64(prefs::kStabilityStatsBuildTime)
964 != MetricsLog::GetBuildTime()) ||
[email protected]ddd231e2010-06-29 20:35:19965 (pref->GetString(prefs::kStabilityStatsVersion)
[email protected]225c50842010-01-19 21:19:13966 != MetricsLog::GetVersionString())) {
[email protected]541f77922009-02-23 21:14:38967 // This is a new version, so we don't want to confuse the stats about the
968 // old version with info that we upload.
969 DiscardOldStabilityStats(pref);
970 pref->SetString(prefs::kStabilityStatsVersion,
[email protected]ddd231e2010-06-29 20:35:19971 MetricsLog::GetVersionString());
[email protected]225c50842010-01-19 21:19:13972 pref->SetInt64(prefs::kStabilityStatsBuildTime,
973 MetricsLog::GetBuildTime());
[email protected]541f77922009-02-23 21:14:38974 }
975
initial.commit09911bf2008-07-26 23:55:29976 session_id_ = pref->GetInteger(prefs::kMetricsSessionID);
initial.commit09911bf2008-07-26 23:55:29977
[email protected]c778687a2014-02-11 14:46:45978#if defined(OS_ANDROID)
979 LogAndroidStabilityToPrefs(pref);
980#endif // defined(OS_ANDROID)
981
[email protected]e73c01972008-08-13 00:18:24982 if (!pref->GetBoolean(prefs::kStabilityExitedCleanly)) {
983 IncrementPrefValue(prefs::kStabilityCrashCount);
[email protected]c0c55e92011-09-10 18:47:30984 // Reset flag, and wait until we call LogNeedForCleanShutdown() before
985 // monitoring.
986 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
[email protected]6a6d0d12013-10-28 15:58:19987
988 // TODO(rtenneti): On windows, consider saving/getting execution_phase from
989 // the registry.
990 int execution_phase = pref->GetInteger(prefs::kStabilityExecutionPhase);
[email protected]6d67ea0d2013-11-14 11:02:21991 UMA_HISTOGRAM_SPARSE_SLOWLY("Chrome.Browser.CrashedExecutionPhase",
[email protected]6a6d0d12013-10-28 15:58:19992 execution_phase);
[email protected]80a8f312013-12-16 18:00:30993
994 // If the previous session didn't exit cleanly, then prepare an initial
995 // stability log if UMA is enabled.
996 bool reporting_will_be_enabled = (reporting_state == REPORTING_ENABLED);
997 if (reporting_will_be_enabled && SendSeparateInitialStabilityLog())
998 PrepareInitialStabilityLog();
initial.commit09911bf2008-07-26 23:55:29999 }
[email protected]80a8f312013-12-16 18:00:301000
1001 // Update session ID.
1002 ++session_id_;
1003 pref->SetInteger(prefs::kMetricsSessionID, session_id_);
1004
1005 // Stability bookkeeping
1006 IncrementPrefValue(prefs::kStabilityLaunchCount);
1007
[email protected]6d67ea0d2013-11-14 11:02:211008 DCHECK_EQ(UNINITIALIZED_PHASE, execution_phase_);
1009 SetExecutionPhase(START_METRICS_RECORDING);
[email protected]e73c01972008-08-13 00:18:241010
[email protected]261ab7c2013-08-19 15:04:581011#if defined(OS_WIN)
1012 CountBrowserCrashDumpAttempts();
1013#endif // defined(OS_WIN)
1014
[email protected]e73c01972008-08-13 00:18:241015 if (!pref->GetBoolean(prefs::kStabilitySessionEndCompleted)) {
1016 IncrementPrefValue(prefs::kStabilityIncompleteSessionEndCount);
[email protected]c9abf242009-07-18 06:00:381017 // This is marked false when we get a WM_ENDSESSION.
1018 pref->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
initial.commit09911bf2008-07-26 23:55:291019 }
initial.commit09911bf2008-07-26 23:55:291020
[email protected]076961c2014-03-12 22:23:561021 // Call GetUptimes() for the first time, thus allowing all later calls
1022 // to record incremental uptimes accurately.
1023 base::TimeDelta ignored_uptime_parameter;
1024 base::TimeDelta startup_uptime;
1025 GetUptimes(pref, &startup_uptime, &ignored_uptime_parameter);
[email protected]c68a2b9b2013-10-09 18:16:361026 DCHECK_EQ(0, startup_uptime.InMicroseconds());
[email protected]9165f742010-03-10 22:55:011027 // For backwards compatibility, leave this intact in case Omaha is checking
1028 // them. prefs::kStabilityLastTimestampSec may also be useless now.
1029 // TODO(jar): Delete these if they have no uses.
[email protected]0bb1a622009-03-04 03:22:321030 pref->SetInt64(prefs::kStabilityLaunchTimeSec, Time::Now().ToTimeT());
1031
1032 // Bookkeeping for the uninstall metrics.
1033 IncrementLongPrefsValue(prefs::kUninstallLaunchCount);
initial.commit09911bf2008-07-26 23:55:291034
[email protected]92745242009-06-12 16:52:211035 // Get stats on use of command line.
1036 const CommandLine* command_line(CommandLine::ForCurrentProcess());
1037 size_t common_commands = 0;
1038 if (command_line->HasSwitch(switches::kUserDataDir)) {
1039 ++common_commands;
1040 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineDatDirCount", 1);
1041 }
1042
1043 if (command_line->HasSwitch(switches::kApp)) {
1044 ++common_commands;
1045 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineAppModeCount", 1);
1046 }
1047
[email protected]62b4e522011-07-13 21:46:321048 size_t switch_count = command_line->GetSwitches().size();
1049 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineFlagCount", switch_count);
[email protected]92745242009-06-12 16:52:211050 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineUncommonFlagCount",
[email protected]62b4e522011-07-13 21:46:321051 switch_count - common_commands);
[email protected]92745242009-06-12 16:52:211052
initial.commit09911bf2008-07-26 23:55:291053 // Kick off the process of saving the state (so the uptime numbers keep
1054 // getting updated) every n minutes.
1055 ScheduleNextStateSave();
1056}
1057
[email protected]c94d7382012-02-28 08:43:401058// static
[email protected]d33e7cc2011-09-23 01:43:561059void MetricsService::InitTaskGetHardwareClass(
[email protected]c94d7382012-02-28 08:43:401060 base::WeakPtr<MetricsService> self,
[email protected]d33e7cc2011-09-23 01:43:561061 base::MessageLoopProxy* target_loop) {
[email protected]d33e7cc2011-09-23 01:43:561062 DCHECK(BrowserThread::CurrentlyOn(BrowserThread::FILE));
1063
1064 std::string hardware_class;
1065#if defined(OS_CHROMEOS)
1066 chromeos::system::StatisticsProvider::GetInstance()->GetMachineStatistic(
1067 "hardware_class", &hardware_class);
1068#endif // OS_CHROMEOS
1069
1070 target_loop->PostTask(FROM_HERE,
1071 base::Bind(&MetricsService::OnInitTaskGotHardwareClass,
[email protected]c94d7382012-02-28 08:43:401072 self, hardware_class));
[email protected]d33e7cc2011-09-23 01:43:561073}
1074
1075void MetricsService::OnInitTaskGotHardwareClass(
1076 const std::string& hardware_class) {
[email protected]ed0fd002012-04-25 23:10:341077 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]85ed9d42010-06-08 22:37:441078 hardware_class_ = hardware_class;
[email protected]d33e7cc2011-09-23 01:43:561079
[email protected]ebd71962012-12-20 02:56:551080#if defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:561081 // Start the next part of the init task: loading plugin information.
1082 PluginService::GetInstance()->GetPlugins(
1083 base::Bind(&MetricsService::OnInitTaskGotPluginInfo,
[email protected]c94d7382012-02-28 08:43:401084 self_ptr_factory_.GetWeakPtr()));
[email protected]ebd71962012-12-20 02:56:551085#else
[email protected]d7bd3e52013-07-21 04:29:201086 std::vector<content::WebPluginInfo> plugin_list_empty;
[email protected]ebd71962012-12-20 02:56:551087 OnInitTaskGotPluginInfo(plugin_list_empty);
1088#endif // defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:561089}
1090
1091void MetricsService::OnInitTaskGotPluginInfo(
[email protected]d7bd3e52013-07-21 04:29:201092 const std::vector<content::WebPluginInfo>& plugins) {
[email protected]ed0fd002012-04-25 23:10:341093 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]35fa6a22009-08-15 00:04:011094 plugins_ = plugins;
[email protected]d33e7cc2011-09-23 01:43:561095
[email protected]197c0772012-05-14 23:50:511096 // Schedules a task on a blocking pool thread to gather Google Update
1097 // statistics (requires Registry reads).
1098 BrowserThread::PostBlockingPoolTask(
1099 FROM_HERE,
1100 base::Bind(&MetricsService::InitTaskGetGoogleUpdateData,
1101 self_ptr_factory_.GetWeakPtr(),
[email protected]b3a25092013-05-28 22:08:161102 base::MessageLoop::current()->message_loop_proxy()));
[email protected]197c0772012-05-14 23:50:511103}
1104
1105// static
1106void MetricsService::InitTaskGetGoogleUpdateData(
1107 base::WeakPtr<MetricsService> self,
1108 base::MessageLoopProxy* target_loop) {
1109 GoogleUpdateMetrics google_update_metrics;
1110
1111#if defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
1112 const bool system_install = GoogleUpdateSettings::IsSystemInstall();
1113
1114 google_update_metrics.is_system_install = system_install;
1115 google_update_metrics.last_started_au =
1116 GoogleUpdateSettings::GetGoogleUpdateLastStartedAU(system_install);
1117 google_update_metrics.last_checked =
1118 GoogleUpdateSettings::GetGoogleUpdateLastChecked(system_install);
1119 GoogleUpdateSettings::GetUpdateDetailForGoogleUpdate(
1120 system_install,
1121 &google_update_metrics.google_update_data);
1122 GoogleUpdateSettings::GetUpdateDetail(
1123 system_install,
1124 &google_update_metrics.product_data);
1125#endif // defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
1126
1127 target_loop->PostTask(FROM_HERE,
1128 base::Bind(&MetricsService::OnInitTaskGotGoogleUpdateData,
1129 self, google_update_metrics));
1130}
1131
1132void MetricsService::OnInitTaskGotGoogleUpdateData(
1133 const GoogleUpdateMetrics& google_update_metrics) {
1134 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
1135
1136 google_update_metrics_ = google_update_metrics;
1137
[email protected]ed0fd002012-04-25 23:10:341138 // Start the next part of the init task: fetching performance data. This will
1139 // call into |FinishedReceivingProfilerData()| when the task completes.
1140 chrome_browser_metrics::TrackingSynchronizer::FetchProfilerDataAsynchronously(
1141 self_ptr_factory_.GetWeakPtr());
1142}
1143
[email protected]dd98f392013-02-04 13:03:221144void MetricsService::OnUserAction(const std::string& action) {
[email protected]e5ad60a2014-03-11 03:54:041145 if (!ShouldLogEvents())
[email protected]dd98f392013-02-04 13:03:221146 return;
1147
1148 log_manager_.current_log()->RecordUserAction(action.c_str());
1149 HandleIdleSinceLastTransmission(false);
1150}
1151
[email protected]ed0fd002012-04-25 23:10:341152void MetricsService::ReceivedProfilerData(
1153 const tracked_objects::ProcessDataSnapshot& process_data,
[email protected]f3b357692013-03-22 05:16:131154 int process_type) {
[email protected]ed0fd002012-04-25 23:10:341155 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
1156
1157 // Upon the first callback, create the initial log so that we can immediately
1158 // save the profiler data.
[email protected]80a8f312013-12-16 18:00:301159 if (!initial_metrics_log_.get())
1160 initial_metrics_log_.reset(new MetricsLog(client_id_, session_id_));
[email protected]ed0fd002012-04-25 23:10:341161
[email protected]80a8f312013-12-16 18:00:301162 initial_metrics_log_->RecordProfilerData(process_data, process_type);
[email protected]ed0fd002012-04-25 23:10:341163}
1164
1165void MetricsService::FinishedReceivingProfilerData() {
1166 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]c68a2b9b2013-10-09 18:16:361167 state_ = INIT_TASK_DONE;
[email protected]70886cd2013-12-04 05:53:421168 scheduler_->InitTaskComplete();
[email protected]c68a2b9b2013-10-09 18:16:361169}
1170
[email protected]076961c2014-03-12 22:23:561171void MetricsService::GetUptimes(PrefService* pref,
1172 base::TimeDelta* incremental_uptime,
1173 base::TimeDelta* uptime) {
[email protected]c68a2b9b2013-10-09 18:16:361174 base::TimeTicks now = base::TimeTicks::Now();
[email protected]076961c2014-03-12 22:23:561175 // If this is the first call, init |first_updated_time_| and
1176 // |last_updated_time_|.
1177 if (last_updated_time_.is_null()) {
1178 first_updated_time_ = now;
[email protected]c68a2b9b2013-10-09 18:16:361179 last_updated_time_ = now;
[email protected]076961c2014-03-12 22:23:561180 }
1181 *incremental_uptime = now - last_updated_time_;
1182 *uptime = now - first_updated_time_;
[email protected]c68a2b9b2013-10-09 18:16:361183 last_updated_time_ = now;
1184
[email protected]076961c2014-03-12 22:23:561185 const int64 incremental_time_secs = incremental_uptime->InSeconds();
[email protected]c68a2b9b2013-10-09 18:16:361186 if (incremental_time_secs > 0) {
1187 int64 metrics_uptime = pref->GetInt64(prefs::kUninstallMetricsUptimeSec);
1188 metrics_uptime += incremental_time_secs;
1189 pref->SetInt64(prefs::kUninstallMetricsUptimeSec, metrics_uptime);
1190 }
initial.commit09911bf2008-07-26 23:55:291191}
1192
[email protected]37d4709a2014-03-29 03:07:401193void MetricsService::ResetMetricsIDsIfNecessary() {
1194 if (metrics_ids_reset_check_performed_)
1195 return;
1196
1197 metrics_ids_reset_check_performed_ = true;
1198
1199 PrefService* local_state = g_browser_process->local_state();
1200 if (!local_state->GetBoolean(prefs::kMetricsResetIds))
1201 return;
1202
1203 UMA_HISTOGRAM_BOOLEAN("UMA.MetricsIDsReset", true);
1204
1205 DCHECK(client_id_.empty());
1206 DCHECK_EQ(kLowEntropySourceNotSet, low_entropy_source_);
1207
1208 local_state->ClearPref(prefs::kMetricsClientID);
1209 local_state->ClearPref(prefs::kMetricsLowEntropySource);
1210 local_state->ClearPref(prefs::kMetricsResetIds);
1211}
1212
[email protected]e61003a2012-05-24 17:03:191213int MetricsService::GetLowEntropySource() {
1214 // Note that the default value for the low entropy source and the default pref
[email protected]e88be472e2013-04-26 22:36:361215 // value are both kLowEntropySourceNotSet, which is used to identify if the
1216 // value has been set or not.
1217 if (low_entropy_source_ != kLowEntropySourceNotSet)
[email protected]e61003a2012-05-24 17:03:191218 return low_entropy_source_;
1219
[email protected]37d4709a2014-03-29 03:07:401220 ResetMetricsIDsIfNecessary();
1221
[email protected]9d7c4a82013-05-07 12:10:491222 PrefService* local_state = g_browser_process->local_state();
[email protected]e61003a2012-05-24 17:03:191223 const CommandLine* command_line(CommandLine::ForCurrentProcess());
1224 // Only try to load the value from prefs if the user did not request a reset.
1225 // Otherwise, skip to generating a new value.
[email protected]1862c0c2013-04-18 06:30:481226 if (!command_line->HasSwitch(switches::kResetVariationState)) {
[email protected]9556a892013-06-21 16:53:201227 int value = local_state->GetInteger(prefs::kMetricsLowEntropySource);
1228 // Old versions of the code would generate values in the range of [1, 8192],
1229 // before the range was switched to [0, 8191] and then to [0, 7999]. Map
1230 // 8192 to 0, so that the 0th bucket remains uniform, while re-generating
1231 // the low entropy source for old values in the [8000, 8191] range.
1232 if (value == 8192)
1233 value = 0;
1234 // If the value is outside the [0, kMaxLowEntropySize) range, re-generate
1235 // it below.
1236 if (value >= 0 && value < kMaxLowEntropySize) {
1237 low_entropy_source_ = value;
[email protected]0c906e92012-10-18 15:24:131238 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", false);
[email protected]e61003a2012-05-24 17:03:191239 return low_entropy_source_;
[email protected]e63a9ef2012-08-30 15:29:421240 }
[email protected]e61003a2012-05-24 17:03:191241 }
1242
[email protected]0c906e92012-10-18 15:24:131243 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", true);
[email protected]e61003a2012-05-24 17:03:191244 low_entropy_source_ = GenerateLowEntropySource();
[email protected]9d7c4a82013-05-07 12:10:491245 local_state->SetInteger(prefs::kMetricsLowEntropySource, low_entropy_source_);
1246 metrics::CachingPermutedEntropyProvider::ClearCache(local_state);
[email protected]e61003a2012-05-24 17:03:191247
1248 return low_entropy_source_;
1249}
1250
1251// static
initial.commit09911bf2008-07-26 23:55:291252std::string MetricsService::GenerateClientID() {
[email protected]7e49ad32012-06-14 14:22:071253 return base::GenerateGUID();
initial.commit09911bf2008-07-26 23:55:291254}
1255
initial.commit09911bf2008-07-26 23:55:291256//------------------------------------------------------------------------------
1257// State save methods
1258
1259void MetricsService::ScheduleNextStateSave() {
[email protected]8454aeb2011-11-19 23:38:201260 state_saver_factory_.InvalidateWeakPtrs();
initial.commit09911bf2008-07-26 23:55:291261
[email protected]b3a25092013-05-28 22:08:161262 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
[email protected]8454aeb2011-11-19 23:38:201263 base::Bind(&MetricsService::SaveLocalState,
1264 state_saver_factory_.GetWeakPtr()),
[email protected]fc4252a72012-01-12 21:58:471265 base::TimeDelta::FromMinutes(kSaveStateIntervalMinutes));
initial.commit09911bf2008-07-26 23:55:291266}
1267
1268void MetricsService::SaveLocalState() {
1269 PrefService* pref = g_browser_process->local_state();
1270 if (!pref) {
[email protected]a063c102010-07-22 22:20:191271 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291272 return;
1273 }
1274
1275 RecordCurrentState(pref);
initial.commit09911bf2008-07-26 23:55:291276
[email protected]fc4252a72012-01-12 21:58:471277 // TODO(jar):110021 Does this run down the batteries????
initial.commit09911bf2008-07-26 23:55:291278 ScheduleNextStateSave();
1279}
1280
1281
1282//------------------------------------------------------------------------------
1283// Recording control methods
1284
[email protected]410938e02012-10-24 16:33:591285void MetricsService::OpenNewLog() {
1286 DCHECK(!log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:291287
[email protected]29948262012-03-01 12:15:081288 log_manager_.BeginLoggingWithLog(new MetricsLog(client_id_, session_id_),
[email protected]0edf8762013-11-21 18:33:301289 MetricsLog::ONGOING_LOG);
initial.commit09911bf2008-07-26 23:55:291290 if (state_ == INITIALIZED) {
1291 // We only need to schedule that run once.
[email protected]85ed9d42010-06-08 22:37:441292 state_ = INIT_TASK_SCHEDULED;
initial.commit09911bf2008-07-26 23:55:291293
[email protected]85ed9d42010-06-08 22:37:441294 // Schedules a task on the file thread for execution of slower
1295 // initialization steps (such as plugin list generation) necessary
1296 // for sending the initial log. This avoids blocking the main UI
1297 // thread.
[email protected]ed10dd12011-12-07 12:03:421298 BrowserThread::PostDelayedTask(
1299 BrowserThread::FILE,
1300 FROM_HERE,
[email protected]d33e7cc2011-09-23 01:43:561301 base::Bind(&MetricsService::InitTaskGetHardwareClass,
[email protected]c94d7382012-02-28 08:43:401302 self_ptr_factory_.GetWeakPtr(),
[email protected]b3a25092013-05-28 22:08:161303 base::MessageLoop::current()->message_loop_proxy()),
[email protected]7e560102012-03-08 20:58:421304 base::TimeDelta::FromSeconds(kInitializationDelaySeconds));
initial.commit09911bf2008-07-26 23:55:291305 }
1306}
1307
[email protected]410938e02012-10-24 16:33:591308void MetricsService::CloseCurrentLog() {
[email protected]cac267c2011-09-29 15:18:101309 if (!log_manager_.current_log())
initial.commit09911bf2008-07-26 23:55:291310 return;
1311
[email protected]68475e602008-08-22 03:21:151312 // TODO(jar): Integrate bounds on log recording more consistently, so that we
1313 // can stop recording logs that are too big much sooner.
[email protected]cac267c2011-09-29 15:18:101314 if (log_manager_.current_log()->num_events() > kEventLimit) {
[email protected]553dba62009-02-24 19:08:231315 UMA_HISTOGRAM_COUNTS("UMA.Discarded Log Events",
[email protected]cac267c2011-09-29 15:18:101316 log_manager_.current_log()->num_events());
1317 log_manager_.DiscardCurrentLog();
[email protected]410938e02012-10-24 16:33:591318 OpenNewLog(); // Start trivial log to hold our histograms.
[email protected]68475e602008-08-22 03:21:151319 }
1320
[email protected]cac267c2011-09-29 15:18:101321 // Adds to ongoing logs.
1322 log_manager_.current_log()->set_hardware_class(hardware_class_);
[email protected]accdfa62011-09-20 01:56:521323
[email protected]0b33f80b2008-12-17 21:34:361324 // Put incremental data (histogram deltas, and realtime stats deltas) at the
[email protected]147bbc0b2009-01-06 19:37:401325 // end of all log transmissions (initial log handles this separately).
[email protected]024b5cd2011-05-27 03:29:381326 // RecordIncrementalStabilityElements only exists on the derived
1327 // MetricsLog class.
[email protected]279703f2012-01-20 22:23:261328 MetricsLog* current_log =
1329 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]024b5cd2011-05-27 03:29:381330 DCHECK(current_log);
[email protected]60677562013-11-17 15:52:551331 std::vector<chrome_variations::ActiveGroupId> synthetic_trials;
1332 GetCurrentSyntheticFieldTrials(&synthetic_trials);
[email protected]0edf8762013-11-21 18:33:301333 current_log->RecordEnvironment(plugins_, google_update_metrics_,
1334 synthetic_trials);
[email protected]c68a2b9b2013-10-09 18:16:361335 PrefService* pref = g_browser_process->local_state();
[email protected]076961c2014-03-12 22:23:561336 base::TimeDelta incremental_uptime;
1337 base::TimeDelta uptime;
1338 GetUptimes(pref, &incremental_uptime, &uptime);
1339 current_log->RecordStabilityMetrics(incremental_uptime, uptime,
[email protected]0edf8762013-11-21 18:33:301340 MetricsLog::ONGOING_LOG);
[email protected]60677562013-11-17 15:52:551341
[email protected]024b5cd2011-05-27 03:29:381342 RecordCurrentHistograms();
initial.commit09911bf2008-07-26 23:55:291343
[email protected]29948262012-03-01 12:15:081344 log_manager_.FinishCurrentLog();
initial.commit09911bf2008-07-26 23:55:291345}
1346
[email protected]cac267c2011-09-29 15:18:101347void MetricsService::PushPendingLogsToPersistentStorage() {
[email protected]80a8f312013-12-16 18:00:301348 if (state_ < SENDING_INITIAL_STABILITY_LOG)
[email protected]28ab7f92009-01-06 21:39:041349 return; // We didn't and still don't have time to get plugin list etc.
initial.commit09911bf2008-07-26 23:55:291350
[email protected]cac267c2011-09-29 15:18:101351 if (log_manager_.has_staged_log()) {
[email protected]7d41ae6d2012-06-26 08:53:031352 // We may race here, and send second copy of the log later.
[email protected]dc61fe92012-06-12 00:13:501353 MetricsLogManager::StoreType store_type;
[email protected]e3eb0c42013-04-18 06:18:581354 if (current_fetch_.get())
[email protected]dc61fe92012-06-12 00:13:501355 store_type = MetricsLogManager::PROVISIONAL_STORE;
1356 else
1357 store_type = MetricsLogManager::NORMAL_STORE;
[email protected]e7508d82012-05-03 15:59:531358 log_manager_.StoreStagedLogAsUnsent(store_type);
initial.commit09911bf2008-07-26 23:55:291359 }
[email protected]cac267c2011-09-29 15:18:101360 DCHECK(!log_manager_.has_staged_log());
[email protected]410938e02012-10-24 16:33:591361 CloseCurrentLog();
[email protected]80a8f312013-12-16 18:00:301362 log_manager_.PersistUnsentLogs();
[email protected]7d41ae6d2012-06-26 08:53:031363
1364 // If there was a staged and/or current log, then there is now at least one
1365 // log waiting to be uploaded.
1366 if (log_manager_.has_unsent_logs())
1367 state_ = SENDING_OLD_LOGS;
initial.commit09911bf2008-07-26 23:55:291368}
1369
1370//------------------------------------------------------------------------------
1371// Transmission of logs methods
1372
[email protected]7f7f1962011-04-20 15:58:161373void MetricsService::StartSchedulerIfNecessary() {
[email protected]410938e02012-10-24 16:33:591374 // Never schedule cutting or uploading of logs in test mode.
1375 if (test_mode_active_)
1376 return;
1377
1378 // Even if reporting is disabled, the scheduler is needed to trigger the
1379 // creation of the initial log, which must be done in order for any logs to be
1380 // persisted on shutdown or backgrounding.
[email protected]80a8f312013-12-16 18:00:301381 if (recording_active() &&
1382 (reporting_active() || state_ < SENDING_INITIAL_STABILITY_LOG)) {
[email protected]7f7f1962011-04-20 15:58:161383 scheduler_->Start();
[email protected]80a8f312013-12-16 18:00:301384 }
initial.commit09911bf2008-07-26 23:55:291385}
1386
[email protected]7f7f1962011-04-20 15:58:161387void MetricsService::StartScheduledUpload() {
[email protected]cd1ac712012-06-26 08:26:471388 // If we're getting no notifications, then the log won't have much in it, and
1389 // it's possible the computer is about to go to sleep, so don't upload and
1390 // stop the scheduler.
[email protected]410938e02012-10-24 16:33:591391 // If recording has been turned off, the scheduler doesn't need to run.
1392 // If reporting is off, proceed if the initial log hasn't been created, since
1393 // that has to happen in order for logs to be cut and stored when persisting.
[email protected]cd1ac712012-06-26 08:26:471394 // TODO(stuartmorgan): Call Stop() on the schedule when reporting and/or
1395 // recording are turned off instead of letting it fire and then aborting.
1396 if (idle_since_last_transmission_ ||
[email protected]410938e02012-10-24 16:33:591397 !recording_active() ||
[email protected]80a8f312013-12-16 18:00:301398 (!reporting_active() && state_ >= SENDING_INITIAL_STABILITY_LOG)) {
[email protected]7f7f1962011-04-20 15:58:161399 scheduler_->Stop();
1400 scheduler_->UploadCancelled();
1401 return;
1402 }
1403
[email protected]c15faf372012-07-11 06:01:341404 // If the callback was to upload an old log, but there no longer is one,
1405 // just report success back to the scheduler to begin the ongoing log
1406 // callbacks.
1407 // TODO(stuartmorgan): Consider removing the distinction between
1408 // SENDING_OLD_LOGS and SENDING_CURRENT_LOGS to simplify the state machine
1409 // now that the log upload flow is the same for both modes.
1410 if (state_ == SENDING_OLD_LOGS && !log_manager_.has_unsent_logs()) {
1411 state_ = SENDING_CURRENT_LOGS;
1412 scheduler_->UploadFinished(true /* healthy */, false /* no unsent logs */);
1413 return;
1414 }
[email protected]cd1ac712012-06-26 08:26:471415 // If there are unsent logs, send the next one. If not, start the asynchronous
1416 // process of finalizing the current log for upload.
1417 if (state_ == SENDING_OLD_LOGS) {
1418 DCHECK(log_manager_.has_unsent_logs());
1419 log_manager_.StageNextLogForUpload();
1420 SendStagedLog();
1421 } else {
1422 StartFinalLogInfoCollection();
1423 }
[email protected]29948262012-03-01 12:15:081424}
1425
1426void MetricsService::StartFinalLogInfoCollection() {
1427 // Begin the multi-step process of collecting memory usage histograms:
1428 // First spawn a task to collect the memory details; when that task is
1429 // finished, it will call OnMemoryDetailCollectionDone. That will in turn
1430 // call HistogramSynchronization to collect histograms from all renderers and
1431 // then call OnHistogramSynchronizationDone to continue processing.
[email protected]d119f222012-06-08 02:33:271432 DCHECK(!waiting_for_asynchronous_reporting_step_);
1433 waiting_for_asynchronous_reporting_step_ = true;
[email protected]7f7f1962011-04-20 15:58:161434
[email protected]2226c222011-11-22 00:08:401435 base::Closure callback =
1436 base::Bind(&MetricsService::OnMemoryDetailCollectionDone,
[email protected]c94d7382012-02-28 08:43:401437 self_ptr_factory_.GetWeakPtr());
[email protected]84c988a2011-04-19 17:56:331438
[email protected]2226c222011-11-22 00:08:401439 scoped_refptr<MetricsMemoryDetails> details(
1440 new MetricsMemoryDetails(callback));
[email protected]4306df72012-04-20 18:58:571441 details->StartFetch(MemoryDetails::UPDATE_USER_METRICS);
[email protected]84c988a2011-04-19 17:56:331442
1443 // Collect WebCore cache information to put into a histogram.
[email protected]f3b1a082011-11-18 00:34:301444 for (content::RenderProcessHost::iterator i(
1445 content::RenderProcessHost::AllHostsIterator());
[email protected]84c988a2011-04-19 17:56:331446 !i.IsAtEnd(); i.Advance())
[email protected]2ccf45c2011-08-19 23:35:501447 i.GetCurrentValue()->Send(new ChromeViewMsg_GetCacheResourceStats());
[email protected]84c988a2011-04-19 17:56:331448}
1449
1450void MetricsService::OnMemoryDetailCollectionDone() {
[email protected]c9a3ef82009-05-28 22:02:461451 DCHECK(IsSingleThreaded());
[email protected]7f7f1962011-04-20 15:58:161452 // This function should only be called as the callback from an ansynchronous
1453 // step.
[email protected]d119f222012-06-08 02:33:271454 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]c9a3ef82009-05-28 22:02:461455
[email protected]c9a3ef82009-05-28 22:02:461456 // Create a callback_task for OnHistogramSynchronizationDone.
[email protected]2226c222011-11-22 00:08:401457 base::Closure callback = base::Bind(
1458 &MetricsService::OnHistogramSynchronizationDone,
[email protected]c94d7382012-02-28 08:43:401459 self_ptr_factory_.GetWeakPtr());
[email protected]c9a3ef82009-05-28 22:02:461460
[email protected]a3079832013-10-24 20:29:361461 base::TimeDelta timeout =
1462 base::TimeDelta::FromMilliseconds(kMaxHistogramGatheringWaitDuration);
1463
1464 DCHECK_EQ(num_async_histogram_fetches_in_progress_, 0);
1465
1466#if defined(OS_ANDROID)
1467 // Android has no service process.
1468 num_async_histogram_fetches_in_progress_ = 1;
1469#else // OS_ANDROID
1470 num_async_histogram_fetches_in_progress_ = 2;
1471 // Run requests to service and content in parallel.
1472 if (!ServiceProcessControl::GetInstance()->GetHistograms(callback, timeout)) {
1473 // Assume |num_async_histogram_fetches_in_progress_| is not changed by
1474 // |GetHistograms()|.
1475 DCHECK_EQ(num_async_histogram_fetches_in_progress_, 2);
1476 // Assign |num_async_histogram_fetches_in_progress_| above and decrement it
1477 // here to make code work even if |GetHistograms()| fired |callback|.
1478 --num_async_histogram_fetches_in_progress_;
1479 }
1480#endif // OS_ANDROID
1481
[email protected]c9a3ef82009-05-28 22:02:461482 // Set up the callback to task to call after we receive histograms from all
[email protected]83ab4a282012-07-12 18:19:451483 // child processes. Wait time specifies how long to wait before absolutely
[email protected]c9a3ef82009-05-28 22:02:461484 // calling us back on the task.
[email protected]a3079832013-10-24 20:29:361485 content::FetchHistogramsAsynchronously(base::MessageLoop::current(), callback,
1486 timeout);
[email protected]c9a3ef82009-05-28 22:02:461487}
1488
1489void MetricsService::OnHistogramSynchronizationDone() {
initial.commit09911bf2008-07-26 23:55:291490 DCHECK(IsSingleThreaded());
[email protected]29948262012-03-01 12:15:081491 // This function should only be called as the callback from an ansynchronous
1492 // step.
[email protected]d119f222012-06-08 02:33:271493 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]a3079832013-10-24 20:29:361494 DCHECK_GT(num_async_histogram_fetches_in_progress_, 0);
1495
1496 // Check if all expected requests finished.
1497 if (--num_async_histogram_fetches_in_progress_ > 0)
1498 return;
initial.commit09911bf2008-07-26 23:55:291499
[email protected]d119f222012-06-08 02:33:271500 waiting_for_asynchronous_reporting_step_ = false;
[email protected]29948262012-03-01 12:15:081501 OnFinalLogInfoCollectionDone();
1502}
1503
1504void MetricsService::OnFinalLogInfoCollectionDone() {
[email protected]7f7f1962011-04-20 15:58:161505 // If somehow there is a fetch in progress, we return and hope things work
1506 // out. The scheduler isn't informed since if this happens, the scheduler
1507 // will get a response from the upload.
[email protected]e3eb0c42013-04-18 06:18:581508 DCHECK(!current_fetch_.get());
1509 if (current_fetch_.get())
[email protected]7f7f1962011-04-20 15:58:161510 return;
1511
[email protected]cd1ac712012-06-26 08:26:471512 // Abort if metrics were turned off during the final info gathering.
[email protected]410938e02012-10-24 16:33:591513 if (!recording_active()) {
[email protected]7f7f1962011-04-20 15:58:161514 scheduler_->Stop();
1515 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071516 return;
1517 }
1518
[email protected]cd1ac712012-06-26 08:26:471519 StageNewLog();
[email protected]410938e02012-10-24 16:33:591520
1521 // If logs shouldn't be uploaded, stop here. It's important that this check
1522 // be after StageNewLog(), otherwise the previous logs will never be loaded,
1523 // and thus the open log won't be persisted.
1524 // TODO(stuartmorgan): This is unnecessarily complicated; restructure loading
1525 // of previous logs to not require running part of the upload logic.
1526 // http://crbug.com/157337
1527 if (!reporting_active()) {
1528 scheduler_->Stop();
1529 scheduler_->UploadCancelled();
1530 return;
1531 }
1532
[email protected]29948262012-03-01 12:15:081533 SendStagedLog();
1534}
1535
[email protected]cd1ac712012-06-26 08:26:471536void MetricsService::StageNewLog() {
[email protected]29948262012-03-01 12:15:081537 if (log_manager_.has_staged_log())
1538 return;
1539
1540 switch (state_) {
1541 case INITIALIZED:
1542 case INIT_TASK_SCHEDULED: // We should be further along by now.
[email protected]dc61fe92012-06-12 00:13:501543 NOTREACHED();
[email protected]29948262012-03-01 12:15:081544 return;
1545
1546 case INIT_TASK_DONE:
[email protected]80a8f312013-12-16 18:00:301547 if (has_initial_stability_log_) {
1548 // There's an initial stability log, ready to send.
1549 log_manager_.StageNextLogForUpload();
1550 has_initial_stability_log_ = false;
[email protected]f61eb842014-01-22 10:59:131551 // Note: No need to call LoadPersistedUnsentLogs() here because unsent
1552 // logs have already been loaded by PrepareInitialStabilityLog().
[email protected]80a8f312013-12-16 18:00:301553 state_ = SENDING_INITIAL_STABILITY_LOG;
1554 } else {
1555 // TODO(asvitkine): When the field trial is removed, the |log_type|
1556 // arg should be removed and PrepareInitialMetricsLog() should always
1557 // use ONGOING_LOG. Use INITIAL_LOG only to match to the old behavior
1558 // when the field trial is off.
1559 MetricsLog::LogType log_type = SendSeparateInitialStabilityLog() ?
1560 MetricsLog::ONGOING_LOG : MetricsLog::INITIAL_LOG;
1561 PrepareInitialMetricsLog(log_type);
[email protected]f61eb842014-01-22 10:59:131562 // Load unsent logs (if any) from local state.
1563 log_manager_.LoadPersistedUnsentLogs();
[email protected]80a8f312013-12-16 18:00:301564 state_ = SENDING_INITIAL_METRICS_LOG;
1565 }
[email protected]29948262012-03-01 12:15:081566 break;
1567
1568 case SENDING_OLD_LOGS:
[email protected]cd1ac712012-06-26 08:26:471569 NOTREACHED(); // Shouldn't be staging a new log during old log sending.
1570 return;
[email protected]29948262012-03-01 12:15:081571
1572 case SENDING_CURRENT_LOGS:
[email protected]410938e02012-10-24 16:33:591573 CloseCurrentLog();
1574 OpenNewLog();
[email protected]29948262012-03-01 12:15:081575 log_manager_.StageNextLogForUpload();
1576 break;
1577
1578 default:
1579 NOTREACHED();
1580 return;
1581 }
1582
1583 DCHECK(log_manager_.has_staged_log());
1584}
1585
[email protected]80a8f312013-12-16 18:00:301586void MetricsService::PrepareInitialStabilityLog() {
1587 DCHECK_EQ(INITIALIZED, state_);
1588 PrefService* pref = g_browser_process->local_state();
1589 DCHECK_NE(0, pref->GetInteger(prefs::kStabilityCrashCount));
[email protected]29948262012-03-01 12:15:081590
[email protected]80a8f312013-12-16 18:00:301591 scoped_ptr<MetricsLog> initial_stability_log(
1592 new MetricsLog(client_id_, session_id_));
1593 if (!initial_stability_log->LoadSavedEnvironmentFromPrefs())
1594 return;
[email protected]076961c2014-03-12 22:23:561595 initial_stability_log->RecordStabilityMetrics(
1596 base::TimeDelta(), base::TimeDelta(), MetricsLog::INITIAL_LOG);
[email protected]80a8f312013-12-16 18:00:301597 log_manager_.LoadPersistedUnsentLogs();
1598
1599 log_manager_.PauseCurrentLog();
1600 log_manager_.BeginLoggingWithLog(initial_stability_log.release(),
1601 MetricsLog::INITIAL_LOG);
[email protected]c778687a2014-02-11 14:46:451602#if defined(OS_ANDROID)
1603 ConvertAndroidStabilityPrefsToHistograms(pref);
1604 RecordCurrentStabilityHistograms();
1605#endif // defined(OS_ANDROID)
[email protected]80a8f312013-12-16 18:00:301606 log_manager_.FinishCurrentLog();
1607 log_manager_.ResumePausedLog();
1608
1609 // Store unsent logs, including the stability log that was just saved, so
1610 // that they're not lost in case of a crash before upload time.
1611 log_manager_.PersistUnsentLogs();
1612
1613 has_initial_stability_log_ = true;
1614}
1615
1616void MetricsService::PrepareInitialMetricsLog(MetricsLog::LogType log_type) {
1617 DCHECK(state_ == INIT_TASK_DONE || state_ == SENDING_INITIAL_STABILITY_LOG);
1618 initial_metrics_log_->set_hardware_class(hardware_class_);
[email protected]0edf8762013-11-21 18:33:301619
[email protected]60677562013-11-17 15:52:551620 std::vector<chrome_variations::ActiveGroupId> synthetic_trials;
1621 GetCurrentSyntheticFieldTrials(&synthetic_trials);
[email protected]80a8f312013-12-16 18:00:301622 initial_metrics_log_->RecordEnvironment(plugins_, google_update_metrics_,
1623 synthetic_trials);
[email protected]0edf8762013-11-21 18:33:301624 PrefService* pref = g_browser_process->local_state();
[email protected]076961c2014-03-12 22:23:561625 base::TimeDelta incremental_uptime;
1626 base::TimeDelta uptime;
1627 GetUptimes(pref, &incremental_uptime, &uptime);
1628 initial_metrics_log_->RecordStabilityMetrics(incremental_uptime, uptime,
[email protected]80a8f312013-12-16 18:00:301629 log_type);
[email protected]29948262012-03-01 12:15:081630
1631 // Histograms only get written to the current log, so make the new log current
1632 // before writing them.
1633 log_manager_.PauseCurrentLog();
[email protected]80a8f312013-12-16 18:00:301634 log_manager_.BeginLoggingWithLog(initial_metrics_log_.release(), log_type);
[email protected]c778687a2014-02-11 14:46:451635#if defined(OS_ANDROID)
1636 ConvertAndroidStabilityPrefsToHistograms(pref);
1637#endif // defined(OS_ANDROID)
[email protected]29948262012-03-01 12:15:081638 RecordCurrentHistograms();
1639 log_manager_.FinishCurrentLog();
1640 log_manager_.ResumePausedLog();
1641
1642 DCHECK(!log_manager_.has_staged_log());
1643 log_manager_.StageNextLogForUpload();
1644}
1645
[email protected]29948262012-03-01 12:15:081646void MetricsService::SendStagedLog() {
1647 DCHECK(log_manager_.has_staged_log());
1648
[email protected]cac267c2011-09-29 15:18:101649 PrepareFetchWithStagedLog();
[email protected]d01b8732008-10-16 02:18:071650
[email protected]e3eb0c42013-04-18 06:18:581651 bool upload_created = (current_fetch_.get() != NULL);
[email protected]d6bebb92012-06-13 23:14:551652 UMA_HISTOGRAM_BOOLEAN("UMA.UploadCreation", upload_created);
1653 if (!upload_created) {
[email protected]d01b8732008-10-16 02:18:071654 // Compression failed, and log discarded :-/.
[email protected]dc61fe92012-06-12 00:13:501655 // Skip this upload and hope things work out next time.
[email protected]cac267c2011-09-29 15:18:101656 log_manager_.DiscardStagedLog();
[email protected]7f7f1962011-04-20 15:58:161657 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071658 return;
1659 }
1660
[email protected]d119f222012-06-08 02:33:271661 DCHECK(!waiting_for_asynchronous_reporting_step_);
[email protected]d119f222012-06-08 02:33:271662 waiting_for_asynchronous_reporting_step_ = true;
[email protected]dc61fe92012-06-12 00:13:501663
[email protected]e3eb0c42013-04-18 06:18:581664 current_fetch_->Start();
[email protected]d01b8732008-10-16 02:18:071665
1666 HandleIdleSinceLastTransmission(true);
1667}
1668
[email protected]cac267c2011-09-29 15:18:101669void MetricsService::PrepareFetchWithStagedLog() {
[email protected]dc61fe92012-06-12 00:13:501670 DCHECK(log_manager_.has_staged_log());
[email protected]cac78842008-11-27 01:02:201671
[email protected]fe58acc22012-02-29 01:29:581672 // Prepare the protobuf version.
[email protected]e3eb0c42013-04-18 06:18:581673 DCHECK(!current_fetch_.get());
[email protected]5f3e1642013-05-05 03:37:341674 if (log_manager_.has_staged_log()) {
[email protected]e3eb0c42013-04-18 06:18:581675 current_fetch_.reset(net::URLFetcher::Create(
[email protected]5f3e1642013-05-05 03:37:341676 GURL(kServerUrl), net::URLFetcher::POST, this));
[email protected]e3eb0c42013-04-18 06:18:581677 current_fetch_->SetRequestContext(
[email protected]fe58acc22012-02-29 01:29:581678 g_browser_process->system_request_context());
[email protected]537c638d2013-07-04 00:49:191679
[email protected]8df71322013-09-13 18:40:001680 std::string log_text = log_manager_.staged_log_text();
1681 std::string compressed_log_text;
1682 bool compression_successful = chrome::GzipCompress(log_text,
1683 &compressed_log_text);
1684 DCHECK(compression_successful);
1685 if (compression_successful) {
1686 current_fetch_->SetUploadData(kMimeType, compressed_log_text);
1687 // Tell the server that we're uploading gzipped protobufs.
1688 current_fetch_->SetExtraRequestHeaders("content-encoding: gzip");
[email protected]cfee9aa52013-10-19 17:53:051689 const std::string hash =
1690 base::HexEncode(log_manager_.staged_log_hash().data(),
1691 log_manager_.staged_log_hash().size());
1692 DCHECK(!hash.empty());
1693 current_fetch_->AddExtraRequestHeader("X-Chrome-UMA-Log-SHA1: " + hash);
[email protected]8df71322013-09-13 18:40:001694 UMA_HISTOGRAM_PERCENTAGE(
1695 "UMA.ProtoCompressionRatio",
1696 100 * compressed_log_text.size() / log_text.size());
1697 UMA_HISTOGRAM_CUSTOM_COUNTS(
1698 "UMA.ProtoGzippedKBSaved",
1699 (log_text.size() - compressed_log_text.size()) / 1024,
1700 1, 2000, 50);
[email protected]537c638d2013-07-04 00:49:191701 }
[email protected]537c638d2013-07-04 00:49:191702
[email protected]fe58acc22012-02-29 01:29:581703 // We already drop cookies server-side, but we might as well strip them out
1704 // client-side as well.
[email protected]e3eb0c42013-04-18 06:18:581705 current_fetch_->SetLoadFlags(net::LOAD_DO_NOT_SAVE_COOKIES |
1706 net::LOAD_DO_NOT_SEND_COOKIES);
[email protected]fe58acc22012-02-29 01:29:581707 }
initial.commit09911bf2008-07-26 23:55:291708}
1709
[email protected]10c2d692012-05-11 05:32:231710void MetricsService::OnURLFetchComplete(const net::URLFetcher* source) {
[email protected]d119f222012-06-08 02:33:271711 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]fe58acc22012-02-29 01:29:581712
1713 // We're not allowed to re-use the existing |URLFetcher|s, so free them here.
[email protected]e3eb0c42013-04-18 06:18:581714 // Note however that |source| is aliased to the fetcher, so we should be
[email protected]4266def22012-05-17 01:02:401715 // careful not to delete it too early.
[email protected]e3eb0c42013-04-18 06:18:581716 DCHECK_EQ(current_fetch_.get(), source);
1717 scoped_ptr<net::URLFetcher> s(current_fetch_.Pass());
[email protected]fe58acc22012-02-29 01:29:581718
[email protected]dc61fe92012-06-12 00:13:501719 int response_code = source->GetResponseCode();
[email protected]fe58acc22012-02-29 01:29:581720
[email protected]dc61fe92012-06-12 00:13:501721 // Log a histogram to track response success vs. failure rates.
[email protected]e3eb0c42013-04-18 06:18:581722 UMA_HISTOGRAM_ENUMERATION("UMA.UploadResponseStatus.Protobuf",
1723 ResponseCodeToStatus(response_code),
1724 NUM_RESPONSE_STATUSES);
[email protected]fe58acc22012-02-29 01:29:581725
[email protected]e7508d82012-05-03 15:59:531726 // If the upload was provisionally stored, drop it now that the upload is
1727 // known to have gone through.
1728 log_manager_.DiscardLastProvisionalStore();
initial.commit09911bf2008-07-26 23:55:291729
[email protected]dc61fe92012-06-12 00:13:501730 bool upload_succeeded = response_code == 200;
[email protected]7f7f1962011-04-20 15:58:161731
[email protected]0eb34fee2009-01-21 08:04:381732 // Provide boolean for error recovery (allow us to ignore response_code).
[email protected]dc6f4962009-02-13 01:25:501733 bool discard_log = false;
[email protected]5f3e1642013-05-05 03:37:341734 const size_t log_size = log_manager_.staged_log_text().length();
[email protected]dc61fe92012-06-12 00:13:501735 if (!upload_succeeded && log_size > kUploadLogAvoidRetransmitSize) {
1736 UMA_HISTOGRAM_COUNTS("UMA.Large Rejected Log was Discarded",
1737 static_cast<int>(log_size));
[email protected]0eb34fee2009-01-21 08:04:381738 discard_log = true;
[email protected]dc61fe92012-06-12 00:13:501739 } else if (response_code == 400) {
[email protected]0eb34fee2009-01-21 08:04:381740 // Bad syntax. Retransmission won't work.
[email protected]0eb34fee2009-01-21 08:04:381741 discard_log = true;
[email protected]68475e602008-08-22 03:21:151742 }
1743
[email protected]e3eb0c42013-04-18 06:18:581744 if (upload_succeeded || discard_log)
[email protected]5f3e1642013-05-05 03:37:341745 log_manager_.DiscardStagedLog();
[email protected]dc61fe92012-06-12 00:13:501746
1747 waiting_for_asynchronous_reporting_step_ = false;
1748
1749 if (!log_manager_.has_staged_log()) {
initial.commit09911bf2008-07-26 23:55:291750 switch (state_) {
[email protected]80a8f312013-12-16 18:00:301751 case SENDING_INITIAL_STABILITY_LOG:
1752 // Store the updated list to disk now that the removed log is uploaded.
1753 log_manager_.PersistUnsentLogs();
1754 PrepareInitialMetricsLog(MetricsLog::ONGOING_LOG);
1755 SendStagedLog();
1756 state_ = SENDING_INITIAL_METRICS_LOG;
1757 break;
1758
1759 case SENDING_INITIAL_METRICS_LOG:
1760 // The initial metrics log never gets persisted to local state, so it's
1761 // not necessary to call log_manager_.PersistUnsentLogs() here.
1762 // TODO(asvitkine): It should be persisted like the initial stability
1763 // log and old unsent logs. http://crbug.com/328417
[email protected]cd1ac712012-06-26 08:26:471764 state_ = log_manager_.has_unsent_logs() ? SENDING_OLD_LOGS
1765 : SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291766 break;
1767
initial.commit09911bf2008-07-26 23:55:291768 case SENDING_OLD_LOGS:
[email protected]d53e2232011-06-30 15:54:571769 // Store the updated list to disk now that the removed log is uploaded.
[email protected]80a8f312013-12-16 18:00:301770 log_manager_.PersistUnsentLogs();
[email protected]cd1ac712012-06-26 08:26:471771 if (!log_manager_.has_unsent_logs())
1772 state_ = SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291773 break;
1774
1775 case SENDING_CURRENT_LOGS:
1776 break;
1777
1778 default:
[email protected]a063c102010-07-22 22:20:191779 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291780 break;
1781 }
[email protected]d01b8732008-10-16 02:18:071782
[email protected]cac267c2011-09-29 15:18:101783 if (log_manager_.has_unsent_logs())
[email protected]ed0fd002012-04-25 23:10:341784 DCHECK_LT(state_, SENDING_CURRENT_LOGS);
initial.commit09911bf2008-07-26 23:55:291785 }
[email protected]252873ef2008-08-04 21:59:451786
[email protected]7f7f1962011-04-20 15:58:161787 // Error 400 indicates a problem with the log, not with the server, so
1788 // don't consider that a sign that the server is in trouble.
[email protected]dc61fe92012-06-12 00:13:501789 bool server_is_healthy = upload_succeeded || response_code == 400;
[email protected]80a8f312013-12-16 18:00:301790 // Don't notify the scheduler that the upload is finished if we've only sent
1791 // the initial stability log, but not yet the initial metrics log (treat the
1792 // two as a single unit of work as far as the scheduler is concerned).
1793 if (state_ != SENDING_INITIAL_METRICS_LOG) {
1794 scheduler_->UploadFinished(server_is_healthy,
1795 log_manager_.has_unsent_logs());
1796 }
[email protected]d67d1052011-06-09 05:11:411797
1798 // Collect network stats if UMA upload succeeded.
[email protected]b8ddb052012-04-19 02:36:061799 IOThread* io_thread = g_browser_process->io_thread();
1800 if (server_is_healthy && io_thread) {
1801 chrome_browser_net::CollectNetworkStats(network_stats_server_, io_thread);
[email protected]adbb3762012-03-09 22:20:081802 chrome_browser_net::CollectPipeliningCapabilityStatsOnUIThread(
[email protected]b8ddb052012-04-19 02:36:061803 http_pipelining_test_server_, io_thread);
[email protected]aa312812013-04-30 19:46:051804#if defined(OS_WIN)
1805 chrome::CollectTimeTicksStats();
1806#endif
[email protected]adbb3762012-03-09 22:20:081807 }
initial.commit09911bf2008-07-26 23:55:291808}
1809
[email protected]57ecc4b2010-08-11 03:02:511810void MetricsService::IncrementPrefValue(const char* path) {
[email protected]e73c01972008-08-13 00:18:241811 PrefService* pref = g_browser_process->local_state();
1812 DCHECK(pref);
1813 int value = pref->GetInteger(path);
1814 pref->SetInteger(path, value + 1);
1815}
1816
[email protected]57ecc4b2010-08-11 03:02:511817void MetricsService::IncrementLongPrefsValue(const char* path) {
[email protected]0bb1a622009-03-04 03:22:321818 PrefService* pref = g_browser_process->local_state();
1819 DCHECK(pref);
1820 int64 value = pref->GetInt64(path);
[email protected]b42c5e42010-06-03 20:43:251821 pref->SetInt64(path, value + 1);
[email protected]0bb1a622009-03-04 03:22:321822}
1823
[email protected]752a5262013-06-23 14:53:421824void MetricsService::LogLoadStarted(content::WebContents* web_contents) {
[email protected]e6e30ac2014-01-13 21:24:391825 content::RecordAction(base::UserMetricsAction("PageLoad"));
[email protected]dd8d12a2011-09-02 02:10:151826 HISTOGRAM_ENUMERATION("Chrome.UmaPageloadCounter", 1, 2);
[email protected]e73c01972008-08-13 00:18:241827 IncrementPrefValue(prefs::kStabilityPageLoadCount);
[email protected]0bb1a622009-03-04 03:22:321828 IncrementLongPrefsValue(prefs::kUninstallMetricsPageLoadCount);
[email protected]0b33f80b2008-12-17 21:34:361829 // We need to save the prefs, as page load count is a critical stat, and it
1830 // might be lost due to a crash :-(.
initial.commit09911bf2008-07-26 23:55:291831}
1832
[email protected]c3721482012-03-23 16:21:481833void MetricsService::LogRendererCrash(content::RenderProcessHost* host,
1834 base::TerminationStatus status,
[email protected]f1675202012-07-09 15:18:001835 int exit_code) {
[email protected]6f371442011-11-09 06:45:461836 bool was_extension_process =
[email protected]fafdc842014-01-17 18:09:081837 extensions::ProcessMap::Get(host->GetBrowserContext())
1838 ->Contains(host->GetID());
[email protected]c3721482012-03-23 16:21:481839 if (status == base::TERMINATION_STATUS_PROCESS_CRASHED ||
1840 status == base::TERMINATION_STATUS_ABNORMAL_TERMINATION) {
[email protected]d7c1fa62012-06-15 23:35:301841 if (was_extension_process) {
[email protected]718eab62011-10-05 21:16:521842 IncrementPrefValue(prefs::kStabilityExtensionRendererCrashCount);
[email protected]d7c1fa62012-06-15 23:35:301843
[email protected]1026afd2013-03-20 14:28:541844 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Extension",
1845 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301846 } else {
[email protected]718eab62011-10-05 21:16:521847 IncrementPrefValue(prefs::kStabilityRendererCrashCount);
initial.commit09911bf2008-07-26 23:55:291848
[email protected]1026afd2013-03-20 14:28:541849 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Renderer",
1850 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301851 }
1852
[email protected]718eab62011-10-05 21:16:521853 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildCrashes",
1854 was_extension_process ? 2 : 1);
[email protected]c3721482012-03-23 16:21:481855 } else if (status == base::TERMINATION_STATUS_PROCESS_WAS_KILLED) {
[email protected]718eab62011-10-05 21:16:521856 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildKills",
1857 was_extension_process ? 2 : 1);
[email protected]f1675202012-07-09 15:18:001858 } else if (status == base::TERMINATION_STATUS_STILL_RUNNING) {
1859 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.DisconnectedAlive",
[email protected]718eab62011-10-05 21:16:521860 was_extension_process ? 2 : 1);
[email protected]718eab62011-10-05 21:16:521861 }
[email protected]80a8f312013-12-16 18:00:301862}
[email protected]1f085622009-12-04 05:33:451863
initial.commit09911bf2008-07-26 23:55:291864void MetricsService::LogRendererHang() {
[email protected]e73c01972008-08-13 00:18:241865 IncrementPrefValue(prefs::kStabilityRendererHangCount);
initial.commit09911bf2008-07-26 23:55:291866}
1867
[email protected]c0c55e92011-09-10 18:47:301868bool MetricsService::UmaMetricsProperlyShutdown() {
1869 CHECK(clean_shutdown_status_ == CLEANLY_SHUTDOWN ||
1870 clean_shutdown_status_ == NEED_TO_SHUTDOWN);
1871 return clean_shutdown_status_ == CLEANLY_SHUTDOWN;
1872}
1873
[email protected]60677562013-11-17 15:52:551874void MetricsService::RegisterSyntheticFieldTrial(
1875 const SyntheticTrialGroup& trial) {
1876 for (size_t i = 0; i < synthetic_trial_groups_.size(); ++i) {
1877 if (synthetic_trial_groups_[i].id.name == trial.id.name) {
1878 if (synthetic_trial_groups_[i].id.group != trial.id.group) {
1879 synthetic_trial_groups_[i].id.group = trial.id.group;
[email protected]7a5c07812014-02-26 11:45:411880 synthetic_trial_groups_[i].start_time = base::TimeTicks::Now();
[email protected]60677562013-11-17 15:52:551881 }
1882 return;
1883 }
1884 }
1885
[email protected]7a5c07812014-02-26 11:45:411886 SyntheticTrialGroup trial_group = trial;
1887 trial_group.start_time = base::TimeTicks::Now();
[email protected]60677562013-11-17 15:52:551888 synthetic_trial_groups_.push_back(trial_group);
1889}
1890
[email protected]99c892d2014-03-24 18:11:211891void MetricsService::CheckForClonedInstall() {
1892 DCHECK(!cloned_install_detector_);
1893
1894 metrics::MachineIdProvider* provider =
1895 metrics::MachineIdProvider::CreateInstance();
1896 if (!provider)
1897 return;
1898
1899 cloned_install_detector_.reset(
1900 new metrics::ClonedInstallDetector(provider));
1901
1902 PrefService* local_state = g_browser_process->local_state();
1903 cloned_install_detector_->CheckForClonedInstall(local_state);
1904}
1905
[email protected]60677562013-11-17 15:52:551906void MetricsService::GetCurrentSyntheticFieldTrials(
1907 std::vector<chrome_variations::ActiveGroupId>* synthetic_trials) {
1908 DCHECK(synthetic_trials);
1909 synthetic_trials->clear();
1910 const MetricsLog* current_log =
1911 static_cast<const MetricsLog*>(log_manager_.current_log());
1912 for (size_t i = 0; i < synthetic_trial_groups_.size(); ++i) {
1913 if (synthetic_trial_groups_[i].start_time <= current_log->creation_time())
1914 synthetic_trials->push_back(synthetic_trial_groups_[i].id);
1915 }
1916}
1917
[email protected]466f3c12011-03-23 21:20:381918void MetricsService::LogCleanShutdown() {
[email protected]acd55b32011-09-05 17:35:311919 // Redundant hack to write pref ASAP.
[email protected]84c384e2013-03-01 23:20:191920 MarkAppCleanShutdownAndCommit();
1921
[email protected]c0c55e92011-09-10 18:47:301922 // Redundant setting to assure that we always reset this value at shutdown
1923 // (and that we don't use some alternate path, and not call LogCleanShutdown).
1924 clean_shutdown_status_ = CLEANLY_SHUTDOWN;
[email protected]acd55b32011-09-05 17:35:311925
[email protected]466f3c12011-03-23 21:20:381926 RecordBooleanPrefValue(prefs::kStabilityExitedCleanly, true);
[email protected]6a6d0d12013-10-28 15:58:191927 PrefService* pref = g_browser_process->local_state();
1928 pref->SetInteger(prefs::kStabilityExecutionPhase,
[email protected]6d67ea0d2013-11-14 11:02:211929 MetricsService::SHUTDOWN_COMPLETE);
[email protected]466f3c12011-03-23 21:20:381930}
1931
[email protected]c1834a92011-01-21 18:21:031932#if defined(OS_CHROMEOS)
1933void MetricsService::LogChromeOSCrash(const std::string &crash_type) {
1934 if (crash_type == "user")
1935 IncrementPrefValue(prefs::kStabilityOtherUserCrashCount);
1936 else if (crash_type == "kernel")
1937 IncrementPrefValue(prefs::kStabilityKernelCrashCount);
1938 else if (crash_type == "uncleanshutdown")
1939 IncrementPrefValue(prefs::kStabilitySystemUncleanShutdownCount);
1940 else
1941 NOTREACHED() << "Unexpected Chrome OS crash type " << crash_type;
1942 // Wake up metrics logs sending if necessary now that new
1943 // log data is available.
1944 HandleIdleSinceLastTransmission(false);
1945}
1946#endif // OS_CHROMEOS
1947
[email protected]650b2d52013-02-10 03:41:451948void MetricsService::LogPluginLoadingError(const base::FilePath& plugin_path) {
[email protected]d7bd3e52013-07-21 04:29:201949 content::WebPluginInfo plugin;
[email protected]cd937072012-07-02 09:00:291950 bool success =
1951 content::PluginService::GetInstance()->GetPluginInfoByPath(plugin_path,
1952 &plugin);
1953 DCHECK(success);
1954 ChildProcessStats& stats = child_process_stats_buffer_[plugin.name];
1955 // Initialize the type if this entry is new.
1956 if (stats.process_type == content::PROCESS_TYPE_UNKNOWN) {
1957 // The plug-in process might not actually of type PLUGIN (which means
1958 // NPAPI), but we only care that it is *a* plug-in process.
1959 stats.process_type = content::PROCESS_TYPE_PLUGIN;
1960 } else {
1961 DCHECK(IsPluginProcess(stats.process_type));
1962 }
1963 stats.loading_errors++;
1964}
1965
[email protected]f4eaf7b92013-02-28 22:00:401966MetricsService::ChildProcessStats& MetricsService::GetChildProcessStats(
1967 const content::ChildProcessData& data) {
[email protected]439f1e32013-12-09 20:09:091968 const base::string16& child_name = data.name;
[email protected]f3b357692013-03-22 05:16:131969 if (!ContainsKey(child_process_stats_buffer_, child_name)) {
1970 child_process_stats_buffer_[child_name] =
1971 ChildProcessStats(data.process_type);
1972 }
[email protected]f4eaf7b92013-02-28 22:00:401973 return child_process_stats_buffer_[child_name];
initial.commit09911bf2008-07-26 23:55:291974}
1975
initial.commit09911bf2008-07-26 23:55:291976void MetricsService::RecordPluginChanges(PrefService* pref) {
[email protected]f8628c22011-04-05 12:10:181977 ListPrefUpdate update(pref, prefs::kStabilityPluginStats);
[email protected]cb1078de2013-12-23 20:04:221978 base::ListValue* plugins = update.Get();
initial.commit09911bf2008-07-26 23:55:291979 DCHECK(plugins);
1980
[email protected]cb1078de2013-12-23 20:04:221981 for (base::ListValue::iterator value_iter = plugins->begin();
initial.commit09911bf2008-07-26 23:55:291982 value_iter != plugins->end(); ++value_iter) {
[email protected]cb1078de2013-12-23 20:04:221983 if (!(*value_iter)->IsType(base::Value::TYPE_DICTIONARY)) {
[email protected]a063c102010-07-22 22:20:191984 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291985 continue;
1986 }
1987
[email protected]cb1078de2013-12-23 20:04:221988 base::DictionaryValue* plugin_dict =
1989 static_cast<base::DictionaryValue*>(*value_iter);
[email protected]57ecc4b2010-08-11 03:02:511990 std::string plugin_name;
[email protected]8e50b602009-03-03 22:59:431991 plugin_dict->GetString(prefs::kStabilityPluginName, &plugin_name);
[email protected]6470ee8f2009-03-03 20:46:401992 if (plugin_name.empty()) {
[email protected]a063c102010-07-22 22:20:191993 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291994 continue;
1995 }
1996
[email protected]57ecc4b2010-08-11 03:02:511997 // TODO(viettrungluu): remove conversions
[email protected]6778fed2013-12-24 20:09:371998 base::string16 name16 = base::UTF8ToUTF16(plugin_name);
[email protected]68b9e72b2011-08-05 23:08:221999 if (child_process_stats_buffer_.find(name16) ==
2000 child_process_stats_buffer_.end()) {
initial.commit09911bf2008-07-26 23:55:292001 continue;
[email protected]68b9e72b2011-08-05 23:08:222002 }
initial.commit09911bf2008-07-26 23:55:292003
[email protected]68b9e72b2011-08-05 23:08:222004 ChildProcessStats stats = child_process_stats_buffer_[name16];
initial.commit09911bf2008-07-26 23:55:292005 if (stats.process_launches) {
2006 int launches = 0;
[email protected]8e50b602009-03-03 22:59:432007 plugin_dict->GetInteger(prefs::kStabilityPluginLaunches, &launches);
initial.commit09911bf2008-07-26 23:55:292008 launches += stats.process_launches;
[email protected]8e50b602009-03-03 22:59:432009 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches, launches);
initial.commit09911bf2008-07-26 23:55:292010 }
2011 if (stats.process_crashes) {
2012 int crashes = 0;
[email protected]8e50b602009-03-03 22:59:432013 plugin_dict->GetInteger(prefs::kStabilityPluginCrashes, &crashes);
initial.commit09911bf2008-07-26 23:55:292014 crashes += stats.process_crashes;
[email protected]8e50b602009-03-03 22:59:432015 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes, crashes);
initial.commit09911bf2008-07-26 23:55:292016 }
2017 if (stats.instances) {
2018 int instances = 0;
[email protected]8e50b602009-03-03 22:59:432019 plugin_dict->GetInteger(prefs::kStabilityPluginInstances, &instances);
initial.commit09911bf2008-07-26 23:55:292020 instances += stats.instances;
[email protected]8e50b602009-03-03 22:59:432021 plugin_dict->SetInteger(prefs::kStabilityPluginInstances, instances);
initial.commit09911bf2008-07-26 23:55:292022 }
[email protected]cd937072012-07-02 09:00:292023 if (stats.loading_errors) {
2024 int loading_errors = 0;
2025 plugin_dict->GetInteger(prefs::kStabilityPluginLoadingErrors,
2026 &loading_errors);
2027 loading_errors += stats.loading_errors;
2028 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
2029 loading_errors);
2030 }
initial.commit09911bf2008-07-26 23:55:292031
[email protected]68b9e72b2011-08-05 23:08:222032 child_process_stats_buffer_.erase(name16);
initial.commit09911bf2008-07-26 23:55:292033 }
2034
2035 // Now go through and add dictionaries for plugins that didn't already have
2036 // reports in Local State.
[email protected]d2065e062013-12-12 23:49:522037 for (std::map<base::string16, ChildProcessStats>::iterator cache_iter =
[email protected]a27a9382009-02-11 23:55:102038 child_process_stats_buffer_.begin();
2039 cache_iter != child_process_stats_buffer_.end(); ++cache_iter) {
[email protected]a27a9382009-02-11 23:55:102040 ChildProcessStats stats = cache_iter->second;
[email protected]0d84c5d2009-10-09 01:10:422041
2042 // Insert only plugins information into the plugins list.
[email protected]8d5f1dae2011-11-11 14:30:412043 if (!IsPluginProcess(stats.process_type))
[email protected]0d84c5d2009-10-09 01:10:422044 continue;
2045
[email protected]57ecc4b2010-08-11 03:02:512046 // TODO(viettrungluu): remove conversion
[email protected]6778fed2013-12-24 20:09:372047 std::string plugin_name = base::UTF16ToUTF8(cache_iter->first);
[email protected]0d84c5d2009-10-09 01:10:422048
[email protected]cb1078de2013-12-23 20:04:222049 base::DictionaryValue* plugin_dict = new base::DictionaryValue;
initial.commit09911bf2008-07-26 23:55:292050
[email protected]8e50b602009-03-03 22:59:432051 plugin_dict->SetString(prefs::kStabilityPluginName, plugin_name);
2052 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches,
initial.commit09911bf2008-07-26 23:55:292053 stats.process_launches);
[email protected]8e50b602009-03-03 22:59:432054 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes,
initial.commit09911bf2008-07-26 23:55:292055 stats.process_crashes);
[email protected]8e50b602009-03-03 22:59:432056 plugin_dict->SetInteger(prefs::kStabilityPluginInstances,
initial.commit09911bf2008-07-26 23:55:292057 stats.instances);
[email protected]cd937072012-07-02 09:00:292058 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
2059 stats.loading_errors);
initial.commit09911bf2008-07-26 23:55:292060 plugins->Append(plugin_dict);
2061 }
[email protected]a27a9382009-02-11 23:55:102062 child_process_stats_buffer_.clear();
initial.commit09911bf2008-07-26 23:55:292063}
2064
[email protected]e5ad60a2014-03-11 03:54:042065bool MetricsService::ShouldLogEvents() {
2066 // We simply don't log events to UMA if there is a single incognito
initial.commit09911bf2008-07-26 23:55:292067 // session visible. The problem is that we always notify using the orginal
2068 // profile in order to simplify notification processing.
[email protected]e764e582012-08-01 03:01:292069 return !chrome::IsOffTheRecordSessionActive();
initial.commit09911bf2008-07-26 23:55:292070}
2071
[email protected]57ecc4b2010-08-11 03:02:512072void MetricsService::RecordBooleanPrefValue(const char* path, bool value) {
initial.commit09911bf2008-07-26 23:55:292073 DCHECK(IsSingleThreaded());
2074
2075 PrefService* pref = g_browser_process->local_state();
2076 DCHECK(pref);
2077
2078 pref->SetBoolean(path, value);
2079 RecordCurrentState(pref);
2080}
2081
2082void MetricsService::RecordCurrentState(PrefService* pref) {
[email protected]0bb1a622009-03-04 03:22:322083 pref->SetInt64(prefs::kStabilityLastTimestampSec, Time::Now().ToTimeT());
initial.commit09911bf2008-07-26 23:55:292084
2085 RecordPluginChanges(pref);
2086}
2087
[email protected]8d5f1dae2011-11-11 14:30:412088// static
[email protected]f3b357692013-03-22 05:16:132089bool MetricsService::IsPluginProcess(int process_type) {
2090 return (process_type == content::PROCESS_TYPE_PLUGIN ||
2091 process_type == content::PROCESS_TYPE_PPAPI_PLUGIN ||
2092 process_type == content::PROCESS_TYPE_PPAPI_BROKER);
[email protected]8d5f1dae2011-11-11 14:30:412093}
2094
[email protected]5ccaa412009-11-13 22:00:162095#if defined(OS_CHROMEOS)
[email protected]29cf16772010-04-21 15:13:472096void MetricsService::StartExternalMetrics() {
[email protected]5ccaa412009-11-13 22:00:162097 external_metrics_ = new chromeos::ExternalMetrics;
[email protected]29cf16772010-04-21 15:13:472098 external_metrics_->Start();
[email protected]5ccaa412009-11-13 22:00:162099}
2100#endif
[email protected]3819f2ee2011-08-21 09:44:382101
2102// static
2103bool MetricsServiceHelper::IsMetricsReportingEnabled() {
2104 bool result = false;
2105 const PrefService* local_state = g_browser_process->local_state();
2106 if (local_state) {
2107 const PrefService::Preference* uma_pref =
2108 local_state->FindPreference(prefs::kMetricsReportingEnabled);
2109 if (uma_pref) {
2110 bool success = uma_pref->GetValue()->GetAsBoolean(&result);
2111 DCHECK(success);
2112 }
2113 }
2114 return result;
2115}
[email protected]1ef13cf2014-03-21 22:44:012116
2117bool MetricsServiceHelper::IsCrashReportingEnabled() {
2118#if defined(GOOGLE_CHROME_BUILD)
2119#if defined(OS_CHROMEOS)
2120 bool reporting_enabled = false;
2121 chromeos::CrosSettings::Get()->GetBoolean(chromeos::kStatsReportingPref,
2122 &reporting_enabled);
2123 return reporting_enabled;
2124#elif defined(OS_ANDROID)
2125 // Android has its own settings for metrics / crash uploading.
2126 const PrefService* prefs = g_browser_process->local_state();
2127 return prefs->GetBoolean(prefs::kCrashReportingEnabled);
2128#else
2129 return MetricsServiceHelper::IsMetricsReportingEnabled();
2130#endif
2131#else
2132 return false;
2133#endif
2134}