blob: d48eb7b747a3fd64c839d25958b888d10d4cbce1 [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]a11e160f2013-07-10 19:45:44180#include "chrome/browser/metrics/gzipped_protobufs_field_trial.h"
[email protected]679082052010-07-21 21:30:13181#include "chrome/browser/metrics/metrics_log.h"
[email protected]cac267c2011-09-29 15:18:10182#include "chrome/browser/metrics/metrics_log_serializer.h"
[email protected]7f7f1962011-04-20 15:58:16183#include "chrome/browser/metrics/metrics_reporting_scheduler.h"
[email protected]aa312812013-04-30 19:46:05184#include "chrome/browser/metrics/time_ticks_experiment_win.h"
[email protected]ed0fd002012-04-25 23:10:34185#include "chrome/browser/metrics/tracking_synchronizer.h"
[email protected]adbb3762012-03-09 22:20:08186#include "chrome/browser/net/http_pipelining_compatibility_client.h"
[email protected]d67d1052011-06-09 05:11:41187#include "chrome/browser/net/network_stats.h"
[email protected]0fafc8d2013-06-01 00:09:50188#include "chrome/browser/omnibox/omnibox_log.h"
[email protected]f8628c22011-04-05 12:10:18189#include "chrome/browser/prefs/scoped_user_pref_update.h"
[email protected]8ecad5e2010-12-02 21:18:33190#include "chrome/browser/profiles/profile.h"
[email protected]71b73f02011-04-06 15:57:29191#include "chrome/browser/ui/browser_list.h"
[email protected]10b132b02012-07-27 20:46:18192#include "chrome/browser/ui/browser_otr_state.h"
[email protected]752a5262013-06-23 14:53:42193#include "chrome/browser/ui/search/search_tab_helper.h"
[email protected]157d5472009-11-05 22:31:03194#include "chrome/common/child_process_logging.h"
[email protected]d7c1fa62012-06-15 23:35:30195#include "chrome/common/chrome_result_codes.h"
[email protected]92745242009-06-12 16:52:21196#include "chrome/common/chrome_switches.h"
[email protected]20f999b52012-08-24 22:32:59197#include "chrome/common/metrics/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]4967f792012-01-20 22:14:40202#include "content/public/browser/child_process_data.h"
[email protected]83ab4a282012-07-12 18:19:45203#include "content/public/browser/histogram_fetcher.h"
[email protected]09d31d52012-03-11 22:30:27204#include "content/public/browser/load_notification_details.h"
[email protected]ad50def52011-10-19 23:17:07205#include "content/public/browser/notification_service.h"
[email protected]3a5180ae2011-12-21 02:39:38206#include "content/public/browser/plugin_service.h"
[email protected]f3b1a082011-11-18 00:34:30207#include "content/public/browser/render_process_host.h"
[email protected]5d490e42012-08-30 05:16:43208#include "content/public/browser/user_metrics.h"
[email protected]459f3502012-09-17 17:08:12209#include "content/public/browser/web_contents.h"
[email protected]d5d383252013-07-04 14:44:32210#include "content/public/common/process_type.h"
[email protected]d7bd3e52013-07-21 04:29:20211#include "content/public/common/webplugininfo.h"
[email protected]fe58acc22012-02-29 01:29:58212#include "net/base/load_flags.h"
[email protected]3dc1bc42012-06-19 08:20:53213#include "net/url_request/url_fetcher.h"
initial.commit09911bf2008-07-26 23:55:29214
[email protected]e06131d2010-02-10 18:40:33215// TODO(port): port browser_distribution.h.
216#if !defined(OS_POSIX)
[email protected]79bf0b72009-04-27 21:30:55217#include "chrome/installer/util/browser_distribution.h"
[email protected]dc6f4962009-02-13 01:25:50218#endif
219
[email protected]5ccaa412009-11-13 22:00:16220#if defined(OS_CHROMEOS)
[email protected]5ccaa412009-11-13 22:00:16221#include "chrome/browser/chromeos/external_metrics.h"
[email protected]d43970a72011-07-10 06:24:52222#include "chrome/browser/chromeos/system/statistics_provider.h"
[email protected]5ccaa412009-11-13 22:00:16223#endif
224
[email protected]d7c1fa62012-06-15 23:35:30225#if defined(OS_WIN)
226#include <windows.h> // Needed for STATUS_* codes
[email protected]d7c1fa62012-06-15 23:35:30227#endif
228
[email protected]e1acf6f2008-10-27 20:43:33229using base::Time;
[email protected]631bb742011-11-02 11:29:39230using content::BrowserThread;
[email protected]4967f792012-01-20 22:14:40231using content::ChildProcessData;
[email protected]09d31d52012-03-11 22:30:27232using content::LoadNotificationDetails;
[email protected]3a5180ae2011-12-21 02:39:38233using content::PluginService;
[email protected]e1acf6f2008-10-27 20:43:33234
[email protected]fe58acc22012-02-29 01:29:58235namespace {
[email protected]b2a4812d2012-02-28 05:31:31236
[email protected]fe58acc22012-02-29 01:29:58237// Check to see that we're being called on only one thread.
238bool IsSingleThreaded() {
239 static base::PlatformThreadId thread_id = 0;
240 if (!thread_id)
241 thread_id = base::PlatformThread::CurrentId();
242 return base::PlatformThread::CurrentId() == thread_id;
243}
244
[email protected]7f7f1962011-04-20 15:58:16245// The delay, in seconds, after starting recording before doing expensive
246// initialization work.
[email protected]12180f82012-10-10 21:13:30247#if defined(OS_ANDROID) || defined(OS_IOS)
248// On mobile devices, a significant portion of sessions last less than a minute.
249// Use a shorter timer on these platforms to avoid losing data.
250// TODO(dfalcantara): To avoid delaying startup, tighten up initialization so
251// that it occurs after the user gets their initial page.
252const int kInitializationDelaySeconds = 5;
253#else
[email protected]fe58acc22012-02-29 01:29:58254const int kInitializationDelaySeconds = 30;
[email protected]12180f82012-10-10 21:13:30255#endif
[email protected]252873ef2008-08-04 21:59:45256
[email protected]c9a3ef82009-05-28 22:02:46257// This specifies the amount of time to wait for all renderers to send their
258// data.
[email protected]fe58acc22012-02-29 01:29:58259const int kMaxHistogramGatheringWaitDuration = 60000; // 60 seconds.
[email protected]c9a3ef82009-05-28 22:02:46260
[email protected]54702c92011-04-15 15:06:43261// The maximum number of events in a log uploaded to the UMA server.
[email protected]fe58acc22012-02-29 01:29:58262const int kEventLimit = 2400;
[email protected]68475e602008-08-22 03:21:15263
264// If an upload fails, and the transmission was over this byte count, then we
265// will discard the log, and not try to retransmit it. We also don't persist
266// the log to the prefs for transmission during the next chrome session if this
267// limit is exceeded.
[email protected]fe58acc22012-02-29 01:29:58268const size_t kUploadLogAvoidRetransmitSize = 50000;
initial.commit09911bf2008-07-26 23:55:29269
[email protected]fc4252a72012-01-12 21:58:47270// Interval, in minutes, between state saves.
[email protected]fe58acc22012-02-29 01:29:58271const int kSaveStateIntervalMinutes = 5;
272
[email protected]4266def22012-05-17 01:02:40273enum ResponseStatus {
274 UNKNOWN_FAILURE,
275 SUCCESS,
276 BAD_REQUEST, // Invalid syntax or log too large.
[email protected]9f5c1ce82012-05-23 23:11:28277 NO_RESPONSE,
[email protected]4266def22012-05-17 01:02:40278 NUM_RESPONSE_STATUSES
279};
280
281ResponseStatus ResponseCodeToStatus(int response_code) {
282 switch (response_code) {
283 case 200:
284 return SUCCESS;
285 case 400:
286 return BAD_REQUEST;
[email protected]9f5c1ce82012-05-23 23:11:28287 case net::URLFetcher::RESPONSE_CODE_INVALID:
288 return NO_RESPONSE;
[email protected]4266def22012-05-17 01:02:40289 default:
290 return UNKNOWN_FAILURE;
291 }
292}
293
[email protected]e61003a2012-05-24 17:03:19294// The argument used to generate a non-identifying entropy source. We want no
[email protected]9556a892013-06-21 16:53:20295// more than 13 bits of entropy, so use this max to return a number in the range
296// [0, 7999] as the entropy source (12.97 bits of entropy).
297const int kMaxLowEntropySize = 8000;
[email protected]e61003a2012-05-24 17:03:19298
[email protected]e63a9ef2012-08-30 15:29:42299// Default prefs value for prefs::kMetricsLowEntropySource to indicate that the
300// value has not yet been set.
301const int kLowEntropySourceNotSet = -1;
302
[email protected]e61003a2012-05-24 17:03:19303// Generates a new non-identifying entropy source used to seed persistent
304// activities.
305int GenerateLowEntropySource() {
[email protected]20f999b52012-08-24 22:32:59306 return base::RandInt(0, kMaxLowEntropySize - 1);
[email protected]e61003a2012-05-24 17:03:19307}
308
[email protected]d7c1fa62012-06-15 23:35:30309// Converts an exit code into something that can be inserted into our
310// histograms (which expect non-negative numbers less than MAX_INT).
311int MapCrashExitCodeForHistogram(int exit_code) {
312#if defined(OS_WIN)
313 // Since |abs(STATUS_GUARD_PAGE_VIOLATION) == MAX_INT| it causes problems in
314 // histograms.cc. Solve this by remapping it to a smaller value, which
315 // hopefully doesn't conflict with other codes.
316 if (exit_code == STATUS_GUARD_PAGE_VIOLATION)
317 return 0x1FCF7EC3; // Randomly picked number.
318#endif
319
320 return std::abs(exit_code);
321}
322
[email protected]84c384e2013-03-01 23:20:19323void MarkAppCleanShutdownAndCommit() {
324 PrefService* pref = g_browser_process->local_state();
325 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
326 // Start writing right away (write happens on a different thread).
327 pref->CommitPendingWrite();
328}
329
[email protected]20f999b52012-08-24 22:32:59330} // namespace
initial.commit09911bf2008-07-26 23:55:29331
[email protected]c0c55e92011-09-10 18:47:30332// static
333MetricsService::ShutdownCleanliness MetricsService::clean_shutdown_status_ =
334 MetricsService::CLEANLY_SHUTDOWN;
335
[email protected]679082052010-07-21 21:30:13336// This is used to quickly log stats from child process related notifications in
337// MetricsService::child_stats_buffer_. The buffer's contents are transferred
338// out when Local State is periodically saved. The information is then
339// reported to the UMA server on next launch.
340struct MetricsService::ChildProcessStats {
341 public:
[email protected]f3b357692013-03-22 05:16:13342 explicit ChildProcessStats(int process_type)
[email protected]679082052010-07-21 21:30:13343 : process_launches(0),
344 process_crashes(0),
345 instances(0),
[email protected]cd937072012-07-02 09:00:29346 loading_errors(0),
[email protected]f3b357692013-03-22 05:16:13347 process_type(process_type) {}
[email protected]679082052010-07-21 21:30:13348
349 // This constructor is only used by the map to return some default value for
350 // an index for which no value has been assigned.
351 ChildProcessStats()
352 : process_launches(0),
[email protected]d88bf0a2011-08-30 23:55:57353 process_crashes(0),
354 instances(0),
[email protected]cd937072012-07-02 09:00:29355 loading_errors(0),
[email protected]bd5d6cf2011-12-01 00:39:12356 process_type(content::PROCESS_TYPE_UNKNOWN) {}
[email protected]679082052010-07-21 21:30:13357
358 // The number of times that the given child process has been launched
359 int process_launches;
360
361 // The number of times that the given child process has crashed
362 int process_crashes;
363
364 // The number of instances of this child process that have been created.
365 // An instance is a DOM object rendered by this child process during a page
366 // load.
367 int instances;
368
[email protected]cd937072012-07-02 09:00:29369 // The number of times there was an error loading an instance of this child
370 // process.
371 int loading_errors;
372
[email protected]f3b357692013-03-22 05:16:13373 int process_type;
[email protected]679082052010-07-21 21:30:13374};
initial.commit09911bf2008-07-26 23:55:29375
[email protected]84c988a2011-04-19 17:56:33376// Handles asynchronous fetching of memory details.
377// Will run the provided task after finished.
378class MetricsMemoryDetails : public MemoryDetails {
379 public:
[email protected]2226c222011-11-22 00:08:40380 explicit MetricsMemoryDetails(const base::Closure& callback)
381 : callback_(callback) {}
[email protected]84c988a2011-04-19 17:56:33382
[email protected]b94584a2013-02-07 03:02:08383 virtual void OnDetailsAvailable() OVERRIDE {
[email protected]b3a25092013-05-28 22:08:16384 base::MessageLoop::current()->PostTask(FROM_HERE, callback_);
[email protected]84c988a2011-04-19 17:56:33385 }
386
387 private:
[email protected]b94584a2013-02-07 03:02:08388 virtual ~MetricsMemoryDetails() {}
[email protected]84c988a2011-04-19 17:56:33389
[email protected]2226c222011-11-22 00:08:40390 base::Closure callback_;
[email protected]84c988a2011-04-19 17:56:33391 DISALLOW_COPY_AND_ASSIGN(MetricsMemoryDetails);
392};
393
initial.commit09911bf2008-07-26 23:55:29394// static
[email protected]b1de2c72013-02-06 02:45:47395void MetricsService::RegisterPrefs(PrefRegistrySimple* registry) {
initial.commit09911bf2008-07-26 23:55:29396 DCHECK(IsSingleThreaded());
[email protected]007b3f82013-04-09 08:46:45397 registry->RegisterStringPref(prefs::kMetricsClientID, std::string());
[email protected]b1de2c72013-02-06 02:45:47398 registry->RegisterIntegerPref(prefs::kMetricsLowEntropySource,
399 kLowEntropySourceNotSet);
400 registry->RegisterInt64Pref(prefs::kMetricsClientIDTimestamp, 0);
401 registry->RegisterInt64Pref(prefs::kStabilityLaunchTimeSec, 0);
402 registry->RegisterInt64Pref(prefs::kStabilityLastTimestampSec, 0);
[email protected]007b3f82013-04-09 08:46:45403 registry->RegisterStringPref(prefs::kStabilityStatsVersion, std::string());
[email protected]b1de2c72013-02-06 02:45:47404 registry->RegisterInt64Pref(prefs::kStabilityStatsBuildTime, 0);
405 registry->RegisterBooleanPref(prefs::kStabilityExitedCleanly, true);
406 registry->RegisterBooleanPref(prefs::kStabilitySessionEndCompleted, true);
407 registry->RegisterIntegerPref(prefs::kMetricsSessionID, -1);
408 registry->RegisterIntegerPref(prefs::kStabilityLaunchCount, 0);
409 registry->RegisterIntegerPref(prefs::kStabilityCrashCount, 0);
410 registry->RegisterIntegerPref(prefs::kStabilityIncompleteSessionEndCount, 0);
411 registry->RegisterIntegerPref(prefs::kStabilityPageLoadCount, 0);
412 registry->RegisterIntegerPref(prefs::kStabilityRendererCrashCount, 0);
413 registry->RegisterIntegerPref(prefs::kStabilityExtensionRendererCrashCount,
414 0);
415 registry->RegisterIntegerPref(prefs::kStabilityRendererHangCount, 0);
416 registry->RegisterIntegerPref(prefs::kStabilityChildProcessCrashCount, 0);
417 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationFail, 0);
418 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationSuccess,
419 0);
420 registry->RegisterIntegerPref(prefs::kStabilityDebuggerPresent, 0);
421 registry->RegisterIntegerPref(prefs::kStabilityDebuggerNotPresent, 0);
[email protected]c1834a92011-01-21 18:21:03422#if defined(OS_CHROMEOS)
[email protected]b1de2c72013-02-06 02:45:47423 registry->RegisterIntegerPref(prefs::kStabilityOtherUserCrashCount, 0);
424 registry->RegisterIntegerPref(prefs::kStabilityKernelCrashCount, 0);
425 registry->RegisterIntegerPref(prefs::kStabilitySystemUncleanShutdownCount, 0);
[email protected]c1834a92011-01-21 18:21:03426#endif // OS_CHROMEOS
[email protected]e73c01972008-08-13 00:18:24427
[email protected]5f3e1642013-05-05 03:37:34428 registry->RegisterListPref(prefs::kMetricsInitialLogs);
429 registry->RegisterListPref(prefs::kMetricsOngoingLogs);
[email protected]0bb1a622009-03-04 03:22:32430
[email protected]5c181552013-02-07 09:12:52431 registry->RegisterInt64Pref(prefs::kInstallDate, 0);
[email protected]b1de2c72013-02-06 02:45:47432 registry->RegisterInt64Pref(prefs::kUninstallMetricsPageLoadCount, 0);
433 registry->RegisterInt64Pref(prefs::kUninstallLaunchCount, 0);
[email protected]b1de2c72013-02-06 02:45:47434 registry->RegisterInt64Pref(prefs::kUninstallMetricsUptimeSec, 0);
435 registry->RegisterInt64Pref(prefs::kUninstallLastLaunchTimeSec, 0);
436 registry->RegisterInt64Pref(prefs::kUninstallLastObservedRunTimeSec, 0);
initial.commit09911bf2008-07-26 23:55:29437}
438
[email protected]541f77922009-02-23 21:14:38439// static
440void MetricsService::DiscardOldStabilityStats(PrefService* local_state) {
441 local_state->SetBoolean(prefs::kStabilityExitedCleanly, true);
[email protected]c9abf242009-07-18 06:00:38442 local_state->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
[email protected]541f77922009-02-23 21:14:38443
444 local_state->SetInteger(prefs::kStabilityIncompleteSessionEndCount, 0);
445 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationSuccess, 0);
446 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationFail, 0);
447 local_state->SetInteger(prefs::kStabilityDebuggerPresent, 0);
448 local_state->SetInteger(prefs::kStabilityDebuggerNotPresent, 0);
449
450 local_state->SetInteger(prefs::kStabilityLaunchCount, 0);
451 local_state->SetInteger(prefs::kStabilityCrashCount, 0);
452
453 local_state->SetInteger(prefs::kStabilityPageLoadCount, 0);
454 local_state->SetInteger(prefs::kStabilityRendererCrashCount, 0);
455 local_state->SetInteger(prefs::kStabilityRendererHangCount, 0);
456
[email protected]9165f742010-03-10 22:55:01457 local_state->SetInt64(prefs::kStabilityLaunchTimeSec, 0);
458 local_state->SetInt64(prefs::kStabilityLastTimestampSec, 0);
[email protected]541f77922009-02-23 21:14:38459
460 local_state->ClearPref(prefs::kStabilityPluginStats);
[email protected]ae155cb92009-06-19 06:10:37461
[email protected]5f3e1642013-05-05 03:37:34462 local_state->ClearPref(prefs::kMetricsInitialLogs);
463 local_state->ClearPref(prefs::kMetricsOngoingLogs);
[email protected]541f77922009-02-23 21:14:38464}
465
initial.commit09911bf2008-07-26 23:55:29466MetricsService::MetricsService()
[email protected]d01b8732008-10-16 02:18:07467 : recording_active_(false),
468 reporting_active_(false),
[email protected]410938e02012-10-24 16:33:59469 test_mode_active_(false),
[email protected]d01b8732008-10-16 02:18:07470 state_(INITIALIZED),
[email protected]e88be472e2013-04-26 22:36:36471 low_entropy_source_(kLowEntropySourceNotSet),
[email protected]d01b8732008-10-16 02:18:07472 idle_since_last_transmission_(false),
initial.commit09911bf2008-07-26 23:55:29473 next_window_id_(0),
[email protected]9c009092013-05-01 03:14:09474 self_ptr_factory_(this),
475 state_saver_factory_(this),
[email protected]e5354322012-08-09 23:07:37476 waiting_for_asynchronous_reporting_step_(false),
477 entropy_source_returned_(LAST_ENTROPY_NONE) {
initial.commit09911bf2008-07-26 23:55:29478 DCHECK(IsSingleThreaded());
479 InitializeMetricsState();
[email protected]7f7f1962011-04-20 15:58:16480
481 base::Closure callback = base::Bind(&MetricsService::StartScheduledUpload,
[email protected]c94d7382012-02-28 08:43:40482 self_ptr_factory_.GetWeakPtr());
[email protected]7f7f1962011-04-20 15:58:16483 scheduler_.reset(new MetricsReportingScheduler(callback));
[email protected]cac267c2011-09-29 15:18:10484 log_manager_.set_log_serializer(new MetricsLogSerializer());
485 log_manager_.set_max_ongoing_log_store_size(kUploadLogAvoidRetransmitSize);
[email protected]f4eaf7b92013-02-28 22:00:40486
487 BrowserChildProcessObserver::Add(this);
initial.commit09911bf2008-07-26 23:55:29488}
489
490MetricsService::~MetricsService() {
[email protected]410938e02012-10-24 16:33:59491 DisableRecording();
[email protected]f4eaf7b92013-02-28 22:00:40492
493 BrowserChildProcessObserver::Remove(this);
initial.commit09911bf2008-07-26 23:55:29494}
495
[email protected]d01b8732008-10-16 02:18:07496void MetricsService::Start() {
[email protected]b1c8dc02011-04-13 18:32:04497 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59498 EnableRecording();
499 EnableReporting();
[email protected]d01b8732008-10-16 02:18:07500}
501
[email protected]410938e02012-10-24 16:33:59502void MetricsService::StartRecordingForTests() {
503 test_mode_active_ = true;
504 EnableRecording();
505 DisableReporting();
[email protected]d01b8732008-10-16 02:18:07506}
507
508void MetricsService::Stop() {
[email protected]b1c8dc02011-04-13 18:32:04509 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59510 DisableReporting();
511 DisableRecording();
512}
513
514void MetricsService::EnableReporting() {
515 if (reporting_active_)
516 return;
517 reporting_active_ = true;
518 StartSchedulerIfNecessary();
519}
520
521void MetricsService::DisableReporting() {
522 reporting_active_ = false;
[email protected]d01b8732008-10-16 02:18:07523}
524
[email protected]edafd4c2011-05-10 17:18:53525std::string MetricsService::GetClientId() {
526 return client_id_;
527}
528
[email protected]20f999b52012-08-24 22:32:59529scoped_ptr<const base::FieldTrial::EntropyProvider>
530 MetricsService::CreateEntropyProvider(bool reporting_will_be_enabled) {
[email protected]29d81ee02012-05-25 05:45:42531 // For metrics reporting-enabled users, we combine the client ID and low
532 // entropy source to get the final entropy source. Otherwise, only use the low
533 // entropy source.
534 // This has two useful properties:
[email protected]e61003a2012-05-24 17:03:19535 // 1) It makes the entropy source less identifiable for parties that do not
536 // know the low entropy source.
537 // 2) It makes the final entropy source resettable.
[email protected]d0a82c82013-06-13 16:31:18538 const int low_entropy_source_value = GetLowEntropySource();
539 UMA_HISTOGRAM_SPARSE_SLOWLY("UMA.LowEntropySourceValue",
540 low_entropy_source_value);
[email protected]e5354322012-08-09 23:07:37541 if (reporting_will_be_enabled) {
[email protected]5fbffb72012-08-18 01:59:18542 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
543 entropy_source_returned_ = LAST_ENTROPY_HIGH;
544 DCHECK_EQ(LAST_ENTROPY_HIGH, entropy_source_returned_);
[email protected]20f999b52012-08-24 22:32:59545 const std::string high_entropy_source =
[email protected]d0a82c82013-06-13 16:31:18546 client_id_ + base::IntToString(low_entropy_source_value);
[email protected]20f999b52012-08-24 22:32:59547 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
548 new metrics::SHA1EntropyProvider(high_entropy_source));
[email protected]e5354322012-08-09 23:07:37549 }
[email protected]20f999b52012-08-24 22:32:59550
[email protected]5fbffb72012-08-18 01:59:18551 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
552 entropy_source_returned_ = LAST_ENTROPY_LOW;
553 DCHECK_EQ(LAST_ENTROPY_LOW, entropy_source_returned_);
[email protected]9d7c4a82013-05-07 12:10:49554
555#if defined(OS_ANDROID) || defined(OS_IOS)
556 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
557 new metrics::CachingPermutedEntropyProvider(
558 g_browser_process->local_state(),
[email protected]d0a82c82013-06-13 16:31:18559 low_entropy_source_value,
[email protected]9d7c4a82013-05-07 12:10:49560 kMaxLowEntropySize));
561#else
[email protected]20f999b52012-08-24 22:32:59562 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
[email protected]d0a82c82013-06-13 16:31:18563 new metrics::PermutedEntropyProvider(low_entropy_source_value,
[email protected]20f999b52012-08-24 22:32:59564 kMaxLowEntropySize));
[email protected]9d7c4a82013-05-07 12:10:49565#endif
[email protected]e61003a2012-05-24 17:03:19566}
567
[email protected]5cbeeef72012-02-08 02:05:18568void MetricsService::ForceClientIdCreation() {
569 if (!client_id_.empty())
570 return;
571 PrefService* pref = g_browser_process->local_state();
572 client_id_ = pref->GetString(prefs::kMetricsClientID);
573 if (!client_id_.empty())
574 return;
575
576 client_id_ = GenerateClientID();
577 pref->SetString(prefs::kMetricsClientID, client_id_);
578
579 // Might as well make a note of how long this ID has existed
580 pref->SetString(prefs::kMetricsClientIDTimestamp,
581 base::Int64ToString(Time::Now().ToTimeT()));
582}
583
[email protected]410938e02012-10-24 16:33:59584void MetricsService::EnableRecording() {
initial.commit09911bf2008-07-26 23:55:29585 DCHECK(IsSingleThreaded());
586
[email protected]410938e02012-10-24 16:33:59587 if (recording_active_)
initial.commit09911bf2008-07-26 23:55:29588 return;
[email protected]410938e02012-10-24 16:33:59589 recording_active_ = true;
initial.commit09911bf2008-07-26 23:55:29590
[email protected]410938e02012-10-24 16:33:59591 ForceClientIdCreation();
592 child_process_logging::SetClientId(client_id_);
593 if (!log_manager_.current_log())
594 OpenNewLog();
[email protected]005ef3e2009-05-22 20:55:46595
[email protected]410938e02012-10-24 16:33:59596 SetUpNotifications(&registrar_, this);
[email protected]dd98f392013-02-04 13:03:22597 content::RemoveActionCallback(action_callback_);
598 action_callback_ = base::Bind(&MetricsService::OnUserAction,
599 base::Unretained(this));
600 content::AddActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59601}
602
603void MetricsService::DisableRecording() {
604 DCHECK(IsSingleThreaded());
605
606 if (!recording_active_)
607 return;
608 recording_active_ = false;
609
[email protected]dd98f392013-02-04 13:03:22610 content::RemoveActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59611 registrar_.RemoveAll();
612 PushPendingLogsToPersistentStorage();
613 DCHECK(!log_manager_.has_staged_log());
initial.commit09911bf2008-07-26 23:55:29614}
615
[email protected]d01b8732008-10-16 02:18:07616bool MetricsService::recording_active() const {
initial.commit09911bf2008-07-26 23:55:29617 DCHECK(IsSingleThreaded());
[email protected]d01b8732008-10-16 02:18:07618 return recording_active_;
initial.commit09911bf2008-07-26 23:55:29619}
620
[email protected]d01b8732008-10-16 02:18:07621bool MetricsService::reporting_active() const {
622 DCHECK(IsSingleThreaded());
623 return reporting_active_;
initial.commit09911bf2008-07-26 23:55:29624}
625
[email protected]87ef9ea2011-02-26 03:15:15626// static
[email protected]6c2381d2011-10-19 02:52:53627void MetricsService::SetUpNotifications(
628 content::NotificationRegistrar* registrar,
629 content::NotificationObserver* observer) {
630 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_OPENED,
[email protected]ad50def52011-10-19 23:17:07631 content::NotificationService::AllBrowserContextsAndSources());
[email protected]6c2381d2011-10-19 02:52:53632 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_CLOSED,
[email protected]ad50def52011-10-19 23:17:07633 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42634 registrar->Add(observer, chrome::NOTIFICATION_TAB_PARENTED,
[email protected]ad50def52011-10-19 23:17:07635 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42636 registrar->Add(observer, chrome::NOTIFICATION_TAB_CLOSING,
[email protected]ad50def52011-10-19 23:17:07637 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53638 registrar->Add(observer, content::NOTIFICATION_LOAD_START,
[email protected]ad50def52011-10-19 23:17:07639 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53640 registrar->Add(observer, content::NOTIFICATION_LOAD_STOP,
[email protected]ad50def52011-10-19 23:17:07641 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53642 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_CLOSED,
[email protected]ad50def52011-10-19 23:17:07643 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53644 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_HANG,
[email protected]ad50def52011-10-19 23:17:07645 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53646 registrar->Add(observer, chrome::NOTIFICATION_OMNIBOX_OPENED_URL,
[email protected]ad50def52011-10-19 23:17:07647 content::NotificationService::AllSources());
[email protected]87ef9ea2011-02-26 03:15:15648}
649
[email protected]f4eaf7b92013-02-28 22:00:40650void MetricsService::BrowserChildProcessHostConnected(
651 const content::ChildProcessData& data) {
652 GetChildProcessStats(data).process_launches++;
653}
654
655void MetricsService::BrowserChildProcessCrashed(
656 const content::ChildProcessData& data) {
657 GetChildProcessStats(data).process_crashes++;
658 // Exclude plugin crashes from the count below because we report them via
659 // a separate UMA metric.
[email protected]f3b357692013-03-22 05:16:13660 if (!IsPluginProcess(data.process_type))
[email protected]f4eaf7b92013-02-28 22:00:40661 IncrementPrefValue(prefs::kStabilityChildProcessCrashCount);
662}
663
664void MetricsService::BrowserChildProcessInstanceCreated(
665 const content::ChildProcessData& data) {
666 GetChildProcessStats(data).instances++;
667}
668
[email protected]432115822011-07-10 15:52:27669void MetricsService::Observe(int type,
[email protected]6c2381d2011-10-19 02:52:53670 const content::NotificationSource& source,
671 const content::NotificationDetails& details) {
[email protected]cac267c2011-09-29 15:18:10672 DCHECK(log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:29673 DCHECK(IsSingleThreaded());
674
[email protected]dd98f392013-02-04 13:03:22675 if (!CanLogNotification())
initial.commit09911bf2008-07-26 23:55:29676 return;
677
[email protected]432115822011-07-10 15:52:27678 switch (type) {
[email protected]432115822011-07-10 15:52:27679 case chrome::NOTIFICATION_BROWSER_OPENED:
[email protected]46a0efc2013-07-17 15:40:47680 case chrome::NOTIFICATION_BROWSER_CLOSED:
681 case chrome::NOTIFICATION_TAB_PARENTED:
682 case chrome::NOTIFICATION_TAB_CLOSING:
[email protected]432115822011-07-10 15:52:27683 case content::NOTIFICATION_LOAD_STOP:
[email protected]46a0efc2013-07-17 15:40:47684 // These notifications are currently used only to break out of idle mode.
initial.commit09911bf2008-07-26 23:55:29685 break;
686
[email protected]752a5262013-06-23 14:53:42687 case content::NOTIFICATION_LOAD_START: {
688 content::NavigationController* controller =
689 content::Source<content::NavigationController>(source).ptr();
690 content::WebContents* web_contents = controller->GetWebContents();
691 LogLoadStarted(web_contents);
initial.commit09911bf2008-07-26 23:55:29692 break;
[email protected]752a5262013-06-23 14:53:42693 }
initial.commit09911bf2008-07-26 23:55:29694
[email protected]432115822011-07-10 15:52:27695 case content::NOTIFICATION_RENDERER_PROCESS_CLOSED: {
[email protected]f3b1a082011-11-18 00:34:30696 content::RenderProcessHost::RendererClosedDetails* process_details =
697 content::Details<
698 content::RenderProcessHost::RendererClosedDetails>(
699 details).ptr();
700 content::RenderProcessHost* host =
701 content::Source<content::RenderProcessHost>(source).ptr();
[email protected]c3721482012-03-23 16:21:48702 LogRendererCrash(
[email protected]f1675202012-07-09 15:18:00703 host, process_details->status, process_details->exit_code);
[email protected]1f085622009-12-04 05:33:45704 }
initial.commit09911bf2008-07-26 23:55:29705 break;
706
[email protected]432115822011-07-10 15:52:27707 case content::NOTIFICATION_RENDERER_PROCESS_HANG:
initial.commit09911bf2008-07-26 23:55:29708 LogRendererHang();
709 break;
710
[email protected]432115822011-07-10 15:52:27711 case chrome::NOTIFICATION_OMNIBOX_OPENED_URL: {
[email protected]279703f2012-01-20 22:23:26712 MetricsLog* current_log =
713 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]1226abb2010-06-10 18:01:28714 DCHECK(current_log);
715 current_log->RecordOmniboxOpenedURL(
[email protected]0fafc8d2013-06-01 00:09:50716 *content::Details<OmniboxLog>(details).ptr());
initial.commit09911bf2008-07-26 23:55:29717 break;
[email protected]1226abb2010-06-10 18:01:28718 }
initial.commit09911bf2008-07-26 23:55:29719
initial.commit09911bf2008-07-26 23:55:29720 default:
[email protected]a063c102010-07-22 22:20:19721 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:29722 break;
723 }
[email protected]d01b8732008-10-16 02:18:07724
725 HandleIdleSinceLastTransmission(false);
[email protected]d01b8732008-10-16 02:18:07726}
727
728void MetricsService::HandleIdleSinceLastTransmission(bool in_idle) {
729 // If there wasn't a lot of action, maybe the computer was asleep, in which
730 // case, the log transmissions should have stopped. Here we start them up
731 // again.
[email protected]cac78842008-11-27 01:02:20732 if (!in_idle && idle_since_last_transmission_)
[email protected]7f7f1962011-04-20 15:58:16733 StartSchedulerIfNecessary();
[email protected]cac78842008-11-27 01:02:20734 idle_since_last_transmission_ = in_idle;
initial.commit09911bf2008-07-26 23:55:29735}
736
initial.commit09911bf2008-07-26 23:55:29737void MetricsService::RecordStartOfSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38738 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29739 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, false);
740}
741
742void MetricsService::RecordCompletedSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38743 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29744 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, true);
745}
746
[email protected]410938e02012-10-24 16:33:59747#if defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39748void MetricsService::OnAppEnterBackground() {
749 scheduler_->Stop();
750
[email protected]84c384e2013-03-01 23:20:19751 MarkAppCleanShutdownAndCommit();
[email protected]117fbdf22012-06-26 18:36:39752
753 // At this point, there's no way of knowing when the process will be
754 // killed, so this has to be treated similar to a shutdown, closing and
755 // persisting all logs. Unlinke a shutdown, the state is primed to be ready
756 // to continue logging and uploading if the process does return.
757 if (recording_active() && state_ >= INITIAL_LOG_READY) {
758 PushPendingLogsToPersistentStorage();
[email protected]410938e02012-10-24 16:33:59759 // Persisting logs closes the current log, so start recording a new log
760 // immediately to capture any background work that might be done before the
761 // process is killed.
762 OpenNewLog();
[email protected]117fbdf22012-06-26 18:36:39763 }
[email protected]117fbdf22012-06-26 18:36:39764}
765
766void MetricsService::OnAppEnterForeground() {
767 PrefService* pref = g_browser_process->local_state();
768 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
769
770 StartSchedulerIfNecessary();
771}
[email protected]84c384e2013-03-01 23:20:19772#else
773void MetricsService::LogNeedForCleanShutdown() {
774 PrefService* pref = g_browser_process->local_state();
775 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
776 // Redundant setting to be sure we call for a clean shutdown.
777 clean_shutdown_status_ = NEED_TO_SHUTDOWN;
778}
779#endif // defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39780
[email protected]7f7f1962011-04-20 15:58:16781void MetricsService::RecordBreakpadRegistration(bool success) {
[email protected]68475e602008-08-22 03:21:15782 if (!success)
[email protected]e73c01972008-08-13 00:18:24783 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationFail);
784 else
785 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationSuccess);
786}
787
788void MetricsService::RecordBreakpadHasDebugger(bool has_debugger) {
789 if (!has_debugger)
790 IncrementPrefValue(prefs::kStabilityDebuggerNotPresent);
791 else
[email protected]68475e602008-08-22 03:21:15792 IncrementPrefValue(prefs::kStabilityDebuggerPresent);
[email protected]e73c01972008-08-13 00:18:24793}
794
initial.commit09911bf2008-07-26 23:55:29795//------------------------------------------------------------------------------
796// private methods
797//------------------------------------------------------------------------------
798
799
800//------------------------------------------------------------------------------
801// Initialization methods
802
803void MetricsService::InitializeMetricsState() {
[email protected]79bf0b72009-04-27 21:30:55804#if defined(OS_POSIX)
[email protected]b4a72d842012-03-22 20:09:09805 network_stats_server_ = chrome_common_net::kEchoTestServerLocation;
806 http_pipelining_test_server_ = chrome_common_net::kPipelineTestServerBaseUrl;
[email protected]79bf0b72009-04-27 21:30:55807#else
808 BrowserDistribution* dist = BrowserDistribution::GetDistribution();
[email protected]d67d1052011-06-09 05:11:41809 network_stats_server_ = dist->GetNetworkStatsServer();
[email protected]adbb3762012-03-09 22:20:08810 http_pipelining_test_server_ = dist->GetHttpPipeliningTestServer();
[email protected]79bf0b72009-04-27 21:30:55811#endif
812
initial.commit09911bf2008-07-26 23:55:29813 PrefService* pref = g_browser_process->local_state();
814 DCHECK(pref);
815
[email protected]225c50842010-01-19 21:19:13816 if ((pref->GetInt64(prefs::kStabilityStatsBuildTime)
817 != MetricsLog::GetBuildTime()) ||
[email protected]ddd231e2010-06-29 20:35:19818 (pref->GetString(prefs::kStabilityStatsVersion)
[email protected]225c50842010-01-19 21:19:13819 != MetricsLog::GetVersionString())) {
[email protected]541f77922009-02-23 21:14:38820 // This is a new version, so we don't want to confuse the stats about the
821 // old version with info that we upload.
822 DiscardOldStabilityStats(pref);
823 pref->SetString(prefs::kStabilityStatsVersion,
[email protected]ddd231e2010-06-29 20:35:19824 MetricsLog::GetVersionString());
[email protected]225c50842010-01-19 21:19:13825 pref->SetInt64(prefs::kStabilityStatsBuildTime,
826 MetricsLog::GetBuildTime());
[email protected]541f77922009-02-23 21:14:38827 }
828
initial.commit09911bf2008-07-26 23:55:29829 // Update session ID
830 session_id_ = pref->GetInteger(prefs::kMetricsSessionID);
831 ++session_id_;
832 pref->SetInteger(prefs::kMetricsSessionID, session_id_);
833
initial.commit09911bf2008-07-26 23:55:29834 // Stability bookkeeping
[email protected]e73c01972008-08-13 00:18:24835 IncrementPrefValue(prefs::kStabilityLaunchCount);
initial.commit09911bf2008-07-26 23:55:29836
[email protected]e73c01972008-08-13 00:18:24837 if (!pref->GetBoolean(prefs::kStabilityExitedCleanly)) {
838 IncrementPrefValue(prefs::kStabilityCrashCount);
[email protected]c0c55e92011-09-10 18:47:30839 // Reset flag, and wait until we call LogNeedForCleanShutdown() before
840 // monitoring.
841 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
initial.commit09911bf2008-07-26 23:55:29842 }
[email protected]e73c01972008-08-13 00:18:24843
[email protected]e73c01972008-08-13 00:18:24844 if (!pref->GetBoolean(prefs::kStabilitySessionEndCompleted)) {
845 IncrementPrefValue(prefs::kStabilityIncompleteSessionEndCount);
[email protected]c9abf242009-07-18 06:00:38846 // This is marked false when we get a WM_ENDSESSION.
847 pref->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
initial.commit09911bf2008-07-26 23:55:29848 }
initial.commit09911bf2008-07-26 23:55:29849
[email protected]9165f742010-03-10 22:55:01850 // Initialize uptime counters.
851 int64 startup_uptime = MetricsLog::GetIncrementalUptime(pref);
[email protected]ae393ec702010-06-27 16:23:14852 DCHECK_EQ(0, startup_uptime);
[email protected]9165f742010-03-10 22:55:01853 // For backwards compatibility, leave this intact in case Omaha is checking
854 // them. prefs::kStabilityLastTimestampSec may also be useless now.
855 // TODO(jar): Delete these if they have no uses.
[email protected]0bb1a622009-03-04 03:22:32856 pref->SetInt64(prefs::kStabilityLaunchTimeSec, Time::Now().ToTimeT());
857
858 // Bookkeeping for the uninstall metrics.
859 IncrementLongPrefsValue(prefs::kUninstallLaunchCount);
initial.commit09911bf2008-07-26 23:55:29860
[email protected]92745242009-06-12 16:52:21861 // Get stats on use of command line.
862 const CommandLine* command_line(CommandLine::ForCurrentProcess());
863 size_t common_commands = 0;
864 if (command_line->HasSwitch(switches::kUserDataDir)) {
865 ++common_commands;
866 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineDatDirCount", 1);
867 }
868
869 if (command_line->HasSwitch(switches::kApp)) {
870 ++common_commands;
871 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineAppModeCount", 1);
872 }
873
[email protected]62b4e522011-07-13 21:46:32874 size_t switch_count = command_line->GetSwitches().size();
875 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineFlagCount", switch_count);
[email protected]92745242009-06-12 16:52:21876 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineUncommonFlagCount",
[email protected]62b4e522011-07-13 21:46:32877 switch_count - common_commands);
[email protected]92745242009-06-12 16:52:21878
initial.commit09911bf2008-07-26 23:55:29879 // Kick off the process of saving the state (so the uptime numbers keep
880 // getting updated) every n minutes.
881 ScheduleNextStateSave();
882}
883
[email protected]c94d7382012-02-28 08:43:40884// static
[email protected]d33e7cc2011-09-23 01:43:56885void MetricsService::InitTaskGetHardwareClass(
[email protected]c94d7382012-02-28 08:43:40886 base::WeakPtr<MetricsService> self,
[email protected]d33e7cc2011-09-23 01:43:56887 base::MessageLoopProxy* target_loop) {
[email protected]d33e7cc2011-09-23 01:43:56888 DCHECK(BrowserThread::CurrentlyOn(BrowserThread::FILE));
889
890 std::string hardware_class;
891#if defined(OS_CHROMEOS)
892 chromeos::system::StatisticsProvider::GetInstance()->GetMachineStatistic(
893 "hardware_class", &hardware_class);
894#endif // OS_CHROMEOS
895
896 target_loop->PostTask(FROM_HERE,
897 base::Bind(&MetricsService::OnInitTaskGotHardwareClass,
[email protected]c94d7382012-02-28 08:43:40898 self, hardware_class));
[email protected]d33e7cc2011-09-23 01:43:56899}
900
901void MetricsService::OnInitTaskGotHardwareClass(
902 const std::string& hardware_class) {
[email protected]ed0fd002012-04-25 23:10:34903 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]85ed9d42010-06-08 22:37:44904 hardware_class_ = hardware_class;
[email protected]d33e7cc2011-09-23 01:43:56905
[email protected]ebd71962012-12-20 02:56:55906#if defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:56907 // Start the next part of the init task: loading plugin information.
908 PluginService::GetInstance()->GetPlugins(
909 base::Bind(&MetricsService::OnInitTaskGotPluginInfo,
[email protected]c94d7382012-02-28 08:43:40910 self_ptr_factory_.GetWeakPtr()));
[email protected]ebd71962012-12-20 02:56:55911#else
[email protected]d7bd3e52013-07-21 04:29:20912 std::vector<content::WebPluginInfo> plugin_list_empty;
[email protected]ebd71962012-12-20 02:56:55913 OnInitTaskGotPluginInfo(plugin_list_empty);
914#endif // defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:56915}
916
917void MetricsService::OnInitTaskGotPluginInfo(
[email protected]d7bd3e52013-07-21 04:29:20918 const std::vector<content::WebPluginInfo>& plugins) {
[email protected]ed0fd002012-04-25 23:10:34919 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]35fa6a22009-08-15 00:04:01920 plugins_ = plugins;
[email protected]d33e7cc2011-09-23 01:43:56921
[email protected]197c0772012-05-14 23:50:51922 // Schedules a task on a blocking pool thread to gather Google Update
923 // statistics (requires Registry reads).
924 BrowserThread::PostBlockingPoolTask(
925 FROM_HERE,
926 base::Bind(&MetricsService::InitTaskGetGoogleUpdateData,
927 self_ptr_factory_.GetWeakPtr(),
[email protected]b3a25092013-05-28 22:08:16928 base::MessageLoop::current()->message_loop_proxy()));
[email protected]197c0772012-05-14 23:50:51929}
930
931// static
932void MetricsService::InitTaskGetGoogleUpdateData(
933 base::WeakPtr<MetricsService> self,
934 base::MessageLoopProxy* target_loop) {
935 GoogleUpdateMetrics google_update_metrics;
936
937#if defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
938 const bool system_install = GoogleUpdateSettings::IsSystemInstall();
939
940 google_update_metrics.is_system_install = system_install;
941 google_update_metrics.last_started_au =
942 GoogleUpdateSettings::GetGoogleUpdateLastStartedAU(system_install);
943 google_update_metrics.last_checked =
944 GoogleUpdateSettings::GetGoogleUpdateLastChecked(system_install);
945 GoogleUpdateSettings::GetUpdateDetailForGoogleUpdate(
946 system_install,
947 &google_update_metrics.google_update_data);
948 GoogleUpdateSettings::GetUpdateDetail(
949 system_install,
950 &google_update_metrics.product_data);
951#endif // defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
952
953 target_loop->PostTask(FROM_HERE,
954 base::Bind(&MetricsService::OnInitTaskGotGoogleUpdateData,
955 self, google_update_metrics));
956}
957
958void MetricsService::OnInitTaskGotGoogleUpdateData(
959 const GoogleUpdateMetrics& google_update_metrics) {
960 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
961
962 google_update_metrics_ = google_update_metrics;
963
[email protected]ed0fd002012-04-25 23:10:34964 // Start the next part of the init task: fetching performance data. This will
965 // call into |FinishedReceivingProfilerData()| when the task completes.
966 chrome_browser_metrics::TrackingSynchronizer::FetchProfilerDataAsynchronously(
967 self_ptr_factory_.GetWeakPtr());
968}
969
[email protected]dd98f392013-02-04 13:03:22970void MetricsService::OnUserAction(const std::string& action) {
971 if (!CanLogNotification())
972 return;
973
974 log_manager_.current_log()->RecordUserAction(action.c_str());
975 HandleIdleSinceLastTransmission(false);
976}
977
[email protected]ed0fd002012-04-25 23:10:34978void MetricsService::ReceivedProfilerData(
979 const tracked_objects::ProcessDataSnapshot& process_data,
[email protected]f3b357692013-03-22 05:16:13980 int process_type) {
[email protected]ed0fd002012-04-25 23:10:34981 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
982
983 // Upon the first callback, create the initial log so that we can immediately
984 // save the profiler data.
985 if (!initial_log_.get())
986 initial_log_.reset(new MetricsLog(client_id_, session_id_));
987
988 initial_log_->RecordProfilerData(process_data, process_type);
989}
990
991void MetricsService::FinishedReceivingProfilerData() {
992 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]3a7b66d2012-04-26 16:34:16993 state_ = INIT_TASK_DONE;
initial.commit09911bf2008-07-26 23:55:29994}
995
[email protected]e61003a2012-05-24 17:03:19996int MetricsService::GetLowEntropySource() {
997 // Note that the default value for the low entropy source and the default pref
[email protected]e88be472e2013-04-26 22:36:36998 // value are both kLowEntropySourceNotSet, which is used to identify if the
999 // value has been set or not.
1000 if (low_entropy_source_ != kLowEntropySourceNotSet)
[email protected]e61003a2012-05-24 17:03:191001 return low_entropy_source_;
1002
[email protected]9d7c4a82013-05-07 12:10:491003 PrefService* local_state = g_browser_process->local_state();
[email protected]e61003a2012-05-24 17:03:191004 const CommandLine* command_line(CommandLine::ForCurrentProcess());
1005 // Only try to load the value from prefs if the user did not request a reset.
1006 // Otherwise, skip to generating a new value.
[email protected]1862c0c2013-04-18 06:30:481007 if (!command_line->HasSwitch(switches::kResetVariationState)) {
[email protected]9556a892013-06-21 16:53:201008 int value = local_state->GetInteger(prefs::kMetricsLowEntropySource);
1009 // Old versions of the code would generate values in the range of [1, 8192],
1010 // before the range was switched to [0, 8191] and then to [0, 7999]. Map
1011 // 8192 to 0, so that the 0th bucket remains uniform, while re-generating
1012 // the low entropy source for old values in the [8000, 8191] range.
1013 if (value == 8192)
1014 value = 0;
1015 // If the value is outside the [0, kMaxLowEntropySize) range, re-generate
1016 // it below.
1017 if (value >= 0 && value < kMaxLowEntropySize) {
1018 low_entropy_source_ = value;
[email protected]0c906e92012-10-18 15:24:131019 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", false);
[email protected]e61003a2012-05-24 17:03:191020 return low_entropy_source_;
[email protected]e63a9ef2012-08-30 15:29:421021 }
[email protected]e61003a2012-05-24 17:03:191022 }
1023
[email protected]0c906e92012-10-18 15:24:131024 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", true);
[email protected]e61003a2012-05-24 17:03:191025 low_entropy_source_ = GenerateLowEntropySource();
[email protected]9d7c4a82013-05-07 12:10:491026 local_state->SetInteger(prefs::kMetricsLowEntropySource, low_entropy_source_);
1027 metrics::CachingPermutedEntropyProvider::ClearCache(local_state);
[email protected]e61003a2012-05-24 17:03:191028
1029 return low_entropy_source_;
1030}
1031
1032// static
initial.commit09911bf2008-07-26 23:55:291033std::string MetricsService::GenerateClientID() {
[email protected]7e49ad32012-06-14 14:22:071034 return base::GenerateGUID();
initial.commit09911bf2008-07-26 23:55:291035}
1036
initial.commit09911bf2008-07-26 23:55:291037//------------------------------------------------------------------------------
1038// State save methods
1039
1040void MetricsService::ScheduleNextStateSave() {
[email protected]8454aeb2011-11-19 23:38:201041 state_saver_factory_.InvalidateWeakPtrs();
initial.commit09911bf2008-07-26 23:55:291042
[email protected]b3a25092013-05-28 22:08:161043 base::MessageLoop::current()->PostDelayedTask(FROM_HERE,
[email protected]8454aeb2011-11-19 23:38:201044 base::Bind(&MetricsService::SaveLocalState,
1045 state_saver_factory_.GetWeakPtr()),
[email protected]fc4252a72012-01-12 21:58:471046 base::TimeDelta::FromMinutes(kSaveStateIntervalMinutes));
initial.commit09911bf2008-07-26 23:55:291047}
1048
1049void MetricsService::SaveLocalState() {
1050 PrefService* pref = g_browser_process->local_state();
1051 if (!pref) {
[email protected]a063c102010-07-22 22:20:191052 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291053 return;
1054 }
1055
1056 RecordCurrentState(pref);
initial.commit09911bf2008-07-26 23:55:291057
[email protected]fc4252a72012-01-12 21:58:471058 // TODO(jar):110021 Does this run down the batteries????
initial.commit09911bf2008-07-26 23:55:291059 ScheduleNextStateSave();
1060}
1061
1062
1063//------------------------------------------------------------------------------
1064// Recording control methods
1065
[email protected]410938e02012-10-24 16:33:591066void MetricsService::OpenNewLog() {
1067 DCHECK(!log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:291068
[email protected]29948262012-03-01 12:15:081069 log_manager_.BeginLoggingWithLog(new MetricsLog(client_id_, session_id_),
1070 MetricsLogManager::ONGOING_LOG);
initial.commit09911bf2008-07-26 23:55:291071 if (state_ == INITIALIZED) {
1072 // We only need to schedule that run once.
[email protected]85ed9d42010-06-08 22:37:441073 state_ = INIT_TASK_SCHEDULED;
initial.commit09911bf2008-07-26 23:55:291074
[email protected]85ed9d42010-06-08 22:37:441075 // Schedules a task on the file thread for execution of slower
1076 // initialization steps (such as plugin list generation) necessary
1077 // for sending the initial log. This avoids blocking the main UI
1078 // thread.
[email protected]ed10dd12011-12-07 12:03:421079 BrowserThread::PostDelayedTask(
1080 BrowserThread::FILE,
1081 FROM_HERE,
[email protected]d33e7cc2011-09-23 01:43:561082 base::Bind(&MetricsService::InitTaskGetHardwareClass,
[email protected]c94d7382012-02-28 08:43:401083 self_ptr_factory_.GetWeakPtr(),
[email protected]b3a25092013-05-28 22:08:161084 base::MessageLoop::current()->message_loop_proxy()),
[email protected]7e560102012-03-08 20:58:421085 base::TimeDelta::FromSeconds(kInitializationDelaySeconds));
initial.commit09911bf2008-07-26 23:55:291086 }
1087}
1088
[email protected]410938e02012-10-24 16:33:591089void MetricsService::CloseCurrentLog() {
[email protected]cac267c2011-09-29 15:18:101090 if (!log_manager_.current_log())
initial.commit09911bf2008-07-26 23:55:291091 return;
1092
[email protected]68475e602008-08-22 03:21:151093 // TODO(jar): Integrate bounds on log recording more consistently, so that we
1094 // can stop recording logs that are too big much sooner.
[email protected]cac267c2011-09-29 15:18:101095 if (log_manager_.current_log()->num_events() > kEventLimit) {
[email protected]553dba62009-02-24 19:08:231096 UMA_HISTOGRAM_COUNTS("UMA.Discarded Log Events",
[email protected]cac267c2011-09-29 15:18:101097 log_manager_.current_log()->num_events());
1098 log_manager_.DiscardCurrentLog();
[email protected]410938e02012-10-24 16:33:591099 OpenNewLog(); // Start trivial log to hold our histograms.
[email protected]68475e602008-08-22 03:21:151100 }
1101
[email protected]cac267c2011-09-29 15:18:101102 // Adds to ongoing logs.
1103 log_manager_.current_log()->set_hardware_class(hardware_class_);
[email protected]accdfa62011-09-20 01:56:521104
[email protected]0b33f80b2008-12-17 21:34:361105 // Put incremental data (histogram deltas, and realtime stats deltas) at the
[email protected]147bbc0b2009-01-06 19:37:401106 // end of all log transmissions (initial log handles this separately).
[email protected]024b5cd2011-05-27 03:29:381107 // RecordIncrementalStabilityElements only exists on the derived
1108 // MetricsLog class.
[email protected]279703f2012-01-20 22:23:261109 MetricsLog* current_log =
1110 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]024b5cd2011-05-27 03:29:381111 DCHECK(current_log);
[email protected]197c0772012-05-14 23:50:511112 current_log->RecordEnvironmentProto(plugins_, google_update_metrics_);
[email protected]fe58acc22012-02-29 01:29:581113 current_log->RecordIncrementalStabilityElements(plugins_);
[email protected]024b5cd2011-05-27 03:29:381114 RecordCurrentHistograms();
initial.commit09911bf2008-07-26 23:55:291115
[email protected]29948262012-03-01 12:15:081116 log_manager_.FinishCurrentLog();
initial.commit09911bf2008-07-26 23:55:291117}
1118
[email protected]cac267c2011-09-29 15:18:101119void MetricsService::PushPendingLogsToPersistentStorage() {
initial.commit09911bf2008-07-26 23:55:291120 if (state_ < INITIAL_LOG_READY)
[email protected]28ab7f92009-01-06 21:39:041121 return; // We didn't and still don't have time to get plugin list etc.
initial.commit09911bf2008-07-26 23:55:291122
[email protected]cac267c2011-09-29 15:18:101123 if (log_manager_.has_staged_log()) {
[email protected]7d41ae6d2012-06-26 08:53:031124 // We may race here, and send second copy of the log later.
[email protected]dc61fe92012-06-12 00:13:501125 MetricsLogManager::StoreType store_type;
[email protected]e3eb0c42013-04-18 06:18:581126 if (current_fetch_.get())
[email protected]dc61fe92012-06-12 00:13:501127 store_type = MetricsLogManager::PROVISIONAL_STORE;
1128 else
1129 store_type = MetricsLogManager::NORMAL_STORE;
[email protected]e7508d82012-05-03 15:59:531130 log_manager_.StoreStagedLogAsUnsent(store_type);
initial.commit09911bf2008-07-26 23:55:291131 }
[email protected]cac267c2011-09-29 15:18:101132 DCHECK(!log_manager_.has_staged_log());
[email protected]410938e02012-10-24 16:33:591133 CloseCurrentLog();
initial.commit09911bf2008-07-26 23:55:291134 StoreUnsentLogs();
[email protected]7d41ae6d2012-06-26 08:53:031135
1136 // If there was a staged and/or current log, then there is now at least one
1137 // log waiting to be uploaded.
1138 if (log_manager_.has_unsent_logs())
1139 state_ = SENDING_OLD_LOGS;
initial.commit09911bf2008-07-26 23:55:291140}
1141
1142//------------------------------------------------------------------------------
1143// Transmission of logs methods
1144
[email protected]7f7f1962011-04-20 15:58:161145void MetricsService::StartSchedulerIfNecessary() {
[email protected]410938e02012-10-24 16:33:591146 // Never schedule cutting or uploading of logs in test mode.
1147 if (test_mode_active_)
1148 return;
1149
1150 // Even if reporting is disabled, the scheduler is needed to trigger the
1151 // creation of the initial log, which must be done in order for any logs to be
1152 // persisted on shutdown or backgrounding.
1153 if (recording_active() && (reporting_active() || state_ < INITIAL_LOG_READY))
[email protected]7f7f1962011-04-20 15:58:161154 scheduler_->Start();
initial.commit09911bf2008-07-26 23:55:291155}
1156
[email protected]7f7f1962011-04-20 15:58:161157void MetricsService::StartScheduledUpload() {
[email protected]cd1ac712012-06-26 08:26:471158 // If we're getting no notifications, then the log won't have much in it, and
1159 // it's possible the computer is about to go to sleep, so don't upload and
1160 // stop the scheduler.
[email protected]410938e02012-10-24 16:33:591161 // If recording has been turned off, the scheduler doesn't need to run.
1162 // If reporting is off, proceed if the initial log hasn't been created, since
1163 // that has to happen in order for logs to be cut and stored when persisting.
[email protected]cd1ac712012-06-26 08:26:471164 // TODO(stuartmorgan): Call Stop() on the schedule when reporting and/or
1165 // recording are turned off instead of letting it fire and then aborting.
1166 if (idle_since_last_transmission_ ||
[email protected]410938e02012-10-24 16:33:591167 !recording_active() ||
1168 (!reporting_active() && state_ >= INITIAL_LOG_READY)) {
[email protected]7f7f1962011-04-20 15:58:161169 scheduler_->Stop();
1170 scheduler_->UploadCancelled();
1171 return;
1172 }
1173
[email protected]c15faf372012-07-11 06:01:341174 // If the callback was to upload an old log, but there no longer is one,
1175 // just report success back to the scheduler to begin the ongoing log
1176 // callbacks.
1177 // TODO(stuartmorgan): Consider removing the distinction between
1178 // SENDING_OLD_LOGS and SENDING_CURRENT_LOGS to simplify the state machine
1179 // now that the log upload flow is the same for both modes.
1180 if (state_ == SENDING_OLD_LOGS && !log_manager_.has_unsent_logs()) {
1181 state_ = SENDING_CURRENT_LOGS;
1182 scheduler_->UploadFinished(true /* healthy */, false /* no unsent logs */);
1183 return;
1184 }
[email protected]cd1ac712012-06-26 08:26:471185 // If there are unsent logs, send the next one. If not, start the asynchronous
1186 // process of finalizing the current log for upload.
1187 if (state_ == SENDING_OLD_LOGS) {
1188 DCHECK(log_manager_.has_unsent_logs());
1189 log_manager_.StageNextLogForUpload();
1190 SendStagedLog();
1191 } else {
1192 StartFinalLogInfoCollection();
1193 }
[email protected]29948262012-03-01 12:15:081194}
1195
1196void MetricsService::StartFinalLogInfoCollection() {
1197 // Begin the multi-step process of collecting memory usage histograms:
1198 // First spawn a task to collect the memory details; when that task is
1199 // finished, it will call OnMemoryDetailCollectionDone. That will in turn
1200 // call HistogramSynchronization to collect histograms from all renderers and
1201 // then call OnHistogramSynchronizationDone to continue processing.
[email protected]d119f222012-06-08 02:33:271202 DCHECK(!waiting_for_asynchronous_reporting_step_);
1203 waiting_for_asynchronous_reporting_step_ = true;
[email protected]7f7f1962011-04-20 15:58:161204
[email protected]2226c222011-11-22 00:08:401205 base::Closure callback =
1206 base::Bind(&MetricsService::OnMemoryDetailCollectionDone,
[email protected]c94d7382012-02-28 08:43:401207 self_ptr_factory_.GetWeakPtr());
[email protected]84c988a2011-04-19 17:56:331208
[email protected]2226c222011-11-22 00:08:401209 scoped_refptr<MetricsMemoryDetails> details(
1210 new MetricsMemoryDetails(callback));
[email protected]4306df72012-04-20 18:58:571211 details->StartFetch(MemoryDetails::UPDATE_USER_METRICS);
[email protected]84c988a2011-04-19 17:56:331212
1213 // Collect WebCore cache information to put into a histogram.
[email protected]f3b1a082011-11-18 00:34:301214 for (content::RenderProcessHost::iterator i(
1215 content::RenderProcessHost::AllHostsIterator());
[email protected]84c988a2011-04-19 17:56:331216 !i.IsAtEnd(); i.Advance())
[email protected]2ccf45c2011-08-19 23:35:501217 i.GetCurrentValue()->Send(new ChromeViewMsg_GetCacheResourceStats());
[email protected]84c988a2011-04-19 17:56:331218}
1219
1220void MetricsService::OnMemoryDetailCollectionDone() {
[email protected]c9a3ef82009-05-28 22:02:461221 DCHECK(IsSingleThreaded());
[email protected]7f7f1962011-04-20 15:58:161222 // This function should only be called as the callback from an ansynchronous
1223 // step.
[email protected]d119f222012-06-08 02:33:271224 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]c9a3ef82009-05-28 22:02:461225
[email protected]c9a3ef82009-05-28 22:02:461226 // Create a callback_task for OnHistogramSynchronizationDone.
[email protected]2226c222011-11-22 00:08:401227 base::Closure callback = base::Bind(
1228 &MetricsService::OnHistogramSynchronizationDone,
[email protected]c94d7382012-02-28 08:43:401229 self_ptr_factory_.GetWeakPtr());
[email protected]c9a3ef82009-05-28 22:02:461230
[email protected]c9a3ef82009-05-28 22:02:461231 // Set up the callback to task to call after we receive histograms from all
[email protected]83ab4a282012-07-12 18:19:451232 // child processes. Wait time specifies how long to wait before absolutely
[email protected]c9a3ef82009-05-28 22:02:461233 // calling us back on the task.
[email protected]83ab4a282012-07-12 18:19:451234 content::FetchHistogramsAsynchronously(
[email protected]b3a25092013-05-28 22:08:161235 base::MessageLoop::current(), callback,
[email protected]7e560102012-03-08 20:58:421236 base::TimeDelta::FromMilliseconds(kMaxHistogramGatheringWaitDuration));
[email protected]c9a3ef82009-05-28 22:02:461237}
1238
1239void MetricsService::OnHistogramSynchronizationDone() {
initial.commit09911bf2008-07-26 23:55:291240 DCHECK(IsSingleThreaded());
[email protected]29948262012-03-01 12:15:081241 // This function should only be called as the callback from an ansynchronous
1242 // step.
[email protected]d119f222012-06-08 02:33:271243 DCHECK(waiting_for_asynchronous_reporting_step_);
initial.commit09911bf2008-07-26 23:55:291244
[email protected]d119f222012-06-08 02:33:271245 waiting_for_asynchronous_reporting_step_ = false;
[email protected]29948262012-03-01 12:15:081246 OnFinalLogInfoCollectionDone();
1247}
1248
1249void MetricsService::OnFinalLogInfoCollectionDone() {
[email protected]7f7f1962011-04-20 15:58:161250 // If somehow there is a fetch in progress, we return and hope things work
1251 // out. The scheduler isn't informed since if this happens, the scheduler
1252 // will get a response from the upload.
[email protected]e3eb0c42013-04-18 06:18:581253 DCHECK(!current_fetch_.get());
1254 if (current_fetch_.get())
[email protected]7f7f1962011-04-20 15:58:161255 return;
1256
[email protected]cd1ac712012-06-26 08:26:471257 // Abort if metrics were turned off during the final info gathering.
[email protected]410938e02012-10-24 16:33:591258 if (!recording_active()) {
[email protected]7f7f1962011-04-20 15:58:161259 scheduler_->Stop();
1260 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071261 return;
1262 }
1263
[email protected]cd1ac712012-06-26 08:26:471264 StageNewLog();
[email protected]410938e02012-10-24 16:33:591265
1266 // If logs shouldn't be uploaded, stop here. It's important that this check
1267 // be after StageNewLog(), otherwise the previous logs will never be loaded,
1268 // and thus the open log won't be persisted.
1269 // TODO(stuartmorgan): This is unnecessarily complicated; restructure loading
1270 // of previous logs to not require running part of the upload logic.
1271 // http://crbug.com/157337
1272 if (!reporting_active()) {
1273 scheduler_->Stop();
1274 scheduler_->UploadCancelled();
1275 return;
1276 }
1277
[email protected]29948262012-03-01 12:15:081278 SendStagedLog();
1279}
1280
[email protected]cd1ac712012-06-26 08:26:471281void MetricsService::StageNewLog() {
[email protected]29948262012-03-01 12:15:081282 if (log_manager_.has_staged_log())
1283 return;
1284
1285 switch (state_) {
1286 case INITIALIZED:
1287 case INIT_TASK_SCHEDULED: // We should be further along by now.
[email protected]dc61fe92012-06-12 00:13:501288 NOTREACHED();
[email protected]29948262012-03-01 12:15:081289 return;
1290
1291 case INIT_TASK_DONE:
[email protected]29948262012-03-01 12:15:081292 PrepareInitialLog();
[email protected]ed0fd002012-04-25 23:10:341293 DCHECK_EQ(INIT_TASK_DONE, state_);
[email protected]29948262012-03-01 12:15:081294 log_manager_.LoadPersistedUnsentLogs();
1295 state_ = INITIAL_LOG_READY;
1296 break;
1297
1298 case SENDING_OLD_LOGS:
[email protected]cd1ac712012-06-26 08:26:471299 NOTREACHED(); // Shouldn't be staging a new log during old log sending.
1300 return;
[email protected]29948262012-03-01 12:15:081301
1302 case SENDING_CURRENT_LOGS:
[email protected]410938e02012-10-24 16:33:591303 CloseCurrentLog();
1304 OpenNewLog();
[email protected]29948262012-03-01 12:15:081305 log_manager_.StageNextLogForUpload();
1306 break;
1307
1308 default:
1309 NOTREACHED();
1310 return;
1311 }
1312
1313 DCHECK(log_manager_.has_staged_log());
1314}
1315
1316void MetricsService::PrepareInitialLog() {
[email protected]ed0fd002012-04-25 23:10:341317 DCHECK_EQ(INIT_TASK_DONE, state_);
[email protected]29948262012-03-01 12:15:081318
[email protected]ed0fd002012-04-25 23:10:341319 DCHECK(initial_log_.get());
1320 initial_log_->set_hardware_class(hardware_class_);
[email protected]f31a01a2013-07-13 02:51:111321 initial_log_->RecordEnvironment(plugins_, google_update_metrics_);
[email protected]29948262012-03-01 12:15:081322
1323 // Histograms only get written to the current log, so make the new log current
1324 // before writing them.
1325 log_manager_.PauseCurrentLog();
[email protected]ed0fd002012-04-25 23:10:341326 log_manager_.BeginLoggingWithLog(initial_log_.release(),
1327 MetricsLogManager::INITIAL_LOG);
[email protected]29948262012-03-01 12:15:081328 RecordCurrentHistograms();
1329 log_manager_.FinishCurrentLog();
1330 log_manager_.ResumePausedLog();
1331
1332 DCHECK(!log_manager_.has_staged_log());
1333 log_manager_.StageNextLogForUpload();
1334}
1335
1336void MetricsService::StoreUnsentLogs() {
1337 if (state_ < INITIAL_LOG_READY)
1338 return; // We never Recalled the prior unsent logs.
1339
1340 log_manager_.PersistUnsentLogs();
1341}
1342
1343void MetricsService::SendStagedLog() {
1344 DCHECK(log_manager_.has_staged_log());
1345
[email protected]cac267c2011-09-29 15:18:101346 PrepareFetchWithStagedLog();
[email protected]d01b8732008-10-16 02:18:071347
[email protected]e3eb0c42013-04-18 06:18:581348 bool upload_created = (current_fetch_.get() != NULL);
[email protected]d6bebb92012-06-13 23:14:551349 UMA_HISTOGRAM_BOOLEAN("UMA.UploadCreation", upload_created);
1350 if (!upload_created) {
[email protected]d01b8732008-10-16 02:18:071351 // Compression failed, and log discarded :-/.
[email protected]dc61fe92012-06-12 00:13:501352 // Skip this upload and hope things work out next time.
[email protected]cac267c2011-09-29 15:18:101353 log_manager_.DiscardStagedLog();
[email protected]7f7f1962011-04-20 15:58:161354 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071355 return;
1356 }
1357
[email protected]d119f222012-06-08 02:33:271358 DCHECK(!waiting_for_asynchronous_reporting_step_);
[email protected]d119f222012-06-08 02:33:271359 waiting_for_asynchronous_reporting_step_ = true;
[email protected]dc61fe92012-06-12 00:13:501360
[email protected]e3eb0c42013-04-18 06:18:581361 current_fetch_->Start();
[email protected]d01b8732008-10-16 02:18:071362
1363 HandleIdleSinceLastTransmission(true);
1364}
1365
[email protected]cac267c2011-09-29 15:18:101366void MetricsService::PrepareFetchWithStagedLog() {
[email protected]dc61fe92012-06-12 00:13:501367 DCHECK(log_manager_.has_staged_log());
[email protected]cac78842008-11-27 01:02:201368
[email protected]fe58acc22012-02-29 01:29:581369 // Prepare the protobuf version.
[email protected]e3eb0c42013-04-18 06:18:581370 DCHECK(!current_fetch_.get());
[email protected]5f3e1642013-05-05 03:37:341371 if (log_manager_.has_staged_log()) {
[email protected]e3eb0c42013-04-18 06:18:581372 current_fetch_.reset(net::URLFetcher::Create(
[email protected]5f3e1642013-05-05 03:37:341373 GURL(kServerUrl), net::URLFetcher::POST, this));
[email protected]e3eb0c42013-04-18 06:18:581374 current_fetch_->SetRequestContext(
[email protected]fe58acc22012-02-29 01:29:581375 g_browser_process->system_request_context());
[email protected]537c638d2013-07-04 00:49:191376
1377 // Compress the protobufs 50% of the time. This can be used to see if
1378 // compressed protobufs are being mishandled by machines between the
1379 // client/server or monitoring programs/firewalls on the client.
[email protected]a11e160f2013-07-10 19:45:441380 bool gzip_protobuf_before_uploading =
1381 metrics::ShouldGzipProtobufsBeforeUploading();
[email protected]537c638d2013-07-04 00:49:191382 if (gzip_protobuf_before_uploading) {
1383 std::string log_text = log_manager_.staged_log_text();
1384 std::string compressed_log_text;
1385 bool compression_successful = chrome::GzipCompress(log_text,
1386 &compressed_log_text);
1387 DCHECK(compression_successful);
1388 if (compression_successful) {
1389 current_fetch_->SetUploadData(kMimeType, compressed_log_text);
1390 // Tell the server that we're uploading gzipped protobufs.
1391 current_fetch_->SetExtraRequestHeaders("content-encoding: gzip");
1392 UMA_HISTOGRAM_PERCENTAGE(
1393 "UMA.ProtoCompressionRatio",
1394 100 * compressed_log_text.size() / log_text.size());
[email protected]86bbd332013-07-10 16:42:081395 UMA_HISTOGRAM_CUSTOM_COUNTS(
1396 "UMA.ProtoGzippedKBSaved",
1397 (log_text.size() - compressed_log_text.size()) / 1024,
1398 1, 2000, 50);
[email protected]537c638d2013-07-04 00:49:191399 }
1400 } else {
1401 current_fetch_->SetUploadData(kMimeType, log_manager_.staged_log_text());
1402 }
1403 UMA_HISTOGRAM_BOOLEAN("UMA.ProtoGzipped",
1404 gzip_protobuf_before_uploading);
1405
[email protected]fe58acc22012-02-29 01:29:581406 // We already drop cookies server-side, but we might as well strip them out
1407 // client-side as well.
[email protected]e3eb0c42013-04-18 06:18:581408 current_fetch_->SetLoadFlags(net::LOAD_DO_NOT_SAVE_COOKIES |
1409 net::LOAD_DO_NOT_SEND_COOKIES);
[email protected]fe58acc22012-02-29 01:29:581410 }
initial.commit09911bf2008-07-26 23:55:291411}
1412
[email protected]10c2d692012-05-11 05:32:231413void MetricsService::OnURLFetchComplete(const net::URLFetcher* source) {
[email protected]d119f222012-06-08 02:33:271414 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]fe58acc22012-02-29 01:29:581415
1416 // We're not allowed to re-use the existing |URLFetcher|s, so free them here.
[email protected]e3eb0c42013-04-18 06:18:581417 // Note however that |source| is aliased to the fetcher, so we should be
[email protected]4266def22012-05-17 01:02:401418 // careful not to delete it too early.
[email protected]e3eb0c42013-04-18 06:18:581419 DCHECK_EQ(current_fetch_.get(), source);
1420 scoped_ptr<net::URLFetcher> s(current_fetch_.Pass());
[email protected]fe58acc22012-02-29 01:29:581421
[email protected]dc61fe92012-06-12 00:13:501422 int response_code = source->GetResponseCode();
[email protected]fe58acc22012-02-29 01:29:581423
[email protected]dc61fe92012-06-12 00:13:501424 // Log a histogram to track response success vs. failure rates.
[email protected]e3eb0c42013-04-18 06:18:581425 UMA_HISTOGRAM_ENUMERATION("UMA.UploadResponseStatus.Protobuf",
1426 ResponseCodeToStatus(response_code),
1427 NUM_RESPONSE_STATUSES);
[email protected]fe58acc22012-02-29 01:29:581428
[email protected]e7508d82012-05-03 15:59:531429 // If the upload was provisionally stored, drop it now that the upload is
1430 // known to have gone through.
1431 log_manager_.DiscardLastProvisionalStore();
initial.commit09911bf2008-07-26 23:55:291432
[email protected]dc61fe92012-06-12 00:13:501433 bool upload_succeeded = response_code == 200;
[email protected]7f7f1962011-04-20 15:58:161434
[email protected]0eb34fee2009-01-21 08:04:381435 // Provide boolean for error recovery (allow us to ignore response_code).
[email protected]dc6f4962009-02-13 01:25:501436 bool discard_log = false;
[email protected]5f3e1642013-05-05 03:37:341437 const size_t log_size = log_manager_.staged_log_text().length();
[email protected]dc61fe92012-06-12 00:13:501438 if (!upload_succeeded && log_size > kUploadLogAvoidRetransmitSize) {
1439 UMA_HISTOGRAM_COUNTS("UMA.Large Rejected Log was Discarded",
1440 static_cast<int>(log_size));
[email protected]0eb34fee2009-01-21 08:04:381441 discard_log = true;
[email protected]dc61fe92012-06-12 00:13:501442 } else if (response_code == 400) {
[email protected]0eb34fee2009-01-21 08:04:381443 // Bad syntax. Retransmission won't work.
[email protected]0eb34fee2009-01-21 08:04:381444 discard_log = true;
[email protected]68475e602008-08-22 03:21:151445 }
1446
[email protected]e3eb0c42013-04-18 06:18:581447 if (upload_succeeded || discard_log)
[email protected]5f3e1642013-05-05 03:37:341448 log_manager_.DiscardStagedLog();
[email protected]dc61fe92012-06-12 00:13:501449
1450 waiting_for_asynchronous_reporting_step_ = false;
1451
1452 if (!log_manager_.has_staged_log()) {
initial.commit09911bf2008-07-26 23:55:291453 switch (state_) {
1454 case INITIAL_LOG_READY:
[email protected]cd1ac712012-06-26 08:26:471455 state_ = log_manager_.has_unsent_logs() ? SENDING_OLD_LOGS
1456 : SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291457 break;
1458
initial.commit09911bf2008-07-26 23:55:291459 case SENDING_OLD_LOGS:
[email protected]d53e2232011-06-30 15:54:571460 // Store the updated list to disk now that the removed log is uploaded.
initial.commit09911bf2008-07-26 23:55:291461 StoreUnsentLogs();
[email protected]cd1ac712012-06-26 08:26:471462 if (!log_manager_.has_unsent_logs())
1463 state_ = SENDING_CURRENT_LOGS;
initial.commit09911bf2008-07-26 23:55:291464 break;
1465
1466 case SENDING_CURRENT_LOGS:
1467 break;
1468
1469 default:
[email protected]a063c102010-07-22 22:20:191470 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291471 break;
1472 }
[email protected]d01b8732008-10-16 02:18:071473
[email protected]cac267c2011-09-29 15:18:101474 if (log_manager_.has_unsent_logs())
[email protected]ed0fd002012-04-25 23:10:341475 DCHECK_LT(state_, SENDING_CURRENT_LOGS);
initial.commit09911bf2008-07-26 23:55:291476 }
[email protected]252873ef2008-08-04 21:59:451477
[email protected]7f7f1962011-04-20 15:58:161478 // Error 400 indicates a problem with the log, not with the server, so
1479 // don't consider that a sign that the server is in trouble.
[email protected]dc61fe92012-06-12 00:13:501480 bool server_is_healthy = upload_succeeded || response_code == 400;
[email protected]dc61fe92012-06-12 00:13:501481 scheduler_->UploadFinished(server_is_healthy, log_manager_.has_unsent_logs());
[email protected]d67d1052011-06-09 05:11:411482
1483 // Collect network stats if UMA upload succeeded.
[email protected]b8ddb052012-04-19 02:36:061484 IOThread* io_thread = g_browser_process->io_thread();
1485 if (server_is_healthy && io_thread) {
1486 chrome_browser_net::CollectNetworkStats(network_stats_server_, io_thread);
[email protected]adbb3762012-03-09 22:20:081487 chrome_browser_net::CollectPipeliningCapabilityStatsOnUIThread(
[email protected]b8ddb052012-04-19 02:36:061488 http_pipelining_test_server_, io_thread);
[email protected]aa312812013-04-30 19:46:051489#if defined(OS_WIN)
1490 chrome::CollectTimeTicksStats();
1491#endif
[email protected]adbb3762012-03-09 22:20:081492 }
initial.commit09911bf2008-07-26 23:55:291493}
1494
[email protected]57ecc4b2010-08-11 03:02:511495void MetricsService::IncrementPrefValue(const char* path) {
[email protected]e73c01972008-08-13 00:18:241496 PrefService* pref = g_browser_process->local_state();
1497 DCHECK(pref);
1498 int value = pref->GetInteger(path);
1499 pref->SetInteger(path, value + 1);
1500}
1501
[email protected]57ecc4b2010-08-11 03:02:511502void MetricsService::IncrementLongPrefsValue(const char* path) {
[email protected]0bb1a622009-03-04 03:22:321503 PrefService* pref = g_browser_process->local_state();
1504 DCHECK(pref);
1505 int64 value = pref->GetInt64(path);
[email protected]b42c5e42010-06-03 20:43:251506 pref->SetInt64(path, value + 1);
[email protected]0bb1a622009-03-04 03:22:321507}
1508
[email protected]752a5262013-06-23 14:53:421509void MetricsService::LogLoadStarted(content::WebContents* web_contents) {
[email protected]5d490e42012-08-30 05:16:431510 content::RecordAction(content::UserMetricsAction("PageLoad"));
[email protected]dd8d12a2011-09-02 02:10:151511 HISTOGRAM_ENUMERATION("Chrome.UmaPageloadCounter", 1, 2);
[email protected]e73c01972008-08-13 00:18:241512 IncrementPrefValue(prefs::kStabilityPageLoadCount);
[email protected]0bb1a622009-03-04 03:22:321513 IncrementLongPrefsValue(prefs::kUninstallMetricsPageLoadCount);
[email protected]0b33f80b2008-12-17 21:34:361514 // We need to save the prefs, as page load count is a critical stat, and it
1515 // might be lost due to a crash :-(.
[email protected]752a5262013-06-23 14:53:421516
1517 // Track whether the page loaded is a search results page.
1518 if (web_contents) {
1519 SearchTabHelper* search_tab_helper =
1520 SearchTabHelper::FromWebContents(web_contents);
1521 if (search_tab_helper) {
1522 if (search_tab_helper->model()->mode().is_search_results())
1523 content::RecordAction(content::UserMetricsAction("PageLoadSRP"));
1524 }
1525 }
initial.commit09911bf2008-07-26 23:55:291526}
1527
[email protected]c3721482012-03-23 16:21:481528void MetricsService::LogRendererCrash(content::RenderProcessHost* host,
1529 base::TerminationStatus status,
[email protected]f1675202012-07-09 15:18:001530 int exit_code) {
[email protected]f3b1a082011-11-18 00:34:301531 Profile* profile = Profile::FromBrowserContext(host->GetBrowserContext());
[email protected]6f371442011-11-09 06:45:461532 ExtensionService* service = profile->GetExtensionService();
1533 bool was_extension_process =
[email protected]f3b1a082011-11-18 00:34:301534 service && service->process_map()->Contains(host->GetID());
[email protected]c3721482012-03-23 16:21:481535 if (status == base::TERMINATION_STATUS_PROCESS_CRASHED ||
1536 status == base::TERMINATION_STATUS_ABNORMAL_TERMINATION) {
[email protected]d7c1fa62012-06-15 23:35:301537 if (was_extension_process) {
[email protected]718eab62011-10-05 21:16:521538 IncrementPrefValue(prefs::kStabilityExtensionRendererCrashCount);
[email protected]d7c1fa62012-06-15 23:35:301539
[email protected]1026afd2013-03-20 14:28:541540 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Extension",
1541 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301542 } else {
[email protected]718eab62011-10-05 21:16:521543 IncrementPrefValue(prefs::kStabilityRendererCrashCount);
initial.commit09911bf2008-07-26 23:55:291544
[email protected]1026afd2013-03-20 14:28:541545 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Renderer",
1546 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301547 }
1548
[email protected]718eab62011-10-05 21:16:521549 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildCrashes",
1550 was_extension_process ? 2 : 1);
[email protected]c3721482012-03-23 16:21:481551 } else if (status == base::TERMINATION_STATUS_PROCESS_WAS_KILLED) {
[email protected]718eab62011-10-05 21:16:521552 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildKills",
1553 was_extension_process ? 2 : 1);
[email protected]f1675202012-07-09 15:18:001554 } else if (status == base::TERMINATION_STATUS_STILL_RUNNING) {
1555 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.DisconnectedAlive",
[email protected]718eab62011-10-05 21:16:521556 was_extension_process ? 2 : 1);
1557 }
1558 }
[email protected]1f085622009-12-04 05:33:451559
initial.commit09911bf2008-07-26 23:55:291560void MetricsService::LogRendererHang() {
[email protected]e73c01972008-08-13 00:18:241561 IncrementPrefValue(prefs::kStabilityRendererHangCount);
initial.commit09911bf2008-07-26 23:55:291562}
1563
[email protected]c0c55e92011-09-10 18:47:301564bool MetricsService::UmaMetricsProperlyShutdown() {
1565 CHECK(clean_shutdown_status_ == CLEANLY_SHUTDOWN ||
1566 clean_shutdown_status_ == NEED_TO_SHUTDOWN);
1567 return clean_shutdown_status_ == CLEANLY_SHUTDOWN;
1568}
1569
[email protected]466f3c12011-03-23 21:20:381570void MetricsService::LogCleanShutdown() {
[email protected]acd55b32011-09-05 17:35:311571 // Redundant hack to write pref ASAP.
[email protected]84c384e2013-03-01 23:20:191572 MarkAppCleanShutdownAndCommit();
1573
[email protected]c0c55e92011-09-10 18:47:301574 // Redundant setting to assure that we always reset this value at shutdown
1575 // (and that we don't use some alternate path, and not call LogCleanShutdown).
1576 clean_shutdown_status_ = CLEANLY_SHUTDOWN;
[email protected]acd55b32011-09-05 17:35:311577
[email protected]466f3c12011-03-23 21:20:381578 RecordBooleanPrefValue(prefs::kStabilityExitedCleanly, true);
1579}
1580
[email protected]c1834a92011-01-21 18:21:031581#if defined(OS_CHROMEOS)
1582void MetricsService::LogChromeOSCrash(const std::string &crash_type) {
1583 if (crash_type == "user")
1584 IncrementPrefValue(prefs::kStabilityOtherUserCrashCount);
1585 else if (crash_type == "kernel")
1586 IncrementPrefValue(prefs::kStabilityKernelCrashCount);
1587 else if (crash_type == "uncleanshutdown")
1588 IncrementPrefValue(prefs::kStabilitySystemUncleanShutdownCount);
1589 else
1590 NOTREACHED() << "Unexpected Chrome OS crash type " << crash_type;
1591 // Wake up metrics logs sending if necessary now that new
1592 // log data is available.
1593 HandleIdleSinceLastTransmission(false);
1594}
1595#endif // OS_CHROMEOS
1596
[email protected]650b2d52013-02-10 03:41:451597void MetricsService::LogPluginLoadingError(const base::FilePath& plugin_path) {
[email protected]d7bd3e52013-07-21 04:29:201598 content::WebPluginInfo plugin;
[email protected]cd937072012-07-02 09:00:291599 bool success =
1600 content::PluginService::GetInstance()->GetPluginInfoByPath(plugin_path,
1601 &plugin);
1602 DCHECK(success);
1603 ChildProcessStats& stats = child_process_stats_buffer_[plugin.name];
1604 // Initialize the type if this entry is new.
1605 if (stats.process_type == content::PROCESS_TYPE_UNKNOWN) {
1606 // The plug-in process might not actually of type PLUGIN (which means
1607 // NPAPI), but we only care that it is *a* plug-in process.
1608 stats.process_type = content::PROCESS_TYPE_PLUGIN;
1609 } else {
1610 DCHECK(IsPluginProcess(stats.process_type));
1611 }
1612 stats.loading_errors++;
1613}
1614
[email protected]f4eaf7b92013-02-28 22:00:401615MetricsService::ChildProcessStats& MetricsService::GetChildProcessStats(
1616 const content::ChildProcessData& data) {
1617 const string16& child_name = data.name;
[email protected]f3b357692013-03-22 05:16:131618 if (!ContainsKey(child_process_stats_buffer_, child_name)) {
1619 child_process_stats_buffer_[child_name] =
1620 ChildProcessStats(data.process_type);
1621 }
[email protected]f4eaf7b92013-02-28 22:00:401622 return child_process_stats_buffer_[child_name];
initial.commit09911bf2008-07-26 23:55:291623}
1624
initial.commit09911bf2008-07-26 23:55:291625void MetricsService::RecordPluginChanges(PrefService* pref) {
[email protected]f8628c22011-04-05 12:10:181626 ListPrefUpdate update(pref, prefs::kStabilityPluginStats);
1627 ListValue* plugins = update.Get();
initial.commit09911bf2008-07-26 23:55:291628 DCHECK(plugins);
1629
1630 for (ListValue::iterator value_iter = plugins->begin();
1631 value_iter != plugins->end(); ++value_iter) {
1632 if (!(*value_iter)->IsType(Value::TYPE_DICTIONARY)) {
[email protected]a063c102010-07-22 22:20:191633 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291634 continue;
1635 }
1636
1637 DictionaryValue* plugin_dict = static_cast<DictionaryValue*>(*value_iter);
[email protected]57ecc4b2010-08-11 03:02:511638 std::string plugin_name;
[email protected]8e50b602009-03-03 22:59:431639 plugin_dict->GetString(prefs::kStabilityPluginName, &plugin_name);
[email protected]6470ee8f2009-03-03 20:46:401640 if (plugin_name.empty()) {
[email protected]a063c102010-07-22 22:20:191641 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291642 continue;
1643 }
1644
[email protected]57ecc4b2010-08-11 03:02:511645 // TODO(viettrungluu): remove conversions
[email protected]68b9e72b2011-08-05 23:08:221646 string16 name16 = UTF8ToUTF16(plugin_name);
1647 if (child_process_stats_buffer_.find(name16) ==
1648 child_process_stats_buffer_.end()) {
initial.commit09911bf2008-07-26 23:55:291649 continue;
[email protected]68b9e72b2011-08-05 23:08:221650 }
initial.commit09911bf2008-07-26 23:55:291651
[email protected]68b9e72b2011-08-05 23:08:221652 ChildProcessStats stats = child_process_stats_buffer_[name16];
initial.commit09911bf2008-07-26 23:55:291653 if (stats.process_launches) {
1654 int launches = 0;
[email protected]8e50b602009-03-03 22:59:431655 plugin_dict->GetInteger(prefs::kStabilityPluginLaunches, &launches);
initial.commit09911bf2008-07-26 23:55:291656 launches += stats.process_launches;
[email protected]8e50b602009-03-03 22:59:431657 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches, launches);
initial.commit09911bf2008-07-26 23:55:291658 }
1659 if (stats.process_crashes) {
1660 int crashes = 0;
[email protected]8e50b602009-03-03 22:59:431661 plugin_dict->GetInteger(prefs::kStabilityPluginCrashes, &crashes);
initial.commit09911bf2008-07-26 23:55:291662 crashes += stats.process_crashes;
[email protected]8e50b602009-03-03 22:59:431663 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes, crashes);
initial.commit09911bf2008-07-26 23:55:291664 }
1665 if (stats.instances) {
1666 int instances = 0;
[email protected]8e50b602009-03-03 22:59:431667 plugin_dict->GetInteger(prefs::kStabilityPluginInstances, &instances);
initial.commit09911bf2008-07-26 23:55:291668 instances += stats.instances;
[email protected]8e50b602009-03-03 22:59:431669 plugin_dict->SetInteger(prefs::kStabilityPluginInstances, instances);
initial.commit09911bf2008-07-26 23:55:291670 }
[email protected]cd937072012-07-02 09:00:291671 if (stats.loading_errors) {
1672 int loading_errors = 0;
1673 plugin_dict->GetInteger(prefs::kStabilityPluginLoadingErrors,
1674 &loading_errors);
1675 loading_errors += stats.loading_errors;
1676 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
1677 loading_errors);
1678 }
initial.commit09911bf2008-07-26 23:55:291679
[email protected]68b9e72b2011-08-05 23:08:221680 child_process_stats_buffer_.erase(name16);
initial.commit09911bf2008-07-26 23:55:291681 }
1682
1683 // Now go through and add dictionaries for plugins that didn't already have
1684 // reports in Local State.
[email protected]68b9e72b2011-08-05 23:08:221685 for (std::map<string16, ChildProcessStats>::iterator cache_iter =
[email protected]a27a9382009-02-11 23:55:101686 child_process_stats_buffer_.begin();
1687 cache_iter != child_process_stats_buffer_.end(); ++cache_iter) {
[email protected]a27a9382009-02-11 23:55:101688 ChildProcessStats stats = cache_iter->second;
[email protected]0d84c5d2009-10-09 01:10:421689
1690 // Insert only plugins information into the plugins list.
[email protected]8d5f1dae2011-11-11 14:30:411691 if (!IsPluginProcess(stats.process_type))
[email protected]0d84c5d2009-10-09 01:10:421692 continue;
1693
[email protected]57ecc4b2010-08-11 03:02:511694 // TODO(viettrungluu): remove conversion
[email protected]68b9e72b2011-08-05 23:08:221695 std::string plugin_name = UTF16ToUTF8(cache_iter->first);
[email protected]0d84c5d2009-10-09 01:10:421696
initial.commit09911bf2008-07-26 23:55:291697 DictionaryValue* plugin_dict = new DictionaryValue;
1698
[email protected]8e50b602009-03-03 22:59:431699 plugin_dict->SetString(prefs::kStabilityPluginName, plugin_name);
1700 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches,
initial.commit09911bf2008-07-26 23:55:291701 stats.process_launches);
[email protected]8e50b602009-03-03 22:59:431702 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes,
initial.commit09911bf2008-07-26 23:55:291703 stats.process_crashes);
[email protected]8e50b602009-03-03 22:59:431704 plugin_dict->SetInteger(prefs::kStabilityPluginInstances,
initial.commit09911bf2008-07-26 23:55:291705 stats.instances);
[email protected]cd937072012-07-02 09:00:291706 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
1707 stats.loading_errors);
initial.commit09911bf2008-07-26 23:55:291708 plugins->Append(plugin_dict);
1709 }
[email protected]a27a9382009-02-11 23:55:101710 child_process_stats_buffer_.clear();
initial.commit09911bf2008-07-26 23:55:291711}
1712
[email protected]dd98f392013-02-04 13:03:221713bool MetricsService::CanLogNotification() {
[email protected]2c910b72011-03-08 21:16:321714 // We simply don't log anything to UMA if there is a single incognito
initial.commit09911bf2008-07-26 23:55:291715 // session visible. The problem is that we always notify using the orginal
1716 // profile in order to simplify notification processing.
[email protected]e764e582012-08-01 03:01:291717 return !chrome::IsOffTheRecordSessionActive();
initial.commit09911bf2008-07-26 23:55:291718}
1719
[email protected]57ecc4b2010-08-11 03:02:511720void MetricsService::RecordBooleanPrefValue(const char* path, bool value) {
initial.commit09911bf2008-07-26 23:55:291721 DCHECK(IsSingleThreaded());
1722
1723 PrefService* pref = g_browser_process->local_state();
1724 DCHECK(pref);
1725
1726 pref->SetBoolean(path, value);
1727 RecordCurrentState(pref);
1728}
1729
1730void MetricsService::RecordCurrentState(PrefService* pref) {
[email protected]0bb1a622009-03-04 03:22:321731 pref->SetInt64(prefs::kStabilityLastTimestampSec, Time::Now().ToTimeT());
initial.commit09911bf2008-07-26 23:55:291732
1733 RecordPluginChanges(pref);
1734}
1735
[email protected]8d5f1dae2011-11-11 14:30:411736// static
[email protected]f3b357692013-03-22 05:16:131737bool MetricsService::IsPluginProcess(int process_type) {
1738 return (process_type == content::PROCESS_TYPE_PLUGIN ||
1739 process_type == content::PROCESS_TYPE_PPAPI_PLUGIN ||
1740 process_type == content::PROCESS_TYPE_PPAPI_BROKER);
[email protected]8d5f1dae2011-11-11 14:30:411741}
1742
[email protected]5ccaa412009-11-13 22:00:161743#if defined(OS_CHROMEOS)
[email protected]29cf16772010-04-21 15:13:471744void MetricsService::StartExternalMetrics() {
[email protected]5ccaa412009-11-13 22:00:161745 external_metrics_ = new chromeos::ExternalMetrics;
[email protected]29cf16772010-04-21 15:13:471746 external_metrics_->Start();
[email protected]5ccaa412009-11-13 22:00:161747}
1748#endif
[email protected]3819f2ee2011-08-21 09:44:381749
[email protected]3819f2ee2011-08-21 09:44:381750// static
1751bool MetricsServiceHelper::IsMetricsReportingEnabled() {
1752 bool result = false;
1753 const PrefService* local_state = g_browser_process->local_state();
1754 if (local_state) {
1755 const PrefService::Preference* uma_pref =
1756 local_state->FindPreference(prefs::kMetricsReportingEnabled);
1757 if (uma_pref) {
1758 bool success = uma_pref->GetValue()->GetAsBoolean(&result);
1759 DCHECK(success);
1760 }
1761 }
1762 return result;
1763}