blob: 9c1450d6df0fb766e4958033443ec8183c0e4a2f [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."
25// There is at most one initial log sent for each complete run of the chromium
initial.commit09911bf2008-07-26 23:55:2926// product (from startup, to browser shutdown). An initial log is generally
27// transmitted some short time (1 minute?) after startup, and includes stats
28// such as recent crash info, the number and types of plugins, etc. The
[email protected]281d2882009-01-20 20:32:4229// external server's response to the initial log conceptually tells this MS if
30// it should continue transmitting logs (during this session). The server
31// response can actually be much more detailed, and always includes (at a
32// minimum) how often additional 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]281d2882009-01-20 20:32:4248// to the UMA server. The finalization also acquires a the most recent number
49// 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
52// log that has not yet been transmitted. At shutdown time, that fragment
[email protected]cac267c2011-09-29 15:18:1053// is closed (including snapshotting histograms), and persisted, for
initial.commit09911bf2008-07-26 23:55:2954// potential transmission during a future run of the product.
55//
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
76// stats, based on the State enum specified in the state_ member. Those states
77// are:
78//
79// INITIALIZED, // Constructor was called.
[email protected]85ed9d42010-06-08 22:37:4480// INIT_TASK_SCHEDULED, // Waiting for deferred init tasks to complete.
81// INIT_TASK_DONE, // Waiting for timer to send initial log.
initial.commit09911bf2008-07-26 23:55:2982// INITIAL_LOG_READY, // Initial log generated, and waiting for reply.
initial.commit09911bf2008-07-26 23:55:2983// SENDING_OLD_LOGS, // Sending unsent logs from previous session.
84// SENDING_CURRENT_LOGS, // Sending standard current logs as they accrue.
85//
86// In more detail, we have:
87//
88// INITIALIZED, // Constructor was called.
89// The MS has been constructed, but has taken no actions to compose the
90// initial log.
91//
[email protected]85ed9d42010-06-08 22:37:4492// INIT_TASK_SCHEDULED, // Waiting for deferred init tasks to complete.
initial.commit09911bf2008-07-26 23:55:2993// Typically about 30 seconds after startup, a task is sent to a second thread
[email protected]85ed9d42010-06-08 22:37:4494// (the file thread) to perform deferred (lower priority and slower)
95// initialization steps such as getting the list of plugins. That task will
96// (when complete) make an async callback (via a Task) to indicate the
97// completion.
initial.commit09911bf2008-07-26 23:55:2998//
[email protected]85ed9d42010-06-08 22:37:4499// INIT_TASK_DONE, // Waiting for timer to send initial log.
initial.commit09911bf2008-07-26 23:55:29100// The callback has arrived, and it is now possible for an initial log to be
101// created. This callback typically arrives back less than one second after
[email protected]85ed9d42010-06-08 22:37:44102// the deferred init task is dispatched.
initial.commit09911bf2008-07-26 23:55:29103//
104// INITIAL_LOG_READY, // Initial log generated, and waiting for reply.
105// This state is entered only after an initial log has been composed, and
106// prepared for transmission. It is also the case that any previously unsent
107// logs have been loaded into instance variables for possible transmission.
108//
initial.commit09911bf2008-07-26 23:55:29109// SENDING_OLD_LOGS, // Sending unsent logs from previous session.
[email protected]cac267c2011-09-29 15:18:10110// This state indicates that the initial log for this session has been
111// successfully sent and it is now time to send any logs that were
112// saved from previous sessions. All such logs will be transmitted before
113// exiting this state, and proceeding with ongoing logs from the current session
114// (see next state).
initial.commit09911bf2008-07-26 23:55:29115//
116// SENDING_CURRENT_LOGS, // Sending standard current logs as they accrue.
[email protected]0b33f80b2008-12-17 21:34:36117// Current logs are being accumulated. Typically every 20 minutes a log is
initial.commit09911bf2008-07-26 23:55:29118// closed and finalized for transmission, at the same time as a new log is
119// started.
120//
121// The progression through the above states is simple, and sequential, in the
122// most common use cases. States proceed from INITIAL to SENDING_CURRENT_LOGS,
123// and remain in the latter until shutdown.
124//
125// The one unusual case is when the user asks that we stop logging. When that
[email protected]cac267c2011-09-29 15:18:10126// happens, any staged (transmission in progress) log is persisted, and any log
[email protected]410938e02012-10-24 16:33:59127// that is currently accumulating is also finalized and persisted. We then
[email protected]cac267c2011-09-29 15:18:10128// regress back to the SEND_OLD_LOGS state in case the user enables log
129// recording again during this session. This way anything we have persisted
130// will be sent automatically if/when we progress back to SENDING_CURRENT_LOG
131// state.
initial.commit09911bf2008-07-26 23:55:29132//
[email protected]410938e02012-10-24 16:33:59133// Another similar case is on mobile, when the application is backgrounded and
134// then foregrounded again. Backgrounding created new "old" stored logs, so the
135// state drops back from SENDING_CURRENT_LOGS to SENDING_OLD_LOGS so those logs
136// will be sent.
137//
[email protected]cac267c2011-09-29 15:18:10138// Also note that whenever we successfully send an old log, we mirror the list
139// of logs into the PrefService. This ensures that IF we crash, we won't start
140// up and retransmit our old logs again.
initial.commit09911bf2008-07-26 23:55:29141//
142// Due to race conditions, it is always possible that a log file could be sent
143// twice. For example, if a log file is sent, but not yet acknowledged by
144// the external server, and the user shuts down, then a copy of the log may be
145// saved for re-transmission. These duplicates could be filtered out server
[email protected]281d2882009-01-20 20:32:42146// side, but are not expected to be a significant problem.
initial.commit09911bf2008-07-26 23:55:29147//
148//
149//------------------------------------------------------------------------------
150
[email protected]40bcc302009-03-02 20:50:39151#include "chrome/browser/metrics/metrics_service.h"
152
[email protected]d7c1fa62012-06-15 23:35:30153#include <algorithm>
154
[email protected]7f7f1962011-04-20 15:58:16155#include "base/bind.h"
156#include "base/callback.h"
[email protected]5d91c9e2010-07-28 17:25:28157#include "base/command_line.h"
[email protected]3dc1bc42012-06-19 08:20:53158#include "base/guid.h"
[email protected]46f89e142010-07-19 08:00:42159#include "base/md5.h"
[email protected]835d7c82010-10-14 04:38:38160#include "base/metrics/histogram.h"
[email protected]1026afd2013-03-20 14:28:54161#include "base/metrics/sparse_histogram.h"
[email protected]567d30e2012-07-13 21:48:29162#include "base/metrics/statistics_recorder.h"
[email protected]3853a4c2013-02-11 17:15:57163#include "base/prefs/pref_registry_simple.h"
164#include "base/prefs/pref_service.h"
[email protected]e61003a2012-05-24 17:03:19165#include "base/rand_util.h"
[email protected]3ea1b182013-02-08 22:38:41166#include "base/strings/string_number_conversions.h"
[email protected]112158af2013-06-07 23:46:18167#include "base/strings/utf_string_conversions.h"
[email protected]ce072a72010-12-31 20:02:16168#include "base/threading/platform_thread.h"
[email protected]b3841c502011-03-09 01:21:31169#include "base/threading/thread.h"
[email protected]3a7b66d2012-04-26 16:34:16170#include "base/threading/thread_restrictions.h"
[email protected]ed0fd002012-04-25 23:10:34171#include "base/tracked_objects.h"
[email protected]679082052010-07-21 21:30:13172#include "base/values.h"
initial.commit09911bf2008-07-26 23:55:29173#include "chrome/browser/browser_process.h"
[email protected]9ea0cd32013-07-12 01:50:36174#include "chrome/browser/chrome_notification_types.h"
[email protected]6f371442011-11-09 06:45:46175#include "chrome/browser/extensions/extension_service.h"
176#include "chrome/browser/extensions/process_map.h"
[email protected]b8ddb052012-04-19 02:36:06177#include "chrome/browser/io_thread.h"
[email protected]84c988a2011-04-19 17:56:33178#include "chrome/browser/memory_details.h"
[email protected]537c638d2013-07-04 00:49:19179#include "chrome/browser/metrics/compression_utils.h"
[email protected]679082052010-07-21 21:30:13180#include "chrome/browser/metrics/metrics_log.h"
[email protected]cac267c2011-09-29 15:18:10181#include "chrome/browser/metrics/metrics_log_serializer.h"
[email protected]7f7f1962011-04-20 15:58:16182#include "chrome/browser/metrics/metrics_reporting_scheduler.h"
[email protected]aa312812013-04-30 19:46:05183#include "chrome/browser/metrics/time_ticks_experiment_win.h"
[email protected]ed0fd002012-04-25 23:10:34184#include "chrome/browser/metrics/tracking_synchronizer.h"
[email protected]adbb3762012-03-09 22:20:08185#include "chrome/browser/net/http_pipelining_compatibility_client.h"
[email protected]d67d1052011-06-09 05:11:41186#include "chrome/browser/net/network_stats.h"
[email protected]0fafc8d2013-06-01 00:09:50187#include "chrome/browser/omnibox/omnibox_log.h"
[email protected]f8628c22011-04-05 12:10:18188#include "chrome/browser/prefs/scoped_user_pref_update.h"
[email protected]8ecad5e2010-12-02 21:18:33189#include "chrome/browser/profiles/profile.h"
[email protected]71b73f02011-04-06 15:57:29190#include "chrome/browser/ui/browser_list.h"
[email protected]10b132b02012-07-27 20:46:18191#include "chrome/browser/ui/browser_otr_state.h"
[email protected]752a5262013-06-23 14:53:42192#include "chrome/browser/ui/search/search_tab_helper.h"
[email protected]261ab7c2013-08-19 15:04:58193#include "chrome/common/chrome_constants.h"
[email protected]d7c1fa62012-06-15 23:35:30194#include "chrome/common/chrome_result_codes.h"
[email protected]92745242009-06-12 16:52:21195#include "chrome/common/chrome_switches.h"
[email protected]264c0acac2013-10-01 13:33:30196#include "chrome/common/crash_keys.h"
[email protected]c277e2b2013-08-02 15:41:08197#include "chrome/common/metrics/caching_permuted_entropy_provider.h"
[email protected]2e4cd1a2012-01-12 08:51:03198#include "chrome/common/metrics/metrics_log_manager.h"
[email protected]b4a72d842012-03-22 20:09:09199#include "chrome/common/net/test_server_locations.h"
initial.commit09911bf2008-07-26 23:55:29200#include "chrome/common/pref_names.h"
[email protected]e09ba552009-02-05 03:26:29201#include "chrome/common/render_messages.h"
[email protected]50ae9f12013-08-29 18:03:22202#include "components/variations/entropy_provider.h"
[email protected]4967f792012-01-20 22:14:40203#include "content/public/browser/child_process_data.h"
[email protected]83ab4a282012-07-12 18:19:45204#include "content/public/browser/histogram_fetcher.h"
[email protected]09d31d52012-03-11 22:30:27205#include "content/public/browser/load_notification_details.h"
[email protected]ad50def52011-10-19 23:17:07206#include "content/public/browser/notification_service.h"
[email protected]3a5180ae2011-12-21 02:39:38207#include "content/public/browser/plugin_service.h"
[email protected]f3b1a082011-11-18 00:34:30208#include "content/public/browser/render_process_host.h"
[email protected]5d490e42012-08-30 05:16:43209#include "content/public/browser/user_metrics.h"
[email protected]459f3502012-09-17 17:08:12210#include "content/public/browser/web_contents.h"
[email protected]d5d383252013-07-04 14:44:32211#include "content/public/common/process_type.h"
[email protected]d7bd3e52013-07-21 04:29:20212#include "content/public/common/webplugininfo.h"
[email protected]fe58acc22012-02-29 01:29:58213#include "net/base/load_flags.h"
[email protected]3dc1bc42012-06-19 08:20:53214#include "net/url_request/url_fetcher.h"
initial.commit09911bf2008-07-26 23:55:29215
[email protected]e06131d2010-02-10 18:40:33216// TODO(port): port browser_distribution.h.
217#if !defined(OS_POSIX)
[email protected]79bf0b72009-04-27 21:30:55218#include "chrome/installer/util/browser_distribution.h"
[email protected]dc6f4962009-02-13 01:25:50219#endif
220
[email protected]5ccaa412009-11-13 22:00:16221#if defined(OS_CHROMEOS)
222#include "chrome/browser/chromeos/external_metrics.h"
[email protected]279690f82013-10-09 08:23:52223#include "chromeos/system/statistics_provider.h"
[email protected]5ccaa412009-11-13 22:00:16224#endif
225
[email protected]d7c1fa62012-06-15 23:35:30226#if defined(OS_WIN)
227#include <windows.h> // Needed for STATUS_* codes
[email protected]261ab7c2013-08-19 15:04:58228#include "base/win/registry.h"
[email protected]d7c1fa62012-06-15 23:35:30229#endif
230
[email protected]a3079832013-10-24 20:29:36231#if !defined(OS_ANDROID)
232#include "chrome/browser/service/service_process_control.h"
233#endif
234
[email protected]e1acf6f2008-10-27 20:43:33235using base::Time;
[email protected]631bb742011-11-02 11:29:39236using content::BrowserThread;
[email protected]4967f792012-01-20 22:14:40237using content::ChildProcessData;
[email protected]09d31d52012-03-11 22:30:27238using content::LoadNotificationDetails;
[email protected]3a5180ae2011-12-21 02:39:38239using content::PluginService;
[email protected]e1acf6f2008-10-27 20:43:33240
[email protected]fe58acc22012-02-29 01:29:58241namespace {
[email protected]b2a4812d2012-02-28 05:31:31242
[email protected]fe58acc22012-02-29 01:29:58243// Check to see that we're being called on only one thread.
244bool IsSingleThreaded() {
245 static base::PlatformThreadId thread_id = 0;
246 if (!thread_id)
247 thread_id = base::PlatformThread::CurrentId();
248 return base::PlatformThread::CurrentId() == thread_id;
249}
250
[email protected]7f7f1962011-04-20 15:58:16251// The delay, in seconds, after starting recording before doing expensive
252// initialization work.
[email protected]12180f82012-10-10 21:13:30253#if defined(OS_ANDROID) || defined(OS_IOS)
254// On mobile devices, a significant portion of sessions last less than a minute.
255// Use a shorter timer on these platforms to avoid losing data.
256// TODO(dfalcantara): To avoid delaying startup, tighten up initialization so
257// that it occurs after the user gets their initial page.
258const int kInitializationDelaySeconds = 5;
259#else
[email protected]fe58acc22012-02-29 01:29:58260const int kInitializationDelaySeconds = 30;
[email protected]12180f82012-10-10 21:13:30261#endif
[email protected]252873ef2008-08-04 21:59:45262
[email protected]c9a3ef82009-05-28 22:02:46263// This specifies the amount of time to wait for all renderers to send their
264// data.
[email protected]fe58acc22012-02-29 01:29:58265const int kMaxHistogramGatheringWaitDuration = 60000; // 60 seconds.
[email protected]c9a3ef82009-05-28 22:02:46266
[email protected]54702c92011-04-15 15:06:43267// The maximum number of events in a log uploaded to the UMA server.
[email protected]fe58acc22012-02-29 01:29:58268const int kEventLimit = 2400;
[email protected]68475e602008-08-22 03:21:15269
270// If an upload fails, and the transmission was over this byte count, then we
271// will discard the log, and not try to retransmit it. We also don't persist
272// the log to the prefs for transmission during the next chrome session if this
273// limit is exceeded.
[email protected]fe58acc22012-02-29 01:29:58274const size_t kUploadLogAvoidRetransmitSize = 50000;
initial.commit09911bf2008-07-26 23:55:29275
[email protected]fc4252a72012-01-12 21:58:47276// Interval, in minutes, between state saves.
[email protected]fe58acc22012-02-29 01:29:58277const int kSaveStateIntervalMinutes = 5;
278
[email protected]4266def22012-05-17 01:02:40279enum ResponseStatus {
280 UNKNOWN_FAILURE,
281 SUCCESS,
282 BAD_REQUEST, // Invalid syntax or log too large.
[email protected]9f5c1ce82012-05-23 23:11:28283 NO_RESPONSE,
[email protected]4266def22012-05-17 01:02:40284 NUM_RESPONSE_STATUSES
285};
286
287ResponseStatus ResponseCodeToStatus(int response_code) {
288 switch (response_code) {
289 case 200:
290 return SUCCESS;
291 case 400:
292 return BAD_REQUEST;
[email protected]9f5c1ce82012-05-23 23:11:28293 case net::URLFetcher::RESPONSE_CODE_INVALID:
294 return NO_RESPONSE;
[email protected]4266def22012-05-17 01:02:40295 default:
296 return UNKNOWN_FAILURE;
297 }
298}
299
[email protected]e61003a2012-05-24 17:03:19300// The argument used to generate a non-identifying entropy source. We want no
[email protected]9556a892013-06-21 16:53:20301// more than 13 bits of entropy, so use this max to return a number in the range
302// [0, 7999] as the entropy source (12.97 bits of entropy).
303const int kMaxLowEntropySize = 8000;
[email protected]e61003a2012-05-24 17:03:19304
[email protected]e63a9ef2012-08-30 15:29:42305// Default prefs value for prefs::kMetricsLowEntropySource to indicate that the
306// value has not yet been set.
307const int kLowEntropySourceNotSet = -1;
308
[email protected]e61003a2012-05-24 17:03:19309// Generates a new non-identifying entropy source used to seed persistent
310// activities.
311int GenerateLowEntropySource() {
[email protected]20f999b52012-08-24 22:32:59312 return base::RandInt(0, kMaxLowEntropySize - 1);
[email protected]e61003a2012-05-24 17:03:19313}
314
[email protected]d7c1fa62012-06-15 23:35:30315// Converts an exit code into something that can be inserted into our
316// histograms (which expect non-negative numbers less than MAX_INT).
317int MapCrashExitCodeForHistogram(int exit_code) {
318#if defined(OS_WIN)
319 // Since |abs(STATUS_GUARD_PAGE_VIOLATION) == MAX_INT| it causes problems in
320 // histograms.cc. Solve this by remapping it to a smaller value, which
321 // hopefully doesn't conflict with other codes.
322 if (exit_code == STATUS_GUARD_PAGE_VIOLATION)
323 return 0x1FCF7EC3; // Randomly picked number.
324#endif
325
326 return std::abs(exit_code);
327}
328
[email protected]84c384e2013-03-01 23:20:19329void MarkAppCleanShutdownAndCommit() {
330 PrefService* pref = g_browser_process->local_state();
331 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
332 // Start writing right away (write happens on a different thread).
333 pref->CommitPendingWrite();
334}
335
[email protected]20f999b52012-08-24 22:32:59336} // namespace
initial.commit09911bf2008-07-26 23:55:29337
[email protected]c0c55e92011-09-10 18:47:30338// static
339MetricsService::ShutdownCleanliness MetricsService::clean_shutdown_status_ =
340 MetricsService::CLEANLY_SHUTDOWN;
341
[email protected]679082052010-07-21 21:30:13342// This is used to quickly log stats from child process related notifications in
343// MetricsService::child_stats_buffer_. The buffer's contents are transferred
344// out when Local State is periodically saved. The information is then
345// reported to the UMA server on next launch.
346struct MetricsService::ChildProcessStats {
347 public:
[email protected]f3b357692013-03-22 05:16:13348 explicit ChildProcessStats(int process_type)
[email protected]679082052010-07-21 21:30:13349 : process_launches(0),
350 process_crashes(0),
351 instances(0),
[email protected]cd937072012-07-02 09:00:29352 loading_errors(0),
[email protected]f3b357692013-03-22 05:16:13353 process_type(process_type) {}
[email protected]679082052010-07-21 21:30:13354
355 // This constructor is only used by the map to return some default value for
356 // an index for which no value has been assigned.
357 ChildProcessStats()
358 : process_launches(0),
[email protected]d88bf0a2011-08-30 23:55:57359 process_crashes(0),
360 instances(0),
[email protected]cd937072012-07-02 09:00:29361 loading_errors(0),
[email protected]bd5d6cf2011-12-01 00:39:12362 process_type(content::PROCESS_TYPE_UNKNOWN) {}
[email protected]679082052010-07-21 21:30:13363
364 // The number of times that the given child process has been launched
365 int process_launches;
366
367 // The number of times that the given child process has crashed
368 int process_crashes;
369
370 // The number of instances of this child process that have been created.
371 // An instance is a DOM object rendered by this child process during a page
372 // load.
373 int instances;
374
[email protected]cd937072012-07-02 09:00:29375 // The number of times there was an error loading an instance of this child
376 // process.
377 int loading_errors;
378
[email protected]f3b357692013-03-22 05:16:13379 int process_type;
[email protected]679082052010-07-21 21:30:13380};
initial.commit09911bf2008-07-26 23:55:29381
[email protected]84c988a2011-04-19 17:56:33382// Handles asynchronous fetching of memory details.
383// Will run the provided task after finished.
384class MetricsMemoryDetails : public MemoryDetails {
385 public:
[email protected]2226c222011-11-22 00:08:40386 explicit MetricsMemoryDetails(const base::Closure& callback)
387 : callback_(callback) {}
[email protected]84c988a2011-04-19 17:56:33388
[email protected]b94584a2013-02-07 03:02:08389 virtual void OnDetailsAvailable() OVERRIDE {
[email protected]b3a25092013-05-28 22:08:16390 base::MessageLoop::current()->PostTask(FROM_HERE, callback_);
[email protected]84c988a2011-04-19 17:56:33391 }
392
393 private:
[email protected]b94584a2013-02-07 03:02:08394 virtual ~MetricsMemoryDetails() {}
[email protected]84c988a2011-04-19 17:56:33395
[email protected]2226c222011-11-22 00:08:40396 base::Closure callback_;
[email protected]84c988a2011-04-19 17:56:33397 DISALLOW_COPY_AND_ASSIGN(MetricsMemoryDetails);
398};
399
initial.commit09911bf2008-07-26 23:55:29400// static
[email protected]b1de2c72013-02-06 02:45:47401void MetricsService::RegisterPrefs(PrefRegistrySimple* registry) {
initial.commit09911bf2008-07-26 23:55:29402 DCHECK(IsSingleThreaded());
[email protected]007b3f82013-04-09 08:46:45403 registry->RegisterStringPref(prefs::kMetricsClientID, std::string());
[email protected]b1de2c72013-02-06 02:45:47404 registry->RegisterIntegerPref(prefs::kMetricsLowEntropySource,
405 kLowEntropySourceNotSet);
406 registry->RegisterInt64Pref(prefs::kMetricsClientIDTimestamp, 0);
407 registry->RegisterInt64Pref(prefs::kStabilityLaunchTimeSec, 0);
408 registry->RegisterInt64Pref(prefs::kStabilityLastTimestampSec, 0);
[email protected]007b3f82013-04-09 08:46:45409 registry->RegisterStringPref(prefs::kStabilityStatsVersion, std::string());
[email protected]b1de2c72013-02-06 02:45:47410 registry->RegisterInt64Pref(prefs::kStabilityStatsBuildTime, 0);
411 registry->RegisterBooleanPref(prefs::kStabilityExitedCleanly, true);
412 registry->RegisterBooleanPref(prefs::kStabilitySessionEndCompleted, true);
413 registry->RegisterIntegerPref(prefs::kMetricsSessionID, -1);
414 registry->RegisterIntegerPref(prefs::kStabilityLaunchCount, 0);
415 registry->RegisterIntegerPref(prefs::kStabilityCrashCount, 0);
416 registry->RegisterIntegerPref(prefs::kStabilityIncompleteSessionEndCount, 0);
417 registry->RegisterIntegerPref(prefs::kStabilityPageLoadCount, 0);
418 registry->RegisterIntegerPref(prefs::kStabilityRendererCrashCount, 0);
419 registry->RegisterIntegerPref(prefs::kStabilityExtensionRendererCrashCount,
420 0);
421 registry->RegisterIntegerPref(prefs::kStabilityRendererHangCount, 0);
422 registry->RegisterIntegerPref(prefs::kStabilityChildProcessCrashCount, 0);
423 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationFail, 0);
424 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationSuccess,
425 0);
426 registry->RegisterIntegerPref(prefs::kStabilityDebuggerPresent, 0);
427 registry->RegisterIntegerPref(prefs::kStabilityDebuggerNotPresent, 0);
[email protected]c1834a92011-01-21 18:21:03428#if defined(OS_CHROMEOS)
[email protected]b1de2c72013-02-06 02:45:47429 registry->RegisterIntegerPref(prefs::kStabilityOtherUserCrashCount, 0);
430 registry->RegisterIntegerPref(prefs::kStabilityKernelCrashCount, 0);
431 registry->RegisterIntegerPref(prefs::kStabilitySystemUncleanShutdownCount, 0);
[email protected]c1834a92011-01-21 18:21:03432#endif // OS_CHROMEOS
[email protected]e73c01972008-08-13 00:18:24433
[email protected]5f3e1642013-05-05 03:37:34434 registry->RegisterListPref(prefs::kMetricsInitialLogs);
435 registry->RegisterListPref(prefs::kMetricsOngoingLogs);
[email protected]0bb1a622009-03-04 03:22:32436
[email protected]5c181552013-02-07 09:12:52437 registry->RegisterInt64Pref(prefs::kInstallDate, 0);
[email protected]b1de2c72013-02-06 02:45:47438 registry->RegisterInt64Pref(prefs::kUninstallMetricsPageLoadCount, 0);
439 registry->RegisterInt64Pref(prefs::kUninstallLaunchCount, 0);
[email protected]b1de2c72013-02-06 02:45:47440 registry->RegisterInt64Pref(prefs::kUninstallMetricsUptimeSec, 0);
441 registry->RegisterInt64Pref(prefs::kUninstallLastLaunchTimeSec, 0);
442 registry->RegisterInt64Pref(prefs::kUninstallLastObservedRunTimeSec, 0);
initial.commit09911bf2008-07-26 23:55:29443}
444
[email protected]541f77922009-02-23 21:14:38445// static
446void MetricsService::DiscardOldStabilityStats(PrefService* local_state) {
447 local_state->SetBoolean(prefs::kStabilityExitedCleanly, true);
[email protected]c9abf242009-07-18 06:00:38448 local_state->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
[email protected]541f77922009-02-23 21:14:38449
450 local_state->SetInteger(prefs::kStabilityIncompleteSessionEndCount, 0);
451 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationSuccess, 0);
452 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationFail, 0);
453 local_state->SetInteger(prefs::kStabilityDebuggerPresent, 0);
454 local_state->SetInteger(prefs::kStabilityDebuggerNotPresent, 0);
455
456 local_state->SetInteger(prefs::kStabilityLaunchCount, 0);
457 local_state->SetInteger(prefs::kStabilityCrashCount, 0);
458
459 local_state->SetInteger(prefs::kStabilityPageLoadCount, 0);
460 local_state->SetInteger(prefs::kStabilityRendererCrashCount, 0);
461 local_state->SetInteger(prefs::kStabilityRendererHangCount, 0);
462
[email protected]9165f742010-03-10 22:55:01463 local_state->SetInt64(prefs::kStabilityLaunchTimeSec, 0);
464 local_state->SetInt64(prefs::kStabilityLastTimestampSec, 0);
[email protected]541f77922009-02-23 21:14:38465
466 local_state->ClearPref(prefs::kStabilityPluginStats);
[email protected]ae155cb92009-06-19 06:10:37467
[email protected]5f3e1642013-05-05 03:37:34468 local_state->ClearPref(prefs::kMetricsInitialLogs);
469 local_state->ClearPref(prefs::kMetricsOngoingLogs);
[email protected]541f77922009-02-23 21:14:38470}
471
initial.commit09911bf2008-07-26 23:55:29472MetricsService::MetricsService()
[email protected]d01b8732008-10-16 02:18:07473 : recording_active_(false),
474 reporting_active_(false),
[email protected]410938e02012-10-24 16:33:59475 test_mode_active_(false),
[email protected]d01b8732008-10-16 02:18:07476 state_(INITIALIZED),
[email protected]e88be472e2013-04-26 22:36:36477 low_entropy_source_(kLowEntropySourceNotSet),
[email protected]d01b8732008-10-16 02:18:07478 idle_since_last_transmission_(false),
initial.commit09911bf2008-07-26 23:55:29479 next_window_id_(0),
[email protected]9c009092013-05-01 03:14:09480 self_ptr_factory_(this),
481 state_saver_factory_(this),
[email protected]e5354322012-08-09 23:07:37482 waiting_for_asynchronous_reporting_step_(false),
[email protected]a3079832013-10-24 20:29:36483 num_async_histogram_fetches_in_progress_(0),
[email protected]e5354322012-08-09 23:07:37484 entropy_source_returned_(LAST_ENTROPY_NONE) {
initial.commit09911bf2008-07-26 23:55:29485 DCHECK(IsSingleThreaded());
486 InitializeMetricsState();
[email protected]7f7f1962011-04-20 15:58:16487
488 base::Closure callback = base::Bind(&MetricsService::StartScheduledUpload,
[email protected]c94d7382012-02-28 08:43:40489 self_ptr_factory_.GetWeakPtr());
[email protected]7f7f1962011-04-20 15:58:16490 scheduler_.reset(new MetricsReportingScheduler(callback));
[email protected]cac267c2011-09-29 15:18:10491 log_manager_.set_log_serializer(new MetricsLogSerializer());
492 log_manager_.set_max_ongoing_log_store_size(kUploadLogAvoidRetransmitSize);
[email protected]f4eaf7b92013-02-28 22:00:40493
494 BrowserChildProcessObserver::Add(this);
initial.commit09911bf2008-07-26 23:55:29495}
496
497MetricsService::~MetricsService() {
[email protected]410938e02012-10-24 16:33:59498 DisableRecording();
[email protected]f4eaf7b92013-02-28 22:00:40499
500 BrowserChildProcessObserver::Remove(this);
initial.commit09911bf2008-07-26 23:55:29501}
502
[email protected]d01b8732008-10-16 02:18:07503void MetricsService::Start() {
[email protected]b1c8dc02011-04-13 18:32:04504 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59505 EnableRecording();
506 EnableReporting();
[email protected]d01b8732008-10-16 02:18:07507}
508
[email protected]410938e02012-10-24 16:33:59509void MetricsService::StartRecordingForTests() {
510 test_mode_active_ = true;
511 EnableRecording();
512 DisableReporting();
[email protected]d01b8732008-10-16 02:18:07513}
514
515void MetricsService::Stop() {
[email protected]b1c8dc02011-04-13 18:32:04516 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59517 DisableReporting();
518 DisableRecording();
519}
520
521void MetricsService::EnableReporting() {
522 if (reporting_active_)
523 return;
524 reporting_active_ = true;
525 StartSchedulerIfNecessary();
526}
527
528void MetricsService::DisableReporting() {
529 reporting_active_ = false;
[email protected]d01b8732008-10-16 02:18:07530}
531
[email protected]edafd4c2011-05-10 17:18:53532std::string MetricsService::GetClientId() {
533 return client_id_;
534}
535
[email protected]20f999b52012-08-24 22:32:59536scoped_ptr<const base::FieldTrial::EntropyProvider>
537 MetricsService::CreateEntropyProvider(bool reporting_will_be_enabled) {
[email protected]29d81ee02012-05-25 05:45:42538 // For metrics reporting-enabled users, we combine the client ID and low
539 // entropy source to get the final entropy source. Otherwise, only use the low
540 // entropy source.
541 // This has two useful properties:
[email protected]e61003a2012-05-24 17:03:19542 // 1) It makes the entropy source less identifiable for parties that do not
543 // know the low entropy source.
544 // 2) It makes the final entropy source resettable.
[email protected]d0a82c82013-06-13 16:31:18545 const int low_entropy_source_value = GetLowEntropySource();
546 UMA_HISTOGRAM_SPARSE_SLOWLY("UMA.LowEntropySourceValue",
547 low_entropy_source_value);
[email protected]e5354322012-08-09 23:07:37548 if (reporting_will_be_enabled) {
[email protected]5fbffb72012-08-18 01:59:18549 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
550 entropy_source_returned_ = LAST_ENTROPY_HIGH;
551 DCHECK_EQ(LAST_ENTROPY_HIGH, entropy_source_returned_);
[email protected]20f999b52012-08-24 22:32:59552 const std::string high_entropy_source =
[email protected]d0a82c82013-06-13 16:31:18553 client_id_ + base::IntToString(low_entropy_source_value);
[email protected]20f999b52012-08-24 22:32:59554 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
555 new metrics::SHA1EntropyProvider(high_entropy_source));
[email protected]e5354322012-08-09 23:07:37556 }
[email protected]20f999b52012-08-24 22:32:59557
[email protected]5fbffb72012-08-18 01:59:18558 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
559 entropy_source_returned_ = LAST_ENTROPY_LOW;
560 DCHECK_EQ(LAST_ENTROPY_LOW, entropy_source_returned_);
[email protected]9d7c4a82013-05-07 12:10:49561
562#if defined(OS_ANDROID) || defined(OS_IOS)
563 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
564 new metrics::CachingPermutedEntropyProvider(
565 g_browser_process->local_state(),
[email protected]d0a82c82013-06-13 16:31:18566 low_entropy_source_value,
[email protected]9d7c4a82013-05-07 12:10:49567 kMaxLowEntropySize));
568#else
[email protected]20f999b52012-08-24 22:32:59569 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
[email protected]d0a82c82013-06-13 16:31:18570 new metrics::PermutedEntropyProvider(low_entropy_source_value,
[email protected]20f999b52012-08-24 22:32:59571 kMaxLowEntropySize));
[email protected]9d7c4a82013-05-07 12:10:49572#endif
[email protected]e61003a2012-05-24 17:03:19573}
574
[email protected]5cbeeef72012-02-08 02:05:18575void MetricsService::ForceClientIdCreation() {
576 if (!client_id_.empty())
577 return;
578 PrefService* pref = g_browser_process->local_state();
579 client_id_ = pref->GetString(prefs::kMetricsClientID);
580 if (!client_id_.empty())
581 return;
582
583 client_id_ = GenerateClientID();
584 pref->SetString(prefs::kMetricsClientID, client_id_);
585
586 // Might as well make a note of how long this ID has existed
587 pref->SetString(prefs::kMetricsClientIDTimestamp,
588 base::Int64ToString(Time::Now().ToTimeT()));
589}
590
[email protected]410938e02012-10-24 16:33:59591void MetricsService::EnableRecording() {
initial.commit09911bf2008-07-26 23:55:29592 DCHECK(IsSingleThreaded());
593
[email protected]410938e02012-10-24 16:33:59594 if (recording_active_)
initial.commit09911bf2008-07-26 23:55:29595 return;
[email protected]410938e02012-10-24 16:33:59596 recording_active_ = true;
initial.commit09911bf2008-07-26 23:55:29597
[email protected]410938e02012-10-24 16:33:59598 ForceClientIdCreation();
[email protected]264c0acac2013-10-01 13:33:30599 crash_keys::SetClientID(client_id_);
[email protected]410938e02012-10-24 16:33:59600 if (!log_manager_.current_log())
601 OpenNewLog();
[email protected]005ef3e2009-05-22 20:55:46602
[email protected]410938e02012-10-24 16:33:59603 SetUpNotifications(&registrar_, this);
[email protected]dd98f392013-02-04 13:03:22604 content::RemoveActionCallback(action_callback_);
605 action_callback_ = base::Bind(&MetricsService::OnUserAction,
606 base::Unretained(this));
607 content::AddActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59608}
609
610void MetricsService::DisableRecording() {
611 DCHECK(IsSingleThreaded());
612
613 if (!recording_active_)
614 return;
615 recording_active_ = false;
616
[email protected]dd98f392013-02-04 13:03:22617 content::RemoveActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59618 registrar_.RemoveAll();
619 PushPendingLogsToPersistentStorage();
620 DCHECK(!log_manager_.has_staged_log());
initial.commit09911bf2008-07-26 23:55:29621}
622
[email protected]d01b8732008-10-16 02:18:07623bool MetricsService::recording_active() const {
initial.commit09911bf2008-07-26 23:55:29624 DCHECK(IsSingleThreaded());
[email protected]d01b8732008-10-16 02:18:07625 return recording_active_;
initial.commit09911bf2008-07-26 23:55:29626}
627
[email protected]d01b8732008-10-16 02:18:07628bool MetricsService::reporting_active() const {
629 DCHECK(IsSingleThreaded());
630 return reporting_active_;
initial.commit09911bf2008-07-26 23:55:29631}
632
[email protected]87ef9ea2011-02-26 03:15:15633// static
[email protected]6c2381d2011-10-19 02:52:53634void MetricsService::SetUpNotifications(
635 content::NotificationRegistrar* registrar,
636 content::NotificationObserver* observer) {
637 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_OPENED,
[email protected]ad50def52011-10-19 23:17:07638 content::NotificationService::AllBrowserContextsAndSources());
[email protected]6c2381d2011-10-19 02:52:53639 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_CLOSED,
[email protected]ad50def52011-10-19 23:17:07640 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42641 registrar->Add(observer, chrome::NOTIFICATION_TAB_PARENTED,
[email protected]ad50def52011-10-19 23:17:07642 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42643 registrar->Add(observer, chrome::NOTIFICATION_TAB_CLOSING,
[email protected]ad50def52011-10-19 23:17:07644 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53645 registrar->Add(observer, content::NOTIFICATION_LOAD_START,
[email protected]ad50def52011-10-19 23:17:07646 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53647 registrar->Add(observer, content::NOTIFICATION_LOAD_STOP,
[email protected]ad50def52011-10-19 23:17:07648 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53649 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_CLOSED,
[email protected]ad50def52011-10-19 23:17:07650 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53651 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_HANG,
[email protected]ad50def52011-10-19 23:17:07652 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53653 registrar->Add(observer, chrome::NOTIFICATION_OMNIBOX_OPENED_URL,
[email protected]ad50def52011-10-19 23:17:07654 content::NotificationService::AllSources());
[email protected]87ef9ea2011-02-26 03:15:15655}
656
[email protected]f4eaf7b92013-02-28 22:00:40657void MetricsService::BrowserChildProcessHostConnected(
658 const content::ChildProcessData& data) {
659 GetChildProcessStats(data).process_launches++;
660}
661
662void MetricsService::BrowserChildProcessCrashed(
663 const content::ChildProcessData& data) {
664 GetChildProcessStats(data).process_crashes++;
665 // Exclude plugin crashes from the count below because we report them via
666 // a separate UMA metric.
[email protected]f3b357692013-03-22 05:16:13667 if (!IsPluginProcess(data.process_type))
[email protected]f4eaf7b92013-02-28 22:00:40668 IncrementPrefValue(prefs::kStabilityChildProcessCrashCount);
669}
670
671void MetricsService::BrowserChildProcessInstanceCreated(
672 const content::ChildProcessData& data) {
673 GetChildProcessStats(data).instances++;
674}
675
[email protected]432115822011-07-10 15:52:27676void MetricsService::Observe(int type,
[email protected]6c2381d2011-10-19 02:52:53677 const content::NotificationSource& source,
678 const content::NotificationDetails& details) {
[email protected]cac267c2011-09-29 15:18:10679 DCHECK(log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:29680 DCHECK(IsSingleThreaded());
681
[email protected]dd98f392013-02-04 13:03:22682 if (!CanLogNotification())
initial.commit09911bf2008-07-26 23:55:29683 return;
684
[email protected]432115822011-07-10 15:52:27685 switch (type) {
[email protected]432115822011-07-10 15:52:27686 case chrome::NOTIFICATION_BROWSER_OPENED:
[email protected]46a0efc2013-07-17 15:40:47687 case chrome::NOTIFICATION_BROWSER_CLOSED:
688 case chrome::NOTIFICATION_TAB_PARENTED:
689 case chrome::NOTIFICATION_TAB_CLOSING:
[email protected]432115822011-07-10 15:52:27690 case content::NOTIFICATION_LOAD_STOP:
[email protected]46a0efc2013-07-17 15:40:47691 // These notifications are currently used only to break out of idle mode.
initial.commit09911bf2008-07-26 23:55:29692 break;
693
[email protected]752a5262013-06-23 14:53:42694 case content::NOTIFICATION_LOAD_START: {
695 content::NavigationController* controller =
696 content::Source<content::NavigationController>(source).ptr();
697 content::WebContents* web_contents = controller->GetWebContents();
698 LogLoadStarted(web_contents);
initial.commit09911bf2008-07-26 23:55:29699 break;
[email protected]752a5262013-06-23 14:53:42700 }
initial.commit09911bf2008-07-26 23:55:29701
[email protected]432115822011-07-10 15:52:27702 case content::NOTIFICATION_RENDERER_PROCESS_CLOSED: {
[email protected]f3b1a082011-11-18 00:34:30703 content::RenderProcessHost::RendererClosedDetails* process_details =
704 content::Details<
705 content::RenderProcessHost::RendererClosedDetails>(
706 details).ptr();
707 content::RenderProcessHost* host =
708 content::Source<content::RenderProcessHost>(source).ptr();
[email protected]c3721482012-03-23 16:21:48709 LogRendererCrash(
[email protected]f1675202012-07-09 15:18:00710 host, process_details->status, process_details->exit_code);
[email protected]1f085622009-12-04 05:33:45711 }
initial.commit09911bf2008-07-26 23:55:29712 break;
713
[email protected]432115822011-07-10 15:52:27714 case content::NOTIFICATION_RENDERER_PROCESS_HANG:
initial.commit09911bf2008-07-26 23:55:29715 LogRendererHang();
716 break;
717
[email protected]432115822011-07-10 15:52:27718 case chrome::NOTIFICATION_OMNIBOX_OPENED_URL: {
[email protected]279703f2012-01-20 22:23:26719 MetricsLog* current_log =
720 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]1226abb2010-06-10 18:01:28721 DCHECK(current_log);
722 current_log->RecordOmniboxOpenedURL(
[email protected]0fafc8d2013-06-01 00:09:50723 *content::Details<OmniboxLog>(details).ptr());
initial.commit09911bf2008-07-26 23:55:29724 break;
[email protected]1226abb2010-06-10 18:01:28725 }
initial.commit09911bf2008-07-26 23:55:29726
initial.commit09911bf2008-07-26 23:55:29727 default:
[email protected]a063c102010-07-22 22:20:19728 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:29729 break;
730 }
[email protected]d01b8732008-10-16 02:18:07731
732 HandleIdleSinceLastTransmission(false);
[email protected]d01b8732008-10-16 02:18:07733}
734
735void MetricsService::HandleIdleSinceLastTransmission(bool in_idle) {
736 // If there wasn't a lot of action, maybe the computer was asleep, in which
737 // case, the log transmissions should have stopped. Here we start them up
738 // again.
[email protected]cac78842008-11-27 01:02:20739 if (!in_idle && idle_since_last_transmission_)
[email protected]7f7f1962011-04-20 15:58:16740 StartSchedulerIfNecessary();
[email protected]cac78842008-11-27 01:02:20741 idle_since_last_transmission_ = in_idle;
initial.commit09911bf2008-07-26 23:55:29742}
743
initial.commit09911bf2008-07-26 23:55:29744void MetricsService::RecordStartOfSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38745 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29746 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, false);
747}
748
749void MetricsService::RecordCompletedSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38750 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29751 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, true);
752}
753
[email protected]410938e02012-10-24 16:33:59754#if defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39755void MetricsService::OnAppEnterBackground() {
756 scheduler_->Stop();
757
[email protected]84c384e2013-03-01 23:20:19758 MarkAppCleanShutdownAndCommit();
[email protected]117fbdf22012-06-26 18:36:39759
760 // At this point, there's no way of knowing when the process will be
761 // killed, so this has to be treated similar to a shutdown, closing and
762 // persisting all logs. Unlinke a shutdown, the state is primed to be ready
763 // to continue logging and uploading if the process does return.
764 if (recording_active() && state_ >= INITIAL_LOG_READY) {
765 PushPendingLogsToPersistentStorage();
[email protected]410938e02012-10-24 16:33:59766 // Persisting logs closes the current log, so start recording a new log
767 // immediately to capture any background work that might be done before the
768 // process is killed.
769 OpenNewLog();
[email protected]117fbdf22012-06-26 18:36:39770 }
[email protected]117fbdf22012-06-26 18:36:39771}
772
773void MetricsService::OnAppEnterForeground() {
774 PrefService* pref = g_browser_process->local_state();
775 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
776
777 StartSchedulerIfNecessary();
778}
[email protected]84c384e2013-03-01 23:20:19779#else
780void MetricsService::LogNeedForCleanShutdown() {
781 PrefService* pref = g_browser_process->local_state();
782 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
783 // Redundant setting to be sure we call for a clean shutdown.
784 clean_shutdown_status_ = NEED_TO_SHUTDOWN;
785}
786#endif // defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39787
[email protected]7f7f1962011-04-20 15:58:16788void MetricsService::RecordBreakpadRegistration(bool success) {
[email protected]68475e602008-08-22 03:21:15789 if (!success)
[email protected]e73c01972008-08-13 00:18:24790 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationFail);
791 else
792 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationSuccess);
793}
794
795void MetricsService::RecordBreakpadHasDebugger(bool has_debugger) {
796 if (!has_debugger)
797 IncrementPrefValue(prefs::kStabilityDebuggerNotPresent);
798 else
[email protected]68475e602008-08-22 03:21:15799 IncrementPrefValue(prefs::kStabilityDebuggerPresent);
[email protected]e73c01972008-08-13 00:18:24800}
801
[email protected]261ab7c2013-08-19 15:04:58802#if defined(OS_WIN)
803void MetricsService::CountBrowserCrashDumpAttempts() {
[email protected]a5e0fe5e2013-09-16 06:15:45804 // Open the registry key for iteration.
[email protected]261ab7c2013-08-19 15:04:58805 base::win::RegKey regkey;
806 if (regkey.Open(HKEY_CURRENT_USER,
[email protected]a5e0fe5e2013-09-16 06:15:45807 chrome::kBrowserCrashDumpAttemptsRegistryPath,
[email protected]261ab7c2013-08-19 15:04:58808 KEY_ALL_ACCESS) != ERROR_SUCCESS) {
809 return;
810 }
811
[email protected]a5e0fe5e2013-09-16 06:15:45812 // The values we're interested in counting are all prefixed with the version.
813 base::string16 chrome_version(base::ASCIIToUTF16(chrome::kChromeVersion));
814
815 // Track a list of values to delete. We don't modify the registry key while
816 // we're iterating over its values.
817 typedef std::vector<base::string16> StringVector;
818 StringVector to_delete;
819
820 // Iterate over the values in the key counting dumps with and without crashes.
821 // We directly walk the values instead of using RegistryValueIterator in order
822 // to read all of the values as DWORDS instead of strings.
823 base::string16 name;
824 DWORD value = 0;
825 int dumps_with_crash = 0;
826 int dumps_with_no_crash = 0;
[email protected]261ab7c2013-08-19 15:04:58827 for (int i = regkey.GetValueCount() - 1; i >= 0; --i) {
[email protected]a5e0fe5e2013-09-16 06:15:45828 if (regkey.GetValueNameAt(i, &name) == ERROR_SUCCESS &&
829 StartsWith(name, chrome_version, false) &&
830 regkey.ReadValueDW(name.c_str(), &value) == ERROR_SUCCESS) {
831 to_delete.push_back(name);
832 if (value == 0)
833 ++dumps_with_no_crash;
834 else
835 ++dumps_with_crash;
[email protected]261ab7c2013-08-19 15:04:58836 }
837 }
[email protected]a5e0fe5e2013-09-16 06:15:45838
839 // Delete the registry keys we've just counted.
840 for (StringVector::iterator i = to_delete.begin(); i != to_delete.end(); ++i)
841 regkey.DeleteValue(i->c_str());
842
843 // Capture the histogram samples.
844 if (dumps_with_crash != 0)
845 UMA_HISTOGRAM_COUNTS("Chrome.BrowserDumpsWithCrash", dumps_with_crash);
846 if (dumps_with_no_crash != 0)
847 UMA_HISTOGRAM_COUNTS("Chrome.BrowserDumpsWithNoCrash", dumps_with_no_crash);
848 int total_dumps = dumps_with_crash + dumps_with_no_crash;
849 if (total_dumps != 0)
850 UMA_HISTOGRAM_COUNTS("Chrome.BrowserCrashDumpAttempts", total_dumps);
[email protected]261ab7c2013-08-19 15:04:58851}
852#endif // defined(OS_WIN)
853
initial.commit09911bf2008-07-26 23:55:29854//------------------------------------------------------------------------------
855// private methods
856//------------------------------------------------------------------------------
857
858
859//------------------------------------------------------------------------------
860// Initialization methods
861
862void MetricsService::InitializeMetricsState() {
[email protected]79bf0b72009-04-27 21:30:55863#if defined(OS_POSIX)
[email protected]b4a72d842012-03-22 20:09:09864 network_stats_server_ = chrome_common_net::kEchoTestServerLocation;
865 http_pipelining_test_server_ = chrome_common_net::kPipelineTestServerBaseUrl;
[email protected]79bf0b72009-04-27 21:30:55866#else
867 BrowserDistribution* dist = BrowserDistribution::GetDistribution();
[email protected]d67d1052011-06-09 05:11:41868 network_stats_server_ = dist->GetNetworkStatsServer();
[email protected]adbb3762012-03-09 22:20:08869 http_pipelining_test_server_ = dist->GetHttpPipeliningTestServer();
[email protected]79bf0b72009-04-27 21:30:55870#endif
871
initial.commit09911bf2008-07-26 23:55:29872 PrefService* pref = g_browser_process->local_state();
873 DCHECK(pref);
874
[email protected]225c50842010-01-19 21:19:13875 if ((pref->GetInt64(prefs::kStabilityStatsBuildTime)
876 != MetricsLog::GetBuildTime()) ||
[email protected]ddd231e2010-06-29 20:35:19877 (pref->GetString(prefs::kStabilityStatsVersion)
[email protected]225c50842010-01-19 21:19:13878 != MetricsLog::GetVersionString())) {
[email protected]541f77922009-02-23 21:14:38879 // This is a new version, so we don't want to confuse the stats about the
880 // old version with info that we upload.
881 DiscardOldStabilityStats(pref);
882 pref->SetString(prefs::kStabilityStatsVersion,
[email protected]ddd231e2010-06-29 20:35:19883 MetricsLog::GetVersionString());
[email protected]225c50842010-01-19 21:19:13884 pref->SetInt64(prefs::kStabilityStatsBuildTime,
885 MetricsLog::GetBuildTime());
[email protected]541f77922009-02-23 21:14:38886 }
887
initial.commit09911bf2008-07-26 23:55:29888 // Update session ID
889 session_id_ = pref->GetInteger(prefs::kMetricsSessionID);
890 ++session_id_;
891 pref->SetInteger(prefs::kMetricsSessionID, session_id_);
892
initial.commit09911bf2008-07-26 23:55:29893 // Stability bookkeeping
[email protected]e73c01972008-08-13 00:18:24894 IncrementPrefValue(prefs::kStabilityLaunchCount);
initial.commit09911bf2008-07-26 23:55:29895
[email protected]e73c01972008-08-13 00:18:24896 if (!pref->GetBoolean(prefs::kStabilityExitedCleanly)) {
897 IncrementPrefValue(prefs::kStabilityCrashCount);
[email protected]c0c55e92011-09-10 18:47:30898 // Reset flag, and wait until we call LogNeedForCleanShutdown() before
899 // monitoring.
900 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
initial.commit09911bf2008-07-26 23:55:29901 }
[email protected]e73c01972008-08-13 00:18:24902
[email protected]261ab7c2013-08-19 15:04:58903#if defined(OS_WIN)
904 CountBrowserCrashDumpAttempts();
905#endif // defined(OS_WIN)
906
[email protected]e73c01972008-08-13 00:18:24907 if (!pref->GetBoolean(prefs::kStabilitySessionEndCompleted)) {
908 IncrementPrefValue(prefs::kStabilityIncompleteSessionEndCount);
[email protected]c9abf242009-07-18 06:00:38909 // This is marked false when we get a WM_ENDSESSION.
910 pref->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
initial.commit09911bf2008-07-26 23:55:29911 }
initial.commit09911bf2008-07-26 23:55:29912
[email protected]9165f742010-03-10 22:55:01913 // Initialize uptime counters.
[email protected]c68a2b9b2013-10-09 18:16:36914 const base::TimeDelta startup_uptime = GetIncrementalUptime(pref);
915 DCHECK_EQ(0, startup_uptime.InMicroseconds());
[email protected]9165f742010-03-10 22:55:01916 // For backwards compatibility, leave this intact in case Omaha is checking
917 // them. prefs::kStabilityLastTimestampSec may also be useless now.
918 // TODO(jar): Delete these if they have no uses.
[email protected]0bb1a622009-03-04 03:22:32919 pref->SetInt64(prefs::kStabilityLaunchTimeSec, Time::Now().ToTimeT());
920
921 // Bookkeeping for the uninstall metrics.
922 IncrementLongPrefsValue(prefs::kUninstallLaunchCount);
initial.commit09911bf2008-07-26 23:55:29923
[email protected]92745242009-06-12 16:52:21924 // Get stats on use of command line.
925 const CommandLine* command_line(CommandLine::ForCurrentProcess());
926 size_t common_commands = 0;
927 if (command_line->HasSwitch(switches::kUserDataDir)) {
928 ++common_commands;
929 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineDatDirCount", 1);
930 }
931
932 if (command_line->HasSwitch(switches::kApp)) {
933 ++common_commands;
934 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineAppModeCount", 1);
935 }
936
[email protected]62b4e522011-07-13 21:46:32937 size_t switch_count = command_line->GetSwitches().size();
938 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineFlagCount", switch_count);
[email protected]92745242009-06-12 16:52:21939 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineUncommonFlagCount",
[email protected]62b4e522011-07-13 21:46:32940 switch_count - common_commands);
[email protected]92745242009-06-12 16:52:21941
initial.commit09911bf2008-07-26 23:55:29942 // Kick off the process of saving the state (so the uptime numbers keep
943 // getting updated) every n minutes.
944 ScheduleNextStateSave();
945}
946
[email protected]c94d7382012-02-28 08:43:40947// static
[email protected]d33e7cc2011-09-23 01:43:56948void MetricsService::InitTaskGetHardwareClass(
[email protected]c94d7382012-02-28 08:43:40949 base::WeakPtr<MetricsService> self,
[email protected]d33e7cc2011-09-23 01:43:56950 base::MessageLoopProxy* target_loop) {
[email protected]d33e7cc2011-09-23 01:43:56951 DCHECK(BrowserThread::CurrentlyOn(BrowserThread::FILE));
952
953 std::string hardware_class;
954#if defined(OS_CHROMEOS)
955 chromeos::system::StatisticsProvider::GetInstance()->GetMachineStatistic(
956 "hardware_class", &hardware_class);
957#endif // OS_CHROMEOS
958
959 target_loop->PostTask(FROM_HERE,
960 base::Bind(&MetricsService::OnInitTaskGotHardwareClass,
[email protected]c94d7382012-02-28 08:43:40961 self, hardware_class));
[email protected]d33e7cc2011-09-23 01:43:56962}
963
964void MetricsService::OnInitTaskGotHardwareClass(
965 const std::string& hardware_class) {
[email protected]ed0fd002012-04-25 23:10:34966 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]85ed9d42010-06-08 22:37:44967 hardware_class_ = hardware_class;
[email protected]d33e7cc2011-09-23 01:43:56968
[email protected]ebd71962012-12-20 02:56:55969#if defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:56970 // Start the next part of the init task: loading plugin information.
971 PluginService::GetInstance()->GetPlugins(
972 base::Bind(&MetricsService::OnInitTaskGotPluginInfo,
[email protected]c94d7382012-02-28 08:43:40973 self_ptr_factory_.GetWeakPtr()));
[email protected]ebd71962012-12-20 02:56:55974#else
[email protected]d7bd3e52013-07-21 04:29:20975 std::vector<content::WebPluginInfo> plugin_list_empty;
[email protected]ebd71962012-12-20 02:56:55976 OnInitTaskGotPluginInfo(plugin_list_empty);
977#endif // defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:56978}
979
980void MetricsService::OnInitTaskGotPluginInfo(
[email protected]d7bd3e52013-07-21 04:29:20981 const std::vector<content::WebPluginInfo>& plugins) {
[email protected]ed0fd002012-04-25 23:10:34982 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]35fa6a22009-08-15 00:04:01983 plugins_ = plugins;
[email protected]d33e7cc2011-09-23 01:43:56984
[email protected]197c0772012-05-14 23:50:51985 // Schedules a task on a blocking pool thread to gather Google Update
986 // statistics (requires Registry reads).
987 BrowserThread::PostBlockingPoolTask(
988 FROM_HERE,
989 base::Bind(&MetricsService::InitTaskGetGoogleUpdateData,
990 self_ptr_factory_.GetWeakPtr(),
[email protected]b3a25092013-05-28 22:08:16991 base::MessageLoop::current()->message_loop_proxy()));
[email protected]197c0772012-05-14 23:50:51992}
993
994// static
995void MetricsService::InitTaskGetGoogleUpdateData(
996 base::WeakPtr<MetricsService> self,
997 base::MessageLoopProxy* target_loop) {
998 GoogleUpdateMetrics google_update_metrics;
999
1000#if defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
1001 const bool system_install = GoogleUpdateSettings::IsSystemInstall();
1002
1003 google_update_metrics.is_system_install = system_install;
1004 google_update_metrics.last_started_au =
1005 GoogleUpdateSettings::GetGoogleUpdateLastStartedAU(system_install);
1006 google_update_metrics.last_checked =
1007 GoogleUpdateSettings::GetGoogleUpdateLastChecked(system_install);
1008 GoogleUpdateSettings::GetUpdateDetailForGoogleUpdate(
1009 system_install,
1010 &google_update_metrics.google_update_data);
1011 GoogleUpdateSettings::GetUpdateDetail(
1012 system_install,
1013 &google_update_metrics.product_data);
1014#endif // defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
1015
1016 target_loop->PostTask(FROM_HERE,
1017 base::Bind(&MetricsService::OnInitTaskGotGoogleUpdateData,
1018 self, google_update_metrics));
1019}
1020
1021void MetricsService::OnInitTaskGotGoogleUpdateData(
1022 const GoogleUpdateMetrics& google_update_metrics) {
1023 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
1024
1025 google_update_metrics_ = google_update_metrics;
1026
[email protected]ed0fd002012-04-25 23:10:341027 // Start the next part of the init task: fetching performance data. This will
1028 // call into |FinishedReceivingProfilerData()| when the task completes.
1029 chrome_browser_metrics::TrackingSynchronizer::FetchProfilerDataAsynchronously(
1030 self_ptr_factory_.GetWeakPtr());
1031}
1032
[email protected]dd98f392013-02-04 13:03:221033void MetricsService::OnUserAction(const std::string& action) {
1034 if (!CanLogNotification())
1035 return;
1036
1037 log_manager_.current_log()->RecordUserAction(action.c_str());
1038 HandleIdleSinceLastTransmission(false);
1039}
1040
[email protected]ed0fd002012-04-25 23:10:341041void MetricsService::ReceivedProfilerData(
1042 const tracked_objects::ProcessDataSnapshot& process_data,
[email protected]f3b357692013-03-22 05:16:131043 int process_type) {
[email protected]ed0fd002012-04-25 23:10:341044 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
1045
1046 // Upon the first callback, create the initial log so that we can immediately
1047 // save the profiler data.
1048 if (!initial_log_.get())
1049 initial_log_.reset(new MetricsLog(client_id_, session_id_));
1050
1051 initial_log_->RecordProfilerData(process_data, process_type);
1052}
1053
1054void MetricsService::FinishedReceivingProfilerData() {
1055 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]c68a2b9b2013-10-09 18:16:361056 state_ = INIT_TASK_DONE;
1057}
1058
1059base::TimeDelta MetricsService::GetIncrementalUptime(PrefService* pref) {
1060 base::TimeTicks now = base::TimeTicks::Now();
1061 // If this is the first call, init |last_updated_time_|.
1062 if (last_updated_time_.is_null())
1063 last_updated_time_ = now;
1064 const base::TimeDelta incremental_time = now - last_updated_time_;
1065 last_updated_time_ = now;
1066
1067 const int64 incremental_time_secs = incremental_time.InSeconds();
1068 if (incremental_time_secs > 0) {
1069 int64 metrics_uptime = pref->GetInt64(prefs::kUninstallMetricsUptimeSec);
1070 metrics_uptime += incremental_time_secs;
1071 pref->SetInt64(prefs::kUninstallMetricsUptimeSec, metrics_uptime);
1072 }
1073
1074 return incremental_time;
initial.commit09911bf2008-07-26 23:55:291075}
1076
[email protected]e61003a2012-05-24 17:03:191077int MetricsService::GetLowEntropySource() {
1078 // Note that the default value for the low entropy source and the default pref
[email protected]e88be472e2013-04-26 22:36:361079 // value are both kLowEntropySourceNotSet, which is used to identify if the
1080 // value has been set or not.
1081 if (low_entropy_source_ != kLowEntropySourceNotSet)
[email protected]e61003a2012-05-24 17:03:191082 return low_entropy_source_;
1083
[email protected]9d7c4a82013-05-07 12:10:491084 PrefService* local_state = g_browser_process->local_state();
[email protected]e61003a2012-05-24 17:03:191085 const CommandLine* command_line(CommandLine::ForCurrentProcess());
1086 // Only try to load the value from prefs if the user did not request a reset.
1087 // Otherwise, skip to generating a new value.
[email protected]1862c0c2013-04-18 06:30:481088 if (!command_line->HasSwitch(switches::kResetVariationState)) {
[email protected]9556a892013-06-21 16:53:201089 int value = local_state->GetInteger(prefs::kMetricsLowEntropySource);
1090 // Old versions of the code would generate values in the range of [1, 8192],
1091 // before the range was switched to [0, 8191] and then to [0, 7999]. Map
1092 // 8192 to 0, so that the 0th bucket remains uniform, while re-generating
1093 // the low entropy source for old values in the [8000, 8191] range.
1094 if (value == 8192)
1095 value = 0;
1096 // If the value is outside the [0, kMaxLowEntropySize) range, re-generate
1097 // it below.
1098 if (value >= 0 && value < kMaxLowEntropySize) {
1099 low_entropy_source_ = value;
[email protected]0c906e92012-10-18 15:24:131100 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", false);
[email protected]e61003a2012-05-24 17:03:191101 return low_entropy_source_;
[email protected]e63a9ef2012-08-30 15:29:421102 }
[email protected]e61003a2012-05-24 17:03:191103 }
1104
[email protected]0c906e92012-10-18 15:24:131105 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", true);
[email protected]e61003a2012-05-24 17:03:191106 low_entropy_source_ = GenerateLowEntropySource();
[email protected]9d7c4a82013-05-07 12:10:491107 local_state->SetInteger(prefs::kMetricsLowEntropySource, low_entropy_source_);
1108 metrics::CachingPermutedEntropyProvider::ClearCache(local_state);
[email protected]e61003a2012-05-24 17:03:191109
1110 return low_entropy_source_;
1111}
1112
1113// static
initial.commit09911bf2008-07-26 23:55:291114std::string MetricsService::GenerateClientID() {
[email protected]7e49ad32012-06-14 14:22:071115 return base::GenerateGUID();
initial.commit09911bf2008-07-26 23:55:291116}
1117
initial.commit09911bf2008-07-26 23:55:291118//------------------------------------------------------------------------------
1119// State save methods
1120
1121void MetricsService::ScheduleNextStateSave() {
[email protected]8454aeb2011-11-19 23:38:201122 state_saver_factory_.InvalidateWeakPtrs();
initial.commit09911bf2008-07-26 23:55:291123
[email protected]b3a25092013-05-28 22:08:161124 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
[email protected]8454aeb2011-11-19 23:38:201125 base::Bind(&MetricsService::SaveLocalState,
1126 state_saver_factory_.GetWeakPtr()),
[email protected]fc4252a72012-01-12 21:58:471127 base::TimeDelta::FromMinutes(kSaveStateIntervalMinutes));
initial.commit09911bf2008-07-26 23:55:291128}
1129
1130void MetricsService::SaveLocalState() {
1131 PrefService* pref = g_browser_process->local_state();
1132 if (!pref) {
[email protected]a063c102010-07-22 22:20:191133 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291134 return;
1135 }
1136
1137 RecordCurrentState(pref);
initial.commit09911bf2008-07-26 23:55:291138
[email protected]fc4252a72012-01-12 21:58:471139 // TODO(jar):110021 Does this run down the batteries????
initial.commit09911bf2008-07-26 23:55:291140 ScheduleNextStateSave();
1141}
1142
1143
1144//------------------------------------------------------------------------------
1145// Recording control methods
1146
[email protected]410938e02012-10-24 16:33:591147void MetricsService::OpenNewLog() {
1148 DCHECK(!log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:291149
[email protected]29948262012-03-01 12:15:081150 log_manager_.BeginLoggingWithLog(new MetricsLog(client_id_, session_id_),
1151 MetricsLogManager::ONGOING_LOG);
initial.commit09911bf2008-07-26 23:55:291152 if (state_ == INITIALIZED) {
1153 // We only need to schedule that run once.
[email protected]85ed9d42010-06-08 22:37:441154 state_ = INIT_TASK_SCHEDULED;
initial.commit09911bf2008-07-26 23:55:291155
[email protected]85ed9d42010-06-08 22:37:441156 // Schedules a task on the file thread for execution of slower
1157 // initialization steps (such as plugin list generation) necessary
1158 // for sending the initial log. This avoids blocking the main UI
1159 // thread.
[email protected]ed10dd12011-12-07 12:03:421160 BrowserThread::PostDelayedTask(
1161 BrowserThread::FILE,
1162 FROM_HERE,
[email protected]d33e7cc2011-09-23 01:43:561163 base::Bind(&MetricsService::InitTaskGetHardwareClass,
[email protected]c94d7382012-02-28 08:43:401164 self_ptr_factory_.GetWeakPtr(),
[email protected]b3a25092013-05-28 22:08:161165 base::MessageLoop::current()->message_loop_proxy()),
[email protected]7e560102012-03-08 20:58:421166 base::TimeDelta::FromSeconds(kInitializationDelaySeconds));
initial.commit09911bf2008-07-26 23:55:291167 }
1168}
1169
[email protected]410938e02012-10-24 16:33:591170void MetricsService::CloseCurrentLog() {
[email protected]cac267c2011-09-29 15:18:101171 if (!log_manager_.current_log())
initial.commit09911bf2008-07-26 23:55:291172 return;
1173
[email protected]68475e602008-08-22 03:21:151174 // TODO(jar): Integrate bounds on log recording more consistently, so that we
1175 // can stop recording logs that are too big much sooner.
[email protected]cac267c2011-09-29 15:18:101176 if (log_manager_.current_log()->num_events() > kEventLimit) {
[email protected]553dba62009-02-24 19:08:231177 UMA_HISTOGRAM_COUNTS("UMA.Discarded Log Events",
[email protected]cac267c2011-09-29 15:18:101178 log_manager_.current_log()->num_events());
1179 log_manager_.DiscardCurrentLog();
[email protected]410938e02012-10-24 16:33:591180 OpenNewLog(); // Start trivial log to hold our histograms.
[email protected]68475e602008-08-22 03:21:151181 }
1182
[email protected]cac267c2011-09-29 15:18:101183 // Adds to ongoing logs.
1184 log_manager_.current_log()->set_hardware_class(hardware_class_);
[email protected]accdfa62011-09-20 01:56:521185
[email protected]0b33f80b2008-12-17 21:34:361186 // Put incremental data (histogram deltas, and realtime stats deltas) at the
[email protected]147bbc0b2009-01-06 19:37:401187 // end of all log transmissions (initial log handles this separately).
[email protected]024b5cd2011-05-27 03:29:381188 // RecordIncrementalStabilityElements only exists on the derived
1189 // MetricsLog class.
[email protected]279703f2012-01-20 22:23:261190 MetricsLog* current_log =
1191 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]024b5cd2011-05-27 03:29:381192 DCHECK(current_log);
[email protected]197c0772012-05-14 23:50:511193 current_log->RecordEnvironmentProto(plugins_, google_update_metrics_);
[email protected]c68a2b9b2013-10-09 18:16:361194 PrefService* pref = g_browser_process->local_state();
1195 current_log->RecordIncrementalStabilityElements(plugins_,
1196 GetIncrementalUptime(pref));
[email protected]024b5cd2011-05-27 03:29:381197 RecordCurrentHistograms();
initial.commit09911bf2008-07-26 23:55:291198
[email protected]29948262012-03-01 12:15:081199 log_manager_.FinishCurrentLog();
initial.commit09911bf2008-07-26 23:55:291200}
1201
[email protected]cac267c2011-09-29 15:18:101202void MetricsService::PushPendingLogsToPersistentStorage() {
initial.commit09911bf2008-07-26 23:55:291203 if (state_ < INITIAL_LOG_READY)
[email protected]28ab7f92009-01-06 21:39:041204 return; // We didn't and still don't have time to get plugin list etc.
initial.commit09911bf2008-07-26 23:55:291205
[email protected]cac267c2011-09-29 15:18:101206 if (log_manager_.has_staged_log()) {
[email protected]7d41ae6d2012-06-26 08:53:031207 // We may race here, and send second copy of the log later.
[email protected]dc61fe92012-06-12 00:13:501208 MetricsLogManager::StoreType store_type;
[email protected]e3eb0c42013-04-18 06:18:581209 if (current_fetch_.get())
[email protected]dc61fe92012-06-12 00:13:501210 store_type = MetricsLogManager::PROVISIONAL_STORE;
1211 else
1212 store_type = MetricsLogManager::NORMAL_STORE;
[email protected]e7508d82012-05-03 15:59:531213 log_manager_.StoreStagedLogAsUnsent(store_type);
initial.commit09911bf2008-07-26 23:55:291214 }
[email protected]cac267c2011-09-29 15:18:101215 DCHECK(!log_manager_.has_staged_log());
[email protected]410938e02012-10-24 16:33:591216 CloseCurrentLog();
initial.commit09911bf2008-07-26 23:55:291217 StoreUnsentLogs();
[email protected]7d41ae6d2012-06-26 08:53:031218
1219 // If there was a staged and/or current log, then there is now at least one
1220 // log waiting to be uploaded.
1221 if (log_manager_.has_unsent_logs())
1222 state_ = SENDING_OLD_LOGS;
initial.commit09911bf2008-07-26 23:55:291223}
1224
1225//------------------------------------------------------------------------------
1226// Transmission of logs methods
1227
[email protected]7f7f1962011-04-20 15:58:161228void MetricsService::StartSchedulerIfNecessary() {
[email protected]410938e02012-10-24 16:33:591229 // Never schedule cutting or uploading of logs in test mode.
1230 if (test_mode_active_)
1231 return;
1232
1233 // Even if reporting is disabled, the scheduler is needed to trigger the
1234 // creation of the initial log, which must be done in order for any logs to be
1235 // persisted on shutdown or backgrounding.
1236 if (recording_active() && (reporting_active() || state_ < INITIAL_LOG_READY))
[email protected]7f7f1962011-04-20 15:58:161237 scheduler_->Start();
initial.commit09911bf2008-07-26 23:55:291238}
1239
[email protected]7f7f1962011-04-20 15:58:161240void MetricsService::StartScheduledUpload() {
[email protected]cd1ac712012-06-26 08:26:471241 // If we're getting no notifications, then the log won't have much in it, and
1242 // it's possible the computer is about to go to sleep, so don't upload and
1243 // stop the scheduler.
[email protected]410938e02012-10-24 16:33:591244 // If recording has been turned off, the scheduler doesn't need to run.
1245 // If reporting is off, proceed if the initial log hasn't been created, since
1246 // that has to happen in order for logs to be cut and stored when persisting.
[email protected]cd1ac712012-06-26 08:26:471247 // TODO(stuartmorgan): Call Stop() on the schedule when reporting and/or
1248 // recording are turned off instead of letting it fire and then aborting.
1249 if (idle_since_last_transmission_ ||
[email protected]410938e02012-10-24 16:33:591250 !recording_active() ||
1251 (!reporting_active() && state_ >= INITIAL_LOG_READY)) {
[email protected]7f7f1962011-04-20 15:58:161252 scheduler_->Stop();
1253 scheduler_->UploadCancelled();
1254 return;
1255 }
1256
[email protected]c15faf372012-07-11 06:01:341257 // If the callback was to upload an old log, but there no longer is one,
1258 // just report success back to the scheduler to begin the ongoing log
1259 // callbacks.
1260 // TODO(stuartmorgan): Consider removing the distinction between
1261 // SENDING_OLD_LOGS and SENDING_CURRENT_LOGS to simplify the state machine
1262 // now that the log upload flow is the same for both modes.
1263 if (state_ == SENDING_OLD_LOGS && !log_manager_.has_unsent_logs()) {
1264 state_ = SENDING_CURRENT_LOGS;
1265 scheduler_->UploadFinished(true /* healthy */, false /* no unsent logs */);
1266 return;
1267 }
[email protected]cd1ac712012-06-26 08:26:471268 // If there are unsent logs, send the next one. If not, start the asynchronous
1269 // process of finalizing the current log for upload.
1270 if (state_ == SENDING_OLD_LOGS) {
1271 DCHECK(log_manager_.has_unsent_logs());
1272 log_manager_.StageNextLogForUpload();
1273 SendStagedLog();
1274 } else {
1275 StartFinalLogInfoCollection();
1276 }
[email protected]29948262012-03-01 12:15:081277}
1278
1279void MetricsService::StartFinalLogInfoCollection() {
1280 // Begin the multi-step process of collecting memory usage histograms:
1281 // First spawn a task to collect the memory details; when that task is
1282 // finished, it will call OnMemoryDetailCollectionDone. That will in turn
1283 // call HistogramSynchronization to collect histograms from all renderers and
1284 // then call OnHistogramSynchronizationDone to continue processing.
[email protected]d119f222012-06-08 02:33:271285 DCHECK(!waiting_for_asynchronous_reporting_step_);
1286 waiting_for_asynchronous_reporting_step_ = true;
[email protected]7f7f1962011-04-20 15:58:161287
[email protected]2226c222011-11-22 00:08:401288 base::Closure callback =
1289 base::Bind(&MetricsService::OnMemoryDetailCollectionDone,
[email protected]c94d7382012-02-28 08:43:401290 self_ptr_factory_.GetWeakPtr());
[email protected]84c988a2011-04-19 17:56:331291
[email protected]2226c222011-11-22 00:08:401292 scoped_refptr<MetricsMemoryDetails> details(
1293 new MetricsMemoryDetails(callback));
[email protected]4306df72012-04-20 18:58:571294 details->StartFetch(MemoryDetails::UPDATE_USER_METRICS);
[email protected]84c988a2011-04-19 17:56:331295
1296 // Collect WebCore cache information to put into a histogram.
[email protected]f3b1a082011-11-18 00:34:301297 for (content::RenderProcessHost::iterator i(
1298 content::RenderProcessHost::AllHostsIterator());
[email protected]84c988a2011-04-19 17:56:331299 !i.IsAtEnd(); i.Advance())
[email protected]2ccf45c2011-08-19 23:35:501300 i.GetCurrentValue()->Send(new ChromeViewMsg_GetCacheResourceStats());
[email protected]84c988a2011-04-19 17:56:331301}
1302
1303void MetricsService::OnMemoryDetailCollectionDone() {
[email protected]c9a3ef82009-05-28 22:02:461304 DCHECK(IsSingleThreaded());
[email protected]7f7f1962011-04-20 15:58:161305 // This function should only be called as the callback from an ansynchronous
1306 // step.
[email protected]d119f222012-06-08 02:33:271307 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]c9a3ef82009-05-28 22:02:461308
[email protected]c9a3ef82009-05-28 22:02:461309 // Create a callback_task for OnHistogramSynchronizationDone.
[email protected]2226c222011-11-22 00:08:401310 base::Closure callback = base::Bind(
1311 &MetricsService::OnHistogramSynchronizationDone,
[email protected]c94d7382012-02-28 08:43:401312 self_ptr_factory_.GetWeakPtr());
[email protected]c9a3ef82009-05-28 22:02:461313
[email protected]a3079832013-10-24 20:29:361314 base::TimeDelta timeout =
1315 base::TimeDelta::FromMilliseconds(kMaxHistogramGatheringWaitDuration);
1316
1317 DCHECK_EQ(num_async_histogram_fetches_in_progress_, 0);
1318
1319#if defined(OS_ANDROID)
1320 // Android has no service process.
1321 num_async_histogram_fetches_in_progress_ = 1;
1322#else // OS_ANDROID
1323 num_async_histogram_fetches_in_progress_ = 2;
1324 // Run requests to service and content in parallel.
1325 if (!ServiceProcessControl::GetInstance()->GetHistograms(callback, timeout)) {
1326 // Assume |num_async_histogram_fetches_in_progress_| is not changed by
1327 // |GetHistograms()|.
1328 DCHECK_EQ(num_async_histogram_fetches_in_progress_, 2);
1329 // Assign |num_async_histogram_fetches_in_progress_| above and decrement it
1330 // here to make code work even if |GetHistograms()| fired |callback|.
1331 --num_async_histogram_fetches_in_progress_;
1332 }
1333#endif // OS_ANDROID
1334
[email protected]c9a3ef82009-05-28 22:02:461335 // Set up the callback to task to call after we receive histograms from all
[email protected]83ab4a282012-07-12 18:19:451336 // child processes. Wait time specifies how long to wait before absolutely
[email protected]c9a3ef82009-05-28 22:02:461337 // calling us back on the task.
[email protected]a3079832013-10-24 20:29:361338 content::FetchHistogramsAsynchronously(base::MessageLoop::current(), callback,
1339 timeout);
[email protected]c9a3ef82009-05-28 22:02:461340}
1341
1342void MetricsService::OnHistogramSynchronizationDone() {
initial.commit09911bf2008-07-26 23:55:291343 DCHECK(IsSingleThreaded());
[email protected]29948262012-03-01 12:15:081344 // This function should only be called as the callback from an ansynchronous
1345 // step.
[email protected]d119f222012-06-08 02:33:271346 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]a3079832013-10-24 20:29:361347 DCHECK_GT(num_async_histogram_fetches_in_progress_, 0);
1348
1349 // Check if all expected requests finished.
1350 if (--num_async_histogram_fetches_in_progress_ > 0)
1351 return;
initial.commit09911bf2008-07-26 23:55:291352
[email protected]d119f222012-06-08 02:33:271353 waiting_for_asynchronous_reporting_step_ = false;
[email protected]29948262012-03-01 12:15:081354 OnFinalLogInfoCollectionDone();
1355}
1356
1357void MetricsService::OnFinalLogInfoCollectionDone() {
[email protected]7f7f1962011-04-20 15:58:161358 // If somehow there is a fetch in progress, we return and hope things work
1359 // out. The scheduler isn't informed since if this happens, the scheduler
1360 // will get a response from the upload.
[email protected]e3eb0c42013-04-18 06:18:581361 DCHECK(!current_fetch_.get());
1362 if (current_fetch_.get())
[email protected]7f7f1962011-04-20 15:58:161363 return;
1364
[email protected]cd1ac712012-06-26 08:26:471365 // Abort if metrics were turned off during the final info gathering.
[email protected]410938e02012-10-24 16:33:591366 if (!recording_active()) {
[email protected]7f7f1962011-04-20 15:58:161367 scheduler_->Stop();
1368 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071369 return;
1370 }
1371
[email protected]cd1ac712012-06-26 08:26:471372 StageNewLog();
[email protected]410938e02012-10-24 16:33:591373
1374 // If logs shouldn't be uploaded, stop here. It's important that this check
1375 // be after StageNewLog(), otherwise the previous logs will never be loaded,
1376 // and thus the open log won't be persisted.
1377 // TODO(stuartmorgan): This is unnecessarily complicated; restructure loading
1378 // of previous logs to not require running part of the upload logic.
1379 // http://crbug.com/157337
1380 if (!reporting_active()) {
1381 scheduler_->Stop();
1382 scheduler_->UploadCancelled();
1383 return;
1384 }
1385
[email protected]29948262012-03-01 12:15:081386 SendStagedLog();
1387}
1388
[email protected]cd1ac712012-06-26 08:26:471389void MetricsService::StageNewLog() {
[email protected]29948262012-03-01 12:15:081390 if (log_manager_.has_staged_log())
1391 return;
1392
1393 switch (state_) {
1394 case INITIALIZED:
1395 case INIT_TASK_SCHEDULED: // We should be further along by now.
[email protected]dc61fe92012-06-12 00:13:501396 NOTREACHED();
[email protected]29948262012-03-01 12:15:081397 return;
1398
1399 case INIT_TASK_DONE:
[email protected]29948262012-03-01 12:15:081400 PrepareInitialLog();
[email protected]ed0fd002012-04-25 23:10:341401 DCHECK_EQ(INIT_TASK_DONE, state_);
[email protected]29948262012-03-01 12:15:081402 log_manager_.LoadPersistedUnsentLogs();
1403 state_ = INITIAL_LOG_READY;
1404 break;
1405
1406 case SENDING_OLD_LOGS:
[email protected]cd1ac712012-06-26 08:26:471407 NOTREACHED(); // Shouldn't be staging a new log during old log sending.
1408 return;
[email protected]29948262012-03-01 12:15:081409
1410 case SENDING_CURRENT_LOGS:
[email protected]410938e02012-10-24 16:33:591411 CloseCurrentLog();
1412 OpenNewLog();
[email protected]29948262012-03-01 12:15:081413 log_manager_.StageNextLogForUpload();
1414 break;
1415
1416 default:
1417 NOTREACHED();
1418 return;
1419 }
1420
1421 DCHECK(log_manager_.has_staged_log());
1422}
1423
1424void MetricsService::PrepareInitialLog() {
[email protected]ed0fd002012-04-25 23:10:341425 DCHECK_EQ(INIT_TASK_DONE, state_);
[email protected]29948262012-03-01 12:15:081426
[email protected]ed0fd002012-04-25 23:10:341427 DCHECK(initial_log_.get());
1428 initial_log_->set_hardware_class(hardware_class_);
[email protected]c68a2b9b2013-10-09 18:16:361429 PrefService* pref = g_browser_process->local_state();
1430 initial_log_->RecordEnvironment(plugins_, google_update_metrics_,
1431 GetIncrementalUptime(pref));
[email protected]29948262012-03-01 12:15:081432
1433 // Histograms only get written to the current log, so make the new log current
1434 // before writing them.
1435 log_manager_.PauseCurrentLog();
[email protected]ed0fd002012-04-25 23:10:341436 log_manager_.BeginLoggingWithLog(initial_log_.release(),
1437 MetricsLogManager::INITIAL_LOG);
[email protected]29948262012-03-01 12:15:081438 RecordCurrentHistograms();
1439 log_manager_.FinishCurrentLog();
1440 log_manager_.ResumePausedLog();
1441
1442 DCHECK(!log_manager_.has_staged_log());
1443 log_manager_.StageNextLogForUpload();
1444}
1445
1446void MetricsService::StoreUnsentLogs() {
1447 if (state_ < INITIAL_LOG_READY)
1448 return; // We never Recalled the prior unsent logs.
1449
1450 log_manager_.PersistUnsentLogs();
1451}
1452
1453void MetricsService::SendStagedLog() {
1454 DCHECK(log_manager_.has_staged_log());
1455
[email protected]cac267c2011-09-29 15:18:101456 PrepareFetchWithStagedLog();
[email protected]d01b8732008-10-16 02:18:071457
[email protected]e3eb0c42013-04-18 06:18:581458 bool upload_created = (current_fetch_.get() != NULL);
[email protected]d6bebb92012-06-13 23:14:551459 UMA_HISTOGRAM_BOOLEAN("UMA.UploadCreation", upload_created);
1460 if (!upload_created) {
[email protected]d01b8732008-10-16 02:18:071461 // Compression failed, and log discarded :-/.
[email protected]dc61fe92012-06-12 00:13:501462 // Skip this upload and hope things work out next time.
[email protected]cac267c2011-09-29 15:18:101463 log_manager_.DiscardStagedLog();
[email protected]7f7f1962011-04-20 15:58:161464 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071465 return;
1466 }
1467
[email protected]d119f222012-06-08 02:33:271468 DCHECK(!waiting_for_asynchronous_reporting_step_);
[email protected]d119f222012-06-08 02:33:271469 waiting_for_asynchronous_reporting_step_ = true;
[email protected]dc61fe92012-06-12 00:13:501470
[email protected]e3eb0c42013-04-18 06:18:581471 current_fetch_->Start();
[email protected]d01b8732008-10-16 02:18:071472
1473 HandleIdleSinceLastTransmission(true);
1474}
1475
[email protected]cac267c2011-09-29 15:18:101476void MetricsService::PrepareFetchWithStagedLog() {
[email protected]dc61fe92012-06-12 00:13:501477 DCHECK(log_manager_.has_staged_log());
[email protected]cac78842008-11-27 01:02:201478
[email protected]fe58acc22012-02-29 01:29:581479 // Prepare the protobuf version.
[email protected]e3eb0c42013-04-18 06:18:581480 DCHECK(!current_fetch_.get());
[email protected]5f3e1642013-05-05 03:37:341481 if (log_manager_.has_staged_log()) {
[email protected]e3eb0c42013-04-18 06:18:581482 current_fetch_.reset(net::URLFetcher::Create(
[email protected]5f3e1642013-05-05 03:37:341483 GURL(kServerUrl), net::URLFetcher::POST, this));
[email protected]e3eb0c42013-04-18 06:18:581484 current_fetch_->SetRequestContext(
[email protected]fe58acc22012-02-29 01:29:581485 g_browser_process->system_request_context());
[email protected]537c638d2013-07-04 00:49:191486
[email protected]8df71322013-09-13 18:40:001487 std::string log_text = log_manager_.staged_log_text();
1488 std::string compressed_log_text;
1489 bool compression_successful = chrome::GzipCompress(log_text,
1490 &compressed_log_text);
1491 DCHECK(compression_successful);
1492 if (compression_successful) {
1493 current_fetch_->SetUploadData(kMimeType, compressed_log_text);
1494 // Tell the server that we're uploading gzipped protobufs.
1495 current_fetch_->SetExtraRequestHeaders("content-encoding: gzip");
[email protected]cfee9aa52013-10-19 17:53:051496 const std::string hash =
1497 base::HexEncode(log_manager_.staged_log_hash().data(),
1498 log_manager_.staged_log_hash().size());
1499 DCHECK(!hash.empty());
1500 current_fetch_->AddExtraRequestHeader("X-Chrome-UMA-Log-SHA1: " + hash);
[email protected]8df71322013-09-13 18:40:001501 UMA_HISTOGRAM_PERCENTAGE(
1502 "UMA.ProtoCompressionRatio",
1503 100 * compressed_log_text.size() / log_text.size());
1504 UMA_HISTOGRAM_CUSTOM_COUNTS(
1505 "UMA.ProtoGzippedKBSaved",
1506 (log_text.size() - compressed_log_text.size()) / 1024,
1507 1, 2000, 50);
[email protected]537c638d2013-07-04 00:49:191508 }
[email protected]537c638d2013-07-04 00:49:191509
[email protected]fe58acc22012-02-29 01:29:581510 // We already drop cookies server-side, but we might as well strip them out
1511 // client-side as well.
[email protected]e3eb0c42013-04-18 06:18:581512 current_fetch_->SetLoadFlags(net::LOAD_DO_NOT_SAVE_COOKIES |
1513 net::LOAD_DO_NOT_SEND_COOKIES);
[email protected]fe58acc22012-02-29 01:29:581514 }
initial.commit09911bf2008-07-26 23:55:291515}
1516
[email protected]10c2d692012-05-11 05:32:231517void MetricsService::OnURLFetchComplete(const net::URLFetcher* source) {
[email protected]d119f222012-06-08 02:33:271518 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]fe58acc22012-02-29 01:29:581519
1520 // We're not allowed to re-use the existing |URLFetcher|s, so free them here.
[email protected]e3eb0c42013-04-18 06:18:581521 // Note however that |source| is aliased to the fetcher, so we should be
[email protected]4266def22012-05-17 01:02:401522 // careful not to delete it too early.
[email protected]e3eb0c42013-04-18 06:18:581523 DCHECK_EQ(current_fetch_.get(), source);
1524 scoped_ptr<net::URLFetcher> s(current_fetch_.Pass());
[email protected]fe58acc22012-02-29 01:29:581525
[email protected]dc61fe92012-06-12 00:13:501526 int response_code = source->GetResponseCode();
[email protected]fe58acc22012-02-29 01:29:581527
[email protected]dc61fe92012-06-12 00:13:501528 // Log a histogram to track response success vs. failure rates.
[email protected]e3eb0c42013-04-18 06:18:581529 UMA_HISTOGRAM_ENUMERATION("UMA.UploadResponseStatus.Protobuf",
1530 ResponseCodeToStatus(response_code),
1531 NUM_RESPONSE_STATUSES);
[email protected]fe58acc22012-02-29 01:29:581532
[email protected]e7508d82012-05-03 15:59:531533 // If the upload was provisionally stored, drop it now that the upload is
1534 // known to have gone through.
1535 log_manager_.DiscardLastProvisionalStore();
initial.commit09911bf2008-07-26 23:55:291536
[email protected]dc61fe92012-06-12 00:13:501537 bool upload_succeeded = response_code == 200;
[email protected]7f7f1962011-04-20 15:58:161538
[email protected]0eb34fee2009-01-21 08:04:381539 // Provide boolean for error recovery (allow us to ignore response_code).
[email protected]dc6f4962009-02-13 01:25:501540 bool discard_log = false;
[email protected]5f3e1642013-05-05 03:37:341541 const size_t log_size = log_manager_.staged_log_text().length();
[email protected]dc61fe92012-06-12 00:13:501542 if (!upload_succeeded && log_size > kUploadLogAvoidRetransmitSize) {
1543 UMA_HISTOGRAM_COUNTS("UMA.Large Rejected Log was Discarded",
1544 static_cast<int>(log_size));
[email protected]0eb34fee2009-01-21 08:04:381545 discard_log = true;
[email protected]dc61fe92012-06-12 00:13:501546 } else if (response_code == 400) {
[email protected]0eb34fee2009-01-21 08:04:381547 // Bad syntax. Retransmission won't work.
[email protected]0eb34fee2009-01-21 08:04:381548 discard_log = true;
[email protected]68475e602008-08-22 03:21:151549 }
1550
[email protected]e3eb0c42013-04-18 06:18:581551 if (upload_succeeded || discard_log)
[email protected]5f3e1642013-05-05 03:37:341552 log_manager_.DiscardStagedLog();
[email protected]dc61fe92012-06-12 00:13:501553
1554 waiting_for_asynchronous_reporting_step_ = false;
1555
1556 if (!log_manager_.has_staged_log()) {
initial.commit09911bf2008-07-26 23:55:291557 switch (state_) {
1558 case INITIAL_LOG_READY:
[email protected]cd1ac712012-06-26 08:26:471559 state_ = log_manager_.has_unsent_logs() ? SENDING_OLD_LOGS
1560 : SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291561 break;
1562
initial.commit09911bf2008-07-26 23:55:291563 case SENDING_OLD_LOGS:
[email protected]d53e2232011-06-30 15:54:571564 // Store the updated list to disk now that the removed log is uploaded.
initial.commit09911bf2008-07-26 23:55:291565 StoreUnsentLogs();
[email protected]cd1ac712012-06-26 08:26:471566 if (!log_manager_.has_unsent_logs())
1567 state_ = SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291568 break;
1569
1570 case SENDING_CURRENT_LOGS:
1571 break;
1572
1573 default:
[email protected]a063c102010-07-22 22:20:191574 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291575 break;
1576 }
[email protected]d01b8732008-10-16 02:18:071577
[email protected]cac267c2011-09-29 15:18:101578 if (log_manager_.has_unsent_logs())
[email protected]ed0fd002012-04-25 23:10:341579 DCHECK_LT(state_, SENDING_CURRENT_LOGS);
initial.commit09911bf2008-07-26 23:55:291580 }
[email protected]252873ef2008-08-04 21:59:451581
[email protected]7f7f1962011-04-20 15:58:161582 // Error 400 indicates a problem with the log, not with the server, so
1583 // don't consider that a sign that the server is in trouble.
[email protected]dc61fe92012-06-12 00:13:501584 bool server_is_healthy = upload_succeeded || response_code == 400;
[email protected]dc61fe92012-06-12 00:13:501585 scheduler_->UploadFinished(server_is_healthy, log_manager_.has_unsent_logs());
[email protected]d67d1052011-06-09 05:11:411586
1587 // Collect network stats if UMA upload succeeded.
[email protected]b8ddb052012-04-19 02:36:061588 IOThread* io_thread = g_browser_process->io_thread();
1589 if (server_is_healthy && io_thread) {
1590 chrome_browser_net::CollectNetworkStats(network_stats_server_, io_thread);
[email protected]adbb3762012-03-09 22:20:081591 chrome_browser_net::CollectPipeliningCapabilityStatsOnUIThread(
[email protected]b8ddb052012-04-19 02:36:061592 http_pipelining_test_server_, io_thread);
[email protected]aa312812013-04-30 19:46:051593#if defined(OS_WIN)
1594 chrome::CollectTimeTicksStats();
1595#endif
[email protected]adbb3762012-03-09 22:20:081596 }
initial.commit09911bf2008-07-26 23:55:291597}
1598
[email protected]57ecc4b2010-08-11 03:02:511599void MetricsService::IncrementPrefValue(const char* path) {
[email protected]e73c01972008-08-13 00:18:241600 PrefService* pref = g_browser_process->local_state();
1601 DCHECK(pref);
1602 int value = pref->GetInteger(path);
1603 pref->SetInteger(path, value + 1);
1604}
1605
[email protected]57ecc4b2010-08-11 03:02:511606void MetricsService::IncrementLongPrefsValue(const char* path) {
[email protected]0bb1a622009-03-04 03:22:321607 PrefService* pref = g_browser_process->local_state();
1608 DCHECK(pref);
1609 int64 value = pref->GetInt64(path);
[email protected]b42c5e42010-06-03 20:43:251610 pref->SetInt64(path, value + 1);
[email protected]0bb1a622009-03-04 03:22:321611}
1612
[email protected]752a5262013-06-23 14:53:421613void MetricsService::LogLoadStarted(content::WebContents* web_contents) {
[email protected]5d490e42012-08-30 05:16:431614 content::RecordAction(content::UserMetricsAction("PageLoad"));
[email protected]dd8d12a2011-09-02 02:10:151615 HISTOGRAM_ENUMERATION("Chrome.UmaPageloadCounter", 1, 2);
[email protected]e73c01972008-08-13 00:18:241616 IncrementPrefValue(prefs::kStabilityPageLoadCount);
[email protected]0bb1a622009-03-04 03:22:321617 IncrementLongPrefsValue(prefs::kUninstallMetricsPageLoadCount);
[email protected]0b33f80b2008-12-17 21:34:361618 // We need to save the prefs, as page load count is a critical stat, and it
1619 // might be lost due to a crash :-(.
initial.commit09911bf2008-07-26 23:55:291620}
1621
[email protected]c3721482012-03-23 16:21:481622void MetricsService::LogRendererCrash(content::RenderProcessHost* host,
1623 base::TerminationStatus status,
[email protected]f1675202012-07-09 15:18:001624 int exit_code) {
[email protected]f3b1a082011-11-18 00:34:301625 Profile* profile = Profile::FromBrowserContext(host->GetBrowserContext());
[email protected]6f371442011-11-09 06:45:461626 ExtensionService* service = profile->GetExtensionService();
1627 bool was_extension_process =
[email protected]f3b1a082011-11-18 00:34:301628 service && service->process_map()->Contains(host->GetID());
[email protected]c3721482012-03-23 16:21:481629 if (status == base::TERMINATION_STATUS_PROCESS_CRASHED ||
1630 status == base::TERMINATION_STATUS_ABNORMAL_TERMINATION) {
[email protected]d7c1fa62012-06-15 23:35:301631 if (was_extension_process) {
[email protected]718eab62011-10-05 21:16:521632 IncrementPrefValue(prefs::kStabilityExtensionRendererCrashCount);
[email protected]d7c1fa62012-06-15 23:35:301633
[email protected]1026afd2013-03-20 14:28:541634 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Extension",
1635 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301636 } else {
[email protected]718eab62011-10-05 21:16:521637 IncrementPrefValue(prefs::kStabilityRendererCrashCount);
initial.commit09911bf2008-07-26 23:55:291638
[email protected]1026afd2013-03-20 14:28:541639 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Renderer",
1640 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301641 }
1642
[email protected]718eab62011-10-05 21:16:521643 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildCrashes",
1644 was_extension_process ? 2 : 1);
[email protected]c3721482012-03-23 16:21:481645 } else if (status == base::TERMINATION_STATUS_PROCESS_WAS_KILLED) {
[email protected]718eab62011-10-05 21:16:521646 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildKills",
1647 was_extension_process ? 2 : 1);
[email protected]f1675202012-07-09 15:18:001648 } else if (status == base::TERMINATION_STATUS_STILL_RUNNING) {
1649 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.DisconnectedAlive",
[email protected]718eab62011-10-05 21:16:521650 was_extension_process ? 2 : 1);
1651 }
1652 }
[email protected]1f085622009-12-04 05:33:451653
initial.commit09911bf2008-07-26 23:55:291654void MetricsService::LogRendererHang() {
[email protected]e73c01972008-08-13 00:18:241655 IncrementPrefValue(prefs::kStabilityRendererHangCount);
initial.commit09911bf2008-07-26 23:55:291656}
1657
[email protected]c0c55e92011-09-10 18:47:301658bool MetricsService::UmaMetricsProperlyShutdown() {
1659 CHECK(clean_shutdown_status_ == CLEANLY_SHUTDOWN ||
1660 clean_shutdown_status_ == NEED_TO_SHUTDOWN);
1661 return clean_shutdown_status_ == CLEANLY_SHUTDOWN;
1662}
1663
[email protected]466f3c12011-03-23 21:20:381664void MetricsService::LogCleanShutdown() {
[email protected]acd55b32011-09-05 17:35:311665 // Redundant hack to write pref ASAP.
[email protected]84c384e2013-03-01 23:20:191666 MarkAppCleanShutdownAndCommit();
1667
[email protected]c0c55e92011-09-10 18:47:301668 // Redundant setting to assure that we always reset this value at shutdown
1669 // (and that we don't use some alternate path, and not call LogCleanShutdown).
1670 clean_shutdown_status_ = CLEANLY_SHUTDOWN;
[email protected]acd55b32011-09-05 17:35:311671
[email protected]466f3c12011-03-23 21:20:381672 RecordBooleanPrefValue(prefs::kStabilityExitedCleanly, true);
1673}
1674
[email protected]c1834a92011-01-21 18:21:031675#if defined(OS_CHROMEOS)
1676void MetricsService::LogChromeOSCrash(const std::string &crash_type) {
1677 if (crash_type == "user")
1678 IncrementPrefValue(prefs::kStabilityOtherUserCrashCount);
1679 else if (crash_type == "kernel")
1680 IncrementPrefValue(prefs::kStabilityKernelCrashCount);
1681 else if (crash_type == "uncleanshutdown")
1682 IncrementPrefValue(prefs::kStabilitySystemUncleanShutdownCount);
1683 else
1684 NOTREACHED() << "Unexpected Chrome OS crash type " << crash_type;
1685 // Wake up metrics logs sending if necessary now that new
1686 // log data is available.
1687 HandleIdleSinceLastTransmission(false);
1688}
1689#endif // OS_CHROMEOS
1690
[email protected]650b2d52013-02-10 03:41:451691void MetricsService::LogPluginLoadingError(const base::FilePath& plugin_path) {
[email protected]d7bd3e52013-07-21 04:29:201692 content::WebPluginInfo plugin;
[email protected]cd937072012-07-02 09:00:291693 bool success =
1694 content::PluginService::GetInstance()->GetPluginInfoByPath(plugin_path,
1695 &plugin);
1696 DCHECK(success);
1697 ChildProcessStats& stats = child_process_stats_buffer_[plugin.name];
1698 // Initialize the type if this entry is new.
1699 if (stats.process_type == content::PROCESS_TYPE_UNKNOWN) {
1700 // The plug-in process might not actually of type PLUGIN (which means
1701 // NPAPI), but we only care that it is *a* plug-in process.
1702 stats.process_type = content::PROCESS_TYPE_PLUGIN;
1703 } else {
1704 DCHECK(IsPluginProcess(stats.process_type));
1705 }
1706 stats.loading_errors++;
1707}
1708
[email protected]f4eaf7b92013-02-28 22:00:401709MetricsService::ChildProcessStats& MetricsService::GetChildProcessStats(
1710 const content::ChildProcessData& data) {
1711 const string16& child_name = data.name;
[email protected]f3b357692013-03-22 05:16:131712 if (!ContainsKey(child_process_stats_buffer_, child_name)) {
1713 child_process_stats_buffer_[child_name] =
1714 ChildProcessStats(data.process_type);
1715 }
[email protected]f4eaf7b92013-02-28 22:00:401716 return child_process_stats_buffer_[child_name];
initial.commit09911bf2008-07-26 23:55:291717}
1718
initial.commit09911bf2008-07-26 23:55:291719void MetricsService::RecordPluginChanges(PrefService* pref) {
[email protected]f8628c22011-04-05 12:10:181720 ListPrefUpdate update(pref, prefs::kStabilityPluginStats);
1721 ListValue* plugins = update.Get();
initial.commit09911bf2008-07-26 23:55:291722 DCHECK(plugins);
1723
1724 for (ListValue::iterator value_iter = plugins->begin();
1725 value_iter != plugins->end(); ++value_iter) {
1726 if (!(*value_iter)->IsType(Value::TYPE_DICTIONARY)) {
[email protected]a063c102010-07-22 22:20:191727 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291728 continue;
1729 }
1730
1731 DictionaryValue* plugin_dict = static_cast<DictionaryValue*>(*value_iter);
[email protected]57ecc4b2010-08-11 03:02:511732 std::string plugin_name;
[email protected]8e50b602009-03-03 22:59:431733 plugin_dict->GetString(prefs::kStabilityPluginName, &plugin_name);
[email protected]6470ee8f2009-03-03 20:46:401734 if (plugin_name.empty()) {
[email protected]a063c102010-07-22 22:20:191735 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291736 continue;
1737 }
1738
[email protected]57ecc4b2010-08-11 03:02:511739 // TODO(viettrungluu): remove conversions
[email protected]68b9e72b2011-08-05 23:08:221740 string16 name16 = UTF8ToUTF16(plugin_name);
1741 if (child_process_stats_buffer_.find(name16) ==
1742 child_process_stats_buffer_.end()) {
initial.commit09911bf2008-07-26 23:55:291743 continue;
[email protected]68b9e72b2011-08-05 23:08:221744 }
initial.commit09911bf2008-07-26 23:55:291745
[email protected]68b9e72b2011-08-05 23:08:221746 ChildProcessStats stats = child_process_stats_buffer_[name16];
initial.commit09911bf2008-07-26 23:55:291747 if (stats.process_launches) {
1748 int launches = 0;
[email protected]8e50b602009-03-03 22:59:431749 plugin_dict->GetInteger(prefs::kStabilityPluginLaunches, &launches);
initial.commit09911bf2008-07-26 23:55:291750 launches += stats.process_launches;
[email protected]8e50b602009-03-03 22:59:431751 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches, launches);
initial.commit09911bf2008-07-26 23:55:291752 }
1753 if (stats.process_crashes) {
1754 int crashes = 0;
[email protected]8e50b602009-03-03 22:59:431755 plugin_dict->GetInteger(prefs::kStabilityPluginCrashes, &crashes);
initial.commit09911bf2008-07-26 23:55:291756 crashes += stats.process_crashes;
[email protected]8e50b602009-03-03 22:59:431757 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes, crashes);
initial.commit09911bf2008-07-26 23:55:291758 }
1759 if (stats.instances) {
1760 int instances = 0;
[email protected]8e50b602009-03-03 22:59:431761 plugin_dict->GetInteger(prefs::kStabilityPluginInstances, &instances);
initial.commit09911bf2008-07-26 23:55:291762 instances += stats.instances;
[email protected]8e50b602009-03-03 22:59:431763 plugin_dict->SetInteger(prefs::kStabilityPluginInstances, instances);
initial.commit09911bf2008-07-26 23:55:291764 }
[email protected]cd937072012-07-02 09:00:291765 if (stats.loading_errors) {
1766 int loading_errors = 0;
1767 plugin_dict->GetInteger(prefs::kStabilityPluginLoadingErrors,
1768 &loading_errors);
1769 loading_errors += stats.loading_errors;
1770 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
1771 loading_errors);
1772 }
initial.commit09911bf2008-07-26 23:55:291773
[email protected]68b9e72b2011-08-05 23:08:221774 child_process_stats_buffer_.erase(name16);
initial.commit09911bf2008-07-26 23:55:291775 }
1776
1777 // Now go through and add dictionaries for plugins that didn't already have
1778 // reports in Local State.
[email protected]68b9e72b2011-08-05 23:08:221779 for (std::map<string16, ChildProcessStats>::iterator cache_iter =
[email protected]a27a9382009-02-11 23:55:101780 child_process_stats_buffer_.begin();
1781 cache_iter != child_process_stats_buffer_.end(); ++cache_iter) {
[email protected]a27a9382009-02-11 23:55:101782 ChildProcessStats stats = cache_iter->second;
[email protected]0d84c5d2009-10-09 01:10:421783
1784 // Insert only plugins information into the plugins list.
[email protected]8d5f1dae2011-11-11 14:30:411785 if (!IsPluginProcess(stats.process_type))
[email protected]0d84c5d2009-10-09 01:10:421786 continue;
1787
[email protected]57ecc4b2010-08-11 03:02:511788 // TODO(viettrungluu): remove conversion
[email protected]68b9e72b2011-08-05 23:08:221789 std::string plugin_name = UTF16ToUTF8(cache_iter->first);
[email protected]0d84c5d2009-10-09 01:10:421790
initial.commit09911bf2008-07-26 23:55:291791 DictionaryValue* plugin_dict = new DictionaryValue;
1792
[email protected]8e50b602009-03-03 22:59:431793 plugin_dict->SetString(prefs::kStabilityPluginName, plugin_name);
1794 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches,
initial.commit09911bf2008-07-26 23:55:291795 stats.process_launches);
[email protected]8e50b602009-03-03 22:59:431796 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes,
initial.commit09911bf2008-07-26 23:55:291797 stats.process_crashes);
[email protected]8e50b602009-03-03 22:59:431798 plugin_dict->SetInteger(prefs::kStabilityPluginInstances,
initial.commit09911bf2008-07-26 23:55:291799 stats.instances);
[email protected]cd937072012-07-02 09:00:291800 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
1801 stats.loading_errors);
initial.commit09911bf2008-07-26 23:55:291802 plugins->Append(plugin_dict);
1803 }
[email protected]a27a9382009-02-11 23:55:101804 child_process_stats_buffer_.clear();
initial.commit09911bf2008-07-26 23:55:291805}
1806
[email protected]dd98f392013-02-04 13:03:221807bool MetricsService::CanLogNotification() {
[email protected]2c910b72011-03-08 21:16:321808 // We simply don't log anything to UMA if there is a single incognito
initial.commit09911bf2008-07-26 23:55:291809 // session visible. The problem is that we always notify using the orginal
1810 // profile in order to simplify notification processing.
[email protected]e764e582012-08-01 03:01:291811 return !chrome::IsOffTheRecordSessionActive();
initial.commit09911bf2008-07-26 23:55:291812}
1813
[email protected]57ecc4b2010-08-11 03:02:511814void MetricsService::RecordBooleanPrefValue(const char* path, bool value) {
initial.commit09911bf2008-07-26 23:55:291815 DCHECK(IsSingleThreaded());
1816
1817 PrefService* pref = g_browser_process->local_state();
1818 DCHECK(pref);
1819
1820 pref->SetBoolean(path, value);
1821 RecordCurrentState(pref);
1822}
1823
1824void MetricsService::RecordCurrentState(PrefService* pref) {
[email protected]0bb1a622009-03-04 03:22:321825 pref->SetInt64(prefs::kStabilityLastTimestampSec, Time::Now().ToTimeT());
initial.commit09911bf2008-07-26 23:55:291826
1827 RecordPluginChanges(pref);
1828}
1829
[email protected]8d5f1dae2011-11-11 14:30:411830// static
[email protected]f3b357692013-03-22 05:16:131831bool MetricsService::IsPluginProcess(int process_type) {
1832 return (process_type == content::PROCESS_TYPE_PLUGIN ||
1833 process_type == content::PROCESS_TYPE_PPAPI_PLUGIN ||
1834 process_type == content::PROCESS_TYPE_PPAPI_BROKER);
[email protected]8d5f1dae2011-11-11 14:30:411835}
1836
[email protected]5ccaa412009-11-13 22:00:161837#if defined(OS_CHROMEOS)
[email protected]29cf16772010-04-21 15:13:471838void MetricsService::StartExternalMetrics() {
[email protected]5ccaa412009-11-13 22:00:161839 external_metrics_ = new chromeos::ExternalMetrics;
[email protected]29cf16772010-04-21 15:13:471840 external_metrics_->Start();
[email protected]5ccaa412009-11-13 22:00:161841}
1842#endif
[email protected]3819f2ee2011-08-21 09:44:381843
1844// static
1845bool MetricsServiceHelper::IsMetricsReportingEnabled() {
1846 bool result = false;
1847 const PrefService* local_state = g_browser_process->local_state();
1848 if (local_state) {
1849 const PrefService::Preference* uma_pref =
1850 local_state->FindPreference(prefs::kMetricsReportingEnabled);
1851 if (uma_pref) {
1852 bool success = uma_pref->GetValue()->GetAsBoolean(&result);
1853 DCHECK(success);
1854 }
1855 }
1856 return result;
1857}