2026-10-16T12:57:20.195Z Diffinery runner: job e2e (End-to-end (real histories)) for codician/diffinery@1fc12a873491 2026-10-16T12:57:20.227Z Runner: image diffinery-runner:2a3052eff7a82a483a7fde51, in lhr14, WEUR, container 1693cd173a318ebbfe3d7a0c58e8e2a1f2b0a4da195c2e7af6f607959954fc74 2026-10-16T12:57:20.227Z Workspace: /workspace/diffinery 2026-10-16T12:57:20.329Z ##[group]Run actions/checkout@v4 2026-10-16T12:57:20.329Z ##[endgroup] 2026-10-16T12:57:20.333Z Syncing repository: codician/diffinery 2026-10-16T12:57:20.636Z Fetching the repository (depth 1) 2026-10-16T12:57:22.000Z remote: Enumerating objects: 602, done. 2026-10-16T12:57:22.597Z Receiving objects: 0% (1/602) Receiving objects: 1% (7/602) Receiving objects: 2% (13/602) Receiving objects: 3% (19/602) Receiving objects: 4% (25/602) Receiving objects: 5% (31/602) Receiving objects: 6% (37/602) Receiving objects: 7% (43/602) Receiving objects: 8% (49/602) Receiving objects: 9% (55/602) Receiving objects: 10% (61/602) Receiving objects: 11% (67/602) Receiving objects: 12% (73/602) Receiving objects: 13% (79/602) Receiving objects: 14% (85/602) Receiving objects: 15% (91/602) Receiving objects: 16% (97/602) Receiving objects: 17% (103/602) Receiving objects: 18% (109/602) Receiving objects: 19% (115/602) Receiving objects: 20% (121/602) Receiving objects: 21% (127/602) Receiving objects: 22% (133/602) Receiving objects: 23% (139/602) Receiving objects: 24% (145/602) Receiving objects: 25% (151/602) Receiving objects: 26% (157/602) Receiving objects: 27% (163/602) Receiving objects: 28% (169/602) Receiving objects: 29% (175/602) Receiving objects: 30% (181/602) Receiving objects: 31% (187/602) Receiving objects: 32% (193/602) Receiving objects: 33% (199/602) Receiving objects: 34% (205/602) Receiving objects: 35% (211/602) Receiving objects: 36% (217/602) Receiving objects: 37% (223/602) Receiving objects: 38% (229/602) Receiving objects: 39% (235/602) Receiving objects: 40% (241/602) Receiving objects: 41% (247/602) Receiving objects: 42% (253/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 43% (259/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 44% (265/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 45% (271/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 46% (277/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 47% (283/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 48% (289/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 49% (295/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 50% (301/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 51% (308/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 52% (314/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 53% (320/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 54% (326/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 55% (332/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 56% (338/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 57% (344/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 58% (350/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 59% (356/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 60% (362/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 61% (368/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 62% (374/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 63% (380/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 64% (386/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 65% (392/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 66% (398/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 67% (404/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 68% (410/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 69% (416/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 70% (422/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 71% (428/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 72% (434/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 73% (440/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 74% (446/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 75% (452/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 76% (458/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 77% (464/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 78% (470/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 79% (476/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 80% (482/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 81% (488/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 82% (494/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 83% (500/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 84% (506/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 85% (512/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 86% (518/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 87% (524/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 88% (530/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 89% (536/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 90% (542/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 91% (548/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 92% (554/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 93% (560/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 94% (566/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 95% (572/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 96% (578/602), 1.07 MiB | 2.09 MiB/s remote: Total 602 (delta 0), reused 602 (delta 0) 2026-10-16T12:57:22.600Z Receiving objects: 97% (584/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 98% (590/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 99% (596/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 100% (602/602), 1.07 MiB | 2.09 MiB/s Receiving objects: 100% (602/602), 2.44 MiB | 4.17 MiB/s, done. 2026-10-16T12:57:22.627Z From https://diffinery.com/codician/diffinery 2026-10-16T12:57:22.627Z * [new ref] 1fc12a873491c0db642dee377efb718cd36a828f -> origin/diffinery-checkout 2026-10-16T12:57:22.759Z Note: switching to '1fc12a873491c0db642dee377efb718cd36a828f'. 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z You are in 'detached HEAD' state. You can look around, make experimental 2026-10-16T12:57:22.759Z changes and commit them, and you can discard any commits you make in this 2026-10-16T12:57:22.759Z state without impacting any branches by switching back to a branch. 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z If you want to create a new branch to retain commits you create, you may 2026-10-16T12:57:22.759Z do so (now or later) by using -c with the switch command. Example: 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z git switch -c 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z Or undo this operation with: 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z git switch - 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z Turn off this advice by setting config variable advice.detachedHead to false 2026-10-16T12:57:22.759Z 2026-10-16T12:57:22.759Z HEAD is now at 1fc12a8 Merge 485179e54aa9803ef8bd7cf4d78d18cc2c7446e3 into be909c86151b78b94d76ad5cb2332b14ffd64f29 2026-10-16T12:57:22.767Z HEAD is now at 1fc12a873491c0db642dee377efb718cd36a828f 2026-10-16T12:57:23.396Z Download action repository 'pnpm/action-setup@b906affcce14559ad1aafd4ab0e942779e9f58b1' 2026-10-16T12:57:24.048Z ##[group]Run pnpm/action-setup@b906affcce14559ad1aafd4ab0e942779e9f58b1 2026-10-16T12:57:24.049Z with: 2026-10-16T12:57:24.049Z dest: ~/setup-pnpm 2026-10-16T12:57:24.049Z run_install: null 2026-10-16T12:57:24.049Z cache: false 2026-10-16T12:57:24.049Z cache_dependency_path: pnpm-lock.yaml 2026-10-16T12:57:24.049Z package_json_file: package.json 2026-10-16T12:57:24.049Z standalone: false 2026-10-16T12:57:24.049Z ##[endgroup] 2026-10-16T12:57:24.456Z ##[group]Running self-installer... 2026-10-16T12:57:26.115Z Progress: resolved 1, reused 0, downloaded 0, added 0 2026-10-16T12:57:26.157Z Packages: +1 2026-10-16T12:57:26.157Z + 2026-10-16T12:57:27.421Z Progress: resolved 1, reused 0, downloaded 1, added 0 2026-10-16T12:57:27.591Z Progress: resolved 1, reused 0, downloaded 1, added 1, done 2026-10-16T12:57:27.676Z 2026-10-16T12:57:27.676Z dependencies: 2026-10-16T12:57:27.676Z + pnpm 10.28.0 (12.14.0 is available) 2026-10-16T12:57:27.676Z 2026-10-16T12:57:27.686Z Done in 2.7s 2026-10-16T12:57:27.737Z ##[endgroup] 2026-10-16T12:57:27.737Z Installation Completed! 2026-10-16T12:57:27.907Z ##[group]Run actions/setup-node@v4 2026-10-16T12:57:27.907Z with: 2026-10-16T12:57:27.907Z node-version: 22 2026-10-16T12:57:27.907Z cache: pnpm 2026-10-16T12:57:27.907Z ##[endgroup] 2026-10-16T12:57:27.922Z Found in cache @ 22.22.0 (pre-installed node satisfies '22') 2026-10-16T12:57:36.449Z Cache Size: ~215 MB (225870423 B) 2026-10-16T12:57:44.561Z Cache restored from key: node-cache-Linux-x64-pnpm-4b94436b86798d88b931ea24b5b847697aebf45520d6ef384cb382ce6c1f1e08 2026-10-16T12:57:44.743Z ##[group]Run pnpm install --frozen-lockfile 2026-10-16T12:57:44.743Z ##[endgroup] 2026-10-16T12:57:45.483Z Scope: all 7 workspace projects 2026-10-16T12:57:45.615Z Lockfile is up to date, resolution step is skipped 2026-10-16T12:57:45.713Z Progress: resolved 1, reused 0, downloaded 0, added 0 2026-10-16T12:57:45.801Z Packages: +248 2026-10-16T12:57:45.801Z ++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ 2026-10-16T12:57:46.715Z Progress: resolved 248, reused 242, downloaded 0, added 2 2026-10-16T12:57:47.719Z Progress: resolved 248, reused 242, downloaded 0, added 241 2026-10-16T12:57:47.980Z Progress: resolved 248, reused 242, downloaded 0, added 248, done 2026-10-16T12:58:08.914Z 2026-10-16T12:58:08.914Z devDependencies: 2026-10-16T12:58:08.914Z + turbo 2.11.7 2026-10-16T12:58:08.914Z + typescript 5.9.3 2026-10-16T12:58:08.914Z 2026-10-16T12:58:09.583Z Done in 24.7s using pnpm v10.28.0 2026-10-16T12:58:09.892Z ##[group]Run pnpm -r --if-present build 2026-10-16T12:58:09.892Z ##[endgroup] 2026-10-16T12:58:10.612Z Scope: 6 of 7 workspace projects 2026-10-16T12:58:10.621Z apps/cli build$ esbuild src/bin.ts --bundle --platform=node --target=node22 --format=esm --outfile=dist/diffinery.mjs "--banner:js=#!/usr/bin/env node 2026-10-16T12:58:10.621Z import { createRequire as __diffineryCreateRequire } from 'node:module'; const require = __diffineryCreateRequire(import.meta.url);" 2026-10-16T12:58:10.622Z containers/runner build$ esbuild src/main.ts --bundle --platform=node --target=node22 --format=esm --outfile=dist/agent.mjs --external:bufferutil --external:utf-8-validate "--banner:js=import { createRequire as __diffineryCreateRequire } from 'node:module'; const require = __diffineryCreateRequire(import.meta.url);" 2026-10-16T12:58:10.623Z apps/diffinery build$ node islands/build.mjs && node scripts/build-docs.mjs 2026-10-16T12:58:10.778Z apps/cli build: dist/diffinery.mjs 439.5kb 2026-10-16T12:58:10.778Z apps/cli build: ⚡ Done in 118ms 2026-10-16T12:58:10.804Z apps/cli build: Done 2026-10-16T12:58:10.811Z containers/runner build: dist/agent.mjs 657.3kb 2026-10-16T12:58:10.813Z containers/runner build: ⚡ Done in 154ms 2026-10-16T12:58:10.824Z containers/runner build: Done 2026-10-16T12:58:12.819Z apps/diffinery build: islands: kumo.js 466 KB, kumo.css 149 KB, terminal.js 286 KB 2026-10-16T12:58:13.425Z apps/diffinery build: docs: 62 pages (+2 generated at runtime), CLI 0.1.0 (230 KiB) 2026-10-16T12:58:13.443Z apps/diffinery build: Done 2026-10-16T12:58:15.253Z ##[group]Run # Shards of similar length; a new test file runs in "rest" unless it's added to another. 2026-10-16T12:58:15.253Z core='/e2e\.test\.mjs$' 2026-10-16T12:58:15.253Z agents='/(agentic|review|tasks)\.test\.mjs$' 2026-10-16T12:58:15.253Z security='/(security|status)\.test\.mjs$' 2026-10-16T12:58:15.253Z # The core file's real histories and large pushes take minutes each (jquery's alone 2 to 3 on 2026-10-16T12:58:15.253Z # Diffinery's runners): two shards of their own, so no part of the file runs into node's 2026-10-16T12:58:15.253Z # per-file timeout, which is the --test-timeout here. 2026-10-16T12:58:15.253Z histories='^real histories:' 2026-10-16T12:58:15.253Z large='^(large files|large pushes):' 2026-10-16T12:58:15.253Z opts=(--test-timeout=600000) 2026-10-16T12:58:15.253Z case "histories" in 2026-10-16T12:58:15.253Z core) files=$(ls test/e2e/*.test.mjs | grep -E "$core"); opts+=(--test-skip-pattern="$histories" --test-skip-pattern="$large") ;; 2026-10-16T12:58:15.253Z histories) files=$(ls test/e2e/*.test.mjs | grep -E "$core"); opts=(--test-timeout=900000 --test-name-pattern="$histories") ;; 2026-10-16T12:58:15.253Z large) files=$(ls test/e2e/*.test.mjs | grep -E "$core"); opts+=(--test-name-pattern="$large") ;; 2026-10-16T12:58:15.253Z agents) files=$(ls test/e2e/*.test.mjs | grep -E "$agents") ;; 2026-10-16T12:58:15.253Z security) files=$(ls test/e2e/*.test.mjs | grep -E "$security") ;; 2026-10-16T12:58:15.253Z *) files=$(ls test/e2e/*.test.mjs | grep -vE "$core|$agents|$security") ;; 2026-10-16T12:58:15.253Z esac 2026-10-16T12:58:15.253Z echo "$files" 2026-10-16T12:58:15.253Z node --test --test-concurrency=1 "${opts[@]}" $files 2026-10-16T12:58:15.253Z ##[endgroup] 2026-10-16T12:58:15.822Z test/e2e/e2e.test.mjs 2026-10-16T12:58:15.903Z TAP version 13 2026-10-16T13:01:33.891Z # Subtest: real histories: jquery's whole history goes in one push and clones back intact 2026-10-16T13:01:33.892Z ok 1 - real histories: jquery's whole history goes in one push and clones back intact 2026-10-16T13:01:33.892Z --- 2026-10-16T13:01:33.892Z duration_ms: 186634.008096 2026-10-16T13:01:33.892Z type: 'test' 2026-10-16T13:01:33.892Z ... 2026-10-16T13:03:00.266Z # Subtest: real histories: tornado's whole history goes in one push and clones back intact 2026-10-16T13:03:00.266Z ok 2 - real histories: tornado's whole history goes in one push and clones back intact 2026-10-16T13:03:00.267Z --- 2026-10-16T13:03:00.267Z duration_ms: 87141.356088 2026-10-16T13:03:00.267Z type: 'test' 2026-10-16T13:03:00.267Z ... 2026-10-16T13:04:24.353Z # Subtest: real histories: flask's whole history goes in one push and clones back intact 2026-10-16T13:04:24.354Z ok 3 - real histories: flask's whole history goes in one push and clones back intact 2026-10-16T13:04:24.354Z --- 2026-10-16T13:04:24.354Z duration_ms: 67383.767994 2026-10-16T13:04:24.354Z type: 'test' 2026-10-16T13:04:24.354Z ... 2026-10-16T13:05:13.675Z # Subtest: real histories: a 2.6 MB lockfile's 60 versions, a chain of deltas, go in one small push 2026-10-16T13:05:13.676Z ok 4 - real histories: a 2.6 MB lockfile's 60 versions, a chain of deltas, go in one small push 2026-10-16T13:05:13.678Z --- 2026-10-16T13:05:13.678Z duration_ms: 67530.108673 2026-10-16T13:05:13.678Z type: 'test' 2026-10-16T13:05:13.678Z ... 2026-10-16T13:05:13.766Z 1..4 2026-10-16T13:05:13.766Z # tests 4 2026-10-16T13:05:13.766Z # suites 0 2026-10-16T13:05:13.766Z # pass 4 2026-10-16T13:05:13.766Z # fail 0 2026-10-16T13:05:13.767Z # cancelled 0 2026-10-16T13:05:13.767Z # skipped 0 2026-10-16T13:05:13.767Z # todo 0 2026-10-16T13:05:13.767Z # duration_ms 417887.090335 2026-10-16T13:05:14.008Z Cache hit occurred on the primary key node-cache-Linux-x64-pnpm-4b94436b86798d88b931ea24b5b847697aebf45520d6ef384cb382ce6c1f1e08, not saving cache. 2026-10-16T13:05:14.664Z Pruning is unnecessary. 2026-10-16T13:05:14.843Z Removing persisted credentials 2026-10-16T13:05:14.913Z Job completed with conclusion: success