Files
vscode/src/vs/workbench/services/timer/browser/timerService.ts

790 lines
33 KiB
TypeScript

/*---------------------------------------------------------------------------------------------
* Copyright (c) Microsoft Corporation. All rights reserved.
* Licensed under the MIT License. See License.txt in the project root for license information.
*--------------------------------------------------------------------------------------------*/
import * as perf from '../../../../base/common/performance.js';
import { createDecorator } from '../../../../platform/instantiation/common/instantiation.js';
import { IWorkspaceContextService, WorkbenchState } from '../../../../platform/workspace/common/workspace.js';
import { IExtensionService } from '../../extensions/common/extensions.js';
import { IUpdateService } from '../../../../platform/update/common/update.js';
import { ILifecycleService, LifecyclePhase } from '../../lifecycle/common/lifecycle.js';
import { IEditorService } from '../../editor/common/editorService.js';
import { IAccessibilityService } from '../../../../platform/accessibility/common/accessibility.js';
import { ITelemetryData, ITelemetryService } from '../../../../platform/telemetry/common/telemetry.js';
import { Barrier, timeout } from '../../../../base/common/async.js';
import { IWorkbenchLayoutService } from '../../layout/browser/layoutService.js';
import { IPaneCompositePartService } from '../../panecomposite/browser/panecomposite.js';
import { ViewContainerLocation } from '../../../common/views.js';
import { TelemetryTrustedValue } from '../../../../platform/telemetry/common/telemetryUtils.js';
import { isWeb } from '../../../../base/common/platform.js';
import { createBlobWorker } from '../../../../platform/webWorker/browser/webWorkerServiceImpl.js';
import { Registry } from '../../../../platform/registry/common/platform.js';
import { ITerminalBackendRegistry, TerminalExtensions } from '../../../../platform/terminal/common/terminal.js';
/* __GDPR__FRAGMENT__
"IMemoryInfo" : {
"workingSetSize" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"privateBytes": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"sharedBytes": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true }
}
*/
export interface IMemoryInfo {
readonly workingSetSize: number;
readonly privateBytes: number;
readonly sharedBytes: number;
}
/* __GDPR__FRAGMENT__
"IStartupMetrics" : {
"ellapsed" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"isLatestVersion": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"didUseCachedData": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"windowKind": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"windowCount": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"viewletId": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"panelId": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"editorIds": { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"timers.ellapsedAppReady" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedNlsGeneration" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedLoadMainBundle" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedRunMainBundle" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedCrashReporter" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedMainServer" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWindowCreate" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWindowLoad" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWindowLoadToRequire" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWaitForWindowConfig" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedStorageInit" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWorkspaceServiceInit" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedSharedProcesConnected" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedRequiredUserDataInit" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedOtherUserDataInit" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedRequire" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedExtensions" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedExtensionsReady" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedViewletRestore" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedAuxiliaryViewletRestore" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedPanelRestore" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedEditorRestore" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWorkbenchContributions" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"timers.ellapsedWorkbench" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"platform" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"release" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"arch" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"totalmem" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"freemem" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"meminfo" : { "${inline}": [ "${IMemoryInfo}" ] },
"cpus.count" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"cpus.speed" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"cpus.model" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"initialStartup" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"hasAccessibilitySupport" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"isVMLikelyhood" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"emptyWorkbench" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true },
"loadavg" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth" },
"isARM64Emulated" : { "classification": "SystemMetaData", "purpose": "PerformanceAndHealth", "isMeasurement": true }
}
*/
export interface IStartupMetrics {
/**
* If this started the main process and renderer or just a renderer (new or reloaded).
*/
readonly initialStartup: boolean;
/**
* No folder, no file, no workspace has been opened
*/
readonly emptyWorkbench: boolean;
/**
* This is the latest (stable/insider) version. Iff not we should ignore this
* measurement.
*/
readonly isLatestVersion: boolean;
/**
* Whether we asked for and V8 accepted cached data.
*/
readonly didUseCachedData: boolean;
/**
* How/why the window was created. See https://github.com/microsoft/vscode/blob/d1f57d871722f4d6ba63e4ef6f06287121ceb045/src/vs/platform/lifecycle/common/lifecycle.ts#L50
*/
readonly windowKind: number;
/**
* The total number of windows that have been restored/created
*/
readonly windowCount: number;
/**
* The active viewlet id or `undedined`
*/
readonly viewletId?: string;
/**
* The active auxiliary viewlet id or `undedined`
*/
readonly auxiliaryViewletId?: string;
/**
* The active panel id or `undefined`
*/
readonly panelId?: string;
/**
* The editor input types or `[]`
*/
readonly editorIds: string[];
/**
* The time it took to create the workbench.
*
* * Happens in the main-process *and* the renderer-process
* * Measured with the *start* and `didStartWorkbench`-performance mark. The *start* is either the start of the
* main process or the start of the renderer.
* * This should be looked at carefully because times vary depending on
* * This being the first window, the only window, or a reloaded window
* * Cached data being present and used or not
* * The numbers and types of editors being restored
* * The numbers of windows being restored (when starting 'fresh')
* * The viewlet being restored (esp. when it's a contributed viewlet)
*/
readonly ellapsed: number;
/**
* Individual timers...
*/
readonly timers: {
/**
* The time it took to receieve the [`ready`](https://electronjs.org/docs/api/app#event-ready)-event. Measured from the first line
* of JavaScript code till receiving that event.
*
* * Happens in the main-process
* * Measured with the `main:started` and `main:appReady` performance marks.
* * This can be compared between insider and stable builds.
* * This should be looked at per OS version and per electron version.
* * This is often affected by AV software (and can change with AV software updates outside of our release-cycle).
* * It is not our code running here and we can only observe what's happening.
*/
readonly ellapsedAppReady?: number;
/**
* The time it took to generate NLS data.
*
* * Happens in the main-process
* * Measured with the `nlsGeneration:start` and `nlsGeneration:end` performance marks.
* * This only happens when a non-english locale is being used.
* * It is our code running here and we should monitor this carefully for regressions.
*/
readonly ellapsedNlsGeneration?: number;
/**
* The time it took to load the main bundle.
*
* * Happens in the main-process
* * Measured with the `willLoadMainBundle` and `didLoadMainBundle` performance marks.
*/
readonly ellapsedLoadMainBundle?: number;
/**
* The time it took to run the main bundle.
*
* * Happens in the main-process
* * Measured with the `didStartMain` and `didRunMainBundle` performance marks.
*/
readonly ellapsedRunMainBundle?: number;
/**
* The time it took to start the crash reporter.
*
* * Happens in the main-process
* * Measured with the `willStartCrashReporter` and `didStartCrashReporter` performance marks.
*/
readonly ellapsedCrashReporter?: number;
/**
* The time it took to create the main instance server.
*
* * Happens in the main-process
* * Measured with the `willStartMainServer` and `didStartMainServer` performance marks.
*/
readonly ellapsedMainServer?: number;
/**
* The time it took to create the window.
*
* * Happens in the main-process
* * Measured with the `willCreateCodeWindow` and `didCreateCodeWindow` performance marks.
*/
readonly ellapsedWindowCreate?: number;
/**
* The time it took to create the electron browser window.
*
* * Happens in the main-process
* * Measured with the `willCreateCodeBrowserWindow` and `didCreateCodeBrowserWindow` performance marks.
*/
readonly ellapsedBrowserWindowCreate?: number;
/**
* The time it took to restore and validate window state.
*
* * Happens in the main-process
* * Measured with the `willRestoreCodeWindowState` and `didRestoreCodeWindowState` performance marks.
*/
readonly ellapsedWindowRestoreState?: number;
/**
* The time it took to maximize/show the window.
*
* * Happens in the main-process
* * Measured with the `willMaximizeCodeWindow` and `didMaximizeCodeWindow` performance marks.
*/
readonly ellapsedWindowMaximize?: number;
/**
* The time it took to tell electron to open/restore a renderer (browser window).
*
* * Happens in the main-process
* * Measured with the `main:appReady` and `code/willOpenNewWindow` performance marks.
* * This can be compared between insider and stable builds.
* * It is our code running here and we should monitor this carefully for regressions.
*/
readonly ellapsedWindowLoad?: number;
/**
* The time it took to create a new renderer (browser window) and to initialize that to the point
* of load the main-bundle (`workbench.desktop.main.js`).
*
* * Happens in the main-process *and* the renderer-process
* * Measured with the `code/willOpenNewWindow` and `willLoadWorkbenchMain` performance marks.
* * This can be compared between insider and stable builds.
* * It is mostly not our code running here and we can only observe what's happening.
*
*/
readonly ellapsedWindowLoadToRequire: number;
/**
* The time it took to wait for resolving the window configuration. This time the workbench
* will not continue to load and be blocked entirely.
*
* * Happens in the renderer-process
* * Measured with the `willWaitForWindowConfig` and `didWaitForWindowConfig` performance marks.
*/
readonly ellapsedWaitForWindowConfig: number;
/**
* The time it took to init the storage database connection from the workbench.
*
* * Happens in the renderer-process
* * Measured with the `code/willInitStorage` and `code/didInitStorage` performance marks.
*/
readonly ellapsedStorageInit: number;
/**
* The time it took to initialize the workspace and configuration service.
*
* * Happens in the renderer-process
* * Measured with the `willInitWorkspaceService` and `didInitWorkspaceService` performance marks.
*/
readonly ellapsedWorkspaceServiceInit: number;
/**
* The time it took to connect to the shared process.
*
* * Happens in the renderer-process
* * Measured with the `willConnectSharedProcess` and `didConnectSharedProcess` performance marks.
*/
readonly ellapsedSharedProcesConnected: number;
/**
* The time it took to initialize required user data (settings & global state) using settings sync service.
*
* * Happens in the renderer-process (only in Web)
* * Measured with the `willInitRequiredUserData` and `didInitRequiredUserData` performance marks.
*/
readonly ellapsedRequiredUserDataInit: number;
/**
* The time it took to initialize other user data (keybindings, snippets & extensions) using settings sync service.
*
* * Happens in the renderer-process (only in Web)
* * Measured with the `willInitOtherUserData` and `didInitOtherUserData` performance marks.
*/
readonly ellapsedOtherUserDataInit: number;
/**
* The time it took to load the main-bundle of the workbench, e.g. `workbench.desktop.main.js`.
*
* * Happens in the renderer-process
* * Measured with the `willLoadWorkbenchMain` and `didLoadWorkbenchMain` performance marks.
* * This varies *a lot* when V8 cached data could be used or not
* * This should be looked at with and without V8 cached data usage and per electron/v8 version
* * This is affected by the size of our code bundle (which grows about 3-5% per release)
*/
readonly ellapsedRequire: number;
/**
* The time it took to read extensions' package.json-files *and* interpret them (invoking
* the contribution points).
*
* * Happens in the renderer-process
* * Measured with the `willLoadExtensions` and `didLoadExtensions` performance marks.
* * Reading of package.json-files is avoided by caching them all in a single file (after the read,
* until another extension is installed)
* * Happens in parallel to other things, depends on async timing
*/
readonly ellapsedExtensions: number;
// the time from start till `didLoadExtensions`
// remove?
readonly ellapsedExtensionsReady: number;
/**
* The time it took to restore the primary sidebar viewlet.
*
* * Happens in the renderer-process
* * Measured with the `willRestoreViewlet` and `didRestoreViewlet` performance marks.
* * This should be looked at per viewlet-type/id.
* * Happens in parallel to other things, depends on async timing
*/
readonly ellapsedViewletRestore: number;
/**
* The time it took to restore the auxiliary bar viewlet.
*
* * Happens in the renderer-process
* * Measured with the `willRestoreAuxiliaryBar` and `didRestoreAuxiliaryBar` performance marks.
* * This should be looked at per viewlet-type/id.
* * Happens in parallel to other things, depends on async timing
*/
readonly ellapsedAuxiliaryViewletRestore: number;
/**
* The time it took to restore the panel.
*
* * Happens in the renderer-process
* * Measured with the `willRestorePanel` and `didRestorePanel` performance marks.
* * This should be looked at per panel-type/id.
* * Happens in parallel to other things, depends on async timing
*/
readonly ellapsedPanelRestore: number;
/**
* The time it took to restore and fully resolve visible editors - that is text editor
* and complex editor likes the settings UI or webviews (markdown preview).
*
* * Happens in the renderer-process
* * Measured with the `willRestoreEditors` and `didRestoreEditors` performance marks.
* * This should be looked at per editor and per editor type.
* * Happens in parallel to other things, depends on async timing
*/
readonly ellapsedEditorRestore: number;
/**
* The time it took to create all workbench contributions on the starting and ready
* lifecycle phase, thus blocking `ellapsedWorkbench`.
*
* * Happens in the renderer-process
* * Measured with the `willCreateWorkbenchContributions/1` and `didCreateWorkbenchContributions/2` performance marks.
*
*/
readonly ellapsedWorkbenchContributions: number;
/**
* The time it took to create the workbench.
*
* * Happens in the renderer-process
* * Measured with the `willStartWorkbench` and `didStartWorkbench` performance marks.
*/
readonly ellapsedWorkbench: number;
/**
* This time it took inside the renderer to start the workbench.
*
* * Happens in the renderer-process
* * Measured with the `renderer/started` and `didStartWorkbench` performance marks
*/
readonly ellapsedRenderer: number;
};
readonly hasAccessibilitySupport: boolean;
readonly isVMLikelyhood?: number;
readonly platform?: string;
readonly release?: string;
readonly arch?: string;
readonly totalmem?: number;
readonly freemem?: number;
readonly meminfo?: IMemoryInfo;
readonly cpus?: { count: number; speed: number; model: string };
readonly loadavg?: number[];
readonly isARM64Emulated?: boolean;
}
export interface ITimerService {
readonly _serviceBrand: undefined;
/**
* A promise that resolved when startup timings and perf marks
* are available. This depends on lifecycle phases and extension
* hosts being started.
*/
whenReady(): Promise<boolean>;
/**
* A baseline performance indicator for this machine. The value will only available
* late after startup because computing it takes away CPU resources
*
* NOTE that this returns -1 if the machine is hopelessly slow...
*/
perfBaseline: Promise<number>;
/**
* Startup metrics. Can ONLY be accessed after `whenReady` has resolved.
*/
readonly startupMetrics: IStartupMetrics;
/**
* Deliver performance marks from a source, like the main process or extension hosts.
* The source argument acts as an identifier and therefore it must be unique.
*/
setPerformanceMarks(source: string, marks: perf.PerformanceMark[]): void;
/**
* Get all currently known performance marks by source. There is no sorting of the
* returned tuples but the marks of a tuple are guaranteed to be sorted by start times.
*/
getPerformanceMarks(): [source: string, marks: readonly perf.PerformanceMark[]][];
/**
* Return the duration between two marks.
* @param from from mark name
* @param to to mark name
*/
getDuration(from: string, to: string): number;
/**
* Return the timestamp of a mark.
* @param mark mark name
*/
getStartTime(mark: string): number;
}
export const ITimerService = createDecorator<ITimerService>('timerService');
class PerfMarks {
private readonly _entries: [string, perf.PerformanceMark[]][] = [];
setMarks(source: string, entries: perf.PerformanceMark[]): void {
this._entries.push([source, entries]);
}
getDuration(from: string, to: string): number {
const fromEntry = this._findEntry(from);
if (!fromEntry) {
return 0;
}
const toEntry = this._findEntry(to);
if (!toEntry) {
return 0;
}
return toEntry.startTime - fromEntry.startTime;
}
getStartTime(mark: string): number {
const entry = this._findEntry(mark);
return entry ? entry.startTime : -1;
}
private _findEntry(name: string): perf.PerformanceMark | void {
for (const [, marks] of this._entries) {
for (let i = marks.length - 1; i >= 0; i--) {
if (marks[i].name === name) {
return marks[i];
}
}
}
}
getEntries() {
return this._entries.slice(0);
}
}
export type Writeable<T> = { -readonly [P in keyof T]: Writeable<T[P]> };
export abstract class AbstractTimerService implements ITimerService {
declare readonly _serviceBrand: undefined;
private readonly _barrier = new Barrier();
private readonly _marks = new PerfMarks();
private readonly _rndValueShouldSendTelemetry = Math.random() < .03; // 3% of users
private _startupMetrics?: IStartupMetrics;
readonly perfBaseline: Promise<number>;
constructor(
@ILifecycleService private readonly _lifecycleService: ILifecycleService,
@IWorkspaceContextService private readonly _contextService: IWorkspaceContextService,
@IExtensionService private readonly _extensionService: IExtensionService,
@IUpdateService private readonly _updateService: IUpdateService,
@IPaneCompositePartService private readonly _paneCompositeService: IPaneCompositePartService,
@IEditorService private readonly _editorService: IEditorService,
@IAccessibilityService private readonly _accessibilityService: IAccessibilityService,
@ITelemetryService private readonly _telemetryService: ITelemetryService,
@IWorkbenchLayoutService layoutService: IWorkbenchLayoutService
) {
Promise.all([
this._extensionService.whenInstalledExtensionsRegistered(), // extensions registered
_lifecycleService.when(LifecyclePhase.Restored), // workbench created and parts restored
layoutService.whenRestored, // layout restored (including visible editors resolved)
Promise.all(Array.from(Registry.as<ITerminalBackendRegistry>(TerminalExtensions.Backend).backends.values()).map(e => e.whenReady))
]).then(() => {
// set perf mark from renderer
this.setPerformanceMarks('renderer', perf.getMarks());
return this._computeStartupMetrics();
}).then(metrics => {
this._startupMetrics = metrics;
this._reportStartupTimes(metrics);
this._barrier.open();
});
this.perfBaseline = this._barrier.wait()
.then(() => this._lifecycleService.when(LifecyclePhase.Eventually))
.then(() => timeout(this._startupMetrics!.timers.ellapsedRequire))
.then(() => {
// we use fibonacci numbers to have a performance baseline that indicates
// how slow/fast THIS machine actually is.
const jsSrc = (function (this: WindowOrWorkerGlobalScope) {
// the following operation took ~16ms (one frame at 64FPS) to complete on my machine. We derive performance observations
// from that. We also bail if that took too long (>1s)
let tooSlow = false;
function fib(n: number): number {
if (tooSlow) {
return 0;
}
if (performance.now() - t1 >= 1000) {
tooSlow = true;
}
if (n <= 2) {
return n;
}
return fib(n - 1) + fib(n - 2);
}
const t1 = performance.now();
fib(24);
const value = Math.round(performance.now() - t1);
self.postMessage({ value: tooSlow ? -1 : value });
}).toString();
const blob = new Blob([`(${jsSrc})();`], { type: 'application/javascript' });
const blobUrl = URL.createObjectURL(blob);
const worker = createBlobWorker(blobUrl, { name: 'perfBaseline' });
return new Promise<number>(resolve => {
worker.onmessage = e => resolve(e.data.value);
}).finally(() => {
worker.terminate();
URL.revokeObjectURL(blobUrl);
});
});
}
whenReady(): Promise<boolean> {
return this._barrier.wait();
}
get startupMetrics(): IStartupMetrics {
if (!this._startupMetrics) {
throw new Error('illegal state, MUST NOT access startupMetrics before whenReady has resolved');
}
return this._startupMetrics;
}
setPerformanceMarks(source: string, marks: perf.PerformanceMark[]): void {
// Perf marks are a shared resource because anyone can generate them
// and because of that we only accept marks that start with 'code/'
const codeMarks = marks.filter(mark => mark.name.startsWith('code/'));
this._marks.setMarks(source, codeMarks);
this._reportPerformanceMarks(source, codeMarks);
}
getPerformanceMarks(): [source: string, marks: readonly perf.PerformanceMark[]][] {
return this._marks.getEntries();
}
getDuration(from: string, to: string): number {
return this._marks.getDuration(from, to);
}
getStartTime(mark: string): number {
return this._marks.getStartTime(mark);
}
private _reportStartupTimes(metrics: IStartupMetrics): void {
// report IStartupMetrics as telemetry
/* __GDPR__
"startupTimeVaried" : {
"owner": "jrieken",
"${include}": [
"${IStartupMetrics}"
]
}
*/
this._telemetryService.publicLog('startupTimeVaried', metrics as unknown as ITelemetryData);
}
protected _shouldReportPerfMarks(): boolean {
return this._rndValueShouldSendTelemetry;
}
private _reportPerformanceMarks(source: string, marks: perf.PerformanceMark[]) {
if (!this._shouldReportPerfMarks()) {
// the `startup.timer.mark` event is send very often. In order to save resources
// we let some of our instances/sessions send this event
return;
}
// report raw timers as telemetry. each mark is send a separate telemetry
// event and it is "normalized" to a relative timestamp where the first mark
// defines the start
type Mark = { source: string; name: TelemetryTrustedValue<string>; startTime: number };
type MarkClassification = {
owner: 'jrieken';
comment: 'Information about a performance marker';
source: { classification: 'SystemMetaData'; purpose: 'PerformanceAndHealth'; comment: 'Where this marker was generated, e.g main, renderer, extension host' };
name: { classification: 'SystemMetaData'; purpose: 'PerformanceAndHealth'; comment: 'The name of this marker (as defined in source code)' };
startTime: { classification: 'SystemMetaData'; purpose: 'PerformanceAndHealth'; comment: 'The absolute timestamp (unix time)' };
};
for (const mark of marks) {
this._telemetryService.publicLog2<Mark, MarkClassification>('startup.timer.mark', {
source,
name: new TelemetryTrustedValue(mark.name),
startTime: mark.startTime
});
}
}
private async _computeStartupMetrics(): Promise<IStartupMetrics> {
const initialStartup = this._isInitialStartup();
let startMark: string;
if (isWeb) {
startMark = 'code/timeOrigin';
} else {
startMark = initialStartup ? 'code/didStartMain' : 'code/willOpenNewWindow';
}
const activeViewlet = this._paneCompositeService.getActivePaneComposite(ViewContainerLocation.Sidebar);
const activeAuxiliaryViewlet = this._paneCompositeService.getActivePaneComposite(ViewContainerLocation.AuxiliaryBar);
const activePanel = this._paneCompositeService.getActivePaneComposite(ViewContainerLocation.Panel);
const info: Writeable<IStartupMetrics> = {
ellapsed: this._marks.getDuration(startMark, 'code/didStartWorkbench'),
// reflections
isLatestVersion: Boolean(await this._updateService.isLatestVersion()),
didUseCachedData: this._didUseCachedData(),
windowKind: this._lifecycleService.startupKind,
windowCount: await this._getWindowCount(),
viewletId: activeViewlet?.getId(),
auxiliaryViewletId: activeAuxiliaryViewlet?.getId(),
editorIds: this._editorService.visibleEditors.map(input => input.typeId),
panelId: activePanel ? activePanel.getId() : undefined,
// timers
timers: {
ellapsedAppReady: initialStartup ? this._marks.getDuration('code/didStartMain', 'code/mainAppReady') : undefined,
ellapsedNlsGeneration: initialStartup ? this._marks.getDuration('code/willGenerateNls', 'code/didGenerateNls') : undefined,
ellapsedLoadMainBundle: initialStartup ? this._marks.getDuration('code/willLoadMainBundle', 'code/didLoadMainBundle') : undefined,
ellapsedRunMainBundle: initialStartup ? this._marks.getDuration('code/didStartMain', 'code/didRunMainBundle') : undefined,
ellapsedCrashReporter: initialStartup ? this._marks.getDuration('code/willStartCrashReporter', 'code/didStartCrashReporter') : undefined,
ellapsedMainServer: initialStartup ? this._marks.getDuration('code/willStartMainServer', 'code/didStartMainServer') : undefined,
ellapsedWindowCreate: initialStartup ? this._marks.getDuration('code/willCreateCodeWindow', 'code/didCreateCodeWindow') : undefined,
ellapsedWindowRestoreState: initialStartup ? this._marks.getDuration('code/willRestoreCodeWindowState', 'code/didRestoreCodeWindowState') : undefined,
ellapsedBrowserWindowCreate: initialStartup ? this._marks.getDuration('code/willCreateCodeBrowserWindow', 'code/didCreateCodeBrowserWindow') : undefined,
ellapsedWindowMaximize: initialStartup ? this._marks.getDuration('code/willMaximizeCodeWindow', 'code/didMaximizeCodeWindow') : undefined,
ellapsedWindowLoad: initialStartup ? this._marks.getDuration('code/mainAppReady', 'code/willOpenNewWindow') : undefined,
ellapsedWindowLoadToRequire: this._marks.getDuration('code/willOpenNewWindow', 'code/willLoadWorkbenchMain'),
ellapsedRequire: this._marks.getDuration('code/willLoadWorkbenchMain', 'code/didLoadWorkbenchMain'),
ellapsedWaitForWindowConfig: this._marks.getDuration('code/willWaitForWindowConfig', 'code/didWaitForWindowConfig'),
ellapsedStorageInit: this._marks.getDuration('code/willInitStorage', 'code/didInitStorage'),
ellapsedSharedProcesConnected: this._marks.getDuration('code/willConnectSharedProcess', 'code/didConnectSharedProcess'),
ellapsedWorkspaceServiceInit: this._marks.getDuration('code/willInitWorkspaceService', 'code/didInitWorkspaceService'),
ellapsedRequiredUserDataInit: this._marks.getDuration('code/willInitRequiredUserData', 'code/didInitRequiredUserData'),
ellapsedOtherUserDataInit: this._marks.getDuration('code/willInitOtherUserData', 'code/didInitOtherUserData'),
ellapsedExtensions: this._marks.getDuration('code/willLoadExtensions', 'code/didLoadExtensions'),
ellapsedEditorRestore: this._marks.getDuration('code/willRestoreEditors', 'code/didRestoreEditors'),
ellapsedViewletRestore: this._marks.getDuration('code/willRestoreViewlet', 'code/didRestoreViewlet'),
ellapsedAuxiliaryViewletRestore: this._marks.getDuration('code/willRestoreAuxiliaryBar', 'code/didRestoreAuxiliaryBar'),
ellapsedPanelRestore: this._marks.getDuration('code/willRestorePanel', 'code/didRestorePanel'),
ellapsedWorkbenchContributions: this._marks.getDuration('code/willCreateWorkbenchContributions/1', 'code/didCreateWorkbenchContributions/2'),
ellapsedWorkbench: this._marks.getDuration('code/willStartWorkbench', 'code/didStartWorkbench'),
ellapsedExtensionsReady: this._marks.getDuration(startMark, 'code/didLoadExtensions'),
ellapsedRenderer: this._marks.getDuration('code/didStartRenderer', 'code/didStartWorkbench')
},
// system info
platform: undefined,
release: undefined,
arch: undefined,
totalmem: undefined,
freemem: undefined,
meminfo: undefined,
cpus: undefined,
loadavg: undefined,
isVMLikelyhood: undefined,
initialStartup,
hasAccessibilitySupport: this._accessibilityService.isScreenReaderOptimized(),
emptyWorkbench: this._contextService.getWorkbenchState() === WorkbenchState.EMPTY
};
await this._extendStartupInfo(info);
return info;
}
protected abstract _isInitialStartup(): boolean;
protected abstract _didUseCachedData(): boolean;
protected abstract _getWindowCount(): Promise<number>;
protected abstract _extendStartupInfo(info: Writeable<IStartupMetrics>): Promise<void>;
}
export class TimerService extends AbstractTimerService {
protected _isInitialStartup(): boolean {
return false;
}
protected _didUseCachedData(): boolean {
return false;
}
protected async _getWindowCount(): Promise<number> {
return 1;
}
protected async _extendStartupInfo(info: Writeable<IStartupMetrics>): Promise<void> {
info.isVMLikelyhood = 0;
info.isARM64Emulated = false;
info.platform = navigator.userAgent;
info.release = navigator.appVersion;
}
}