/* -*- js-indent-level: 2; indent-tabs-mode: nil -*- */ /* This Source Code Form is subject to the terms of the Mozilla Public * License, v. 2.0. If a copy of the MPL was not distributed with this * file, You can obtain one at http://mozilla.org/MPL/2.0/. */ "use strict"; const Cc = Components.classes; const Ci = Components.interfaces; const Cr = Components.results; const Cu = Components.utils; Cu.import("resource://gre/modules/Log.jsm"); Cu.import("resource://gre/modules/debug.js", this); Cu.import("resource://gre/modules/Services.jsm", this); Cu.import("resource://gre/modules/XPCOMUtils.jsm", this); Cu.import("resource://gre/modules/Promise.jsm", this); Cu.import("resource://gre/modules/DeferredTask.jsm", this); Cu.import("resource://gre/modules/Preferences.jsm"); const LOGGER_NAME = "Toolkit.Telemetry"; const LOGGER_PREFIX = "TelemetryPing::"; const PREF_BRANCH = "toolkit.telemetry."; const PREF_BRANCH_LOG = PREF_BRANCH + "log."; const PREF_SERVER = PREF_BRANCH + "server"; const PREF_ENABLED = PREF_BRANCH + "enabled"; const PREF_LOG_LEVEL = PREF_BRANCH_LOG + "level"; const PREF_LOG_DUMP = PREF_BRANCH_LOG + "dump"; const PREF_CACHED_CLIENTID = PREF_BRANCH + "cachedClientID" const PREF_FHR_UPLOAD_ENABLED = "datareporting.healthreport.uploadEnabled"; // Delay before intializing telemetry (ms) const TELEMETRY_DELAY = 60000; // Delay before initializing telemetry if we're testing (ms) const TELEMETRY_TEST_DELAY = 100; XPCOMUtils.defineLazyServiceGetter(this, "Telemetry", "@mozilla.org/base/telemetry;1", "nsITelemetry"); XPCOMUtils.defineLazyModuleGetter(this, "AsyncShutdown", "resource://gre/modules/AsyncShutdown.jsm"); XPCOMUtils.defineLazyModuleGetter(this, "TelemetryFile", "resource://gre/modules/TelemetryFile.jsm"); XPCOMUtils.defineLazyModuleGetter(this, "TelemetryLog", "resource://gre/modules/TelemetryLog.jsm"); XPCOMUtils.defineLazyModuleGetter(this, "ThirdPartyCookieProbe", "resource://gre/modules/ThirdPartyCookieProbe.jsm"); /** * Setup Telemetry logging. This function also gets called when loggin related * preferences change. */ let gLogger = null; let gLogAppenderDump = null; function configureLogging() { if (!gLogger) { gLogger = Log.repository.getLogger(LOGGER_NAME); // Log messages need to go to the browser console. let consoleAppender = new Log.ConsoleAppender(new Log.BasicFormatter()); gLogger.addAppender(consoleAppender); Preferences.observe(PREF_BRANCH_LOG, configureLogging); } // Make sure the logger keeps up with the logging level preference. gLogger.level = Log.Level[Preferences.get(PREF_LOG_LEVEL, "Warn")]; // If enabled in the preferences, add a dump appender. let logDumping = Preferences.get(PREF_LOG_DUMP, false); if (logDumping != !!gLogAppenderDump) { if (logDumping) { gLogAppenderDump = new Log.DumpAppender(new Log.BasicFormatter()); gLogger.addAppender(gLogAppenderDump); } else { gLogger.removeAppender(gLogAppenderDump); gLogAppenderDump = null; } } } function generateUUID() { let str = Cc["@mozilla.org/uuid-generator;1"].getService(Ci.nsIUUIDGenerator).generateUUID().toString(); // strip {} return str.substring(1, str.length - 1); } this.EXPORTED_SYMBOLS = ["TelemetryPing"]; this.TelemetryPing = Object.freeze({ Constants: Object.freeze({ PREF_ENABLED: PREF_ENABLED, PREF_LOG_LEVEL: PREF_LOG_LEVEL, PREF_LOG_DUMP: PREF_LOG_DUMP, PREF_SERVER: PREF_SERVER, }), /** * Used only for testing purposes. */ initLogging: function() { configureLogging(); }, /** * Used only for testing purposes. */ reset: function() { Impl._clientID = null; return this.setup(); }, /** * Used only for testing purposes. */ setup: function() { return Impl.setupTelemetry(true); }, /** * Send a notification. */ observe: function (aSubject, aTopic, aData) { return Impl.observe(aSubject, aTopic, aData); }, /** * Sets a server to send pings to. */ setServer: function(aServer) { return Impl.setServer(aServer); }, /** * Send payloads to the server. */ send: function(aReason, aPingPayload) { return Impl.send(aReason, aPingPayload); }, /** * The client id send with the telemetry ping. * * @return The client id as string, or null. */ get clientID() { return Impl.clientID; }, }); let Impl = { _initialized: false, _log: null, _prevValues: {}, // The previous build ID, if this is the first run with a new build. // Undefined if this is not the first run, or the previous build ID is unknown. _previousBuildID: undefined, _clientID: null, popPayloads: function popPayloads(reason, externalPayload) { function payloadIter() { if (externalPayload && reason != "overdue-flush") { yield externalPayload; } let iterator = TelemetryFile.popPendingPings(reason); for (let data of iterator) { yield data; } } let payloadIterWithThis = payloadIter.bind(this); return { __iterator__: payloadIterWithThis }; }, /** * Only used in tests. */ setServer: function (aServer) { this._server = aServer; }, /** * Send data to the server. Record success/send-time in histograms */ send: function send(reason, aPayload) { this._log.trace("send - Reason " + reason + ", Server " + this._server); return this.sendPingsFromIterator(this._server, reason, Iterator(this.popPayloads(reason, aPayload))); }, sendPingsFromIterator: function sendPingsFromIterator(server, reason, i) { let p = [data for (data in i)].map((data) => this.doPing(server, data).then(null, () => TelemetryFile.savePing(data, true))); return Promise.all(p); }, finishPingRequest: function finishPingRequest(success, startTime, ping) { let hping = Telemetry.getHistogramById("TELEMETRY_PING"); let hsuccess = Telemetry.getHistogramById("TELEMETRY_SUCCESS"); hsuccess.add(success); hping.add(new Date() - startTime); if (success) { return TelemetryFile.cleanupPingFile(ping); } else { return Promise.resolve(); } }, submissionPath: function submissionPath(ping) { let slug; if (!ping) { slug = this._uuid; } else { let info = ping.payload.info; let pathComponents = [ping.slug, info.reason, info.appName, info.appVersion, info.appUpdateChannel, info.appBuildID]; slug = pathComponents.join("/"); } return "/submit/telemetry/" + slug; }, doPing: function doPing(server, ping) { this._log.trace("doPing - Server " + server); let deferred = Promise.defer(); let url = server + this.submissionPath(ping); let request = Cc["@mozilla.org/xmlextras/xmlhttprequest;1"] .createInstance(Ci.nsIXMLHttpRequest); request.mozBackgroundRequest = true; request.open("POST", url, true); request.overrideMimeType("text/plain"); request.setRequestHeader("Content-Type", "application/json; charset=UTF-8"); let startTime = new Date(); function handler(success) { return function(event) { this.finishPingRequest(success, startTime, ping).then(() => { if (success) { deferred.resolve(); } else { deferred.reject(event); } }); }; } request.addEventListener("error", handler(false).bind(this), false); request.addEventListener("load", handler(true).bind(this), false); request.setRequestHeader("Content-Encoding", "gzip"); let converter = Cc["@mozilla.org/intl/scriptableunicodeconverter"] .createInstance(Ci.nsIScriptableUnicodeConverter); converter.charset = "UTF-8"; let utf8Payload = converter.ConvertFromUnicode(JSON.stringify(ping.payload)); utf8Payload += converter.Finish(); let payloadStream = Cc["@mozilla.org/io/string-input-stream;1"] .createInstance(Ci.nsIStringInputStream); payloadStream.data = this.gzipCompressString(utf8Payload); request.send(payloadStream); return deferred.promise; }, gzipCompressString: function gzipCompressString(string) { let observer = { buffer: "", onStreamComplete: function(loader, context, status, length, result) { this.buffer = String.fromCharCode.apply(this, result); } }; let scs = Cc["@mozilla.org/streamConverters;1"] .getService(Ci.nsIStreamConverterService); let listener = Cc["@mozilla.org/network/stream-loader;1"] .createInstance(Ci.nsIStreamLoader); listener.init(observer); let converter = scs.asyncConvertData("uncompressed", "gzip", listener, null); let stringStream = Cc["@mozilla.org/io/string-input-stream;1"] .createInstance(Ci.nsIStringInputStream); stringStream.data = string; converter.onStartRequest(null, null); converter.onDataAvailable(null, null, stringStream, 0, string.length); converter.onStopRequest(null, null, null); return observer.buffer; }, /** * Perform telemetry initialization for either chrome or content process. */ enableTelemetryRecording: function enableTelemetryRecording(testing) { #ifdef MOZILLA_OFFICIAL if (!Telemetry.canSend && !testing) { // We can't send data; no point in initializing observers etc. // Only do this for official builds so that e.g. developer builds // still enable Telemetry based on prefs. Telemetry.canRecord = false; this._log.config("enableTelemetryRecording - Can't send data, disabling Telemetry recording."); return false; } #endif let enabled = Preferences.get(PREF_ENABLED, false); this._server = Preferences.get(PREF_SERVER, undefined); if (!enabled) { // Turn off local telemetry if telemetry is disabled. // This may change once about:telemetry is added. Telemetry.canRecord = false; this._log.config("enableTelemetryRecording - Telemetry is disabled, turning off Telemetry recording."); return false; } return true; }, /** * Initializes telemetry within a timer. If there is no PREF_SERVER set, don't turn on telemetry. */ setupTelemetry: function setupTelemetry(testing) { if (testing && !this._log) { this._log = Log.repository.getLoggerWithMessagePrefix(LOGGER_NAME, LOGGER_PREFIX); } this._log.trace("setupTelemetry"); // Initialize some probes that are kept in their own modules this._thirdPartyCookies = new ThirdPartyCookieProbe(); this._thirdPartyCookies.init(); if (!this.enableTelemetryRecording(testing)) { this._log.config("setupTelemetry - Telemetry recording is disabled, skipping Telemetry setup."); return Promise.resolve(); } // For very short session durations, we may never load the client // id from disk. // We try to cache it in prefs to avoid this, even though this may // lead to some stale client ids. this._clientID = Preferences.get(PREF_CACHED_CLIENTID, null); // Delay full telemetry initialization to give the browser time to // run various late initializers. Otherwise our gathered memory // footprint and other numbers would be too optimistic. let deferred = Promise.defer(); let delayedTask = new DeferredTask(function* () { this._initialized = true; yield TelemetryFile.loadSavedPings(); // If we have any TelemetryPings lying around, we'll be aggressive // and try to send them all off ASAP. if (TelemetryFile.pingsOverdue > 0) { this._log.trace("setupChromeProcess - Sending " + TelemetryFile.pingsOverdue + " overdue pings now."); // It doesn't really matter what we pass to this.send as a reason, // since it's never sent to the server. All that this.send does with // the reason is check to make sure it's not a test-ping. yield this.send("overdue-flush"); } if ("@mozilla.org/datareporting/service;1" in Cc) { let drs = Cc["@mozilla.org/datareporting/service;1"] .getService(Ci.nsISupports) .wrappedJSObject; this._clientID = yield drs.getClientID(); // Update cached client id. Preferences.set(PREF_CACHED_CLIENTID, this._clientID); } else { // Nuke potentially cached client id. Preferences.reset(PREF_CACHED_CLIENTID); } Telemetry.asyncFetchTelemetryData(function () {}); deferred.resolve(); }.bind(this), testing ? TELEMETRY_TEST_DELAY : TELEMETRY_DELAY); delayedTask.arm(); return deferred.promise; }, /** * This observer drives telemetry. */ observe: function (aSubject, aTopic, aData) { // The logger might still be not available at this point. if (!this._log) { // If we don't have a logger, we need to make sure |Log.repository.getLogger()| is // called before |getLoggerWithMessagePrefix|. Otherwise logging won't work. configureLogging(); this._log = Log.repository.getLoggerWithMessagePrefix(LOGGER_NAME, LOGGER_PREFIX); } this._log.trace("observe - " + aTopic + " notified."); switch (aTopic) { case "profile-after-change": // profile-after-change is only registered for chrome processes. return this.setupTelemetry(); case "app-startup": // app-startup is only registered for content processes. Services.obs.addObserver(this, "content-child-shutdown", false); break; case "content-child-shutdown": // content-child-shutdown is only registered for content processes. Services.obs.removeObserver(this, "content-child-shutdown"); Preferences.ignore(PREF_BRANCH_LOG, configureLogging); break; } }, get clientID() { return this._clientID; }, };