these 20 derivations will be built: /nix/store/qshm65kv1b6rsgxbghkk0hcwy1ba7pnq-unit-postgresql.service.drv /nix/store/27nq2gkaf346snayfgh2ww887wk3sgka-system-units.drv /nix/store/52bmiwdi5hn9kcq1i73jzmbwjadmmdis-etc.drv /nix/store/qjqnywww4c77bzq4x0rbh604qlms9c9x-activate.drv /nix/store/0374m29ryz823dkb33nr5iqppngfj436-nixos-system-server-test.drv /nix/store/rkh2x64ai095gma3z5dlx96dhvfcasq1-unit-postgresql.service.drv /nix/store/xgsj18569ayv6072qka7hmj7xq913qbr-system-units.drv /nix/store/fpg8abrjwjkf8jxgywc0sjn04nmf82m7-etc.drv /nix/store/si88d6zqy2wjhdb6n1qigvb8im96n61y-activate.drv /nix/store/8q13hp0syhz7ip89mp1hqp2izk729iz6-unit-postgresql.service.drv /nix/store/8gg2a2jgsn843h2xyy2glawp881502l2-system-units.drv /nix/store/nxsvx1xk3wnc7blz7mxnkhsynpp5hvdh-etc.drv /nix/store/aqj5jqg8pf2hkxz7czwcx34hfpdvy3fa-activate.drv /nix/store/vc56hvhf79svakpv4p37rk2lnbdgw8gi-nixos-system-server-test.drv /nix/store/68kl110zq6jqci995r841hl52k7c3d6b-nixos-system-server-test.drv /nix/store/cwpd0v9hsnihj1bj9hmvkwq4aiysn0xv-closure-info.drv /nix/store/7pifn41zydp38qysrmk58sfn86f5l8sa-run-nixos-vm.drv /nix/store/582akggrs3q1n263bp3c9kpib0nh9j15-nixos-vm.drv /nix/store/ybncp4vj8bvsq4x2bpc0b3hxgp8mwxmy-nixos-test-driver-http.drv /nix/store/hna0s4a3h8nzdlhv5ns4plslsz8nqm4v-vm-test-run-http.drv building '/nix/store/rkh2x64ai095gma3z5dlx96dhvfcasq1-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/8q13hp0syhz7ip89mp1hqp2izk729iz6-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/qshm65kv1b6rsgxbghkk0hcwy1ba7pnq-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/8q13hp0syhz7ip89mp1hqp2izk729iz6-unit-postgresql.service.drv' building '/nix/store/rkh2x64ai095gma3z5dlx96dhvfcasq1-unit-postgresql.service.drv' building '/nix/store/qshm65kv1b6rsgxbghkk0hcwy1ba7pnq-unit-postgresql.service.drv' building '/nix/store/xgsj18569ayv6072qka7hmj7xq913qbr-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/27nq2gkaf346snayfgh2ww887wk3sgka-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/8gg2a2jgsn843h2xyy2glawp881502l2-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/27nq2gkaf346snayfgh2ww887wk3sgka-system-units.drv' building '/nix/store/xgsj18569ayv6072qka7hmj7xq913qbr-system-units.drv' building '/nix/store/8gg2a2jgsn843h2xyy2glawp881502l2-system-units.drv' building '/nix/store/fpg8abrjwjkf8jxgywc0sjn04nmf82m7-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/fpg8abrjwjkf8jxgywc0sjn04nmf82m7-etc.drv' building '/nix/store/nxsvx1xk3wnc7blz7mxnkhsynpp5hvdh-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/nxsvx1xk3wnc7blz7mxnkhsynpp5hvdh-etc.drv' building '/nix/store/aqj5jqg8pf2hkxz7czwcx34hfpdvy3fa-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/vc56hvhf79svakpv4p37rk2lnbdgw8gi-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/vc56hvhf79svakpv4p37rk2lnbdgw8gi-nixos-system-server-test.drv' building '/nix/store/si88d6zqy2wjhdb6n1qigvb8im96n61y-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/52bmiwdi5hn9kcq1i73jzmbwjadmmdis-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/52bmiwdi5hn9kcq1i73jzmbwjadmmdis-etc.drv' building '/nix/store/qjqnywww4c77bzq4x0rbh604qlms9c9x-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/0374m29ryz823dkb33nr5iqppngfj436-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/0374m29ryz823dkb33nr5iqppngfj436-nixos-system-server-test.drv' building '/nix/store/68kl110zq6jqci995r841hl52k7c3d6b-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/68kl110zq6jqci995r841hl52k7c3d6b-nixos-system-server-test.drv' building '/nix/store/cwpd0v9hsnihj1bj9hmvkwq4aiysn0xv-closure-info.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/7pifn41zydp38qysrmk58sfn86f5l8sa-run-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/7pifn41zydp38qysrmk58sfn86f5l8sa-run-nixos-vm.drv' building '/nix/store/582akggrs3q1n263bp3c9kpib0nh9j15-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/ybncp4vj8bvsq4x2bpc0b3hxgp8mwxmy-nixos-test-driver-http.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/hna0s4a3h8nzdlhv5ns4plslsz8nqm4v-vm-test-run-http.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/hna0s4a3h8nzdlhv5ns4plslsz8nqm4v-vm-test-run-http.drv' vm-test-run-http> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-http> start all VLans vm-test-run-http> start vlan vm-test-run-http> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-http> (finished: start all VLans, in 0.00 seconds) vm-test-run-http> Test will time out and terminate in 3600 seconds vm-test-run-http> run the VM test script vm-test-run-http> additionally exposed symbols: vm-test-run-http> server, vm-test-run-http> vlan1, vm-test-run-http> start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug vm-test-run-http> start all VMs vm-test-run-http> server: starting vm vm-test-run-http> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-http> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-http> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-http> qemu-system-aarch64: falling back to tcg vm-test-run-http> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-http> server # Formatting '/build/vm-state-server/tmp.kYkSNY6Vpl', fmt=raw size=1073741824 vm-test-run-http> server # Discarding device blocks: 0/262144 done vm-test-run-http> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-http> server # Filesystem UUID: d8e30196-c62f-434a-8bba-e4653f0f09d9 vm-test-run-http> server # Superblock backups stored on blocks: vm-test-run-http> server: QEMU running (pid 9) vm-test-run-http> server # 32768, 98304, 163840, 229376 vm-test-run-http> server # vm-test-run-http> server # Allocating group tables: 0/8 done vm-test-run-http> server # Writing inode tables: 0/8 done vm-test-run-http> server # Creating journal (8192 blocks): done vm-test-run-http> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-http> server # vm-test-run-http> server # Virtualisation disk image created. vm-test-run-http> (finished: start all VMs, in 0.18 seconds) vm-test-run-http> server: waiting for unit supabase-db-init.service vm-test-run-http> server: waiting for the VM to finish booting vm-test-run-http> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-http> server # [ 0.000000] Linux version 6.12.63 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP Thu Dec 18 12:55:23 UTC 2025 vm-test-run-http> server # [ 0.000000] KASLR enabled vm-test-run-http> server # [ 0.000000] random: crng init done vm-test-run-http> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-http> server # [ 0.000000] efi: UEFI not found. vm-test-run-http> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-http> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-http> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f] vm-test-run-http> server # [ 0.000000] Zone ranges: vm-test-run-http> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-http> server # [ 0.000000] DMA32 empty vm-test-run-http> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-http> server # [ 0.000000] Device empty vm-test-run-http> server # [ 0.000000] Movable zone start for each node vm-test-run-http> server # [ 0.000000] Early memory node ranges vm-test-run-http> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-http> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-http> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1 vm-test-run-http> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-http> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-http> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-http> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-http> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-http> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-http> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-http> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-http> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-http> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-http> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-http> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-http> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-http> server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/g53is3x0c1mybrlfhqf0miwhfqvcpa6z-nixos-system-server-test/init regInfo=/nix/store/drivzxkzi7iq7vgzcqy3licfk72gkssk-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-http> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/drivzxkzi7iq7vgzcqy3licfk72gkssk-closure-info/registration", will be passed to user space. vm-test-run-http> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-http> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-http> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-http> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-http> server # [ 0.000000] Policy zone: Normal vm-test-run-http> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-http> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-http> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-http> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-http> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-http> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-http> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-http> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-http> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-http> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-http> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-http> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-http> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-http> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-http> server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-http> server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-http> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-http> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-http> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-http> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-http> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-http> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-http> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-http> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-http> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ad0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-http> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100ae0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-http> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b00000 vm-test-run-http> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000 vm-test-run-http> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-http> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-http> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-http> server # [ 0.000039] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-http> server # [ 0.003783] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-http> server # [ 0.014522] Console: colour dummy device 80x25 vm-test-run-http> server # [ 0.016166] printk: legacy console [tty0] enabled vm-test-run-http> server # [ 0.021757] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-http> server # [ 0.022153] pid_max: default: 32768 minimum: 301 vm-test-run-http> server # [ 0.024039] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-http> server # [ 0.025821] landlock: Up and running. vm-test-run-http> server # [ 0.025864] Yama: becoming mindful. vm-test-run-http> server # [ 0.029073] LSM support for eBPF active vm-test-run-http> server # [ 0.031908] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-http> server # [ 0.032962] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-http> server # [ 0.063191] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-http> server # [ 0.074218] rcu: Hierarchical SRCU implementation. vm-test-run-http> server # [ 0.074307] rcu: Max phase no-delay instances is 1000. vm-test-run-http> server # [ 0.075705] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-http> server # [ 0.083054] fsl-mc MSI: its@8080000 domain created vm-test-run-http> server # [ 0.085751] EFI services will not be available. vm-test-run-http> server # [ 0.087335] smp: Bringing up secondary CPUs ... vm-test-run-http> server # [ 0.092484] Detected PIPT I-cache on CPU1 vm-test-run-http> server # [ 0.093759] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-http> server # [ 0.094466] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000 vm-test-run-http> server # [ 0.096022] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-http> server # [ 0.099226] smp: Brought up 1 node, 2 CPUs vm-test-run-http> server # [ 0.099368] SMP: Total of 2 processors activated. vm-test-run-http> server # [ 0.099414] CPU: All CPU(s) started at EL1 vm-test-run-http> server # [ 0.099575] CPU features: detected: Branch Target Identification vm-test-run-http> server # [ 0.099627] CPU features: detected: 32-bit EL0 Support vm-test-run-http> server # [ 0.099663] CPU features: detected: 32-bit EL1 Support vm-test-run-http> server # [ 0.099691] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-http> server # [ 0.099724] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-http> server # [ 0.099760] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-http> server # [ 0.099826] CPU features: detected: Common not Private translations vm-test-run-http> server # [ 0.099848] CPU features: detected: CRC32 instructions vm-test-run-http> server # [ 0.099872] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-http> server # [ 0.099903] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-http> server # [ 0.099930] CPU features: detected: Data independent timing control (DIT) vm-test-run-http> server # [ 0.099956] CPU features: detected: E0PD vm-test-run-http> server # [ 0.099978] CPU features: detected: Enhanced Counter Virtualization vm-test-run-http> server # [ 0.100003] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-http> server # [ 0.100031] CPU features: detected: Enhanced Privileged Access Never vm-test-run-http> server # [ 0.100056] CPU features: detected: Enhanced Virtualization Traps vm-test-run-http> server # [ 0.100083] CPU features: detected: Fine Grained Traps vm-test-run-http> server # [ 0.100312] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-http> server # [ 0.100419] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-http> server # [ 0.100472] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-http> server # [ 0.100502] CPU features: detected: LSE atomic instructions vm-test-run-http> server # [ 0.100528] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-http> server # [ 0.100556] CPU features: detected: Privileged Access Never vm-test-run-http> server # [ 0.100582] CPU features: detected: RAS Extension Support vm-test-run-http> server # [ 0.100607] CPU features: detected: Random Number Generator vm-test-run-http> server # [ 0.100631] CPU features: detected: Speculation barrier (SB) vm-test-run-http> server # [ 0.100656] CPU features: detected: Stage-2 Force Write-Back vm-test-run-http> server # [ 0.100679] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-http> server # [ 0.100708] CPU features: detected: TLB range maintenance instructions vm-test-run-http> server # [ 0.100733] CPU features: detected: WFx with timeout vm-test-run-http> server # [ 0.100779] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-http> server # [ 0.100966] CPU features: detected: Scalable Vector Extension vm-test-run-http> server # [ 0.108249] alternatives: applying system-wide alternatives vm-test-run-http> server # [ 0.124917] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-http> server # [ 0.125710] SVE: maximum available vector length 256 bytes per vector vm-test-run-http> server # [ 0.125811] SVE: default vector length 64 bytes per vector vm-test-run-http> server # [ 0.140599] Memory: 3922864K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved) vm-test-run-http> server # [ 0.164376] devtmpfs: initialized vm-test-run-http> server # [ 0.191804] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-http> server # [ 0.192302] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-http> server # [ 0.194764] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-http> server # [ 0.194823] 0 pages in range for non-PLT usage vm-test-run-http> server # [ 0.194865] 509248 pages in range for PLT usage vm-test-run-http> server # [ 0.197329] pinctrl core: initialized pinctrl subsystem vm-test-run-http> server # [ 0.208205] DMI not present or invalid. vm-test-run-http> server # [ 0.227152] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-http> server # [ 0.240120] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-http> server # [ 0.241172] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-http> server # [ 0.242370] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-http> server # [ 0.242651] audit: initializing netlink subsys (disabled) vm-test-run-http> server # [ 0.244942] audit: type=2000 audit(0.180:1): state=initialized audit_enabled=0 res=1 vm-test-run-http> server # [ 0.250852] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-http> server # [ 0.250904] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-http> server # [ 0.250958] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-http> server # [ 0.250988] thermal_sys: Registered thermal governor 'user_space' vm-test-run-http> server # [ 0.251015] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-http> server # [ 0.251688] cpuidle: using governor ladder vm-test-run-http> server # [ 0.252071] cpuidle: using governor menu vm-test-run-http> server # [ 0.254424] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-http> server # [ 0.255187] ASID allocator initialised with 65536 entries vm-test-run-http> server # [ 0.262242] Serial: AMBA PL011 UART driver vm-test-run-http> server # [ 0.313095] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-http> server # [ 0.314984] printk: legacy console [ttyAMA0] enabled vm-test-run-http> server # [ 0.355219] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-http> server # [ 0.355717] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-http> server # [ 0.355852] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-http> server # [ 0.355950] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-http> server # [ 0.356039] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-http> server # [ 0.356139] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-http> server # [ 0.356227] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-http> server # [ 0.356316] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-http> server # [ 0.367910] fbcon: Taking over console vm-test-run-http> server # [ 0.368499] ACPI: Interpreter disabled. vm-test-run-http> server # [ 0.375977] iommu: Default domain type: Translated vm-test-run-http> server # [ 0.376108] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-http> server # [ 0.379028] SCSI subsystem initialized vm-test-run-http> server # [ 0.381280] usbcore: registered new interface driver usbfs vm-test-run-http> server # [ 0.381834] usbcore: registered new interface driver hub vm-test-run-http> server # [ 0.382681] usbcore: registered new device driver usb vm-test-run-http> server # [ 0.385143] pps_core: LinuxPPS API ver. 1 registered vm-test-run-http> server # [ 0.385447] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-http> server # [ 0.385831] PTP clock support registered vm-test-run-http> server # [ 0.386256] EDAC MC: Ver: 3.0.0 vm-test-run-http> server # [ 0.387870] scmi_core: SCMI protocol bus registered vm-test-run-http> server # [ 0.390618] FPGA manager framework vm-test-run-http> server # [ 0.392274] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-http> server # [ 0.399568] vgaarb: loaded vm-test-run-http> server # [ 0.404360] clocksource: Switched to clocksource arch_sys_counter vm-test-run-http> server # [ 0.408486] VFS: Disk quotas dquot_6.6.0 vm-test-run-http> server # [ 0.410036] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-http> server # [ 0.412732] netfs: FS-Cache loaded vm-test-run-http> server # [ 0.414059] pnp: PnP ACPI: disabled vm-test-run-http> server # [ 0.449833] NET: Registered PF_INET protocol family vm-test-run-http> server # [ 0.450942] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-http> server # [ 0.560372] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-http> server # [ 0.561196] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-http> server # [ 0.562185] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-http> server # [ 0.563043] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-http> server # [ 0.564014] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-http> server # [ 0.565848] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-http> server # [ 0.566533] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-http> server # [ 0.566978] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-http> server # [ 0.568483] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-http> server # [ 0.568957] NET: Registered PF_XDP protocol family vm-test-run-http> server # [ 0.569408] PCI: CLS 0 bytes, default 64 vm-test-run-http> server # [ 0.571174] kvm [1]: HYP mode not available vm-test-run-http> server # [ 0.573317] Trying to unpack rootfs image as initramfs... vm-test-run-http> server # [ 0.668688] Initialise system trusted keyrings vm-test-run-http> server # [ 0.670591] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-http> server # [ 0.674347] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-http> server # [ 0.675356] 9p: Installing v9fs 9p2000 file system support vm-test-run-http> server # [ 0.735697] Key type asymmetric registered vm-test-run-http> server # [ 0.736126] Asymmetric key parser 'x509' registered vm-test-run-http> server # [ 0.737765] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-http> server # [ 0.740121] io scheduler mq-deadline registered vm-test-run-http> server # [ 0.740431] io scheduler kyber registered vm-test-run-http> server # [ 0.767001] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-http> server # [ 0.771331] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-http> server # [ 0.774915] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-http> server # [ 0.775588] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-http> server # [ 0.776193] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-http> server # [ 0.776820] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-http> server # [ 0.777370] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-http> server # [ 0.777941] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-http> server # [ 0.779324] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-http> server # [ 0.779684] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-http> server # [ 0.779844] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-http> server # [ 0.779970] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-http> server # [ 0.780135] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-http> server # [ 0.782006] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-http> server # [ 0.785601] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-http> server # [ 0.785942] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-http> server # [ 0.786385] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.786561] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.786717] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-http> server # [ 0.787291] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-http> server # [ 0.787537] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-http> server # [ 0.787641] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.787909] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.789405] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-http> server # [ 0.789785] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-http> server # [ 0.790538] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.790862] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.791724] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-http> server # [ 0.792306] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-http> server # [ 0.792484] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.792607] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.793896] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-http> server # [ 0.794071] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-http> server # [ 0.794178] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.794293] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.794815] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-http> server # [ 0.794973] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-http> server # [ 0.795075] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.795186] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.795657] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-http> server # [ 0.795822] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-http> server # [ 0.795927] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.796044] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.796618] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-http> server # [ 0.797781] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-http> server # [ 0.798102] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.798227] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.798750] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-http> server # [ 0.798919] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.799042] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.799592] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-http> server # [ 0.799740] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.800153] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-http> server # [ 0.800785] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-http> server # [ 0.801333] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.801709] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.802224] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-http> server # [ 0.802375] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-http> server # [ 0.802466] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-http> server # [ 0.802577] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-http> server # [ 0.804646] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-http> server # [ 0.805011] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-http> server # [ 0.805214] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-http> server # [ 0.805447] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-http> server # [ 0.806170] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-http> server # [ 0.806484] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-http> server # [ 0.806644] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-http> server # [ 0.806795] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-http> server # [ 0.807114] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-http> server # [ 0.807268] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-http> server # [ 0.807414] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-http> server # [ 0.807557] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-http> server # [ 0.807718] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-http> server # [ 0.808315] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-http> server # [ 0.809221] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-http> server # [ 0.809931] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-http> server # [ 0.810055] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-http> server # [ 0.810172] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-http> server # [ 0.810288] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-http> server # [ 0.810402] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-http> server # [ 0.810517] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-http> server # [ 0.810695] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-http> server # [ 0.811073] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-http> server # [ 0.811437] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-http> server # [ 0.811559] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-http> server # [ 0.811684] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-http> server # [ 0.811809] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-http> server # [ 0.811914] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-http> server # [ 0.812020] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-http> server # [ 0.812126] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-http> server # [ 0.812230] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-http> server # [ 0.812331] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-http> server # [ 0.812435] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-http> server # [ 0.812538] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-http> server # [ 0.813326] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-http> server # [ 0.813648] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-http> server # [ 0.813767] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-http> server # [ 0.814700] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-http> server # [ 0.874009] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.878532] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.881836] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.886369] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.888271] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.894268] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.897079] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.901798] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-http> server # [ 0.903413] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-http> server # [ 0.907964] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.913284] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-http> server # [ 0.932488] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-http> server # [ 0.941678] msm_serial: driver initialized vm-test-run-http> server # [ 0.943065] SuperH (H)SCI(F) driver initialized vm-test-run-http> server # [ 0.943541] STM32 USART driver initialized vm-test-run-http> server # [ 0.988040] loop: module loaded vm-test-run-http> server # [ 0.989337] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-http> server # [ 0.992842] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-http> server # [ 1.004421] megasas: 07.727.03.00-rc1 vm-test-run-http> server # [ 1.010470] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-http> server # [ 1.013269] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-http> server # [ 1.013901] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-http> server # [ 1.015708] Using buffer write method vm-test-run-http> server # [ 1.016298] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-http> server # [ 1.018900] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-http> server # [ 1.019369] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-http> server # [ 1.021397] Using buffer write method vm-test-run-http> server # [ 1.021777] Concatenating MTD devices: vm-test-run-http> server # [ 1.022161] (0): "0.flash" vm-test-run-http> server # [ 1.022500] (1): "0.flash" vm-test-run-http> server # [ 1.022599] into device "0.flash" vm-test-run-http> server # [ 1.023023] Freeing initrd memory: 10684K vm-test-run-http> server # [ 1.044722] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-http> server # [ 1.059091] thunder_xcv, ver 1.0 vm-test-run-http> server # [ 1.059315] thunder_bgx, ver 1.0 vm-test-run-http> server # [ 1.059520] nicpf, ver 1.0 vm-test-run-http> server # [ 1.063595] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-http> server # [ 1.063795] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-http> server # [ 1.064150] hclge is initializing vm-test-run-http> server # [ 1.065392] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-http> server # [ 1.065921] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-http> server # [ 1.066154] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-http> server # [ 1.066248] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-http> server # [ 1.066757] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-http> server # [ 1.067192] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-http> server # [ 1.067881] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-http> server # [ 1.068149] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-http> server # [ 1.069864] sky2: driver version 1.30 vm-test-run-http> server # [ 1.078336] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-http> server # [ 1.079660] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-http> server # [ 1.080137] usbcore: registered new interface driver usb-storage vm-test-run-http> server # [ 1.081793] usbcore: registered new interface driver usbserial_generic vm-test-run-http> server # [ 1.082096] usbserial: USB Serial support registered for generic vm-test-run-http> server # [ 1.083179] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-http> server # [ 1.091499] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-http> server # [ 1.092241] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T20:37:23 UTC (1791405443) vm-test-run-http> server # [ 1.093692] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-http> server # [ 1.096026] i2c_dev: i2c /dev entries driver vm-test-run-http> server # [ 1.100562] hub 1-0:1.0: USB hub found vm-test-run-http> server # [ 1.101127] hub 1-0:1.0: 6 ports detected vm-test-run-http> server # [ 1.110203] sdhci: Secure Digital Host Controller Interface driver vm-test-run-http> server # [ 1.110762] sdhci: Copyright(c) Pierre Ossman vm-test-run-http> server # [ 1.112830] Synopsys Designware Multimedia Card Interface Driver vm-test-run-http> server # [ 1.115526] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-http> server # [ 1.122880] hid: raw HID events driver (C) Jiri Kosina vm-test-run-http> server # [ 1.125629] usbcore: registered new interface driver usbhid vm-test-run-http> server # [ 1.125785] usbhid: USB HID core driver vm-test-run-http> server # [ 1.134264] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-http> server # [ 1.148062] drop_monitor: Initializing network drop monitor service vm-test-run-http> server # [ 1.150051] NET: Registered PF_INET6 protocol family vm-test-run-http> server # [ 1.168629] Segment Routing with IPv6 vm-test-run-http> server # [ 1.169417] In-situ OAM (IOAM) with IPv6 vm-test-run-http> server # [ 1.170277] NET: Registered PF_PACKET protocol family vm-test-run-http> server # [ 1.171624] 9pnet: Installing 9P2000 support vm-test-run-http> server # [ 1.175387] Key type dns_resolver registered vm-test-run-http> server # [ 1.211791] registered taskstats version 1 vm-test-run-http> server # [ 1.215231] Loading compiled-in X.509 certificates vm-test-run-http> server # [ 1.275342] Demotion targets for Node 0: null vm-test-run-http> server # [ 1.278213] Key type .fscrypt registered vm-test-run-http> server # [ 1.278374] Key type fscrypt-provisioning registered vm-test-run-http> server # [ 1.288670] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-http> server # [ 1.337064] clk: Disabling unused clocks vm-test-run-http> server # [ 1.338066] PM: genpd: Disabling unused power domains vm-test-run-http> server # [ 1.338597] ALSA device list: vm-test-run-http> server # [ 1.338743] No soundcards found. vm-test-run-http> server # [ 1.351001] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-http> server # [ 1.429029] Freeing unused kernel memory: 4416K vm-test-run-http> server # [ 1.430754] Run /init as init process vm-test-run-http> server # [ 1.563237] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 vm-test-run-http> server # [ 1.724243] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 vm-test-run-http> server # [ 1.848691] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-http> server # [ 1.987996] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-http> server # [ 2.042726] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 vm-test-run-http> server # [ 2.045653] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 vm-test-run-http> server # [ 2.127585] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-http> server # [ 2.129037] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-http> server # [ 2.129105] [drm] features: -context_init vm-test-run-http> server # [ 2.131361] [drm] number of scanouts: 1 vm-test-run-http> server # [ 2.131477] [drm] number of cap sets: 0 vm-test-run-http> server # [ 2.143310] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-http> server # [ 2.178703] Console: switching to colour frame buffer device 160x50 vm-test-run-http> server # [ 2.191022] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-http> server # [ 5.037711] EXT4-fs (vda): mounted filesystem d8e30196-c62f-434a-8bba-e4653f0f09d9 r/w with ordered data mode. Quota mode: none. vm-test-run-http> server # [ 7.493421] EXT4-fs (vda): re-mounted d8e30196-c62f-434a-8bba-e4653f0f09d9. vm-test-run-http> server # [ 7.509711] booting system configuration /nix/store/g53is3x0c1mybrlfhqf0miwhfqvcpa6z-nixos-system-server-test vm-test-run-http> server # [ 20.548017] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) vm-test-run-http> server # [ 20.559151] systemd[1]: Detected virtualization qemu. vm-test-run-http> server # [ 20.564836] systemd[1]: Detected architecture arm64. vm-test-run-http> server # [ 20.568408] systemd[1]: Detected first boot. vm-test-run-http> server # [ 20.601843] systemd[1]: Initializing machine ID from random generator. vm-test-run-http> server # [ 20.637911] systemd[1]: Hostname set to . vm-test-run-http> server # [ 21.939080] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-http> server # [ 23.507441] systemd[1]: Applying preset policy. vm-test-run-http> server # [ 27.589394] systemd[1]: Populated /etc with preset unit settings. vm-test-run-http> server: Guest root shell did not produce any data yet... vm-test-run-http> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-http> server # [ 31.448096] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-http> server # [ 31.683240] systemd[1]: Created slice Slice /system/getty. vm-test-run-http> server # [ 31.699143] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-http> server # [ 31.713042] systemd[1]: Created slice User and Session Slice. vm-test-run-http> server # [ 31.721587] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-http> server # [ 31.728013] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-http> server # [ 31.734508] systemd[1]: Expecting device /dev/hvc0... vm-test-run-http> server # [ 31.740138] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-http> server # [ 31.745658] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-http> server # [ 31.750262] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-http> server # [ 31.755059] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-http> server # [ 31.760843] systemd[1]: Reached target Path Units. vm-test-run-http> server # [ 31.767241] systemd[1]: Reached target Remote File Systems. vm-test-run-http> server # [ 31.772088] systemd[1]: Reached target Slice Units. vm-test-run-http> server # [ 31.777891] systemd[1]: Reached target Swaps. vm-test-run-http> server # [ 31.819691] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-http> server # [ 31.845955] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-http> server # [ 31.858545] systemd[1]: Listening on Journal Audit Socket. vm-test-run-http> server # [ 31.864435] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-http> server # [ 31.871537] systemd[1]: Listening on Journal Sockets. vm-test-run-http> server # [ 31.879891] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-http> server # [ 31.886347] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-http> server # [ 31.893103] systemd[1]: Listening on udev Control Socket. vm-test-run-http> server # [ 31.899788] systemd[1]: Listening on udev Kernel Socket. vm-test-run-http> server # [ 31.949865] systemd[1]: Mounting Huge Pages File System... vm-test-run-http> server # [ 31.992116] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-http> server # [ 32.042951] systemd[1]: Mounting Kernel Debug File System... vm-test-run-http> server # [ 32.093868] systemd[1]: Mounting Kernel Trace File System... vm-test-run-http> server # [ 32.239850] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-http> server # [ 32.387693] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-http> server # [ 32.420226] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-http> server # [ 32.541810] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-http> server # [ 32.557906] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-http> server # [ 32.564940] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-http> server # [ 32.663321] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-http> server # [ 32.691997] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-http> server # [ 32.702094] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). vm-test-run-http> server # [ 32.819638] systemd[1]: Starting Journal Service... vm-test-run-http> server # [ 32.902472] systemd[1]: Starting Load Kernel Modules... vm-test-run-http> server # [ 33.010261] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-http> server # [ 33.071297] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-http> server # [ 33.078284] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-http> server # [ 33.160653] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-http> server # [ 33.251688] systemd[1]: Mounted Huge Pages File System. vm-test-run-http> server # [ 33.270742] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-http> server # [ 33.278815] systemd[1]: Mounted Kernel Debug File System. vm-test-run-http> server # [ 33.292099] systemd[1]: Mounted Kernel Trace File System. vm-test-run-http> server # [ 33.303970] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-http> server # [ 33.331682] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-http> server # [ 33.446798] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-http> server # [ 34.114824] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-http> server # [ 34.133017] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-http> server # [ 34.528336] fuse: init (API version 7.41) vm-test-run-http> server # [ 34.567927] EXT4-fs (vda): re-mounted d8e30196-c62f-434a-8bba-e4653f0f09d9. vm-test-run-http> server # [ 34.620801] systemd[1]: Finished Load Kernel Modules. vm-test-run-http> server # [ 34.692272] systemd[1]: Starting Firewall... vm-test-run-http> server # [ 34.754994] systemd[1]: Starting Apply Kernel Variables... vm-test-run-http> server # [ 34.790223] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-http> server # [ 34.801416] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-http> server # [ 34.826975] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-http> server # [ 34.883186] systemd[1]: Mounting FUSE Control File System... vm-test-run-http> server # [ 34.893092] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-http> server # [ 34.983880] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-http> server # [ 34.997922] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-http> server # [ 35.194681] systemd-journald[397]: Collecting audit messages is enabled. vm-test-run-http> server # [ 35.244414] systemd[1]: Mounted FUSE Control File System. vm-test-run-http> server # [ 36.043565] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-http> server # [ 36.132114] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-http> server # [ 36.374307] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-http> server # [ 36.385069] systemd[1]: Reached target First Boot Complete. vm-test-run-http> server # [ 36.690553] systemd[1]: Finished Apply Kernel Variables. vm-test-run-http> server # [ 36.960008] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-http> server # [ 37.743344] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-http> server # [ 37.768455] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-http> server # [ 37.830185] systemd[1]: Mounting /run/wrappers... vm-test-run-http> server # [ 37.895973] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-http> server # [ 38.275322] systemd-modules-load[398]: Module 'atkbd' is built in[ 38.358060] systemd[1]: Started Journal Service. vm-test-run-http> server # vm-test-run-http> server # [ 38.304096] systemd-modules-load[398]: Module 'loop' is built in vm-test-run-http> server # [ 38.334792] systemd-oomd[399]: No swap; memory pressure usage will be degraded vm-test-run-http> server # [ 38.368255] systemd[1]: Mounted /run/wrappers. vm-test-run-http> server # [ 38.384292] systemd[1]: Reached target Local File Systems. vm-test-run-http> server # [ 38.410010] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-http> server # [ 38.471744] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-http> server # [ 38.491711] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-http> server # [ 38.523189] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-http> server # [ 38.566267] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-http> server # [ 39.618308] systemd-journald[397]: Received client request to flush runtime journal. vm-test-run-http> server # [ 40.400291] systemd-udevd[424]: Using default interface naming scheme 'v258'. vm-test-run-http> server # [ 40.467942] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-http> server # [ 40.495956] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-http> server # [ 40.529245] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-http> server # [ 40.543497] systemd[1]: Starting Create System Files and Directories... vm-test-run-http> server # [ 42.575482] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-http> server # [ 42.773634] systemd[1]: Finished Create System Files and Directories. vm-test-run-http> server # [ 42.852871] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-http> server # [ 42.927112] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-http> server # [ 43.863452] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-http> server # [ 45.344433] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-http> server # [ 45.421828] systemd[1]: Starting Update is Completed... vm-test-run-http> server # [ 46.276985] systemd[1]: Finished Update is Completed. vm-test-run-http> server # [ 50.484219] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-http> server # [ 50.497502] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-http> server # [ 50.511422] systemd[1]: suid-sgid-wrappers.service: Consumed 3.285s CPU time, 5.6M memory peak. vm-test-run-http> server # [ 53.906535] systemd[1]: Finished Firewall. vm-test-run-http> server # [ 55.529257] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-http> server # [ 55.538452] systemd[1]: Reached target System Initialization. vm-test-run-http> server # [ 55.539966] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-http> server # [ 55.556382] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-http> server # [ 55.567510] systemd[1]: Reached target Timer Units. vm-test-run-http> server # [ 55.582362] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-http> server # [ 55.589304] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-http> server # [ 55.592062] systemd[1]: Listening on Hostname Service Socket. vm-test-run-http> server # [ 55.601117] systemd[1]: Reached target Socket Units. vm-test-run-http> server # [ 55.606595] systemd[1]: Reached target Basic System. vm-test-run-http> server # [ 55.625766] systemd[1]: Started http-mock-server.service. vm-test-run-http> server # [ 55.674929] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-http> server # [ 55.735993] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-http> server # [ 55.805215] systemd[1]: Started Reset console on configuration changes. vm-test-run-http> server # [ 55.844557] systemd[1]: Starting resolvconf update... vm-test-run-http> server # [ 55.933881] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-http> server # [ 57.498157] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-http> server # [ 57.512031] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-http> server # [ 57.558226] nsncd[633]: Oct 07 20:38:19.921 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-http> server # [ 57.582153] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-http> server # [ 57.603075] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-http> server # [ 57.629162] systemd[1]: Starting User Login Management... vm-test-run-http> server # [ 58.017280] dbus-daemon[636]: dbus[636]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-http> server # [ 58.627340] systemd[1]: Started D-Bus System Message Bus. vm-test-run-http> server: Guest root shell did not produce any data yet... vm-test-run-http> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-http> server # [ 59.753806] systemd-logind[651]: New seat seat0. vm-test-run-http> server # [ 59.817575] systemd[1]: Started User Login Management. vm-test-run-http> server # [ 59.861893] systemd[1]: Starting linger-users.service... vm-test-run-http> server # [ 60.263102] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-http> server # [ 60.299945] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-http> server # [ 60.311329] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-http> server # [ 60.345066] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-http> server # [ 60.353966] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-http> server # [ 60.372122] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-http> server # [ 60.407389] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-http> server # [ 60.540021] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-http> server # [ 60.721689] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-http> server # [ 60.748865] systemd[1]: Finished linger-users.service. vm-test-run-http> server # [ 61.449238] nsncd[695]: Oct 07 20:38:23.897 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-http> server # [ 61.482179] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-http> server # [ 61.493150] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-http> server # [ 61.495043] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-http> server # [ 62.364186] systemd[1]: Finished resolvconf update. vm-test-run-http> server # [ 62.381259] systemd[1]: Reached target Preparation for Network. vm-test-run-http> server # [ 62.455164] systemd[1]: Starting DHCP Client... vm-test-run-http> server # [ 64.466787] dhcpcd[741]: dhcpcd-10.2.4 starting vm-test-run-http> server # [ 64.639852] dhcpcd[750]: dev: loaded udev vm-test-run-http> server # [ 65.189561] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-http> server # [ 65.882778] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-http> server # [ 65.819971] http-mock-server-start[631]: Mock HTTP server running on port 8880 vm-test-run-http> server # [ 65.862653] http-mock-server-start[631]: Port file written to /tmp/http-mock-port vm-test-run-http> server # [ 66.179208] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-http> server # [ 66.188769] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-http> server # [ 66.219786] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-http> server # [ 66.225262] cfg80211: failed to load regulatory.db vm-test-run-http> server # [ 66.942234] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-http> server # [ 66.874777] dhcpcd[750]: eth0: waiting for carrier vm-test-run-http> server # [ 66.879480] dhcpcd[750]: eth0: carrier acquired vm-test-run-http> server # [ 67.063263] dhcpcd[750]: DUID 00:01:00:01:32:59:6a:45:52:54:00:12:34:56 vm-test-run-http> server # [ 67.069801] dhcpcd[750]: eth0: IAID 00:12:34:56 vm-test-run-http> server # [ 67.071103] dhcpcd[750]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-http> server # [ 68.350907] systemd[1]: Found device /dev/hvc0. vm-test-run-http> server # [ 68.707394] dhcpcd[750]: eth0: soliciting a DHCP lease vm-test-run-http> server # [ 68.742459] dhcpcd[750]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-http> server # [ 68.762945] dhcpcd[750]: eth0: probing address 10.0.2.15/24 vm-test-run-http> server # [ 68.885091] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-http> server # [ 68.929128] systemd[1]: Started backdoor.service. vm-test-run-http> server # [ 68.948716] dhcpcd[750]: eth0: soliciting an IPv6 router vm-test-run-http> server # [ 68.965973] dhcpcd[750]: eth0: Router Advertisement from fe80::2 vm-test-run-http> server # [ 68.968311] dhcpcd[750]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-http> server # [ 68.980066] dhcpcd[750]: eth0: adding route to fec0::/64 vm-test-run-http> server # [ 68.988567] dhcpcd[750]: eth0: adding default route via fe80::2 vm-test-run-http> server # connecting to host... vm-test-run-http> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-http> server: connected to guest root shell vm-test-run-http> server: (connecting took 70.78 seconds) vm-test-run-http> server: (finished: waiting for the VM to finish booting, in 70.78 seconds) vm-test-run-http> server # [ 72.210269] (udev-worker)[820]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-http> server # [ 72.217111] (udev-worker)[820]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-http> server # [ 72.260996] (udev-worker)[818]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-http> server # [ 72.754954] dhcpcd[750]: eth0: IAID 00:12:34:56 vm-test-run-http> server # [ 72.807033] systemd[1]: Found device Virtio network device. vm-test-run-http> server # [ 72.831479] systemd[1]: Starting Address configuration of eth1... vm-test-run-http> server # [ 73.402490] dhcpcd[750]: eth0: soliciting an IPv6 router vm-test-run-http> server # [ 73.985765] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-http> server # [ 74.306915] network-addresses-eth1-start[843]: adding address 192.168.1.1/24... done vm-test-run-http> server # [ 74.590763] dhcpcd[750]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-http> server # [ 74.607168] dhcpcd[750]: eth0: adding route to 10.0.2.0/24 vm-test-run-http> server # [ 74.615537] network-addresses-eth1-start[843]: adding address 2001:db8:1::1/64... done vm-test-run-http> server # [ 74.622692] dhcpcd[750]: eth0: adding default route via 10.0.2.2 vm-test-run-http> server # [ 75.037823] systemd[1]: Finished Address configuration of eth1. vm-test-run-http> server # [ 75.101122] systemd[1]: Starting Networking Setup... vm-test-run-http> server # [ 78.310616] dhcpcd[750]: eth0: rebinding lease of 10.0.2.15 vm-test-run-http> server # [ 78.317752] systemd[1]: Started DHCP Client. vm-test-run-http> server # [ 78.320794] dhcpcd[750]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-http> server # [ 81.299861] systemd[1]: Finished Networking Setup. vm-test-run-http> server # [ 81.319763] systemd[1]: Reached target Network. vm-test-run-http> server # [ 81.345813] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-http> server # [ 81.385220] systemd[1]: Starting Permit User Sessions... vm-test-run-http> server # [ 82.045660] systemd[1]: Finished Permit User Sessions. vm-test-run-http> server # [ 82.134442] systemd[1]: Started Getty on tty1. vm-test-run-http> server # [ 82.141455] systemd[1]: Reached target Login Prompts. vm-test-run-http> server # [ 82.978554] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1043]: Initializing database at /var/lib/postgresql/data vm-test-run-http> server # [ 84.275083] systemd-logind[651]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-http> server # [ 84.805317] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The files belonging to this database system will be owned by user "postgres". vm-test-run-http> server # [ 84.811459] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: This user must also own the server process. vm-test-run-http> server # [ 84.902079] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-http> server # [ 84.903493] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The default database encoding has accordingly been set to "UTF8". vm-test-run-http> server # [ 84.910206] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The default text search configuration will be set to "english". vm-test-run-http> server # [ 84.911588] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: Data page checksums are enabled. vm-test-run-http> server # [ 84.912014] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-http> server # [ 84.918069] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: creating subdirectories ... ok vm-test-run-http> server # [ 84.923128] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting dynamic shared memory implementation ... posix vm-test-run-http> server # [ 86.318464] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting default max_connections ... 100 vm-test-run-http> server # [ 87.475525] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting default shared_buffers ... 128MB vm-test-run-http> server # [ 94.449249] mousedev: PS/2 mouse device common for all mice vm-test-run-http> server # [ 99.061902] systemd-logind[651]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-http> server # [ 99.307993] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting default time zone ... UTC vm-test-run-http> server # [ 99.332034] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: creating configuration files ... ok vm-test-run-http> server # [ 105.177811] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: running bootstrap script ... ok vm-test-run-http> server # [ 118.819124] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: performing post-bootstrap initialization ... ok vm-test-run-http> server # [ 122.059410] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: syncing data to disk ... ok vm-test-run-http> server # [ 122.061299] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-http> server # [ 122.063122] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: 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. vm-test-run-http> server # [ 122.066205] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: Success. You can now start the database server using: vm-test-run-http> server # [ 122.070172] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: /nix/store/2bhdbvpk81xy3mkfgqrpc719ip588cba-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-http> server # [ 124.456634] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1043]: Validating PostgreSQL configuration... vm-test-run-http> server # [ 124.950537] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1453]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault vm-test-run-http> server # [ 126.119750] postgres[1455]: 2026-10-07 20:39:28.592 UTC [1455] LOG: pgaudit extension initialized vm-test-run-http> server # [ 127.315936] postgres[1455]: 2026-10-07 20:39:29.787 UTC [1455] LOG: pgsodium primary server secret key loaded vm-test-run-http> server # [ 127.969986] postgres[1455]: 2026-10-07 20:39:30.459 UTC [1455] LOG: vault primary server secret key loaded vm-test-run-http> server # [ 128.517898] postgres[1455]: 2026-10-07 20:39:30.996 UTC [1455] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-http> server # [ 128.522770] postgres[1455]: 2026-10-07 20:39:31.014 UTC [1455] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-http> server # [ 128.532940] postgres[1455]: 2026-10-07 20:39:31.019 UTC [1455] LOG: listening on IPv6 address "::", port 5432 vm-test-run-http> server # [ 128.545959] postgres[1455]: 2026-10-07 20:39:31.035 UTC [1455] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-http> server # [ 128.718738] postgres[1493]: 2026-10-07 20:39:31.198 UTC [1493] LOG: database system was shut down at 2026-10-07 20:39:21 UTC vm-test-run-http> server # [ 129.115837] postgres[1455]: 2026-10-07 20:39:31.606 UTC [1455] LOG: database system is ready to accept connections vm-test-run-http> server # [ 129.191798] systemd[1]: Started PostgreSQL Database Server. vm-test-run-http> server # [ 129.240063] postgres[1502]: 2026-10-07 20:39:31.708 UTC [1502] FATAL: role "postgres" does not exist vm-test-run-http> server # [ 129.260079] postgres[1500]: 2026-10-07 20:39:31.731 UTC [1500] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-http> server # [ 129.262746] systemd[1]: Starting Supabase Database Initialization... vm-test-run-http> server # [ 129.404265] postgres[1455]: 2026-10-07 20:39:31.895 UTC [1455] LOG: background worker "pg_net 0.20.4 worker" (PID 1502) exited with exit code 1 vm-test-run-http> server # [ 129.550129] postgres[1503]: 2026-10-07 20:39:32.033 UTC [1503] LOG: pg_cron scheduler started vm-test-run-http> server # [ 129.731002] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Waiting for PostgreSQL to be ready... vm-test-run-http> server # [ 130.359307] postgres[1511]: ::1 2026-10-07 20:39:32.802 UTC [1511] root@root FATAL: role "root" does not exist vm-test-run-http> server # [ 130.409566] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: PostgreSQL is ready vm-test-run-http> server # [ 130.411192] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Creating postgres role... vm-test-run-http> server # [ 130.506185] postgres[1518]: 2026-10-07 20:39:32.971 UTC [1518] FATAL: role "postgres" does not exist vm-test-run-http> server # [ 130.558058] postgres[1455]: 2026-10-07 20:39:33.034 UTC [1455] LOG: background worker "pg_net 0.20.4 worker" (PID 1518) exited with exit code 1 vm-test-run-http> server # [ 131.113259] postgres[1527]: ::1 2026-10-07 20:39:33.594 UTC [1527] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-http> server # [ 131.245686] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1517]: CREATE ROLE vm-test-run-http> server # [ 131.843013] postgres[1538]: ::1 2026-10-07 20:39:34.305 UTC [1538] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-http> server # [ 131.879616] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1532]: ALTER DATABASE vm-test-run-http> server # [ 131.929610] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-http> server # [ 132.204273] postgres[1541]: ::1 2026-10-07 20:39:34.693 UTC [1541] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-http> server # [ 132.225786] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE PUBLICATION vm-test-run-http> server # [ 132.227930] postgres[1541]: ::1 2026-10-07 20:39:34.711 UTC [1541] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-http> server # [ 132.240011] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE vm-test-run-http> server # [ 132.245674] postgres[1541]: ::1 2026-10-07 20:39:34.726 UTC [1541] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-http> server # [ 132.250003] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 132.253674] postgres[1541]: ::1 2026-10-07 20:39:34.743 UTC [1541] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-http> server # [ 132.266684] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 132.267980] postgres[1541]: ::1 2026-10-07 20:39:34.759 UTC [1541] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-http> server # [ 132.280120] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE vm-test-run-http> server # [ 132.285135] postgres[1541]: ::1 2026-10-07 20:39:34.776 UTC [1541] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-http> server # [ 132.302103] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT vm-test-run-http> server # [ 132.305565] postgres[1541]: ::1 2026-10-07 20:39:34.791 UTC [1541] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-http> server # [ 132.309545] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 132.313021] postgres[1541]: ::1 2026-10-07 20:39:34.803 UTC [1541] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-http> server # [ 132.319678] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE vm-test-run-http> server # [ 132.325695] postgres[1541]: ::1 2026-10-07 20:39:34.815 UTC [1541] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-http> server # [ 132.335853] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE SCHEMA vm-test-run-http> server # [ 132.337615] postgres[1541]: ::1 2026-10-07 20:39:34.828 UTC [1541] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-http> server # [ 135.565734] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE EXTENSION vm-test-run-http> server # [ 135.589301] postgres[1541]: ::1 2026-10-07 20:39:38.053 UTC [1541] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-http> server # [ 136.474159] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE EXTENSION vm-test-run-http> server # [ 136.475403] postgres[1541]: ::1 2026-10-07 20:39:38.967 UTC [1541] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-http> server # [ 136.483159] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 136.485944] postgres[1541]: ::1 2026-10-07 20:39:38.975 UTC [1541] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-http> server # [ 136.496710] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 136.498006] postgres[1541]: ::1 2026-10-07 20:39:38.987 UTC [1541] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-http> server # [ 136.508533] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 136.510423] postgres[1541]: ::1 2026-10-07 20:39:39.000 UTC [1541] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-http> server # [ 136.518782] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE vm-test-run-http> server # [ 136.529822] postgres[1541]: ::1 2026-10-07 20:39:39.019 UTC [1541] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-http> server # [ 136.538198] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE vm-test-run-http> server # [ 136.540201] postgres[1541]: ::1 2026-10-07 20:39:39.031 UTC [1541] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-http> server # [ 136.554913] postgres[1541]: ::1 2026-10-07 20:39:39.042 UTC [1541] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-http> server # [ 136.563679] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE vm-test-run-http> server # [ 136.567815] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE vm-test-run-http> server # [ 136.569236] postgres[1541]: ::1 2026-10-07 20:39:39.055 UTC [1541] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-http> server # [ 136.574879] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE vm-test-run-http> server # [ 136.581822] postgres[1541]: ::1 2026-10-07 20:39:39.067 UTC [1541] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.589597] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT vm-test-run-http> server # [ 136.591323] postgres[1541]: ::1 2026-10-07 20:39:39.082 UTC [1541] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.610322] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 136.615827] postgres[1541]: ::1 2026-10-07 20:39:39.106 UTC [1541] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.634446] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 136.641248] postgres[1541]: ::1 2026-10-07 20:39:39.129 UTC [1541] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.657542] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 136.660230] postgres[1541]: ::1 2026-10-07 20:39:39.148 UTC [1541] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.683610] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT vm-test-run-http> server # [ 136.686220] postgres[1541]: ::1 2026-10-07 20:39:39.168 UTC [1541] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-http> server # [ 136.705009] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE vm-test-run-http> server # [ 136.706806] postgres[1541]: ::1 2026-10-07 20:39:39.198 UTC [1541] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-http> server # [ 136.708035] postgres[1541]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.725933] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 136.733510] postgres[1541]: ::1 2026-10-07 20:39:39.221 UTC [1541] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-http> server # [ 136.735478] postgres[1541]: on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.742574] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 136.745982] postgres[1541]: ::1 2026-10-07 20:39:39.237 UTC [1541] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-http> server # [ 136.751501] postgres[1541]: on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 136.760585] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 136.763567] postgres[1541]: ::1 2026-10-07 20:39:39.255 UTC [1541] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-http> server # [ 136.787564] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE vm-test-run-http> server # [ 136.790248] postgres[1541]: ::1 2026-10-07 20:39:39.279 UTC [1541] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-http> server # [ 136.809617] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE vm-test-run-http> server # [ 136.890429] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-http> server # [ 137.476127] postgres[1580]: ::1 2026-10-07 20:39:39.935 UTC [1580] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-http> server # [ 137.510903] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE SCHEMA vm-test-run-http> server # [ 137.512520] postgres[1580]: ::1 2026-10-07 20:39:39.992 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-http> server # [ 137.521689] postgres[1580]: instance_id uuid NULL, vm-test-run-http> server # [ 137.523281] postgres[1580]: id uuid NOT NULL UNIQUE, vm-test-run-http> server # [ 137.525642] postgres[1580]: aud varchar(255) NULL, vm-test-run-http> server # [ 137.526470] postgres[1580]: "role" varchar(255) NULL, vm-test-run-http> server # [ 137.526768] postgres[1580]: email varchar(255) NULL UNIQUE, vm-test-run-http> server # [ 137.527031] postgres[1580]: encrypted_password varchar(255) NULL, vm-test-run-http> server # [ 137.533298] postgres[1580]: confirmed_at timestamptz NULL, vm-test-run-http> server # [ 137.535870] postgres[1580]: invited_at timestamptz NULL, vm-test-run-http> server # [ 137.538949] postgres[1580]: confirmation_token varchar(255) NULL, vm-test-run-http> server # [ 137.546885] postgres[1580]: confirmation_sent_at timestamptz NULL, vm-test-run-http> server # [ 137.547492] postgres[1580]: recovery_token varchar(255) NULL, vm-test-run-http> server # [ 137.547880] postgres[1580]: recovery_sent_at timestamptz NULL, vm-test-run-http> server # [ 137.548242] postgres[1580]: email_change_token varchar(255) NULL, vm-test-run-http> server # [ 137.548520] postgres[1580]: email_change varchar(255) NULL, vm-test-run-http> server # [ 137.555256] postgres[1580]: email_change_sent_at timestamptz NULL, vm-test-run-http> server # [ 137.555939] postgres[1580]: last_sign_in_at timestamptz NULL, vm-test-run-http> server # [ 137.566067] postgres[1580]: raw_app_meta_data jsonb NULL, vm-test-run-http> server # [ 137.566778] postgres[1580]: raw_user_meta_data jsonb NULL, vm-test-run-http> server # [ 137.567063] postgres[1580]: is_super_admin bool NULL, vm-test-run-http> server # [ 137.567293] postgres[1580]: created_at timestamptz NULL, vm-test-run-http> server # [ 137.567644] postgres[1580]: updated_at timestamptz NULL, vm-test-run-http> server # [ 137.572954] postgres[1580]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-http> server # [ 137.574106] postgres[1580]: ); vm-test-run-http> server # [ 137.853167] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE vm-test-run-http> server # [ 137.854535] postgres[1580]: ::1 2026-10-07 20:39:40.342 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-http> server # [ 137.931970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX vm-test-run-http> server # [ 137.943259] postgres[1580]: ::1 2026-10-07 20:39:40.420 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-http> server # [ 137.994203] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX vm-test-run-http> server # [ 137.995781] postgres[1580]: ::1 2026-10-07 20:39:40.486 UTC [1580] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-http> server # [ 138.010160] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT vm-test-run-http> server # [ 138.011856] postgres[1580]: ::1 2026-10-07 20:39:40.503 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-http> server # [ 138.014242] postgres[1580]: instance_id uuid NULL, vm-test-run-http> server # [ 138.017163] postgres[1580]: id bigserial NOT NULL, vm-test-run-http> server # [ 138.018049] postgres[1580]: "token" varchar(255) NULL, vm-test-run-http> server # [ 138.018624] postgres[1580]: user_id varchar(255) NULL, vm-test-run-http> server # [ 138.018932] postgres[1580]: revoked bool NULL, vm-test-run-http> server # [ 138.019359] postgres[1580]: created_at timestamptz NULL, vm-test-run-http> server # [ 138.019728] postgres[1580]: updated_at timestamptz NULL, vm-test-run-http> server # [ 138.019983] postgres[1580]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-http> server # [ 138.020236] postgres[1580]: ); vm-test-run-http> server # [ 138.187233] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE vm-test-run-http> server # [ 138.193583] postgres[1580]: ::1 2026-10-07 20:39:40.679 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-http> server # [ 138.230521] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX vm-test-run-http> server # [ 138.234475] postgres[1580]: ::1 2026-10-07 20:39:40.725 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id); vm-test-run-http> server # [ 138.266428] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX vm-test-run-http> server # [ 138.270488] postgres[1580]: ::1 2026-10-07 20:39:40.761 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-http> server # [ 138.299403] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX vm-test-run-http> server # [ 138.309565] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT vm-test-run-http> server # [ 138.311044] postgres[1580]: ::1 2026-10-07 20:39:40.793 UTC [1580] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.'; vm-test-run-http> server # [ 138.314754] postgres[1580]: ::1 2026-10-07 20:39:40.805 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-http> server # [ 138.315554] postgres[1580]: id uuid NOT NULL, vm-test-run-http> server # [ 138.319150] postgres[1580]: uuid uuid NULL, vm-test-run-http> server # [ 138.325809] postgres[1580]: raw_base_config text NULL, vm-test-run-http> server # [ 138.326934] postgres[1580]: created_at timestamptz NULL, vm-test-run-http> server # [ 138.327285] postgres[1580]: updated_at timestamptz NULL, vm-test-run-http> server # [ 138.327544] postgres[1580]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-http> server # [ 138.328122] postgres[1580]: ); vm-test-run-http> server # [ 138.378846] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE vm-test-run-http> server # [ 138.381095] postgres[1580]: ::1 2026-10-07 20:39:40.867 UTC [1580] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-http> server # [ 138.388078] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT vm-test-run-http> server # [ 138.393224] postgres[1580]: ::1 2026-10-07 20:39:40.882 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-http> server # [ 138.394575] postgres[1580]: instance_id uuid NULL, vm-test-run-http> server # [ 138.395520] postgres[1580]: id uuid NOT NULL, vm-test-run-http> server # [ 138.396345] postgres[1580]: payload json NULL, vm-test-run-http> server # [ 138.406230] postgres[1580]: created_at timestamptz NULL, vm-test-run-http> server # [ 138.406831] postgres[1580]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-http> server # [ 138.414695] postgres[1580]: ); vm-test-run-http> server # [ 138.478118] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE vm-test-run-http> server # [ 138.491508] postgres[1580]: ::1 2026-10-07 20:39:40.965 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id); vm-test-run-http> server # [ 138.501926] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX vm-test-run-http> server # [ 138.503108] postgres[1580]: ::1 2026-10-07 20:39:40.990 UTC [1580] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-http> server # [ 138.507745] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT vm-test-run-http> server # [ 138.513874] postgres[1580]: ::1 2026-10-07 20:39:41.002 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-http> server # [ 138.515226] postgres[1580]: "version" varchar(255) NOT NULL, vm-test-run-http> server # [ 138.515911] postgres[1580]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-http> server # [ 138.523683] postgres[1580]: ); vm-test-run-http> server # [ 138.548274] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE vm-test-run-http> server # [ 138.551785] postgres[1580]: ::1 2026-10-07 20:39:41.043 UTC [1580] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-http> server # [ 138.558914] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT vm-test-run-http> server # [ 138.579733] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: INSERT 0 7 vm-test-run-http> server # [ 138.581007] postgres[1580]: ::1 2026-10-07 20:39:41.072 UTC [1580] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-http> server # [ 138.581734] postgres[1580]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-http> server # [ 138.583325] postgres[1580]: $$ language sql stable; vm-test-run-http> server # [ 138.592070] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE FUNCTION vm-test-run-http> server # [ 138.596341] postgres[1580]: ::1 2026-10-07 20:39:41.088 UTC [1580] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-http> server # [ 138.597951] postgres[1580]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-http> server # [ 138.598768] postgres[1580]: $$ language sql stable; vm-test-run-http> server # [ 138.611471] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE FUNCTION vm-test-run-http> server # [ 138.615166] postgres[1580]: ::1 2026-10-07 20:39:41.101 UTC [1580] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-http> server # [ 138.618513] postgres[1580]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-http> server # [ 138.621486] postgres[1580]: $$ language sql stable; vm-test-run-http> server # [ 138.624880] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE FUNCTION vm-test-run-http> server # [ 138.630611] postgres[1580]: ::1 2026-10-07 20:39:41.119 UTC [1580] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-http> server # [ 138.636809] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT vm-test-run-http> server # [ 138.639018] postgres[1580]: ::1 2026-10-07 20:39:41.130 UTC [1580] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-http> server # [ 138.648166] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE ROLE vm-test-run-http> server # [ 138.653968] postgres[1580]: ::1 2026-10-07 20:39:41.139 UTC [1580] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-http> server # [ 138.655860] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT vm-test-run-http> server # [ 138.660063] postgres[1580]: ::1 2026-10-07 20:39:41.149 UTC [1580] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-http> server # [ 138.671209] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT vm-test-run-http> server # [ 138.672032] postgres[1580]: ::1 2026-10-07 20:39:41.163 UTC [1580] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-http> server # [ 138.678821] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT vm-test-run-http> server # [ 138.685394] postgres[1580]: ::1 2026-10-07 20:39:41.173 UTC [1580] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-http> server # [ 138.691993] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER ROLE vm-test-run-http> server # [ 138.694655] postgres[1580]: ::1 2026-10-07 20:39:41.185 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 138.703398] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE vm-test-run-http> server # [ 138.708921] postgres[1580]: ::1 2026-10-07 20:39:41.197 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 138.726119] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE vm-test-run-http> server # [ 138.733798] postgres[1580]: ::1 2026-10-07 20:39:41.216 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 138.751851] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE vm-test-run-http> server # [ 138.758778] postgres[1580]: ::1 2026-10-07 20:39:41.245 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 138.770724] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE vm-test-run-http> server # [ 138.776386] postgres[1580]: ::1 2026-10-07 20:39:41.267 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 138.790840] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE vm-test-run-http> server # [ 138.879796] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-http> server # [ 139.307899] postgres[1601]: ::1 2026-10-07 20:39:41.790 UTC [1601] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-http> server # [ 139.330735] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: CREATE SCHEMA vm-test-run-http> server # [ 139.332396] postgres[1601]: ::1 2026-10-07 20:39:41.817 UTC [1601] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-http> server # [ 139.341970] postgres[1601]: ::1 2026-10-07 20:39:41.832 UTC [1601] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-http> server # [ 139.351921] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: CREATE ROLE vm-test-run-http> server # [ 139.359033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: ALTER ROLE vm-test-run-http> server # [ 139.364118] postgres[1601]: ::1 2026-10-07 20:39:41.848 UTC [1601] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-http> server # [ 139.379593] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: GRANT vm-test-run-http> server # [ 139.410975] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: DO vm-test-run-http> server # [ 139.478731] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-http> server # [ 139.875478] postgres[1603]: ::1 2026-10-07 20:39:42.334 UTC [1603] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-http> server # [ 139.889643] postgres[1603]: ::1 2026-10-07 20:39:42.375 UTC [1603] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-http> server # [ 139.909178] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: ALTER ROLE vm-test-run-http> server # [ 139.914388] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: ALTER ROLE vm-test-run-http> server # [ 139.921246] postgres[1603]: ::1 2026-10-07 20:39:42.408 UTC [1603] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-http> server # [ 139.929422] postgres[1603]: RETURNS event_trigger vm-test-run-http> server # [ 139.930536] postgres[1603]: LANGUAGE plpgsql vm-test-run-http> server # [ 139.930856] postgres[1603]: AS $$ vm-test-run-http> server # [ 139.931111] postgres[1603]: DECLARE vm-test-run-http> server # [ 139.931339] postgres[1603]: schema_is_cron bool; vm-test-run-http> server # [ 139.931563] postgres[1603]: BEGIN vm-test-run-http> server # [ 139.931954] postgres[1603]: schema_is_cron = ( vm-test-run-http> server # [ 139.937440] postgres[1603]: SELECT n.nspname = 'cron' vm-test-run-http> server # [ 139.939729] postgres[1603]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 139.947074] postgres[1603]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-http> server # [ 139.950042] postgres[1603]: ON ev.objid = n.oid vm-test-run-http> server # [ 139.959325] postgres[1603]: ); vm-test-run-http> server # [ 139.967613] postgres[1603]: vm-test-run-http> server # [ 139.979764] postgres[1603]: IF schema_is_cron vm-test-run-http> server # [ 139.987286] postgres[1603]: THEN vm-test-run-http> server # [ 139.988241] postgres[1603]: grant usage on schema cron to postgres with grant option; vm-test-run-http> server # [ 139.993923] postgres[1603]: vm-test-run-http> server # [ 139.995070] postgres[1603]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-http> server # [ 140.002597] postgres[1603]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-http> server # [ 140.003939] postgres[1603]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-http> server # [ 140.024872] postgres[1603]: vm-test-run-http> server # [ 140.026717] postgres[1603]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-http> server # [ 140.028322] postgres[1603]: on sequences to postgres with grant option; vm-test-run-http> server # [ 140.029935] postgres[1603]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-http> server # [ 140.030511] postgres[1603]: on tables to postgres with grant option; vm-test-run-http> server # [ 140.030857] postgres[1603]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-http> server # [ 140.031156] postgres[1603]: on functions to postgres with grant option; vm-test-run-http> server # [ 140.032897] postgres[1603]: vm-test-run-http> server # [ 140.034717] postgres[1603]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-http> server # [ 140.036577] postgres[1603]: vm-test-run-http> server # [ 140.036951] postgres[1603]: END IF; vm-test-run-http> server # [ 140.037610] postgres[1603]: vm-test-run-http> server # [ 140.038802] postgres[1603]: END; vm-test-run-http> server # [ 140.039234] postgres[1603]: $$; vm-test-run-http> server # [ 140.040339] postgres[1603]: ::1 2026-10-07 20:39:42.465 UTC [1603] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA') vm-test-run-http> server # [ 140.041606] postgres[1603]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-http> server # [ 140.042842] postgres[1603]: ::1 2026-10-07 20:39:42.506 UTC [1603] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-http> server # [ 140.044067] postgres[1603]: ::1 2026-10-07 20:39:42.531 UTC [1603] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-http> server # [ 140.046244] postgres[1603]: RETURNS event_trigger vm-test-run-http> server # [ 140.046726] postgres[1603]: LANGUAGE plpgsql vm-test-run-http> server # [ 140.046989] postgres[1603]: AS $$ vm-test-run-http> server # [ 140.047215] postgres[1603]: BEGIN vm-test-run-http> server # [ 140.047444] postgres[1603]: IF EXISTS ( vm-test-run-http> server # [ 140.047689] postgres[1603]: SELECT 1 vm-test-run-http> server # [ 140.048056] postgres[1603]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 140.048671] postgres[1603]: JOIN pg_extension AS ext vm-test-run-http> server # [ 140.049176] postgres[1603]: ON ev.objid = ext.oid vm-test-run-http> server # [ 140.049928] postgres[1603]: WHERE ext.extname = 'pg_net' vm-test-run-http> server # [ 140.053219] postgres[1603]: ) vm-test-run-http> server # [ 140.053754] postgres[1603]: THEN vm-test-run-http> server # [ 140.054056] postgres[1603]: IF NOT EXISTS ( vm-test-run-http> server # [ 140.055366] postgres[1603]: SELECT 1 vm-test-run-http> server # [ 140.056322] postgres[1603]: FROM pg_roles vm-test-run-http> server # [ 140.057901] postgres[1603]: WHERE rolname = 'supabase_functions_admin' vm-test-run-http> server # [ 140.058949] postgres[1603]: ) vm-test-run-http> server # [ 140.059356] postgres[1603]: THEN vm-test-run-http> server # [ 140.061142] postgres[1603]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-http> server # [ 140.062722] postgres[1603]: END IF; vm-test-run-http> server # [ 140.063794] postgres[1603]: vm-test-run-http> server # [ 140.064606] postgres[1603]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 140.065849] postgres[1603]: vm-test-run-http> server # [ 140.066327] postgres[1603]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-http> server # [ 140.067325] postgres[1603]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-http> server # [ 140.067949] postgres[1603]: vm-test-run-http> server # [ 140.068239] postgres[1603]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-http> server # [ 140.069156] postgres[1603]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-http> server # [ 140.070020] postgres[1603]: vm-test-run-http> server # [ 140.070469] postgres[1603]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-http> server # [ 140.071213] postgres[1603]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-http> server # [ 140.072206] postgres[1603]: vm-test-run-http> server # [ 140.076937] postgres[1603]: GRANT EXECUTE ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 140.078317] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE FUNCTION vm-test-run-http> server # [ 140.078910] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 140.081206] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: COMMENT vm-test-run-http> server # [ 140.083541] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE FUNCTION vm-test-run-http> server # [ 140.084881] postgres[1603]: GRANT EXECUTE ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 140.086285] postgres[1603]: END IF; vm-test-run-http> server # [ 140.086588] postgres[1603]: END; vm-test-run-http> server # [ 140.086853] postgres[1603]: $$; vm-test-run-http> server # [ 140.087268] postgres[1603]: ::1 2026-10-07 20:39:42.558 UTC [1603] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-http> server # [ 140.089027] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: COMMENT vm-test-run-http> server # [ 140.133278] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: DO vm-test-run-http> server # [ 140.141631] postgres[1603]: ::1 2026-10-07 20:39:42.630 UTC [1603] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-http> server # [ 140.154866] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE ROLE vm-test-run-http> server # [ 140.166971] postgres[1603]: ::1 2026-10-07 20:39:42.653 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-http> server # [ 140.193084] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.218463] postgres[1603]: ::1 2026-10-07 20:39:42.681 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-http> server # [ 140.230278] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.242037] postgres[1603]: ::1 2026-10-07 20:39:42.723 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-http> server # [ 140.256599] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.270713] postgres[1603]: ::1 2026-10-07 20:39:42.744 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-http> server # [ 140.284652] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.289023] postgres[1603]: ::1 2026-10-07 20:39:42.779 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-http> server # [ 140.299922] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.305127] postgres[1603]: ::1 2026-10-07 20:39:42.795 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-http> server # [ 140.333559] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.335581] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.346098] postgres[1603]: ::1 2026-10-07 20:39:42.817 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-http> server # [ 140.353524] postgres[1603]: ::1 2026-10-07 20:39:42.832 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-http> server # [ 140.385726] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.392920] postgres[1603]: ::1 2026-10-07 20:39:42.881 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-http> server # [ 140.473229] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT vm-test-run-http> server # [ 140.515210] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: DO vm-test-run-http> server # [ 140.575102] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running pgbouncer auth schema... vm-test-run-http> server # [ 141.058179] postgres[1626]: ::1 2026-10-07 20:39:43.531 UTC [1626] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-http> server # [ 141.089737] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: CREATE ROLE vm-test-run-http> server # [ 141.090668] postgres[1626]: ::1 2026-10-07 20:39:43.561 UTC [1626] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-http> server # [ 141.091608] postgres[1626]: ::1 2026-10-07 20:39:43.579 UTC [1626] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-http> server # [ 141.101828] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: REVOKE vm-test-run-http> server # [ 141.152163] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: CREATE SCHEMA vm-test-run-http> server # [ 141.155452] postgres[1626]: ::1 2026-10-07 20:39:43.647 UTC [1626] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-http> server # [ 141.156012] postgres[1626]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-http> server # [ 141.166978] postgres[1626]: $$ vm-test-run-http> server # [ 141.167755] postgres[1626]: BEGIN vm-test-run-http> server # [ 141.180125] postgres[1626]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-http> server # [ 141.201160] postgres[1626]: vm-test-run-http> server # [ 141.202653] postgres[1626]: RETURN QUERY vm-test-run-http> server # [ 141.215270] postgres[1626]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-http> server # [ 141.253834] postgres[1626]: WHERE usename = p_usename; vm-test-run-http> server # [ 141.254571] postgres[1626]: END; vm-test-run-http> server # [ 141.255548] postgres[1626]: $$ LANGUAGE plpgsql vm-test-run-http> server # [ 141.255845] postgres[1626]: SET search_path = '' vm-test-run-http> server # [ 141.256275] postgres[1626]: SECURITY DEFINER; vm-test-run-http> server # [ 141.275154] postgres[1626]: ::1 2026-10-07 20:39:43.678 UTC [1626] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-http> server # [ 141.314587] postgres[1626]: ::1 2026-10-07 20:39:43.685 UTC [1626] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-http> server # [ 141.327132] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: CREATE FUNCTION vm-test-run-http> server # [ 141.343696] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: REVOKE vm-test-run-http> server # [ 141.347338] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: GRANT vm-test-run-http> server # [ 141.355230] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running stat extension... vm-test-run-http> server # [ 141.710769] postgres[1631]: ::1 2026-10-07 20:39:44.196 UTC [1631] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-http> server # [ 141.732977] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1630]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-http> server # [ 141.753627] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1630]: CREATE SCHEMA vm-test-run-http> server # [ 141.757763] postgres[1631]: ::1 2026-10-07 20:39:44.248 UTC [1631] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-http> server # [ 143.090430] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1630]: CREATE EXTENSION vm-test-run-http> server # [ 143.189446] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-http> server # [ 143.923235] postgres[1648]: ::1 2026-10-07 20:39:46.389 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-http> server # [ 143.934485] postgres[1648]: ::1 2026-10-07 20:39:46.402 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-http> server # [ 143.935072] postgres[1648]: ::1 2026-10-07 20:39:46.419 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-http> server # [ 143.935914] postgres[1648]: ::1 2026-10-07 20:39:46.426 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-http> server # [ 143.955191] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 143.956164] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 143.970013] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 143.970580] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 143.970902] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 143.972273] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 143.988119] postgres[1648]: ::1 2026-10-07 20:39:46.442 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-http> server # [ 144.001961] postgres[1648]: ::1 2026-10-07 20:39:46.455 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-http> server # [ 144.002856] postgres[1648]: ::1 2026-10-07 20:39:46.466 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-http> server # [ 144.003280] postgres[1648]: ::1 2026-10-07 20:39:46.475 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-http> server # [ 144.003819] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 144.004109] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 144.020120] postgres[1648]: ::1 2026-10-07 20:39:46.493 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-http> server # [ 144.063119] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT vm-test-run-http> server # [ 144.119856] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: DO vm-test-run-http> server # [ 144.125470] postgres[1648]: ::1 2026-10-07 20:39:46.612 UTC [1648] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-http> server # [ 144.133954] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: ALTER ROLE vm-test-run-http> server # [ 144.212251] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-http> server # [ 144.548725] postgres[1659]: ::1 2026-10-07 20:39:47.038 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-http> server # [ 144.571833] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.578112] postgres[1659]: ::1 2026-10-07 20:39:47.052 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-http> server # [ 144.582844] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.588386] postgres[1659]: ::1 2026-10-07 20:39:47.076 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-http> server # [ 144.596884] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.601641] postgres[1659]: ::1 2026-10-07 20:39:47.091 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 144.617657] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE vm-test-run-http> server # [ 144.618701] postgres[1659]: ::1 2026-10-07 20:39:47.107 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 144.641953] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE vm-test-run-http> server # [ 144.643941] postgres[1659]: ::1 2026-10-07 20:39:47.128 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 144.650329] postgres[1659]: ::1 2026-10-07 20:39:47.141 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 144.658179] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE vm-test-run-http> server # [ 144.659116] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE vm-test-run-http> server # [ 144.659618] postgres[1659]: ::1 2026-10-07 20:39:47.151 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-http> server # [ 144.670011] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE vm-test-run-http> server # [ 144.674065] postgres[1659]: ::1 2026-10-07 20:39:47.166 UTC [1659] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-http> server # [ 144.682432] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.686886] postgres[1659]: ::1 2026-10-07 20:39:47.176 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-http> server # [ 144.701559] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.703562] postgres[1659]: ::1 2026-10-07 20:39:47.192 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-http> server # [ 144.713962] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.714959] postgres[1659]: ::1 2026-10-07 20:39:47.202 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-http> server # [ 144.727120] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT vm-test-run-http> server # [ 144.728403] postgres[1659]: ::1 2026-10-07 20:39:47.220 UTC [1659] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user; vm-test-run-http> server # [ 144.745265] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 144.746553] postgres[1659]: ::1 2026-10-07 20:39:47.233 UTC [1659] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user; vm-test-run-http> server # [ 144.762802] postgres[1659]: ::1 2026-10-07 20:39:47.246 UTC [1659] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user; vm-test-run-http> server # [ 144.763998] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 144.765919] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 144.801078] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-http> server # [ 145.107802] postgres[1661]: ::1 2026-10-07 20:39:47.579 UTC [1661] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-http> server # [ 145.143055] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1660]: CREATE SCHEMA vm-test-run-http> server # [ 145.181109] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-http> server # [ 145.604453] postgres[1667]: ::1 2026-10-07 20:39:48.089 UTC [1667] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-http> server # [ 145.628031] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT vm-test-run-http> server # [ 145.635955] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT vm-test-run-http> server # [ 145.643052] postgres[1667]: ::1 2026-10-07 20:39:48.110 UTC [1667] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-http> server # [ 145.659146] postgres[1667]: ::1 2026-10-07 20:39:48.123 UTC [1667] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-http> server # [ 145.666245] postgres[1667]: ::1 2026-10-07 20:39:48.131 UTC [1667] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-http> server # [ 145.680964] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT vm-test-run-http> server # [ 145.690932] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT vm-test-run-http> server # [ 145.735235] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-http> server # [ 146.121235] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1668]: DO vm-test-run-http> server # [ 146.135962] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1668]: DO vm-test-run-http> server # [ 146.151560] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1668]: DO vm-test-run-http> server # [ 146.206016] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-http> server # [ 146.686169] postgres[1677]: ::1 2026-10-07 20:39:49.166 UTC [1677] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user; vm-test-run-http> server # [ 146.720560] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1671]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 146.736986] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1671]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 146.738337] postgres[1677]: ::1 2026-10-07 20:39:49.205 UTC [1677] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user; vm-test-run-http> server # [ 146.745562] postgres[1677]: ::1 2026-10-07 20:39:49.217 UTC [1677] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user; vm-test-run-http> server # [ 146.756363] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1671]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 146.839329] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-http> server # [ 147.381435] postgres[1693]: ::1 2026-10-07 20:39:49.853 UTC [1693] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-http> server # [ 147.429589] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1686]: ALTER ROLE vm-test-run-http> server # [ 147.462540] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-http> server # [ 147.855981] postgres[1699]: ::1 2026-10-07 20:39:50.336 UTC [1699] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-http> server # [ 147.875266] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-http> server # [ 147.885015] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP EVENT TRIGGER vm-test-run-http> server # [ 147.885937] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-http> server # [ 147.887928] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP FUNCTION vm-test-run-http> server # [ 147.901599] postgres[1699]: ::1 2026-10-07 20:39:50.362 UTC [1699] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-http> server # [ 147.902524] postgres[1699]: ::1 2026-10-07 20:39:50.383 UTC [1699] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-http> server # [ 147.903525] postgres[1699]: DECLARE vm-test-run-http> server # [ 147.904172] postgres[1699]: cmd record; vm-test-run-http> server # [ 147.911052] postgres[1699]: BEGIN vm-test-run-http> server # [ 147.911698] postgres[1699]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-http> server # [ 147.921514] postgres[1699]: LOOP vm-test-run-http> server # [ 147.922584] postgres[1699]: IF cmd.command_tag IN ( vm-test-run-http> server # [ 147.922956] postgres[1699]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-http> server # [ 147.923423] postgres[1699]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-http> server # [ 147.937591] postgres[1699]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-http> server # [ 147.938309] postgres[1699]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-http> server # [ 147.938640] postgres[1699]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-http> server # [ 147.939759] postgres[1699]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-http> server # [ 147.940916] postgres[1699]: , 'CREATE TRIGGER' vm-test-run-http> server # [ 147.943371] postgres[1699]: , 'CREATE TYPE' vm-test-run-http> server # [ 147.958897] postgres[1699]: , 'CREATE RULE' vm-test-run-http> server # [ 147.959683] postgres[1699]: , 'COMMENT' vm-test-run-http> server # [ 147.959996] postgres[1699]: ) vm-test-run-http> server # [ 147.964263] postgres[1699]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-http> server # [ 147.974402] postgres[1699]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-http> server # [ 147.975662] postgres[1699]: THEN vm-test-run-http> server # [ 147.976027] postgres[1699]: NOTIFY pgrst, 'reload schema'; vm-test-run-http> server # [ 147.982559] postgres[1699]: END IF; vm-test-run-http> server # [ 147.983503] postgres[1699]: END LOOP; vm-test-run-http> server # [ 147.991240] postgres[1699]: END; $$ LANGUAGE plpgsql; vm-test-run-http> server # [ 147.993930] postgres[1699]: ::1 2026-10-07 20:39:50.410 UTC [1699] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-http> server # [ 148.005393] postgres[1699]: DECLARE vm-test-run-http> server # [ 148.006100] postgres[1699]: obj record; vm-test-run-http> server # [ 148.006399] postgres[1699]: BEGIN vm-test-run-http> server # [ 148.007450] postgres[1699]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-http> server # [ 148.009505] postgres[1699]: LOOP vm-test-run-http> server # [ 148.009905] postgres[1699]: IF obj.object_type IN ( vm-test-run-http> server # [ 148.010175] postgres[1699]: 'schema' vm-test-run-http> server # [ 148.010433] postgres[1699]: , 'table' vm-test-run-http> server # [ 148.010707] postgres[1699]: , 'foreign table' vm-test-run-http> server # [ 148.010965] postgres[1699]: , 'view' vm-test-run-http> server # [ 148.011570] postgres[1699]: , 'materialized view' vm-test-run-http> server # [ 148.011871] postgres[1699]: , 'function' vm-test-run-http> server # [ 148.012109] postgres[1699]: , 'trigger' vm-test-run-http> server # [ 148.012336] postgres[1699]: , 'type' vm-test-run-http> server # [ 148.014255] postgres[1699]: , 'rule' vm-test-run-http> server # [ 148.015947] postgres[1699]: ) vm-test-run-http> server # [ 148.021693] postgres[1699]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-http> server # [ 148.023700] postgres[1699]: THEN vm-test-run-http> server # [ 148.030014] postgres[1699]: NOTIFY pgrst, 'reload schema'; vm-test-run-http> server # [ 148.030800] postgres[1699]: END IF; vm-test-run-http> server # [ 148.038175] postgres[1699]: END LOOP; vm-test-run-http> server # [ 148.039636] postgres[1699]: END; $$ LANGUAGE plpgsql; vm-test-run-http> server # [ 148.041419] postgres[1699]: ::1 2026-10-07 20:39:50.438 UTC [1699] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-http> server # [ 148.054497] postgres[1699]: ::1 2026-10-07 20:39:50.444 UTC [1699] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-http> server # [ 148.062471] postgres[1699]: ON ddl_command_end vm-test-run-http> server # [ 148.068807] postgres[1699]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-http> server # [ 148.070382] postgres[1699]: ::1 2026-10-07 20:39:50.461 UTC [1699] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-http> server # [ 148.074709] postgres[1699]: ::1 2026-10-07 20:39:50.470 UTC [1699] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-http> server # [ 148.076047] postgres[1699]: ON sql_drop vm-test-run-http> server # [ 148.082382] postgres[1699]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-http> server # [ 148.085160] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE FUNCTION vm-test-run-http> server # [ 148.103516] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE FUNCTION vm-test-run-http> server # [ 148.104206] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-http> server # [ 148.128500] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP EVENT TRIGGER vm-test-run-http> server # [ 148.142173] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 148.146864] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-http> server # [ 148.156309] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP EVENT TRIGGER vm-test-run-http> server # [ 148.156931] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 148.164538] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-http> server # [ 149.042073] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1700]: DO vm-test-run-http> server # [ 149.064847] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-http> server # [ 149.555290] postgres[1708]: ::1 2026-10-07 20:39:52.014 UTC [1708] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-http> server # [ 149.631363] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE SCHEMA vm-test-run-http> server # [ 149.639130] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping vm-test-run-http> server # [ 149.646131] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: DROP FUNCTION vm-test-run-http> server # [ 149.647400] postgres[1708]: ::1 2026-10-07 20:39:52.096 UTC [1708] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-http> server # [ 149.660205] postgres[1708]: ::1 2026-10-07 20:39:52.112 UTC [1708] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 149.667382] postgres[1708]: "operationName" text default null, vm-test-run-http> server # [ 149.683504] postgres[1708]: query text default null, vm-test-run-http> server # [ 149.686178] postgres[1708]: variables jsonb default null, vm-test-run-http> server # [ 149.695126] postgres[1708]: extensions jsonb default null vm-test-run-http> server # [ 149.701057] postgres[1708]: ) vm-test-run-http> server # [ 149.707053] postgres[1708]: returns jsonb vm-test-run-http> server # [ 149.727290] postgres[1708]: language plpgsql vm-test-run-http> server # [ 149.730867] postgres[1708]: as $$ vm-test-run-http> server # [ 149.731501] postgres[1708]: DECLARE vm-test-run-http> server # [ 149.731868] postgres[1708]: server_version float; vm-test-run-http> server # [ 149.732157] postgres[1708]: BEGIN vm-test-run-http> server # [ 149.736895] postgres[1708]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-http> server # [ 149.750261] postgres[1708]: vm-test-run-http> server # [ 149.751017] postgres[1708]: IF server_version >= 14 THEN vm-test-run-http> server # [ 149.759299] postgres[1708]: RETURN jsonb_build_object( vm-test-run-http> server # [ 149.766566] postgres[1708]: 'data', null::jsonb, vm-test-run-http> server # [ 149.774947] postgres[1708]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-http> server # [ 149.782905] postgres[1708]: ); vm-test-run-http> server # [ 149.783778] postgres[1708]: ELSE vm-test-run-http> server # [ 149.797146] postgres[1708]: RETURN jsonb_build_object( vm-test-run-http> server # [ 149.798566] postgres[1708]: 'data', null::jsonb, vm-test-run-http> server # [ 149.799148] postgres[1708]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-http> server # [ 149.812392] postgres[1708]: ); vm-test-run-http> server # [ 149.839235] postgres[1708]: END IF; vm-test-run-http> server # [ 149.851448] postgres[1708]: END; vm-test-run-http> server # [ 149.866640] postgres[1708]: $$; vm-test-run-http> server # [ 149.868891] postgres[1708]: ::1 2026-10-07 20:39:52.153 UTC [1708] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.890808] postgres[1708]: ::1 2026-10-07 20:39:52.171 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.902356] postgres[1708]: ::1 2026-10-07 20:39:52.192 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.903894] postgres[1708]: ::1 2026-10-07 20:39:52.209 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.904608] postgres[1708]: ::1 2026-10-07 20:39:52.224 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-http> server # [ 149.919610] postgres[1708]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.930793] postgres[1708]: ::1 2026-10-07 20:39:52.241 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-http> server # [ 149.934300] postgres[1708]: on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.935688] postgres[1708]: ::1 2026-10-07 20:39:52.255 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-http> server # [ 149.942420] postgres[1708]: on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 149.948776] postgres[1708]: ::1 2026-10-07 20:39:52.273 UTC [1708] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-http> server # [ 149.951424] postgres[1708]: RETURNS event_trigger vm-test-run-http> server # [ 149.952169] postgres[1708]: LANGUAGE plpgsql vm-test-run-http> server # [ 149.961794] postgres[1708]: AS $func$ vm-test-run-http> server # [ 149.962767] postgres[1708]: DECLARE vm-test-run-http> server # [ 149.968565] postgres[1708]: func_is_graphql_resolve bool; vm-test-run-http> server # [ 149.970638] postgres[1708]: BEGIN vm-test-run-http> server # [ 149.971144] postgres[1708]: func_is_graphql_resolve = ( vm-test-run-http> server # [ 149.977039] postgres[1708]: SELECT n.proname = 'resolve' vm-test-run-http> server # [ 149.978485] postgres[1708]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 149.979260] postgres[1708]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-http> server # [ 149.979912] postgres[1708]: ON ev.objid = n.oid vm-test-run-http> server # [ 149.986317] postgres[1708]: ); vm-test-run-http> server # [ 149.994110] postgres[1708]: vm-test-run-http> server # [ 150.010307] postgres[1708]: IF func_is_graphql_resolve vm-test-run-http> server # [ 150.013449] postgres[1708]: THEN vm-test-run-http> server # [ 150.015043] postgres[1708]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 150.017419] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE FUNCTION vm-test-run-http> server # [ 150.027929] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: GRANT vm-test-run-http> server # [ 150.038871] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 150.039566] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 150.046722] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 150.047721] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 150.048113] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 150.048534] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 150.051755] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE FUNCTION vm-test-run-http> server # [ 150.056006] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-http> server # [ 150.059433] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: DROP EVENT TRIGGER vm-test-run-http> server # [ 150.062091] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 150.063520] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: COMMENT vm-test-run-http> server # [ 150.064309] postgres[1708]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 150.070333] postgres[1708]: vm-test-run-http> server # [ 150.078288] postgres[1708]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 150.079699] postgres[1708]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 150.088548] postgres[1708]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 150.090944] postgres[1708]: vm-test-run-http> server # [ 150.091802] postgres[1708]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-http> server # [ 150.098316] postgres[1708]: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 150.102369] postgres[1708]: "operationName" text default null, vm-test-run-http> server # [ 150.114064] postgres[1708]: query text default null, vm-test-run-http> server # [ 150.115802] postgres[1708]: variables jsonb default null, vm-test-run-http> server # [ 150.125228] postgres[1708]: extensions jsonb default null vm-test-run-http> server # [ 150.126501] postgres[1708]: ) vm-test-run-http> server # [ 150.126998] postgres[1708]: returns jsonb vm-test-run-http> server # [ 150.127272] postgres[1708]: language sql vm-test-run-http> server # [ 150.127497] postgres[1708]: as $$ vm-test-run-http> server # [ 150.127845] postgres[1708]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-http> server # [ 150.139099] postgres[1708]: $$; vm-test-run-http> server # [ 150.149707] postgres[1708]: vm-test-run-http> server # [ 150.150727] postgres[1708]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 150.151230] postgres[1708]: END IF; vm-test-run-http> server # [ 150.151829] postgres[1708]: vm-test-run-http> server # [ 150.159875] postgres[1708]: END; vm-test-run-http> server # [ 150.160297] postgres[1708]: $func$; vm-test-run-http> server # [ 150.162634] postgres[1708]: ::1 2026-10-07 20:39:52.290 UTC [1708] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-http> server # [ 150.163575] postgres[1708]: ::1 2026-10-07 20:39:52.324 UTC [1708] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION') vm-test-run-http> server # [ 150.176007] postgres[1708]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-http> server # [ 150.179743] postgres[1708]: ::1 2026-10-07 20:39:52.369 UTC [1708] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql'; vm-test-run-http> server # [ 150.188797] postgres[1708]: ::1 2026-10-07 20:39:52.419 UTC [1708] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-http> server # [ 150.191617] postgres[1708]: RETURNS event_trigger vm-test-run-http> server # [ 150.201766] postgres[1708]: LANGUAGE plpgsql vm-test-run-http> server # [ 150.203494] postgres[1708]: AS $func$ vm-test-run-http> server # [ 150.213212] postgres[1708]: DECLARE vm-test-run-http> server # [ 150.215922] postgres[1708]: graphql_is_dropped bool; vm-test-run-http> server # [ 150.235143] postgres[1708]: BEGIN vm-test-run-http> server # [ 150.243636] postgres[1708]: graphql_is_dropped = ( vm-test-run-http> server # [ 150.268245] postgres[1708]: SELECT ev.schema_name = 'graphql_public' vm-test-run-http> server # [ 150.270056] postgres[1708]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-http> server # [ 150.272074] postgres[1708]: WHERE ev.schema_name = 'graphql_public' vm-test-run-http> server # [ 150.280581] postgres[1708]: ); vm-test-run-http> server # [ 150.283595] postgres[1708]: vm-test-run-http> server # [ 150.314447] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE FUNCTION vm-test-run-http> server # [ 150.324436] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-http> server # [ 150.327114] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: DROP EVENT TRIGGER vm-test-run-http> server # [ 150.331445] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 150.338438] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: COMMENT vm-test-run-http> server # [ 150.347584] postgres[1708]: IF graphql_is_dropped vm-test-run-http> server # [ 150.361017] postgres[1708]: THEN vm-test-run-http> server # [ 150.362360] postgres[1708]: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 150.369967] postgres[1708]: "operationName" text default null, vm-test-run-http> server # [ 150.371103] postgres[1708]: query text default null, vm-test-run-http> server # [ 150.371655] postgres[1708]: variables jsonb default null, vm-test-run-http> server # [ 150.373531] postgres[1708]: extensions jsonb default null vm-test-run-http> server # [ 150.373987] postgres[1708]: ) vm-test-run-http> server # [ 150.374306] postgres[1708]: returns jsonb vm-test-run-http> server # [ 150.374788] postgres[1708]: language plpgsql vm-test-run-http> server # [ 150.380221] postgres[1708]: as $$ vm-test-run-http> server # [ 150.386274] postgres[1708]: DECLARE vm-test-run-http> server # [ 150.389168] postgres[1708]: server_version float; vm-test-run-http> server # [ 150.391265] postgres[1708]: BEGIN vm-test-run-http> server # [ 150.393709] postgres[1708]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-http> server # [ 150.394682] postgres[1708]: vm-test-run-http> server # [ 150.395685] postgres[1708]: IF server_version >= 14 THEN vm-test-run-http> server # [ 150.400919] postgres[1708]: RETURN jsonb_build_object( vm-test-run-http> server # [ 150.403594] postgres[1708]: 'data', null::jsonb, vm-test-run-http> server # [ 150.405474] postgres[1708]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-http> server # [ 150.405938] postgres[1708]: ); vm-test-run-http> server # [ 150.406214] postgres[1708]: ELSE vm-test-run-http> server # [ 150.407519] postgres[1708]: RETURN jsonb_build_object( vm-test-run-http> server # [ 150.409265] postgres[1708]: 'data', null::jsonb, vm-test-run-http> server # [ 150.417538] postgres[1708]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-http> server # [ 150.422276] postgres[1708]: ); vm-test-run-http> server # [ 150.422925] postgres[1708]: END IF; vm-test-run-http> server # [ 150.423979] postgres[1708]: END; vm-test-run-http> server # [ 150.429754] postgres[1708]: $$; vm-test-run-http> server # [ 150.430974] postgres[1708]: END IF; vm-test-run-http> server # [ 150.431909] postgres[1708]: vm-test-run-http> server # [ 150.433761] postgres[1708]: END; vm-test-run-http> server # [ 150.436036] postgres[1708]: $func$; vm-test-run-http> server # [ 150.437285] postgres[1708]: ::1 2026-10-07 20:39:52.515 UTC [1708] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-http> server # [ 150.439530] postgres[1708]: ::1 2026-10-07 20:39:52.517 UTC [1708] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION') vm-test-run-http> server # [ 150.446944] postgres[1708]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-http> server # [ 150.447807] postgres[1708]: ::1 2026-10-07 20:39:52.527 UTC [1708] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql'; vm-test-run-http> server # [ 150.464542] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-http> server # [ 150.686275] postgres[1721]: ::1 2026-10-07 20:39:53.159 UTC [1721] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-http> server # [ 150.715751] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-http> server # [ 150.726585] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP EVENT TRIGGER vm-test-run-http> server # [ 150.734927] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-http> server # [ 150.743411] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP FUNCTION vm-test-run-http> server # [ 150.749873] postgres[1721]: ::1 2026-10-07 20:39:53.182 UTC [1721] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-http> server # [ 150.750425] postgres[1721]: ::1 2026-10-07 20:39:53.221 UTC [1721] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-http> server # [ 150.751296] postgres[1721]: DECLARE vm-test-run-http> server # [ 150.752751] postgres[1721]: cmd record; vm-test-run-http> server # [ 150.761772] postgres[1721]: BEGIN vm-test-run-http> server # [ 150.762670] postgres[1721]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-http> server # [ 150.773825] postgres[1721]: LOOP vm-test-run-http> server # [ 150.774654] postgres[1721]: IF cmd.command_tag IN ( vm-test-run-http> server # [ 150.775231] postgres[1721]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-http> server # [ 150.775681] postgres[1721]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-http> server # [ 150.776346] postgres[1721]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-http> server # [ 150.778965] postgres[1721]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-http> server # [ 150.779593] postgres[1721]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-http> server # [ 150.779917] postgres[1721]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-http> server # [ 150.780614] postgres[1721]: , 'CREATE TRIGGER' vm-test-run-http> server # [ 150.781602] postgres[1721]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-http> server # [ 150.782151] postgres[1721]: , 'CREATE RULE' vm-test-run-http> server # [ 150.783217] postgres[1721]: , 'COMMENT' vm-test-run-http> server # [ 150.784121] postgres[1721]: ) vm-test-run-http> server # [ 150.789807] postgres[1721]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-http> server # [ 150.790972] postgres[1721]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-http> server # [ 150.791357] postgres[1721]: THEN vm-test-run-http> server # [ 150.791595] postgres[1721]: NOTIFY pgrst, 'reload schema'; vm-test-run-http> server # [ 150.796815] postgres[1721]: END IF; vm-test-run-http> server # [ 150.797886] postgres[1721]: END LOOP; vm-test-run-http> server # [ 150.798264] postgres[1721]: END; $$ LANGUAGE plpgsql; vm-test-run-http> server # [ 150.821309] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE FUNCTION vm-test-run-http> server # [ 150.824799] postgres[1721]: ::1 2026-10-07 20:39:53.315 UTC [1721] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-http> server # [ 150.825855] postgres[1721]: DECLARE vm-test-run-http> server # [ 150.826217] postgres[1721]: obj record; vm-test-run-http> server # [ 150.826612] postgres[1721]: BEGIN vm-test-run-http> server # [ 150.827427] postgres[1721]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-http> server # [ 150.827772] postgres[1721]: LOOP vm-test-run-http> server # [ 150.828019] postgres[1721]: IF obj.object_type IN ( vm-test-run-http> server # [ 150.833274] postgres[1721]: 'schema' vm-test-run-http> server # [ 150.835591] postgres[1721]: , 'table' vm-test-run-http> server # [ 150.836220] postgres[1721]: , 'foreign table' vm-test-run-http> server # [ 150.841673] postgres[1721]: , 'view' vm-test-run-http> server # [ 150.842410] postgres[1721]: , 'materialized view' vm-test-run-http> server # [ 150.842704] postgres[1721]: , 'function' vm-test-run-http> server # [ 150.843258] postgres[1721]: , 'trigger' vm-test-run-http> server # [ 150.843548] postgres[1721]: , 'type' vm-test-run-http> server # [ 150.843905] postgres[1721]: , 'rule' vm-test-run-http> server # [ 150.844533] postgres[1721]: ) vm-test-run-http> server # [ 150.853938] postgres[1721]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-http> server # [ 150.854812] postgres[1721]: THEN vm-test-run-http> server # [ 150.855303] postgres[1721]: NOTIFY pgrst, 'reload schema'; vm-test-run-http> server # [ 150.855621] postgres[1721]: END IF; vm-test-run-http> server # [ 150.855883] postgres[1721]: END LOOP; vm-test-run-http> server # [ 150.856118] postgres[1721]: END; $$ LANGUAGE plpgsql; vm-test-run-http> server # [ 150.856531] postgres[1721]: ::1 2026-10-07 20:39:53.348 UTC [1721] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-http> server # [ 150.858390] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE FUNCTION vm-test-run-http> server # [ 150.873655] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP EVENT TRIGGER vm-test-run-http> server # [ 150.877484] postgres[1721]: ::1 2026-10-07 20:39:53.366 UTC [1721] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-http> server # [ 150.879571] postgres[1721]: ON ddl_command_end vm-test-run-http> server # [ 150.879965] postgres[1721]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-http> server # [ 150.891076] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 150.892044] postgres[1721]: ::1 2026-10-07 20:39:53.384 UTC [1721] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-http> server # [ 150.916970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP EVENT TRIGGER vm-test-run-http> server # [ 150.918541] postgres[1721]: ::1 2026-10-07 20:39:53.409 UTC [1721] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-http> server # [ 150.919460] postgres[1721]: ON sql_drop vm-test-run-http> server # [ 150.920452] postgres[1721]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-http> server # [ 150.927735] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 150.979955] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-http> server # [ 151.382872] postgres[1731]: ::1 2026-10-07 20:39:53.845 UTC [1731] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-http> server # [ 151.425764] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1729]: ALTER ROLE vm-test-run-http> server # [ 151.489718] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-http> server # [ 151.825966] postgres[1737]: ::1 2026-10-07 20:39:54.302 UTC [1737] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-http> server # [ 151.827054] postgres[1737]: returns event_trigger vm-test-run-http> server # [ 151.827751] postgres[1737]: language plpgsql vm-test-run-http> server # [ 151.828080] postgres[1737]: AS $func$ vm-test-run-http> server # [ 151.832765] postgres[1737]: DECLARE vm-test-run-http> server # [ 151.843713] postgres[1737]: func_is_graphql_resolve bool; vm-test-run-http> server # [ 151.845888] postgres[1737]: BEGIN vm-test-run-http> server # [ 151.846710] postgres[1737]: func_is_graphql_resolve = ( vm-test-run-http> server # [ 151.847047] postgres[1737]: SELECT n.proname = 'resolve' vm-test-run-http> server # [ 151.847429] postgres[1737]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 151.848040] postgres[1737]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-http> server # [ 151.849062] postgres[1737]: ON ev.objid = n.oid vm-test-run-http> server # [ 151.857593] postgres[1737]: ); vm-test-run-http> server # [ 151.858262] postgres[1737]: vm-test-run-http> server # [ 151.859203] postgres[1737]: IF func_is_graphql_resolve vm-test-run-http> server # [ 151.859800] postgres[1737]: THEN vm-test-run-http> server # [ 151.860363] postgres[1737]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 151.860712] postgres[1737]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 151.866466] postgres[1737]: vm-test-run-http> server # [ 151.868067] postgres[1737]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 151.878880] postgres[1737]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 151.887378] postgres[1737]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 151.889584] postgres[1737]: vm-test-run-http> server # [ 151.890093] postgres[1737]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-http> server # [ 151.891126] postgres[1737]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-http> server # [ 151.891842] postgres[1737]: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 151.900621] postgres[1737]: "operationName" text default null, vm-test-run-http> server # [ 151.901291] postgres[1737]: query text default null, vm-test-run-http> server # [ 151.902117] postgres[1737]: variables jsonb default null, vm-test-run-http> server # [ 151.902854] postgres[1737]: extensions jsonb default null vm-test-run-http> server # [ 151.903596] postgres[1737]: ) vm-test-run-http> server # [ 151.904172] postgres[1737]: returns jsonb vm-test-run-http> server # [ 151.917016] postgres[1737]: language sql vm-test-run-http> server # [ 151.917641] postgres[1737]: as $$ vm-test-run-http> server # [ 151.918376] postgres[1737]: -- This changed vm-test-run-http> server # [ 151.924188] postgres[1737]: select graphql.resolve( vm-test-run-http> server # [ 151.935171] postgres[1737]: query := query, vm-test-run-http> server # [ 151.938077] postgres[1737]: variables := coalesce(variables, '{}'), vm-test-run-http> server # [ 151.939219] postgres[1737]: "operationName" := "operationName", vm-test-run-http> server # [ 151.942376] postgres[1737]: extensions := extensions vm-test-run-http> server # [ 151.949412] postgres[1737]: ); vm-test-run-http> server # [ 151.950165] postgres[1737]: $$; vm-test-run-http> server # [ 151.950697] postgres[1737]: vm-test-run-http> server # [ 151.951352] postgres[1737]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 151.957630] postgres[1737]: END IF; vm-test-run-http> server # [ 151.962602] postgres[1737]: vm-test-run-http> server # [ 151.962991] postgres[1737]: END; vm-test-run-http> server # [ 151.963597] postgres[1737]: $func$; vm-test-run-http> server # [ 151.964050] postgres[1737]: ::1 2026-10-07 20:39:54.437 UTC [1737] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-http> server # [ 151.973835] postgres[1737]: RETURNS event_trigger vm-test-run-http> server # [ 151.974452] postgres[1737]: LANGUAGE plpgsql vm-test-run-http> server # [ 151.975353] postgres[1737]: AS $func$ vm-test-run-http> server # [ 151.975656] postgres[1737]: DECLARE vm-test-run-http> server # [ 151.976904] postgres[1737]: graphql_is_dropped bool; vm-test-run-http> server # [ 151.977594] postgres[1737]: BEGIN vm-test-run-http> server # [ 151.977926] postgres[1737]: graphql_is_dropped = ( vm-test-run-http> server # [ 151.978580] postgres[1737]: SELECT ev.schema_name = 'graphql_public' vm-test-run-http> server # [ 151.980359] postgres[1737]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-http> server # [ 151.982003] postgres[1737]: WHERE ev.schema_name = 'graphql_public' vm-test-run-http> server # [ 151.982564] postgres[1737]: ); vm-test-run-http> server # [ 151.983248] postgres[1737]: vm-test-run-http> server # [ 151.983594] postgres[1737]: IF graphql_is_dropped vm-test-run-http> server # [ 151.984044] postgres[1737]: THEN vm-test-run-http> server # [ 151.984291] postgres[1737]: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 151.986908] postgres[1737]: "operationName" text default null, vm-test-run-http> server # [ 151.987479] postgres[1737]: query text default null, vm-test-run-http> server # [ 151.988936] postgres[1737]: variables jsonb default null, vm-test-run-http> server # [ 151.989822] postgres[1737]: extensions jsonb default null vm-test-run-http> server # [ 151.990988] postgres[1737]: ) vm-test-run-http> server # [ 151.991486] postgres[1737]: returns jsonb vm-test-run-http> server # [ 151.993162] postgres[1737]: language plpgsql vm-test-run-http> server # [ 151.995079] postgres[1737]: as $$ vm-test-run-http> server # [ 151.995473] postgres[1737]: DECLARE vm-test-run-http> server # [ 151.995732] postgres[1737]: server_version float; vm-test-run-http> server # [ 151.995987] postgres[1737]: BEGIN vm-test-run-http> server # [ 151.997638] postgres[1737]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-http> server # [ 151.998199] postgres[1737]: vm-test-run-http> server # [ 151.998739] postgres[1737]: IF server_version >= 14 THEN vm-test-run-http> server # [ 151.999548] postgres[1737]: RETURN jsonb_build_object( vm-test-run-http> server # [ 152.000431] postgres[1737]: 'errors', jsonb_build_array( vm-test-run-http> server # [ 152.002394] postgres[1737]: jsonb_build_object( vm-test-run-http> server # [ 152.002964] postgres[1737]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-http> server # [ 152.004567] postgres[1737]: ) vm-test-run-http> server # [ 152.005066] postgres[1737]: ) vm-test-run-http> server # [ 152.005472] postgres[1737]: ); vm-test-run-http> server # [ 152.006121] postgres[1737]: ELSE vm-test-run-http> server # [ 152.006706] postgres[1737]: RETURN jsonb_build_object( vm-test-run-http> server # [ 152.008270] postgres[1737]: 'errors', jsonb_build_array( vm-test-run-http> server # [ 152.011347] postgres[1737]: jsonb_build_object( vm-test-run-http> server # [ 152.012954] postgres[1737]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-http> server # [ 152.018712] postgres[1737]: ) vm-test-run-http> server # [ 152.019474] postgres[1737]: ) vm-test-run-http> server # [ 152.021776] postgres[1737]: ); vm-test-run-http> server # [ 152.026959] postgres[1737]: END IF; vm-test-run-http> server # [ 152.027692] postgres[1737]: END; vm-test-run-http> server # [ 152.028027] postgres[1737]: $$; vm-test-run-http> server # [ 152.034207] postgres[1737]: END IF; vm-test-run-http> server # [ 152.034943] postgres[1737]: vm-test-run-http> server # [ 152.035212] postgres[1737]: END; vm-test-run-http> server # [ 152.035580] postgres[1737]: $func$; vm-test-run-http> server # [ 152.043495] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: CREATE FUNCTION vm-test-run-http> server # [ 152.051127] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: CREATE FUNCTION vm-test-run-http> server # [ 152.059576] postgres[1737]: ::1 2026-10-07 20:39:54.519 UTC [1737] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 152.065573] postgres[1737]: "operationName" text default null, vm-test-run-http> server # [ 152.066269] postgres[1737]: query text default null, vm-test-run-http> server # [ 152.067385] postgres[1737]: variables jsonb default null, vm-test-run-http> server # [ 152.067843] postgres[1737]: extensions jsonb default null vm-test-run-http> server # [ 152.072073] postgres[1737]: ) vm-test-run-http> server # [ 152.073528] postgres[1737]: returns jsonb vm-test-run-http> server # [ 152.073907] postgres[1737]: language plpgsql vm-test-run-http> server # [ 152.074601] postgres[1737]: as $$ vm-test-run-http> server # [ 152.075069] postgres[1737]: DECLARE vm-test-run-http> server # [ 152.075344] postgres[1737]: server_version float; vm-test-run-http> server # [ 152.075911] postgres[1737]: BEGIN vm-test-run-http> server # [ 152.076185] postgres[1737]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-http> server # [ 152.077306] postgres[1737]: vm-test-run-http> server # [ 152.078107] postgres[1737]: IF server_version >= 14 THEN vm-test-run-http> server # [ 152.078555] postgres[1737]: RETURN jsonb_build_object( vm-test-run-http> server # [ 152.079168] postgres[1737]: 'errors', jsonb_build_array( vm-test-run-http> server # [ 152.079656] postgres[1737]: jsonb_build_object( vm-test-run-http> server # [ 152.082954] postgres[1737]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-http> server # [ 152.083528] postgres[1737]: ) vm-test-run-http> server # [ 152.083834] postgres[1737]: ) vm-test-run-http> server # [ 152.084416] postgres[1737]: ); vm-test-run-http> server # [ 152.084714] postgres[1737]: ELSE vm-test-run-http> server # [ 152.089038] postgres[1737]: RETURN jsonb_build_object( vm-test-run-http> server # [ 152.089905] postgres[1737]: 'errors', jsonb_build_array( vm-test-run-http> server # [ 152.090615] postgres[1737]: jsonb_build_object( vm-test-run-http> server # [ 152.098256] postgres[1737]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-http> server # [ 152.099509] postgres[1737]: ) vm-test-run-http> server # [ 152.099931] postgres[1737]: ) vm-test-run-http> server # [ 152.100194] postgres[1737]: ); vm-test-run-http> server # [ 152.100443] postgres[1737]: END IF; vm-test-run-http> server # [ 152.100677] postgres[1737]: END; vm-test-run-http> server # [ 152.106764] postgres[1737]: $$; vm-test-run-http> server # [ 152.115088] postgres[1737]: ::1 2026-10-07 20:39:54.579 UTC [1737] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-http> server # [ 152.121969] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: CREATE FUNCTION vm-test-run-http> server # [ 152.122562] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-http> server # [ 152.123834] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: DROP EXTENSION vm-test-run-http> server # [ 154.377952] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: DO vm-test-run-http> server # [ 154.390082] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-http> server # [ 154.754849] postgres[1763]: ::1 2026-10-07 20:39:57.234 UTC [1763] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-http> server # [ 154.772050] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1762]: GRANT ROLE vm-test-run-http> server # [ 154.812961] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-http> server # [ 155.070420] postgres[1765]: ::1 2026-10-07 20:39:57.554 UTC [1765] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-http> server # [ 155.075734] postgres[1765]: returns event_trigger vm-test-run-http> server # [ 155.081242] postgres[1765]: language plpgsql vm-test-run-http> server # [ 155.082170] postgres[1765]: AS $func$ vm-test-run-http> server # [ 155.082508] postgres[1765]: DECLARE vm-test-run-http> server # [ 155.082747] postgres[1765]: func_is_graphql_resolve bool; vm-test-run-http> server # [ 155.082997] postgres[1765]: BEGIN vm-test-run-http> server # [ 155.083222] postgres[1765]: func_is_graphql_resolve = ( vm-test-run-http> server # [ 155.083625] postgres[1765]: SELECT n.proname = 'resolve' vm-test-run-http> server # [ 155.089225] postgres[1765]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 155.090158] postgres[1765]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-http> server # [ 155.090913] postgres[1765]: ON ev.objid = n.oid vm-test-run-http> server # [ 155.091238] postgres[1765]: ); vm-test-run-http> server # [ 155.093096] postgres[1765]: vm-test-run-http> server # [ 155.093490] postgres[1765]: IF func_is_graphql_resolve vm-test-run-http> server # [ 155.093755] postgres[1765]: THEN vm-test-run-http> server # [ 155.094098] postgres[1765]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-http> server # [ 155.098899] postgres[1765]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-http> server # [ 155.099537] postgres[1765]: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 155.101939] postgres[1765]: "operationName" text default null, vm-test-run-http> server # [ 155.103058] postgres[1765]: query text default null, vm-test-run-http> server # [ 155.103645] postgres[1765]: variables jsonb default null, vm-test-run-http> server # [ 155.104231] postgres[1765]: extensions jsonb default null vm-test-run-http> server # [ 155.105550] postgres[1765]: ) vm-test-run-http> server # [ 155.106502] postgres[1765]: returns jsonb vm-test-run-http> server # [ 155.106830] postgres[1765]: language sql vm-test-run-http> server # [ 155.107098] postgres[1765]: as $$ vm-test-run-http> server # [ 155.107607] postgres[1765]: select graphql.resolve( vm-test-run-http> server # [ 155.108008] postgres[1765]: query := query, vm-test-run-http> server # [ 155.108824] postgres[1765]: variables := coalesce(variables, '{}'), vm-test-run-http> server # [ 155.109884] postgres[1765]: "operationName" := "operationName", vm-test-run-http> server # [ 155.110751] postgres[1765]: extensions := extensions vm-test-run-http> server # [ 155.111991] postgres[1765]: ); vm-test-run-http> server # [ 155.113698] postgres[1765]: $$; vm-test-run-http> server # [ 155.114086] postgres[1765]: vm-test-run-http> server # [ 155.114311] postgres[1765]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-http> server # [ 155.115167] postgres[1765]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-http> server # [ 155.115882] postgres[1765]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-http> server # [ 155.116389] postgres[1765]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.118096] postgres[1765]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.119064] postgres[1765]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.120174] postgres[1765]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.129851] postgres[1765]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.131118] postgres[1765]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.132587] postgres[1765]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 155.134269] postgres[1765]: END IF; vm-test-run-http> server # [ 155.135225] postgres[1765]: vm-test-run-http> server # [ 155.135932] postgres[1765]: END; vm-test-run-http> server # [ 155.136436] postgres[1765]: $func$; vm-test-run-http> server # [ 155.165894] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1764]: CREATE FUNCTION vm-test-run-http> server # [ 155.167292] postgres[1765]: ::1 2026-10-07 20:39:57.654 UTC [1765] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-http> server # [ 155.207760] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1764]: DROP EXTENSION vm-test-run-http> server # [ 157.467299] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1764]: DO vm-test-run-http> server # [ 157.507156] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-http> server # [ 158.719052] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1790]: DO vm-test-run-http> server # [ 159.390840] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1790]: DO vm-test-run-http> server # [ 159.462033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-http> server # [ 160.008969] postgres[1804]: ::1 2026-10-07 20:40:02.475 UTC [1804] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-http> server # [ 160.040184] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1796]: ALTER ROLE vm-test-run-http> server # [ 160.082837] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-http> server # [ 160.551669] postgres[1814]: ::1 2026-10-07 20:40:03.015 UTC [1814] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-http> server # [ 160.594669] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1809]: REVOKE ROLE vm-test-run-http> server # [ 160.619519] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-http> server # [ 163.314665] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1816]: DO vm-test-run-http> server # [ 163.354947] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-http> server # [ 163.731647] postgres[1847]: ::1 2026-10-07 20:40:06.213 UTC [1847] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-http> server # [ 163.753438] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1842]: GRANT ROLE vm-test-run-http> server # [ 163.808279] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-http> server # [ 164.303630] postgres[1857]: ::1 2026-10-07 20:40:06.783 UTC [1857] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-http> server # [ 164.359212] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: GRANT vm-test-run-http> server # [ 164.369921] postgres[1857]: ::1 2026-10-07 20:40:06.848 UTC [1857] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-http> server # [ 164.404028] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: GRANT vm-test-run-http> server # [ 164.409066] postgres[1857]: ::1 2026-10-07 20:40:06.898 UTC [1857] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-http> server # [ 164.416697] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: GRANT vm-test-run-http> server # [ 164.420908] postgres[1857]: ::1 2026-10-07 20:40:06.911 UTC [1857] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option; vm-test-run-http> server # [ 164.435142] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 164.436026] postgres[1857]: ::1 2026-10-07 20:40:06.927 UTC [1857] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option; vm-test-run-http> server # [ 164.451536] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 164.455945] postgres[1857]: ::1 2026-10-07 20:40:06.946 UTC [1857] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option; vm-test-run-http> server # [ 164.471164] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: ALTER DEFAULT PRIVILEGES vm-test-run-http> server # [ 164.518475] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-http> server # [ 164.871837] postgres[1859]: ::1 2026-10-07 20:40:07.359 UTC [1859] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-http> server # [ 164.910627] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1858]: GRANT ROLE vm-test-run-http> server # [ 164.963107] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-http> server # [ 165.242764] postgres[1861]: ::1 2026-10-07 20:40:07.730 UTC [1861] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-http> server # [ 165.252636] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1860]: GRANT ROLE vm-test-run-http> server # [ 165.292971] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-http> server # [ 165.575411] postgres[1863]: ::1 2026-10-07 20:40:08.055 UTC [1863] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-http> server # [ 165.588074] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: ALTER ROLE vm-test-run-http> server # [ 165.594006] postgres[1863]: ::1 2026-10-07 20:40:08.071 UTC [1863] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-http> server # [ 165.595500] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: ALTER ROLE vm-test-run-http> server # [ 165.598060] postgres[1863]: ::1 2026-10-07 20:40:08.089 UTC [1863] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-http> server # [ 165.609996] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: ALTER ROLE vm-test-run-http> server # [ 165.650562] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: DO vm-test-run-http> server # [ 165.727692] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-http> server # [ 166.210324] postgres[1869]: ::1 2026-10-07 20:40:08.699 UTC [1869] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-http> server # [ 166.254534] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1866]: GRANT ROLE vm-test-run-http> server # [ 166.255808] postgres[1869]: ::1 2026-10-07 20:40:08.720 UTC [1869] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-http> server # [ 166.279043] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1866]: REVOKE ROLE vm-test-run-http> server # [ 166.336026] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-http> server # [ 166.805084] postgres[1880]: ::1 2026-10-07 20:40:09.288 UTC [1880] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-http> server # [ 166.806319] postgres[1880]: returns event_trigger vm-test-run-http> server # [ 166.808225] postgres[1880]: language plpgsql vm-test-run-http> server # [ 166.808671] postgres[1880]: AS $func$ vm-test-run-http> server # [ 166.814999] postgres[1880]: DECLARE vm-test-run-http> server # [ 166.820704] postgres[1880]: func_is_graphql_resolve bool; vm-test-run-http> server # [ 166.823276] postgres[1880]: BEGIN vm-test-run-http> server # [ 166.823955] postgres[1880]: func_is_graphql_resolve = ( vm-test-run-http> server # [ 166.828801] postgres[1880]: SELECT n.proname = 'resolve' vm-test-run-http> server # [ 166.829888] postgres[1880]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 166.831763] postgres[1880]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-http> server # [ 166.841022] postgres[1880]: ON ev.objid = n.oid vm-test-run-http> server # [ 166.841699] postgres[1880]: ); vm-test-run-http> server # [ 166.842571] postgres[1880]: vm-test-run-http> server # [ 166.843544] postgres[1880]: IF func_is_graphql_resolve vm-test-run-http> server # [ 166.849997] postgres[1880]: THEN vm-test-run-http> server # [ 166.857227] postgres[1880]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-http> server # [ 166.859327] postgres[1880]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-http> server # [ 166.860185] postgres[1880]: create or replace function graphql_public.graphql( vm-test-run-http> server # [ 166.867882] postgres[1880]: "operationName" text default null, vm-test-run-http> server # [ 166.868749] postgres[1880]: query text default null, vm-test-run-http> server # [ 166.869529] postgres[1880]: variables jsonb default null, vm-test-run-http> server # [ 166.870167] postgres[1880]: extensions jsonb default null vm-test-run-http> server # [ 166.870852] postgres[1880]: ) vm-test-run-http> server # [ 166.871418] postgres[1880]: returns jsonb vm-test-run-http> server # [ 166.872529] postgres[1880]: language sql vm-test-run-http> server # [ 166.873215] postgres[1880]: as $$ vm-test-run-http> server # [ 166.873625] postgres[1880]: select graphql.resolve( vm-test-run-http> server # [ 166.874273] postgres[1880]: query := query, vm-test-run-http> server # [ 166.875227] postgres[1880]: variables := coalesce(variables, '{}'), vm-test-run-http> server # [ 166.875777] postgres[1880]: "operationName" := "operationName", vm-test-run-http> server # [ 166.880820] postgres[1880]: extensions := extensions vm-test-run-http> server # [ 166.883768] postgres[1880]: ); vm-test-run-http> server # [ 166.885241] postgres[1880]: $$; vm-test-run-http> server # [ 166.886262] postgres[1880]: vm-test-run-http> server # [ 166.886848] postgres[1880]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-http> server # [ 166.887484] postgres[1880]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-http> server # [ 166.888179] postgres[1880]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-http> server # [ 166.896528] postgres[1880]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.897495] postgres[1880]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.897993] postgres[1880]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.898395] postgres[1880]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.898965] postgres[1880]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.899885] postgres[1880]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.907966] postgres[1880]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-http> server # [ 166.909810] postgres[1880]: vm-test-run-http> server # [ 166.910353] postgres[1880]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-http> server # [ 166.915160] postgres[1880]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-http> server # [ 166.920923] postgres[1880]: grant usage on schema graphql to postgres with grant option; vm-test-run-http> server # [ 166.921776] postgres[1880]: END IF; vm-test-run-http> server # [ 166.922060] postgres[1880]: vm-test-run-http> server # [ 166.922287] postgres[1880]: END; vm-test-run-http> server # [ 166.924791] postgres[1880]: $func$; vm-test-run-http> server # [ 166.931250] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1876]: CREATE FUNCTION vm-test-run-http> server # [ 166.937870] postgres[1880]: ::1 2026-10-07 20:40:09.421 UTC [1880] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-http> server # [ 167.028701] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1876]: DROP EXTENSION vm-test-run-http> server # [ 168.859658] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1876]: DO vm-test-run-http> server # [ 168.907427] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-http> server # [ 169.225277] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: DO vm-test-run-http> server # [ 169.242941] postgres[1897]: ::1 2026-10-07 20:40:11.708 UTC [1897] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-http> server # [ 169.249814] postgres[1897]: LANGUAGE plpgsql vm-test-run-http> server # [ 169.251013] postgres[1897]: AS $$ vm-test-run-http> server # [ 169.256883] postgres[1897]: BEGIN vm-test-run-http> server # [ 169.258239] postgres[1897]: IF EXISTS ( vm-test-run-http> server # [ 169.259076] postgres[1897]: SELECT vm-test-run-http> server # [ 169.259492] postgres[1897]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-http> server # [ 169.259791] postgres[1897]: JOIN pg_extension AS ext vm-test-run-http> server # [ 169.260034] postgres[1897]: ON ev.objid = ext.oid vm-test-run-http> server # [ 169.260273] postgres[1897]: WHERE ext.extname = 'pg_cron' vm-test-run-http> server # [ 169.260741] postgres[1897]: ) vm-test-run-http> server # [ 169.268065] postgres[1897]: THEN vm-test-run-http> server # [ 169.269229] postgres[1897]: grant usage on schema cron to postgres with grant option; vm-test-run-http> server # [ 169.271221] postgres[1897]: vm-test-run-http> server # [ 169.271561] postgres[1897]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-http> server # [ 169.271870] postgres[1897]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-http> server # [ 169.272157] postgres[1897]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-http> server # [ 169.277509] postgres[1897]: vm-test-run-http> server # [ 169.278184] postgres[1897]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-http> server # [ 169.278949] postgres[1897]: on sequences to postgres with grant option; vm-test-run-http> server # [ 169.279277] postgres[1897]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-http> server # [ 169.279790] postgres[1897]: on tables to postgres with grant option; vm-test-run-http> server # [ 169.280091] postgres[1897]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-http> server # [ 169.285997] postgres[1897]: on functions to postgres with grant option; vm-test-run-http> server # [ 169.286868] postgres[1897]: vm-test-run-http> server # [ 169.287196] postgres[1897]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-http> server # [ 169.287478] postgres[1897]: revoke all on table cron.job from postgres; vm-test-run-http> server # [ 169.288730] postgres[1897]: grant select on table cron.job to postgres with grant option; vm-test-run-http> server # [ 169.289190] postgres[1897]: END IF; vm-test-run-http> server # [ 169.293695] postgres[1897]: END; vm-test-run-http> server # [ 169.294438] postgres[1897]: $$; vm-test-run-http> server # [ 169.304064] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: CREATE FUNCTION vm-test-run-http> server # [ 169.309779] postgres[1897]: ::1 2026-10-07 20:40:11.796 UTC [1897] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-http> server # [ 169.329230] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: DROP EVENT TRIGGER vm-test-run-http> server # [ 169.331534] postgres[1897]: ::1 2026-10-07 20:40:11.822 UTC [1897] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-http> server # [ 169.350443] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: ALTER FUNCTION vm-test-run-http> server # [ 169.357130] postgres[1897]: ::1 2026-10-07 20:40:11.847 UTC [1897] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-http> server # [ 169.358257] postgres[1897]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-http> server # [ 169.359046] postgres[1897]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-http> server # [ 169.366923] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 169.413705] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-http> server # [ 169.905550] postgres[1909]: ::1 2026-10-07 20:40:12.351 UTC [1909] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-http> server # [ 169.961475] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1903]: ALTER ROLE vm-test-run-http> server # [ 169.985940] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-http> server # [ 170.435890] postgres[1921]: ::1 2026-10-07 20:40:12.903 UTC [1921] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-http> server # [ 170.482988] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1916]: ALTER FUNCTION vm-test-run-http> server # [ 170.491177] postgres[1921]: ::1 2026-10-07 20:40:12.970 UTC [1921] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-http> server # [ 170.497841] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1916]: ALTER FUNCTION vm-test-run-http> server # [ 170.501706] postgres[1921]: ::1 2026-10-07 20:40:12.991 UTC [1921] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-http> server # [ 170.522806] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1916]: ALTER FUNCTION vm-test-run-http> server # [ 170.589663] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-http> server # [ 171.019934] postgres[1927]: ::1 2026-10-07 20:40:13.502 UTC [1927] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-http> server # [ 171.050503] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1925]: GRANT ROLE vm-test-run-http> server # [ 171.103703] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-http> server # [ 172.042162] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1928]: DO vm-test-run-http> server # [ 172.094259] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-http> server # [ 172.543984] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1933]: DO vm-test-run-http> server # [ 172.555392] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1933]: DO vm-test-run-http> server # [ 172.649282] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-http> server # [ 173.100163] postgres[1945]: ::1 2026-10-07 20:40:15.589 UTC [1945] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-http> server # [ 173.113033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1938]: ALTER ROLE vm-test-run-http> server # [ 173.115306] postgres[1945]: ::1 2026-10-07 20:40:15.602 UTC [1945] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-http> server # [ 173.121643] postgres[1945]: ::1 2026-10-07 20:40:15.613 UTC [1945] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-http> server # [ 173.123089] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1938]: ALTER ROLE vm-test-run-http> server # [ 173.130380] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1938]: ALTER ROLE vm-test-run-http> server # [ 173.181151] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-http> server # [ 173.660996] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1949]: DO vm-test-run-http> server # [ 173.731383] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-http> server # [ 174.231930] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1960]: DO vm-test-run-http> server # [ 174.286179] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-http> server # [ 174.813853] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1966]: CREATE FUNCTION vm-test-run-http> server # [ 174.829059] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1966]: DO vm-test-run-http> server # [ 174.885963] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-http> server # [ 175.178456] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1968]: ALTER FUNCTION vm-test-run-http> server # [ 175.197116] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1968]: GRANT vm-test-run-http> server # [ 175.221726] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-http> server # [ 175.515917] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1970]: DROP EVENT TRIGGER vm-test-run-http> server # [ 175.546558] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1970]: ALTER FUNCTION vm-test-run-http> server # [ 175.561858] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1970]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 175.607213] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-http> server # [ 176.128101] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1972]: DO vm-test-run-http> server # [ 176.202747] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-http> server # [ 176.883842] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1988]: CREATE FUNCTION vm-test-run-http> server # [ 176.925697] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1988]: GRANT vm-test-run-http> server # [ 176.991442] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-http> server # [ 177.378295] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: REVOKE ROLE vm-test-run-http> server # [ 177.421103] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: DO vm-test-run-http> server # [ 177.438637] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: DO vm-test-run-http> server # [ 177.445543] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: REVOKE ROLE vm-test-run-http> server # [ 177.461145] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: REVOKE vm-test-run-http> server # [ 177.494607] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: DO vm-test-run-http> server # [ 177.568005] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-http> server # [ 177.883046] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2002]: DO vm-test-run-http> server # [ 177.918153] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-http> server # [ 178.347968] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2004]: DO vm-test-run-http> server # [ 178.388252] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-http> server # [ 178.903188] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2010]: DO vm-test-run-http> server # [ 178.932293] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-http> server # [ 179.339362] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2012]: ALTER ROLE vm-test-run-http> server # [ 179.375499] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-http> server # [ 179.870332] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2022]: GRANT ROLE vm-test-run-http> server # [ 179.892444] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2022]: DO vm-test-run-http> server # [ 179.993040] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-http> server # [ 180.601851] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2034]: GRANT vm-test-run-http> server # [ 180.649139] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-http> server # [ 181.115729] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: CREATE FUNCTION vm-test-run-http> server # [ 181.125261] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: REVOKE vm-test-run-http> server # [ 181.139452] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: REVOKE vm-test-run-http> server # [ 181.153471] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: GRANT vm-test-run-http> server # [ 181.209824] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-http> server # [ 181.486895] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2044]: DO vm-test-run-http> server # [ 181.523463] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-http> server # [ 181.800688] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2046]: ALTER ROLE vm-test-run-http> server # [ 181.839809] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-http> server # [ 182.566133] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2048]: DROP EXTENSION vm-test-run-http> server # [ 182.614286] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-http> server # [ 183.152741] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2060]: CREATE FUNCTION vm-test-run-http> server # [ 183.158598] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2060]: DROP EVENT TRIGGER vm-test-run-http> server # [ 183.169739] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2060]: CREATE EVENT TRIGGER vm-test-run-http> server # [ 183.207449] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-http> server # [ 183.587370] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2069]: DO vm-test-run-http> server # [ 183.619640] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2069]: CREATE FUNCTION vm-test-run-http> server # [ 183.680081] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql vm-test-run-http> server # [ 184.107471] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2078]: CREATE FUNCTION vm-test-run-http> server # [ 184.127794] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2078]: CREATE FUNCTION vm-test-run-http> server # [ 184.173103] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running postgresql schema... vm-test-run-http> server # [ 184.619281] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER DATABASE vm-test-run-http> server # [ 184.630388] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER DATABASE vm-test-run-http> server # [ 184.735346] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 184.816827] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 184.890273] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 184.968734] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.050906] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.165245] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.290979] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.410715] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.556971] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.565111] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE vm-test-run-http> server # [ 185.643295] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Database initialization complete vm-test-run-http> server # [ 185.695852] systemd[1]: Finished Supabase Database Initialization. vm-test-run-http> server # [ 185.707045] systemd[1]: Reached target Multi-User System. vm-test-run-http> server # [ 185.707932] systemd[1]: Startup finished in 19.661s (kernel) + 2min 46.038s (userspace) = 3min 5.699s. vm-test-run-http> server: (finished: waiting for unit supabase-db-init.service, in 187.46 seconds) vm-test-run-http> subtest: Verify PostgreSQL 15 is our custom build vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();" vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.57 seconds) vm-test-run-http> server: must succeed: readlink -f $(which postgres) vm-test-run-http> server: (finished: must succeed: readlink -f $(which postgres), in 0.43 seconds) vm-test-run-http> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 1.01 seconds) vm-test-run-http> subtest: Verify ansible config loaded vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;" vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.49 seconds) vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;" vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.77 seconds) vm-test-run-http> (finished: subtest: Verify ansible config loaded, in 1.26 seconds) vm-test-run-http> subtest: Verify init scripts and migrations ran vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;" vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;", in 0.89 seconds) vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;"