GitNexus/gitnexus/test/unit/lbug-extension-loader.test.ts
Artur Kaminski 91953a3cba fix(fts): stop warning "FTS extension unavailable" on the run that installs it
On a machine with no cached LadybugDB extension, the first `gitnexus analyze`
logs a WARN

  GitNexus: FTS extension unavailable; continuing without FTS features.
  load-only policy (no install attempted); LOAD fts failed: ...

and then, in the same run, installs FTS and builds every search index. Nothing
was degraded — only the log was wrong, and it sent users chasing a broken
install path that does not exist (see the first of the two warn lines in #2184,
where only the second one is real).

The line comes from `initLbug`'s writable FTS pre-load. That call deliberately
never installs (analyze owns extension installation), so on a cold cache it is
*expected* to miss; Phase 3 retries moments later with the `auto` policy and
succeeds. `ExtensionManager.markUnavailable` had no way to tell that
speculative probe from a final answer, so it reported every miss as a user-
facing degradation.

Adds `quiet` to `ExtensionEnsureOptions`: the outcome is still recorded in
capabilities, but it is logged at debug level and does not consume the
once-per-(extension, reason) warn budget — so a later real failure with the
same reason still warns. Set only on the writable `initLbug` pre-load. The
read-only serve/MCP branch keeps `{ policy: 'load-only' }` with no `quiet`:
there is no later retry there, so that warning is accurate. Analyze Phase 3,
`--repair-fts` and genuinely-offline installs (#2184) are untouched and still
report loudly.

Verified end-to-end against a temp `HOME` with no `~/.lbdb`: analyze emits no
FTS warning, installs `libfts.lbug_extension`, and builds all FTS indexes.

Co-Authored-By: Claude Opus 5 <noreply@anthropic.com>
2026-07-26 07:42:08 +02:00

415 lines
15 KiB
TypeScript

import { describe, expect, it, vi } from 'vitest';
import {
ExtensionManager,
getExtensionInstallChildProcessArgs,
getExtensionInstallPolicy,
getExtensionInstallTimeoutMs,
type ExtensionInstallResult,
} from '../../src/core/lbug/extension-loader.js';
const okInstall: ExtensionInstallResult = {
success: true,
timedOut: false,
message: 'installed',
};
const failedInstall: ExtensionInstallResult = {
success: false,
timedOut: false,
message: 'install failed',
};
const timedOutInstall: ExtensionInstallResult = {
success: false,
timedOut: true,
message: 'INSTALL vector timed out after 10ms',
};
const noopWarn = (): void => {};
describe('ExtensionManager — LOAD-first behavior', () => {
it('uses LOAD only and never invokes INSTALL when the extension is already available', async () => {
const installExtension = vi.fn();
const manager = new ExtensionManager({ policy: 'auto', installExtension });
const query = vi.fn().mockResolvedValue({});
await expect(manager.ensure(query, 'fts', 'FTS')).resolves.toBe(true);
expect(query.mock.calls.map(([sql]) => sql)).toEqual(['LOAD EXTENSION fts']);
expect(installExtension).not.toHaveBeenCalled();
expect(manager.getCapabilities()).toEqual([{ name: 'fts', loaded: true }]);
});
it('treats "already loaded" load errors as success', async () => {
const installExtension = vi.fn();
const manager = new ExtensionManager({ policy: 'auto', installExtension });
const query = vi.fn().mockRejectedValue(new Error('Extension fts is already loaded'));
await expect(manager.ensure(query, 'fts', 'FTS')).resolves.toBe(true);
expect(installExtension).not.toHaveBeenCalled();
});
});
describe('ExtensionManager — install policies', () => {
it('runs bounded out-of-process INSTALL and retries LOAD when policy=auto', async () => {
const installExtension = vi.fn().mockResolvedValue(okInstall);
const manager = new ExtensionManager({ policy: 'auto', installExtension });
const query = vi
.fn()
.mockRejectedValueOnce(new Error('Extension "fts" not found'))
.mockResolvedValueOnce({});
await expect(manager.ensure(query, 'fts', 'FTS', { installTimeoutMs: 1234 })).resolves.toBe(
true,
);
// The LOAD failure reason is threaded to the installer so it can pick
// INSTALL vs FORCE INSTALL from the error class (#2374, PR #2375).
expect(installExtension).toHaveBeenCalledWith('fts', 1234, 'Extension "fts" not found');
expect(query.mock.calls.map(([sql]) => sql)).toEqual([
'LOAD EXTENSION fts',
'LOAD EXTENSION fts',
]);
expect(query.mock.calls.some(([sql]) => String(sql).startsWith('INSTALL '))).toBe(false);
});
it('skips INSTALL and warns when policy=load-only', async () => {
const installExtension = vi.fn();
const warn = vi.fn();
const manager = new ExtensionManager({ policy: 'load-only', installExtension, warn });
const query = vi.fn().mockRejectedValue(new Error('Extension "fts" not found'));
await expect(manager.ensure(query, 'fts', 'FTS')).resolves.toBe(false);
expect(installExtension).not.toHaveBeenCalled();
expect(warn).toHaveBeenCalledWith(expect.stringContaining('continuing without FTS features'));
expect(manager.getCapabilities()).toMatchObject([
{ name: 'fts', loaded: false, reason: expect.stringContaining('load-only') },
]);
});
it('short-circuits LOAD and INSTALL when policy=never', async () => {
const installExtension = vi.fn();
const warn = vi.fn();
const manager = new ExtensionManager({ policy: 'never', installExtension, warn });
const query = vi.fn();
await expect(manager.ensure(query, 'vector', 'VECTOR')).resolves.toBe(false);
expect(query).not.toHaveBeenCalled();
expect(installExtension).not.toHaveBeenCalled();
expect(warn).toHaveBeenCalledWith(
expect.stringContaining('continuing without VECTOR features'),
);
});
it('per-call options override manager defaults', async () => {
const installExtension = vi.fn().mockResolvedValue(okInstall);
const manager = new ExtensionManager({
policy: 'auto',
installExtension,
warn: noopWarn,
});
const query = vi.fn().mockRejectedValue(new Error('Extension "vector" not found'));
await expect(manager.ensure(query, 'vector', 'VECTOR', { policy: 'load-only' })).resolves.toBe(
false,
);
expect(installExtension).not.toHaveBeenCalled();
});
it('returns false and warns when bounded install times out', async () => {
const installExtension = vi.fn().mockResolvedValue(timedOutInstall);
const warn = vi.fn();
const manager = new ExtensionManager({ policy: 'auto', installExtension, warn });
const query = vi.fn().mockRejectedValue(new Error('Extension "vector" not found'));
await expect(manager.ensure(query, 'vector', 'VECTOR', { installTimeoutMs: 10 })).resolves.toBe(
false,
);
expect(query.mock.calls.map(([sql]) => sql)).toEqual(['LOAD EXTENSION vector']);
expect(warn).toHaveBeenCalledWith(
expect.stringContaining('continuing without VECTOR features'),
);
});
});
describe('ExtensionManager — reason strings carry the real LOAD error (#2374)', () => {
it('load-only failure reason includes the underlying LadybugDB error, collapsed to one line', async () => {
const warn = vi.fn();
const manager = new ExtensionManager({ policy: 'load-only', warn });
const query = vi
.fn()
.mockRejectedValue(
new Error(
'IO exception: Failed to load library: /x/libfts.lbug_extension.\ninvalid ELF header',
),
);
await expect(manager.ensure(query, 'fts', 'FTS')).resolves.toBe(false);
expect(manager.getCapabilities()).toMatchObject([
{
name: 'fts',
loaded: false,
reason: expect.stringContaining(
'LOAD fts failed: IO exception: Failed to load library: /x/libfts.lbug_extension. invalid ELF header',
),
},
]);
expect(warn).toHaveBeenCalledWith(expect.stringContaining('invalid ELF header'));
});
it('failed-install reason includes both the install message and the original LOAD error', async () => {
const installExtension = vi.fn().mockResolvedValue(failedInstall);
const manager = new ExtensionManager({ policy: 'auto', installExtension, warn: noopWarn });
const query = vi.fn().mockRejectedValue(new Error('Extension "fts" not found'));
await expect(manager.ensure(query, 'fts', 'FTS')).resolves.toBe(false);
expect(manager.getCapabilities()).toMatchObject([
{
name: 'fts',
loaded: false,
reason: 'install failed; LOAD fts had failed: Extension "fts" not found',
},
]);
});
it('post-install LOAD failure reason includes the retry error', async () => {
const installExtension = vi.fn().mockResolvedValue(okInstall);
const manager = new ExtensionManager({ policy: 'auto', installExtension, warn: noopWarn });
const query = vi
.fn()
.mockRejectedValue(new Error('version mismatch: extension built for 0.17.0'));
await expect(manager.ensure(query, 'fts', 'FTS')).resolves.toBe(false);
expect(manager.getCapabilities()).toMatchObject([
{
name: 'fts',
loaded: false,
reason:
'LOAD fts failed after successful INSTALL: version mismatch: extension built for 0.17.0',
},
]);
});
});
describe('ExtensionManager — caching', () => {
it('caches install attempt outcome to avoid retrying within the same process', async () => {
const installExtension = vi.fn().mockResolvedValue(timedOutInstall);
const manager = new ExtensionManager({
policy: 'auto',
installExtension,
warn: noopWarn,
});
const query = vi.fn().mockRejectedValue(new Error('Extension "vector" not found'));
await expect(manager.ensure(query, 'vector', 'VECTOR')).resolves.toBe(false);
await expect(manager.ensure(query, 'vector', 'VECTOR')).resolves.toBe(false);
expect(installExtension).toHaveBeenCalledOnce();
});
it('reset() clears capability and install state so install is retried', async () => {
const installExtension = vi.fn().mockResolvedValue(failedInstall);
const manager = new ExtensionManager({
policy: 'auto',
installExtension,
warn: noopWarn,
});
const query = vi.fn().mockRejectedValue(new Error('Extension "fts" not found'));
await manager.ensure(query, 'fts', 'FTS');
expect(manager.getCapabilities()).toHaveLength(1);
manager.reset();
expect(manager.getCapabilities()).toEqual([]);
await manager.ensure(query, 'fts', 'FTS');
expect(installExtension).toHaveBeenCalledTimes(2);
});
});
describe('ExtensionManager — observability', () => {
it('exposes per-extension capability snapshot', async () => {
const manager = new ExtensionManager({ policy: 'load-only', warn: noopWarn });
const okQuery = vi.fn().mockResolvedValue({});
const failQuery = vi.fn().mockRejectedValue(new Error('Extension "vector" not found'));
await manager.ensure(okQuery, 'fts', 'FTS');
await manager.ensure(failQuery, 'vector', 'VECTOR');
expect(manager.getCapabilities()).toMatchObject([
{ name: 'fts', loaded: true },
{ name: 'vector', loaded: false, reason: expect.stringContaining('load-only') },
]);
});
it('warns at most once per (extension, reason) pair', async () => {
const installExtension = vi.fn();
const warn = vi.fn();
const manager = new ExtensionManager({ policy: 'load-only', installExtension, warn });
const query = vi.fn().mockRejectedValue(new Error('Extension "fts" not found'));
await manager.ensure(query, 'fts', 'FTS');
await manager.ensure(query, 'fts', 'FTS');
expect(warn).toHaveBeenCalledTimes(1);
});
// initLbug's writable FTS pre-load is a speculative probe — on a cold
// machine it misses, then analyze Phase 3 installs and every FTS index builds.
// The probe must not report a degradation that the same run repairs.
it('stays silent on a quiet probe that a later install-capable call repairs', async () => {
const installExtension = vi.fn().mockResolvedValue(okInstall);
const warn = vi.fn();
const manager = new ExtensionManager({ installExtension, warn });
const query = vi
.fn()
.mockRejectedValueOnce(new Error('Extension "fts" not found'))
.mockRejectedValueOnce(new Error('Extension "fts" not found'))
.mockResolvedValueOnce({});
await expect(
manager.ensure(query, 'fts', 'FTS', { policy: 'load-only', quiet: true }),
).resolves.toBe(false);
expect(warn).not.toHaveBeenCalled();
await expect(manager.ensure(query, 'fts', 'FTS', { policy: 'auto' })).resolves.toBe(true);
expect(warn).not.toHaveBeenCalled();
expect(manager.getCapabilities()).toEqual([{ name: 'fts', loaded: true }]);
});
it('still warns on a genuine load-only miss that follows a quiet probe of the same reason', async () => {
const warn = vi.fn();
const manager = new ExtensionManager({ policy: 'load-only', warn });
const query = vi.fn().mockRejectedValue(new Error('Extension "fts" not found'));
await manager.ensure(query, 'fts', 'FTS', { quiet: true });
await manager.ensure(query, 'fts', 'FTS');
expect(warn).toHaveBeenCalledTimes(1);
expect(warn).toHaveBeenCalledWith(expect.stringContaining('continuing without FTS features'));
});
});
describe('ExtensionManager — input validation', () => {
it('rejects extension names that are not bare identifiers', async () => {
const manager = new ExtensionManager({ policy: 'auto' });
const query = vi.fn();
await expect(manager.ensure(query, 'fts; DROP TABLE x', 'FTS')).rejects.toThrow(/Invalid/);
expect(query).not.toHaveBeenCalled();
});
});
describe('installDuckDbExtensionOutOfProcess child process', () => {
it('spawns the stable packaged installer script instead of inline -e code', () => {
const args = getExtensionInstallChildProcessArgs('fts');
expect(args).not.toContain('-e');
expect(args).not.toContain('--input-type=module');
expect(args[0]).toContain('scripts');
expect(args[0]).toContain('install-duckdb-extension.mjs');
expect(args[1]).toBe('fts');
expect(Number(args[2])).toBeGreaterThan(0);
});
it('passes the resolved LadybugDB max DB size to the installer child', () => {
expect(getExtensionInstallChildProcessArgs('fts', 1234).at(-1)).toBe('1234');
});
});
describe('getExtensionInstallPolicy', () => {
it('defaults to load-only when env var is unset', () => {
const original = process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
try {
expect(getExtensionInstallPolicy()).toBe('load-only');
} finally {
if (original === undefined) {
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
} else {
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = original;
}
}
});
it('returns auto when env var is set to auto', () => {
const original = process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = 'auto';
try {
expect(getExtensionInstallPolicy()).toBe('auto');
} finally {
if (original === undefined) {
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
} else {
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = original;
}
}
});
it('returns never when env var is set to never', () => {
const original = process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = 'never';
try {
expect(getExtensionInstallPolicy()).toBe('never');
} finally {
if (original === undefined) {
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
} else {
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = original;
}
}
});
it('falls back to load-only for invalid env var values', () => {
const original = process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = 'bogus';
try {
expect(getExtensionInstallPolicy()).toBe('load-only');
} finally {
if (original === undefined) {
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL;
} else {
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL = original;
}
}
});
});
describe('getExtensionInstallTimeoutMs', () => {
it('reads a positive override from the environment', () => {
const original = process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS;
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS = '42';
try {
expect(getExtensionInstallTimeoutMs()).toBe(42);
} finally {
if (original === undefined) {
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS;
} else {
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS = original;
}
}
});
it('falls back to the default when the env var is missing or invalid', () => {
const original = process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS;
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS;
try {
expect(getExtensionInstallTimeoutMs()).toBe(15_000);
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS = 'notanumber';
expect(getExtensionInstallTimeoutMs()).toBe(15_000);
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS = '0';
expect(getExtensionInstallTimeoutMs()).toBe(15_000);
} finally {
if (original === undefined) {
delete process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS;
} else {
process.env.GITNEXUS_LBUG_EXTENSION_INSTALL_TIMEOUT_MS = original;
}
}
});
});