blob: 91a38ed1fb72e378886142a58aeca243627204e5 [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]0b33f80b2008-12-17 21:34:3639// is 20 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]ce072a72010-12-31 20:02:16167#include "base/threading/platform_thread.h"
[email protected]b3841c502011-03-09 01:21:31168#include "base/threading/thread.h"
[email protected]3a7b66d2012-04-26 16:34:16169#include "base/threading/thread_restrictions.h"
[email protected]ed0fd002012-04-25 23:10:34170#include "base/tracked_objects.h"
[email protected]440b37b22010-08-30 05:31:40171#include "base/utf_string_conversions.h"
[email protected]679082052010-07-21 21:30:13172#include "base/values.h"
[email protected]19871a92012-06-27 19:50:32173#include "chrome/browser/autocomplete/autocomplete_log.h"
initial.commit09911bf2008-07-26 23:55:29174#include "chrome/browser/browser_process.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]679082052010-07-21 21:30:13179#include "chrome/browser/metrics/metrics_log.h"
[email protected]cac267c2011-09-29 15:18:10180#include "chrome/browser/metrics/metrics_log_serializer.h"
[email protected]7f7f1962011-04-20 15:58:16181#include "chrome/browser/metrics/metrics_reporting_scheduler.h"
[email protected]aa312812013-04-30 19:46:05182#include "chrome/browser/metrics/time_ticks_experiment_win.h"
[email protected]ed0fd002012-04-25 23:10:34183#include "chrome/browser/metrics/tracking_synchronizer.h"
[email protected]adbb3762012-03-09 22:20:08184#include "chrome/browser/net/http_pipelining_compatibility_client.h"
[email protected]d67d1052011-06-09 05:11:41185#include "chrome/browser/net/network_stats.h"
[email protected]f8628c22011-04-05 12:10:18186#include "chrome/browser/prefs/scoped_user_pref_update.h"
[email protected]8ecad5e2010-12-02 21:18:33187#include "chrome/browser/profiles/profile.h"
[email protected]8e5c89a2011-06-07 18:13:33188#include "chrome/browser/search_engines/template_url_service.h"
[email protected]71b73f02011-04-06 15:57:29189#include "chrome/browser/ui/browser_list.h"
[email protected]10b132b02012-07-27 20:46:18190#include "chrome/browser/ui/browser_otr_state.h"
[email protected]157d5472009-11-05 22:31:03191#include "chrome/common/child_process_logging.h"
[email protected]f3b357692013-03-22 05:16:13192#include "chrome/common/chrome_process_type.h"
[email protected]432115822011-07-10 15:52:27193#include "chrome/common/chrome_notification_types.h"
[email protected]d7c1fa62012-06-15 23:35:30194#include "chrome/common/chrome_result_codes.h"
[email protected]92745242009-06-12 16:52:21195#include "chrome/common/chrome_switches.h"
[email protected]20f999b52012-08-24 22:32:59196#include "chrome/common/metrics/entropy_provider.h"
[email protected]2e4cd1a2012-01-12 08:51:03197#include "chrome/common/metrics/metrics_log_manager.h"
[email protected]b4a72d842012-03-22 20:09:09198#include "chrome/common/net/test_server_locations.h"
initial.commit09911bf2008-07-26 23:55:29199#include "chrome/common/pref_names.h"
[email protected]e09ba552009-02-05 03:26:29200#include "chrome/common/render_messages.h"
[email protected]4967f792012-01-20 22:14:40201#include "content/public/browser/child_process_data.h"
[email protected]83ab4a282012-07-12 18:19:45202#include "content/public/browser/histogram_fetcher.h"
[email protected]09d31d52012-03-11 22:30:27203#include "content/public/browser/load_notification_details.h"
[email protected]ad50def52011-10-19 23:17:07204#include "content/public/browser/notification_service.h"
[email protected]3a5180ae2011-12-21 02:39:38205#include "content/public/browser/plugin_service.h"
[email protected]f3b1a082011-11-18 00:34:30206#include "content/public/browser/render_process_host.h"
[email protected]5d490e42012-08-30 05:16:43207#include "content/public/browser/user_metrics.h"
[email protected]459f3502012-09-17 17:08:12208#include "content/public/browser/web_contents.h"
[email protected]fe58acc22012-02-29 01:29:58209#include "net/base/load_flags.h"
[email protected]3dc1bc42012-06-19 08:20:53210#include "net/url_request/url_fetcher.h"
[email protected]91d9f3d2011-08-14 05:24:44211#include "webkit/plugins/webplugininfo.h"
initial.commit09911bf2008-07-26 23:55:29212
[email protected]e06131d2010-02-10 18:40:33213// TODO(port): port browser_distribution.h.
214#if !defined(OS_POSIX)
[email protected]79bf0b72009-04-27 21:30:55215#include "chrome/installer/util/browser_distribution.h"
[email protected]dc6f4962009-02-13 01:25:50216#endif
217
[email protected]5ccaa412009-11-13 22:00:16218#if defined(OS_CHROMEOS)
[email protected]db342d52010-08-09 21:19:37219#include "chrome/browser/chromeos/cros/cros_library.h"
[email protected]5ccaa412009-11-13 22:00:16220#include "chrome/browser/chromeos/external_metrics.h"
[email protected]d43970a72011-07-10 06:24:52221#include "chrome/browser/chromeos/system/statistics_provider.h"
[email protected]5ccaa412009-11-13 22:00:16222#endif
223
[email protected]d7c1fa62012-06-15 23:35:30224#if defined(OS_WIN)
225#include <windows.h> // Needed for STATUS_* codes
[email protected]d7c1fa62012-06-15 23:35:30226#endif
227
[email protected]e1acf6f2008-10-27 20:43:33228using base::Time;
[email protected]631bb742011-11-02 11:29:39229using content::BrowserThread;
[email protected]4967f792012-01-20 22:14:40230using content::ChildProcessData;
[email protected]09d31d52012-03-11 22:30:27231using content::LoadNotificationDetails;
[email protected]3a5180ae2011-12-21 02:39:38232using content::PluginService;
[email protected]e1acf6f2008-10-27 20:43:33233
[email protected]fe58acc22012-02-29 01:29:58234namespace {
[email protected]b2a4812d2012-02-28 05:31:31235
[email protected]fe58acc22012-02-29 01:29:58236// Check to see that we're being called on only one thread.
237bool IsSingleThreaded() {
238 static base::PlatformThreadId thread_id = 0;
239 if (!thread_id)
240 thread_id = base::PlatformThread::CurrentId();
241 return base::PlatformThread::CurrentId() == thread_id;
242}
243
[email protected]7f7f1962011-04-20 15:58:16244// The delay, in seconds, after starting recording before doing expensive
245// initialization work.
[email protected]12180f82012-10-10 21:13:30246#if defined(OS_ANDROID) || defined(OS_IOS)
247// On mobile devices, a significant portion of sessions last less than a minute.
248// Use a shorter timer on these platforms to avoid losing data.
249// TODO(dfalcantara): To avoid delaying startup, tighten up initialization so
250// that it occurs after the user gets their initial page.
251const int kInitializationDelaySeconds = 5;
252#else
[email protected]fe58acc22012-02-29 01:29:58253const int kInitializationDelaySeconds = 30;
[email protected]12180f82012-10-10 21:13:30254#endif
[email protected]252873ef2008-08-04 21:59:45255
[email protected]c9a3ef82009-05-28 22:02:46256// This specifies the amount of time to wait for all renderers to send their
257// data.
[email protected]fe58acc22012-02-29 01:29:58258const int kMaxHistogramGatheringWaitDuration = 60000; // 60 seconds.
[email protected]c9a3ef82009-05-28 22:02:46259
[email protected]54702c92011-04-15 15:06:43260// The maximum number of events in a log uploaded to the UMA server.
[email protected]fe58acc22012-02-29 01:29:58261const int kEventLimit = 2400;
[email protected]68475e602008-08-22 03:21:15262
263// If an upload fails, and the transmission was over this byte count, then we
264// will discard the log, and not try to retransmit it. We also don't persist
265// the log to the prefs for transmission during the next chrome session if this
266// limit is exceeded.
[email protected]fe58acc22012-02-29 01:29:58267const size_t kUploadLogAvoidRetransmitSize = 50000;
initial.commit09911bf2008-07-26 23:55:29268
[email protected]fc4252a72012-01-12 21:58:47269// Interval, in minutes, between state saves.
[email protected]fe58acc22012-02-29 01:29:58270const int kSaveStateIntervalMinutes = 5;
271
[email protected]4266def22012-05-17 01:02:40272enum ResponseStatus {
273 UNKNOWN_FAILURE,
274 SUCCESS,
275 BAD_REQUEST, // Invalid syntax or log too large.
[email protected]9f5c1ce82012-05-23 23:11:28276 NO_RESPONSE,
[email protected]4266def22012-05-17 01:02:40277 NUM_RESPONSE_STATUSES
278};
279
280ResponseStatus ResponseCodeToStatus(int response_code) {
281 switch (response_code) {
282 case 200:
283 return SUCCESS;
284 case 400:
285 return BAD_REQUEST;
[email protected]9f5c1ce82012-05-23 23:11:28286 case net::URLFetcher::RESPONSE_CODE_INVALID:
287 return NO_RESPONSE;
[email protected]4266def22012-05-17 01:02:40288 default:
289 return UNKNOWN_FAILURE;
290 }
291}
292
[email protected]e61003a2012-05-24 17:03:19293// The argument used to generate a non-identifying entropy source. We want no
294// more than 13 bits of entropy, so use this max to return a number between 1
295// and 2^13 = 8192 as the entropy source.
[email protected]20f999b52012-08-24 22:32:59296const uint32 kMaxLowEntropySize = (1 << 13);
[email protected]e61003a2012-05-24 17:03:19297
[email protected]e63a9ef2012-08-30 15:29:42298// Default prefs value for prefs::kMetricsLowEntropySource to indicate that the
299// value has not yet been set.
300const int kLowEntropySourceNotSet = -1;
301
[email protected]e61003a2012-05-24 17:03:19302// Generates a new non-identifying entropy source used to seed persistent
303// activities.
304int GenerateLowEntropySource() {
[email protected]20f999b52012-08-24 22:32:59305 return base::RandInt(0, kMaxLowEntropySize - 1);
[email protected]e61003a2012-05-24 17:03:19306}
307
[email protected]d7c1fa62012-06-15 23:35:30308// Converts an exit code into something that can be inserted into our
309// histograms (which expect non-negative numbers less than MAX_INT).
310int MapCrashExitCodeForHistogram(int exit_code) {
311#if defined(OS_WIN)
312 // Since |abs(STATUS_GUARD_PAGE_VIOLATION) == MAX_INT| it causes problems in
313 // histograms.cc. Solve this by remapping it to a smaller value, which
314 // hopefully doesn't conflict with other codes.
315 if (exit_code == STATUS_GUARD_PAGE_VIOLATION)
316 return 0x1FCF7EC3; // Randomly picked number.
317#endif
318
319 return std::abs(exit_code);
320}
321
[email protected]84c384e2013-03-01 23:20:19322void MarkAppCleanShutdownAndCommit() {
323 PrefService* pref = g_browser_process->local_state();
324 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
325 // Start writing right away (write happens on a different thread).
326 pref->CommitPendingWrite();
327}
328
[email protected]20f999b52012-08-24 22:32:59329} // namespace
initial.commit09911bf2008-07-26 23:55:29330
[email protected]c0c55e92011-09-10 18:47:30331// static
332MetricsService::ShutdownCleanliness MetricsService::clean_shutdown_status_ =
333 MetricsService::CLEANLY_SHUTDOWN;
334
[email protected]679082052010-07-21 21:30:13335// This is used to quickly log stats from child process related notifications in
336// MetricsService::child_stats_buffer_. The buffer's contents are transferred
337// out when Local State is periodically saved. The information is then
338// reported to the UMA server on next launch.
339struct MetricsService::ChildProcessStats {
340 public:
[email protected]f3b357692013-03-22 05:16:13341 explicit ChildProcessStats(int process_type)
[email protected]679082052010-07-21 21:30:13342 : process_launches(0),
343 process_crashes(0),
344 instances(0),
[email protected]cd937072012-07-02 09:00:29345 loading_errors(0),
[email protected]f3b357692013-03-22 05:16:13346 process_type(process_type) {}
[email protected]679082052010-07-21 21:30:13347
348 // This constructor is only used by the map to return some default value for
349 // an index for which no value has been assigned.
350 ChildProcessStats()
351 : process_launches(0),
[email protected]d88bf0a2011-08-30 23:55:57352 process_crashes(0),
353 instances(0),
[email protected]cd937072012-07-02 09:00:29354 loading_errors(0),
[email protected]bd5d6cf2011-12-01 00:39:12355 process_type(content::PROCESS_TYPE_UNKNOWN) {}
[email protected]679082052010-07-21 21:30:13356
357 // The number of times that the given child process has been launched
358 int process_launches;
359
360 // The number of times that the given child process has crashed
361 int process_crashes;
362
363 // The number of instances of this child process that have been created.
364 // An instance is a DOM object rendered by this child process during a page
365 // load.
366 int instances;
367
[email protected]cd937072012-07-02 09:00:29368 // The number of times there was an error loading an instance of this child
369 // process.
370 int loading_errors;
371
[email protected]f3b357692013-03-22 05:16:13372 int process_type;
[email protected]679082052010-07-21 21:30:13373};
initial.commit09911bf2008-07-26 23:55:29374
[email protected]84c988a2011-04-19 17:56:33375// Handles asynchronous fetching of memory details.
376// Will run the provided task after finished.
377class MetricsMemoryDetails : public MemoryDetails {
378 public:
[email protected]2226c222011-11-22 00:08:40379 explicit MetricsMemoryDetails(const base::Closure& callback)
380 : callback_(callback) {}
[email protected]84c988a2011-04-19 17:56:33381
[email protected]b94584a2013-02-07 03:02:08382 virtual void OnDetailsAvailable() OVERRIDE {
[email protected]2226c222011-11-22 00:08:40383 MessageLoop::current()->PostTask(FROM_HERE, callback_);
[email protected]84c988a2011-04-19 17:56:33384 }
385
386 private:
[email protected]b94584a2013-02-07 03:02:08387 virtual ~MetricsMemoryDetails() {}
[email protected]84c988a2011-04-19 17:56:33388
[email protected]2226c222011-11-22 00:08:40389 base::Closure callback_;
[email protected]84c988a2011-04-19 17:56:33390 DISALLOW_COPY_AND_ASSIGN(MetricsMemoryDetails);
391};
392
initial.commit09911bf2008-07-26 23:55:29393// static
[email protected]b1de2c72013-02-06 02:45:47394void MetricsService::RegisterPrefs(PrefRegistrySimple* registry) {
initial.commit09911bf2008-07-26 23:55:29395 DCHECK(IsSingleThreaded());
[email protected]007b3f82013-04-09 08:46:45396 registry->RegisterStringPref(prefs::kMetricsClientID, std::string());
[email protected]b1de2c72013-02-06 02:45:47397 registry->RegisterIntegerPref(prefs::kMetricsLowEntropySource,
398 kLowEntropySourceNotSet);
399 registry->RegisterInt64Pref(prefs::kMetricsClientIDTimestamp, 0);
400 registry->RegisterInt64Pref(prefs::kStabilityLaunchTimeSec, 0);
401 registry->RegisterInt64Pref(prefs::kStabilityLastTimestampSec, 0);
[email protected]007b3f82013-04-09 08:46:45402 registry->RegisterStringPref(prefs::kStabilityStatsVersion, std::string());
[email protected]b1de2c72013-02-06 02:45:47403 registry->RegisterInt64Pref(prefs::kStabilityStatsBuildTime, 0);
404 registry->RegisterBooleanPref(prefs::kStabilityExitedCleanly, true);
405 registry->RegisterBooleanPref(prefs::kStabilitySessionEndCompleted, true);
406 registry->RegisterIntegerPref(prefs::kMetricsSessionID, -1);
407 registry->RegisterIntegerPref(prefs::kStabilityLaunchCount, 0);
408 registry->RegisterIntegerPref(prefs::kStabilityCrashCount, 0);
409 registry->RegisterIntegerPref(prefs::kStabilityIncompleteSessionEndCount, 0);
410 registry->RegisterIntegerPref(prefs::kStabilityPageLoadCount, 0);
411 registry->RegisterIntegerPref(prefs::kStabilityRendererCrashCount, 0);
412 registry->RegisterIntegerPref(prefs::kStabilityExtensionRendererCrashCount,
413 0);
414 registry->RegisterIntegerPref(prefs::kStabilityRendererHangCount, 0);
415 registry->RegisterIntegerPref(prefs::kStabilityChildProcessCrashCount, 0);
416 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationFail, 0);
417 registry->RegisterIntegerPref(prefs::kStabilityBreakpadRegistrationSuccess,
418 0);
419 registry->RegisterIntegerPref(prefs::kStabilityDebuggerPresent, 0);
420 registry->RegisterIntegerPref(prefs::kStabilityDebuggerNotPresent, 0);
[email protected]c1834a92011-01-21 18:21:03421#if defined(OS_CHROMEOS)
[email protected]b1de2c72013-02-06 02:45:47422 registry->RegisterIntegerPref(prefs::kStabilityOtherUserCrashCount, 0);
423 registry->RegisterIntegerPref(prefs::kStabilityKernelCrashCount, 0);
424 registry->RegisterIntegerPref(prefs::kStabilitySystemUncleanShutdownCount, 0);
[email protected]c1834a92011-01-21 18:21:03425#endif // OS_CHROMEOS
[email protected]e73c01972008-08-13 00:18:24426
[email protected]b1de2c72013-02-06 02:45:47427 registry->RegisterDictionaryPref(prefs::kProfileMetrics);
428 registry->RegisterIntegerPref(prefs::kNumKeywords, 0);
429 registry->RegisterListPref(prefs::kMetricsInitialLogsXml);
430 registry->RegisterListPref(prefs::kMetricsOngoingLogsXml);
431 registry->RegisterListPref(prefs::kMetricsInitialLogsProto);
432 registry->RegisterListPref(prefs::kMetricsOngoingLogsProto);
[email protected]0bb1a622009-03-04 03:22:32433
[email protected]5c181552013-02-07 09:12:52434 registry->RegisterInt64Pref(prefs::kInstallDate, 0);
[email protected]b1de2c72013-02-06 02:45:47435 registry->RegisterInt64Pref(prefs::kUninstallMetricsPageLoadCount, 0);
436 registry->RegisterInt64Pref(prefs::kUninstallLaunchCount, 0);
[email protected]b1de2c72013-02-06 02:45:47437 registry->RegisterInt64Pref(prefs::kUninstallMetricsUptimeSec, 0);
438 registry->RegisterInt64Pref(prefs::kUninstallLastLaunchTimeSec, 0);
439 registry->RegisterInt64Pref(prefs::kUninstallLastObservedRunTimeSec, 0);
initial.commit09911bf2008-07-26 23:55:29440}
441
[email protected]541f77922009-02-23 21:14:38442// static
443void MetricsService::DiscardOldStabilityStats(PrefService* local_state) {
444 local_state->SetBoolean(prefs::kStabilityExitedCleanly, true);
[email protected]c9abf242009-07-18 06:00:38445 local_state->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
[email protected]541f77922009-02-23 21:14:38446
447 local_state->SetInteger(prefs::kStabilityIncompleteSessionEndCount, 0);
448 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationSuccess, 0);
449 local_state->SetInteger(prefs::kStabilityBreakpadRegistrationFail, 0);
450 local_state->SetInteger(prefs::kStabilityDebuggerPresent, 0);
451 local_state->SetInteger(prefs::kStabilityDebuggerNotPresent, 0);
452
453 local_state->SetInteger(prefs::kStabilityLaunchCount, 0);
454 local_state->SetInteger(prefs::kStabilityCrashCount, 0);
455
456 local_state->SetInteger(prefs::kStabilityPageLoadCount, 0);
457 local_state->SetInteger(prefs::kStabilityRendererCrashCount, 0);
458 local_state->SetInteger(prefs::kStabilityRendererHangCount, 0);
459
[email protected]9165f742010-03-10 22:55:01460 local_state->SetInt64(prefs::kStabilityLaunchTimeSec, 0);
461 local_state->SetInt64(prefs::kStabilityLastTimestampSec, 0);
[email protected]541f77922009-02-23 21:14:38462
463 local_state->ClearPref(prefs::kStabilityPluginStats);
[email protected]ae155cb92009-06-19 06:10:37464
[email protected]fe58acc22012-02-29 01:29:58465 local_state->ClearPref(prefs::kMetricsInitialLogsXml);
466 local_state->ClearPref(prefs::kMetricsOngoingLogsXml);
467 local_state->ClearPref(prefs::kMetricsInitialLogsProto);
468 local_state->ClearPref(prefs::kMetricsOngoingLogsProto);
[email protected]541f77922009-02-23 21:14:38469}
470
initial.commit09911bf2008-07-26 23:55:29471MetricsService::MetricsService()
[email protected]d01b8732008-10-16 02:18:07472 : recording_active_(false),
473 reporting_active_(false),
[email protected]410938e02012-10-24 16:33:59474 test_mode_active_(false),
[email protected]d01b8732008-10-16 02:18:07475 state_(INITIALIZED),
[email protected]e88be472e2013-04-26 22:36:36476 low_entropy_source_(kLowEntropySourceNotSet),
[email protected]d01b8732008-10-16 02:18:07477 idle_since_last_transmission_(false),
initial.commit09911bf2008-07-26 23:55:29478 next_window_id_(0),
[email protected]c94d7382012-02-28 08:43:40479 ALLOW_THIS_IN_INITIALIZER_LIST(self_ptr_factory_(this)),
[email protected]40bcc302009-03-02 20:50:39480 ALLOW_THIS_IN_INITIALIZER_LIST(state_saver_factory_(this)),
[email protected]e5354322012-08-09 23:07:37481 waiting_for_asynchronous_reporting_step_(false),
482 entropy_source_returned_(LAST_ENTROPY_NONE) {
initial.commit09911bf2008-07-26 23:55:29483 DCHECK(IsSingleThreaded());
484 InitializeMetricsState();
[email protected]7f7f1962011-04-20 15:58:16485
486 base::Closure callback = base::Bind(&MetricsService::StartScheduledUpload,
[email protected]c94d7382012-02-28 08:43:40487 self_ptr_factory_.GetWeakPtr());
[email protected]7f7f1962011-04-20 15:58:16488 scheduler_.reset(new MetricsReportingScheduler(callback));
[email protected]cac267c2011-09-29 15:18:10489 log_manager_.set_log_serializer(new MetricsLogSerializer());
490 log_manager_.set_max_ongoing_log_store_size(kUploadLogAvoidRetransmitSize);
[email protected]f4eaf7b92013-02-28 22:00:40491
492 BrowserChildProcessObserver::Add(this);
initial.commit09911bf2008-07-26 23:55:29493}
494
495MetricsService::~MetricsService() {
[email protected]410938e02012-10-24 16:33:59496 DisableRecording();
[email protected]f4eaf7b92013-02-28 22:00:40497
498 BrowserChildProcessObserver::Remove(this);
initial.commit09911bf2008-07-26 23:55:29499}
500
[email protected]d01b8732008-10-16 02:18:07501void MetricsService::Start() {
[email protected]b1c8dc02011-04-13 18:32:04502 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59503 EnableRecording();
504 EnableReporting();
[email protected]d01b8732008-10-16 02:18:07505}
506
[email protected]410938e02012-10-24 16:33:59507void MetricsService::StartRecordingForTests() {
508 test_mode_active_ = true;
509 EnableRecording();
510 DisableReporting();
[email protected]d01b8732008-10-16 02:18:07511}
512
513void MetricsService::Stop() {
[email protected]b1c8dc02011-04-13 18:32:04514 HandleIdleSinceLastTransmission(false);
[email protected]410938e02012-10-24 16:33:59515 DisableReporting();
516 DisableRecording();
517}
518
519void MetricsService::EnableReporting() {
520 if (reporting_active_)
521 return;
522 reporting_active_ = true;
523 StartSchedulerIfNecessary();
524}
525
526void MetricsService::DisableReporting() {
527 reporting_active_ = false;
[email protected]d01b8732008-10-16 02:18:07528}
529
[email protected]edafd4c2011-05-10 17:18:53530std::string MetricsService::GetClientId() {
531 return client_id_;
532}
533
[email protected]20f999b52012-08-24 22:32:59534scoped_ptr<const base::FieldTrial::EntropyProvider>
535 MetricsService::CreateEntropyProvider(bool reporting_will_be_enabled) {
[email protected]29d81ee02012-05-25 05:45:42536 // For metrics reporting-enabled users, we combine the client ID and low
537 // entropy source to get the final entropy source. Otherwise, only use the low
538 // entropy source.
539 // This has two useful properties:
[email protected]e61003a2012-05-24 17:03:19540 // 1) It makes the entropy source less identifiable for parties that do not
541 // know the low entropy source.
542 // 2) It makes the final entropy source resettable.
[email protected]e5354322012-08-09 23:07:37543 if (reporting_will_be_enabled) {
[email protected]5fbffb72012-08-18 01:59:18544 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
545 entropy_source_returned_ = LAST_ENTROPY_HIGH;
546 DCHECK_EQ(LAST_ENTROPY_HIGH, entropy_source_returned_);
[email protected]20f999b52012-08-24 22:32:59547 const std::string high_entropy_source =
548 client_id_ + base::IntToString(GetLowEntropySource());
549 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
550 new metrics::SHA1EntropyProvider(high_entropy_source));
[email protected]e5354322012-08-09 23:07:37551 }
[email protected]20f999b52012-08-24 22:32:59552
[email protected]5fbffb72012-08-18 01:59:18553 if (entropy_source_returned_ == LAST_ENTROPY_NONE)
554 entropy_source_returned_ = LAST_ENTROPY_LOW;
555 DCHECK_EQ(LAST_ENTROPY_LOW, entropy_source_returned_);
[email protected]20f999b52012-08-24 22:32:59556 return scoped_ptr<const base::FieldTrial::EntropyProvider>(
557 new metrics::PermutedEntropyProvider(GetLowEntropySource(),
558 kMaxLowEntropySize));
[email protected]e61003a2012-05-24 17:03:19559}
560
[email protected]5cbeeef72012-02-08 02:05:18561void MetricsService::ForceClientIdCreation() {
562 if (!client_id_.empty())
563 return;
564 PrefService* pref = g_browser_process->local_state();
565 client_id_ = pref->GetString(prefs::kMetricsClientID);
566 if (!client_id_.empty())
567 return;
568
569 client_id_ = GenerateClientID();
570 pref->SetString(prefs::kMetricsClientID, client_id_);
571
572 // Might as well make a note of how long this ID has existed
573 pref->SetString(prefs::kMetricsClientIDTimestamp,
574 base::Int64ToString(Time::Now().ToTimeT()));
575}
576
[email protected]410938e02012-10-24 16:33:59577void MetricsService::EnableRecording() {
initial.commit09911bf2008-07-26 23:55:29578 DCHECK(IsSingleThreaded());
579
[email protected]410938e02012-10-24 16:33:59580 if (recording_active_)
initial.commit09911bf2008-07-26 23:55:29581 return;
[email protected]410938e02012-10-24 16:33:59582 recording_active_ = true;
initial.commit09911bf2008-07-26 23:55:29583
[email protected]410938e02012-10-24 16:33:59584 ForceClientIdCreation();
585 child_process_logging::SetClientId(client_id_);
586 if (!log_manager_.current_log())
587 OpenNewLog();
[email protected]005ef3e2009-05-22 20:55:46588
[email protected]410938e02012-10-24 16:33:59589 SetUpNotifications(&registrar_, this);
[email protected]dd98f392013-02-04 13:03:22590 content::RemoveActionCallback(action_callback_);
591 action_callback_ = base::Bind(&MetricsService::OnUserAction,
592 base::Unretained(this));
593 content::AddActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59594}
595
596void MetricsService::DisableRecording() {
597 DCHECK(IsSingleThreaded());
598
599 if (!recording_active_)
600 return;
601 recording_active_ = false;
602
[email protected]dd98f392013-02-04 13:03:22603 content::RemoveActionCallback(action_callback_);
[email protected]410938e02012-10-24 16:33:59604 registrar_.RemoveAll();
605 PushPendingLogsToPersistentStorage();
606 DCHECK(!log_manager_.has_staged_log());
initial.commit09911bf2008-07-26 23:55:29607}
608
[email protected]d01b8732008-10-16 02:18:07609bool MetricsService::recording_active() const {
initial.commit09911bf2008-07-26 23:55:29610 DCHECK(IsSingleThreaded());
[email protected]d01b8732008-10-16 02:18:07611 return recording_active_;
initial.commit09911bf2008-07-26 23:55:29612}
613
[email protected]d01b8732008-10-16 02:18:07614bool MetricsService::reporting_active() const {
615 DCHECK(IsSingleThreaded());
616 return reporting_active_;
initial.commit09911bf2008-07-26 23:55:29617}
618
[email protected]87ef9ea2011-02-26 03:15:15619// static
[email protected]6c2381d2011-10-19 02:52:53620void MetricsService::SetUpNotifications(
621 content::NotificationRegistrar* registrar,
622 content::NotificationObserver* observer) {
623 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_OPENED,
[email protected]ad50def52011-10-19 23:17:07624 content::NotificationService::AllBrowserContextsAndSources());
[email protected]6c2381d2011-10-19 02:52:53625 registrar->Add(observer, chrome::NOTIFICATION_BROWSER_CLOSED,
[email protected]ad50def52011-10-19 23:17:07626 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42627 registrar->Add(observer, chrome::NOTIFICATION_TAB_PARENTED,
[email protected]ad50def52011-10-19 23:17:07628 content::NotificationService::AllSources());
[email protected]884033e2012-04-16 19:38:42629 registrar->Add(observer, chrome::NOTIFICATION_TAB_CLOSING,
[email protected]ad50def52011-10-19 23:17:07630 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53631 registrar->Add(observer, content::NOTIFICATION_LOAD_START,
[email protected]ad50def52011-10-19 23:17:07632 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53633 registrar->Add(observer, content::NOTIFICATION_LOAD_STOP,
[email protected]ad50def52011-10-19 23:17:07634 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53635 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_CLOSED,
[email protected]ad50def52011-10-19 23:17:07636 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53637 registrar->Add(observer, content::NOTIFICATION_RENDERER_PROCESS_HANG,
[email protected]ad50def52011-10-19 23:17:07638 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53639 registrar->Add(observer, chrome::NOTIFICATION_TEMPLATE_URL_SERVICE_LOADED,
[email protected]ad50def52011-10-19 23:17:07640 content::NotificationService::AllSources());
[email protected]6c2381d2011-10-19 02:52:53641 registrar->Add(observer, chrome::NOTIFICATION_OMNIBOX_OPENED_URL,
[email protected]ad50def52011-10-19 23:17:07642 content::NotificationService::AllSources());
[email protected]87ef9ea2011-02-26 03:15:15643}
644
[email protected]f4eaf7b92013-02-28 22:00:40645void MetricsService::BrowserChildProcessHostConnected(
646 const content::ChildProcessData& data) {
647 GetChildProcessStats(data).process_launches++;
648}
649
650void MetricsService::BrowserChildProcessCrashed(
651 const content::ChildProcessData& data) {
652 GetChildProcessStats(data).process_crashes++;
653 // Exclude plugin crashes from the count below because we report them via
654 // a separate UMA metric.
[email protected]f3b357692013-03-22 05:16:13655 if (!IsPluginProcess(data.process_type))
[email protected]f4eaf7b92013-02-28 22:00:40656 IncrementPrefValue(prefs::kStabilityChildProcessCrashCount);
657}
658
659void MetricsService::BrowserChildProcessInstanceCreated(
660 const content::ChildProcessData& data) {
661 GetChildProcessStats(data).instances++;
662}
663
[email protected]432115822011-07-10 15:52:27664void MetricsService::Observe(int type,
[email protected]6c2381d2011-10-19 02:52:53665 const content::NotificationSource& source,
666 const content::NotificationDetails& details) {
[email protected]cac267c2011-09-29 15:18:10667 DCHECK(log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:29668 DCHECK(IsSingleThreaded());
669
[email protected]dd98f392013-02-04 13:03:22670 if (!CanLogNotification())
initial.commit09911bf2008-07-26 23:55:29671 return;
672
[email protected]432115822011-07-10 15:52:27673 switch (type) {
[email protected]432115822011-07-10 15:52:27674 case chrome::NOTIFICATION_BROWSER_OPENED:
[email protected]459f3502012-09-17 17:08:12675 case chrome::NOTIFICATION_BROWSER_CLOSED: {
676 Browser* browser = content::Source<Browser>(source).ptr();
677 LogWindowOrTabChange(type, reinterpret_cast<uintptr_t>(browser));
initial.commit09911bf2008-07-26 23:55:29678 break;
[email protected]459f3502012-09-17 17:08:12679 }
initial.commit09911bf2008-07-26 23:55:29680
[email protected]459f3502012-09-17 17:08:12681 case chrome::NOTIFICATION_TAB_PARENTED: {
682 content::WebContents* web_contents =
683 content::Source<content::WebContents>(source).ptr();
684 LogWindowOrTabChange(type, reinterpret_cast<uintptr_t>(web_contents));
initial.commit09911bf2008-07-26 23:55:29685 break;
[email protected]459f3502012-09-17 17:08:12686 }
687
688 case chrome::NOTIFICATION_TAB_CLOSING: {
689 content::NavigationController* controller =
690 content::Source<content::NavigationController>(source).ptr();
691 content::WebContents* web_contents = controller->GetWebContents();
692 LogWindowOrTabChange(type, reinterpret_cast<uintptr_t>(web_contents));
693 break;
694 }
initial.commit09911bf2008-07-26 23:55:29695
[email protected]432115822011-07-10 15:52:27696 case content::NOTIFICATION_LOAD_STOP:
initial.commit09911bf2008-07-26 23:55:29697 LogLoadComplete(type, source, details);
698 break;
699
[email protected]432115822011-07-10 15:52:27700 case content::NOTIFICATION_LOAD_START:
initial.commit09911bf2008-07-26 23:55:29701 LogLoadStarted();
702 break;
703
[email protected]432115822011-07-10 15:52:27704 case content::NOTIFICATION_RENDERER_PROCESS_CLOSED: {
[email protected]f3b1a082011-11-18 00:34:30705 content::RenderProcessHost::RendererClosedDetails* process_details =
706 content::Details<
707 content::RenderProcessHost::RendererClosedDetails>(
708 details).ptr();
709 content::RenderProcessHost* host =
710 content::Source<content::RenderProcessHost>(source).ptr();
[email protected]c3721482012-03-23 16:21:48711 LogRendererCrash(
[email protected]f1675202012-07-09 15:18:00712 host, process_details->status, process_details->exit_code);
[email protected]1f085622009-12-04 05:33:45713 }
initial.commit09911bf2008-07-26 23:55:29714 break;
715
[email protected]432115822011-07-10 15:52:27716 case content::NOTIFICATION_RENDERER_PROCESS_HANG:
initial.commit09911bf2008-07-26 23:55:29717 LogRendererHang();
718 break;
719
[email protected]432115822011-07-10 15:52:27720 case chrome::NOTIFICATION_TEMPLATE_URL_SERVICE_LOADED:
[email protected]604d8a062012-03-16 21:37:46721 LogKeywordCount(content::Source<TemplateURLService>(
722 source)->GetTemplateURLs().size());
initial.commit09911bf2008-07-26 23:55:29723 break;
724
[email protected]432115822011-07-10 15:52:27725 case chrome::NOTIFICATION_OMNIBOX_OPENED_URL: {
[email protected]279703f2012-01-20 22:23:26726 MetricsLog* current_log =
727 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]1226abb2010-06-10 18:01:28728 DCHECK(current_log);
729 current_log->RecordOmniboxOpenedURL(
[email protected]6c2381d2011-10-19 02:52:53730 *content::Details<AutocompleteLog>(details).ptr());
initial.commit09911bf2008-07-26 23:55:29731 break;
[email protected]1226abb2010-06-10 18:01:28732 }
initial.commit09911bf2008-07-26 23:55:29733
initial.commit09911bf2008-07-26 23:55:29734 default:
[email protected]a063c102010-07-22 22:20:19735 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:29736 break;
737 }
[email protected]d01b8732008-10-16 02:18:07738
739 HandleIdleSinceLastTransmission(false);
[email protected]d01b8732008-10-16 02:18:07740}
741
742void MetricsService::HandleIdleSinceLastTransmission(bool in_idle) {
743 // If there wasn't a lot of action, maybe the computer was asleep, in which
744 // case, the log transmissions should have stopped. Here we start them up
745 // again.
[email protected]cac78842008-11-27 01:02:20746 if (!in_idle && idle_since_last_transmission_)
[email protected]7f7f1962011-04-20 15:58:16747 StartSchedulerIfNecessary();
[email protected]cac78842008-11-27 01:02:20748 idle_since_last_transmission_ = in_idle;
initial.commit09911bf2008-07-26 23:55:29749}
750
initial.commit09911bf2008-07-26 23:55:29751void MetricsService::RecordStartOfSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38752 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29753 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, false);
754}
755
756void MetricsService::RecordCompletedSessionEnd() {
[email protected]466f3c12011-03-23 21:20:38757 LogCleanShutdown();
initial.commit09911bf2008-07-26 23:55:29758 RecordBooleanPrefValue(prefs::kStabilitySessionEndCompleted, true);
759}
760
[email protected]410938e02012-10-24 16:33:59761#if defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39762void MetricsService::OnAppEnterBackground() {
763 scheduler_->Stop();
764
[email protected]84c384e2013-03-01 23:20:19765 MarkAppCleanShutdownAndCommit();
[email protected]117fbdf22012-06-26 18:36:39766
767 // At this point, there's no way of knowing when the process will be
768 // killed, so this has to be treated similar to a shutdown, closing and
769 // persisting all logs. Unlinke a shutdown, the state is primed to be ready
770 // to continue logging and uploading if the process does return.
771 if (recording_active() && state_ >= INITIAL_LOG_READY) {
772 PushPendingLogsToPersistentStorage();
[email protected]410938e02012-10-24 16:33:59773 // Persisting logs closes the current log, so start recording a new log
774 // immediately to capture any background work that might be done before the
775 // process is killed.
776 OpenNewLog();
[email protected]117fbdf22012-06-26 18:36:39777 }
[email protected]117fbdf22012-06-26 18:36:39778}
779
780void MetricsService::OnAppEnterForeground() {
781 PrefService* pref = g_browser_process->local_state();
782 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
783
784 StartSchedulerIfNecessary();
785}
[email protected]84c384e2013-03-01 23:20:19786#else
787void MetricsService::LogNeedForCleanShutdown() {
788 PrefService* pref = g_browser_process->local_state();
789 pref->SetBoolean(prefs::kStabilityExitedCleanly, false);
790 // Redundant setting to be sure we call for a clean shutdown.
791 clean_shutdown_status_ = NEED_TO_SHUTDOWN;
792}
793#endif // defined(OS_ANDROID) || defined(OS_IOS)
[email protected]117fbdf22012-06-26 18:36:39794
[email protected]7f7f1962011-04-20 15:58:16795void MetricsService::RecordBreakpadRegistration(bool success) {
[email protected]68475e602008-08-22 03:21:15796 if (!success)
[email protected]e73c01972008-08-13 00:18:24797 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationFail);
798 else
799 IncrementPrefValue(prefs::kStabilityBreakpadRegistrationSuccess);
800}
801
802void MetricsService::RecordBreakpadHasDebugger(bool has_debugger) {
803 if (!has_debugger)
804 IncrementPrefValue(prefs::kStabilityDebuggerNotPresent);
805 else
[email protected]68475e602008-08-22 03:21:15806 IncrementPrefValue(prefs::kStabilityDebuggerPresent);
[email protected]e73c01972008-08-13 00:18:24807}
808
initial.commit09911bf2008-07-26 23:55:29809//------------------------------------------------------------------------------
810// private methods
811//------------------------------------------------------------------------------
812
813
814//------------------------------------------------------------------------------
815// Initialization methods
816
817void MetricsService::InitializeMetricsState() {
[email protected]79bf0b72009-04-27 21:30:55818#if defined(OS_POSIX)
[email protected]b4a72d842012-03-22 20:09:09819 network_stats_server_ = chrome_common_net::kEchoTestServerLocation;
820 http_pipelining_test_server_ = chrome_common_net::kPipelineTestServerBaseUrl;
[email protected]79bf0b72009-04-27 21:30:55821#else
822 BrowserDistribution* dist = BrowserDistribution::GetDistribution();
[email protected]d67d1052011-06-09 05:11:41823 network_stats_server_ = dist->GetNetworkStatsServer();
[email protected]adbb3762012-03-09 22:20:08824 http_pipelining_test_server_ = dist->GetHttpPipeliningTestServer();
[email protected]79bf0b72009-04-27 21:30:55825#endif
826
initial.commit09911bf2008-07-26 23:55:29827 PrefService* pref = g_browser_process->local_state();
828 DCHECK(pref);
829
[email protected]225c50842010-01-19 21:19:13830 if ((pref->GetInt64(prefs::kStabilityStatsBuildTime)
831 != MetricsLog::GetBuildTime()) ||
[email protected]ddd231e2010-06-29 20:35:19832 (pref->GetString(prefs::kStabilityStatsVersion)
[email protected]225c50842010-01-19 21:19:13833 != MetricsLog::GetVersionString())) {
[email protected]541f77922009-02-23 21:14:38834 // This is a new version, so we don't want to confuse the stats about the
835 // old version with info that we upload.
836 DiscardOldStabilityStats(pref);
837 pref->SetString(prefs::kStabilityStatsVersion,
[email protected]ddd231e2010-06-29 20:35:19838 MetricsLog::GetVersionString());
[email protected]225c50842010-01-19 21:19:13839 pref->SetInt64(prefs::kStabilityStatsBuildTime,
840 MetricsLog::GetBuildTime());
[email protected]541f77922009-02-23 21:14:38841 }
842
initial.commit09911bf2008-07-26 23:55:29843 // Update session ID
844 session_id_ = pref->GetInteger(prefs::kMetricsSessionID);
845 ++session_id_;
846 pref->SetInteger(prefs::kMetricsSessionID, session_id_);
847
initial.commit09911bf2008-07-26 23:55:29848 // Stability bookkeeping
[email protected]e73c01972008-08-13 00:18:24849 IncrementPrefValue(prefs::kStabilityLaunchCount);
initial.commit09911bf2008-07-26 23:55:29850
[email protected]e73c01972008-08-13 00:18:24851 if (!pref->GetBoolean(prefs::kStabilityExitedCleanly)) {
852 IncrementPrefValue(prefs::kStabilityCrashCount);
[email protected]c0c55e92011-09-10 18:47:30853 // Reset flag, and wait until we call LogNeedForCleanShutdown() before
854 // monitoring.
855 pref->SetBoolean(prefs::kStabilityExitedCleanly, true);
initial.commit09911bf2008-07-26 23:55:29856 }
[email protected]e73c01972008-08-13 00:18:24857
[email protected]e73c01972008-08-13 00:18:24858 if (!pref->GetBoolean(prefs::kStabilitySessionEndCompleted)) {
859 IncrementPrefValue(prefs::kStabilityIncompleteSessionEndCount);
[email protected]c9abf242009-07-18 06:00:38860 // This is marked false when we get a WM_ENDSESSION.
861 pref->SetBoolean(prefs::kStabilitySessionEndCompleted, true);
initial.commit09911bf2008-07-26 23:55:29862 }
initial.commit09911bf2008-07-26 23:55:29863
[email protected]9165f742010-03-10 22:55:01864 // Initialize uptime counters.
865 int64 startup_uptime = MetricsLog::GetIncrementalUptime(pref);
[email protected]ae393ec702010-06-27 16:23:14866 DCHECK_EQ(0, startup_uptime);
[email protected]9165f742010-03-10 22:55:01867 // For backwards compatibility, leave this intact in case Omaha is checking
868 // them. prefs::kStabilityLastTimestampSec may also be useless now.
869 // TODO(jar): Delete these if they have no uses.
[email protected]0bb1a622009-03-04 03:22:32870 pref->SetInt64(prefs::kStabilityLaunchTimeSec, Time::Now().ToTimeT());
871
872 // Bookkeeping for the uninstall metrics.
873 IncrementLongPrefsValue(prefs::kUninstallLaunchCount);
initial.commit09911bf2008-07-26 23:55:29874
875 // Save profile metrics.
876 PrefService* prefs = g_browser_process->local_state();
877 if (prefs) {
878 // Remove the current dictionary and store it for use when sending data to
879 // server. By removing the value we prune potentially dead profiles
880 // (and keys). All valid values are added back once services startup.
881 const DictionaryValue* profile_dictionary =
882 prefs->GetDictionary(prefs::kProfileMetrics);
883 if (profile_dictionary) {
884 // Do a deep copy of profile_dictionary since ClearPref will delete it.
885 profile_dictionary_.reset(static_cast<DictionaryValue*>(
886 profile_dictionary->DeepCopy()));
887 prefs->ClearPref(prefs::kProfileMetrics);
888 }
889 }
890
[email protected]92745242009-06-12 16:52:21891 // Get stats on use of command line.
892 const CommandLine* command_line(CommandLine::ForCurrentProcess());
893 size_t common_commands = 0;
894 if (command_line->HasSwitch(switches::kUserDataDir)) {
895 ++common_commands;
896 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineDatDirCount", 1);
897 }
898
899 if (command_line->HasSwitch(switches::kApp)) {
900 ++common_commands;
901 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineAppModeCount", 1);
902 }
903
[email protected]62b4e522011-07-13 21:46:32904 size_t switch_count = command_line->GetSwitches().size();
905 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineFlagCount", switch_count);
[email protected]92745242009-06-12 16:52:21906 UMA_HISTOGRAM_COUNTS_100("Chrome.CommandLineUncommonFlagCount",
[email protected]62b4e522011-07-13 21:46:32907 switch_count - common_commands);
[email protected]92745242009-06-12 16:52:21908
initial.commit09911bf2008-07-26 23:55:29909 // Kick off the process of saving the state (so the uptime numbers keep
910 // getting updated) every n minutes.
911 ScheduleNextStateSave();
912}
913
[email protected]c94d7382012-02-28 08:43:40914// static
[email protected]d33e7cc2011-09-23 01:43:56915void MetricsService::InitTaskGetHardwareClass(
[email protected]c94d7382012-02-28 08:43:40916 base::WeakPtr<MetricsService> self,
[email protected]d33e7cc2011-09-23 01:43:56917 base::MessageLoopProxy* target_loop) {
[email protected]d33e7cc2011-09-23 01:43:56918 DCHECK(BrowserThread::CurrentlyOn(BrowserThread::FILE));
919
920 std::string hardware_class;
921#if defined(OS_CHROMEOS)
922 chromeos::system::StatisticsProvider::GetInstance()->GetMachineStatistic(
923 "hardware_class", &hardware_class);
924#endif // OS_CHROMEOS
925
926 target_loop->PostTask(FROM_HERE,
927 base::Bind(&MetricsService::OnInitTaskGotHardwareClass,
[email protected]c94d7382012-02-28 08:43:40928 self, hardware_class));
[email protected]d33e7cc2011-09-23 01:43:56929}
930
931void MetricsService::OnInitTaskGotHardwareClass(
932 const std::string& hardware_class) {
[email protected]ed0fd002012-04-25 23:10:34933 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]85ed9d42010-06-08 22:37:44934 hardware_class_ = hardware_class;
[email protected]d33e7cc2011-09-23 01:43:56935
[email protected]ebd71962012-12-20 02:56:55936#if defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:56937 // Start the next part of the init task: loading plugin information.
938 PluginService::GetInstance()->GetPlugins(
939 base::Bind(&MetricsService::OnInitTaskGotPluginInfo,
[email protected]c94d7382012-02-28 08:43:40940 self_ptr_factory_.GetWeakPtr()));
[email protected]ebd71962012-12-20 02:56:55941#else
942 std::vector<webkit::WebPluginInfo> plugin_list_empty;
943 OnInitTaskGotPluginInfo(plugin_list_empty);
944#endif // defined(ENABLE_PLUGINS)
[email protected]d33e7cc2011-09-23 01:43:56945}
946
947void MetricsService::OnInitTaskGotPluginInfo(
948 const std::vector<webkit::WebPluginInfo>& plugins) {
[email protected]ed0fd002012-04-25 23:10:34949 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]35fa6a22009-08-15 00:04:01950 plugins_ = plugins;
[email protected]d33e7cc2011-09-23 01:43:56951
[email protected]197c0772012-05-14 23:50:51952 // Schedules a task on a blocking pool thread to gather Google Update
953 // statistics (requires Registry reads).
954 BrowserThread::PostBlockingPoolTask(
955 FROM_HERE,
956 base::Bind(&MetricsService::InitTaskGetGoogleUpdateData,
957 self_ptr_factory_.GetWeakPtr(),
958 MessageLoop::current()->message_loop_proxy()));
959}
960
961// static
962void MetricsService::InitTaskGetGoogleUpdateData(
963 base::WeakPtr<MetricsService> self,
964 base::MessageLoopProxy* target_loop) {
965 GoogleUpdateMetrics google_update_metrics;
966
967#if defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
968 const bool system_install = GoogleUpdateSettings::IsSystemInstall();
969
970 google_update_metrics.is_system_install = system_install;
971 google_update_metrics.last_started_au =
972 GoogleUpdateSettings::GetGoogleUpdateLastStartedAU(system_install);
973 google_update_metrics.last_checked =
974 GoogleUpdateSettings::GetGoogleUpdateLastChecked(system_install);
975 GoogleUpdateSettings::GetUpdateDetailForGoogleUpdate(
976 system_install,
977 &google_update_metrics.google_update_data);
978 GoogleUpdateSettings::GetUpdateDetail(
979 system_install,
980 &google_update_metrics.product_data);
981#endif // defined(OS_WIN) && defined(GOOGLE_CHROME_BUILD)
982
983 target_loop->PostTask(FROM_HERE,
984 base::Bind(&MetricsService::OnInitTaskGotGoogleUpdateData,
985 self, google_update_metrics));
986}
987
988void MetricsService::OnInitTaskGotGoogleUpdateData(
989 const GoogleUpdateMetrics& google_update_metrics) {
990 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
991
992 google_update_metrics_ = google_update_metrics;
993
[email protected]ed0fd002012-04-25 23:10:34994 // Start the next part of the init task: fetching performance data. This will
995 // call into |FinishedReceivingProfilerData()| when the task completes.
996 chrome_browser_metrics::TrackingSynchronizer::FetchProfilerDataAsynchronously(
997 self_ptr_factory_.GetWeakPtr());
998}
999
[email protected]dd98f392013-02-04 13:03:221000void MetricsService::OnUserAction(const std::string& action) {
1001 if (!CanLogNotification())
1002 return;
1003
1004 log_manager_.current_log()->RecordUserAction(action.c_str());
1005 HandleIdleSinceLastTransmission(false);
1006}
1007
[email protected]ed0fd002012-04-25 23:10:341008void MetricsService::ReceivedProfilerData(
1009 const tracked_objects::ProcessDataSnapshot& process_data,
[email protected]f3b357692013-03-22 05:16:131010 int process_type) {
[email protected]ed0fd002012-04-25 23:10:341011 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
1012
1013 // Upon the first callback, create the initial log so that we can immediately
1014 // save the profiler data.
1015 if (!initial_log_.get())
1016 initial_log_.reset(new MetricsLog(client_id_, session_id_));
1017
1018 initial_log_->RecordProfilerData(process_data, process_type);
1019}
1020
1021void MetricsService::FinishedReceivingProfilerData() {
1022 DCHECK_EQ(INIT_TASK_SCHEDULED, state_);
[email protected]3a7b66d2012-04-26 16:34:161023 state_ = INIT_TASK_DONE;
initial.commit09911bf2008-07-26 23:55:291024}
1025
[email protected]e61003a2012-05-24 17:03:191026int MetricsService::GetLowEntropySource() {
1027 // Note that the default value for the low entropy source and the default pref
[email protected]e88be472e2013-04-26 22:36:361028 // value are both kLowEntropySourceNotSet, which is used to identify if the
1029 // value has been set or not.
1030 if (low_entropy_source_ != kLowEntropySourceNotSet)
[email protected]e61003a2012-05-24 17:03:191031 return low_entropy_source_;
1032
1033 PrefService* pref = g_browser_process->local_state();
1034 const CommandLine* command_line(CommandLine::ForCurrentProcess());
1035 // Only try to load the value from prefs if the user did not request a reset.
1036 // Otherwise, skip to generating a new value.
[email protected]1862c0c2013-04-18 06:30:481037 if (!command_line->HasSwitch(switches::kResetVariationState)) {
[email protected]e63a9ef2012-08-30 15:29:421038 const int value = pref->GetInteger(prefs::kMetricsLowEntropySource);
1039 if (value != kLowEntropySourceNotSet) {
1040 // Ensure the prefs value is in the range [0, kMaxLowEntropySize). Old
1041 // versions of the code would generate values in the range of [1, 8192],
1042 // so the below line ensures 8192 gets mapped to 0 and also guards against
1043 // the case of corrupted values.
1044 low_entropy_source_ = value % kMaxLowEntropySize;
[email protected]0c906e92012-10-18 15:24:131045 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", false);
[email protected]e61003a2012-05-24 17:03:191046 return low_entropy_source_;
[email protected]e63a9ef2012-08-30 15:29:421047 }
[email protected]e61003a2012-05-24 17:03:191048 }
1049
[email protected]0c906e92012-10-18 15:24:131050 UMA_HISTOGRAM_BOOLEAN("UMA.GeneratedLowEntropySource", true);
[email protected]e61003a2012-05-24 17:03:191051 low_entropy_source_ = GenerateLowEntropySource();
1052 pref->SetInteger(prefs::kMetricsLowEntropySource, low_entropy_source_);
1053
1054 return low_entropy_source_;
1055}
1056
1057// static
initial.commit09911bf2008-07-26 23:55:291058std::string MetricsService::GenerateClientID() {
[email protected]7e49ad32012-06-14 14:22:071059 return base::GenerateGUID();
initial.commit09911bf2008-07-26 23:55:291060}
1061
initial.commit09911bf2008-07-26 23:55:291062//------------------------------------------------------------------------------
1063// State save methods
1064
1065void MetricsService::ScheduleNextStateSave() {
[email protected]8454aeb2011-11-19 23:38:201066 state_saver_factory_.InvalidateWeakPtrs();
initial.commit09911bf2008-07-26 23:55:291067
1068 MessageLoop::current()->PostDelayedTask(FROM_HERE,
[email protected]8454aeb2011-11-19 23:38:201069 base::Bind(&MetricsService::SaveLocalState,
1070 state_saver_factory_.GetWeakPtr()),
[email protected]fc4252a72012-01-12 21:58:471071 base::TimeDelta::FromMinutes(kSaveStateIntervalMinutes));
initial.commit09911bf2008-07-26 23:55:291072}
1073
1074void MetricsService::SaveLocalState() {
1075 PrefService* pref = g_browser_process->local_state();
1076 if (!pref) {
[email protected]a063c102010-07-22 22:20:191077 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291078 return;
1079 }
1080
1081 RecordCurrentState(pref);
initial.commit09911bf2008-07-26 23:55:291082
[email protected]fc4252a72012-01-12 21:58:471083 // TODO(jar):110021 Does this run down the batteries????
initial.commit09911bf2008-07-26 23:55:291084 ScheduleNextStateSave();
1085}
1086
1087
1088//------------------------------------------------------------------------------
1089// Recording control methods
1090
[email protected]410938e02012-10-24 16:33:591091void MetricsService::OpenNewLog() {
1092 DCHECK(!log_manager_.current_log());
initial.commit09911bf2008-07-26 23:55:291093
[email protected]29948262012-03-01 12:15:081094 log_manager_.BeginLoggingWithLog(new MetricsLog(client_id_, session_id_),
1095 MetricsLogManager::ONGOING_LOG);
initial.commit09911bf2008-07-26 23:55:291096 if (state_ == INITIALIZED) {
1097 // We only need to schedule that run once.
[email protected]85ed9d42010-06-08 22:37:441098 state_ = INIT_TASK_SCHEDULED;
initial.commit09911bf2008-07-26 23:55:291099
[email protected]85ed9d42010-06-08 22:37:441100 // Schedules a task on the file thread for execution of slower
1101 // initialization steps (such as plugin list generation) necessary
1102 // for sending the initial log. This avoids blocking the main UI
1103 // thread.
[email protected]ed10dd12011-12-07 12:03:421104 BrowserThread::PostDelayedTask(
1105 BrowserThread::FILE,
1106 FROM_HERE,
[email protected]d33e7cc2011-09-23 01:43:561107 base::Bind(&MetricsService::InitTaskGetHardwareClass,
[email protected]c94d7382012-02-28 08:43:401108 self_ptr_factory_.GetWeakPtr(),
[email protected]d33e7cc2011-09-23 01:43:561109 MessageLoop::current()->message_loop_proxy()),
[email protected]7e560102012-03-08 20:58:421110 base::TimeDelta::FromSeconds(kInitializationDelaySeconds));
initial.commit09911bf2008-07-26 23:55:291111 }
1112}
1113
[email protected]410938e02012-10-24 16:33:591114void MetricsService::CloseCurrentLog() {
[email protected]cac267c2011-09-29 15:18:101115 if (!log_manager_.current_log())
initial.commit09911bf2008-07-26 23:55:291116 return;
1117
[email protected]68475e602008-08-22 03:21:151118 // TODO(jar): Integrate bounds on log recording more consistently, so that we
1119 // can stop recording logs that are too big much sooner.
[email protected]cac267c2011-09-29 15:18:101120 if (log_manager_.current_log()->num_events() > kEventLimit) {
[email protected]553dba62009-02-24 19:08:231121 UMA_HISTOGRAM_COUNTS("UMA.Discarded Log Events",
[email protected]cac267c2011-09-29 15:18:101122 log_manager_.current_log()->num_events());
1123 log_manager_.DiscardCurrentLog();
[email protected]410938e02012-10-24 16:33:591124 OpenNewLog(); // Start trivial log to hold our histograms.
[email protected]68475e602008-08-22 03:21:151125 }
1126
[email protected]cac267c2011-09-29 15:18:101127 // Adds to ongoing logs.
1128 log_manager_.current_log()->set_hardware_class(hardware_class_);
[email protected]accdfa62011-09-20 01:56:521129
[email protected]0b33f80b2008-12-17 21:34:361130 // Put incremental data (histogram deltas, and realtime stats deltas) at the
[email protected]147bbc0b2009-01-06 19:37:401131 // end of all log transmissions (initial log handles this separately).
[email protected]024b5cd2011-05-27 03:29:381132 // RecordIncrementalStabilityElements only exists on the derived
1133 // MetricsLog class.
[email protected]279703f2012-01-20 22:23:261134 MetricsLog* current_log =
1135 static_cast<MetricsLog*>(log_manager_.current_log());
[email protected]024b5cd2011-05-27 03:29:381136 DCHECK(current_log);
[email protected]197c0772012-05-14 23:50:511137 current_log->RecordEnvironmentProto(plugins_, google_update_metrics_);
[email protected]fe58acc22012-02-29 01:29:581138 current_log->RecordIncrementalStabilityElements(plugins_);
[email protected]024b5cd2011-05-27 03:29:381139 RecordCurrentHistograms();
initial.commit09911bf2008-07-26 23:55:291140
[email protected]29948262012-03-01 12:15:081141 log_manager_.FinishCurrentLog();
initial.commit09911bf2008-07-26 23:55:291142}
1143
[email protected]cac267c2011-09-29 15:18:101144void MetricsService::PushPendingLogsToPersistentStorage() {
initial.commit09911bf2008-07-26 23:55:291145 if (state_ < INITIAL_LOG_READY)
[email protected]28ab7f92009-01-06 21:39:041146 return; // We didn't and still don't have time to get plugin list etc.
initial.commit09911bf2008-07-26 23:55:291147
[email protected]cac267c2011-09-29 15:18:101148 if (log_manager_.has_staged_log()) {
[email protected]7d41ae6d2012-06-26 08:53:031149 // We may race here, and send second copy of the log later.
[email protected]dc61fe92012-06-12 00:13:501150 MetricsLogManager::StoreType store_type;
[email protected]e3eb0c42013-04-18 06:18:581151 if (current_fetch_.get())
[email protected]dc61fe92012-06-12 00:13:501152 store_type = MetricsLogManager::PROVISIONAL_STORE;
1153 else
1154 store_type = MetricsLogManager::NORMAL_STORE;
[email protected]e7508d82012-05-03 15:59:531155 log_manager_.StoreStagedLogAsUnsent(store_type);
initial.commit09911bf2008-07-26 23:55:291156 }
[email protected]cac267c2011-09-29 15:18:101157 DCHECK(!log_manager_.has_staged_log());
[email protected]410938e02012-10-24 16:33:591158 CloseCurrentLog();
initial.commit09911bf2008-07-26 23:55:291159 StoreUnsentLogs();
[email protected]7d41ae6d2012-06-26 08:53:031160
1161 // If there was a staged and/or current log, then there is now at least one
1162 // log waiting to be uploaded.
1163 if (log_manager_.has_unsent_logs())
1164 state_ = SENDING_OLD_LOGS;
initial.commit09911bf2008-07-26 23:55:291165}
1166
1167//------------------------------------------------------------------------------
1168// Transmission of logs methods
1169
[email protected]7f7f1962011-04-20 15:58:161170void MetricsService::StartSchedulerIfNecessary() {
[email protected]410938e02012-10-24 16:33:591171 // Never schedule cutting or uploading of logs in test mode.
1172 if (test_mode_active_)
1173 return;
1174
1175 // Even if reporting is disabled, the scheduler is needed to trigger the
1176 // creation of the initial log, which must be done in order for any logs to be
1177 // persisted on shutdown or backgrounding.
1178 if (recording_active() && (reporting_active() || state_ < INITIAL_LOG_READY))
[email protected]7f7f1962011-04-20 15:58:161179 scheduler_->Start();
initial.commit09911bf2008-07-26 23:55:291180}
1181
[email protected]7f7f1962011-04-20 15:58:161182void MetricsService::StartScheduledUpload() {
[email protected]cd1ac712012-06-26 08:26:471183 // If we're getting no notifications, then the log won't have much in it, and
1184 // it's possible the computer is about to go to sleep, so don't upload and
1185 // stop the scheduler.
[email protected]410938e02012-10-24 16:33:591186 // If recording has been turned off, the scheduler doesn't need to run.
1187 // If reporting is off, proceed if the initial log hasn't been created, since
1188 // that has to happen in order for logs to be cut and stored when persisting.
[email protected]cd1ac712012-06-26 08:26:471189 // TODO(stuartmorgan): Call Stop() on the schedule when reporting and/or
1190 // recording are turned off instead of letting it fire and then aborting.
1191 if (idle_since_last_transmission_ ||
[email protected]410938e02012-10-24 16:33:591192 !recording_active() ||
1193 (!reporting_active() && state_ >= INITIAL_LOG_READY)) {
[email protected]7f7f1962011-04-20 15:58:161194 scheduler_->Stop();
1195 scheduler_->UploadCancelled();
1196 return;
1197 }
1198
[email protected]c15faf372012-07-11 06:01:341199 // If the callback was to upload an old log, but there no longer is one,
1200 // just report success back to the scheduler to begin the ongoing log
1201 // callbacks.
1202 // TODO(stuartmorgan): Consider removing the distinction between
1203 // SENDING_OLD_LOGS and SENDING_CURRENT_LOGS to simplify the state machine
1204 // now that the log upload flow is the same for both modes.
1205 if (state_ == SENDING_OLD_LOGS && !log_manager_.has_unsent_logs()) {
1206 state_ = SENDING_CURRENT_LOGS;
1207 scheduler_->UploadFinished(true /* healthy */, false /* no unsent logs */);
1208 return;
1209 }
[email protected]cd1ac712012-06-26 08:26:471210 // If there are unsent logs, send the next one. If not, start the asynchronous
1211 // process of finalizing the current log for upload.
1212 if (state_ == SENDING_OLD_LOGS) {
1213 DCHECK(log_manager_.has_unsent_logs());
1214 log_manager_.StageNextLogForUpload();
1215 SendStagedLog();
1216 } else {
1217 StartFinalLogInfoCollection();
1218 }
[email protected]29948262012-03-01 12:15:081219}
1220
1221void MetricsService::StartFinalLogInfoCollection() {
1222 // Begin the multi-step process of collecting memory usage histograms:
1223 // First spawn a task to collect the memory details; when that task is
1224 // finished, it will call OnMemoryDetailCollectionDone. That will in turn
1225 // call HistogramSynchronization to collect histograms from all renderers and
1226 // then call OnHistogramSynchronizationDone to continue processing.
[email protected]d119f222012-06-08 02:33:271227 DCHECK(!waiting_for_asynchronous_reporting_step_);
1228 waiting_for_asynchronous_reporting_step_ = true;
[email protected]7f7f1962011-04-20 15:58:161229
[email protected]2226c222011-11-22 00:08:401230 base::Closure callback =
1231 base::Bind(&MetricsService::OnMemoryDetailCollectionDone,
[email protected]c94d7382012-02-28 08:43:401232 self_ptr_factory_.GetWeakPtr());
[email protected]84c988a2011-04-19 17:56:331233
[email protected]2226c222011-11-22 00:08:401234 scoped_refptr<MetricsMemoryDetails> details(
1235 new MetricsMemoryDetails(callback));
[email protected]4306df72012-04-20 18:58:571236 details->StartFetch(MemoryDetails::UPDATE_USER_METRICS);
[email protected]84c988a2011-04-19 17:56:331237
1238 // Collect WebCore cache information to put into a histogram.
[email protected]f3b1a082011-11-18 00:34:301239 for (content::RenderProcessHost::iterator i(
1240 content::RenderProcessHost::AllHostsIterator());
[email protected]84c988a2011-04-19 17:56:331241 !i.IsAtEnd(); i.Advance())
[email protected]2ccf45c2011-08-19 23:35:501242 i.GetCurrentValue()->Send(new ChromeViewMsg_GetCacheResourceStats());
[email protected]84c988a2011-04-19 17:56:331243}
1244
1245void MetricsService::OnMemoryDetailCollectionDone() {
[email protected]c9a3ef82009-05-28 22:02:461246 DCHECK(IsSingleThreaded());
[email protected]7f7f1962011-04-20 15:58:161247 // This function should only be called as the callback from an ansynchronous
1248 // step.
[email protected]d119f222012-06-08 02:33:271249 DCHECK(waiting_for_asynchronous_reporting_step_);
[email protected]c9a3ef82009-05-28 22:02:461250
[email protected]c9a3ef82009-05-28 22:02:461251 // Create a callback_task for OnHistogramSynchronizationDone.
[email protected]2226c222011-11-22 00:08:401252 base::Closure callback = base::Bind(
1253 &MetricsService::OnHistogramSynchronizationDone,
[email protected]c94d7382012-02-28 08:43:401254 self_ptr_factory_.GetWeakPtr());
[email protected]c9a3ef82009-05-28 22:02:461255
[email protected]908de522011-10-20 00:55:001256 base::StatisticsRecorder::CollectHistogramStats("Browser");
1257
[email protected]c9a3ef82009-05-28 22:02:461258 // Set up the callback to task to call after we receive histograms from all
[email protected]83ab4a282012-07-12 18:19:451259 // child processes. Wait time specifies how long to wait before absolutely
[email protected]c9a3ef82009-05-28 22:02:461260 // calling us back on the task.
[email protected]83ab4a282012-07-12 18:19:451261 content::FetchHistogramsAsynchronously(
[email protected]2226c222011-11-22 00:08:401262 MessageLoop::current(), callback,
[email protected]7e560102012-03-08 20:58:421263 base::TimeDelta::FromMilliseconds(kMaxHistogramGatheringWaitDuration));
[email protected]c9a3ef82009-05-28 22:02:461264}
1265
1266void MetricsService::OnHistogramSynchronizationDone() {
initial.commit09911bf2008-07-26 23:55:291267 DCHECK(IsSingleThreaded());
[email protected]29948262012-03-01 12:15:081268 // This function should only be called as the callback from an ansynchronous
1269 // step.
[email protected]d119f222012-06-08 02:33:271270 DCHECK(waiting_for_asynchronous_reporting_step_);
initial.commit09911bf2008-07-26 23:55:291271
[email protected]d119f222012-06-08 02:33:271272 waiting_for_asynchronous_reporting_step_ = false;
[email protected]29948262012-03-01 12:15:081273 OnFinalLogInfoCollectionDone();
1274}
1275
1276void MetricsService::OnFinalLogInfoCollectionDone() {
[email protected]7f7f1962011-04-20 15:58:161277 // If somehow there is a fetch in progress, we return and hope things work
1278 // out. The scheduler isn't informed since if this happens, the scheduler
1279 // will get a response from the upload.
[email protected]e3eb0c42013-04-18 06:18:581280 DCHECK(!current_fetch_.get());
1281 if (current_fetch_.get())
[email protected]7f7f1962011-04-20 15:58:161282 return;
1283
[email protected]cd1ac712012-06-26 08:26:471284 // Abort if metrics were turned off during the final info gathering.
[email protected]410938e02012-10-24 16:33:591285 if (!recording_active()) {
[email protected]7f7f1962011-04-20 15:58:161286 scheduler_->Stop();
1287 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071288 return;
1289 }
1290
[email protected]cd1ac712012-06-26 08:26:471291 StageNewLog();
[email protected]410938e02012-10-24 16:33:591292
1293 // If logs shouldn't be uploaded, stop here. It's important that this check
1294 // be after StageNewLog(), otherwise the previous logs will never be loaded,
1295 // and thus the open log won't be persisted.
1296 // TODO(stuartmorgan): This is unnecessarily complicated; restructure loading
1297 // of previous logs to not require running part of the upload logic.
1298 // http://crbug.com/157337
1299 if (!reporting_active()) {
1300 scheduler_->Stop();
1301 scheduler_->UploadCancelled();
1302 return;
1303 }
1304
[email protected]29948262012-03-01 12:15:081305 SendStagedLog();
1306}
1307
[email protected]cd1ac712012-06-26 08:26:471308void MetricsService::StageNewLog() {
[email protected]29948262012-03-01 12:15:081309 if (log_manager_.has_staged_log())
1310 return;
1311
1312 switch (state_) {
1313 case INITIALIZED:
1314 case INIT_TASK_SCHEDULED: // We should be further along by now.
[email protected]dc61fe92012-06-12 00:13:501315 NOTREACHED();
[email protected]29948262012-03-01 12:15:081316 return;
1317
1318 case INIT_TASK_DONE:
[email protected]29948262012-03-01 12:15:081319 PrepareInitialLog();
[email protected]ed0fd002012-04-25 23:10:341320 DCHECK_EQ(INIT_TASK_DONE, state_);
[email protected]29948262012-03-01 12:15:081321 log_manager_.LoadPersistedUnsentLogs();
1322 state_ = INITIAL_LOG_READY;
1323 break;
1324
1325 case SENDING_OLD_LOGS:
[email protected]cd1ac712012-06-26 08:26:471326 NOTREACHED(); // Shouldn't be staging a new log during old log sending.
1327 return;
[email protected]29948262012-03-01 12:15:081328
1329 case SENDING_CURRENT_LOGS:
[email protected]410938e02012-10-24 16:33:591330 CloseCurrentLog();
1331 OpenNewLog();
[email protected]29948262012-03-01 12:15:081332 log_manager_.StageNextLogForUpload();
1333 break;
1334
1335 default:
1336 NOTREACHED();
1337 return;
1338 }
1339
1340 DCHECK(log_manager_.has_staged_log());
1341}
1342
1343void MetricsService::PrepareInitialLog() {
[email protected]ed0fd002012-04-25 23:10:341344 DCHECK_EQ(INIT_TASK_DONE, state_);
[email protected]29948262012-03-01 12:15:081345
[email protected]ed0fd002012-04-25 23:10:341346 DCHECK(initial_log_.get());
1347 initial_log_->set_hardware_class(hardware_class_);
[email protected]197c0772012-05-14 23:50:511348 initial_log_->RecordEnvironment(plugins_, google_update_metrics_,
1349 profile_dictionary_.get());
[email protected]29948262012-03-01 12:15:081350
1351 // Histograms only get written to the current log, so make the new log current
1352 // before writing them.
1353 log_manager_.PauseCurrentLog();
[email protected]ed0fd002012-04-25 23:10:341354 log_manager_.BeginLoggingWithLog(initial_log_.release(),
1355 MetricsLogManager::INITIAL_LOG);
[email protected]29948262012-03-01 12:15:081356 RecordCurrentHistograms();
1357 log_manager_.FinishCurrentLog();
1358 log_manager_.ResumePausedLog();
1359
1360 DCHECK(!log_manager_.has_staged_log());
1361 log_manager_.StageNextLogForUpload();
1362}
1363
1364void MetricsService::StoreUnsentLogs() {
1365 if (state_ < INITIAL_LOG_READY)
1366 return; // We never Recalled the prior unsent logs.
1367
1368 log_manager_.PersistUnsentLogs();
1369}
1370
1371void MetricsService::SendStagedLog() {
1372 DCHECK(log_manager_.has_staged_log());
1373
[email protected]cac267c2011-09-29 15:18:101374 PrepareFetchWithStagedLog();
[email protected]d01b8732008-10-16 02:18:071375
[email protected]e3eb0c42013-04-18 06:18:581376 bool upload_created = (current_fetch_.get() != NULL);
[email protected]d6bebb92012-06-13 23:14:551377 UMA_HISTOGRAM_BOOLEAN("UMA.UploadCreation", upload_created);
1378 if (!upload_created) {
[email protected]d01b8732008-10-16 02:18:071379 // Compression failed, and log discarded :-/.
[email protected]dc61fe92012-06-12 00:13:501380 // Skip this upload and hope things work out next time.
[email protected]cac267c2011-09-29 15:18:101381 log_manager_.DiscardStagedLog();
[email protected]7f7f1962011-04-20 15:58:161382 scheduler_->UploadCancelled();
[email protected]d01b8732008-10-16 02:18:071383 return;
1384 }
1385
[email protected]d119f222012-06-08 02:33:271386 DCHECK(!waiting_for_asynchronous_reporting_step_);
[email protected]d119f222012-06-08 02:33:271387 waiting_for_asynchronous_reporting_step_ = true;
[email protected]dc61fe92012-06-12 00:13:501388
[email protected]e3eb0c42013-04-18 06:18:581389 current_fetch_->Start();
[email protected]d01b8732008-10-16 02:18:071390
1391 HandleIdleSinceLastTransmission(true);
1392}
1393
[email protected]cac267c2011-09-29 15:18:101394void MetricsService::PrepareFetchWithStagedLog() {
[email protected]dc61fe92012-06-12 00:13:501395 DCHECK(log_manager_.has_staged_log());
[email protected]cac78842008-11-27 01:02:201396
[email protected]fe58acc22012-02-29 01:29:581397 // Prepare the protobuf version.
[email protected]e3eb0c42013-04-18 06:18:581398 DCHECK(!current_fetch_.get());
[email protected]fe58acc22012-02-29 01:29:581399 if (log_manager_.has_staged_log_proto()) {
[email protected]e3eb0c42013-04-18 06:18:581400 current_fetch_.reset(net::URLFetcher::Create(
1401 GURL(kServerUrlProto), net::URLFetcher::POST, this));
1402 current_fetch_->SetRequestContext(
[email protected]fe58acc22012-02-29 01:29:581403 g_browser_process->system_request_context());
[email protected]e3eb0c42013-04-18 06:18:581404 current_fetch_->SetUploadData(kMimeTypeProto,
1405 log_manager_.staged_log_text().proto);
[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]e3eb0c42013-04-18 06:18:581437 const size_t log_size = log_manager_.staged_log_text().proto.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)
1448 log_manager_.DiscardStagedLogProto();
[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]459f3502012-09-17 17:08:121495void MetricsService::LogWindowOrTabChange(int type, uintptr_t window_or_tab) {
[email protected]534e54b2008-08-13 15:40:091496 int controller_id = -1;
initial.commit09911bf2008-07-26 23:55:291497 MetricsLog::WindowEventType window_type;
1498
1499 // Note: since we stop all logging when a single OTR session is active, it is
1500 // possible that we start getting notifications about a window that we don't
1501 // know about.
[email protected]534e54b2008-08-13 15:40:091502 if (window_map_.find(window_or_tab) == window_map_.end()) {
1503 controller_id = next_window_id_++;
1504 window_map_[window_or_tab] = controller_id;
initial.commit09911bf2008-07-26 23:55:291505 } else {
[email protected]534e54b2008-08-13 15:40:091506 controller_id = window_map_[window_or_tab];
initial.commit09911bf2008-07-26 23:55:291507 }
[email protected]92745242009-06-12 16:52:211508 DCHECK_NE(controller_id, -1);
initial.commit09911bf2008-07-26 23:55:291509
[email protected]432115822011-07-10 15:52:271510 switch (type) {
[email protected]884033e2012-04-16 19:38:421511 case chrome::NOTIFICATION_TAB_PARENTED:
[email protected]432115822011-07-10 15:52:271512 case chrome::NOTIFICATION_BROWSER_OPENED:
initial.commit09911bf2008-07-26 23:55:291513 window_type = MetricsLog::WINDOW_CREATE;
1514 break;
1515
[email protected]884033e2012-04-16 19:38:421516 case chrome::NOTIFICATION_TAB_CLOSING:
[email protected]432115822011-07-10 15:52:271517 case chrome::NOTIFICATION_BROWSER_CLOSED:
[email protected]534e54b2008-08-13 15:40:091518 window_map_.erase(window_map_.find(window_or_tab));
initial.commit09911bf2008-07-26 23:55:291519 window_type = MetricsLog::WINDOW_DESTROY;
1520 break;
1521
1522 default:
[email protected]a063c102010-07-22 22:20:191523 NOTREACHED();
[email protected]68d74f02009-02-13 01:36:501524 return;
initial.commit09911bf2008-07-26 23:55:291525 }
1526
[email protected]534e54b2008-08-13 15:40:091527 // TODO(brettw) we should have some kind of ID for the parent.
[email protected]cac267c2011-09-29 15:18:101528 log_manager_.current_log()->RecordWindowEvent(window_type, controller_id, 0);
initial.commit09911bf2008-07-26 23:55:291529}
1530
[email protected]6c2381d2011-10-19 02:52:531531void MetricsService::LogLoadComplete(
1532 int type,
1533 const content::NotificationSource& source,
1534 const content::NotificationDetails& details) {
[email protected]ad50def52011-10-19 23:17:071535 if (details == content::NotificationService::NoDetails())
initial.commit09911bf2008-07-26 23:55:291536 return;
1537
[email protected]68475e602008-08-22 03:21:151538 // TODO(jar): There is a bug causing this to be called too many times, and
1539 // the log overflows. For now, we won't record these events.
[email protected]553dba62009-02-24 19:08:231540 UMA_HISTOGRAM_COUNTS("UMA.LogLoadComplete called", 1);
[email protected]68475e602008-08-22 03:21:151541 return;
1542
[email protected]6c2381d2011-10-19 02:52:531543 const content::Details<LoadNotificationDetails> load_details(details);
[email protected]534e54b2008-08-13 15:40:091544 int controller_id = window_map_[details.map_key()];
[email protected]cac267c2011-09-29 15:18:101545 log_manager_.current_log()->RecordLoadEvent(controller_id,
[email protected]09d31d52012-03-11 22:30:271546 load_details->url,
1547 load_details->origin,
1548 load_details->session_index,
1549 load_details->load_time);
initial.commit09911bf2008-07-26 23:55:291550}
1551
[email protected]57ecc4b2010-08-11 03:02:511552void MetricsService::IncrementPrefValue(const char* path) {
[email protected]e73c01972008-08-13 00:18:241553 PrefService* pref = g_browser_process->local_state();
1554 DCHECK(pref);
1555 int value = pref->GetInteger(path);
1556 pref->SetInteger(path, value + 1);
1557}
1558
[email protected]57ecc4b2010-08-11 03:02:511559void MetricsService::IncrementLongPrefsValue(const char* path) {
[email protected]0bb1a622009-03-04 03:22:321560 PrefService* pref = g_browser_process->local_state();
1561 DCHECK(pref);
1562 int64 value = pref->GetInt64(path);
[email protected]b42c5e42010-06-03 20:43:251563 pref->SetInt64(path, value + 1);
[email protected]0bb1a622009-03-04 03:22:321564}
1565
initial.commit09911bf2008-07-26 23:55:291566void MetricsService::LogLoadStarted() {
[email protected]5d490e42012-08-30 05:16:431567 content::RecordAction(content::UserMetricsAction("PageLoad"));
[email protected]dd8d12a2011-09-02 02:10:151568 HISTOGRAM_ENUMERATION("Chrome.UmaPageloadCounter", 1, 2);
[email protected]e73c01972008-08-13 00:18:241569 IncrementPrefValue(prefs::kStabilityPageLoadCount);
[email protected]0bb1a622009-03-04 03:22:321570 IncrementLongPrefsValue(prefs::kUninstallMetricsPageLoadCount);
[email protected]0b33f80b2008-12-17 21:34:361571 // We need to save the prefs, as page load count is a critical stat, and it
1572 // might be lost due to a crash :-(.
initial.commit09911bf2008-07-26 23:55:291573}
1574
[email protected]c3721482012-03-23 16:21:481575void MetricsService::LogRendererCrash(content::RenderProcessHost* host,
1576 base::TerminationStatus status,
[email protected]f1675202012-07-09 15:18:001577 int exit_code) {
[email protected]f3b1a082011-11-18 00:34:301578 Profile* profile = Profile::FromBrowserContext(host->GetBrowserContext());
[email protected]6f371442011-11-09 06:45:461579 ExtensionService* service = profile->GetExtensionService();
1580 bool was_extension_process =
[email protected]f3b1a082011-11-18 00:34:301581 service && service->process_map()->Contains(host->GetID());
[email protected]c3721482012-03-23 16:21:481582 if (status == base::TERMINATION_STATUS_PROCESS_CRASHED ||
1583 status == base::TERMINATION_STATUS_ABNORMAL_TERMINATION) {
[email protected]d7c1fa62012-06-15 23:35:301584 if (was_extension_process) {
[email protected]718eab62011-10-05 21:16:521585 IncrementPrefValue(prefs::kStabilityExtensionRendererCrashCount);
[email protected]d7c1fa62012-06-15 23:35:301586
[email protected]1026afd2013-03-20 14:28:541587 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Extension",
1588 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301589 } else {
[email protected]718eab62011-10-05 21:16:521590 IncrementPrefValue(prefs::kStabilityRendererCrashCount);
initial.commit09911bf2008-07-26 23:55:291591
[email protected]1026afd2013-03-20 14:28:541592 UMA_HISTOGRAM_SPARSE_SLOWLY("CrashExitCodes.Renderer",
1593 MapCrashExitCodeForHistogram(exit_code));
[email protected]d7c1fa62012-06-15 23:35:301594 }
1595
[email protected]718eab62011-10-05 21:16:521596 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildCrashes",
1597 was_extension_process ? 2 : 1);
[email protected]c3721482012-03-23 16:21:481598 } else if (status == base::TERMINATION_STATUS_PROCESS_WAS_KILLED) {
[email protected]718eab62011-10-05 21:16:521599 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.ChildKills",
1600 was_extension_process ? 2 : 1);
[email protected]f1675202012-07-09 15:18:001601 } else if (status == base::TERMINATION_STATUS_STILL_RUNNING) {
1602 UMA_HISTOGRAM_PERCENTAGE("BrowserRenderProcessHost.DisconnectedAlive",
[email protected]718eab62011-10-05 21:16:521603 was_extension_process ? 2 : 1);
1604 }
1605 }
[email protected]1f085622009-12-04 05:33:451606
initial.commit09911bf2008-07-26 23:55:291607void MetricsService::LogRendererHang() {
[email protected]e73c01972008-08-13 00:18:241608 IncrementPrefValue(prefs::kStabilityRendererHangCount);
initial.commit09911bf2008-07-26 23:55:291609}
1610
[email protected]c0c55e92011-09-10 18:47:301611bool MetricsService::UmaMetricsProperlyShutdown() {
1612 CHECK(clean_shutdown_status_ == CLEANLY_SHUTDOWN ||
1613 clean_shutdown_status_ == NEED_TO_SHUTDOWN);
1614 return clean_shutdown_status_ == CLEANLY_SHUTDOWN;
1615}
1616
[email protected]466f3c12011-03-23 21:20:381617void MetricsService::LogCleanShutdown() {
[email protected]acd55b32011-09-05 17:35:311618 // Redundant hack to write pref ASAP.
[email protected]84c384e2013-03-01 23:20:191619 MarkAppCleanShutdownAndCommit();
1620
[email protected]c0c55e92011-09-10 18:47:301621 // Redundant setting to assure that we always reset this value at shutdown
1622 // (and that we don't use some alternate path, and not call LogCleanShutdown).
1623 clean_shutdown_status_ = CLEANLY_SHUTDOWN;
[email protected]acd55b32011-09-05 17:35:311624
[email protected]466f3c12011-03-23 21:20:381625 RecordBooleanPrefValue(prefs::kStabilityExitedCleanly, true);
1626}
1627
[email protected]c1834a92011-01-21 18:21:031628#if defined(OS_CHROMEOS)
1629void MetricsService::LogChromeOSCrash(const std::string &crash_type) {
1630 if (crash_type == "user")
1631 IncrementPrefValue(prefs::kStabilityOtherUserCrashCount);
1632 else if (crash_type == "kernel")
1633 IncrementPrefValue(prefs::kStabilityKernelCrashCount);
1634 else if (crash_type == "uncleanshutdown")
1635 IncrementPrefValue(prefs::kStabilitySystemUncleanShutdownCount);
1636 else
1637 NOTREACHED() << "Unexpected Chrome OS crash type " << crash_type;
1638 // Wake up metrics logs sending if necessary now that new
1639 // log data is available.
1640 HandleIdleSinceLastTransmission(false);
1641}
1642#endif // OS_CHROMEOS
1643
[email protected]650b2d52013-02-10 03:41:451644void MetricsService::LogPluginLoadingError(const base::FilePath& plugin_path) {
[email protected]cd937072012-07-02 09:00:291645 webkit::WebPluginInfo plugin;
1646 bool success =
1647 content::PluginService::GetInstance()->GetPluginInfoByPath(plugin_path,
1648 &plugin);
1649 DCHECK(success);
1650 ChildProcessStats& stats = child_process_stats_buffer_[plugin.name];
1651 // Initialize the type if this entry is new.
1652 if (stats.process_type == content::PROCESS_TYPE_UNKNOWN) {
1653 // The plug-in process might not actually of type PLUGIN (which means
1654 // NPAPI), but we only care that it is *a* plug-in process.
1655 stats.process_type = content::PROCESS_TYPE_PLUGIN;
1656 } else {
1657 DCHECK(IsPluginProcess(stats.process_type));
1658 }
1659 stats.loading_errors++;
1660}
1661
[email protected]f4eaf7b92013-02-28 22:00:401662MetricsService::ChildProcessStats& MetricsService::GetChildProcessStats(
1663 const content::ChildProcessData& data) {
1664 const string16& child_name = data.name;
[email protected]f3b357692013-03-22 05:16:131665 if (!ContainsKey(child_process_stats_buffer_, child_name)) {
1666 child_process_stats_buffer_[child_name] =
1667 ChildProcessStats(data.process_type);
1668 }
[email protected]f4eaf7b92013-02-28 22:00:401669 return child_process_stats_buffer_[child_name];
initial.commit09911bf2008-07-26 23:55:291670}
1671
[email protected]604d8a062012-03-16 21:37:461672void MetricsService::LogKeywordCount(size_t keyword_count) {
initial.commit09911bf2008-07-26 23:55:291673 PrefService* pref = g_browser_process->local_state();
1674 DCHECK(pref);
[email protected]604d8a062012-03-16 21:37:461675 pref->SetInteger(prefs::kNumKeywords, static_cast<int>(keyword_count));
initial.commit09911bf2008-07-26 23:55:291676 ScheduleNextStateSave();
1677}
1678
1679void MetricsService::RecordPluginChanges(PrefService* pref) {
[email protected]f8628c22011-04-05 12:10:181680 ListPrefUpdate update(pref, prefs::kStabilityPluginStats);
1681 ListValue* plugins = update.Get();
initial.commit09911bf2008-07-26 23:55:291682 DCHECK(plugins);
1683
1684 for (ListValue::iterator value_iter = plugins->begin();
1685 value_iter != plugins->end(); ++value_iter) {
1686 if (!(*value_iter)->IsType(Value::TYPE_DICTIONARY)) {
[email protected]a063c102010-07-22 22:20:191687 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291688 continue;
1689 }
1690
1691 DictionaryValue* plugin_dict = static_cast<DictionaryValue*>(*value_iter);
[email protected]57ecc4b2010-08-11 03:02:511692 std::string plugin_name;
[email protected]8e50b602009-03-03 22:59:431693 plugin_dict->GetString(prefs::kStabilityPluginName, &plugin_name);
[email protected]6470ee8f2009-03-03 20:46:401694 if (plugin_name.empty()) {
[email protected]a063c102010-07-22 22:20:191695 NOTREACHED();
initial.commit09911bf2008-07-26 23:55:291696 continue;
1697 }
1698
[email protected]57ecc4b2010-08-11 03:02:511699 // TODO(viettrungluu): remove conversions
[email protected]68b9e72b2011-08-05 23:08:221700 string16 name16 = UTF8ToUTF16(plugin_name);
1701 if (child_process_stats_buffer_.find(name16) ==
1702 child_process_stats_buffer_.end()) {
initial.commit09911bf2008-07-26 23:55:291703 continue;
[email protected]68b9e72b2011-08-05 23:08:221704 }
initial.commit09911bf2008-07-26 23:55:291705
[email protected]68b9e72b2011-08-05 23:08:221706 ChildProcessStats stats = child_process_stats_buffer_[name16];
initial.commit09911bf2008-07-26 23:55:291707 if (stats.process_launches) {
1708 int launches = 0;
[email protected]8e50b602009-03-03 22:59:431709 plugin_dict->GetInteger(prefs::kStabilityPluginLaunches, &launches);
initial.commit09911bf2008-07-26 23:55:291710 launches += stats.process_launches;
[email protected]8e50b602009-03-03 22:59:431711 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches, launches);
initial.commit09911bf2008-07-26 23:55:291712 }
1713 if (stats.process_crashes) {
1714 int crashes = 0;
[email protected]8e50b602009-03-03 22:59:431715 plugin_dict->GetInteger(prefs::kStabilityPluginCrashes, &crashes);
initial.commit09911bf2008-07-26 23:55:291716 crashes += stats.process_crashes;
[email protected]8e50b602009-03-03 22:59:431717 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes, crashes);
initial.commit09911bf2008-07-26 23:55:291718 }
1719 if (stats.instances) {
1720 int instances = 0;
[email protected]8e50b602009-03-03 22:59:431721 plugin_dict->GetInteger(prefs::kStabilityPluginInstances, &instances);
initial.commit09911bf2008-07-26 23:55:291722 instances += stats.instances;
[email protected]8e50b602009-03-03 22:59:431723 plugin_dict->SetInteger(prefs::kStabilityPluginInstances, instances);
initial.commit09911bf2008-07-26 23:55:291724 }
[email protected]cd937072012-07-02 09:00:291725 if (stats.loading_errors) {
1726 int loading_errors = 0;
1727 plugin_dict->GetInteger(prefs::kStabilityPluginLoadingErrors,
1728 &loading_errors);
1729 loading_errors += stats.loading_errors;
1730 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
1731 loading_errors);
1732 }
initial.commit09911bf2008-07-26 23:55:291733
[email protected]68b9e72b2011-08-05 23:08:221734 child_process_stats_buffer_.erase(name16);
initial.commit09911bf2008-07-26 23:55:291735 }
1736
1737 // Now go through and add dictionaries for plugins that didn't already have
1738 // reports in Local State.
[email protected]68b9e72b2011-08-05 23:08:221739 for (std::map<string16, ChildProcessStats>::iterator cache_iter =
[email protected]a27a9382009-02-11 23:55:101740 child_process_stats_buffer_.begin();
1741 cache_iter != child_process_stats_buffer_.end(); ++cache_iter) {
[email protected]a27a9382009-02-11 23:55:101742 ChildProcessStats stats = cache_iter->second;
[email protected]0d84c5d2009-10-09 01:10:421743
1744 // Insert only plugins information into the plugins list.
[email protected]8d5f1dae2011-11-11 14:30:411745 if (!IsPluginProcess(stats.process_type))
[email protected]0d84c5d2009-10-09 01:10:421746 continue;
1747
[email protected]57ecc4b2010-08-11 03:02:511748 // TODO(viettrungluu): remove conversion
[email protected]68b9e72b2011-08-05 23:08:221749 std::string plugin_name = UTF16ToUTF8(cache_iter->first);
[email protected]0d84c5d2009-10-09 01:10:421750
initial.commit09911bf2008-07-26 23:55:291751 DictionaryValue* plugin_dict = new DictionaryValue;
1752
[email protected]8e50b602009-03-03 22:59:431753 plugin_dict->SetString(prefs::kStabilityPluginName, plugin_name);
1754 plugin_dict->SetInteger(prefs::kStabilityPluginLaunches,
initial.commit09911bf2008-07-26 23:55:291755 stats.process_launches);
[email protected]8e50b602009-03-03 22:59:431756 plugin_dict->SetInteger(prefs::kStabilityPluginCrashes,
initial.commit09911bf2008-07-26 23:55:291757 stats.process_crashes);
[email protected]8e50b602009-03-03 22:59:431758 plugin_dict->SetInteger(prefs::kStabilityPluginInstances,
initial.commit09911bf2008-07-26 23:55:291759 stats.instances);
[email protected]cd937072012-07-02 09:00:291760 plugin_dict->SetInteger(prefs::kStabilityPluginLoadingErrors,
1761 stats.loading_errors);
initial.commit09911bf2008-07-26 23:55:291762 plugins->Append(plugin_dict);
1763 }
[email protected]a27a9382009-02-11 23:55:101764 child_process_stats_buffer_.clear();
initial.commit09911bf2008-07-26 23:55:291765}
1766
[email protected]dd98f392013-02-04 13:03:221767bool MetricsService::CanLogNotification() {
[email protected]2c910b72011-03-08 21:16:321768 // We simply don't log anything to UMA if there is a single incognito
initial.commit09911bf2008-07-26 23:55:291769 // session visible. The problem is that we always notify using the orginal
1770 // profile in order to simplify notification processing.
[email protected]e764e582012-08-01 03:01:291771 return !chrome::IsOffTheRecordSessionActive();
initial.commit09911bf2008-07-26 23:55:291772}
1773
[email protected]57ecc4b2010-08-11 03:02:511774void MetricsService::RecordBooleanPrefValue(const char* path, bool value) {
initial.commit09911bf2008-07-26 23:55:291775 DCHECK(IsSingleThreaded());
1776
1777 PrefService* pref = g_browser_process->local_state();
1778 DCHECK(pref);
1779
1780 pref->SetBoolean(path, value);
1781 RecordCurrentState(pref);
1782}
1783
1784void MetricsService::RecordCurrentState(PrefService* pref) {
[email protected]0bb1a622009-03-04 03:22:321785 pref->SetInt64(prefs::kStabilityLastTimestampSec, Time::Now().ToTimeT());
initial.commit09911bf2008-07-26 23:55:291786
1787 RecordPluginChanges(pref);
1788}
1789
[email protected]8d5f1dae2011-11-11 14:30:411790// static
[email protected]f3b357692013-03-22 05:16:131791bool MetricsService::IsPluginProcess(int process_type) {
1792 return (process_type == content::PROCESS_TYPE_PLUGIN ||
1793 process_type == content::PROCESS_TYPE_PPAPI_PLUGIN ||
1794 process_type == content::PROCESS_TYPE_PPAPI_BROKER);
[email protected]8d5f1dae2011-11-11 14:30:411795}
1796
[email protected]5ccaa412009-11-13 22:00:161797#if defined(OS_CHROMEOS)
[email protected]29cf16772010-04-21 15:13:471798void MetricsService::StartExternalMetrics() {
[email protected]5ccaa412009-11-13 22:00:161799 external_metrics_ = new chromeos::ExternalMetrics;
[email protected]29cf16772010-04-21 15:13:471800 external_metrics_->Start();
[email protected]5ccaa412009-11-13 22:00:161801}
1802#endif
[email protected]3819f2ee2011-08-21 09:44:381803
1804// static
1805bool MetricsServiceHelper::IsMetricsReportingEnabled() {
1806 bool result = false;
1807 const PrefService* local_state = g_browser_process->local_state();
1808 if (local_state) {
1809 const PrefService::Preference* uma_pref =
1810 local_state->FindPreference(prefs::kMetricsReportingEnabled);
1811 if (uma_pref) {
1812 bool success = uma_pref->GetValue()->GetAsBoolean(&result);
1813 DCHECK(success);
1814 }
1815 }
1816 return result;
1817}