2021-04-29 23:02:27 +00:00
|
|
|
// Copyright 2021 Signal Messenger, LLC
|
|
|
|
// SPDX-License-Identifier: AGPL-3.0-only
|
|
|
|
|
|
|
|
import { v4 as uuid } from 'uuid';
|
|
|
|
import { noop } from 'lodash';
|
|
|
|
|
|
|
|
import { Job } from './Job';
|
|
|
|
import { JobError } from './JobError';
|
|
|
|
import { ParsedJob, StoredJob, JobQueueStore } from './types';
|
|
|
|
import { assert } from '../util/assert';
|
|
|
|
import * as log from '../logging/log';
|
2021-08-17 15:43:26 +00:00
|
|
|
import { JobLogger } from './JobLogger';
|
2021-08-25 23:58:33 +00:00
|
|
|
import * as Errors from '../types/errors';
|
2021-04-29 23:02:27 +00:00
|
|
|
|
|
|
|
const noopOnCompleteCallbacks = {
|
|
|
|
resolve: noop,
|
|
|
|
reject: noop,
|
|
|
|
};
|
|
|
|
|
2021-07-21 21:10:08 +00:00
|
|
|
type JobQueueOptions = {
|
2021-04-29 23:02:27 +00:00
|
|
|
/**
|
|
|
|
* The backing store for jobs. Typically a wrapper around the database.
|
|
|
|
*/
|
|
|
|
store: JobQueueStore;
|
|
|
|
|
|
|
|
/**
|
|
|
|
* A unique name for this job queue. For example, might be "attachment downloads" or
|
|
|
|
* "message send".
|
|
|
|
*/
|
|
|
|
queueType: string;
|
|
|
|
|
|
|
|
/**
|
|
|
|
* The maximum number of attempts for a job in this queue. A value of 1 will not allow
|
|
|
|
* the job to fail; a value of 2 will allow the job to fail once; etc.
|
|
|
|
*/
|
|
|
|
maxAttempts: number;
|
2021-08-17 15:43:26 +00:00
|
|
|
|
|
|
|
/**
|
|
|
|
* A custom logger. Might be overwritten in test.
|
|
|
|
*/
|
|
|
|
logger?: log.LoggerType;
|
2021-04-29 23:02:27 +00:00
|
|
|
};
|
|
|
|
|
2021-07-21 21:10:08 +00:00
|
|
|
export abstract class JobQueue<T> {
|
2021-04-29 23:02:27 +00:00
|
|
|
private readonly maxAttempts: number;
|
|
|
|
|
|
|
|
private readonly queueType: string;
|
|
|
|
|
|
|
|
private readonly store: JobQueueStore;
|
|
|
|
|
2021-08-17 15:43:26 +00:00
|
|
|
private readonly logger: log.LoggerType;
|
|
|
|
|
2021-04-29 23:02:27 +00:00
|
|
|
private readonly logPrefix: string;
|
|
|
|
|
|
|
|
private readonly onCompleteCallbacks = new Map<
|
|
|
|
string,
|
|
|
|
{
|
|
|
|
resolve: () => void;
|
|
|
|
reject: (err: unknown) => void;
|
|
|
|
}
|
|
|
|
>();
|
|
|
|
|
|
|
|
private started = false;
|
|
|
|
|
2021-07-21 21:10:08 +00:00
|
|
|
constructor(options: Readonly<JobQueueOptions>) {
|
2021-04-29 23:02:27 +00:00
|
|
|
assert(
|
|
|
|
Number.isInteger(options.maxAttempts) && options.maxAttempts >= 1,
|
|
|
|
'maxAttempts should be a positive integer'
|
|
|
|
);
|
|
|
|
assert(
|
|
|
|
options.maxAttempts <= Number.MAX_SAFE_INTEGER,
|
|
|
|
'maxAttempts is too large'
|
|
|
|
);
|
|
|
|
assert(
|
|
|
|
options.queueType.trim().length,
|
|
|
|
'queueType should be a non-blank string'
|
|
|
|
);
|
|
|
|
|
|
|
|
this.maxAttempts = options.maxAttempts;
|
|
|
|
this.queueType = options.queueType;
|
|
|
|
this.store = options.store;
|
2021-08-17 15:43:26 +00:00
|
|
|
this.logger = options.logger ?? log;
|
2021-04-29 23:02:27 +00:00
|
|
|
|
|
|
|
this.logPrefix = `${this.queueType} job queue:`;
|
|
|
|
}
|
|
|
|
|
2021-07-21 21:10:08 +00:00
|
|
|
/**
|
|
|
|
* `parseData` will be called with the raw data from `store`. For example, if the job
|
|
|
|
* takes a single number, `parseData` should throw if `data` is a number and should
|
|
|
|
* return the number otherwise.
|
|
|
|
*
|
|
|
|
* If it throws, the job will be deleted from the store and the job will not be run.
|
|
|
|
*
|
|
|
|
* Will only be called once per job, even if `maxAttempts > 1`.
|
|
|
|
*/
|
|
|
|
protected abstract parseData(data: unknown): T;
|
|
|
|
|
|
|
|
/**
|
|
|
|
* Run the job, given data.
|
|
|
|
*
|
|
|
|
* If it resolves, the job will be deleted from the store.
|
|
|
|
*
|
|
|
|
* If it rejects, the job will be retried up to `maxAttempts - 1` times, after which it
|
|
|
|
* will be deleted from the store.
|
2021-08-17 15:43:26 +00:00
|
|
|
*
|
|
|
|
* If your job logs things, you're encouraged to use the logger provided, as it
|
|
|
|
* automatically includes debugging information.
|
2021-07-21 21:10:08 +00:00
|
|
|
*/
|
2021-07-23 22:02:36 +00:00
|
|
|
protected abstract run(
|
|
|
|
job: Readonly<ParsedJob<T>>,
|
2021-08-17 15:43:26 +00:00
|
|
|
extra?: Readonly<{ attempt?: number; log?: log.LoggerType }>
|
2021-07-23 22:02:36 +00:00
|
|
|
): Promise<void>;
|
2021-07-21 21:10:08 +00:00
|
|
|
|
2021-04-29 23:02:27 +00:00
|
|
|
/**
|
|
|
|
* Start streaming jobs from the store.
|
|
|
|
*/
|
|
|
|
async streamJobs(): Promise<void> {
|
|
|
|
if (this.started) {
|
|
|
|
throw new Error(
|
|
|
|
`${this.logPrefix} should not start streaming more than once`
|
|
|
|
);
|
|
|
|
}
|
|
|
|
this.started = true;
|
|
|
|
|
|
|
|
log.info(`${this.logPrefix} starting to stream jobs`);
|
|
|
|
|
|
|
|
const stream = this.store.stream(this.queueType);
|
|
|
|
for await (const storedJob of stream) {
|
|
|
|
this.enqueueStoredJob(storedJob);
|
|
|
|
}
|
|
|
|
}
|
|
|
|
|
|
|
|
/**
|
|
|
|
* Add a job, which should cause it to be enqueued and run.
|
|
|
|
*
|
2021-08-27 20:20:16 +00:00
|
|
|
* If `streamJobs` has not been called yet, this will throw an error.
|
2021-04-29 23:02:27 +00:00
|
|
|
*/
|
|
|
|
async add(data: Readonly<T>): Promise<Job<T>> {
|
2021-08-31 20:58:39 +00:00
|
|
|
this.throwIfNotStarted();
|
|
|
|
|
|
|
|
const job = this.createJob(data);
|
|
|
|
await this.store.insert(job);
|
|
|
|
log.info(`${this.logPrefix} added new job ${job.id}`);
|
|
|
|
return job;
|
|
|
|
}
|
|
|
|
|
|
|
|
protected throwIfNotStarted(): void {
|
2021-04-29 23:02:27 +00:00
|
|
|
if (!this.started) {
|
|
|
|
throw new Error(
|
|
|
|
`${this.logPrefix} has not started streaming. Make sure to call streamJobs().`
|
|
|
|
);
|
|
|
|
}
|
2021-08-31 20:58:39 +00:00
|
|
|
}
|
2021-04-29 23:02:27 +00:00
|
|
|
|
2021-08-31 20:58:39 +00:00
|
|
|
protected createJob(data: Readonly<T>): Job<T> {
|
2021-04-29 23:02:27 +00:00
|
|
|
const id = uuid();
|
|
|
|
const timestamp = Date.now();
|
|
|
|
|
|
|
|
const completionPromise = new Promise<void>((resolve, reject) => {
|
|
|
|
this.onCompleteCallbacks.set(id, { resolve, reject });
|
|
|
|
});
|
|
|
|
const completion = (async () => {
|
|
|
|
try {
|
|
|
|
await completionPromise;
|
|
|
|
} catch (err: unknown) {
|
|
|
|
throw new JobError(err);
|
|
|
|
} finally {
|
|
|
|
this.onCompleteCallbacks.delete(id);
|
|
|
|
}
|
|
|
|
})();
|
|
|
|
|
2021-08-31 20:58:39 +00:00
|
|
|
return new Job(id, timestamp, this.queueType, data, completion);
|
2021-04-29 23:02:27 +00:00
|
|
|
}
|
|
|
|
|
|
|
|
private async enqueueStoredJob(storedJob: Readonly<StoredJob>) {
|
|
|
|
assert(
|
|
|
|
storedJob.queueType === this.queueType,
|
|
|
|
'Received a mis-matched queue type'
|
|
|
|
);
|
|
|
|
|
|
|
|
log.info(`${this.logPrefix} enqueuing job ${storedJob.id}`);
|
|
|
|
|
|
|
|
// It's okay if we don't have a callback; that likely means the job was created before
|
|
|
|
// the process was started (e.g., from a previous run).
|
|
|
|
const { resolve, reject } =
|
|
|
|
this.onCompleteCallbacks.get(storedJob.id) || noopOnCompleteCallbacks;
|
|
|
|
|
|
|
|
let parsedData: T;
|
|
|
|
try {
|
|
|
|
parsedData = this.parseData(storedJob.data);
|
|
|
|
} catch (err) {
|
|
|
|
log.error(
|
|
|
|
`${this.logPrefix} failed to parse data for job ${storedJob.id}`
|
|
|
|
);
|
|
|
|
reject(
|
|
|
|
new Error(
|
|
|
|
'Failed to parse job data. Was unexpected data loaded from the database?'
|
|
|
|
)
|
|
|
|
);
|
|
|
|
return;
|
|
|
|
}
|
|
|
|
|
|
|
|
const parsedJob: ParsedJob<T> = {
|
|
|
|
...storedJob,
|
|
|
|
data: parsedData,
|
|
|
|
};
|
|
|
|
|
2021-08-17 15:43:26 +00:00
|
|
|
const logger = new JobLogger(parsedJob, this.logger);
|
|
|
|
|
2021-04-29 23:02:27 +00:00
|
|
|
let result:
|
|
|
|
| undefined
|
|
|
|
| { success: true }
|
|
|
|
| { success: false; err: unknown };
|
|
|
|
|
|
|
|
for (let attempt = 1; attempt <= this.maxAttempts; attempt += 1) {
|
2021-08-17 15:43:26 +00:00
|
|
|
logger.attempt = attempt;
|
|
|
|
|
2021-04-29 23:02:27 +00:00
|
|
|
log.info(
|
|
|
|
`${this.logPrefix} running job ${storedJob.id}, attempt ${attempt} of ${this.maxAttempts}`
|
|
|
|
);
|
|
|
|
try {
|
|
|
|
// We want an `await` in the loop, as we don't want a single job running more
|
|
|
|
// than once at a time. Ideally, the job will succeed on the first attempt.
|
|
|
|
// eslint-disable-next-line no-await-in-loop
|
2021-08-17 15:43:26 +00:00
|
|
|
await this.run(parsedJob, { attempt, log: logger });
|
2021-04-29 23:02:27 +00:00
|
|
|
result = { success: true };
|
|
|
|
log.info(
|
|
|
|
`${this.logPrefix} job ${storedJob.id} succeeded on attempt ${attempt}`
|
|
|
|
);
|
|
|
|
break;
|
|
|
|
} catch (err: unknown) {
|
|
|
|
result = { success: false, err };
|
|
|
|
log.error(
|
2021-08-25 23:58:33 +00:00
|
|
|
`${this.logPrefix} job ${
|
|
|
|
storedJob.id
|
|
|
|
} failed on attempt ${attempt}. ${Errors.toLogFormat(err)}`
|
2021-04-29 23:02:27 +00:00
|
|
|
);
|
|
|
|
}
|
|
|
|
}
|
|
|
|
|
|
|
|
await this.store.delete(storedJob.id);
|
|
|
|
|
|
|
|
assert(
|
|
|
|
result,
|
|
|
|
'The job never ran. This indicates a developer error in the job queue'
|
|
|
|
);
|
|
|
|
if (result.success) {
|
|
|
|
resolve();
|
|
|
|
} else {
|
|
|
|
reject(result.err);
|
|
|
|
}
|
|
|
|
}
|
|
|
|
}
|