2026-10-14T16:13:33.907Z Diffinery runner: job e2e (End-to-end (real histories)) for codician/diffinery@491614d3659c 2026-10-14T16:13:33.956Z Runner: image diffinery-runner:75052421315d69e3ccfad876, in lax11, WNAM, container 44502681f11bed5ce15dd1cd5702c972a24d176607c29c1198c3eb2ce83b0d60 2026-10-14T16:13:33.956Z Workspace: /workspace/diffinery 2026-10-14T16:13:34.157Z ##[group]Run actions/checkout@v4 2026-10-14T16:13:34.157Z ##[endgroup] 2026-10-14T16:13:34.159Z Syncing repository: codician/diffinery 2026-10-14T16:13:34.291Z Fetching the repository (depth 1) 2026-10-14T16:13:35.620Z remote: Enumerating objects: 597, done. 2026-10-14T16:13:36.373Z Receiving objects: 0% (1/597) Receiving objects: 1% (6/597) Receiving objects: 2% (12/597) Receiving objects: 3% (18/597) Receiving objects: 4% (24/597) Receiving objects: 5% (30/597) Receiving objects: 6% (36/597) Receiving objects: 7% (42/597) Receiving objects: 8% (48/597) Receiving objects: 9% (54/597) Receiving objects: 10% (60/597) Receiving objects: 11% (66/597) Receiving objects: 12% (72/597) Receiving objects: 13% (78/597) Receiving objects: 14% (84/597) Receiving objects: 15% (90/597) Receiving objects: 16% (96/597) Receiving objects: 17% (102/597) Receiving objects: 18% (108/597) Receiving objects: 19% (114/597) Receiving objects: 20% (120/597) Receiving objects: 21% (126/597) Receiving objects: 22% (132/597) Receiving objects: 23% (138/597) Receiving objects: 24% (144/597) Receiving objects: 25% (150/597) Receiving objects: 26% (156/597) Receiving objects: 27% (162/597) Receiving objects: 28% (168/597) Receiving objects: 29% (174/597) Receiving objects: 30% (180/597) Receiving objects: 31% (186/597) Receiving objects: 32% (192/597) Receiving objects: 33% (198/597) Receiving objects: 34% (203/597) Receiving objects: 35% (209/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 36% (215/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 37% (221/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 38% (227/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 39% (233/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 40% (239/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 41% (245/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 42% (251/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 43% (257/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 44% (263/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 45% (269/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 46% (275/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 47% (281/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 48% (287/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 49% (293/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 50% (299/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 51% (305/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 52% (311/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 53% (317/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 54% (323/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 55% (329/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 56% (335/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 57% (341/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 58% (347/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 59% (353/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 60% (359/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 61% (365/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 62% (371/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 63% (377/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 64% (383/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 65% (389/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 66% (395/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 67% (400/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 68% (406/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 69% (412/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 70% (418/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 71% (424/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 72% (430/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 73% (436/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 74% (442/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 75% (448/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 76% (454/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 77% (460/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 78% (466/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 79% (472/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 80% (478/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 81% (484/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 82% (490/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 83% (496/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 84% (502/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 85% (508/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 86% (514/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 87% (520/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 88% (526/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 89% (532/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 90% (538/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 91% (544/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 92% (550/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 93% (556/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 94% (562/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 95% (568/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 96% (574/597), 899.71 KiB | 1.73 MiB/s remote: Total 597 (delta 0), reused 597 (delta 0) 2026-10-14T16:13:36.376Z Receiving objects: 97% (580/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 98% (586/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 99% (592/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 100% (597/597), 899.71 KiB | 1.73 MiB/s Receiving objects: 100% (597/597), 2.43 MiB | 3.23 MiB/s, done. 2026-10-14T16:13:36.386Z From https://diffinery.com/codician/diffinery 2026-10-14T16:13:36.386Z * [new ref] 491614d3659cfa00e165021dac21367246c7d375 -> origin/diffinery-checkout 2026-10-14T16:13:36.458Z Note: switching to '491614d3659cfa00e165021dac21367246c7d375'. 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z You are in 'detached HEAD' state. You can look around, make experimental 2026-10-14T16:13:36.458Z changes and commit them, and you can discard any commits you make in this 2026-10-14T16:13:36.458Z state without impacting any branches by switching back to a branch. 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z If you want to create a new branch to retain commits you create, you may 2026-10-14T16:13:36.458Z do so (now or later) by using -c with the switch command. Example: 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z git switch -c 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z Or undo this operation with: 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z git switch - 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z Turn off this advice by setting config variable advice.detachedHead to false 2026-10-14T16:13:36.458Z 2026-10-14T16:13:36.458Z HEAD is now at 491614d Merge fb64d9a83eb9ef2426134a0c6488a9fecd9cee6c into effb7caf7edead436a659ba45594e585320b1e70 2026-10-14T16:13:36.463Z HEAD is now at 491614d3659cfa00e165021dac21367246c7d375 2026-10-14T16:13:36.862Z Download action repository 'pnpm/action-setup@v4' 2026-10-14T16:13:37.280Z ##[group]Run pnpm/action-setup@v4 2026-10-14T16:13:37.280Z with: 2026-10-14T16:13:37.280Z dest: ~/setup-pnpm 2026-10-14T16:13:37.280Z run_install: null 2026-10-14T16:13:37.280Z cache: false 2026-10-14T16:13:37.280Z cache_dependency_path: pnpm-lock.yaml 2026-10-14T16:13:37.280Z package_json_file: package.json 2026-10-14T16:13:37.280Z standalone: false 2026-10-14T16:13:37.280Z ##[endgroup] 2026-10-14T16:13:37.511Z ##[group]Running self-installer... 2026-10-14T16:13:38.427Z Progress: resolved 1, reused 0, downloaded 0, added 0 2026-10-14T16:13:38.449Z Packages: +1 2026-10-14T16:13:38.449Z + 2026-10-14T16:13:39.099Z Progress: resolved 1, reused 0, downloaded 1, added 1, done 2026-10-14T16:13:39.127Z 2026-10-14T16:13:39.127Z dependencies: 2026-10-14T16:13:39.127Z + pnpm 10.28.0 (12.11.2 is available) 2026-10-14T16:13:39.127Z 2026-10-14T16:13:39.130Z Done in 1.3s 2026-10-14T16:13:39.150Z ##[endgroup] 2026-10-14T16:13:39.150Z Installation Completed! 2026-10-14T16:13:39.546Z ##[group]Run actions/setup-node@v4 2026-10-14T16:13:39.547Z with: 2026-10-14T16:13:39.547Z node-version: 22 2026-10-14T16:13:39.547Z cache: pnpm 2026-10-14T16:13:39.547Z ##[endgroup] 2026-10-14T16:13:39.555Z Found in cache @ 22.22.0 (pre-installed node satisfies '22') 2026-10-14T16:13:46.786Z Cache Size: ~215 MB (225870423 B) 2026-10-14T16:13:52.865Z Cache restored from key: node-cache-Linux-x64-pnpm-4b94436b86798d88b931ea24b5b847697aebf45520d6ef384cb382ce6c1f1e08 2026-10-14T16:13:53.274Z ##[group]Run pnpm install --frozen-lockfile 2026-10-14T16:13:53.274Z ##[endgroup] 2026-10-14T16:13:54.420Z Scope: all 7 workspace projects 2026-10-14T16:13:54.501Z Lockfile is up to date, resolution step is skipped 2026-10-14T16:13:54.624Z Progress: resolved 1, reused 0, downloaded 0, added 0 2026-10-14T16:13:54.720Z Packages: +248 2026-10-14T16:13:54.720Z ++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ 2026-10-14T16:13:55.623Z Progress: resolved 248, reused 196, downloaded 0, added 0 2026-10-14T16:13:56.623Z Progress: resolved 248, reused 242, downloaded 0, added 154 2026-10-14T16:13:57.130Z Progress: resolved 248, reused 242, downloaded 0, added 248, done 2026-10-14T16:14:09.714Z 2026-10-14T16:14:09.714Z devDependencies: 2026-10-14T16:14:09.714Z + turbo 2.11.7 2026-10-14T16:14:09.714Z + typescript 5.9.3 2026-10-14T16:14:09.714Z 2026-10-14T16:14:10.052Z Done in 16s using pnpm v10.28.0 2026-10-14T16:14:10.495Z ##[group]Run pnpm -r --if-present build 2026-10-14T16:14:10.495Z ##[endgroup] 2026-10-14T16:14:10.920Z Scope: 6 of 7 workspace projects 2026-10-14T16:14:10.925Z ##[group]@diffinery/cli@0.1.0 : build apps/cli 2026-10-14T16:14:10.927Z 2026-10-14T16:14:10.927Z > @diffinery/cli@0.1.0 build /workspace/diffinery/apps/cli 2026-10-14T16:14:10.927Z > esbuild src/bin.ts --bundle --platform=node --target=node22 --format=esm --outfile=dist/diffinery.mjs "--banner:js=#!/usr/bin/env node 2026-10-14T16:14:10.927Z import { createRequire as __diffineryCreateRequire } from 'node:module'; const require = __diffineryCreateRequire(import.meta.url);" 2026-10-14T16:14:10.927Z 2026-10-14T16:14:11.018Z 2026-10-14T16:14:11.019Z dist/diffinery.mjs 439.5kb 2026-10-14T16:14:11.019Z 2026-10-14T16:14:11.019Z ⚡ Done in 79ms 2026-10-14T16:14:11.022Z ##[endgroup] 2026-10-14T16:14:11.023Z ##[group]@diffinery/app@0.1.0 : build apps/diffinery 2026-10-14T16:14:11.024Z 2026-10-14T16:14:11.024Z > @diffinery/app@0.1.0 build /workspace/diffinery/apps/diffinery 2026-10-14T16:14:11.024Z > node islands/build.mjs && node scripts/build-docs.mjs 2026-10-14T16:14:11.024Z 2026-10-14T16:14:13.676Z islands: kumo.js 466 KB, kumo.css 149 KB, terminal.js 286 KB 2026-10-14T16:14:14.180Z docs: 62 pages (+2 generated at runtime), CLI 0.1.0 (230 KiB) 2026-10-14T16:14:14.194Z ##[endgroup] 2026-10-14T16:14:14.196Z ##[group]@diffinery/runner@0.1.0 : build containers/runner 2026-10-14T16:14:14.196Z 2026-10-14T16:14:14.196Z > @diffinery/runner@0.1.0 build /workspace/diffinery/containers/runner 2026-10-14T16:14:14.196Z > 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-14T16:14:14.196Z 2026-10-14T16:14:14.325Z 2026-10-14T16:14:14.325Z dist/agent.mjs 657.2kb 2026-10-14T16:14:14.325Z 2026-10-14T16:14:14.325Z ⚡ Done in 117ms 2026-10-14T16:14:14.331Z ##[endgroup] 2026-10-14T16:14:15.053Z ##[group]Run # Shards of similar length; a new test file runs in "rest" unless it's added to another. 2026-10-14T16:14:15.053Z core='/e2e\.test\.mjs$' 2026-10-14T16:14:15.053Z agents='/(agentic|review|tasks)\.test\.mjs$' 2026-10-14T16:14:15.053Z security='/(security|status)\.test\.mjs$' 2026-10-14T16:14:15.053Z # The core file's real histories and large pushes take minutes each (jquery's alone 2 to 3 on 2026-10-14T16:14:15.053Z # Diffinery's runners): two shards of their own, so no part of the file runs into node's 2026-10-14T16:14:15.053Z # per-file timeout, which is the --test-timeout here. 2026-10-14T16:14:15.053Z histories='^real histories:' 2026-10-14T16:14:15.053Z large='^(large files|large pushes):' 2026-10-14T16:14:15.053Z opts=(--test-timeout=600000) 2026-10-14T16:14:15.053Z case "histories" in 2026-10-14T16:14:15.053Z core) files=$(ls test/e2e/*.test.mjs | grep -E "$core"); opts+=(--test-skip-pattern="$histories" --test-skip-pattern="$large") ;; 2026-10-14T16:14:15.053Z histories) files=$(ls test/e2e/*.test.mjs | grep -E "$core"); opts=(--test-timeout=900000 --test-name-pattern="$histories") ;; 2026-10-14T16:14:15.053Z large) files=$(ls test/e2e/*.test.mjs | grep -E "$core"); opts+=(--test-name-pattern="$large") ;; 2026-10-14T16:14:15.053Z agents) files=$(ls test/e2e/*.test.mjs | grep -E "$agents") ;; 2026-10-14T16:14:15.053Z security) files=$(ls test/e2e/*.test.mjs | grep -E "$security") ;; 2026-10-14T16:14:15.053Z *) files=$(ls test/e2e/*.test.mjs | grep -vE "$core|$agents|$security") ;; 2026-10-14T16:14:15.053Z esac 2026-10-14T16:14:15.053Z echo "$files" 2026-10-14T16:14:15.053Z node --test --test-concurrency=1 "${opts[@]}" $files 2026-10-14T16:14:15.053Z ##[endgroup] 2026-10-14T16:14:15.076Z test/e2e/e2e.test.mjs 2026-10-14T16:14:15.139Z TAP version 13 2026-10-14T16:16:12.876Z # Subtest: real histories: jquery's whole history goes in one push and clones back intact 2026-10-14T16:16:12.880Z ok 1 - real histories: jquery's whole history goes in one push and clones back intact 2026-10-14T16:16:12.881Z --- 2026-10-14T16:16:12.881Z duration_ms: 109985.638575 2026-10-14T16:16:12.881Z type: 'test' 2026-10-14T16:16:12.881Z ... 2026-10-14T16:17:08.633Z # Subtest: real histories: tornado's whole history goes in one push and clones back intact 2026-10-14T16:17:08.635Z ok 2 - real histories: tornado's whole history goes in one push and clones back intact 2026-10-14T16:17:08.635Z --- 2026-10-14T16:17:08.635Z duration_ms: 56700.892919 2026-10-14T16:17:08.635Z type: 'test' 2026-10-14T16:17:08.635Z ... 2026-10-14T16:18:05.590Z # Subtest: real histories: flask's whole history goes in one push and clones back intact 2026-10-14T16:18:05.590Z ok 3 - real histories: flask's whole history goes in one push and clones back intact 2026-10-14T16:18:05.590Z --- 2026-10-14T16:18:05.590Z duration_ms: 48836.439115 2026-10-14T16:18:05.590Z type: 'test' 2026-10-14T16:18:05.590Z ... 2026-10-14T16:18:34.050Z # Subtest: real histories: a 2.6 MB lockfile's 60 versions, a chain of deltas, go in one small push 2026-10-14T16:18:34.050Z ok 4 - real histories: a 2.6 MB lockfile's 60 versions, a chain of deltas, go in one small push 2026-10-14T16:18:34.059Z --- 2026-10-14T16:18:34.059Z duration_ms: 37776.135708 2026-10-14T16:18:34.059Z type: 'test' 2026-10-14T16:18:34.059Z ... 2026-10-14T16:18:34.106Z 1..4 2026-10-14T16:18:34.107Z # tests 4 2026-10-14T16:18:34.107Z # suites 0 2026-10-14T16:18:34.107Z # pass 4 2026-10-14T16:18:34.107Z # fail 0 2026-10-14T16:18:34.107Z # cancelled 0 2026-10-14T16:18:34.108Z # skipped 0 2026-10-14T16:18:34.108Z # todo 0 2026-10-14T16:18:34.108Z # duration_ms 258988.366977 2026-10-14T16:18:34.526Z Cache hit occurred on the primary key node-cache-Linux-x64-pnpm-4b94436b86798d88b931ea24b5b847697aebf45520d6ef384cb382ce6c1f1e08, not saving cache. 2026-10-14T16:18:35.123Z Pruning is unnecessary. 2026-10-14T16:18:35.504Z Removing persisted credentials 2026-10-14T16:18:35.714Z Job completed with conclusion: success