Running phase: patchPhase
Running phase: updateAutotoolsGnuConfigScriptsPhase
Running phase: checkPhase
initializing postgresql
The files belonging to this database system will be owned by user "nixbld".
This user must also own the server process.

The database cluster will be initialized with locale "C".
The default text search configuration will be set to "english".

Data page checksums are disabled.

creating directory /build/postgresql ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... posix
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... UTC
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok
syncing data to disk ... ok

initdb: warning: enabling "trust" authentication for local connections
initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.

Success. You can now start the database server using:

    pg_ctl -D /build/postgresql -l logfile start

starting postgresql
waiting for server to start....2026-10-27 23:01:07.357 UTC [28] LOG:  starting PostgreSQL 16.15 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit
2026-10-27 23:01:07.358 UTC [28] LOG:  listening on Unix socket "/build/run/postgresql/.s.PGSQL.5432"
2026-10-27 23:01:07.366 UTC [31] LOG:  database system was shut down at 2026-10-27 23:01:06 UTC
2026-10-27 23:01:07.370 UTC [28] LOG:  database system is ready to accept connections
 done
server started
setting up postgresql
CREATE ROLE
CREATE DATABASE
ok: the patch's production code returns abandonMarker as an abandoned attribute's error
psql:/nix/store/xlv03xvmrbn2xabh4msznpa4b2dq5dvh-source/nixbot/nixbot/migrations/0033_effect_runs_catchup.sql:11: NOTICE:  column "owner" of relation "effect_runs" already exists, skipping
psql:/nix/store/xlv03xvmrbn2xabh4msznpa4b2dq5dvh-source/nixbot/nixbot/migrations/0033_effect_runs_catchup.sql:12: NOTICE:  column "lock" of relation "effect_runs" already exists, skipping
psql:/nix/store/xlv03xvmrbn2xabh4msznpa4b2dq5dvh-source/nixbot/nixbot/migrations/0033_effect_runs_catchup.sql:20: NOTICE:  relation "effect_eval_errors" already exists, skipping
ok: abandoned is true only for the ignored_failure row whose error is exactly the marker
ok: no ci_attribute cell holds any error text the fixtures stored
ok: outputs_out is empty for a null outputs
ok: with a cursor 10 min old, the rows tied with the cursor and the rows 4 min old are returned
ok: with a cursor 1 min old, the rows 4 min old are returned and the rows 7 min old are not
ok: reconcile: attributes returns nixbot's (build_id, attr, finished_at) in the window, the start included, as devloop-reconcile
ok: reconcile: builds returns nixbot's (id, finished_at) in the window, the start included and the end excluded, as devloop-reconcile
ok: reconcile: batchBuilds counts the one gitea-mq/batch/ build finished in the window as devloop-reconcile
ok: reconcile: currentFinish returns exactly the stored keys nixbot does not explain, as devloop-reconcile
mutant currentFinishIgnoresDuplicates rejected: FAIL: reconcile: currentFinish returns exactly the stored keys nixbot does not explain, as devloop-reconcile
mutant errorUnderAlias rejected: FAIL: no ci_attribute cell holds any error text the fixtures stored
2026-10-27 23:01:11.271 UTC [615] ERROR:  permission denied for table build_attributes
2026-10-27 23:01:11.271 UTC [615] STATEMENT:  EXECUTE ci_attribute('2026-10-27T22:51:11.000000Z');
mutant grantWithoutError rejected: psql:/nix/store/l93cp743a3swcrmpblzkchp4097wcyy0-devloop-queries-ci_attribute-old.sql:23: ERROR:  permission denied for table build_attributes
2026-10-27 23:01:12.069 UTC [723] ERROR:  permission denied for table build_attributes
2026-10-27 23:01:12.069 UTC [723] STATEMENT:  EXECUTE reconcile_attributes('2026-10-20T00:00:00.000000Z', '2026-10-21T00:00:00.000000Z');
mutant reconcileGrantWithoutFinishedAt rejected: psql:/nix/store/a4q9frhff0z4kw12s0mnqhr0c90913p4-devloop-queries-reconcile_attributes-window.sql:6: ERROR:  permission denied for table build_attributes
2026-10-27 23:01:12.845 UTC [818] ERROR:  column a.cached does not exist at character 242
2026-10-27 23:01:12.845 UTC [818] STATEMENT:  PREPARE ci_attribute(text) AS
	SELECT '' AS "body",
	  coalesce((b.id)::text, '') AS "build_id",
	  coalesce((a.attr)::text, '') AS "attr",
	  coalesce((a.system)::text, '') AS "system",
	  coalesce((a.status)::text, '') AS "status",
	  coalesce((a.cached)::text, '') AS "cached",
	  coalesce(((a.status = 'ignored_failure' AND a.error IS NOT DISTINCT FROM 'not finished when the required attributes had settled'))::text, '') AS "abandoned",
	  coalesce((a.drv_path)::text, '') AS "drv_path",
	  coalesce((a.outputs ->> 'out')::text, '') AS "outputs_out",
	  coalesce(to_char(a.started_at AT TIME ZONE 'UTC', 'YYYY-MM-DD"T"HH24:MI:SS.US"Z"'), '') AS "started_at",
	  coalesce(to_char(a.finished_at AT TIME ZONE 'UTC', 'YYYY-MM-DD"T"HH24:MI:SS.US"Z"'), '') AS "finished_at",
	  coalesce((a.eval_wall_ms)::text, '') AS "eval_wall_ms",
	  coalesce((a.eval_alloc_bytes)::text, '') AS "eval_alloc_bytes",
	  coalesce((b.commit_sha)::text, '') AS "commit_sha",
	  coalesce((b.branch)::text, '') AS "branch",
	  coalesce((b.pr_number)::text, '') AS "pr_number",
	  coalesce((p.owner || '/' || p.name)::text, '') AS "project"
	FROM build_attributes a JOIN builds b ON b.id = a.build_id JOIN projects p ON p.id = b.project_id
	WHERE a.finished_at >= least($1::timestamptz, now() - interval '5 minutes')
	ORDER BY a.finished_at
	LIMIT 10000;
mutant renameCached rejected: psql:/nix/store/l93cp743a3swcrmpblzkchp4097wcyy0-devloop-queries-ci_attribute-old.sql:22: ERROR:  column a.cached does not exist
mutant selectError rejected: FAIL: no ci_attribute cell holds any error text the fixtures stored
stopping postgresql
waiting for server to shut down....2026-10-27 23:01:13.672 UTC [28] LOG:  received fast shutdown request
2026-10-27 23:01:13.673 UTC [28] LOG:  aborting any active transactions
2026-10-27 23:01:13.674 UTC [28] LOG:  background worker "logical replication launcher" (PID 34) exited with exit code 1
2026-10-27 23:01:13.676 UTC [29] LOG:  shutting down
2026-10-27 23:01:13.676 UTC [29] LOG:  checkpoint starting: shutdown immediate
.2026-10-27 23:01:15.477 UTC [29] LOG:  checkpoint complete: wrote 7999 buffers (48.8%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.087 s, sync=1.692 s, total=1.801 s; sync files=3263, longest=0.004 s, average=0.001 s; distance=39042 kB, estimate=39042 kB; lsn=0/3B173E8, redo lsn=0/3B173E8
2026-10-27 23:01:15.498 UTC [28] LOG:  database system is shut down
 done
server stopped
Running phase: fixupPhase
shrinking RPATHs of ELF executables and libraries in /nix/store/n6yp2m1k7vkabghb7yx8qknzlk52c8yy-devloop-queries
checking for references to /build/ in /nix/store/n6yp2m1k7vkabghb7yx8qknzlk52c8yy-devloop-queries...
patching script interpreter paths in /nix/store/n6yp2m1k7vkabghb7yx8qknzlk52c8yy-devloop-queries