/* -*- Mode: js2; js2-basic-offset: 2; indent-tabs-mode: nil; -*- */ /* vim: set ft=javascript ts=2 et sw=2 tw=80: */ /* 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/. */ let Cu = Components.utils; let Ci = Components.interfaces; let Cc = Components.classes; // The maximum allowed number of concurrent timers per page. const MAX_PAGE_TIMERS = 10000; // The regular expression used to parse %s/%d and other placeholders for // variables in strings that need to be interpolated. const ARGUMENT_PATTERN = /%\d*\.?\d*([osdif])\b/g; // The maximum stacktrace depth when populating the stacktrace array used for // console.trace(). const DEFAULT_MAX_STACKTRACE_DEPTH = 200; // The console API methods are async and their action is executed later. This // delay tells how much later. const CALL_DELAY = 15; // milliseconds // This constant tells how many messages to process in a single timer execution. const MESSAGES_IN_INTERVAL = 1500; Cu.import("resource://gre/modules/XPCOMUtils.jsm"); Cu.import("resource://gre/modules/Services.jsm"); Cu.import("resource://gre/modules/ConsoleAPIStorage.jsm"); Cu.import("resource://gre/modules/PrivateBrowsingUtils.jsm"); /** * The window.console API implementation. One instance is lazily created for * every inner window, when the window.console object is accessed. */ function ConsoleAPI() {} ConsoleAPI.prototype = { classID: Components.ID("{b49c18f8-3379-4fc0-8c90-d7772c1a9ff3}"), QueryInterface: XPCOMUtils.generateQI([Ci.nsIDOMGlobalPropertyInitializer, Ci.nsISupportsWeakReference, Ci.nsIObserver]), _timerInitialized: false, _queuedCalls: null, _window: null, _innerID: null, _outerID: null, _windowDestroyed: false, _timer: null, // nsIDOMGlobalPropertyInitializer init: function CA_init(aWindow) { Services.obs.addObserver(this, "inner-window-destroyed", true); try { let windowUtils = aWindow.QueryInterface(Ci.nsIInterfaceRequestor) .getInterface(Ci.nsIDOMWindowUtils); this._outerID = windowUtils.outerWindowID; this._innerID = windowUtils.currentInnerWindowID; } catch (ex) { Cu.reportError(ex); } let self = this; let chromeObject = { // window.console API log: function CA_log() { self.queueCall("log", arguments); }, info: function CA_info() { self.queueCall("info", arguments); }, warn: function CA_warn() { self.queueCall("warn", arguments); }, error: function CA_error() { self.queueCall("error", arguments); }, exception: function CA_exception() { self.queueCall("exception", arguments); }, debug: function CA_debug() { self.queueCall("debug", arguments); }, trace: function CA_trace() { self.queueCall("trace", arguments); }, // Displays an interactive listing of all the properties of an object. dir: function CA_dir() { self.queueCall("dir", arguments); }, group: function CA_group() { self.queueCall("group", arguments); }, groupCollapsed: function CA_groupCollapsed() { self.queueCall("groupCollapsed", arguments); }, groupEnd: function CA_groupEnd() { self.queueCall("groupEnd", arguments); }, time: function CA_time() { self.queueCall("time", arguments); }, timeEnd: function CA_timeEnd() { self.queueCall("timeEnd", arguments); }, profile: function CA_profile() { // Send a notification picked up by the profiler if installed. // This must happen right away otherwise we will miss samples let consoleEvent = { action: "profile", arguments: arguments }; consoleEvent.wrappedJSObject = consoleEvent; Services.obs.notifyObservers(consoleEvent, "console-api-profiler", null); }, profileEnd: function CA_profileEnd() { // Send a notification picked up by the profiler if installed. // This must happen right away otherwise we will miss samples let consoleEvent = { action: "profileEnd", arguments: arguments }; consoleEvent.wrappedJSObject = consoleEvent; Services.obs.notifyObservers(consoleEvent, "console-api-profiler", null); }, assert: function CA_assert() { let args = Array.prototype.slice.call(arguments); if(!args.shift()) { self.queueCall("assert", args); } }, __exposedProps__: { log: "r", info: "r", warn: "r", error: "r", exception: "r", debug: "r", trace: "r", dir: "r", group: "r", groupCollapsed: "r", groupEnd: "r", time: "r", timeEnd: "r", profile: "r", profileEnd: "r", assert: "r" } }; // We need to return an actual content object here, instead of a wrapped // chrome object. This allows things like console.log.bind() to work. let contentObj = Cu.createObjectIn(aWindow); function genPropDesc(fun) { return { enumerable: true, configurable: true, writable: true, value: chromeObject[fun].bind(chromeObject) }; } const properties = { log: genPropDesc('log'), info: genPropDesc('info'), warn: genPropDesc('warn'), error: genPropDesc('error'), exception: genPropDesc('exception'), debug: genPropDesc('debug'), trace: genPropDesc('trace'), dir: genPropDesc('dir'), group: genPropDesc('group'), groupCollapsed: genPropDesc('groupCollapsed'), groupEnd: genPropDesc('groupEnd'), time: genPropDesc('time'), timeEnd: genPropDesc('timeEnd'), profile: genPropDesc('profile'), profileEnd: genPropDesc('profileEnd'), assert: genPropDesc('assert'), __noSuchMethod__: { enumerable: true, configurable: true, writable: true, value: function() {} }, __mozillaConsole__: { value: true } }; Object.defineProperties(contentObj, properties); Cu.makeObjectPropsNormal(contentObj); this._queuedCalls = []; this._timer = Cc["@mozilla.org/timer;1"].createInstance(Ci.nsITimer); this._window = Cu.getWeakReference(aWindow); this.timerRegistry = new Map(); return contentObj; }, observe: function CA_observe(aSubject, aTopic, aData) { if (aTopic == "inner-window-destroyed") { let innerWindowID = aSubject.QueryInterface(Ci.nsISupportsPRUint64).data; if (innerWindowID == this._innerID) { Services.obs.removeObserver(this, "inner-window-destroyed"); this._windowDestroyed = true; if (!this._timerInitialized) { this.timerRegistry.clear(); } } } }, /** * Queue a call to a console method. See the CALL_DELAY constant. * * @param string aMethod * The console method the code has invoked. * @param object aArguments * The arguments passed to the console method. */ queueCall: function CA_queueCall(aMethod, aArguments) { let window = this._window.get(); let metaForCall = { private: PrivateBrowsingUtils.isWindowPrivate(window), timeStamp: Date.now(), stack: this.getStackTrace(aMethod != "trace" ? 1 : null), }; if (aMethod == "time" || aMethod == "timeEnd") { metaForCall.monotonicTimer = window.performance.now(); } this._queuedCalls.push([aMethod, aArguments, metaForCall]); if (!this._timerInitialized) { this._timer.initWithCallback(this._timerCallback.bind(this), CALL_DELAY, Ci.nsITimer.TYPE_REPEATING_SLACK); this._timerInitialized = true; } }, /** * Timer callback used to process each of the queued calls. * @private */ _timerCallback: function CA__timerCallback() { this._queuedCalls.splice(0, MESSAGES_IN_INTERVAL) .forEach(this._processQueuedCall, this); if (!this._queuedCalls.length) { this._timerInitialized = false; this._timer.cancel(); if (this._windowDestroyed) { ConsoleAPIStorage.clearEvents(this._innerID); this.timerRegistry.clear(); } } }, /** * Process a queued call to a console method. * * @private * @param array aCall * Array that holds information about the queued call. */ _processQueuedCall: function CA__processQueuedItem(aCall) { let [method, args, meta] = aCall; let frame; if (meta.stack.length) { frame = meta.stack[0]; } else { frame = { filename: "", lineNumber: 0, functionName: "", }; } let consoleEvent = { ID: this._outerID, innerID: this._innerID, level: method, filename: frame.filename, lineNumber: frame.lineNumber, functionName: frame.functionName, timeStamp: meta.timeStamp, arguments: args, private: meta.private, }; switch (method) { case "log": case "info": case "warn": case "error": case "exception": case "debug": case "assert": consoleEvent.arguments = this.processArguments(args); break; case "trace": consoleEvent.stacktrace = meta.stack; break; case "group": case "groupCollapsed": case "groupEnd": try { consoleEvent.groupName = Array.prototype.join.call(args, " "); } catch (ex) { Cu.reportError(ex); Cu.reportError(ex.stack); return; } break; case "dir": break; case "time": consoleEvent.timer = this.startTimer(args[0], meta.monotonicTimer); break; case "timeEnd": consoleEvent.timer = this.stopTimer(args[0], meta.monotonicTimer); break; default: // unknown console API method! return; } this.notifyObservers(method, consoleEvent); }, /** * Notify all observers of any console API call. * * @param string aLevel * The message level. * @param object aConsoleEvent * The console event object to send to observers for the given console * API call. */ notifyObservers: function CA_notifyObservers(aLevel, aConsoleEvent) { aConsoleEvent.wrappedJSObject = aConsoleEvent; ConsoleAPIStorage.recordEvent(this._innerID, aConsoleEvent); Services.obs.notifyObservers(aConsoleEvent, "console-api-log-event", this._outerID); }, /** * Process the console API call arguments in order to perform printf-like * string substitution. * * TODO: object substitution should take into account width and precision * qualifiers (bug 685813). * * @param mixed aArguments * The arguments given to the console API call. **/ processArguments: function CA_processArguments(aArguments) { if (aArguments.length < 2 || typeof aArguments[0] != "string") { return aArguments; } let args = Array.prototype.slice.call(aArguments); let format = args.shift(); let splitter = "%" + format.length + Date.now() + "%"; let objects = []; // Format specification regular expression. let processed = format.replace(ARGUMENT_PATTERN, function CA_PA_substitute(match, submatch) { switch (submatch) { case "o": objects.push(args.shift()); return splitter; case "s": return String(args.shift()); case "d": case "i": return parseInt(args.shift()); case "f": return parseFloat(args.shift()); default: return submatch; }; }); let result = []; let processedArray = processed.split(splitter); processedArray.forEach(function(aValue, aIndex) { if (aValue !== "") { result.push(aValue); } if (objects[aIndex]) { result.push(objects[aIndex]); } }); return result.concat(args); }, /** * Build the stacktrace array for the console.trace() call. * * @param number [aMaxDepth=DEFAULT_MAX_STACKTRACE_DEPTH] * Optional maximum stacktrace depth. * @return array * Each element is a stack frame that holds the following properties: * filename, lineNumber, functionName and language. */ getStackTrace: function CA_getStackTrace(aMaxDepth) { if (!aMaxDepth) { aMaxDepth = DEFAULT_MAX_STACKTRACE_DEPTH; } let stack = []; let frame = Components.stack.caller.caller; while (frame = frame.caller) { if (frame.language == Ci.nsIProgrammingLanguage.JAVASCRIPT || frame.language == Ci.nsIProgrammingLanguage.JAVASCRIPT2) { stack.push({ filename: frame.filename, lineNumber: frame.lineNumber, functionName: frame.name, language: frame.language, }); if (stack.length == aMaxDepth) { break; } } } return stack; }, /* * A registry of started timers. * @type Map */ timerRegistry: null, /** * Create a new timer by recording the current time under the specified name. * * @param string aName * The name of the timer. * @param number aTimestamp * A monotonic strictly-increasing timing value that tells when the * timer was started. * @return object * The name property holds the timer name and the started property * holds the time the timer was started. In case of error, it returns * an object with the single property "error" that contains the key * for retrieving the localized error message. **/ startTimer: function CA_startTimer(aName, aTimestamp) { if (!aName) { return; } if (this.timerRegistry.size > MAX_PAGE_TIMERS - 1) { return { error: "maxTimersExceeded" }; } let key = aName.toString(); if (!this.timerRegistry.has(key)) { this.timerRegistry.set(key, aTimestamp); } return { name: aName, started: this.timerRegistry.get(key) }; }, /** * Stop the timer with the specified name and retrieve the elapsed time. * * @param string aName * The name of the timer. * @param number aTimestamp * A monotonic strictly-increasing timing value that tells when the * timer was stopped. * @return object * The name property holds the timer name and the duration property * holds the number of milliseconds since the timer was started. **/ stopTimer: function CA_stopTimer(aName, aTimestamp) { if (!aName) { return; } let key = aName.toString(); if (!this.timerRegistry.has(key)) { return; } let duration = aTimestamp - this.timerRegistry.get(key); this.timerRegistry.delete(key); return { name: aName, duration: duration }; } }; this.NSGetFactory = XPCOMUtils.generateNSGetFactory([ConsoleAPI]);