feat: profiling setup

shirakaba · shirakaba · commit 40db84e6537a · 2023-01-21T15:29:33.000+09:00
diff --git a/apps/new/src/plugin-demos/properties.ts b/apps/new/src/plugin-demos/properties.ts
@@ -1,4 +1,5 @@
 import { Button, EventData, Page, Switch, View, getViewById, Observable, Label, PropertyChangeData } from '@nativescript/core';
+import { jamieProfiler } from '@nativescript/core/profiling/jamie';
 
 export function navigatingTo(args: EventData) {
 	const page = <Page>args.object;
@@ -47,11 +48,22 @@ export class DemoModel extends Observable {
 
 		this.target.addEventListener(Observable.propertyChangeEvent, onPropertyChange, null);
 
+		jamieProfiler.flush();
+
+		console.log('BEGIN PROFILE');
 		const time = profile(() => {
 			for (let i = 0; i < 1000000; i++) {
 				this.target.setProperty(propName, i);
 			}
 		});
+		console.log('END PROFILE');
+
+		console.log(
+			jamieProfiler
+				.report(jamieProfiler.flush())
+				.map(([key, value]) => `${key}: ${value} ms`)
+				.join('\n')
+		);
 
 		this.target.removeEventListener(Observable.propertyChangeEvent, onPropertyChange, null);
 
diff --git a/apps/old/src/plugin-demos/properties.ts b/apps/old/src/plugin-demos/properties.ts
@@ -1,5 +1,43 @@
 import { Button, EventData, Page, Switch, View, getViewById, Observable, Label, PropertyChangeData } from '@nativescript/core';
 
+class Profiler {
+	private map: Record<string, number> = {};
+
+	profile<T>(key: string, action: () => T) {
+		const start = global.isIOS ? (global as any).performance.now() : __time();
+		const returnValue = action();
+		const stop = global.isIOS ? (global as any).performance.now() : __time();
+		const period = stop - start;
+
+		this.map[key] = (this.map[key] || 0) + period;
+
+		// console.log(`[PROFILE] ${key}: ${stop - start} ms`);
+		return returnValue;
+	}
+
+	flush() {
+		const map = this.map;
+		this.map = {};
+		return map;
+	}
+
+	get(key: string) {
+		return this.map[key];
+	}
+
+	report(map: Record<string, number> = this.map) {
+		return Object.entries(map).sort(([, valueA], [, valueB]) => {
+			return sortDescending(valueA, valueB);
+		});
+	}
+}
+
+function sortDescending(a: number, b: number): 1 | 0 | -1 {
+	return a < b ? 1 : a > b ? -1 : 0;
+}
+
+const jamieProfiler = new Profiler();
+
 export function navigatingTo(args: EventData) {
 	const page = <Page>args.object;
 
@@ -47,11 +85,22 @@ export class DemoModel extends Observable {
 
 		this.target.addEventListener(Observable.propertyChangeEvent, onPropertyChange, null);
 
+		jamieProfiler.flush();
+
+		console.log('BEGIN PROFILE');
 		const time = profile(() => {
 			for (let i = 0; i < 1000000; i++) {
 				this.target.setProperty(propName, i);
 			}
 		});
+		console.log('END PROFILE');
+
+		console.log(
+			jamieProfiler
+				.report(jamieProfiler.flush())
+				.map(([key, value]) => `${key}: ${value} ms`)
+				.join('\n')
+		);
 
 		this.target.removeEventListener(Observable.propertyChangeEvent, onPropertyChange, null);
 
diff --git a/packages/core/profiling/jamie.ts b/packages/core/profiling/jamie.ts
@@ -0,0 +1,37 @@
+class Profiler {
+	private map: Record<string, number> = {};
+
+	profile<T>(key: string, action: () => T) {
+		const start = global.isIOS ? (global as any).performance.now() : __time();
+		const returnValue = action();
+		const stop = global.isIOS ? (global as any).performance.now() : __time();
+		const period = stop - start;
+
+		this.map[key] = (this.map[key] || 0) + period;
+
+		// console.log(`[PROFILE] ${key}: ${stop - start} ms`);
+		return returnValue;
+	}
+
+	flush() {
+		const map = this.map;
+		this.map = {};
+		return map;
+	}
+
+	get(key: string) {
+		return this.map[key];
+	}
+
+	report(map: Record<string, number> = this.map) {
+		return Object.entries(map).sort(([, valueA], [, valueB]) => {
+			return sortDescending(valueA, valueB);
+		});
+	}
+}
+
+function sortDescending(a: number, b: number): 1 | 0 | -1 {
+	return a < b ? 1 : a > b ? -1 : 0;
+}
+
+export const jamieProfiler = new Profiler();
