// Copyright 2021 Signal Messenger, LLC // SPDX-License-Identifier: AGPL-3.0-only import { join } from 'path'; import { Worker } from 'worker_threads'; import { format } from 'util'; import { app } from 'electron'; import { strictAssert } from '../util/assert'; import { explodePromise } from '../util/explodePromise'; import type { LoggerType } from '../types/Logging'; import * as Errors from '../types/errors'; import { SqliteErrorKind } from './errors'; import type { ServerReadableDirectInterface, ServerWritableDirectInterface, } from './Interface'; const MIN_TRACE_DURATION = 40; const WORKER_COUNT = 4; const PAGING_QUERIES = new Set([ 'pageMessages', 'finishPageMessages', 'getKnownMessageAttachments', 'finishGetKnownMessageAttachments', ]); export type InitializeOptions = Readonly<{ appVersion: string; configDir: string; key: string; logger: LoggerType; }>; export type WorkerRequest = Readonly< | { type: 'init'; options: Omit; isPrimary: boolean; } | { type: 'close' | 'removeDB'; } | { type: 'sqlCall:read'; method: keyof ServerReadableDirectInterface; args: ReadonlyArray; } | { type: 'sqlCall:write'; method: keyof ServerWritableDirectInterface; args: ReadonlyArray; } >; export type WrappedWorkerRequest = Readonly<{ seq: number; request: WorkerRequest; }>; export type WrappedWorkerLogEntry = Readonly<{ type: 'log'; level: 'fatal' | 'error' | 'warn' | 'info' | 'debug' | 'trace'; args: ReadonlyArray; }>; export type WrappedWorkerResponse = | Readonly<{ type: 'response'; seq: number; error: | Readonly<{ name: string; message: string; stack: string | undefined; }> | undefined; errorKind: SqliteErrorKind | undefined; // eslint-disable-next-line @typescript-eslint/no-explicit-any response: any; }> | WrappedWorkerLogEntry; type PromisePair = { resolve: (response: T) => void; reject: (error: Error) => void; }; type KnownErrorResolverType = Readonly<{ kind: SqliteErrorKind; resolve: (err: Error) => void; }>; type CreateWorkerResultType = Readonly<{ worker: Worker; onExit: Promise; }>; type PoolEntry = { readonly worker: Worker; load: number; }; type QueryStatsType = { queryName: string; count: number; cumulative: number; max: number; }; export class MainSQL { private readonly pool = new Array(); private pauseWaiters: Array<() => void> | undefined; private isReady = false; private onReady: Promise | undefined; private readonly onExit: Promise; // Promise resolve callbacks for corruption and readonly errors. private errorResolvers = new Array(); private seq = 0; private logger?: LoggerType; // eslint-disable-next-line @typescript-eslint/no-explicit-any private onResponse = new Map>(); private shouldTimeQueries = false; private shouldTrackQueryStats = false; private queryStats?: { start: number; statsByQuery: Map; }; constructor() { const exitPromises = new Array>(); for (let i = 0; i < WORKER_COUNT; i += 1) { const { worker, onExit } = this.createWorker(); this.pool.push({ worker, load: 0 }); exitPromises.push(onExit); } this.onExit = Promise.all(exitPromises); } public async initialize({ appVersion, configDir, key, logger, }: InitializeOptions): Promise { if (this.isReady || this.onReady) { throw new Error('Already initialized'); } this.shouldTimeQueries = Boolean(process.env.TIME_QUERIES); this.logger = logger; this.onReady = (async () => { const primary = this.pool[0]; const rest = this.pool.slice(1); await this.send(primary, { type: 'init', options: { appVersion, configDir, key }, isPrimary: true, }); await Promise.all( rest.map(worker => this.send(worker, { type: 'init', options: { appVersion, configDir, key }, isPrimary: false, }) ) ); })(); await this.onReady; this.onReady = undefined; this.isReady = true; } public pauseWriteAccess(): void { strictAssert(this.pauseWaiters == null, 'Already paused'); this.pauseWaiters = []; } public resumeWriteAccess(): void { const { pauseWaiters } = this; strictAssert(pauseWaiters != null, 'Not paused'); this.pauseWaiters = undefined; for (const waiter of pauseWaiters) { waiter(); } } public whenCorrupted(): Promise { const { promise, resolve } = explodePromise(); this.errorResolvers.push({ kind: SqliteErrorKind.Corrupted, resolve }); return promise; } public whenReadonly(): Promise { const { promise, resolve } = explodePromise(); this.errorResolvers.push({ kind: SqliteErrorKind.Readonly, resolve }); return promise; } public async close(): Promise { if (this.onReady) { try { await this.onReady; } catch (err) { this.logger?.error(`MainSQL close, failed: ${Errors.toLogFormat(err)}`); // Init failed return; } } if (!this.isReady) { throw new Error('Not initialized'); } await this.terminate({ type: 'close' }); await this.onExit; } public async removeDB(): Promise { await this.terminate({ type: 'removeDB' }); } public async sqlRead( method: Method, ...args: Parameters ): Promise> { type SqlCallResult = Readonly<{ result: ReturnType; duration: number; }>; // pageMessages runs over several queries and needs to have access to // the same temporary table. const isPaging = PAGING_QUERIES.has(method); const entry = isPaging ? this.pool.at(-1) : this.getWorker(); strictAssert(entry != null, 'Must have a pool entry'); const { result, duration } = await this.send(entry, { type: 'sqlCall:read', method, args, }); this.traceDuration(method, duration); return result; } public async sqlWrite( method: Method, ...args: Parameters ): Promise> { type Result = ReturnType; type SqlCallResult = Readonly<{ result: Result; duration: number; }>; while (this.pauseWaiters != null) { const { promise, resolve } = explodePromise(); this.pauseWaiters.push(resolve); // eslint-disable-next-line no-await-in-loop await promise; } const primary = this.pool[0]; const { result, duration } = await this.send(primary, { type: 'sqlCall:write', method, args, }); this.traceDuration(method, duration); return result; } public startTrackingQueryStats(): void { if (this.shouldTrackQueryStats) { this.logQueryStats(); } this.resetQueryStats(); this.shouldTrackQueryStats = true; } public stopTrackingQueryStats(): void { if (this.shouldTrackQueryStats) { this.logQueryStats(); } this.queryStats = undefined; this.shouldTrackQueryStats = false; } private async send( entry: PoolEntry, request: WorkerRequest ): Promise { if (request.type === 'sqlCall:read' || request.type === 'sqlCall:write') { if (this.onReady) { await this.onReady; } if (!this.isReady) { throw new Error('Not initialized'); } } const { seq } = this; // eslint-disable-next-line no-bitwise this.seq = (this.seq + 1) >>> 0; const { promise: result, resolve, reject } = explodePromise(); this.onResponse.set(seq, { resolve, reject }); const wrappedRequest: WrappedWorkerRequest = { seq, request, }; entry.worker.postMessage(wrappedRequest); try { // eslint-disable-next-line no-param-reassign entry.load += 1; return await result; } finally { // eslint-disable-next-line no-param-reassign entry.load -= 1; } } private async terminate(request: WorkerRequest): Promise { const primary = this.pool[0]; const rest = this.pool.slice(1); // Terminate non-primary workers first await Promise.all(rest.map(worker => this.send(worker, request))); // Primary last await this.send(primary, request); } private onError(errorKind: SqliteErrorKind, error: Error): void { if (errorKind === SqliteErrorKind.Unknown) { return; } const resolvers = new Array<(error: Error) => void>(); this.errorResolvers = this.errorResolvers.filter(entry => { if (entry.kind === errorKind) { resolvers.push(entry.resolve); return false; } return true; }); for (const resolve of resolvers) { resolve(error); } } private resetQueryStats() { this.queryStats = { start: Date.now(), statsByQuery: new Map() }; } private roundDuration(duration: number): number { return Math.round(100 * duration) / 100; } private logQueryStats(numberToLog: number = 10) { if (!this.queryStats) { return; } const duration = Date.now() - this.queryStats.start; const sortedByCumulativeDuration = [ ...this.queryStats.statsByQuery.values(), ].sort((a, b) => (b.cumulative ?? 0) - (a.cumulative ?? 0)); this.logger?.info( `Top ${numberToLog} queries by cumulative duration (ms) over last ${duration}ms: ` + `${sortedByCumulativeDuration .slice(0, numberToLog) .map(stats => { return ( `${stats.queryName}: cumulative ${this.roundDuration(stats.cumulative)} | ` + `average: ${this.roundDuration(stats.cumulative / (stats.count || 1))} | ` + `max: ${this.roundDuration(stats.max)} | ` + `count: ${stats.count}` ); }) .join(' ||| ')}` ); } private traceDuration(method: string, duration: number): void { if (this.shouldTrackQueryStats) { if (!this.queryStats) { this.resetQueryStats(); } strictAssert(this.queryStats, 'has been initialized'); let currentStats = this.queryStats.statsByQuery.get(method); if (!currentStats) { currentStats = { count: 0, cumulative: 0, queryName: method, max: 0 }; this.queryStats.statsByQuery.set(method, currentStats); } currentStats.count += 1; currentStats.cumulative += duration; currentStats.max = Math.max(currentStats.max, duration); } if (this.shouldTimeQueries && !app.isPackaged) { const twoDecimals = this.roundDuration(duration); this.logger?.info(`MainSQL query: ${method}, duration=${twoDecimals}ms`); } if (duration > MIN_TRACE_DURATION) { strictAssert(this.logger !== undefined, 'Logger not initialized'); this.logger.info( `MainSQL: slow query ${method} duration=${Math.round(duration)}ms` ); } } private createWorker(): CreateWorkerResultType { const scriptPath = join(app.getAppPath(), 'ts', 'sql', 'mainWorker.js'); const worker = new Worker(scriptPath); worker.on('message', (wrappedResponse: WrappedWorkerResponse) => { if (wrappedResponse.type === 'log') { const { level, args } = wrappedResponse; strictAssert(this.logger !== undefined, 'Logger not initialized'); this.logger[level](`MainSQL: ${format(...args)}`); return; } const { seq, error, errorKind, response } = wrappedResponse; const pair = this.onResponse.get(seq); this.onResponse.delete(seq); if (!pair) { throw new Error(`Unexpected worker response with seq: ${seq}`); } if (error) { const errorObj = new Error(error.message); errorObj.stack = error.stack; errorObj.name = error.name; this.onError(errorKind ?? SqliteErrorKind.Unknown, errorObj); pair.reject(errorObj); } else { pair.resolve(response); } }); const { promise: onExit, resolve: resolveOnExit } = explodePromise(); worker.once('exit', resolveOnExit); return { worker, onExit }; } // Find first pool entry with minimal load private getWorker(): PoolEntry { let min = this.pool[0]; for (const entry of this.pool) { if (min && min.load < entry.load) { continue; } min = entry; } return min; } }