these 22 derivations will be built: /nix/store/9jr1x35x09vifkv91g3afq2shy0fs1dx-index_advisor.drv /nix/store/xadisz0aqsqccyix6z839xmlxdhspgr3-postgresql-and-plugins-15.19.drv /nix/store/smysy4dl8rcni7dnaz0ajbh8r2xb37qy-postgresql-and-plugins-15.19.drv /nix/store/sb323b341irv65xs4g1izral4jscvw7a-supabase-db-init.drv /nix/store/90hxz7kg7qgbb2w7lmhway1bz61qlssp-unit-supabase-db-init.service.drv /nix/store/fy3pz9qcafh9vilyr24ikh8zajf7cypm-system-path.drv /nix/store/rpvqh8yhnx6h22pbyxlfwh1nhhm83na3-dbus-1.drv /nix/store/3521xc5v916i624xf5d148nvlylnp2zz-X-Restart-Triggers-dbus.drv /nix/store/fnjip28nw4kkqa518n1gsyfqag7fc07r-unit-dbus.service.drv /nix/store/m21hr203s1ynwy09rid8dq3d7xp8y5ls-postgresql-init.drv /nix/store/vqny4059js92bqyhzzz01vk7rmi9f57s-unit-postgresql.service.drv /nix/store/fll1iqm7cmyb0687dif4biswd74xzgds-system-units.drv /nix/store/km346k3ka0fnclvz7nnalmf5df6p2inb-unit-dbus.service.drv /nix/store/fnlm16wmmcacdy1nqi8sbyw07qjjpbzk-user-units.drv /nix/store/2nchr256mm8w6dj7i3xw320pd876d5s8-etc.drv /nix/store/sb7nhffmjj38ryg5kqrcbk5r5ymi865k-activate.drv /nix/store/k4yv9l8p3d77hv0fnl4nm8xkas5ddshl-nixos-system-server-test.drv /nix/store/m06z4zj3jn0r8242pyc40dqbv71ibwjw-closure-info.drv /nix/store/gvbqjfzjiszkb94azd7mlphhzzcb2l07-run-nixos-vm.drv /nix/store/vlpig3ifqmylqg83ahkxipnxfv4pmhnn-nixos-vm.drv /nix/store/25zn00zqnsbmj0sbl9ixrqz1yl78gw7q-nixos-test-driver-plv8.drv /nix/store/kmd1gvg8d1yjjnmxdl728sp05gyxy57h-vm-test-run-plv8.drv building '/nix/store/9jr1x35x09vifkv91g3afq2shy0fs1dx-index_advisor.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/9jr1x35x09vifkv91g3afq2shy0fs1dx-index_advisor.drv' index_advisor> created 19 symlinks in user environment building '/nix/store/xadisz0aqsqccyix6z839xmlxdhspgr3-postgresql-and-plugins-15.19.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/xadisz0aqsqccyix6z839xmlxdhspgr3-postgresql-and-plugins-15.19.drv' postgresql-and-plugins-15.19> created 2573 symlinks in user environment building '/nix/store/smysy4dl8rcni7dnaz0ajbh8r2xb37qy-postgresql-and-plugins-15.19.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/smysy4dl8rcni7dnaz0ajbh8r2xb37qy-postgresql-and-plugins-15.19.drv' building '/nix/store/m21hr203s1ynwy09rid8dq3d7xp8y5ls-postgresql-init.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/sb323b341irv65xs4g1izral4jscvw7a-supabase-db-init.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/fy3pz9qcafh9vilyr24ikh8zajf7cypm-system-path.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/m21hr203s1ynwy09rid8dq3d7xp8y5ls-postgresql-init.drv' building '/nix/store/sb323b341irv65xs4g1izral4jscvw7a-supabase-db-init.drv' building '/nix/store/fy3pz9qcafh9vilyr24ikh8zajf7cypm-system-path.drv' building '/nix/store/vqny4059js92bqyhzzz01vk7rmi9f57s-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' system-path> created 6952 symlinks in user environment building '/nix/store/90hxz7kg7qgbb2w7lmhway1bz61qlssp-unit-supabase-db-init.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/vqny4059js92bqyhzzz01vk7rmi9f57s-unit-postgresql.service.drv' building '/nix/store/90hxz7kg7qgbb2w7lmhway1bz61qlssp-unit-supabase-db-init.service.drv' building '/nix/store/rpvqh8yhnx6h22pbyxlfwh1nhhm83na3-dbus-1.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/rpvqh8yhnx6h22pbyxlfwh1nhhm83na3-dbus-1.drv' building '/nix/store/3521xc5v916i624xf5d148nvlylnp2zz-X-Restart-Triggers-dbus.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/3521xc5v916i624xf5d148nvlylnp2zz-X-Restart-Triggers-dbus.drv' building '/nix/store/fnjip28nw4kkqa518n1gsyfqag7fc07r-unit-dbus.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/km346k3ka0fnclvz7nnalmf5df6p2inb-unit-dbus.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/fnjip28nw4kkqa518n1gsyfqag7fc07r-unit-dbus.service.drv' building '/nix/store/km346k3ka0fnclvz7nnalmf5df6p2inb-unit-dbus.service.drv' building '/nix/store/25zn00zqnsbmj0sbl9ixrqz1yl78gw7q-nixos-test-driver-plv8.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/25zn00zqnsbmj0sbl9ixrqz1yl78gw7q-nixos-test-driver-plv8.drv' nixos-test-driver-plv8> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-plv8> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-plv8> Success: no issues found in 1 source file nixos-test-driver-plv8> additionally exposed symbols: nixos-test-driver-plv8> , nixos-test-driver-plv8> , nixos-test-driver-plv8> 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 nixos-test-driver-plv8> Linting test script (enable/disable: config.skipLint) nixos-test-driver-plv8> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint building '/nix/store/kmd1gvg8d1yjjnmxdl728sp05gyxy57h-vm-test-run-plv8.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/kmd1gvg8d1yjjnmxdl728sp05gyxy57h-vm-test-run-plv8.drv' vm-test-run-plv8> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-plv8> start all VLans vm-test-run-plv8> start vlan vm-test-run-plv8> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-plv8> (finished: start all VLans, in 0.00 seconds) vm-test-run-plv8> Test will time out and terminate in 3600 seconds vm-test-run-plv8> run the VM test script vm-test-run-plv8> additionally exposed symbols: vm-test-run-plv8> server, vm-test-run-plv8> vlan1, vm-test-run-plv8> 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-plv8> start all VMs vm-test-run-plv8> server: starting vm vm-test-run-plv8> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-plv8> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-plv8> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-plv8> qemu-system-aarch64: falling back to tcg vm-test-run-plv8> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-plv8> server # Formatting '/build/vm-state-server/tmp.nr6xttDKqZ', fmt=raw size=1073741824 vm-test-run-plv8> server # Discarding device blocks: 0/262144 done vm-test-run-plv8> server: QEMU running (pid 9) vm-test-run-plv8> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-plv8> server # Filesystem UUID: ec2e1f85-c695-4e59-97de-ebe548f805a6 vm-test-run-plv8> server # Superblock backups stored on blocks: vm-test-run-plv8> server # 32768, 98304, 163840, 229376 vm-test-run-plv8> server # vm-test-run-plv8> server # Allocating group tables: 0/8 done vm-test-run-plv8> server # Writing inode tables: 0/8 done vm-test-run-plv8> server # Creating journal (8192 blocks): done vm-test-run-plv8> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-plv8> server # vm-test-run-plv8> server # Virtualisation disk image created. vm-test-run-plv8> (finished: start all VMs, in 0.20 seconds) vm-test-run-plv8> server: waiting for unit supabase-db-init.service vm-test-run-plv8> server: waiting for the VM to finish booting vm-test-run-plv8> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-plv8> 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-plv8> server # [ 0.000000] KASLR enabled vm-test-run-plv8> server # [ 0.000000] random: crng init done vm-test-run-plv8> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-plv8> server # [ 0.000000] efi: UEFI not found. vm-test-run-plv8> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-plv8> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f] vm-test-run-plv8> server # [ 0.000000] Zone ranges: vm-test-run-plv8> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-plv8> server # [ 0.000000] DMA32 empty vm-test-run-plv8> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] Device empty vm-test-run-plv8> server # [ 0.000000] Movable zone start for each node vm-test-run-plv8> server # [ 0.000000] Early memory node ranges vm-test-run-plv8> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1 vm-test-run-plv8> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-plv8> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-plv8> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-plv8> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-plv8> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-plv8> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-plv8> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-plv8> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-plv8> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-plv8> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-plv8> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-plv8> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-plv8> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-plv8> 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/ksfjsbiy38wpx85ypg3dfppi9ms1cm5v-nixos-system-server-test/init regInfo=/nix/store/4mh7niziijl6nki4j27q3ykjnmjslhc5-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-plv8> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4mh7niziijl6nki4j27q3ykjnmjslhc5-closure-info/registration", will be passed to user space. vm-test-run-plv8> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-plv8> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-plv8> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-plv8> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-plv8> server # [ 0.000000] Policy zone: Normal vm-test-run-plv8> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-plv8> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-plv8> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-plv8> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-plv8> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-plv8> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-plv8> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-plv8> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-plv8> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-plv8> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-plv8> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-plv8> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-plv8> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-plv8> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-plv8> 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-plv8> 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-plv8> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-plv8> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-plv8> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-plv8> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-plv8> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-plv8> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-plv8> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-plv8> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-plv8> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100af0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-plv8> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100b00000 (flat, esz 8, psz 64K, shr 1) vm-test-run-plv8> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b10000 vm-test-run-plv8> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b20000 vm-test-run-plv8> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-plv8> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-plv8> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-plv8> server # [ 0.000038] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-plv8> server # [ 0.002885] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-plv8> server # [ 0.010308] Console: colour dummy device 80x25 vm-test-run-plv8> server # [ 0.011606] printk: legacy console [tty0] enabled vm-test-run-plv8> server # [ 0.018838] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-plv8> server # [ 0.019250] pid_max: default: 32768 minimum: 301 vm-test-run-plv8> server # [ 0.021161] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-plv8> server # [ 0.022424] landlock: Up and running. vm-test-run-plv8> server # [ 0.022458] Yama: becoming mindful. vm-test-run-plv8> server # [ 0.025788] LSM support for eBPF active vm-test-run-plv8> server # [ 0.028677] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.028847] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.055442] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-plv8> server # [ 0.065476] rcu: Hierarchical SRCU implementation. vm-test-run-plv8> server # [ 0.065573] rcu: Max phase no-delay instances is 1000. vm-test-run-plv8> server # [ 0.066766] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-plv8> server # [ 0.073175] fsl-mc MSI: its@8080000 domain created vm-test-run-plv8> server # [ 0.074849] EFI services will not be available. vm-test-run-plv8> server # [ 0.076765] smp: Bringing up secondary CPUs ... vm-test-run-plv8> server # [ 0.080837] Detected PIPT I-cache on CPU1 vm-test-run-plv8> server # [ 0.082666] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-plv8> server # [ 0.083653] GICv3: CPU1: using allocated LPI pending table @0x0000000100b30000 vm-test-run-plv8> server # [ 0.085202] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-plv8> server # [ 0.090175] smp: Brought up 1 node, 2 CPUs vm-test-run-plv8> server # [ 0.090428] SMP: Total of 2 processors activated. vm-test-run-plv8> server # [ 0.090494] CPU: All CPU(s) started at EL1 vm-test-run-plv8> server # [ 0.090732] CPU features: detected: Branch Target Identification vm-test-run-plv8> server # [ 0.090830] CPU features: detected: 32-bit EL0 Support vm-test-run-plv8> server # [ 0.090899] CPU features: detected: 32-bit EL1 Support vm-test-run-plv8> server # [ 0.090944] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-plv8> server # [ 0.091002] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-plv8> server # [ 0.091102] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-plv8> server # [ 0.091179] CPU features: detected: Common not Private translations vm-test-run-plv8> server # [ 0.091206] CPU features: detected: CRC32 instructions vm-test-run-plv8> server # [ 0.091229] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-plv8> server # [ 0.091258] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-plv8> server # [ 0.091284] CPU features: detected: Data independent timing control (DIT) vm-test-run-plv8> server # [ 0.091306] CPU features: detected: E0PD vm-test-run-plv8> server # [ 0.091322] CPU features: detected: Enhanced Counter Virtualization vm-test-run-plv8> server # [ 0.091345] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-plv8> server # [ 0.091371] CPU features: detected: Enhanced Privileged Access Never vm-test-run-plv8> server # [ 0.091391] CPU features: detected: Enhanced Virtualization Traps vm-test-run-plv8> server # [ 0.091411] CPU features: detected: Fine Grained Traps vm-test-run-plv8> server # [ 0.091458] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-plv8> server # [ 0.091548] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-plv8> server # [ 0.091590] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-plv8> server # [ 0.091613] CPU features: detected: LSE atomic instructions vm-test-run-plv8> server # [ 0.091632] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-plv8> server # [ 0.091658] CPU features: detected: Privileged Access Never vm-test-run-plv8> server # [ 0.091681] CPU features: detected: RAS Extension Support vm-test-run-plv8> server # [ 0.091700] CPU features: detected: Random Number Generator vm-test-run-plv8> server # [ 0.091722] CPU features: detected: Speculation barrier (SB) vm-test-run-plv8> server # [ 0.091745] CPU features: detected: Stage-2 Force Write-Back vm-test-run-plv8> server # [ 0.091768] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-plv8> server # [ 0.091795] CPU features: detected: TLB range maintenance instructions vm-test-run-plv8> server # [ 0.091818] CPU features: detected: WFx with timeout vm-test-run-plv8> server # [ 0.091883] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-plv8> server # [ 0.091909] CPU features: detected: Scalable Vector Extension vm-test-run-plv8> server # [ 0.100708] alternatives: applying system-wide alternatives vm-test-run-plv8> server # [ 0.115483] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-plv8> server # [ 0.116265] SVE: maximum available vector length 256 bytes per vector vm-test-run-plv8> server # [ 0.116344] SVE: default vector length 64 bytes per vector vm-test-run-plv8> server # [ 0.131710] Memory: 3922792K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved) vm-test-run-plv8> server # [ 0.152384] devtmpfs: initialized vm-test-run-plv8> server # [ 0.176290] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-plv8> server # [ 0.176763] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-plv8> server # [ 0.179250] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-plv8> server # [ 0.179320] 0 pages in range for non-PLT usage vm-test-run-plv8> server # [ 0.179359] 509248 pages in range for PLT usage vm-test-run-plv8> server # [ 0.181767] pinctrl core: initialized pinctrl subsystem vm-test-run-plv8> server # [ 0.191652] DMI not present or invalid. vm-test-run-plv8> server # [ 0.217111] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-plv8> server # [ 0.231237] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-plv8> server # [ 0.232992] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-plv8> server # [ 0.235003] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-plv8> server # [ 0.235506] audit: initializing netlink subsys (disabled) vm-test-run-plv8> server # [ 0.239034] audit: type=2000 audit(0.116:1): state=initialized audit_enabled=0 res=1 vm-test-run-plv8> server # [ 0.246364] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-plv8> server # [ 0.246475] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-plv8> server # [ 0.246589] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-plv8> server # [ 0.246646] thermal_sys: Registered thermal governor 'user_space' vm-test-run-plv8> server # [ 0.246701] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-plv8> server # [ 0.248399] cpuidle: using governor ladder vm-test-run-plv8> server # [ 0.248963] cpuidle: using governor menu vm-test-run-plv8> server # [ 0.252310] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-plv8> server # [ 0.253344] ASID allocator initialised with 65536 entries vm-test-run-plv8> server # [ 0.262793] Serial: AMBA PL011 UART driver vm-test-run-plv8> server # [ 0.311954] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-plv8> server # [ 0.313405] printk: legacy console [ttyAMA0] enabled vm-test-run-plv8> server # [ 0.349739] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.349942] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-plv8> server # [ 0.350052] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.350174] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-plv8> server # [ 0.351422] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.351690] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-plv8> server # [ 0.351922] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.352144] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-plv8> server # [ 0.371216] fbcon: Taking over console vm-test-run-plv8> server # [ 0.371730] ACPI: Interpreter disabled. vm-test-run-plv8> server # [ 0.377437] iommu: Default domain type: Translated vm-test-run-plv8> server # [ 0.377591] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-plv8> server # [ 0.388655] SCSI subsystem initialized vm-test-run-plv8> server # [ 0.393144] usbcore: registered new interface driver usbfs vm-test-run-plv8> server # [ 0.393451] usbcore: registered new interface driver hub vm-test-run-plv8> server # [ 0.393956] usbcore: registered new device driver usb vm-test-run-plv8> server # [ 0.399717] pps_core: LinuxPPS API ver. 1 registered vm-test-run-plv8> server # [ 0.399957] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-plv8> server # [ 0.400752] PTP clock support registered vm-test-run-plv8> server # [ 0.401774] EDAC MC: Ver: 3.0.0 vm-test-run-plv8> server # [ 0.404401] scmi_core: SCMI protocol bus registered vm-test-run-plv8> server # [ 0.407841] FPGA manager framework vm-test-run-plv8> server # [ 0.409522] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-plv8> server # [ 0.415990] vgaarb: loaded vm-test-run-plv8> server # [ 0.420877] clocksource: Switched to clocksource arch_sys_counter vm-test-run-plv8> server # [ 0.445843] VFS: Disk quotas dquot_6.6.0 vm-test-run-plv8> server # [ 0.446083] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-plv8> server # [ 0.448945] netfs: FS-Cache loaded vm-test-run-plv8> server # [ 0.450955] pnp: PnP ACPI: disabled vm-test-run-plv8> server # [ 0.469593] NET: Registered PF_INET protocol family vm-test-run-plv8> server # [ 0.471415] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-plv8> server # [ 0.572466] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-plv8> server # [ 0.573076] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-plv8> server # [ 0.574492] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-plv8> server # [ 0.575708] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-plv8> server # [ 0.576894] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-plv8> server # [ 0.578573] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-plv8> server # [ 0.579033] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.579676] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.581529] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-plv8> server # [ 0.581885] NET: Registered PF_XDP protocol family vm-test-run-plv8> server # [ 0.582562] PCI: CLS 0 bytes, default 64 vm-test-run-plv8> server # [ 0.584833] kvm [1]: HYP mode not available vm-test-run-plv8> server # [ 0.587232] Trying to unpack rootfs image as initramfs... vm-test-run-plv8> server # [ 0.683779] Initialise system trusted keyrings vm-test-run-plv8> server # [ 0.686174] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-plv8> server # [ 0.690211] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-plv8> server # [ 0.691438] 9p: Installing v9fs 9p2000 file system support vm-test-run-plv8> server # [ 0.754320] Key type asymmetric registered vm-test-run-plv8> server # [ 0.754538] Asymmetric key parser 'x509' registered vm-test-run-plv8> server # [ 0.754955] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-plv8> server # [ 0.756614] io scheduler mq-deadline registered vm-test-run-plv8> server # [ 0.756763] io scheduler kyber registered vm-test-run-plv8> server # [ 0.782627] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-plv8> server # [ 0.786348] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-plv8> server # [ 0.789707] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-plv8> server # [ 0.790690] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-plv8> server # [ 0.791844] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-plv8> server # [ 0.792316] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-plv8> server # [ 0.793917] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-plv8> server # [ 0.794426] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-plv8> server # [ 0.796127] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-plv8> server # [ 0.796464] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-plv8> server # [ 0.796789] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-plv8> server # [ 0.796902] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-plv8> server # [ 0.796997] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-plv8> server # [ 0.798599] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.801388] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.801776] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.801907] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.802050] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.803306] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-plv8> server # [ 0.804297] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-plv8> server # [ 0.804566] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.804689] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.806155] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.807409] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.808128] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-plv8> server # [ 0.808234] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.808354] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.808836] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.808982] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.809253] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.809740] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.811029] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.811419] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.811615] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.811819] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.812691] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.813627] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-plv8> server # [ 0.813910] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.814376] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.815442] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.815815] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.816043] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.816197] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.816334] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-plv8> server # [ 0.816951] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.817142] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.817269] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.818104] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.818603] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.818839] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.820018] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-plv8> server # [ 0.820549] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.821497] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.821874] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-plv8> server # [ 0.822202] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.822681] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.823691] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-plv8> server # [ 0.824189] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.824374] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.824502] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.826812] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-plv8> server # [ 0.827139] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-plv8> server # [ 0.827382] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.827697] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.828276] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.828776] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.829041] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.830292] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.830408] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.830520] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.830629] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.830758] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.830925] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.831101] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-plv8> server # [ 0.831203] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-plv8> server # [ 0.831293] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-plv8> server # [ 0.831385] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-plv8> server # [ 0.831474] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-plv8> server # [ 0.831574] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-plv8> server # [ 0.831672] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-plv8> server # [ 0.831764] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-plv8> server # [ 0.831875] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-plv8> server # [ 0.831969] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-plv8> server # [ 0.832384] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-plv8> server # [ 0.832496] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-plv8> server # [ 0.832599] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-plv8> server # [ 0.832701] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-plv8> server # [ 0.832794] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-plv8> server # [ 0.832881] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-plv8> server # [ 0.832971] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-plv8> server # [ 0.833062] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-plv8> server # [ 0.833147] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-plv8> server # [ 0.833233] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-plv8> server # [ 0.833319] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-plv8> server # [ 0.833564] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-plv8> server # [ 0.833659] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-plv8> server # [ 0.833747] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-plv8> server # [ 0.834484] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.892614] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.897736] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.900634] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.903731] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.905572] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.906930] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.908423] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.910868] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.914346] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.915925] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.917810] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.932638] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-plv8> server # [ 0.944422] msm_serial: driver initialized vm-test-run-plv8> server # [ 0.946341] SuperH (H)SCI(F) driver initialized vm-test-run-plv8> server # [ 0.947096] STM32 USART driver initialized vm-test-run-plv8> server # [ 0.996607] loop: module loaded vm-test-run-plv8> server # [ 0.997105] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-plv8> server # [ 1.001069] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-plv8> server # [ 1.012382] megasas: 07.727.03.00-rc1 vm-test-run-plv8> server # [ 1.019347] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-plv8> server # [ 1.019758] Freeing initrd memory: 10684K vm-test-run-plv8> server # [ 1.022870] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-plv8> server # [ 1.024439] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-plv8> server # [ 1.026479] Using buffer write method vm-test-run-plv8> server # [ 1.026974] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-plv8> server # [ 1.028959] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-plv8> server # [ 1.029724] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-plv8> server # [ 1.031953] Using buffer write method vm-test-run-plv8> server # [ 1.032168] Concatenating MTD devices: vm-test-run-plv8> server # [ 1.032246] (0): "0.flash" vm-test-run-plv8> server # [ 1.032349] (1): "0.flash" vm-test-run-plv8> server # [ 1.032419] into device "0.flash" vm-test-run-plv8> server # [ 1.054552] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-plv8> server # [ 1.066067] thunder_xcv, ver 1.0 vm-test-run-plv8> server # [ 1.066633] thunder_bgx, ver 1.0 vm-test-run-plv8> server # [ 1.066794] nicpf, ver 1.0 vm-test-run-plv8> server # [ 1.069870] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-plv8> server # [ 1.070006] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-plv8> server # [ 1.070291] hclge is initializing vm-test-run-plv8> server # [ 1.070475] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-plv8> server # [ 1.070553] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-plv8> server # [ 1.070714] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-plv8> server # [ 1.070791] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-plv8> server # [ 1.070955] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-plv8> server # [ 1.071100] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-plv8> server # [ 1.071252] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-plv8> server # [ 1.071334] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-plv8> server # [ 1.072052] sky2: driver version 1.30 vm-test-run-plv8> server # [ 1.079204] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-plv8> server # [ 1.079659] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-plv8> server # [ 1.080670] usbcore: registered new interface driver usb-storage vm-test-run-plv8> server # [ 1.082170] usbcore: registered new interface driver usbserial_generic vm-test-run-plv8> server # [ 1.082219] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-plv8> server # [ 1.082660] usbserial: USB Serial support registered for generic vm-test-run-plv8> server # [ 1.091765] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-plv8> server # [ 1.092309] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T21:29:03 UTC (1792704543) vm-test-run-plv8> server # [ 1.094717] i2c_dev: i2c /dev entries driver vm-test-run-plv8> server # [ 1.100882] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-plv8> server # [ 1.107593] sdhci: Secure Digital Host Controller Interface driver vm-test-run-plv8> server # [ 1.107730] sdhci: Copyright(c) Pierre Ossman vm-test-run-plv8> server # [ 1.107735] hub 1-0:1.0: USB hub found vm-test-run-plv8> server # [ 1.108655] hub 1-0:1.0: 6 ports detected vm-test-run-plv8> server # [ 1.109146] Synopsys Designware Multimedia Card Interface Driver vm-test-run-plv8> server # [ 1.111344] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-plv8> server # [ 1.117390] hid: raw HID events driver (C) Jiri Kosina vm-test-run-plv8> server # [ 1.119392] usbcore: registered new interface driver usbhid vm-test-run-plv8> server # [ 1.119515] usbhid: USB HID core driver vm-test-run-plv8> server # [ 1.126610] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-plv8> server # [ 1.137661] drop_monitor: Initializing network drop monitor service vm-test-run-plv8> server # [ 1.138713] NET: Registered PF_INET6 protocol family vm-test-run-plv8> server # [ 1.151744] Segment Routing with IPv6 vm-test-run-plv8> server # [ 1.152138] In-situ OAM (IOAM) with IPv6 vm-test-run-plv8> server # [ 1.153426] NET: Registered PF_PACKET protocol family vm-test-run-plv8> server # [ 1.154560] 9pnet: Installing 9P2000 support vm-test-run-plv8> server # [ 1.158259] Key type dns_resolver registered vm-test-run-plv8> server # [ 1.191150] registered taskstats version 1 vm-test-run-plv8> server # [ 1.193486] Loading compiled-in X.509 certificates vm-test-run-plv8> server # [ 1.245816] Demotion targets for Node 0: null vm-test-run-plv8> server # [ 1.247883] Key type .fscrypt registered vm-test-run-plv8> server # [ 1.248020] Key type fscrypt-provisioning registered vm-test-run-plv8> server # [ 1.256797] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-plv8> server # [ 1.293023] clk: Disabling unused clocks vm-test-run-plv8> server # [ 1.293285] PM: genpd: Disabling unused power domains vm-test-run-plv8> server # [ 1.294309] ALSA device list: vm-test-run-plv8> server # [ 1.294437] No soundcards found. vm-test-run-plv8> server # [ 1.362421] Freeing unused kernel memory: 4416K vm-test-run-plv8> server # [ 1.364806] Run /init as init process vm-test-run-plv8> server # [ 1.428605] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-plv8> server # [ 1.639954] 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-plv8> server # [ 1.811486] 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-plv8> server # [ 1.862912] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-plv8> server # [ 1.959336] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-plv8> server # [ 2.011375] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-plv8> server # [ 2.012071] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-plv8> server # [ 2.012091] [drm] features: -context_init vm-test-run-plv8> server # [ 2.014988] [drm] number of scanouts: 1 vm-test-run-plv8> server # [ 2.015104] [drm] number of cap sets: 0 vm-test-run-plv8> server # [ 2.027808] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-plv8> server # [ 2.063223] Console: switching to colour frame buffer device 160x50 vm-test-run-plv8> server # [ 2.074335] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-plv8> server # [ 2.164336] 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-plv8> server # [ 2.170969] 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-plv8> server # [ 4.682001] EXT4-fs (vda): mounted filesystem ec2e1f85-c695-4e59-97de-ebe548f805a6 r/w with ordered data mode. Quota mode: none. vm-test-run-plv8> server # [ 6.973641] EXT4-fs (vda): re-mounted ec2e1f85-c695-4e59-97de-ebe548f805a6. vm-test-run-plv8> server # [ 6.991816] booting system configuration /nix/store/ksfjsbiy38wpx85ypg3dfppi9ms1cm5v-nixos-system-server-test vm-test-run-plv8> server # [ 17.460079] 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-plv8> server # [ 17.470729] systemd[1]: Detected virtualization qemu. vm-test-run-plv8> server # [ 17.474667] systemd[1]: Detected architecture arm64. vm-test-run-plv8> server # [ 17.480577] systemd[1]: Detected first boot. vm-test-run-plv8> server # [ 17.506780] systemd[1]: Initializing machine ID from random generator. vm-test-run-plv8> server # [ 17.537855] systemd[1]: Hostname set to . vm-test-run-plv8> server # [ 18.783617] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-plv8> server # [ 20.161827] systemd[1]: Applying preset policy. vm-test-run-plv8> server # [ 23.365821] systemd[1]: Populated /etc with preset unit settings. vm-test-run-plv8> server # [ 26.222982] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-plv8> server # [ 26.410878] systemd[1]: Created slice Slice /system/getty. vm-test-run-plv8> server # [ 26.426355] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-plv8> server # [ 26.439366] systemd[1]: Created slice User and Session Slice. vm-test-run-plv8> server # [ 26.447564] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-plv8> server # [ 26.454215] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-plv8> server # [ 26.460072] systemd[1]: Expecting device /dev/hvc0... vm-test-run-plv8> server # [ 26.465943] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-plv8> server # [ 26.470504] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-plv8> server # [ 26.475011] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-plv8> server # [ 26.479656] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-plv8> server # [ 26.484197] systemd[1]: Reached target Path Units. vm-test-run-plv8> server # [ 26.489099] systemd[1]: Reached target Remote File Systems. vm-test-run-plv8> server # [ 26.493345] systemd[1]: Reached target Slice Units. vm-test-run-plv8> server # [ 26.497635] systemd[1]: Reached target Swaps. vm-test-run-plv8> server # [ 26.532872] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-plv8> server # [ 26.555195] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-plv8> server # [ 26.565948] systemd[1]: Listening on Journal Audit Socket. vm-test-run-plv8> server # [ 26.572370] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-plv8> server # [ 26.578855] systemd[1]: Listening on Journal Sockets. vm-test-run-plv8> server # [ 26.587186] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-plv8> server # [ 26.592301] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-plv8> server # [ 26.598991] systemd[1]: Listening on udev Control Socket. vm-test-run-plv8> server # [ 26.604357] systemd[1]: Listening on udev Kernel Socket. vm-test-run-plv8> server # [ 26.655013] systemd[1]: Mounting Huge Pages File System... vm-test-run-plv8> server # [ 26.699380] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-plv8> server # [ 26.750620] systemd[1]: Mounting Kernel Debug File System... vm-test-run-plv8> server # [ 26.808004] systemd[1]: Mounting Kernel Trace File System... vm-test-run-plv8> server # [ 26.958938] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-plv8> server # [ 27.076089] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-plv8> server # [ 27.093860] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-plv8> server # [ 27.237551] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-plv8> server # [ 27.251641] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-plv8> server # [ 27.270636] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-plv8> server # [ 27.394790] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-plv8> server # [ 27.407445] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-plv8> server # [ 27.417783] 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-plv8> server # [ 27.554036] systemd[1]: Starting Journal Service... vm-test-run-plv8> server # [ 27.636732] systemd[1]: Starting Load Kernel Modules... vm-test-run-plv8> server # [ 27.706866] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-plv8> server # [ 27.760082] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-plv8> server # [ 27.769930] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-plv8> server # [ 27.852007] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-plv8> server # [ 27.944711] systemd[1]: Mounted Huge Pages File System. vm-test-run-plv8> server # [ 27.954513] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-plv8> server # [ 27.964497] systemd[1]: Mounted Kernel Debug File System. vm-test-run-plv8> server # [ 27.976800] systemd[1]: Mounted Kernel Trace File System. vm-test-run-plv8> server # [ 28.002064] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-plv8> server # [ 28.018492] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-plv8> server # [ 28.072608] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-plv8> server # [ 28.304802] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-plv8> server # [ 28.323678] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-plv8> server # [ 29.031054] fuse: init (API version 7.41) vm-test-run-plv8> server # [ 29.115961] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-plv8> server # [ 29.137760] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-plv8> server # [ 29.160384] systemd[1]: Finished Load Kernel Modules. vm-test-run-plv8> server # [ 29.160441] EXT4-fs (vda): re-mounted ec2e1f85-c695-4e59-97de-ebe548f805a6. vm-test-run-plv8> server: Guest root shell did not produce any data yet... vm-test-run-plv8> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-plv8> server # [ 29.254227] systemd[1]: Mounting FUSE Control File System... vm-test-run-plv8> server # [ 29.356005] systemd[1]: Starting Firewall... vm-test-run-plv8> server # [ 29.415826] systemd[1]: Starting Apply Kernel Variables... vm-test-run-plv8> server # [ 29.459477] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-plv8> server # [ 29.469012] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-plv8> server # [ 29.538494] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-plv8> server # [ 29.549965] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-plv8> server # [ 29.581564] systemd[1]: Mounted FUSE Control File System. vm-test-run-plv8> server # [ 29.728133] systemd-journald[395]: Collecting audit messages is enabled. vm-test-run-plv8> server # [ 30.472652] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-plv8> server # [ 30.558481] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-plv8> server # [ 30.718608] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-plv8> server # [ 30.731286] systemd[1]: Reached target First Boot Complete. vm-test-run-plv8> server # [ 31.279664] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-plv8> server # [ 31.318082] systemd[1]: Finished Apply Kernel Variables. vm-test-run-plv8> server # [ 31.979524] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-plv8> server # [ 32.007929] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-plv8> server # [ 32.075826] systemd[1]: Mounting /run/wrappers... vm-test-run-plv8> server # [ 32.133562] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-plv8> server # [ 32.633975] systemd[1]: Mounted /run/wrappers. vm-test-run-plv8> server # [ 32.643936] systemd[1]: Reached target Local File Systems. vm-test-run-plv8> server # [ 32.674831] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-plv8> server # [ 32.715578] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-plv8> server # [ 32.724424] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-plv8> server # [ 32.771072] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-plv8> server # [ 32.824628] systemd[1]: Started Journal Service. vm-test-run-plv8> server # [ 32.660158] systemd-modules-load[396]: Module 'atkbd' is built in vm-test-run-plv8> server # [ 32.683881] systemd-modules-load[396]: Module 'loop' is built in vm-test-run-plv8> server # [ 32.747172] systemd-oomd[397]: No swap; memory pressure usage will be degraded vm-test-run-plv8> server # [ 32.761764] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-plv8> server # [ 33.924751] systemd-journald[395]: Received client request to flush runtime journal. vm-test-run-plv8> server # [ 34.316071] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-plv8> server # [ 34.367431] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-plv8> server # [ 34.422904] systemd-udevd[423]: Using default interface naming scheme 'v258'. vm-test-run-plv8> server # [ 34.429422] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-plv8> server # [ 34.432037] systemd[1]: Starting Create System Files and Directories... vm-test-run-plv8> server # [ 36.363542] systemd[1]: Finished Create System Files and Directories. vm-test-run-plv8> server # [ 36.482397] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-plv8> server # [ 36.518730] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-plv8> server # [ 36.714862] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-plv8> server # [ 37.479929] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-plv8> server # [ 38.902642] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-plv8> server # [ 38.976925] systemd[1]: Starting Update is Completed... vm-test-run-plv8> server # [ 39.656643] systemd[1]: Finished Update is Completed. vm-test-run-plv8> server # [ 43.567313] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-plv8> server # [ 43.586359] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-plv8> server # [ 43.596970] systemd[1]: suid-sgid-wrappers.service: Consumed 3.118s CPU time, 5.6M memory peak. vm-test-run-plv8> server # [ 46.915173] systemd[1]: Finished Firewall. vm-test-run-plv8> server # [ 48.686090] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-plv8> server # [ 48.700547] systemd[1]: Reached target System Initialization. vm-test-run-plv8> server # [ 48.706825] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-plv8> server # [ 48.710448] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-plv8> server # [ 48.716274] systemd[1]: Reached target Timer Units. vm-test-run-plv8> server # [ 48.720140] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-plv8> server # [ 48.724211] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-plv8> server # [ 48.727557] systemd[1]: Listening on Hostname Service Socket. vm-test-run-plv8> server # [ 48.729795] systemd[1]: Reached target Socket Units. vm-test-run-plv8> server # [ 48.731814] systemd[1]: Reached target Basic System. vm-test-run-plv8> server # [ 48.765229] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-plv8> server # [ 48.805054] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 48.863685] systemd[1]: Started Reset console on configuration changes. vm-test-run-plv8> server # [ 48.923042] systemd[1]: Starting resolvconf update... vm-test-run-plv8> server # [ 49.013705] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-plv8> server # [ 50.043694] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-plv8> server # [ 50.461707] nsncd[629]: Oct 22 21:29:53.014 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-plv8> server # [ 50.493434] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 50.510179] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-plv8> server # [ 50.518876] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-plv8> server # [ 50.595563] dbus-daemon[632]: dbus[632]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-plv8> server # [ 50.621363] systemd[1]: Starting User Login Management... vm-test-run-plv8> server # [ 51.077425] systemd[1]: Started D-Bus System Message Bus. vm-test-run-plv8> server # [ 52.279150] systemd-logind[651]: New seat seat0. vm-test-run-plv8> server # [ 52.322261] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-plv8> server # [ 52.342510] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-plv8> server # [ 52.362042] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-plv8> server # [ 52.371052] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-plv8> server # [ 52.379538] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 52.392665] systemd[1]: Started User Login Management. vm-test-run-plv8> server # [ 52.427406] systemd[1]: Starting linger-users.service... vm-test-run-plv8> server # [ 52.430652] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-plv8> server # [ 52.432154] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 52.630518] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 53.096670] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-plv8> server # [ 53.116478] systemd[1]: Finished linger-users.service. vm-test-run-plv8> server # [ 53.551278] nsncd[693]: Oct 22 21:29:56.108 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-plv8> server # [ 53.567045] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 53.573629] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-plv8> server # [ 53.580502] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-plv8> server # [ 54.064205] systemd[1]: Finished resolvconf update. vm-test-run-plv8> server # [ 54.080332] systemd[1]: Reached target Preparation for Network. vm-test-run-plv8> server # [ 54.131704] systemd[1]: Starting DHCP Client... vm-test-run-plv8> server # [ 55.734829] dhcpcd[740]: dhcpcd-10.2.4 starting vm-test-run-plv8> server # [ 55.857194] dhcpcd[760]: dev: loaded udev vm-test-run-plv8> server # [ 56.437526] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-plv8> server # [ 56.896775] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-plv8> server # [ 57.156915] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-plv8> server # [ 57.166179] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-plv8> server # [ 57.194279] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-plv8> server # [ 57.197835] cfg80211: failed to load regulatory.db vm-test-run-plv8> server # [ 58.002433] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-plv8> server # [ 57.835897] dhcpcd[760]: eth0: waiting for carrier vm-test-run-plv8> server # [ 57.860958] dhcpcd[760]: eth0: carrier acquired vm-test-run-plv8> server # [ 57.972752] dhcpcd[760]: DUID 00:01:00:01:32:6d:3c:d8:52:54:00:12:34:56 vm-test-run-plv8> server # [ 57.974780] dhcpcd[760]: eth0: IAID 00:12:34:56 vm-test-run-plv8> server # [ 57.978130] dhcpcd[760]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-plv8> server # [ 58.393852] systemd[1]: Found device /dev/hvc0. vm-test-run-plv8> server: Guest root shell did not produce any data yet... vm-test-run-plv8> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-plv8> server # [ 59.346875] dhcpcd[760]: eth0: soliciting a DHCP lease vm-test-run-plv8> server # [ 59.350696] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-plv8> server # [ 59.387170] dhcpcd[760]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-plv8> server # [ 59.421591] systemd[1]: Started backdoor.service. vm-test-run-plv8> server # [ 59.427376] dhcpcd[760]: eth0: probing address 10.0.2.15/24 vm-test-run-plv8> server # connecting to host... vm-test-run-plv8> server # [ 59.986023] (udev-worker)[789]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server # [ 60.001787] (udev-worker)[794]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-plv8> server # [ 60.022209] (udev-worker)[794]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-plv8> server: connected to guest root shell vm-test-run-plv8> server: (connecting took 61.23 seconds) vm-test-run-plv8> server: (finished: waiting for the VM to finish booting, in 61.23 seconds) vm-test-run-plv8> server # [ 60.656193] dhcpcd[760]: eth0: soliciting an IPv6 router vm-test-run-plv8> server # [ 60.666872] dhcpcd[760]: eth0: Router Advertisement from fe80::2 vm-test-run-plv8> server # [ 60.682131] dhcpcd[760]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-plv8> server # [ 60.693478] dhcpcd[760]: eth0: adding route to fec0::/64 vm-test-run-plv8> server # [ 60.694522] dhcpcd[760]: eth0: adding default route via fe80::2 vm-test-run-plv8> server # [ 60.951974] systemd[1]: Found device Virtio network device. vm-test-run-plv8> server # [ 60.989345] systemd[1]: Starting Address configuration of eth1... vm-test-run-plv8> server # [ 61.310991] dhcpcd[760]: eth0: IAID 00:12:34:56 vm-test-run-plv8> server # [ 61.762366] dhcpcd[760]: eth0: soliciting an IPv6 router vm-test-run-plv8> server # [ 62.146163] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-plv8> server # [ 62.181939] network-addresses-eth1-start[812]: adding address 192.168.1.1/24... done vm-test-run-plv8> server # [ 62.401642] network-addresses-eth1-start[812]: adding address 2001:db8:1::1/64... done vm-test-run-plv8> server # [ 62.648825] systemd[1]: Finished Address configuration of eth1. vm-test-run-plv8> server # [ 62.725372] systemd[1]: Starting Networking Setup... vm-test-run-plv8> server # [ 63.023980] dhcpcd[760]: eth0: soliciting a DHCP lease vm-test-run-plv8> server # [ 63.030778] dhcpcd[760]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-plv8> server # [ 63.031995] dhcpcd[760]: eth0: probing address 10.0.2.15/24 vm-test-run-plv8> server # [ 65.013529] dhcpcd[760]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-plv8> server # [ 65.023261] dhcpcd[760]: eth0: adding route to 10.0.2.0/24 vm-test-run-plv8> server # [ 65.024235] dhcpcd[760]: eth0: adding default route via 10.0.2.2 vm-test-run-plv8> server # [ 65.576011] dhcpcd[895]: /nix/store/hsj50yr7hr62ql9xaynbihkhfhbgprcn-openresolv-3.17.0/sbin/.resolvconf-wrapped: line 1221: kill: (847) - Operation not permitted vm-test-run-plv8> server # [ 65.584008] dhcpcd[895]: clearing stale lock pid 847 vm-test-run-plv8> server # [ 65.804032] systemd[1]: Finished Networking Setup. vm-test-run-plv8> server # [ 65.814758] systemd[1]: Reached target Network. vm-test-run-plv8> server # [ 65.871434] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-plv8> server # [ 65.919014] systemd[1]: Starting Permit User Sessions... vm-test-run-plv8> server # [ 66.709063] systemd[1]: Finished Permit User Sessions. vm-test-run-plv8> server # [ 66.842790] systemd[1]: Started Getty on tty1. vm-test-run-plv8> server # [ 66.870057] systemd[1]: Reached target Login Prompts. vm-test-run-plv8> server # [ 67.750043] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[938]: Initializing database at /var/lib/postgresql/data vm-test-run-plv8> server # [ 69.461552] systemd[1]: Started DHCP Client. vm-test-run-plv8> server # [ 69.771657] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: The files belonging to this database system will be owned by user "postgres". vm-test-run-plv8> server # [ 69.778368] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: This user must also own the server process. vm-test-run-plv8> server # [ 69.899824] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-plv8> server # [ 69.905753] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: The default database encoding has accordingly been set to "UTF8". vm-test-run-plv8> server # [ 69.906468] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: The default text search configuration will be set to "english". vm-test-run-plv8> server # [ 69.907692] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: Data page checksums are enabled. vm-test-run-plv8> server # [ 69.916517] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-plv8> server # [ 69.922783] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: creating subdirectories ... ok vm-test-run-plv8> server # [ 69.935975] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: selecting dynamic shared memory implementation ... posix vm-test-run-plv8> server # [ 71.325203] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: selecting default max_connections ... 100 vm-test-run-plv8> server # [ 72.039861] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: selecting default shared_buffers ... 128MB vm-test-run-plv8> server # [ 75.029769] systemd-logind[651]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-plv8> server # [ 83.101290] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: selecting default time zone ... UTC vm-test-run-plv8> server # [ 83.147346] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: creating configuration files ... ok vm-test-run-plv8> server # [ 84.870814] mousedev: PS/2 mouse device common for all mice vm-test-run-plv8> server # [ 88.269624] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: running bootstrap script ... ok vm-test-run-plv8> server # [ 89.073868] systemd-logind[651]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-plv8> server # [ 101.653389] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: performing post-bootstrap initialization ... ok vm-test-run-plv8> server # [ 104.578438] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: syncing data to disk ... ok vm-test-run-plv8> server # [ 104.587568] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-plv8> server # [ 104.590275] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: 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-plv8> server # [ 104.591148] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: Success. You can now start the database server using: vm-test-run-plv8> server # [ 104.591942] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[960]: /nix/store/1zk32bp26apmbl88jx2hy94jccll7bp8-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-plv8> server # [ 106.156519] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[938]: Validating PostgreSQL configuration... vm-test-run-plv8> server # [ 106.911731] vdfcnzi4b4yhh73bpjl7ix6ms53c3bhg-postgresql-init[1353]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault vm-test-run-plv8> server # [ 107.999921] postgres[1370]: 2026-10-22 21:30:50.575 UTC [1370] LOG: pgaudit extension initialized vm-test-run-plv8> server # [ 108.754990] postgres[1370]: 2026-10-22 21:30:51.346 UTC [1370] LOG: pgsodium primary server secret key loaded vm-test-run-plv8> server # [ 109.493316] postgres[1370]: 2026-10-22 21:30:52.066 UTC [1370] LOG: vault primary server secret key loaded vm-test-run-plv8> server # [ 110.187740] postgres[1370]: 2026-10-22 21:30:52.779 UTC [1370] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-plv8> server # [ 110.213662] postgres[1370]: 2026-10-22 21:30:52.805 UTC [1370] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-plv8> server # [ 110.220865] postgres[1370]: 2026-10-22 21:30:52.806 UTC [1370] LOG: listening on IPv6 address "::", port 5432 vm-test-run-plv8> server # [ 110.234375] postgres[1370]: 2026-10-22 21:30:52.826 UTC [1370] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-plv8> server # [ 110.347946] postgres[1406]: 2026-10-22 21:30:52.930 UTC [1406] LOG: database system was shut down at 2026-10-22 21:30:44 UTC vm-test-run-plv8> server # [ 110.664086] postgres[1370]: 2026-10-22 21:30:53.250 UTC [1370] LOG: database system is ready to accept connections vm-test-run-plv8> server # [ 110.688193] postgres[1412]: 2026-10-22 21:30:53.255 UTC [1412] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-plv8> server # [ 110.726698] postgres[1414]: 2026-10-22 21:30:53.260 UTC [1414] FATAL: role "postgres" does not exist vm-test-run-plv8> server # [ 110.748703] systemd[1]: Started PostgreSQL Database Server. vm-test-run-plv8> server # [ 110.777979] systemd[1]: Starting Supabase Database Initialization... vm-test-run-plv8> server # [ 110.871391] postgres[1370]: 2026-10-22 21:30:53.458 UTC [1370] LOG: background worker "pg_net 0.20.4 worker" (PID 1414) exited with exit code 1 vm-test-run-plv8> server # [ 111.188135] postgres[1415]: 2026-10-22 21:30:53.765 UTC [1415] LOG: pg_cron scheduler started vm-test-run-plv8> server # [ 111.287574] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Waiting for PostgreSQL to be ready... vm-test-run-plv8> server # [ 111.908781] postgres[1425]: ::1 2026-10-22 21:30:54.479 UTC [1425] root@root FATAL: role "root" does not exist vm-test-run-plv8> server # [ 111.930831] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: PostgreSQL is ready vm-test-run-plv8> server # [ 111.963314] postgres[1426]: 2026-10-22 21:30:54.530 UTC [1426] FATAL: role "postgres" does not exist vm-test-run-plv8> server # [ 111.969992] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Creating postgres role... vm-test-run-plv8> server # [ 112.021877] postgres[1370]: 2026-10-22 21:30:54.608 UTC [1370] LOG: background worker "pg_net 0.20.4 worker" (PID 1426) exited with exit code 1 vm-test-run-plv8> server # [ 112.309960] postgres[1428]: ::1 2026-10-22 21:30:54.899 UTC [1428] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-plv8> server # [ 112.357330] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1427]: CREATE ROLE vm-test-run-plv8> server # [ 112.657027] postgres[1430]: ::1 2026-10-22 21:30:55.242 UTC [1430] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-plv8> server # [ 112.695821] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1429]: ALTER DATABASE vm-test-run-plv8> server # [ 112.792915] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-plv8> server # [ 113.379592] postgres[1443]: ::1 2026-10-22 21:30:55.946 UTC [1443] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-plv8> server # [ 113.426063] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE PUBLICATION vm-test-run-plv8> server # [ 113.431011] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER ROLE vm-test-run-plv8> server # [ 113.432608] postgres[1443]: ::1 2026-10-22 21:30:55.999 UTC [1443] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-plv8> server # [ 113.433554] postgres[1443]: ::1 2026-10-22 21:30:56.024 UTC [1443] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-plv8> server # [ 113.463756] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 113.467769] postgres[1443]: ::1 2026-10-22 21:30:56.059 UTC [1443] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-plv8> server # [ 113.491426] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 113.497988] postgres[1443]: ::1 2026-10-22 21:30:56.085 UTC [1443] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-plv8> server # [ 113.520881] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT ROLE vm-test-run-plv8> server # [ 113.526135] postgres[1443]: ::1 2026-10-22 21:30:56.109 UTC [1443] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-plv8> server # [ 113.533031] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT vm-test-run-plv8> server # [ 113.534511] postgres[1443]: ::1 2026-10-22 21:30:56.122 UTC [1443] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-plv8> server # [ 113.547355] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 113.550339] postgres[1443]: ::1 2026-10-22 21:30:56.136 UTC [1443] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-plv8> server # [ 113.579059] postgres[1443]: ::1 2026-10-22 21:30:56.149 UTC [1443] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-plv8> server # [ 113.592143] postgres[1443]: ::1 2026-10-22 21:30:56.159 UTC [1443] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-plv8> server # [ 113.594193] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT ROLE vm-test-run-plv8> server # [ 113.594871] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE SCHEMA vm-test-run-plv8> server # [ 116.113895] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE EXTENSION vm-test-run-plv8> server # [ 116.122448] postgres[1443]: ::1 2026-10-22 21:30:58.683 UTC [1443] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-plv8> server # [ 117.327553] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE EXTENSION vm-test-run-plv8> server # [ 117.339981] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 117.350160] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 117.352004] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 117.380296] postgres[1443]: ::1 2026-10-22 21:30:59.882 UTC [1443] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-plv8> server # [ 117.401981] postgres[1443]: ::1 2026-10-22 21:30:59.897 UTC [1443] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-plv8> server # [ 117.421332] postgres[1443]: ::1 2026-10-22 21:30:59.918 UTC [1443] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-plv8> server # [ 117.422212] postgres[1443]: ::1 2026-10-22 21:30:59.932 UTC [1443] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-plv8> server # [ 117.423075] postgres[1443]: ::1 2026-10-22 21:30:59.947 UTC [1443] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-plv8> server # [ 117.434174] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 117.438377] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT ROLE vm-test-run-plv8> server # [ 117.439613] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT ROLE vm-test-run-plv8> server # [ 117.450943] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT ROLE vm-test-run-plv8> server # [ 117.462672] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT ROLE vm-test-run-plv8> server # [ 117.463651] postgres[1443]: ::1 2026-10-22 21:30:59.959 UTC [1443] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-plv8> server # [ 117.464137] postgres[1443]: ::1 2026-10-22 21:30:59.981 UTC [1443] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-plv8> server # [ 117.468063] postgres[1443]: ::1 2026-10-22 21:31:00.002 UTC [1443] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-plv8> server # [ 117.479997] postgres[1443]: ::1 2026-10-22 21:31:00.018 UTC [1443] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.491342] postgres[1443]: ::1 2026-10-22 21:31:00.028 UTC [1443] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.502316] postgres[1443]: ::1 2026-10-22 21:31:00.054 UTC [1443] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.513587] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT vm-test-run-plv8> server # [ 117.514879] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 117.515578] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 117.529455] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 117.532197] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: GRANT vm-test-run-plv8> server # [ 117.538813] postgres[1443]: ::1 2026-10-22 21:31:00.063 UTC [1443] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.549655] postgres[1443]: ::1 2026-10-22 21:31:00.072 UTC [1443] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.551969] postgres[1443]: ::1 2026-10-22 21:31:00.092 UTC [1443] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-plv8> server # [ 117.553296] postgres[1443]: ::1 2026-10-22 21:31:00.112 UTC [1443] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 117.555014] postgres[1443]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.555677] postgres[1443]: ::1 2026-10-22 21:31:00.126 UTC [1443] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 117.556063] postgres[1443]: on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.557709] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER ROLE vm-test-run-plv8> server # [ 117.559989] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 117.561470] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 117.561959] postgres[1443]: ::1 2026-10-22 21:31:00.147 UTC [1443] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 117.562612] postgres[1443]: on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 117.588752] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 117.589966] postgres[1443]: ::1 2026-10-22 21:31:00.182 UTC [1443] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-plv8> server # [ 117.607428] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER ROLE vm-test-run-plv8> server # [ 117.614424] postgres[1443]: ::1 2026-10-22 21:31:00.206 UTC [1443] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-plv8> server # [ 117.633421] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1433]: ALTER ROLE vm-test-run-plv8> server # [ 117.707623] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-plv8> server # [ 118.147829] postgres[1487]: ::1 2026-10-22 21:31:00.727 UTC [1487] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-plv8> server # [ 118.163831] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE SCHEMA vm-test-run-plv8> server # [ 118.172660] postgres[1487]: ::1 2026-10-22 21:31:00.758 UTC [1487] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-plv8> server # [ 118.189148] postgres[1487]: instance_id uuid NULL, vm-test-run-plv8> server # [ 118.191340] postgres[1487]: id uuid NOT NULL UNIQUE, vm-test-run-plv8> server # [ 118.192099] postgres[1487]: aud varchar(255) NULL, vm-test-run-plv8> server # [ 118.197401] postgres[1487]: "role" varchar(255) NULL, vm-test-run-plv8> server # [ 118.198109] postgres[1487]: email varchar(255) NULL UNIQUE, vm-test-run-plv8> server # [ 118.198446] postgres[1487]: encrypted_password varchar(255) NULL, vm-test-run-plv8> server # [ 118.198906] postgres[1487]: confirmed_at timestamptz NULL, vm-test-run-plv8> server # [ 118.199711] postgres[1487]: invited_at timestamptz NULL, vm-test-run-plv8> server # [ 118.210870] postgres[1487]: confirmation_token varchar(255) NULL, vm-test-run-plv8> server # [ 118.213160] postgres[1487]: confirmation_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 118.213918] postgres[1487]: recovery_token varchar(255) NULL, vm-test-run-plv8> server # [ 118.214468] postgres[1487]: recovery_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 118.214784] postgres[1487]: email_change_token varchar(255) NULL, vm-test-run-plv8> server # [ 118.226078] postgres[1487]: email_change varchar(255) NULL, vm-test-run-plv8> server # [ 118.227058] postgres[1487]: email_change_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 118.227555] postgres[1487]: last_sign_in_at timestamptz NULL, vm-test-run-plv8> server # [ 118.228837] postgres[1487]: raw_app_meta_data jsonb NULL, vm-test-run-plv8> server # [ 118.229263] postgres[1487]: raw_user_meta_data jsonb NULL, vm-test-run-plv8> server # [ 118.229540] postgres[1487]: is_super_admin bool NULL, vm-test-run-plv8> server # [ 118.229914] postgres[1487]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 118.230177] postgres[1487]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 118.230913] postgres[1487]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 118.231808] postgres[1487]: ); vm-test-run-plv8> server # [ 118.326901] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE TABLE vm-test-run-plv8> server # [ 118.334601] postgres[1487]: ::1 2026-10-22 21:31:00.925 UTC [1487] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-plv8> server # [ 118.366053] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE INDEX vm-test-run-plv8> server # [ 118.367552] postgres[1487]: ::1 2026-10-22 21:31:00.959 UTC [1487] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-plv8> server # [ 118.403991] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE INDEX vm-test-run-plv8> server # [ 118.405955] postgres[1487]: ::1 2026-10-22 21:31:00.995 UTC [1487] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-plv8> server # [ 118.414778] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: COMMENT vm-test-run-plv8> server # [ 118.421652] postgres[1487]: ::1 2026-10-22 21:31:01.011 UTC [1487] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-plv8> server # [ 118.423661] postgres[1487]: instance_id uuid NULL, vm-test-run-plv8> server # [ 118.430504] postgres[1487]: id bigserial NOT NULL, vm-test-run-plv8> server # [ 118.431404] postgres[1487]: "token" varchar(255) NULL, vm-test-run-plv8> server # [ 118.431940] postgres[1487]: user_id varchar(255) NULL, vm-test-run-plv8> server # [ 118.433136] postgres[1487]: revoked bool NULL, vm-test-run-plv8> server # [ 118.434489] postgres[1487]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 118.435341] postgres[1487]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 118.435780] postgres[1487]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 118.439889] postgres[1487]: ); vm-test-run-plv8> server # [ 118.496150] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE TABLE vm-test-run-plv8> server # [ 118.498514] postgres[1487]: ::1 2026-10-22 21:31:01.090 UTC [1487] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-plv8> server # [ 118.536844] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE INDEX vm-test-run-plv8> server # [ 118.537951] postgres[1487]: ::1 2026-10-22 21:31:01.128 UTC [1487] 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-plv8> server # [ 118.573124] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE INDEX vm-test-run-plv8> server # [ 118.574308] postgres[1487]: ::1 2026-10-22 21:31:01.162 UTC [1487] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-plv8> server # [ 118.598325] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE INDEX vm-test-run-plv8> server # [ 118.599951] postgres[1487]: ::1 2026-10-22 21:31:01.192 UTC [1487] 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-plv8> server # [ 118.609689] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: COMMENT vm-test-run-plv8> server # [ 118.610575] postgres[1487]: ::1 2026-10-22 21:31:01.202 UTC [1487] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-plv8> server # [ 118.611722] postgres[1487]: id uuid NOT NULL, vm-test-run-plv8> server # [ 118.618624] postgres[1487]: uuid uuid NULL, vm-test-run-plv8> server # [ 118.619413] postgres[1487]: raw_base_config text NULL, vm-test-run-plv8> server # [ 118.622046] postgres[1487]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 118.622678] postgres[1487]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 118.623434] postgres[1487]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 118.623761] postgres[1487]: ); vm-test-run-plv8> server # [ 118.676519] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE TABLE vm-test-run-plv8> server # [ 118.681217] postgres[1487]: ::1 2026-10-22 21:31:01.267 UTC [1487] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-plv8> server # [ 118.683648] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: COMMENT vm-test-run-plv8> server # [ 118.689062] postgres[1487]: ::1 2026-10-22 21:31:01.278 UTC [1487] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-plv8> server # [ 118.690121] postgres[1487]: instance_id uuid NULL, vm-test-run-plv8> server # [ 118.690890] postgres[1487]: id uuid NOT NULL, vm-test-run-plv8> server # [ 118.691537] postgres[1487]: payload json NULL, vm-test-run-plv8> server # [ 118.693712] postgres[1487]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 118.698143] postgres[1487]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 118.703319] postgres[1487]: ); vm-test-run-plv8> server # [ 118.748799] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE TABLE vm-test-run-plv8> server # [ 118.752151] postgres[1487]: ::1 2026-10-22 21:31:01.344 UTC [1487] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id); vm-test-run-plv8> server # [ 118.779997] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE INDEX vm-test-run-plv8> server # [ 118.781853] postgres[1487]: ::1 2026-10-22 21:31:01.372 UTC [1487] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-plv8> server # [ 118.788088] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: COMMENT vm-test-run-plv8> server # [ 118.793162] postgres[1487]: ::1 2026-10-22 21:31:01.383 UTC [1487] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-plv8> server # [ 118.796197] postgres[1487]: "version" varchar(255) NOT NULL, vm-test-run-plv8> server # [ 118.797958] postgres[1487]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-plv8> server # [ 118.798418] postgres[1487]: ); vm-test-run-plv8> server # [ 118.826771] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE TABLE vm-test-run-plv8> server # [ 118.827870] postgres[1487]: ::1 2026-10-22 21:31:01.419 UTC [1487] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-plv8> server # [ 118.836749] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: COMMENT vm-test-run-plv8> server # [ 118.854092] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: INSERT 0 7 vm-test-run-plv8> server # [ 118.857006] postgres[1487]: ::1 2026-10-22 21:31:01.448 UTC [1487] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-plv8> server # [ 118.858854] postgres[1487]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-plv8> server # [ 118.863563] postgres[1487]: $$ language sql stable; vm-test-run-plv8> server # [ 118.874644] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE FUNCTION vm-test-run-plv8> server # [ 118.875673] postgres[1487]: ::1 2026-10-22 21:31:01.464 UTC [1487] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-plv8> server # [ 118.881990] postgres[1487]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-plv8> server # [ 118.885345] postgres[1487]: $$ language sql stable; vm-test-run-plv8> server # [ 118.885874] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE FUNCTION vm-test-run-plv8> server # [ 118.888875] postgres[1487]: ::1 2026-10-22 21:31:01.480 UTC [1487] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-plv8> server # [ 118.890016] postgres[1487]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-plv8> server # [ 118.890530] postgres[1487]: $$ language sql stable; vm-test-run-plv8> server # [ 118.897464] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE FUNCTION vm-test-run-plv8> server # [ 118.898769] postgres[1487]: ::1 2026-10-22 21:31:01.490 UTC [1487] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-plv8> server # [ 118.908278] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: GRANT vm-test-run-plv8> server # [ 118.915151] postgres[1487]: ::1 2026-10-22 21:31:01.506 UTC [1487] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 118.925356] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: CREATE ROLE vm-test-run-plv8> server # [ 118.928923] postgres[1487]: ::1 2026-10-22 21:31:01.519 UTC [1487] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 118.937927] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: GRANT vm-test-run-plv8> server # [ 118.942127] postgres[1487]: ::1 2026-10-22 21:31:01.533 UTC [1487] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 118.956445] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: GRANT vm-test-run-plv8> server # [ 118.961782] postgres[1487]: ::1 2026-10-22 21:31:01.551 UTC [1487] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 118.968072] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: GRANT vm-test-run-plv8> server # [ 118.969773] postgres[1487]: ::1 2026-10-22 21:31:01.561 UTC [1487] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-plv8> server # [ 118.991670] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: ALTER ROLE vm-test-run-plv8> server # [ 118.999429] postgres[1487]: ::1 2026-10-22 21:31:01.590 UTC [1487] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 119.045992] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: ALTER TABLE vm-test-run-plv8> server # [ 119.061414] postgres[1487]: ::1 2026-10-22 21:31:01.648 UTC [1487] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 119.087456] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: ALTER TABLE vm-test-run-plv8> server # [ 119.090681] postgres[1487]: ::1 2026-10-22 21:31:01.679 UTC [1487] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 119.110522] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: ALTER TABLE vm-test-run-plv8> server # [ 119.117813] postgres[1487]: ::1 2026-10-22 21:31:01.701 UTC [1487] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 119.137855] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: ALTER TABLE vm-test-run-plv8> server # [ 119.169017] postgres[1487]: ::1 2026-10-22 21:31:01.728 UTC [1487] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 119.202017] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1486]: ALTER TABLE vm-test-run-plv8> server # [ 119.226361] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-plv8> server # [ 119.696698] postgres[1496]: ::1 2026-10-22 21:31:02.263 UTC [1496] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-plv8> server # [ 119.742820] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1491]: CREATE SCHEMA vm-test-run-plv8> server # [ 119.743838] postgres[1496]: ::1 2026-10-22 21:31:02.310 UTC [1496] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 119.744734] postgres[1496]: ::1 2026-10-22 21:31:02.327 UTC [1496] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-plv8> server # [ 119.751549] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1491]: CREATE ROLE vm-test-run-plv8> server # [ 119.757261] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1491]: ALTER ROLE vm-test-run-plv8> server # [ 119.765321] postgres[1496]: ::1 2026-10-22 21:31:02.348 UTC [1496] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-plv8> server # [ 119.802762] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1491]: GRANT vm-test-run-plv8> server # [ 119.845664] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1491]: DO vm-test-run-plv8> server # [ 119.925183] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-plv8> server # [ 120.399273] postgres[1505]: ::1 2026-10-22 21:31:02.966 UTC [1505] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-plv8> server # [ 120.441409] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: ALTER ROLE vm-test-run-plv8> server # [ 120.459871] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: ALTER ROLE vm-test-run-plv8> server # [ 120.473400] postgres[1505]: ::1 2026-10-22 21:31:03.020 UTC [1505] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-plv8> server # [ 120.474683] postgres[1505]: ::1 2026-10-22 21:31:03.046 UTC [1505] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-plv8> server # [ 120.475590] postgres[1505]: RETURNS event_trigger vm-test-run-plv8> server # [ 120.485227] postgres[1505]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 120.497512] postgres[1505]: AS $$ vm-test-run-plv8> server # [ 120.498501] postgres[1505]: DECLARE vm-test-run-plv8> server # [ 120.499238] postgres[1505]: schema_is_cron bool; vm-test-run-plv8> server # [ 120.509536] postgres[1505]: BEGIN vm-test-run-plv8> server # [ 120.510333] postgres[1505]: schema_is_cron = ( vm-test-run-plv8> server # [ 120.511388] postgres[1505]: SELECT n.nspname = 'cron' vm-test-run-plv8> server # [ 120.511754] postgres[1505]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 120.517350] postgres[1505]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-plv8> server # [ 120.525756] postgres[1505]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 120.526914] postgres[1505]: ); vm-test-run-plv8> server # [ 120.537468] postgres[1505]: vm-test-run-plv8> server # [ 120.538023] postgres[1505]: IF schema_is_cron vm-test-run-plv8> server # [ 120.538321] postgres[1505]: THEN vm-test-run-plv8> server # [ 120.538873] postgres[1505]: grant usage on schema cron to postgres with grant option; vm-test-run-plv8> server # [ 120.539876] postgres[1505]: vm-test-run-plv8> server # [ 120.540149] postgres[1505]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 120.545244] postgres[1505]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-plv8> server # [ 120.546099] postgres[1505]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 120.546940] postgres[1505]: vm-test-run-plv8> server # [ 120.547920] postgres[1505]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 120.550401] postgres[1505]: on sequences to postgres with grant option; vm-test-run-plv8> server # [ 120.553880] postgres[1505]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 120.554356] postgres[1505]: on tables to postgres with grant option; vm-test-run-plv8> server # [ 120.555705] postgres[1505]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 120.557959] postgres[1505]: on functions to postgres with grant option; vm-test-run-plv8> server # [ 120.560790] postgres[1505]: vm-test-run-plv8> server # [ 120.562149] postgres[1505]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-plv8> server # [ 120.563532] postgres[1505]: vm-test-run-plv8> server # [ 120.563997] postgres[1505]: END IF; vm-test-run-plv8> server # [ 120.565692] postgres[1505]: vm-test-run-plv8> server # [ 120.566226] postgres[1505]: END; vm-test-run-plv8> server # [ 120.566738] postgres[1505]: $$; vm-test-run-plv8> server # [ 120.569311] postgres[1505]: ::1 2026-10-22 21:31:03.132 UTC [1505] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA') vm-test-run-plv8> server # [ 120.571979] postgres[1505]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-plv8> server # [ 120.573288] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: CREATE FUNCTION vm-test-run-plv8> server # [ 120.577181] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 120.578249] postgres[1505]: ::1 2026-10-22 21:31:03.170 UTC [1505] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-plv8> server # [ 120.597328] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: COMMENT vm-test-run-plv8> server # [ 120.599384] postgres[1505]: ::1 2026-10-22 21:31:03.190 UTC [1505] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-plv8> server # [ 120.600073] postgres[1505]: RETURNS event_trigger vm-test-run-plv8> server # [ 120.602745] postgres[1505]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 120.605732] postgres[1505]: AS $$ vm-test-run-plv8> server # [ 120.606198] postgres[1505]: BEGIN vm-test-run-plv8> server # [ 120.606490] postgres[1505]: IF EXISTS ( vm-test-run-plv8> server # [ 120.606748] postgres[1505]: SELECT 1 vm-test-run-plv8> server # [ 120.606995] postgres[1505]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 120.607352] postgres[1505]: JOIN pg_extension AS ext vm-test-run-plv8> server # [ 120.607687] postgres[1505]: ON ev.objid = ext.oid vm-test-run-plv8> server # [ 120.607930] postgres[1505]: WHERE ext.extname = 'pg_net' vm-test-run-plv8> server # [ 120.613815] postgres[1505]: ) vm-test-run-plv8> server # [ 120.616044] postgres[1505]: THEN vm-test-run-plv8> server # [ 120.621562] postgres[1505]: IF NOT EXISTS ( vm-test-run-plv8> server # [ 120.623536] postgres[1505]: SELECT 1 vm-test-run-plv8> server # [ 120.624907] postgres[1505]: FROM pg_roles vm-test-run-plv8> server # [ 120.625826] postgres[1505]: WHERE rolname = 'supabase_functions_admin' vm-test-run-plv8> server # [ 120.626652] postgres[1505]: ) vm-test-run-plv8> server # [ 120.627618] postgres[1505]: THEN vm-test-run-plv8> server # [ 120.633206] postgres[1505]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 120.634938] postgres[1505]: END IF; vm-test-run-plv8> server # [ 120.635696] postgres[1505]: vm-test-run-plv8> server # [ 120.636300] postgres[1505]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.642595] postgres[1505]: vm-test-run-plv8> server # [ 120.643069] postgres[1505]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-plv8> server # [ 120.645050] postgres[1505]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-plv8> server # [ 120.646542] postgres[1505]: vm-test-run-plv8> server # [ 120.647823] postgres[1505]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-plv8> server # [ 120.652894] postgres[1505]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-plv8> server # [ 120.653699] postgres[1505]: vm-test-run-plv8> server # [ 120.654143] postgres[1505]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-plv8> server # [ 120.654918] postgres[1505]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-plv8> server # [ 120.656296] postgres[1505]: vm-test-run-plv8> server # [ 120.662464] postgres[1505]: 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-plv8> server # [ 120.667767] postgres[1505]: 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-plv8> server # [ 120.676248] postgres[1505]: END IF; vm-test-run-plv8> server # [ 120.681505] postgres[1505]: END; vm-test-run-plv8> server # [ 120.681955] postgres[1505]: $$; vm-test-run-plv8> server # [ 120.682691] postgres[1505]: ::1 2026-10-22 21:31:03.212 UTC [1505] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-plv8> server # [ 120.689851] postgres[1505]: ::1 2026-10-22 21:31:03.260 UTC [1505] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-plv8> server # [ 120.690859] postgres[1505]: ::1 2026-10-22 21:31:03.271 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-plv8> server # [ 120.691928] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: CREATE FUNCTION vm-test-run-plv8> server # [ 120.694122] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: COMMENT vm-test-run-plv8> server # [ 120.701345] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: DO vm-test-run-plv8> server # [ 120.711032] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: CREATE ROLE vm-test-run-plv8> server # [ 120.712709] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.714747] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.716676] postgres[1505]: ::1 2026-10-22 21:31:03.284 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 120.717414] postgres[1505]: ::1 2026-10-22 21:31:03.300 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 120.718009] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.723765] postgres[1505]: ::1 2026-10-22 21:31:03.314 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 120.741031] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.742160] postgres[1505]: ::1 2026-10-22 21:31:03.331 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 120.745624] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.749944] postgres[1505]: ::1 2026-10-22 21:31:03.342 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 120.757767] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.758720] postgres[1505]: ::1 2026-10-22 21:31:03.350 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 120.765866] postgres[1505]: ::1 2026-10-22 21:31:03.358 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 120.767908] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.789925] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.802964] postgres[1505]: ::1 2026-10-22 21:31:03.390 UTC [1505] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 120.905772] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: GRANT vm-test-run-plv8> server # [ 120.918546] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1501]: DO vm-test-run-plv8> server # [ 120.978679] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running pgbouncer auth schema... vm-test-run-plv8> server # [ 121.317292] postgres[1519]: ::1 2026-10-22 21:31:03.876 UTC [1519] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-plv8> server # [ 121.318859] postgres[1519]: ::1 2026-10-22 21:31:03.903 UTC [1519] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-plv8> server # [ 121.327765] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1516]: CREATE ROLE vm-test-run-plv8> server # [ 121.337825] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1516]: REVOKE vm-test-run-plv8> server # [ 121.338681] postgres[1519]: ::1 2026-10-22 21:31:03.919 UTC [1519] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-plv8> server # [ 121.413590] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1516]: CREATE SCHEMA vm-test-run-plv8> server # [ 121.418451] postgres[1519]: ::1 2026-10-22 21:31:04.010 UTC [1519] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-plv8> server # [ 121.423006] postgres[1519]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-plv8> server # [ 121.423785] postgres[1519]: $$ vm-test-run-plv8> server # [ 121.431377] postgres[1519]: BEGIN vm-test-run-plv8> server # [ 121.433105] postgres[1519]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-plv8> server # [ 121.433962] postgres[1519]: vm-test-run-plv8> server # [ 121.437149] postgres[1519]: RETURN QUERY vm-test-run-plv8> server # [ 121.442442] postgres[1519]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-plv8> server # [ 121.443439] postgres[1519]: WHERE usename = p_usename; vm-test-run-plv8> server # [ 121.443789] postgres[1519]: END; vm-test-run-plv8> server # [ 121.444055] postgres[1519]: $$ LANGUAGE plpgsql vm-test-run-plv8> server # [ 121.445454] postgres[1519]: SET search_path = '' vm-test-run-plv8> server # [ 121.457180] postgres[1519]: SECURITY DEFINER; vm-test-run-plv8> server # [ 121.459614] postgres[1519]: ::1 2026-10-22 21:31:04.050 UTC [1519] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-plv8> server # [ 121.466352] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1516]: CREATE FUNCTION vm-test-run-plv8> server # [ 121.467934] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1516]: REVOKE vm-test-run-plv8> server # [ 121.471794] postgres[1519]: ::1 2026-10-22 21:31:04.062 UTC [1519] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-plv8> server # [ 121.500907] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1516]: GRANT vm-test-run-plv8> server # [ 121.539053] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running stat extension... vm-test-run-plv8> server # [ 121.887654] postgres[1523]: ::1 2026-10-22 21:31:04.474 UTC [1523] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-plv8> server # [ 121.913312] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1522]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-plv8> server # [ 121.918928] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1522]: CREATE SCHEMA vm-test-run-plv8> server # [ 121.926768] postgres[1523]: ::1 2026-10-22 21:31:04.514 UTC [1523] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-plv8> server # [ 123.295376] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1522]: CREATE EXTENSION vm-test-run-plv8> server # [ 123.375966] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-plv8> server # [ 123.871576] postgres[1545]: ::1 2026-10-22 21:31:06.442 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-plv8> server # [ 123.896756] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 123.917646] postgres[1545]: ::1 2026-10-22 21:31:06.509 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 123.962909] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 123.963955] postgres[1545]: ::1 2026-10-22 21:31:06.556 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 123.983132] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.008425] postgres[1545]: ::1 2026-10-22 21:31:06.572 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 124.010424] postgres[1545]: ::1 2026-10-22 21:31:06.586 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 124.016940] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.018787] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.030193] postgres[1545]: ::1 2026-10-22 21:31:06.600 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 124.031957] postgres[1545]: ::1 2026-10-22 21:31:06.608 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 124.038456] postgres[1545]: ::1 2026-10-22 21:31:06.614 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 124.039538] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.040094] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.046496] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.047842] postgres[1545]: ::1 2026-10-22 21:31:06.629 UTC [1545] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 124.081520] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: GRANT vm-test-run-plv8> server # [ 124.167915] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: DO vm-test-run-plv8> server # [ 124.174892] postgres[1545]: ::1 2026-10-22 21:31:06.760 UTC [1545] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-plv8> server # [ 124.180889] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1540]: ALTER ROLE vm-test-run-plv8> server # [ 124.250341] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-plv8> server # [ 124.502253] postgres[1551]: ::1 2026-10-22 21:31:07.086 UTC [1551] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.519247] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.521703] postgres[1551]: ::1 2026-10-22 21:31:07.106 UTC [1551] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.538396] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.549050] postgres[1551]: ::1 2026-10-22 21:31:07.133 UTC [1551] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.551415] postgres[1551]: ::1 2026-10-22 21:31:07.139 UTC [1551] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.559403] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.573711] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER TABLE vm-test-run-plv8> server # [ 124.575103] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER TABLE vm-test-run-plv8> server # [ 124.576564] postgres[1551]: ::1 2026-10-22 21:31:07.153 UTC [1551] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.579572] postgres[1551]: ::1 2026-10-22 21:31:07.163 UTC [1551] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.584959] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER TABLE vm-test-run-plv8> server # [ 124.587157] postgres[1551]: ::1 2026-10-22 21:31:07.179 UTC [1551] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.594676] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER TABLE vm-test-run-plv8> server # [ 124.597410] postgres[1551]: ::1 2026-10-22 21:31:07.188 UTC [1551] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 124.608448] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER TABLE vm-test-run-plv8> server # [ 124.612785] postgres[1551]: ::1 2026-10-22 21:31:07.202 UTC [1551] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 124.617912] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.625698] postgres[1551]: ::1 2026-10-22 21:31:07.214 UTC [1551] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 124.632131] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.637448] postgres[1551]: ::1 2026-10-22 21:31:07.228 UTC [1551] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 124.647723] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.652902] postgres[1551]: ::1 2026-10-22 21:31:07.242 UTC [1551] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 124.662777] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: GRANT vm-test-run-plv8> server # [ 124.666028] postgres[1551]: ::1 2026-10-22 21:31:07.258 UTC [1551] 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-plv8> server # [ 124.678610] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 124.683840] postgres[1551]: ::1 2026-10-22 21:31:07.272 UTC [1551] 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-plv8> server # [ 124.691531] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 124.698275] postgres[1551]: ::1 2026-10-22 21:31:07.286 UTC [1551] 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-plv8> server # [ 124.706175] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1550]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 124.774551] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-plv8> server # [ 125.001996] postgres[1553]: ::1 2026-10-22 21:31:07.590 UTC [1553] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-plv8> server # [ 125.032787] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1552]: CREATE SCHEMA vm-test-run-plv8> server # [ 125.063970] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-plv8> server # [ 125.435149] postgres[1558]: ::1 2026-10-22 21:31:08.013 UTC [1558] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-plv8> server # [ 125.467634] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1554]: GRANT vm-test-run-plv8> server # [ 125.475773] postgres[1558]: ::1 2026-10-22 21:31:08.056 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 125.481784] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1554]: GRANT vm-test-run-plv8> server # [ 125.489096] postgres[1558]: ::1 2026-10-22 21:31:08.078 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 125.494781] postgres[1558]: ::1 2026-10-22 21:31:08.082 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 125.495767] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1554]: GRANT vm-test-run-plv8> server # [ 125.504780] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1554]: GRANT vm-test-run-plv8> server # [ 125.551021] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-plv8> server # [ 126.062337] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1560]: DO vm-test-run-plv8> server # [ 126.063586] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1560]: DO vm-test-run-plv8> server # [ 126.080578] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1560]: DO vm-test-run-plv8> server # [ 126.143777] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-plv8> server # [ 126.561315] postgres[1572]: ::1 2026-10-22 21:31:09.134 UTC [1572] 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-plv8> server # [ 126.589654] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1569]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 126.591461] postgres[1572]: ::1 2026-10-22 21:31:09.176 UTC [1572] 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-plv8> server # [ 126.598290] postgres[1572]: ::1 2026-10-22 21:31:09.186 UTC [1572] 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-plv8> server # [ 126.602035] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1569]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 126.607705] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1569]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 126.667043] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-plv8> server # [ 127.139467] postgres[1585]: ::1 2026-10-22 21:31:09.720 UTC [1585] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-plv8> server # [ 127.175397] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1579]: ALTER ROLE vm-test-run-plv8> server # [ 127.235522] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-plv8> server # [ 127.630503] postgres[1591]: ::1 2026-10-22 21:31:10.207 UTC [1591] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-plv8> server # [ 127.631991] postgres[1591]: ::1 2026-10-22 21:31:10.223 UTC [1591] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-plv8> server # [ 127.643358] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-plv8> server # [ 127.650276] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 127.651721] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-plv8> server # [ 127.657365] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: DROP FUNCTION vm-test-run-plv8> server # [ 127.658229] postgres[1591]: ::1 2026-10-22 21:31:10.231 UTC [1591] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 127.659175] postgres[1591]: DECLARE vm-test-run-plv8> server # [ 127.665192] postgres[1591]: cmd record; vm-test-run-plv8> server # [ 127.666803] postgres[1591]: BEGIN vm-test-run-plv8> server # [ 127.667868] postgres[1591]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-plv8> server # [ 127.673368] postgres[1591]: LOOP vm-test-run-plv8> server # [ 127.674532] postgres[1591]: IF cmd.command_tag IN ( vm-test-run-plv8> server # [ 127.675370] postgres[1591]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-plv8> server # [ 127.675748] postgres[1591]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-plv8> server # [ 127.685770] postgres[1591]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-plv8> server # [ 127.687040] postgres[1591]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-plv8> server # [ 127.687669] postgres[1591]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-plv8> server # [ 127.688016] postgres[1591]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-plv8> server # [ 127.697819] postgres[1591]: , 'CREATE TRIGGER' vm-test-run-plv8> server # [ 127.698252] postgres[1591]: , 'CREATE TYPE' vm-test-run-plv8> server # [ 127.698746] postgres[1591]: , 'CREATE RULE' vm-test-run-plv8> server # [ 127.699857] postgres[1591]: , 'COMMENT' vm-test-run-plv8> server # [ 127.713055] postgres[1591]: ) vm-test-run-plv8> server # [ 127.713808] postgres[1591]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-plv8> server # [ 127.715650] postgres[1591]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-plv8> server # [ 127.722283] postgres[1591]: THEN vm-test-run-plv8> server # [ 127.741704] postgres[1591]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 127.742373] postgres[1591]: END IF; vm-test-run-plv8> server # [ 127.743411] postgres[1591]: END LOOP; vm-test-run-plv8> server # [ 127.753646] postgres[1591]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 127.754975] postgres[1591]: ::1 2026-10-22 21:31:10.266 UTC [1591] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 127.755917] postgres[1591]: DECLARE vm-test-run-plv8> server # [ 127.756421] postgres[1591]: obj record; vm-test-run-plv8> server # [ 127.758416] postgres[1591]: BEGIN vm-test-run-plv8> server # [ 127.758766] postgres[1591]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-plv8> server # [ 127.759484] postgres[1591]: LOOP vm-test-run-plv8> server # [ 127.764868] postgres[1591]: IF obj.object_type IN ( vm-test-run-plv8> server # [ 127.767582] postgres[1591]: 'schema' vm-test-run-plv8> server # [ 127.768171] postgres[1591]: , 'table' vm-test-run-plv8> server # [ 127.779304] postgres[1591]: , 'foreign table' vm-test-run-plv8> server # [ 127.781438] postgres[1591]: , 'view' vm-test-run-plv8> server # [ 127.782079] postgres[1591]: , 'materialized view' vm-test-run-plv8> server # [ 127.783294] postgres[1591]: , 'function' vm-test-run-plv8> server # [ 127.788665] postgres[1591]: , 'trigger' vm-test-run-plv8> server # [ 127.790011] postgres[1591]: , 'type' vm-test-run-plv8> server # [ 127.790736] postgres[1591]: , 'rule' vm-test-run-plv8> server # [ 127.791499] postgres[1591]: ) vm-test-run-plv8> server # [ 127.792153] postgres[1591]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-plv8> server # [ 127.797671] postgres[1591]: THEN vm-test-run-plv8> server # [ 127.799114] postgres[1591]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 127.799631] postgres[1591]: END IF; vm-test-run-plv8> server # [ 127.800308] postgres[1591]: END LOOP; vm-test-run-plv8> server # [ 127.814257] postgres[1591]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 127.814810] postgres[1591]: ::1 2026-10-22 21:31:10.288 UTC [1591] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-plv8> server # [ 127.815750] postgres[1591]: ::1 2026-10-22 21:31:10.294 UTC [1591] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-plv8> server # [ 127.816142] postgres[1591]: ON ddl_command_end vm-test-run-plv8> server # [ 127.826676] postgres[1591]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-plv8> server # [ 127.827298] postgres[1591]: ::1 2026-10-22 21:31:10.312 UTC [1591] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-plv8> server # [ 127.837194] postgres[1591]: ::1 2026-10-22 21:31:10.318 UTC [1591] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-plv8> server # [ 127.839662] postgres[1591]: ON sql_drop vm-test-run-plv8> server # [ 127.853140] postgres[1591]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-plv8> server # [ 127.855455] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: CREATE FUNCTION vm-test-run-plv8> server # [ 127.876947] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: CREATE FUNCTION vm-test-run-plv8> server # [ 127.878876] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-plv8> server # [ 127.879991] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 127.888783] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 127.889761] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-plv8> server # [ 127.891119] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 127.891920] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1589]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 127.898608] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-plv8> server # [ 128.800308] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1592]: DO vm-test-run-plv8> server # [ 128.861895] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-plv8> server # [ 129.233532] postgres[1598]: ::1 2026-10-22 21:31:11.819 UTC [1598] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-plv8> server # [ 129.355792] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: CREATE SCHEMA vm-test-run-plv8> server # [ 129.363839] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping vm-test-run-plv8> server # [ 129.381680] postgres[1598]: ::1 2026-10-22 21:31:11.939 UTC [1598] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-plv8> server # [ 129.382866] postgres[1598]: ::1 2026-10-22 21:31:11.970 UTC [1598] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 129.390534] postgres[1598]: "operationName" text default null, vm-test-run-plv8> server # [ 129.397689] postgres[1598]: query text default null, vm-test-run-plv8> server # [ 129.398510] postgres[1598]: variables jsonb default null, vm-test-run-plv8> server # [ 129.401095] postgres[1598]: extensions jsonb default null vm-test-run-plv8> server # [ 129.401939] postgres[1598]: ) vm-test-run-plv8> server # [ 129.403029] postgres[1598]: returns jsonb vm-test-run-plv8> server # [ 129.404116] postgres[1598]: language plpgsql vm-test-run-plv8> server # [ 129.413944] postgres[1598]: as $$ vm-test-run-plv8> server # [ 129.415053] postgres[1598]: DECLARE vm-test-run-plv8> server # [ 129.415558] postgres[1598]: server_version float; vm-test-run-plv8> server # [ 129.415864] postgres[1598]: BEGIN vm-test-run-plv8> server # [ 129.416453] postgres[1598]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 129.422981] postgres[1598]: vm-test-run-plv8> server # [ 129.423567] postgres[1598]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 129.423995] postgres[1598]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 129.424293] postgres[1598]: 'data', null::jsonb, vm-test-run-plv8> server # [ 129.429080] postgres[1598]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-plv8> server # [ 129.429872] postgres[1598]: ); vm-test-run-plv8> server # [ 129.430937] postgres[1598]: ELSE vm-test-run-plv8> server # [ 129.432254] postgres[1598]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 129.438319] postgres[1598]: 'data', null::jsonb, vm-test-run-plv8> server # [ 129.439589] postgres[1598]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-plv8> server # [ 129.446849] postgres[1598]: ); vm-test-run-plv8> server # [ 129.457696] postgres[1598]: END IF; vm-test-run-plv8> server # [ 129.460091] postgres[1598]: END; vm-test-run-plv8> server # [ 129.467027] postgres[1598]: $$; vm-test-run-plv8> server # [ 129.477457] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: DROP FUNCTION vm-test-run-plv8> server # [ 129.478358] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: CREATE FUNCTION vm-test-run-plv8> server # [ 129.497761] postgres[1598]: ::1 2026-10-22 21:31:12.061 UTC [1598] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.560829] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: GRANT vm-test-run-plv8> server # [ 129.570221] postgres[1598]: ::1 2026-10-22 21:31:12.162 UTC [1598] 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-plv8> server # [ 129.604131] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.610369] postgres[1598]: ::1 2026-10-22 21:31:12.199 UTC [1598] 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-plv8> server # [ 129.649401] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.661058] postgres[1598]: ::1 2026-10-22 21:31:12.250 UTC [1598] 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-plv8> server # [ 129.693127] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.706441] postgres[1598]: ::1 2026-10-22 21:31:12.294 UTC [1598] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 129.708556] postgres[1598]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.733866] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.736674] postgres[1598]: ::1 2026-10-22 21:31:12.322 UTC [1598] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 129.737509] postgres[1598]: on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.751556] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.765638] postgres[1598]: ::1 2026-10-22 21:31:12.344 UTC [1598] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 129.766708] postgres[1598]: on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.778665] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.779668] postgres[1598]: ::1 2026-10-22 21:31:12.367 UTC [1598] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 129.787309] postgres[1598]: RETURNS event_trigger vm-test-run-plv8> server # [ 129.793674] postgres[1598]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 129.808093] postgres[1598]: AS $func$ vm-test-run-plv8> server # [ 129.808685] postgres[1598]: DECLARE vm-test-run-plv8> server # [ 129.809130] postgres[1598]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 129.825929] postgres[1598]: BEGIN vm-test-run-plv8> server # [ 129.827323] postgres[1598]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 129.837603] postgres[1598]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 129.838389] postgres[1598]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 129.839748] postgres[1598]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 129.840190] postgres[1598]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 129.849506] postgres[1598]: ); vm-test-run-plv8> server # [ 129.849973] postgres[1598]: vm-test-run-plv8> server # [ 129.850237] postgres[1598]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 129.850489] postgres[1598]: THEN vm-test-run-plv8> server # [ 129.850866] postgres[1598]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.851567] postgres[1598]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.859604] postgres[1598]: vm-test-run-plv8> server # [ 129.869081] postgres[1598]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.870587] postgres[1598]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.872130] postgres[1598]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.881442] postgres[1598]: vm-test-run-plv8> server # [ 129.881919] postgres[1598]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 129.882236] postgres[1598]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 129.882843] postgres[1598]: "operationName" text default null, vm-test-run-plv8> server # [ 129.883455] postgres[1598]: query text default null, vm-test-run-plv8> server # [ 129.884095] postgres[1598]: variables jsonb default null, vm-test-run-plv8> server # [ 129.890783] postgres[1598]: extensions jsonb default null vm-test-run-plv8> server # [ 129.891931] postgres[1598]: ) vm-test-run-plv8> server # [ 129.902869] postgres[1598]: returns jsonb vm-test-run-plv8> server # [ 129.913404] postgres[1598]: language sql vm-test-run-plv8> server # [ 129.914135] postgres[1598]: as $$ vm-test-run-plv8> server # [ 129.914706] postgres[1598]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-plv8> server # [ 129.915109] postgres[1598]: $$; vm-test-run-plv8> server # [ 129.916024] postgres[1598]: vm-test-run-plv8> server # [ 129.918425] postgres[1598]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 129.930008] postgres[1598]: END IF; vm-test-run-plv8> server # [ 129.938744] postgres[1598]: vm-test-run-plv8> server # [ 129.946028] postgres[1598]: END; vm-test-run-plv8> server # [ 129.958499] postgres[1598]: $func$; vm-test-run-plv8> server # [ 129.962818] postgres[1598]: ::1 2026-10-22 21:31:12.387 UTC [1598] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-plv8> server # [ 129.963851] postgres[1598]: ::1 2026-10-22 21:31:12.400 UTC [1598] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION') vm-test-run-plv8> server # [ 129.982085] postgres[1598]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-plv8> server # [ 129.983784] postgres[1598]: ::1 2026-10-22 21:31:12.416 UTC [1598] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql'; vm-test-run-plv8> server # [ 129.994700] postgres[1598]: ::1 2026-10-22 21:31:12.428 UTC [1598] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-plv8> server # [ 129.997576] postgres[1598]: RETURNS event_trigger vm-test-run-plv8> server # [ 130.009494] postgres[1598]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 130.010284] postgres[1598]: AS $func$ vm-test-run-plv8> server # [ 130.010612] postgres[1598]: DECLARE vm-test-run-plv8> server # [ 130.011444] postgres[1598]: graphql_is_dropped bool; vm-test-run-plv8> server # [ 130.012291] postgres[1598]: BEGIN vm-test-run-plv8> server # [ 130.022772] postgres[1598]: graphql_is_dropped = ( vm-test-run-plv8> server # [ 130.023923] postgres[1598]: SELECT ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 130.029885] postgres[1598]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-plv8> server # [ 130.030896] postgres[1598]: WHERE ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 130.038442] postgres[1598]: ); vm-test-run-plv8> server # [ 130.045118] postgres[1598]: vm-test-run-plv8> server # [ 130.045919] postgres[1598]: IF graphql_is_dropped vm-test-run-plv8> server # [ 130.046321] postgres[1598]: THEN vm-test-run-plv8> server # [ 130.046576] postgres[1598]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 130.046847] postgres[1598]: "operationName" text default null, vm-test-run-plv8> server # [ 130.047088] postgres[1598]: query text default null, vm-test-run-plv8> server # [ 130.047362] postgres[1598]: variables jsonb default null, vm-test-run-plv8> server # [ 130.047600] postgres[1598]: extensions jsonb default null vm-test-run-plv8> server # [ 130.047836] postgres[1598]: ) vm-test-run-plv8> server # [ 130.048268] postgres[1598]: returns jsonb vm-test-run-plv8> server # [ 130.048525] postgres[1598]: language plpgsql vm-test-run-plv8> server # [ 130.048748] postgres[1598]: as $$ vm-test-run-plv8> server # [ 130.048960] postgres[1598]: DECLARE vm-test-run-plv8> server # [ 130.049176] postgres[1598]: server_version float; vm-test-run-plv8> server # [ 130.059740] postgres[1598]: BEGIN vm-test-run-plv8> server # [ 130.066327] postgres[1598]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 130.066753] postgres[1598]: vm-test-run-plv8> server # [ 130.066985] postgres[1598]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 130.068014] postgres[1598]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 130.075711] postgres[1598]: 'data', null::jsonb, vm-test-run-plv8> server # [ 130.080944] postgres[1598]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-plv8> server # [ 130.081785] postgres[1598]: ); vm-test-run-plv8> server # [ 130.084081] postgres[1598]: ELSE vm-test-run-plv8> server # [ 130.102049] postgres[1598]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 130.113285] postgres[1598]: 'data', null::jsonb, vm-test-run-plv8> server # [ 130.123328] postgres[1598]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-plv8> server # [ 130.125823] postgres[1598]: ); vm-test-run-plv8> server # [ 130.143533] postgres[1598]: END IF; vm-test-run-plv8> server # [ 130.158779] postgres[1598]: END; vm-test-run-plv8> server # [ 130.170089] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: CREATE FUNCTION vm-test-run-plv8> server # [ 130.177687] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-plv8> server # [ 130.179762] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.187955] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 130.188882] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: COMMENT vm-test-run-plv8> server # [ 130.199629] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: CREATE FUNCTION vm-test-run-plv8> server # [ 130.202477] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-plv8> server # [ 130.209067] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.212943] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 130.214698] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1594]: COMMENT vm-test-run-plv8> server # [ 130.215587] postgres[1598]: $$; vm-test-run-plv8> server # [ 130.221726] postgres[1598]: END IF; vm-test-run-plv8> server # [ 130.222514] postgres[1598]: vm-test-run-plv8> server # [ 130.223618] postgres[1598]: END; vm-test-run-plv8> server # [ 130.252011] postgres[1598]: $func$; vm-test-run-plv8> server # [ 130.261033] postgres[1598]: ::1 2026-10-22 21:31:12.474 UTC [1598] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-plv8> server # [ 130.267439] postgres[1598]: ::1 2026-10-22 21:31:12.485 UTC [1598] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION') vm-test-run-plv8> server # [ 130.275980] postgres[1598]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-plv8> server # [ 130.282038] postgres[1598]: ::1 2026-10-22 21:31:12.497 UTC [1598] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql'; vm-test-run-plv8> server # [ 130.314505] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-plv8> server # [ 130.475578] postgres[1615]: ::1 2026-10-22 21:31:13.043 UTC [1615] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-plv8> server # [ 130.483482] postgres[1615]: ::1 2026-10-22 21:31:13.059 UTC [1615] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-plv8> server # [ 130.505227] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-plv8> server # [ 130.506886] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.507570] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-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-plv8> server # [ 130.517868] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: DROP FUNCTION vm-test-run-plv8> server # [ 130.521016] postgres[1615]: ::1 2026-10-22 21:31:13.112 UTC [1615] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 130.522085] postgres[1615]: DECLARE vm-test-run-plv8> server # [ 130.522829] postgres[1615]: cmd record; vm-test-run-plv8> server # [ 130.523779] postgres[1615]: BEGIN vm-test-run-plv8> server # [ 130.524747] postgres[1615]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-plv8> server # [ 130.525235] postgres[1615]: LOOP vm-test-run-plv8> server # [ 130.534791] postgres[1615]: IF cmd.command_tag IN ( vm-test-run-plv8> server # [ 130.536338] postgres[1615]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-plv8> server # [ 130.549178] postgres[1615]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-plv8> server # [ 130.550024] postgres[1615]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-plv8> server # [ 130.550971] postgres[1615]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-plv8> server # [ 130.551709] postgres[1615]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-plv8> server # [ 130.552265] postgres[1615]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-plv8> server # [ 130.559432] postgres[1615]: , 'CREATE TRIGGER' vm-test-run-plv8> server # [ 130.574711] postgres[1615]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-plv8> server # [ 130.575335] postgres[1615]: , 'CREATE RULE' vm-test-run-plv8> server # [ 130.576003] postgres[1615]: , 'COMMENT' vm-test-run-plv8> server # [ 130.581848] postgres[1615]: ) vm-test-run-plv8> server # [ 130.583540] postgres[1615]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-plv8> server # [ 130.589203] postgres[1615]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-plv8> server # [ 130.590965] postgres[1615]: THEN vm-test-run-plv8> server # [ 130.591669] postgres[1615]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 130.592156] postgres[1615]: END IF; vm-test-run-plv8> server # [ 130.592446] postgres[1615]: END LOOP; vm-test-run-plv8> server # [ 130.592685] postgres[1615]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 130.620610] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: CREATE FUNCTION vm-test-run-plv8> server # [ 130.624900] postgres[1615]: ::1 2026-10-22 21:31:13.215 UTC [1615] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 130.626085] postgres[1615]: DECLARE vm-test-run-plv8> server # [ 130.626617] postgres[1615]: obj record; vm-test-run-plv8> server # [ 130.626901] postgres[1615]: BEGIN vm-test-run-plv8> server # [ 130.627115] postgres[1615]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-plv8> server # [ 130.627967] postgres[1615]: LOOP vm-test-run-plv8> server # [ 130.628220] postgres[1615]: IF obj.object_type IN ( vm-test-run-plv8> server # [ 130.634233] postgres[1615]: 'schema' vm-test-run-plv8> server # [ 130.635152] postgres[1615]: , 'table' vm-test-run-plv8> server # [ 130.636846] postgres[1615]: , 'foreign table' vm-test-run-plv8> server # [ 130.637780] postgres[1615]: , 'view' vm-test-run-plv8> server # [ 130.638129] postgres[1615]: , 'materialized view' vm-test-run-plv8> server # [ 130.638385] postgres[1615]: , 'function' vm-test-run-plv8> server # [ 130.638670] postgres[1615]: , 'trigger' vm-test-run-plv8> server # [ 130.639431] postgres[1615]: , 'type' vm-test-run-plv8> server # [ 130.640024] postgres[1615]: , 'rule' vm-test-run-plv8> server # [ 130.645738] postgres[1615]: ) vm-test-run-plv8> server # [ 130.646235] postgres[1615]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-plv8> server # [ 130.646541] postgres[1615]: THEN vm-test-run-plv8> server # [ 130.647502] postgres[1615]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 130.654606] postgres[1615]: END IF; vm-test-run-plv8> server # [ 130.655075] postgres[1615]: END LOOP; vm-test-run-plv8> server # [ 130.655828] postgres[1615]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 130.662703] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: CREATE FUNCTION vm-test-run-plv8> server # [ 130.664078] postgres[1615]: ::1 2026-10-22 21:31:13.256 UTC [1615] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-plv8> server # [ 130.677570] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.679087] postgres[1615]: ::1 2026-10-22 21:31:13.269 UTC [1615] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-plv8> server # [ 130.681909] postgres[1615]: ON ddl_command_end vm-test-run-plv8> server # [ 130.683172] postgres[1615]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-plv8> server # [ 130.699802] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 130.713746] postgres[1615]: ::1 2026-10-22 21:31:13.287 UTC [1615] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-plv8> server # [ 130.717761] postgres[1615]: ::1 2026-10-22 21:31:13.302 UTC [1615] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-plv8> server # [ 130.719134] postgres[1615]: ON sql_drop vm-test-run-plv8> server # [ 130.722103] postgres[1615]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-plv8> server # [ 130.722983] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.726526] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1612]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 130.790442] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-plv8> server # [ 131.142475] postgres[1623]: ::1 2026-10-22 21:31:13.714 UTC [1623] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-plv8> server # [ 131.174742] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1622]: ALTER ROLE vm-test-run-plv8> server # [ 131.233355] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-plv8> server # [ 131.626118] postgres[1629]: ::1 2026-10-22 21:31:14.205 UTC [1629] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 131.627625] postgres[1629]: returns event_trigger vm-test-run-plv8> server # [ 131.641488] postgres[1629]: language plpgsql vm-test-run-plv8> server # [ 131.648633] postgres[1629]: AS $func$ vm-test-run-plv8> server # [ 131.649385] postgres[1629]: DECLARE vm-test-run-plv8> server # [ 131.650116] postgres[1629]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 131.651036] postgres[1629]: BEGIN vm-test-run-plv8> server # [ 131.659114] postgres[1629]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 131.667081] postgres[1629]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 131.670608] postgres[1629]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 131.671048] postgres[1629]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 131.671357] postgres[1629]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 131.676084] postgres[1629]: ); vm-test-run-plv8> server # [ 131.689286] postgres[1629]: vm-test-run-plv8> server # [ 131.689835] postgres[1629]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 131.691154] postgres[1629]: THEN vm-test-run-plv8> server # [ 131.691582] postgres[1629]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 131.691908] postgres[1629]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 131.692432] postgres[1629]: vm-test-run-plv8> server # [ 131.701623] postgres[1629]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 131.702862] postgres[1629]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 131.703451] postgres[1629]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 131.704269] postgres[1629]: vm-test-run-plv8> server # [ 131.725389] postgres[1629]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 131.726744] postgres[1629]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 131.727160] postgres[1629]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 131.733069] postgres[1629]: "operationName" text default null, vm-test-run-plv8> server # [ 131.733983] postgres[1629]: query text default null, vm-test-run-plv8> server # [ 131.735428] postgres[1629]: variables jsonb default null, vm-test-run-plv8> server # [ 131.749175] postgres[1629]: extensions jsonb default null vm-test-run-plv8> server # [ 131.751799] postgres[1629]: ) vm-test-run-plv8> server # [ 131.758059] postgres[1629]: returns jsonb vm-test-run-plv8> server # [ 131.759158] postgres[1629]: language sql vm-test-run-plv8> server # [ 131.759642] postgres[1629]: as $$ vm-test-run-plv8> server # [ 131.760085] postgres[1629]: -- This changed vm-test-run-plv8> server # [ 131.767477] postgres[1629]: select graphql.resolve( vm-test-run-plv8> server # [ 131.770033] postgres[1629]: query := query, vm-test-run-plv8> server # [ 131.790850] postgres[1629]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 131.802849] postgres[1629]: "operationName" := "operationName", vm-test-run-plv8> server # [ 131.803815] postgres[1629]: extensions := extensions vm-test-run-plv8> server # [ 131.804330] postgres[1629]: ); vm-test-run-plv8> server # [ 131.813251] postgres[1629]: $$; vm-test-run-plv8> server # [ 131.814665] postgres[1629]: vm-test-run-plv8> server # [ 131.815329] postgres[1629]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 131.815989] postgres[1629]: END IF; vm-test-run-plv8> server # [ 131.816406] postgres[1629]: vm-test-run-plv8> server # [ 131.817698] postgres[1629]: END; vm-test-run-plv8> server # [ 131.818392] postgres[1629]: $func$; vm-test-run-plv8> server # [ 131.825963] postgres[1629]: ::1 2026-10-22 21:31:14.369 UTC [1629] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-plv8> server # [ 131.826899] postgres[1629]: RETURNS event_trigger vm-test-run-plv8> server # [ 131.832895] postgres[1629]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 131.833668] postgres[1629]: AS $func$ vm-test-run-plv8> server # [ 131.833989] postgres[1629]: DECLARE vm-test-run-plv8> server # [ 131.834228] postgres[1629]: graphql_is_dropped bool; vm-test-run-plv8> server # [ 131.834499] postgres[1629]: BEGIN vm-test-run-plv8> server # [ 131.834732] postgres[1629]: graphql_is_dropped = ( vm-test-run-plv8> server # [ 131.835385] postgres[1629]: SELECT ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 131.836023] postgres[1629]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-plv8> server # [ 131.843755] postgres[1629]: WHERE ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 131.854646] postgres[1629]: ); vm-test-run-plv8> server # [ 131.858288] postgres[1629]: vm-test-run-plv8> server # [ 131.859110] postgres[1629]: IF graphql_is_dropped vm-test-run-plv8> server # [ 131.859663] postgres[1629]: THEN vm-test-run-plv8> server # [ 131.860004] postgres[1629]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 131.861656] postgres[1629]: "operationName" text default null, vm-test-run-plv8> server # [ 131.862286] postgres[1629]: query text default null, vm-test-run-plv8> server # [ 131.862720] postgres[1629]: variables jsonb default null, vm-test-run-plv8> server # [ 131.862995] postgres[1629]: extensions jsonb default null vm-test-run-plv8> server # [ 131.863307] postgres[1629]: ) vm-test-run-plv8> server # [ 131.866550] postgres[1629]: returns jsonb vm-test-run-plv8> server # [ 131.867537] postgres[1629]: language plpgsql vm-test-run-plv8> server # [ 131.868883] postgres[1629]: as $$ vm-test-run-plv8> server # [ 131.869641] postgres[1629]: DECLARE vm-test-run-plv8> server # [ 131.870967] postgres[1629]: server_version float; vm-test-run-plv8> server # [ 131.871663] postgres[1629]: BEGIN vm-test-run-plv8> server # [ 131.876915] postgres[1629]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 131.877873] postgres[1629]: vm-test-run-plv8> server # [ 131.879873] postgres[1629]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 131.880819] postgres[1629]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 131.881475] postgres[1629]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 131.882265] postgres[1629]: jsonb_build_object( vm-test-run-plv8> server # [ 131.882830] postgres[1629]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-plv8> server # [ 131.883994] postgres[1629]: ) vm-test-run-plv8> server # [ 131.889578] postgres[1629]: ) vm-test-run-plv8> server # [ 131.890018] postgres[1629]: ); vm-test-run-plv8> server # [ 131.890651] postgres[1629]: ELSE vm-test-run-plv8> server # [ 131.891411] postgres[1629]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 131.891870] postgres[1629]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 131.892537] postgres[1629]: jsonb_build_object( vm-test-run-plv8> server # [ 131.892879] postgres[1629]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-plv8> server # [ 131.893986] postgres[1629]: ) vm-test-run-plv8> server # [ 131.894690] postgres[1629]: ) vm-test-run-plv8> server # [ 131.895780] postgres[1629]: ); vm-test-run-plv8> server # [ 131.896185] postgres[1629]: END IF; vm-test-run-plv8> server # [ 131.902306] postgres[1629]: END; vm-test-run-plv8> server # [ 131.903108] postgres[1629]: $$; vm-test-run-plv8> server # [ 131.904886] postgres[1629]: END IF; vm-test-run-plv8> server # [ 131.905306] postgres[1629]: vm-test-run-plv8> server # [ 131.909118] postgres[1629]: END; vm-test-run-plv8> server # [ 131.910070] postgres[1629]: $func$; vm-test-run-plv8> server # [ 131.910636] postgres[1629]: ::1 2026-10-22 21:31:14.414 UTC [1629] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 131.912106] postgres[1629]: "operationName" text default null, vm-test-run-plv8> server # [ 131.915651] postgres[1629]: query text default null, vm-test-run-plv8> server # [ 131.917038] postgres[1629]: variables jsonb default null, vm-test-run-plv8> server # [ 131.928871] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1626]: CREATE FUNCTION vm-test-run-plv8> server # [ 131.938558] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1626]: CREATE FUNCTION vm-test-run-plv8> server # [ 131.950415] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1626]: CREATE FUNCTION vm-test-run-plv8> server # [ 131.962157] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1626]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-plv8> server # [ 131.963572] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1626]: DROP EXTENSION vm-test-run-plv8> server # [ 131.968731] postgres[1629]: extensions jsonb default null vm-test-run-plv8> server # [ 131.986255] postgres[1629]: ) vm-test-run-plv8> server # [ 131.989187] postgres[1629]: returns jsonb vm-test-run-plv8> server # [ 131.989877] postgres[1629]: language plpgsql vm-test-run-plv8> server # [ 131.990180] postgres[1629]: as $$ vm-test-run-plv8> server # [ 131.990441] postgres[1629]: DECLARE vm-test-run-plv8> server # [ 131.990685] postgres[1629]: server_version float; vm-test-run-plv8> server # [ 131.990929] postgres[1629]: BEGIN vm-test-run-plv8> server # [ 131.998099] postgres[1629]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 131.999565] postgres[1629]: vm-test-run-plv8> server # [ 131.999942] postgres[1629]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 132.009816] postgres[1629]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 132.013685] postgres[1629]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 132.015346] postgres[1629]: jsonb_build_object( vm-test-run-plv8> server # [ 132.021819] postgres[1629]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-plv8> server # [ 132.029863] postgres[1629]: ) vm-test-run-plv8> server # [ 132.032076] postgres[1629]: ) vm-test-run-plv8> server # [ 132.038190] postgres[1629]: ); vm-test-run-plv8> server # [ 132.039144] postgres[1629]: ELSE vm-test-run-plv8> server # [ 132.039814] postgres[1629]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 132.040304] postgres[1629]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 132.042538] postgres[1629]: jsonb_build_object( vm-test-run-plv8> server # [ 132.043249] postgres[1629]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-plv8> server # [ 132.043907] postgres[1629]: ) vm-test-run-plv8> server # [ 132.048300] postgres[1629]: ) vm-test-run-plv8> server # [ 132.050028] postgres[1629]: ); vm-test-run-plv8> server # [ 132.050424] postgres[1629]: END IF; vm-test-run-plv8> server # [ 132.052135] postgres[1629]: END; vm-test-run-plv8> server # [ 132.054560] postgres[1629]: $$; vm-test-run-plv8> server # [ 132.055171] postgres[1629]: ::1 2026-10-22 21:31:14.454 UTC [1629] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 133.770935] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1626]: DO vm-test-run-plv8> server # [ 133.822353] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-plv8> server # [ 134.252130] postgres[1654]: ::1 2026-10-22 21:31:16.810 UTC [1654] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-plv8> server # [ 134.301229] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1649]: GRANT ROLE vm-test-run-plv8> server # [ 134.335929] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-plv8> server # [ 134.601968] postgres[1657]: ::1 2026-10-22 21:31:17.184 UTC [1657] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 134.604137] postgres[1657]: returns event_trigger vm-test-run-plv8> server # [ 134.611717] postgres[1657]: language plpgsql vm-test-run-plv8> server # [ 134.617901] postgres[1657]: AS $func$ vm-test-run-plv8> server # [ 134.618834] postgres[1657]: DECLARE vm-test-run-plv8> server # [ 134.619692] postgres[1657]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 134.626473] postgres[1657]: BEGIN vm-test-run-plv8> server # [ 134.632775] postgres[1657]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 134.633915] postgres[1657]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 134.634835] postgres[1657]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 134.635560] postgres[1657]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 134.635908] postgres[1657]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 134.642107] postgres[1657]: ); vm-test-run-plv8> server # [ 134.643504] postgres[1657]: vm-test-run-plv8> server # [ 134.643871] postgres[1657]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 134.648206] postgres[1657]: THEN vm-test-run-plv8> server # [ 134.650439] postgres[1657]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 134.651367] postgres[1657]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 134.656323] postgres[1657]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 134.663453] postgres[1657]: "operationName" text default null, vm-test-run-plv8> server # [ 134.665617] postgres[1657]: query text default null, vm-test-run-plv8> server # [ 134.666176] postgres[1657]: variables jsonb default null, vm-test-run-plv8> server # [ 134.668067] postgres[1657]: extensions jsonb default null vm-test-run-plv8> server # [ 134.670210] postgres[1657]: ) vm-test-run-plv8> server # [ 134.671286] postgres[1657]: returns jsonb vm-test-run-plv8> server # [ 134.671807] postgres[1657]: language sql vm-test-run-plv8> server # [ 134.676003] postgres[1657]: as $$ vm-test-run-plv8> server # [ 134.679592] postgres[1657]: select graphql.resolve( vm-test-run-plv8> server # [ 134.680137] postgres[1657]: query := query, vm-test-run-plv8> server # [ 134.680437] postgres[1657]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 134.682021] postgres[1657]: "operationName" := "operationName", vm-test-run-plv8> server # [ 134.683687] postgres[1657]: extensions := extensions vm-test-run-plv8> server # [ 134.685583] postgres[1657]: ); vm-test-run-plv8> server # [ 134.686327] postgres[1657]: $$; vm-test-run-plv8> server # [ 134.687041] postgres[1657]: vm-test-run-plv8> server # [ 134.687745] postgres[1657]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-plv8> server # [ 134.690344] postgres[1657]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-plv8> server # [ 134.694785] postgres[1657]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-plv8> server # [ 134.696321] postgres[1657]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.697994] postgres[1657]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.699571] postgres[1657]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.701678] postgres[1657]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.702613] postgres[1657]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.703956] postgres[1657]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.706180] postgres[1657]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.707426] postgres[1657]: END IF; vm-test-run-plv8> server # [ 134.708189] postgres[1657]: vm-test-run-plv8> server # [ 134.709612] postgres[1657]: END; vm-test-run-plv8> server # [ 134.710243] postgres[1657]: $func$; vm-test-run-plv8> server # [ 134.710612] postgres[1657]: ::1 2026-10-22 21:31:17.265 UTC [1657] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 134.716620] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1656]: CREATE FUNCTION vm-test-run-plv8> server # [ 134.753205] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1656]: DROP EXTENSION vm-test-run-plv8> server # [ 136.510944] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1656]: DO vm-test-run-plv8> server # [ 136.553214] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-plv8> server # [ 137.841832] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1672]: DO vm-test-run-plv8> server # [ 138.432131] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1672]: DO vm-test-run-plv8> server # [ 138.484592] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-plv8> server # [ 138.709145] postgres[1689]: ::1 2026-10-22 21:31:21.293 UTC [1689] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-plv8> server # [ 138.723043] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1688]: ALTER ROLE vm-test-run-plv8> server # [ 138.767696] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-plv8> server # [ 139.166755] postgres[1695]: ::1 2026-10-22 21:31:21.751 UTC [1695] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-plv8> server # [ 139.189072] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1690]: REVOKE ROLE vm-test-run-plv8> server # [ 139.256490] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-plv8> server # [ 141.707149] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1697]: DO vm-test-run-plv8> server # [ 141.753380] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-plv8> server # [ 141.994020] postgres[1723]: ::1 2026-10-22 21:31:24.579 UTC [1723] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-plv8> server # [ 142.014140] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1722]: GRANT ROLE vm-test-run-plv8> server # [ 142.055110] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-plv8> server # [ 142.323728] postgres[1725]: ::1 2026-10-22 21:31:24.896 UTC [1725] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 142.355024] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1724]: GRANT vm-test-run-plv8> server # [ 142.367658] postgres[1725]: ::1 2026-10-22 21:31:24.938 UTC [1725] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 142.430060] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1724]: GRANT vm-test-run-plv8> server # [ 142.440765] postgres[1725]: ::1 2026-10-22 21:31:25.026 UTC [1725] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 142.450622] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1724]: GRANT vm-test-run-plv8> server # [ 142.466680] postgres[1725]: ::1 2026-10-22 21:31:25.053 UTC [1725] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 142.492004] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1724]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 142.502454] postgres[1725]: ::1 2026-10-22 21:31:25.088 UTC [1725] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option; vm-test-run-plv8> server # [ 142.515797] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1724]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 142.525755] postgres[1725]: ::1 2026-10-22 21:31:25.113 UTC [1725] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 142.566758] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1724]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 142.622248] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-plv8> server # [ 143.095789] postgres[1739]: ::1 2026-10-22 21:31:25.657 UTC [1739] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-plv8> server # [ 143.140249] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1732]: GRANT ROLE vm-test-run-plv8> server # [ 143.204549] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-plv8> server # [ 143.757367] postgres[1749]: ::1 2026-10-22 21:31:26.314 UTC [1749] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-plv8> server # [ 143.778502] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1746]: GRANT ROLE vm-test-run-plv8> server # [ 143.807300] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-plv8> server # [ 144.107795] postgres[1755]: ::1 2026-10-22 21:31:26.695 UTC [1755] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-plv8> server # [ 144.128019] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1754]: ALTER ROLE vm-test-run-plv8> server # [ 144.133972] postgres[1755]: ::1 2026-10-22 21:31:26.710 UTC [1755] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-plv8> server # [ 144.135729] postgres[1755]: ::1 2026-10-22 21:31:26.720 UTC [1755] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-plv8> server # [ 144.142749] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1754]: ALTER ROLE vm-test-run-plv8> server # [ 144.143755] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1754]: ALTER ROLE vm-test-run-plv8> server # [ 144.162833] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1754]: DO vm-test-run-plv8> server # [ 144.216097] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-plv8> server # [ 144.465639] postgres[1757]: ::1 2026-10-22 21:31:27.054 UTC [1757] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-plv8> server # [ 144.481671] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1756]: GRANT ROLE vm-test-run-plv8> server # [ 144.483114] postgres[1757]: ::1 2026-10-22 21:31:27.071 UTC [1757] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-plv8> server # [ 144.488895] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1756]: REVOKE ROLE vm-test-run-plv8> server # [ 144.521479] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-plv8> server # [ 144.852151] postgres[1760]: ::1 2026-10-22 21:31:27.431 UTC [1760] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 144.875393] postgres[1760]: returns event_trigger vm-test-run-plv8> server # [ 144.876073] postgres[1760]: language plpgsql vm-test-run-plv8> server # [ 144.878427] postgres[1760]: AS $func$ vm-test-run-plv8> server # [ 144.882244] postgres[1760]: DECLARE vm-test-run-plv8> server # [ 144.882703] postgres[1760]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 144.883985] postgres[1760]: BEGIN vm-test-run-plv8> server # [ 144.889750] postgres[1760]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 144.893855] postgres[1760]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 144.894526] postgres[1760]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 144.895713] postgres[1760]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 144.899340] postgres[1760]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 144.900229] postgres[1760]: ); vm-test-run-plv8> server # [ 144.904604] postgres[1760]: vm-test-run-plv8> server # [ 144.907355] postgres[1760]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 144.907826] postgres[1760]: THEN vm-test-run-plv8> server # [ 144.908109] postgres[1760]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 144.910269] postgres[1760]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 144.928332] postgres[1760]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 144.929739] postgres[1760]: "operationName" text default null, vm-test-run-plv8> server # [ 144.930436] postgres[1760]: query text default null, vm-test-run-plv8> server # [ 144.930941] postgres[1760]: variables jsonb default null, vm-test-run-plv8> server # [ 144.931939] postgres[1760]: extensions jsonb default null vm-test-run-plv8> server # [ 144.937746] postgres[1760]: ) vm-test-run-plv8> server # [ 144.938443] postgres[1760]: returns jsonb vm-test-run-plv8> server # [ 144.938750] postgres[1760]: language sql vm-test-run-plv8> server # [ 144.939300] postgres[1760]: as $$ vm-test-run-plv8> server # [ 144.939628] postgres[1760]: select graphql.resolve( vm-test-run-plv8> server # [ 144.939909] postgres[1760]: query := query, vm-test-run-plv8> server # [ 144.942463] postgres[1760]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 144.954327] postgres[1760]: "operationName" := "operationName", vm-test-run-plv8> server # [ 144.955871] postgres[1760]: extensions := extensions vm-test-run-plv8> server # [ 144.961744] postgres[1760]: ); vm-test-run-plv8> server # [ 144.962585] postgres[1760]: $$; vm-test-run-plv8> server # [ 144.964170] postgres[1760]: vm-test-run-plv8> server # [ 144.967875] postgres[1760]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-plv8> server # [ 144.973596] postgres[1760]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-plv8> server # [ 144.974467] postgres[1760]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-plv8> server # [ 144.974838] postgres[1760]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 144.975577] postgres[1760]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 144.976263] postgres[1760]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 144.987363] postgres[1760]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 144.998468] postgres[1760]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 144.999940] postgres[1760]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 145.002001] postgres[1760]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 145.003626] postgres[1760]: vm-test-run-plv8> server # [ 145.004255] postgres[1760]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-plv8> server # [ 145.023670] postgres[1760]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-plv8> server # [ 145.030504] postgres[1760]: grant usage on schema graphql to postgres with grant option; vm-test-run-plv8> server # [ 145.031962] postgres[1760]: END IF; vm-test-run-plv8> server # [ 145.033404] postgres[1760]: vm-test-run-plv8> server # [ 145.034485] postgres[1760]: END; vm-test-run-plv8> server # [ 145.035302] postgres[1760]: $func$; vm-test-run-plv8> server # [ 145.035886] postgres[1760]: ::1 2026-10-22 21:31:27.572 UTC [1760] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 145.051697] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1758]: CREATE FUNCTION vm-test-run-plv8> server # [ 145.079992] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1758]: DROP EXTENSION vm-test-run-plv8> server # [ 147.090639] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1758]: DO vm-test-run-plv8> server # [ 147.105260] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-plv8> server # [ 147.537858] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1788]: DO vm-test-run-plv8> server # [ 147.547534] postgres[1789]: ::1 2026-10-22 21:31:30.127 UTC [1789] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-plv8> server # [ 147.550428] postgres[1789]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 147.550986] postgres[1789]: AS $$ vm-test-run-plv8> server # [ 147.551610] postgres[1789]: BEGIN vm-test-run-plv8> server # [ 147.553052] postgres[1789]: IF EXISTS ( vm-test-run-plv8> server # [ 147.553850] postgres[1789]: SELECT vm-test-run-plv8> server # [ 147.554477] postgres[1789]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 147.555094] postgres[1789]: JOIN pg_extension AS ext vm-test-run-plv8> server # [ 147.555619] postgres[1789]: ON ev.objid = ext.oid vm-test-run-plv8> server # [ 147.557290] postgres[1789]: WHERE ext.extname = 'pg_cron' vm-test-run-plv8> server # [ 147.558110] postgres[1789]: ) vm-test-run-plv8> server # [ 147.559887] postgres[1789]: THEN vm-test-run-plv8> server # [ 147.561187] postgres[1789]: grant usage on schema cron to postgres with grant option; vm-test-run-plv8> server # [ 147.562226] postgres[1789]: vm-test-run-plv8> server # [ 147.562575] postgres[1789]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 147.563540] postgres[1789]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-plv8> server # [ 147.565264] postgres[1789]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 147.566274] postgres[1789]: vm-test-run-plv8> server # [ 147.566731] postgres[1789]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 147.567737] postgres[1789]: on sequences to postgres with grant option; vm-test-run-plv8> server # [ 147.568387] postgres[1789]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 147.570103] postgres[1789]: on tables to postgres with grant option; vm-test-run-plv8> server # [ 147.571780] postgres[1789]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 147.573813] postgres[1789]: on functions to postgres with grant option; vm-test-run-plv8> server # [ 147.574220] postgres[1789]: vm-test-run-plv8> server # [ 147.575868] postgres[1789]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-plv8> server # [ 147.576401] postgres[1789]: revoke all on table cron.job from postgres; vm-test-run-plv8> server # [ 147.578236] postgres[1789]: grant select on table cron.job to postgres with grant option; vm-test-run-plv8> server # [ 147.579552] postgres[1789]: END IF; vm-test-run-plv8> server # [ 147.580097] postgres[1789]: END; vm-test-run-plv8> server # [ 147.580481] postgres[1789]: $$; vm-test-run-plv8> server # [ 147.614764] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1788]: CREATE FUNCTION vm-test-run-plv8> server # [ 147.616802] postgres[1789]: ::1 2026-10-22 21:31:30.208 UTC [1789] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-plv8> server # [ 147.627061] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1788]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 147.633183] postgres[1789]: ::1 2026-10-22 21:31:30.223 UTC [1789] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-plv8> server # [ 147.643243] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1788]: ALTER FUNCTION vm-test-run-plv8> server # [ 147.646929] postgres[1789]: ::1 2026-10-22 21:31:30.233 UTC [1789] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-plv8> server # [ 147.651151] postgres[1789]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-plv8> server # [ 147.653597] postgres[1789]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-plv8> server # [ 147.655946] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1788]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 147.690928] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-plv8> server # [ 147.946479] postgres[1791]: ::1 2026-10-22 21:31:30.530 UTC [1791] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-plv8> server # [ 147.968187] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1790]: ALTER ROLE vm-test-run-plv8> server # [ 147.996775] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-plv8> server # [ 148.248030] postgres[1793]: ::1 2026-10-22 21:31:30.836 UTC [1793] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-plv8> server # [ 148.288554] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1792]: ALTER FUNCTION vm-test-run-plv8> server # [ 148.302308] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1792]: ALTER FUNCTION vm-test-run-plv8> server # [ 148.317424] postgres[1793]: ::1 2026-10-22 21:31:30.879 UTC [1793] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-plv8> server # [ 148.320276] postgres[1793]: ::1 2026-10-22 21:31:30.886 UTC [1793] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-plv8> server # [ 148.345285] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1792]: ALTER FUNCTION vm-test-run-plv8> server # [ 148.367299] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-plv8> server # [ 148.839931] postgres[1801]: ::1 2026-10-22 21:31:31.412 UTC [1801] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-plv8> server # [ 148.895278] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1794]: GRANT ROLE vm-test-run-plv8> server # [ 148.903451] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-plv8> server # [ 150.270509] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1806]: DO vm-test-run-plv8> server # [ 150.314358] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-plv8> server # [ 150.628446] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1822]: DO vm-test-run-plv8> server # [ 150.645024] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1822]: DO vm-test-run-plv8> server # [ 150.675380] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-plv8> server # [ 151.090639] postgres[1829]: ::1 2026-10-22 21:31:33.672 UTC [1829] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-plv8> server # [ 151.116748] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1826]: ALTER ROLE vm-test-run-plv8> server # [ 151.123663] postgres[1829]: ::1 2026-10-22 21:31:33.699 UTC [1829] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-plv8> server # [ 151.127585] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1826]: ALTER ROLE vm-test-run-plv8> server # [ 151.134517] postgres[1829]: ::1 2026-10-22 21:31:33.721 UTC [1829] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-plv8> server # [ 151.141357] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1826]: ALTER ROLE vm-test-run-plv8> server # [ 151.199155] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-plv8> server # [ 151.588498] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1830]: DO vm-test-run-plv8> server # [ 151.632679] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-plv8> server # [ 151.973180] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1832]: DO vm-test-run-plv8> server # [ 152.018083] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-plv8> server # [ 152.608470] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1839]: CREATE FUNCTION vm-test-run-plv8> server # [ 152.630039] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1839]: DO vm-test-run-plv8> server # [ 152.704835] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-plv8> server # [ 153.292428] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1856]: ALTER FUNCTION vm-test-run-plv8> server # [ 153.309204] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1856]: GRANT vm-test-run-plv8> server # [ 153.376559] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-plv8> server # [ 153.703921] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1862]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 153.754925] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1862]: ALTER FUNCTION vm-test-run-plv8> server # [ 153.767601] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1862]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 153.826673] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-plv8> server # [ 154.094562] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1864]: DO vm-test-run-plv8> server # [ 154.125149] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-plv8> server # [ 154.427530] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1866]: CREATE FUNCTION vm-test-run-plv8> server # [ 154.436836] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1866]: GRANT vm-test-run-plv8> server # [ 154.469126] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-plv8> server # [ 154.873956] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1868]: REVOKE ROLE vm-test-run-plv8> server # [ 154.933294] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1868]: DO vm-test-run-plv8> server # [ 154.957435] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1868]: DO vm-test-run-plv8> server # [ 154.985514] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1868]: REVOKE ROLE vm-test-run-plv8> server # [ 155.005730] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1868]: REVOKE vm-test-run-plv8> server # [ 155.053600] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1868]: DO vm-test-run-plv8> server # [ 155.138753] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-plv8> server # [ 155.522532] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1877]: DO vm-test-run-plv8> server # [ 155.548987] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-plv8> server # [ 156.042986] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1884]: DO vm-test-run-plv8> server # [ 156.069064] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-plv8> server # [ 156.566941] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1896]: DO vm-test-run-plv8> server # [ 156.615772] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-plv8> server # [ 156.994493] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1902]: ALTER ROLE vm-test-run-plv8> server # [ 157.019062] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-plv8> server # [ 157.393598] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1906]: GRANT ROLE vm-test-run-plv8> server # [ 157.401035] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1906]: DO vm-test-run-plv8> server # [ 157.429773] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-plv8> server # [ 157.725607] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1908]: GRANT vm-test-run-plv8> server # [ 157.766903] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-plv8> server # [ 158.090202] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1910]: CREATE FUNCTION vm-test-run-plv8> server # [ 158.093526] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1910]: REVOKE vm-test-run-plv8> server # [ 158.109540] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1910]: REVOKE vm-test-run-plv8> server # [ 158.127416] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1910]: GRANT vm-test-run-plv8> server # [ 158.161120] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-plv8> server # [ 158.801842] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1912]: DO vm-test-run-plv8> server # [ 158.847132] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-plv8> server # [ 159.308124] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1926]: ALTER ROLE vm-test-run-plv8> server # [ 159.338772] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-plv8> server # [ 159.939868] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1933]: DROP EXTENSION vm-test-run-plv8> server # [ 160.001582] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-plv8> server # [ 160.313461] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1942]: CREATE FUNCTION vm-test-run-plv8> server # [ 160.330616] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1942]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 160.352554] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1942]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 160.393452] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-plv8> server # [ 160.759153] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1947]: DO vm-test-run-plv8> server # [ 160.802106] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1947]: CREATE FUNCTION vm-test-run-plv8> server # [ 160.853363] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql vm-test-run-plv8> server # [ 161.193803] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1950]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.208086] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1950]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.256062] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20261016000001_search_path_for_supa_functions.sql vm-test-run-plv8> server # [ 161.558275] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1952]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.580941] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1952]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.620011] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1952]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.661044] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1952]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.697856] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1952]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.724804] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1952]: CREATE FUNCTION vm-test-run-plv8> server # [ 161.780701] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Running postgresql schema... vm-test-run-plv8> server # [ 162.190556] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER DATABASE vm-test-run-plv8> server # [ 162.232736] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER DATABASE vm-test-run-plv8> server # [ 162.473082] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 162.652970] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 162.739951] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 162.825299] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 162.894578] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 162.960391] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 163.057369] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 163.146015] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 163.222049] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 163.226675] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1961]: ALTER ROLE vm-test-run-plv8> server # [ 163.281193] vb2bcqn0kw9gdsg31y0clw0z0144kzs4-supabase-db-init[1417]: Database initialization complete vm-test-run-plv8> server # [ 163.306811] systemd[1]: Finished Supabase Database Initialization. vm-test-run-plv8> server # [ 163.315837] systemd[1]: Reached target Multi-User System. vm-test-run-plv8> server # [ 163.322174] systemd[1]: Startup finished in 16.695s (kernel) + 2min 26.622s (userspace) = 2min 43.318s. vm-test-run-plv8> server: (finished: waiting for unit supabase-db-init.service, in 165.99 seconds) vm-test-run-plv8> subtest: Check upgrade path with postgresql 15 vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server # NOTICE: extension "plv8" does not exist, skipping vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.59 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.4' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.4' CASCADE;" , in 7.18 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.51 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.68 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.5' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.5' CASCADE;" , in 7.26 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.38 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.47 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" , in 5.71 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server # [ 188.279204] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.60 seconds) vm-test-run-plv8> (finished: subtest: Check upgrade path with postgresql 15, in 23.38 seconds) vm-test-run-plv8> subtest: Check the install of the last version of the extension vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.64 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" , in 2.17 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.48 seconds) vm-test-run-plv8> (finished: subtest: Check the install of the last version of the extension, in 3.28 seconds) vm-test-run-plv8> (finished: run the VM test script, in 192.86 seconds) vm-test-run-plv8> test script finished in 193.30s vm-test-run-plv8> cleanup vm-test-run-plv8> kill machine (pid 9) vm-test-run-plv8> qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/y88crn50yx35fiiwcjx6xqwi6ahk7mlq-python3-3.13.11/bin/python3.13) vm-test-run-plv8> vde_switch: EOF data port: Interrupted system call vm-test-run-plv8> kill vlan (pid 7) vm-test-run-plv8> (finished: cleanup, in 0.00 seconds) vm-test-run-plv8> vde_switch: Caught signal 15, cleaning up and exiting