these 19 derivations will be built: /nix/store/c2fy76sb4l1a9bircmk53qy752ymmh9h-postgresql-migrate.drv /nix/store/q2cyar3rvwil73pryzpxyd3ni38795rl-unit-postgresql-migrate.service.drv /nix/store/xbhbrbm7jn3fsknqsm7v2gdgvy4v9bvh-unit-postgresql.service.drv /nix/store/ifghd86p4zmd9373zwpyr5vn9dxiirjk-system-units.drv /nix/store/mrrr9xz4b4hd28yjc7qdjx6x7f37zy2a-etc.drv /nix/store/kn21khmc00g1xh07msy7svv2jm7zkzhk-activate.drv /nix/store/vzz9c6myxag79wjl0lv40d2369gzkvrb-nixos-system-server-test.drv /nix/store/v7355r3wlapz2cqnq2y0rp67b09883yy-postgresql-orioledb-init.drv /nix/store/k74xnf9pjsknj1nhi1icfbn6a1924wkg-unit-postgresql.service.drv /nix/store/kacqda995i4iyn5h06wvi51fbgdajdnx-system-units.drv /nix/store/pymwaf1wg64nna92jdd21wcdaknpyxqf-etc.drv /nix/store/vlc1xh4g0qvm2s8svqnlil5pmhm5sy69-activate.drv /nix/store/xrg85rzkkgahwvw7pqda40wq458jk5sa-nixos-system-server-test.drv /nix/store/lppj8qr2wflzclj8m0lqr870nn64c27z-nixos-system-server-test.drv /nix/store/rnpw7k68mn82a1hhfb6rh8mzicn1fydl-closure-info.drv /nix/store/ngavxv8s29rqf926pkg1q754lgzdjxp2-run-nixos-vm.drv /nix/store/dv7npnf9vd58iz3zb1dwics1ksap7bk9-nixos-vm.drv /nix/store/lz9qchw7ylzbc7qbcxbc39iac0m8bcdc-nixos-test-driver-index_advisor.drv /nix/store/7wc3pfmpzi1q1wxb0vdrn69fbaixi61j-vm-test-run-index_advisor.drv these 2 paths will be fetched (29.8 KiB download, 122.1 KiB unpacked): /nix/store/3kiik8dkh8ish7bwxc1zs3lmwcbdl2vb-processed-postgresql-config-17 /nix/store/3qadyyycv5n7nmai0rq9kb7grqxad4qz-processed-postgresql-config-orioledb-17 building '/nix/store/c2fy76sb4l1a9bircmk53qy752ymmh9h-postgresql-migrate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/c2fy76sb4l1a9bircmk53qy752ymmh9h-postgresql-migrate.drv' building '/nix/store/q2cyar3rvwil73pryzpxyd3ni38795rl-unit-postgresql-migrate.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/q2cyar3rvwil73pryzpxyd3ni38795rl-unit-postgresql-migrate.service.drv' building '/nix/store/ifghd86p4zmd9373zwpyr5vn9dxiirjk-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/ifghd86p4zmd9373zwpyr5vn9dxiirjk-system-units.drv' building '/nix/store/mrrr9xz4b4hd28yjc7qdjx6x7f37zy2a-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/mrrr9xz4b4hd28yjc7qdjx6x7f37zy2a-etc.drv' building '/nix/store/kn21khmc00g1xh07msy7svv2jm7zkzhk-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/vzz9c6myxag79wjl0lv40d2369gzkvrb-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/vzz9c6myxag79wjl0lv40d2369gzkvrb-nixos-system-server-test.drv' building '/nix/store/lz9qchw7ylzbc7qbcxbc39iac0m8bcdc-nixos-test-driver-index_advisor.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/lz9qchw7ylzbc7qbcxbc39iac0m8bcdc-nixos-test-driver-index_advisor.drv' nixos-test-driver-index_advisor> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-index_advisor> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-index_advisor> Success: no issues found in 1 source file nixos-test-driver-index_advisor> additionally exposed symbols: nixos-test-driver-index_advisor> , nixos-test-driver-index_advisor> , nixos-test-driver-index_advisor> 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-index_advisor> Linting test script (enable/disable: config.skipLint) nixos-test-driver-index_advisor> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint building '/nix/store/7wc3pfmpzi1q1wxb0vdrn69fbaixi61j-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/7wc3pfmpzi1q1wxb0vdrn69fbaixi61j-vm-test-run-index_advisor.drv' vm-test-run-index_advisor> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-index_advisor> start all VLans vm-test-run-index_advisor> start vlan vm-test-run-index_advisor> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-index_advisor> (finished: start all VLans, in 0.00 seconds) vm-test-run-index_advisor> Test will time out and terminate in 3600 seconds vm-test-run-index_advisor> run the VM test script vm-test-run-index_advisor> additionally exposed symbols: vm-test-run-index_advisor> server, vm-test-run-index_advisor> vlan1, vm-test-run-index_advisor> 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-index_advisor> start all VMs vm-test-run-index_advisor> server: starting vm vm-test-run-index_advisor> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-index_advisor> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-index_advisor> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-index_advisor> qemu-system-aarch64: falling back to tcg vm-test-run-index_advisor> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-index_advisor> server # Formatting '/build/vm-state-server/tmp.wzcyUAIlQB', fmt=raw size=1073741824 vm-test-run-index_advisor> server # Discarding device blocks: 0/262144 done vm-test-run-index_advisor> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-index_advisor> server # Filesystem UUID: 824ef44d-3250-4846-9fb4-c5c0c400af74 vm-test-run-index_advisor> server # Superblock backups stored on blocks: vm-test-run-index_advisor> server # 32768, 98304, 163840, 229376 vm-test-run-index_advisor> server # vm-test-run-index_advisor> server # Allocating group tables: 0/8 done vm-test-run-index_advisor> server # Writing inode tables: 0/8 done vm-test-run-index_advisor> server # Creating journal (8192 blocks): done vm-test-run-index_advisor> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-index_advisor> server # vm-test-run-index_advisor> server # Virtualisation disk image created. vm-test-run-index_advisor> server: QEMU running (pid 9) vm-test-run-index_advisor> (finished: start all VMs, in 0.20 seconds) vm-test-run-index_advisor> server: waiting for unit supabase-db-init.service vm-test-run-index_advisor> server: waiting for the VM to finish booting vm-test-run-index_advisor> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-index_advisor> 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-index_advisor> server # [ 0.000000] KASLR enabled vm-test-run-index_advisor> server # [ 0.000000] random: crng init done vm-test-run-index_advisor> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-index_advisor> server # [ 0.000000] efi: UEFI not found. vm-test-run-index_advisor> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-index_advisor> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-index_advisor> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f] vm-test-run-index_advisor> server # [ 0.000000] Zone ranges: vm-test-run-index_advisor> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-index_advisor> server # [ 0.000000] DMA32 empty vm-test-run-index_advisor> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-index_advisor> server # [ 0.000000] Device empty vm-test-run-index_advisor> server # [ 0.000000] Movable zone start for each node vm-test-run-index_advisor> server # [ 0.000000] Early memory node ranges vm-test-run-index_advisor> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-index_advisor> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-index_advisor> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1 vm-test-run-index_advisor> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-index_advisor> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-index_advisor> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-index_advisor> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-index_advisor> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-index_advisor> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-index_advisor> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-index_advisor> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-index_advisor> 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/x62hqmx3ldqjyxqdifjh19n95m6gd3ls-nixos-system-server-test/init regInfo=/nix/store/v0hgk9bky38s5kn96qj1wqfj8yfk88jm-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-index_advisor> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/v0hgk9bky38s5kn96qj1wqfj8yfk88jm-closure-info/registration", will be passed to user space. vm-test-run-index_advisor> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-index_advisor> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-index_advisor> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-index_advisor> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-index_advisor> server # [ 0.000000] Policy zone: Normal vm-test-run-index_advisor> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-index_advisor> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-index_advisor> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-index_advisor> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-index_advisor> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-index_advisor> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-index_advisor> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-index_advisor> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-index_advisor> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-index_advisor> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-index_advisor> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-index_advisor> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-index_advisor> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-index_advisor> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-index_advisor> 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-index_advisor> 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-index_advisor> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-index_advisor> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-index_advisor> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-index_advisor> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-index_advisor> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-index_advisor> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-index_advisor> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-index_advisor> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-index_advisor> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-index_advisor> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-index_advisor> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b00000 vm-test-run-index_advisor> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000 vm-test-run-index_advisor> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-index_advisor> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-index_advisor> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-index_advisor> server # [ 0.000039] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-index_advisor> server # [ 0.003409] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-index_advisor> server # [ 0.014484] Console: colour dummy device 80x25 vm-test-run-index_advisor> server # [ 0.016351] printk: legacy console [tty0] enabled vm-test-run-index_advisor> server # [ 0.022539] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-index_advisor> server # [ 0.022964] pid_max: default: 32768 minimum: 301 vm-test-run-index_advisor> server # [ 0.025169] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-index_advisor> server # [ 0.026635] landlock: Up and running. vm-test-run-index_advisor> server # [ 0.026692] Yama: becoming mindful. vm-test-run-index_advisor> server # [ 0.030190] LSM support for eBPF active vm-test-run-index_advisor> server # [ 0.033492] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.033693] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.065060] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-index_advisor> server # [ 0.076325] rcu: Hierarchical SRCU implementation. vm-test-run-index_advisor> server # [ 0.076425] rcu: Max phase no-delay instances is 1000. vm-test-run-index_advisor> server # [ 0.077792] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-index_advisor> server # [ 0.084762] fsl-mc MSI: its@8080000 domain created vm-test-run-index_advisor> server # [ 0.086734] EFI services will not be available. vm-test-run-index_advisor> server # [ 0.089895] smp: Bringing up secondary CPUs ... vm-test-run-index_advisor> server # [ 0.094320] Detected PIPT I-cache on CPU1 vm-test-run-index_advisor> server # [ 0.095625] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-index_advisor> server # [ 0.096470] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000 vm-test-run-index_advisor> server # [ 0.098112] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-index_advisor> server # [ 0.102102] smp: Brought up 1 node, 2 CPUs vm-test-run-index_advisor> server # [ 0.102260] SMP: Total of 2 processors activated. vm-test-run-index_advisor> server # [ 0.102306] CPU: All CPU(s) started at EL1 vm-test-run-index_advisor> server # [ 0.102476] CPU features: detected: Branch Target Identification vm-test-run-index_advisor> server # [ 0.102528] CPU features: detected: 32-bit EL0 Support vm-test-run-index_advisor> server # [ 0.102560] CPU features: detected: 32-bit EL1 Support vm-test-run-index_advisor> server # [ 0.102582] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-index_advisor> server # [ 0.102618] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-index_advisor> server # [ 0.102662] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-index_advisor> server # [ 0.102726] CPU features: detected: Common not Private translations vm-test-run-index_advisor> server # [ 0.102750] CPU features: detected: CRC32 instructions vm-test-run-index_advisor> server # [ 0.102770] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-index_advisor> server # [ 0.102800] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-index_advisor> server # [ 0.102828] CPU features: detected: Data independent timing control (DIT) vm-test-run-index_advisor> server # [ 0.102855] CPU features: detected: E0PD vm-test-run-index_advisor> server # [ 0.102877] CPU features: detected: Enhanced Counter Virtualization vm-test-run-index_advisor> server # [ 0.102900] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-index_advisor> server # [ 0.102929] CPU features: detected: Enhanced Privileged Access Never vm-test-run-index_advisor> server # [ 0.102956] CPU features: detected: Enhanced Virtualization Traps vm-test-run-index_advisor> server # [ 0.102984] CPU features: detected: Fine Grained Traps vm-test-run-index_advisor> server # [ 0.103045] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-index_advisor> server # [ 0.103131] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-index_advisor> server # [ 0.103181] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-index_advisor> server # [ 0.103209] CPU features: detected: LSE atomic instructions vm-test-run-index_advisor> server # [ 0.103235] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-index_advisor> server # [ 0.103263] CPU features: detected: Privileged Access Never vm-test-run-index_advisor> server # [ 0.103289] CPU features: detected: RAS Extension Support vm-test-run-index_advisor> server # [ 0.103313] CPU features: detected: Random Number Generator vm-test-run-index_advisor> server # [ 0.103338] CPU features: detected: Speculation barrier (SB) vm-test-run-index_advisor> server # [ 0.103363] CPU features: detected: Stage-2 Force Write-Back vm-test-run-index_advisor> server # [ 0.103388] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-index_advisor> server # [ 0.103415] CPU features: detected: TLB range maintenance instructions vm-test-run-index_advisor> server # [ 0.103437] CPU features: detected: WFx with timeout vm-test-run-index_advisor> server # [ 0.103479] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-index_advisor> server # [ 0.103506] CPU features: detected: Scalable Vector Extension vm-test-run-index_advisor> server # [ 0.112860] alternatives: applying system-wide alternatives vm-test-run-index_advisor> server # [ 0.127484] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-index_advisor> server # [ 0.128186] SVE: maximum available vector length 256 bytes per vector vm-test-run-index_advisor> server # [ 0.128277] SVE: default vector length 64 bytes per vector vm-test-run-index_advisor> server # [ 0.140937] Memory: 3922804K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved) vm-test-run-index_advisor> server # [ 0.163609] devtmpfs: initialized vm-test-run-index_advisor> server # [ 0.191336] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-index_advisor> server # [ 0.191842] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-index_advisor> server # [ 0.194599] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-index_advisor> server # [ 0.194672] 0 pages in range for non-PLT usage vm-test-run-index_advisor> server # [ 0.194716] 509248 pages in range for PLT usage vm-test-run-index_advisor> server # [ 0.197572] pinctrl core: initialized pinctrl subsystem vm-test-run-index_advisor> server # [ 0.208338] DMI not present or invalid. vm-test-run-index_advisor> server # [ 0.226910] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-index_advisor> server # [ 0.238177] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-index_advisor> server # [ 0.239245] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-index_advisor> server # [ 0.240796] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-index_advisor> server # [ 0.241122] audit: initializing netlink subsys (disabled) vm-test-run-index_advisor> server # [ 0.243188] audit: type=2000 audit(0.140:1): state=initialized audit_enabled=0 res=1 vm-test-run-index_advisor> server # [ 0.250449] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-index_advisor> server # [ 0.250503] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-index_advisor> server # [ 0.250561] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-index_advisor> server # [ 0.250592] thermal_sys: Registered thermal governor 'user_space' vm-test-run-index_advisor> server # [ 0.250620] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-index_advisor> server # [ 0.251254] cpuidle: using governor ladder vm-test-run-index_advisor> server # [ 0.251645] cpuidle: using governor menu vm-test-run-index_advisor> server # [ 0.253534] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-index_advisor> server # [ 0.254190] ASID allocator initialised with 65536 entries vm-test-run-index_advisor> server # [ 0.261870] Serial: AMBA PL011 UART driver vm-test-run-index_advisor> server # [ 0.310651] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-index_advisor> server # [ 0.311697] printk: legacy console [ttyAMA0] enabled vm-test-run-index_advisor> server # [ 0.346536] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.346743] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-index_advisor> server # [ 0.346866] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.347109] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-index_advisor> server # [ 0.347229] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.347325] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-index_advisor> server # [ 0.347418] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.347511] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-index_advisor> server # [ 0.359538] fbcon: Taking over console vm-test-run-index_advisor> server # [ 0.360438] ACPI: Interpreter disabled. vm-test-run-index_advisor> server # [ 0.367223] iommu: Default domain type: Translated vm-test-run-index_advisor> server # [ 0.367821] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-index_advisor> server # [ 0.370547] SCSI subsystem initialized vm-test-run-index_advisor> server # [ 0.372627] usbcore: registered new interface driver usbfs vm-test-run-index_advisor> server # [ 0.373053] usbcore: registered new interface driver hub vm-test-run-index_advisor> server # [ 0.373291] usbcore: registered new device driver usb vm-test-run-index_advisor> server # [ 0.376533] pps_core: LinuxPPS API ver. 1 registered vm-test-run-index_advisor> server # [ 0.376659] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-index_advisor> server # [ 0.376838] PTP clock support registered vm-test-run-index_advisor> server # [ 0.377347] EDAC MC: Ver: 3.0.0 vm-test-run-index_advisor> server # [ 0.379218] scmi_core: SCMI protocol bus registered vm-test-run-index_advisor> server # [ 0.382271] FPGA manager framework vm-test-run-index_advisor> server # [ 0.384852] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-index_advisor> server # [ 0.393101] vgaarb: loaded vm-test-run-index_advisor> server # [ 0.397986] clocksource: Switched to clocksource arch_sys_counter vm-test-run-index_advisor> server # [ 0.402128] VFS: Disk quotas dquot_6.6.0 vm-test-run-index_advisor> server # [ 0.402400] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-index_advisor> server # [ 0.405629] netfs: FS-Cache loaded vm-test-run-index_advisor> server # [ 0.407809] pnp: PnP ACPI: disabled vm-test-run-index_advisor> server # [ 0.430962] NET: Registered PF_INET protocol family vm-test-run-index_advisor> server # [ 0.432624] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-index_advisor> server # [ 0.549500] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-index_advisor> server # [ 0.550678] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-index_advisor> server # [ 0.551871] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-index_advisor> server # [ 0.552865] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-index_advisor> server # [ 0.554143] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-index_advisor> server # [ 0.555987] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-index_advisor> server # [ 0.556749] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.557422] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.559382] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-index_advisor> server # [ 0.560213] NET: Registered PF_XDP protocol family vm-test-run-index_advisor> server # [ 0.560439] PCI: CLS 0 bytes, default 64 vm-test-run-index_advisor> server # [ 0.564326] kvm [1]: HYP mode not available vm-test-run-index_advisor> server # [ 0.564497] Trying to unpack rootfs image as initramfs... vm-test-run-index_advisor> server # [ 0.671394] Initialise system trusted keyrings vm-test-run-index_advisor> server # [ 0.673484] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-index_advisor> server # [ 0.677370] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-index_advisor> server # [ 0.678916] 9p: Installing v9fs 9p2000 file system support vm-test-run-index_advisor> server # [ 0.737197] Key type asymmetric registered vm-test-run-index_advisor> server # [ 0.737439] Asymmetric key parser 'x509' registered vm-test-run-index_advisor> server # [ 0.738794] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-index_advisor> server # [ 0.740082] io scheduler mq-deadline registered vm-test-run-index_advisor> server # [ 0.740251] io scheduler kyber registered vm-test-run-index_advisor> server # [ 0.766497] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-index_advisor> server # [ 0.772587] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-index_advisor> server # [ 0.776646] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-index_advisor> server # [ 0.777276] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-index_advisor> server # [ 0.778402] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-index_advisor> server # [ 0.779309] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-index_advisor> server # [ 0.780462] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-index_advisor> server # [ 0.781548] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-index_advisor> server # [ 0.783422] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-index_advisor> server # [ 0.784403] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-index_advisor> server # [ 0.784593] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-index_advisor> server # [ 0.784951] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-index_advisor> server # [ 0.785269] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-index_advisor> server # [ 0.786802] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.789718] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.790752] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.791264] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.791478] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.791995] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-index_advisor> server # [ 0.793037] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.793440] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.793826] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.793949] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.794680] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.795058] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-index_advisor> server # [ 0.795178] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.795713] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.796615] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.796997] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.797219] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.797552] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.799389] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.799593] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.799852] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.800216] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.800840] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.801589] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-index_advisor> server # [ 0.801701] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.801820] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.802889] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.803231] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.803343] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.803677] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.803810] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-index_advisor> server # [ 0.804641] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.805162] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.805285] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.805766] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.806268] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.806607] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.807244] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.807390] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.807785] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.807985] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-index_advisor> server # [ 0.808788] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.808912] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.809431] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.809810] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.809912] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.810029] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.813019] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-index_advisor> server # [ 0.813306] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-index_advisor> server # [ 0.813506] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.813843] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.814034] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.814187] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.814924] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.815579] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.815954] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.816604] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.816778] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.817043] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.817220] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.817356] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-index_advisor> server # [ 0.817650] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-index_advisor> server # [ 0.817782] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-index_advisor> server # [ 0.817892] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-index_advisor> server # [ 0.818242] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-index_advisor> server # [ 0.819066] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-index_advisor> server # [ 0.819180] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-index_advisor> server # [ 0.819771] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-index_advisor> server # [ 0.819891] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-index_advisor> server # [ 0.820094] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-index_advisor> server # [ 0.820384] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-index_advisor> server # [ 0.820781] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-index_advisor> server # [ 0.821075] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-index_advisor> server # [ 0.821219] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-index_advisor> server # [ 0.821332] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-index_advisor> server # [ 0.821439] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-index_advisor> server # [ 0.821539] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-index_advisor> server # [ 0.821645] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-index_advisor> server # [ 0.821746] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-index_advisor> server # [ 0.821852] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-index_advisor> server # [ 0.821955] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-index_advisor> server # [ 0.822266] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-index_advisor> server # [ 0.822377] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-index_advisor> server # [ 0.823243] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-index_advisor> server # [ 0.824517] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.880919] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.885226] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.888345] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.891429] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.893826] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.896606] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.898410] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.900483] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.902992] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.905292] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.907294] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.923224] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-index_advisor> server # [ 0.933005] msm_serial: driver initialized vm-test-run-index_advisor> server # [ 0.935477] SuperH (H)SCI(F) driver initialized vm-test-run-index_advisor> server # [ 0.935919] STM32 USART driver initialized vm-test-run-index_advisor> server # [ 0.980629] loop: module loaded vm-test-run-index_advisor> server # [ 0.981925] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-index_advisor> server # [ 0.986212] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-index_advisor> server # [ 0.997673] megasas: 07.727.03.00-rc1 vm-test-run-index_advisor> server # [ 1.002793] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-index_advisor> server # [ 1.005719] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-index_advisor> server # [ 1.007296] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-index_advisor> server # [ 1.009112] Using buffer write method vm-test-run-index_advisor> server # [ 1.009686] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-index_advisor> server # [ 1.012008] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-index_advisor> server # [ 1.012810] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-index_advisor> server # [ 1.015825] Using buffer write method vm-test-run-index_advisor> server # [ 1.016360] Concatenating MTD devices: vm-test-run-index_advisor> server # [ 1.016455] (0): "0.flash" vm-test-run-index_advisor> server # [ 1.016514] (1): "0.flash" vm-test-run-index_advisor> server # [ 1.016574] into device "0.flash" vm-test-run-index_advisor> server # [ 1.025440] Freeing initrd memory: 10684K vm-test-run-index_advisor> server # [ 1.050761] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-index_advisor> server # [ 1.063522] thunder_xcv, ver 1.0 vm-test-run-index_advisor> server # [ 1.063771] thunder_bgx, ver 1.0 vm-test-run-index_advisor> server # [ 1.064537] nicpf, ver 1.0 vm-test-run-index_advisor> server # [ 1.067410] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-index_advisor> server # [ 1.067596] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-index_advisor> server # [ 1.068085] hclge is initializing vm-test-run-index_advisor> server # [ 1.068317] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-index_advisor> server # [ 1.068398] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-index_advisor> server # [ 1.068615] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-index_advisor> server # [ 1.068870] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-index_advisor> server # [ 1.069213] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-index_advisor> server # [ 1.069470] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-index_advisor> server # [ 1.069728] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-index_advisor> server # [ 1.070625] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-index_advisor> server # [ 1.071424] sky2: driver version 1.30 vm-test-run-index_advisor> server # [ 1.079156] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-index_advisor> server # [ 1.081142] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-index_advisor> server # [ 1.082738] usbcore: registered new interface driver usb-storage vm-test-run-index_advisor> server # [ 1.084249] usbcore: registered new interface driver usbserial_generic vm-test-run-index_advisor> server # [ 1.085105] usbserial: USB Serial support registered for generic vm-test-run-index_advisor> server # [ 1.085605] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-index_advisor> server # [ 1.096499] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-index_advisor> server # [ 1.097161] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T20:37:03 UTC (1791405423) vm-test-run-index_advisor> server # [ 1.100950] i2c_dev: i2c /dev entries driver vm-test-run-index_advisor> server # [ 1.110574] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-index_advisor> server # [ 1.115539] sdhci: Secure Digital Host Controller Interface driver vm-test-run-index_advisor> server # [ 1.115835] sdhci: Copyright(c) Pierre Ossman vm-test-run-index_advisor> server # [ 1.117757] hub 1-0:1.0: USB hub found vm-test-run-index_advisor> server # [ 1.118872] Synopsys Designware Multimedia Card Interface Driver vm-test-run-index_advisor> server # [ 1.119956] hub 1-0:1.0: 6 ports detected vm-test-run-index_advisor> server # [ 1.121849] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-index_advisor> server # [ 1.128795] hid: raw HID events driver (C) Jiri Kosina vm-test-run-index_advisor> server # [ 1.131238] usbcore: registered new interface driver usbhid vm-test-run-index_advisor> server # [ 1.131411] usbhid: USB HID core driver vm-test-run-index_advisor> server # [ 1.140178] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-index_advisor> server # [ 1.153883] drop_monitor: Initializing network drop monitor service vm-test-run-index_advisor> server # [ 1.155819] NET: Registered PF_INET6 protocol family vm-test-run-index_advisor> server # [ 1.171407] Segment Routing with IPv6 vm-test-run-index_advisor> server # [ 1.172730] In-situ OAM (IOAM) with IPv6 vm-test-run-index_advisor> server # [ 1.173600] NET: Registered PF_PACKET protocol family vm-test-run-index_advisor> server # [ 1.175709] 9pnet: Installing 9P2000 support vm-test-run-index_advisor> server # [ 1.180430] Key type dns_resolver registered vm-test-run-index_advisor> server # [ 1.215372] registered taskstats version 1 vm-test-run-index_advisor> server # [ 1.217959] Loading compiled-in X.509 certificates vm-test-run-index_advisor> server # [ 1.276092] Demotion targets for Node 0: null vm-test-run-index_advisor> server # [ 1.278673] Key type .fscrypt registered vm-test-run-index_advisor> server # [ 1.278882] Key type fscrypt-provisioning registered vm-test-run-index_advisor> server # [ 1.287879] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-index_advisor> server # [ 1.330661] clk: Disabling unused clocks vm-test-run-index_advisor> server # [ 1.330959] PM: genpd: Disabling unused power domains vm-test-run-index_advisor> server # [ 1.332624] ALSA device list: vm-test-run-index_advisor> server # [ 1.332773] No soundcards found. vm-test-run-index_advisor> server # [ 1.415235] Freeing unused kernel memory: 4416K vm-test-run-index_advisor> server # [ 1.417317] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-index_advisor> server # [ 1.418826] Run /init as init process vm-test-run-index_advisor> server # [ 1.647441] 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-index_advisor> server # [ 1.808303] 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-index_advisor> server # [ 1.938466] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-index_advisor> server # [ 2.006750] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-index_advisor> server # [ 2.144190] 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-index_advisor> server # [ 2.146416] 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-index_advisor> server # [ 2.160878] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-index_advisor> server # [ 2.161705] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-index_advisor> server # [ 2.161730] [drm] features: -context_init vm-test-run-index_advisor> server # [ 2.164328] [drm] number of scanouts: 1 vm-test-run-index_advisor> server # [ 2.164469] [drm] number of cap sets: 0 vm-test-run-index_advisor> server # [ 2.178724] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-index_advisor> server # [ 2.214645] Console: switching to colour frame buffer device 160x50 vm-test-run-index_advisor> server # [ 2.228209] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-index_advisor> server # [ 5.480926] EXT4-fs (vda): mounted filesystem 824ef44d-3250-4846-9fb4-c5c0c400af74 r/w with ordered data mode. Quota mode: none. vm-test-run-index_advisor> server # [ 8.283930] EXT4-fs (vda): re-mounted 824ef44d-3250-4846-9fb4-c5c0c400af74. vm-test-run-index_advisor> server # [ 8.306943] booting system configuration /nix/store/x62hqmx3ldqjyxqdifjh19n95m6gd3ls-nixos-system-server-test vm-test-run-index_advisor> server # [ 19.680735] 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-index_advisor> server # [ 19.691705] systemd[1]: Detected virtualization qemu. vm-test-run-index_advisor> server # [ 19.696464] systemd[1]: Detected architecture arm64. vm-test-run-index_advisor> server # [ 19.700532] systemd[1]: Detected first boot. vm-test-run-index_advisor> server # [ 19.728836] systemd[1]: Initializing machine ID from random generator. vm-test-run-index_advisor> server # [ 19.756966] systemd[1]: Hostname set to . vm-test-run-index_advisor> server # [ 21.076240] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-index_advisor> server # [ 22.458103] systemd[1]: Applying preset policy. vm-test-run-index_advisor> server # [ 26.170899] systemd[1]: Populated /etc with preset unit settings. vm-test-run-index_advisor> server: Guest root shell did not produce any data yet... vm-test-run-index_advisor> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-index_advisor> server # [ 29.147649] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-index_advisor> server # [ 29.370749] systemd[1]: Created slice Slice /system/getty. vm-test-run-index_advisor> server # [ 29.385132] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-index_advisor> server # [ 29.397405] systemd[1]: Created slice User and Session Slice. vm-test-run-index_advisor> server # [ 29.405264] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-index_advisor> server # [ 29.410340] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-index_advisor> server # [ 29.415537] systemd[1]: Expecting device /dev/hvc0... vm-test-run-index_advisor> server # [ 29.419409] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-index_advisor> server # [ 29.423573] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-index_advisor> server # [ 29.426518] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-index_advisor> server # [ 29.431377] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-index_advisor> server # [ 29.435975] systemd[1]: Reached target Path Units. vm-test-run-index_advisor> server # [ 29.440852] systemd[1]: Reached target Remote File Systems. vm-test-run-index_advisor> server # [ 29.445668] systemd[1]: Reached target Slice Units. vm-test-run-index_advisor> server # [ 29.450470] systemd[1]: Reached target Swaps. vm-test-run-index_advisor> server # [ 29.487101] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-index_advisor> server # [ 29.507531] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-index_advisor> server # [ 29.517713] systemd[1]: Listening on Journal Audit Socket. vm-test-run-index_advisor> server # [ 29.524153] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-index_advisor> server # [ 29.530810] systemd[1]: Listening on Journal Sockets. vm-test-run-index_advisor> server # [ 29.539551] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-index_advisor> server # [ 29.543667] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 29.549590] systemd[1]: Listening on udev Control Socket. vm-test-run-index_advisor> server # [ 29.554547] systemd[1]: Listening on udev Kernel Socket. vm-test-run-index_advisor> server # [ 29.597956] systemd[1]: Mounting Huge Pages File System... vm-test-run-index_advisor> server # [ 29.639953] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-index_advisor> server # [ 29.685215] systemd[1]: Mounting Kernel Debug File System... vm-test-run-index_advisor> server # [ 29.739226] systemd[1]: Mounting Kernel Trace File System... vm-test-run-index_advisor> server # [ 29.835660] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-index_advisor> server # [ 29.985406] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-index_advisor> server # [ 30.009130] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-index_advisor> server # [ 30.126972] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-index_advisor> server # [ 30.145126] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-index_advisor> server # [ 30.164784] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-index_advisor> server # [ 30.212690] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-index_advisor> server # [ 30.219603] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-index_advisor> server # [ 30.225568] 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-index_advisor> server # [ 30.308083] systemd[1]: Starting Journal Service... vm-test-run-index_advisor> server # [ 30.371630] systemd[1]: Starting Load Kernel Modules... vm-test-run-index_advisor> server # [ 30.465785] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-index_advisor> server # [ 30.522705] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-index_advisor> server # [ 30.531207] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 30.583056] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-index_advisor> server # [ 30.690293] systemd[1]: Mounted Huge Pages File System. vm-test-run-index_advisor> server # [ 30.699123] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-index_advisor> server # [ 30.709705] systemd[1]: Mounted Kernel Debug File System. vm-test-run-index_advisor> server # [ 30.728142] systemd[1]: Mounted Kernel Trace File System. vm-test-run-index_advisor> server # [ 30.748048] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-index_advisor> server # [ 30.772794] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-index_advisor> server # [ 30.829200] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-index_advisor> server # [ 31.336754] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 31.353266] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-index_advisor> server # [ 31.554622] EXT4-fs (vda): re-mounted 824ef44d-3250-4846-9fb4-c5c0c400af74. vm-test-run-index_advisor> server # [ 31.613077] systemd[1]: Finished Load Kernel Modules. vm-test-run-index_advisor> server # [ 31.661716] fuse: init (API version 7.41) vm-test-run-index_advisor> server # [ 31.675922] systemd[1]: Starting Firewall... vm-test-run-index_advisor> server # [ 31.739554] systemd[1]: Starting Apply Kernel Variables... vm-test-run-index_advisor> server # [ 31.754041] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 31.767252] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-index_advisor> server # [ 31.784019] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-index_advisor> server # [ 31.822792] systemd[1]: Mounting FUSE Control File System... vm-test-run-index_advisor> server # [ 31.829234] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-index_advisor> server # [ 31.887566] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-index_advisor> server # [ 31.893346] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 32.061520] systemd[1]: Mounted FUSE Control File System. vm-test-run-index_advisor> server # [ 32.125079] systemd-journald[398]: Collecting audit messages is enabled. vm-test-run-index_advisor> server # [ 32.824398] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-index_advisor> server # [ 32.835301] systemd[1]: Reached target First Boot Complete. vm-test-run-index_advisor> server # [ 33.037587] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-index_advisor> server # [ 33.145404] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-index_advisor> server # [ 33.197405] systemd[1]: Finished Apply Kernel Variables. vm-test-run-index_advisor> server # [ 33.424188] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-index_advisor> server # [ 34.287050] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-index_advisor> server # [ 34.312366] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-index_advisor> server # [ 34.365887] systemd[1]: Mounting /run/wrappers... vm-test-run-index_advisor> server # [ 34.425654] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-index_advisor> server # [ 34.627232] systemd[1]: Started Journal Service. vm-test-run-index_advisor> server # [ 34.501913] systemd-modules-load[399]: Module 'atkbd' is built in vm-test-run-index_advisor> server # [ 34.511368] systemd-modules-load[399]: Module 'loop' is built in vm-test-run-index_advisor> server # [ 34.579541] systemd-oomd[400]: No swap; memory pressure usage will be degraded vm-test-run-index_advisor> server # [ 34.621905] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-index_advisor> server # [ 34.855381] systemd[1]: Mounted /run/wrappers. vm-test-run-index_advisor> server # [ 34.905429] systemd[1]: Reached target Local File Systems. vm-test-run-index_advisor> server # [ 34.937156] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-index_advisor> server # [ 34.995199] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-index_advisor> server # [ 35.013956] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-index_advisor> server # [ 35.055526] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-index_advisor> server # [ 35.591793] systemd-journald[398]: Received client request to flush runtime journal. vm-test-run-index_advisor> server # [ 36.278551] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-index_advisor> server # [ 36.314845] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-index_advisor> server # [ 36.371610] systemd-udevd[428]: Using default interface naming scheme 'v258'. vm-test-run-index_advisor> server # [ 36.381578] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-index_advisor> server # [ 36.407221] systemd[1]: Starting Create System Files and Directories... vm-test-run-index_advisor> server # [ 38.520136] systemd[1]: Finished Create System Files and Directories. vm-test-run-index_advisor> server # [ 38.610133] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-index_advisor> server # [ 38.649592] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-index_advisor> server # [ 38.697162] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-index_advisor> server # [ 39.779747] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-index_advisor> server # [ 40.796449] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-index_advisor> server # [ 40.838466] systemd[1]: Starting Update is Completed... vm-test-run-index_advisor> server # [ 41.683510] systemd[1]: Finished Update is Completed. vm-test-run-index_advisor> server # [ 45.526634] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 45.546978] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-index_advisor> server # [ 45.550707] systemd[1]: suid-sgid-wrappers.service: Consumed 2.909s CPU time, 5.5M memory peak. vm-test-run-index_advisor> server # [ 47.791739] systemd[1]: Finished Firewall. vm-test-run-index_advisor> server # [ 49.261372] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-index_advisor> server # [ 49.270792] systemd[1]: Reached target System Initialization. vm-test-run-index_advisor> server # [ 49.284943] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-index_advisor> server # [ 49.289593] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-index_advisor> server # [ 49.297601] systemd[1]: Reached target Timer Units. vm-test-run-index_advisor> server # [ 49.301890] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-index_advisor> server # [ 49.313771] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-index_advisor> server # [ 49.321850] systemd[1]: Listening on Hostname Service Socket. vm-test-run-index_advisor> server # [ 49.323212] systemd[1]: Reached target Socket Units. vm-test-run-index_advisor> server # [ 49.330696] systemd[1]: Reached target Basic System. vm-test-run-index_advisor> server # [ 49.343090] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-index_advisor> server # [ 49.391225] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 49.446887] systemd[1]: Started Reset console on configuration changes. vm-test-run-index_advisor> server # [ 49.497123] systemd[1]: Starting resolvconf update... vm-test-run-index_advisor> server # [ 49.563545] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-index_advisor> server # [ 50.526609] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-index_advisor> server # [ 50.829059] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 50.902179] nsncd[631]: Oct 07 20:37:53.352 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-index_advisor> server # [ 50.922929] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 50.938290] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 50.945348] systemd[1]: Starting User Login Management... vm-test-run-index_advisor> server # [ 50.967400] dbus-daemon[634]: dbus[634]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-index_advisor> server # [ 51.598488] systemd[1]: Started D-Bus System Message Bus. vm-test-run-index_advisor> server # [ 52.642971] systemd-logind[652]: New seat seat0. vm-test-run-index_advisor> server # [ 52.691700] systemd[1]: Started User Login Management. vm-test-run-index_advisor> server # [ 52.735324] systemd[1]: Starting linger-users.service... vm-test-run-index_advisor> server # [ 52.891580] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 52.911133] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-index_advisor> server # [ 52.927347] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 52.935389] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-index_advisor> server # [ 52.957146] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 53.002824] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 53.017063] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 53.217021] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 53.612014] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 53.635783] systemd[1]: Finished linger-users.service. vm-test-run-index_advisor> server # [ 54.170626] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 54.180143] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 54.191732] nsncd[698]: Oct 07 20:37:56.736 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-index_advisor> server # [ 54.198086] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 54.559510] systemd[1]: Finished resolvconf update. vm-test-run-index_advisor> server # [ 54.582233] systemd[1]: Reached target Preparation for Network. vm-test-run-index_advisor> server # [ 54.634808] systemd[1]: Starting DHCP Client... vm-test-run-index_advisor> server # [ 56.467567] dhcpcd[740]: dhcpcd-10.2.4 starting vm-test-run-index_advisor> server # [ 56.604221] dhcpcd[764]: dev: loaded udev vm-test-run-index_advisor> server # [ 57.131948] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-index_advisor> server # [ 57.808205] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-index_advisor> server # [ 58.116480] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-index_advisor> server # [ 58.121617] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-index_advisor> server # [ 58.149031] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-index_advisor> server # [ 58.164150] cfg80211: failed to load regulatory.db vm-test-run-index_advisor> server: Guest root shell did not produce any data yet... vm-test-run-index_advisor> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-index_advisor> server # [ 59.155758] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-index_advisor> server # [ 59.010322] dhcpcd[764]: eth0: waiting for carrier vm-test-run-index_advisor> server # [ 59.023652] dhcpcd[764]: libudev: received NULL device vm-test-run-index_advisor> server # [ 59.033053] dhcpcd[764]: libudev: received NULL device vm-test-run-index_advisor> server # [ 59.037548] dhcpcd[764]: eth0: carrier acquired vm-test-run-index_advisor> server # [ 59.198147] dhcpcd[764]: DUID 00:01:00:01:32:59:6a:29:52:54:00:12:34:56 vm-test-run-index_advisor> server # [ 59.201585] dhcpcd[764]: eth0: IAID 00:12:34:56 vm-test-run-index_advisor> server # [ 59.204758] dhcpcd[764]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-index_advisor> server # [ 59.523192] dhcpcd[764]: eth0: soliciting a DHCP lease vm-test-run-index_advisor> server # [ 59.544244] systemd[1]: Found device /dev/hvc0. vm-test-run-index_advisor> server # [ 59.589541] dhcpcd[764]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-index_advisor> server # [ 59.629266] dhcpcd[764]: eth0: probing address 10.0.2.15/24 vm-test-run-index_advisor> server # [ 60.341888] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-index_advisor> server # [ 60.383448] systemd[1]: Started backdoor.service. vm-test-run-index_advisor> server # [ 60.642910] dhcpcd[764]: eth0: soliciting an IPv6 router vm-test-run-index_advisor> server # [ 60.656498] dhcpcd[764]: eth0: Router Advertisement from fe80::2 vm-test-run-index_advisor> server # [ 60.668010] dhcpcd[764]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-index_advisor> server # [ 60.671049] dhcpcd[764]: eth0: adding route to fec0::/64 vm-test-run-index_advisor> server # [ 60.675777] dhcpcd[764]: eth0: adding default route via fe80::2 vm-test-run-index_advisor> server # connecting to host... vm-test-run-index_advisor> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-index_advisor> server: connected to guest root shell vm-test-run-index_advisor> server: (connecting took 62.37 seconds) vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 62.37 seconds) vm-test-run-index_advisor> server # [ 63.635804] (udev-worker)[810]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-index_advisor> server # [ 63.646886] (udev-worker)[809]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-index_advisor> server # [ 63.651428] (udev-worker)[810]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-index_advisor> server # [ 64.199825] systemd[1]: Found device Virtio network device. vm-test-run-index_advisor> server # [ 64.225416] systemd[1]: Starting Address configuration of eth1... vm-test-run-index_advisor> server # [ 64.291150] dhcpcd[764]: eth0: IAID 00:12:34:56 vm-test-run-index_advisor> server # [ 64.587730] dhcpcd[764]: eth0: soliciting a DHCP lease vm-test-run-index_advisor> server # [ 64.596718] dhcpcd[764]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-index_advisor> server # [ 64.597753] dhcpcd[764]: eth0: probing address 10.0.2.15/24 vm-test-run-index_advisor> server # [ 65.037508] dhcpcd[764]: eth0: soliciting an IPv6 router vm-test-run-index_advisor> server # [ 65.387054] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-index_advisor> server # [ 65.627360] network-addresses-eth1-start[842]: adding address 192.168.1.1/24... done vm-test-run-index_advisor> server # [ 65.936803] network-addresses-eth1-start[842]: adding address 2001:db8:1::1/64... done vm-test-run-index_advisor> server # [ 66.271815] systemd[1]: Finished Address configuration of eth1. vm-test-run-index_advisor> server # [ 66.334994] systemd[1]: Starting Networking Setup... vm-test-run-index_advisor> server # [ 66.391181] dhcpcd[764]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-index_advisor> server # [ 66.397671] dhcpcd[764]: eth0: adding route to 10.0.2.0/24 vm-test-run-index_advisor> server # [ 66.403823] dhcpcd[764]: eth0: adding default route via 10.0.2.2 vm-test-run-index_advisor> server # [ 69.859546] systemd[1]: Started DHCP Client. vm-test-run-index_advisor> server # [ 70.014972] systemd-logind[652]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-index_advisor> server # [ 72.033218] systemd[1]: Finished Networking Setup. vm-test-run-index_advisor> server # [ 72.057458] systemd[1]: Reached target Network. vm-test-run-index_advisor> server # [ 72.097617] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-index_advisor> server # [ 72.151325] systemd[1]: Starting Permit User Sessions... vm-test-run-index_advisor> server # [ 72.790410] systemd[1]: Finished Permit User Sessions. vm-test-run-index_advisor> server # [ 72.863756] systemd[1]: Started Getty on tty1. vm-test-run-index_advisor> server # [ 72.871501] systemd[1]: Reached target Login Prompts. vm-test-run-index_advisor> server # [ 73.594381] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1023]: Initializing database at /var/lib/postgresql/data vm-test-run-index_advisor> server # [ 75.779673] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The files belonging to this database system will be owned by user "postgres". vm-test-run-index_advisor> server # [ 75.791588] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: This user must also own the server process. vm-test-run-index_advisor> server # [ 75.897593] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-index_advisor> server # [ 75.899788] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The default database encoding has accordingly been set to "UTF8". vm-test-run-index_advisor> server # [ 75.906725] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The default text search configuration will be set to "english". vm-test-run-index_advisor> server # [ 75.913061] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: Data page checksums are enabled. vm-test-run-index_advisor> server # [ 75.914925] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-index_advisor> server # [ 75.915638] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: creating subdirectories ... ok vm-test-run-index_advisor> server # [ 75.923677] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting dynamic shared memory implementation ... posix vm-test-run-index_advisor> server # [ 77.178843] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting default max_connections ... 100 vm-test-run-index_advisor> server # [ 78.198802] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting default shared_buffers ... 128MB vm-test-run-index_advisor> server # [ 82.312731] mousedev: PS/2 mouse device common for all mice vm-test-run-index_advisor> server # [ 87.127487] systemd-logind[652]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-index_advisor> server # [ 90.117478] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting default time zone ... UTC vm-test-run-index_advisor> server # [ 90.163642] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: creating configuration files ... ok vm-test-run-index_advisor> server # [ 95.791906] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: running bootstrap script ... ok vm-test-run-index_advisor> server # [ 110.206867] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: performing post-bootstrap initialization ... ok vm-test-run-index_advisor> server # [ 112.956018] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: syncing data to disk ... ok vm-test-run-index_advisor> server # [ 112.966717] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-index_advisor> server # [ 112.968749] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: 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-index_advisor> server # [ 112.978241] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: Success. You can now start the database server using: vm-test-run-index_advisor> server # [ 112.989736] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: /nix/store/2bhdbvpk81xy3mkfgqrpc719ip588cba-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-index_advisor> server # [ 115.231905] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1023]: Validating PostgreSQL configuration... vm-test-run-index_advisor> server # [ 115.747120] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1420]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault vm-test-run-index_advisor> server # [ 116.757130] postgres[1423]: 2026-10-07 20:38:59.292 UTC [1423] LOG: pgaudit extension initialized vm-test-run-index_advisor> server # [ 118.087691] postgres[1423]: 2026-10-07 20:39:00.602 UTC [1423] LOG: pgsodium primary server secret key loaded vm-test-run-index_advisor> server # [ 118.820067] postgres[1423]: 2026-10-07 20:39:01.392 UTC [1423] LOG: vault primary server secret key loaded vm-test-run-index_advisor> server # [ 119.203269] postgres[1423]: 2026-10-07 20:39:01.771 UTC [1423] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-index_advisor> server # [ 119.216519] postgres[1423]: 2026-10-07 20:39:01.789 UTC [1423] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-index_advisor> server # [ 119.222646] postgres[1423]: 2026-10-07 20:39:01.792 UTC [1423] LOG: listening on IPv6 address "::", port 5432 vm-test-run-index_advisor> server # [ 119.240017] postgres[1423]: 2026-10-07 20:39:01.811 UTC [1423] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-index_advisor> server # [ 119.357220] postgres[1457]: 2026-10-07 20:39:01.914 UTC [1457] LOG: database system was shut down at 2026-10-07 20:38:52 UTC vm-test-run-index_advisor> server # [ 119.591737] postgres[1423]: 2026-10-07 20:39:02.162 UTC [1423] LOG: database system is ready to accept connections vm-test-run-index_advisor> server # [ 119.677977] postgres[1460]: 2026-10-07 20:39:02.181 UTC [1460] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-index_advisor> server # [ 119.682484] postgres[1461]: 2026-10-07 20:39:02.181 UTC [1461] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 119.699097] systemd[1]: Started PostgreSQL Database Server. vm-test-run-index_advisor> server # [ 119.716788] systemd[1]: Starting Supabase Database Initialization... vm-test-run-index_advisor> server # [ 119.817476] postgres[1423]: 2026-10-07 20:39:02.384 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1461) exited with exit code 1 vm-test-run-index_advisor> server # [ 120.131047] postgres[1462]: 2026-10-07 20:39:02.660 UTC [1462] LOG: pg_cron scheduler started vm-test-run-index_advisor> server # [ 120.362618] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Waiting for PostgreSQL to be ready... vm-test-run-index_advisor> server # [ 121.009056] postgres[1475]: 2026-10-07 20:39:03.545 UTC [1475] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 121.188176] postgres[1423]: 2026-10-07 20:39:03.703 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1475) exited with exit code 1 vm-test-run-index_advisor> server # [ 121.563874] postgres[1479]: ::1 2026-10-07 20:39:04.098 UTC [1479] root@root FATAL: role "root" does not exist vm-test-run-index_advisor> server # [ 121.646933] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: PostgreSQL is ready vm-test-run-index_advisor> server # [ 121.655287] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Creating postgres role... vm-test-run-index_advisor> server # [ 122.261532] postgres[1494]: 2026-10-07 20:39:04.787 UTC [1494] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 122.339387] postgres[1423]: 2026-10-07 20:39:04.898 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1494) exited with exit code 1 vm-test-run-index_advisor> server # [ 122.439201] postgres[1493]: ::1 2026-10-07 20:39:04.981 UTC [1493] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-index_advisor> server # [ 122.499744] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1484]: CREATE ROLE vm-test-run-index_advisor> server # [ 122.931776] postgres[1502]: ::1 2026-10-07 20:39:05.487 UTC [1502] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-index_advisor> server # [ 123.000878] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1500]: ALTER DATABASE vm-test-run-index_advisor> server # [ 123.054764] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-index_advisor> server # [ 123.402067] postgres[1504]: ::1 2026-10-07 20:39:05.960 UTC [1504] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-index_advisor> server # [ 123.423333] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE PUBLICATION vm-test-run-index_advisor> server # [ 123.429191] postgres[1504]: ::1 2026-10-07 20:39:05.992 UTC [1504] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-index_advisor> server # [ 123.441778] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE vm-test-run-index_advisor> server # [ 123.445886] postgres[1504]: ::1 2026-10-07 20:39:06.015 UTC [1504] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-index_advisor> server # [ 123.456482] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 123.460357] postgres[1504]: ::1 2026-10-07 20:39:06.032 UTC [1504] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-index_advisor> server # [ 123.471081] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 123.477665] postgres[1504]: ::1 2026-10-07 20:39:06.046 UTC [1504] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-index_advisor> server # [ 123.484510] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE vm-test-run-index_advisor> server # [ 123.488651] postgres[1504]: ::1 2026-10-07 20:39:06.060 UTC [1504] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-index_advisor> server # [ 123.499574] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT vm-test-run-index_advisor> server # [ 123.502853] postgres[1504]: ::1 2026-10-07 20:39:06.075 UTC [1504] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-index_advisor> server # [ 123.513453] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 123.517175] postgres[1504]: ::1 2026-10-07 20:39:06.087 UTC [1504] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-index_advisor> server # [ 123.526311] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE vm-test-run-index_advisor> server # [ 123.530931] postgres[1504]: ::1 2026-10-07 20:39:06.100 UTC [1504] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-index_advisor> server # [ 123.541314] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 123.542297] postgres[1504]: ::1 2026-10-07 20:39:06.112 UTC [1504] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-index_advisor> server # [ 126.790228] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 126.795646] postgres[1504]: ::1 2026-10-07 20:39:09.346 UTC [1504] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-index_advisor> server # [ 127.818664] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 127.829649] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 127.839685] postgres[1504]: ::1 2026-10-07 20:39:10.392 UTC [1504] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-index_advisor> server # [ 127.845654] postgres[1504]: ::1 2026-10-07 20:39:10.402 UTC [1504] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-index_advisor> server # [ 127.847061] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 127.851305] postgres[1504]: ::1 2026-10-07 20:39:10.422 UTC [1504] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-index_advisor> server # [ 127.860034] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 127.861893] postgres[1504]: ::1 2026-10-07 20:39:10.432 UTC [1504] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-index_advisor> server # [ 127.871455] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE vm-test-run-index_advisor> server # [ 127.874804] postgres[1504]: ::1 2026-10-07 20:39:10.446 UTC [1504] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-index_advisor> server # [ 127.886277] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE vm-test-run-index_advisor> server # [ 127.889488] postgres[1504]: ::1 2026-10-07 20:39:10.461 UTC [1504] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-index_advisor> server # [ 127.900096] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE vm-test-run-index_advisor> server # [ 127.902784] postgres[1504]: ::1 2026-10-07 20:39:10.474 UTC [1504] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-index_advisor> server # [ 127.913784] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE vm-test-run-index_advisor> server # [ 127.915698] postgres[1504]: ::1 2026-10-07 20:39:10.488 UTC [1504] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-index_advisor> server # [ 127.927970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE vm-test-run-index_advisor> server # [ 127.934141] postgres[1504]: ::1 2026-10-07 20:39:10.503 UTC [1504] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 127.942536] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT vm-test-run-index_advisor> server # [ 127.945951] postgres[1504]: ::1 2026-10-07 20:39:10.517 UTC [1504] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 127.967230] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 127.974012] postgres[1504]: ::1 2026-10-07 20:39:10.544 UTC [1504] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 127.985735] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 127.987214] postgres[1504]: ::1 2026-10-07 20:39:10.559 UTC [1504] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.001170] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 128.002227] postgres[1504]: ::1 2026-10-07 20:39:10.572 UTC [1504] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.017542] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT vm-test-run-index_advisor> server # [ 128.019765] postgres[1504]: ::1 2026-10-07 20:39:10.585 UTC [1504] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-index_advisor> server # [ 128.024011] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE vm-test-run-index_advisor> server # [ 128.030949] postgres[1504]: ::1 2026-10-07 20:39:10.600 UTC [1504] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 128.033188] postgres[1504]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.040068] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 128.042045] postgres[1504]: ::1 2026-10-07 20:39:10.612 UTC [1504] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 128.044005] postgres[1504]: on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.056484] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 128.057347] postgres[1504]: ::1 2026-10-07 20:39:10.629 UTC [1504] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 128.057815] postgres[1504]: on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.069554] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 128.072479] postgres[1504]: ::1 2026-10-07 20:39:10.640 UTC [1504] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-index_advisor> server # [ 128.080183] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE vm-test-run-index_advisor> server # [ 128.083186] postgres[1504]: ::1 2026-10-07 20:39:10.655 UTC [1504] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-index_advisor> server # [ 128.092674] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE vm-test-run-index_advisor> server # [ 128.150314] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-index_advisor> server # [ 128.760757] postgres[1542]: ::1 2026-10-07 20:39:11.309 UTC [1542] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-index_advisor> server # [ 128.763614] postgres[1542]: ::1 2026-10-07 20:39:11.330 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-index_advisor> server # [ 128.773531] postgres[1542]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 128.774238] postgres[1542]: id uuid NOT NULL UNIQUE, vm-test-run-index_advisor> server # [ 128.774624] postgres[1542]: aud varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.774969] postgres[1542]: "role" varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.775239] postgres[1542]: email varchar(255) NULL UNIQUE, vm-test-run-index_advisor> server # [ 128.775497] postgres[1542]: encrypted_password varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.775767] postgres[1542]: confirmed_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.785986] postgres[1542]: invited_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.787315] postgres[1542]: confirmation_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.794992] postgres[1542]: confirmation_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.795788] postgres[1542]: recovery_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.796122] postgres[1542]: recovery_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.796406] postgres[1542]: email_change_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.803096] postgres[1542]: email_change varchar(255) NULL, vm-test-run-index_advisor> server # [ 128.803713] postgres[1542]: email_change_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.810158] postgres[1542]: last_sign_in_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.811099] postgres[1542]: raw_app_meta_data jsonb NULL, vm-test-run-index_advisor> server # [ 128.811670] postgres[1542]: raw_user_meta_data jsonb NULL, vm-test-run-index_advisor> server # [ 128.818197] postgres[1542]: is_super_admin bool NULL, vm-test-run-index_advisor> server # [ 128.819781] postgres[1542]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.826939] postgres[1542]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 128.828018] postgres[1542]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 128.835516] postgres[1542]: ); vm-test-run-index_advisor> server # [ 128.858857] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 128.999659] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE vm-test-run-index_advisor> server # [ 129.005955] postgres[1542]: ::1 2026-10-07 20:39:11.575 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-index_advisor> server # [ 129.107430] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX vm-test-run-index_advisor> server # [ 129.138078] postgres[1542]: ::1 2026-10-07 20:39:11.698 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-index_advisor> server # [ 129.202715] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX vm-test-run-index_advisor> server # [ 129.210425] postgres[1542]: ::1 2026-10-07 20:39:11.778 UTC [1542] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-index_advisor> server # [ 129.227419] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT vm-test-run-index_advisor> server # [ 129.236447] postgres[1542]: ::1 2026-10-07 20:39:11.806 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-index_advisor> server # [ 129.238653] postgres[1542]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 129.249149] postgres[1542]: id bigserial NOT NULL, vm-test-run-index_advisor> server # [ 129.250642] postgres[1542]: "token" varchar(255) NULL, vm-test-run-index_advisor> server # [ 129.251469] postgres[1542]: user_id varchar(255) NULL, vm-test-run-index_advisor> server # [ 129.263983] postgres[1542]: revoked bool NULL, vm-test-run-index_advisor> server # [ 129.270586] postgres[1542]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 129.271654] postgres[1542]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 129.279910] postgres[1542]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 129.289682] postgres[1542]: ); vm-test-run-index_advisor> server # [ 129.430505] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE vm-test-run-index_advisor> server # [ 129.450515] postgres[1542]: ::1 2026-10-07 20:39:12.018 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-index_advisor> server # [ 129.506732] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX vm-test-run-index_advisor> server # [ 129.515066] postgres[1542]: ::1 2026-10-07 20:39:12.081 UTC [1542] 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-index_advisor> server # [ 129.587049] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX vm-test-run-index_advisor> server # [ 129.601498] postgres[1542]: ::1 2026-10-07 20:39:12.154 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-index_advisor> server # [ 129.640012] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX vm-test-run-index_advisor> server # [ 129.649569] postgres[1542]: ::1 2026-10-07 20:39:12.210 UTC [1542] 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-index_advisor> server # [ 129.651621] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT vm-test-run-index_advisor> server # [ 129.661376] postgres[1542]: ::1 2026-10-07 20:39:12.220 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-index_advisor> server # [ 129.671567] postgres[1542]: id uuid NOT NULL, vm-test-run-index_advisor> server # [ 129.690683] postgres[1542]: uuid uuid NULL, vm-test-run-index_advisor> server # [ 129.697377] postgres[1542]: raw_base_config text NULL, vm-test-run-index_advisor> server # [ 129.709096] postgres[1542]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 129.710658] postgres[1542]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 129.712022] postgres[1542]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 129.712456] postgres[1542]: ); vm-test-run-index_advisor> server # [ 129.828203] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE vm-test-run-index_advisor> server # [ 129.879701] postgres[1542]: ::1 2026-10-07 20:39:12.396 UTC [1542] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-index_advisor> server # [ 129.889014] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT vm-test-run-index_advisor> server # [ 129.890520] postgres[1542]: ::1 2026-10-07 20:39:12.414 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-index_advisor> server # [ 129.892144] postgres[1542]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 129.900497] postgres[1542]: id uuid NOT NULL, vm-test-run-index_advisor> server # [ 129.903984] postgres[1542]: payload json NULL, vm-test-run-index_advisor> server # [ 129.911844] postgres[1542]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 129.914285] postgres[1542]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 129.925021] postgres[1542]: ); vm-test-run-index_advisor> server # [ 130.000284] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE vm-test-run-index_advisor> server # [ 130.018024] postgres[1542]: ::1 2026-10-07 20:39:12.584 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id); vm-test-run-index_advisor> server # [ 130.051146] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX vm-test-run-index_advisor> server # [ 130.057358] postgres[1542]: ::1 2026-10-07 20:39:12.625 UTC [1542] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-index_advisor> server # [ 130.062266] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT vm-test-run-index_advisor> server # [ 130.063306] postgres[1542]: ::1 2026-10-07 20:39:12.635 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-index_advisor> server # [ 130.064114] postgres[1542]: "version" varchar(255) NOT NULL, vm-test-run-index_advisor> server # [ 130.065437] postgres[1542]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-index_advisor> server # [ 130.066149] postgres[1542]: ); vm-test-run-index_advisor> server # [ 130.101110] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE vm-test-run-index_advisor> server # [ 130.102984] postgres[1542]: ::1 2026-10-07 20:39:12.674 UTC [1542] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-index_advisor> server # [ 130.111675] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT vm-test-run-index_advisor> server # [ 130.131777] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: INSERT 0 7 vm-test-run-index_advisor> server # [ 130.135400] postgres[1542]: ::1 2026-10-07 20:39:12.706 UTC [1542] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-index_advisor> server # [ 130.137771] postgres[1542]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-index_advisor> server # [ 130.138496] postgres[1542]: $$ language sql stable; vm-test-run-index_advisor> server # [ 130.149701] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 130.158219] postgres[1542]: ::1 2026-10-07 20:39:12.728 UTC [1542] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-index_advisor> server # [ 130.159726] postgres[1542]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-index_advisor> server # [ 130.166276] postgres[1542]: $$ language sql stable; vm-test-run-index_advisor> server # [ 130.174896] postgres[1542]: ::1 2026-10-07 20:39:12.743 UTC [1542] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-index_advisor> server # [ 130.175874] postgres[1542]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-index_advisor> server # [ 130.179352] postgres[1542]: $$ language sql stable; vm-test-run-index_advisor> server # [ 130.190031] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 130.190918] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 130.191537] postgres[1542]: ::1 2026-10-07 20:39:12.757 UTC [1542] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.193059] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT vm-test-run-index_advisor> server # [ 130.198883] postgres[1542]: ::1 2026-10-07 20:39:12.767 UTC [1542] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 130.209577] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE ROLE vm-test-run-index_advisor> server # [ 130.210567] postgres[1542]: ::1 2026-10-07 20:39:12.778 UTC [1542] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.219928] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT vm-test-run-index_advisor> server # [ 130.229661] postgres[1542]: ::1 2026-10-07 20:39:12.794 UTC [1542] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.237260] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT vm-test-run-index_advisor> server # [ 130.238107] postgres[1542]: ::1 2026-10-07 20:39:12.810 UTC [1542] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.250808] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT vm-test-run-index_advisor> server # [ 130.253625] postgres[1542]: ::1 2026-10-07 20:39:12.826 UTC [1542] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-index_advisor> server # [ 130.259949] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER ROLE vm-test-run-index_advisor> server # [ 130.264275] postgres[1542]: ::1 2026-10-07 20:39:12.835 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.285602] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE vm-test-run-index_advisor> server # [ 130.290196] postgres[1542]: ::1 2026-10-07 20:39:12.855 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.303126] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE vm-test-run-index_advisor> server # [ 130.303996] postgres[1542]: ::1 2026-10-07 20:39:12.876 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.319124] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE vm-test-run-index_advisor> server # [ 130.320001] postgres[1542]: ::1 2026-10-07 20:39:12.892 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.327886] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE vm-test-run-index_advisor> server # [ 130.331985] postgres[1542]: ::1 2026-10-07 20:39:12.904 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 130.346113] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE vm-test-run-index_advisor> server # [ 130.391590] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-index_advisor> server # [ 130.767182] postgres[1564]: ::1 2026-10-07 20:39:13.325 UTC [1564] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-index_advisor> server # [ 130.798316] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 130.803637] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: CREATE ROLE vm-test-run-index_advisor> server # [ 130.810782] postgres[1564]: ::1 2026-10-07 20:39:13.358 UTC [1564] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 130.811394] postgres[1564]: ::1 2026-10-07 20:39:13.371 UTC [1564] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-index_advisor> server # [ 130.819078] postgres[1564]: ::1 2026-10-07 20:39:13.386 UTC [1564] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-index_advisor> server # [ 130.829589] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: ALTER ROLE vm-test-run-index_advisor> server # [ 130.833772] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: GRANT vm-test-run-index_advisor> server # [ 130.884861] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: DO vm-test-run-index_advisor> server # [ 130.941404] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-index_advisor> server # [ 131.331917] postgres[1567]: ::1 2026-10-07 20:39:13.886 UTC [1567] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-index_advisor> server # [ 131.344256] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: ALTER ROLE vm-test-run-index_advisor> server # [ 131.350944] postgres[1567]: ::1 2026-10-07 20:39:13.923 UTC [1567] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-index_advisor> server # [ 131.364107] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: ALTER ROLE vm-test-run-index_advisor> server # [ 131.366319] postgres[1567]: ::1 2026-10-07 20:39:13.937 UTC [1567] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-index_advisor> server # [ 131.367831] postgres[1567]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 131.368849] postgres[1567]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 131.372901] postgres[1567]: AS $$ vm-test-run-index_advisor> server # [ 131.382258] postgres[1567]: DECLARE vm-test-run-index_advisor> server # [ 131.398306] postgres[1567]: schema_is_cron bool; vm-test-run-index_advisor> server # [ 131.399997] postgres[1567]: BEGIN vm-test-run-index_advisor> server # [ 131.401763] postgres[1567]: schema_is_cron = ( vm-test-run-index_advisor> server # [ 131.402900] postgres[1567]: SELECT n.nspname = 'cron' vm-test-run-index_advisor> server # [ 131.403654] postgres[1567]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 131.408021] postgres[1567]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-index_advisor> server # [ 131.413535] postgres[1567]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 131.418901] postgres[1567]: ); vm-test-run-index_advisor> server # [ 131.419786] postgres[1567]: vm-test-run-index_advisor> server # [ 131.427011] postgres[1567]: IF schema_is_cron vm-test-run-index_advisor> server # [ 131.429888] postgres[1567]: THEN vm-test-run-index_advisor> server # [ 131.430530] postgres[1567]: grant usage on schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 131.431081] postgres[1567]: vm-test-run-index_advisor> server # [ 131.431616] postgres[1567]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 131.431941] postgres[1567]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 131.433214] postgres[1567]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 131.434210] postgres[1567]: vm-test-run-index_advisor> server # [ 131.435120] postgres[1567]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 131.435716] postgres[1567]: on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 131.436041] postgres[1567]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 131.437145] postgres[1567]: on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 131.438296] postgres[1567]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 131.438823] postgres[1567]: on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 131.439224] postgres[1567]: vm-test-run-index_advisor> server # [ 131.440159] postgres[1567]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 131.441819] postgres[1567]: vm-test-run-index_advisor> server # [ 131.443464] postgres[1567]: END IF; vm-test-run-index_advisor> server # [ 131.443910] postgres[1567]: vm-test-run-index_advisor> server # [ 131.447775] postgres[1567]: END; vm-test-run-index_advisor> server # [ 131.453248] postgres[1567]: $$; vm-test-run-index_advisor> server # [ 131.461636] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 131.467284] postgres[1567]: ::1 2026-10-07 20:39:14.036 UTC [1567] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA') vm-test-run-index_advisor> server # [ 131.468087] postgres[1567]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-index_advisor> server # [ 131.479016] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 131.489506] postgres[1567]: ::1 2026-10-07 20:39:14.052 UTC [1567] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-index_advisor> server # [ 131.495175] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: COMMENT vm-test-run-index_advisor> server # [ 131.499706] postgres[1567]: ::1 2026-10-07 20:39:14.069 UTC [1567] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-index_advisor> server # [ 131.505045] postgres[1567]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 131.506488] postgres[1567]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 131.506840] postgres[1567]: AS $$ vm-test-run-index_advisor> server # [ 131.507099] postgres[1567]: BEGIN vm-test-run-index_advisor> server # [ 131.507409] postgres[1567]: IF EXISTS ( vm-test-run-index_advisor> server # [ 131.507702] postgres[1567]: SELECT 1 vm-test-run-index_advisor> server # [ 131.509771] postgres[1567]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 131.510587] postgres[1567]: JOIN pg_extension AS ext vm-test-run-index_advisor> server # [ 131.511417] postgres[1567]: ON ev.objid = ext.oid vm-test-run-index_advisor> server # [ 131.511730] postgres[1567]: WHERE ext.extname = 'pg_net' vm-test-run-index_advisor> server # [ 131.512451] postgres[1567]: ) vm-test-run-index_advisor> server # [ 131.513143] postgres[1567]: THEN vm-test-run-index_advisor> server # [ 131.515558] postgres[1567]: IF NOT EXISTS ( vm-test-run-index_advisor> server # [ 131.516045] postgres[1567]: SELECT 1 vm-test-run-index_advisor> server # [ 131.516730] postgres[1567]: FROM pg_roles vm-test-run-index_advisor> server # [ 131.522652] postgres[1567]: WHERE rolname = 'supabase_functions_admin' vm-test-run-index_advisor> server # [ 131.523202] postgres[1567]: ) vm-test-run-index_advisor> server # [ 131.523478] postgres[1567]: THEN vm-test-run-index_advisor> server # [ 131.524773] postgres[1567]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 131.526619] postgres[1567]: END IF; vm-test-run-index_advisor> server # [ 131.527450] postgres[1567]: vm-test-run-index_advisor> server # [ 131.529938] postgres[1567]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 131.531898] postgres[1567]: vm-test-run-index_advisor> server # [ 131.532461] postgres[1567]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-index_advisor> server # [ 131.534845] postgres[1567]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-index_advisor> server # [ 131.539260] postgres[1567]: vm-test-run-index_advisor> server # [ 131.539731] postgres[1567]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-index_advisor> server # [ 131.541684] postgres[1567]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-index_advisor> server # [ 131.543605] postgres[1567]: vm-test-run-index_advisor> server # [ 131.545564] postgres[1567]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-index_advisor> server # [ 131.546523] postgres[1567]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-index_advisor> server # [ 131.550737] postgres[1567]: vm-test-run-index_advisor> server # [ 131.551299] postgres[1567]: 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-index_advisor> server # [ 131.552606] postgres[1567]: 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-index_advisor> server # [ 131.553442] postgres[1567]: END IF; vm-test-run-index_advisor> server # [ 131.559152] postgres[1567]: END; vm-test-run-index_advisor> server # [ 131.569438] postgres[1567]: $$; vm-test-run-index_advisor> server # [ 131.578183] postgres[1567]: ::1 2026-10-07 20:39:14.092 UTC [1567] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-index_advisor> server # [ 131.579750] postgres[1567]: ::1 2026-10-07 20:39:14.145 UTC [1567] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-index_advisor> server # [ 131.582824] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 131.585978] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: COMMENT vm-test-run-index_advisor> server # [ 131.595306] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: DO vm-test-run-index_advisor> server # [ 131.598086] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE ROLE vm-test-run-index_advisor> server # [ 131.599194] postgres[1567]: ::1 2026-10-07 20:39:14.161 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-index_advisor> server # [ 131.601558] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.603677] postgres[1567]: ::1 2026-10-07 20:39:14.176 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 131.614293] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.615537] postgres[1567]: ::1 2026-10-07 20:39:14.187 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 131.624404] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.629011] postgres[1567]: ::1 2026-10-07 20:39:14.199 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 131.647626] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.650733] postgres[1567]: ::1 2026-10-07 20:39:14.219 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 131.655747] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.662141] postgres[1567]: ::1 2026-10-07 20:39:14.228 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 131.676730] postgres[1567]: ::1 2026-10-07 20:39:14.248 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 131.679223] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.680457] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.692118] postgres[1567]: ::1 2026-10-07 20:39:14.258 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 131.712614] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.714651] postgres[1567]: ::1 2026-10-07 20:39:14.285 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 131.769092] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT vm-test-run-index_advisor> server # [ 131.793616] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: DO vm-test-run-index_advisor> server # [ 131.862604] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running pgbouncer auth schema... vm-test-run-index_advisor> server # [ 132.383468] postgres[1574]: ::1 2026-10-07 20:39:14.938 UTC [1574] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-index_advisor> server # [ 132.408335] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: CREATE ROLE vm-test-run-index_advisor> server # [ 132.429933] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: REVOKE vm-test-run-index_advisor> server # [ 132.430864] postgres[1574]: ::1 2026-10-07 20:39:14.976 UTC [1574] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-index_advisor> server # [ 132.431997] postgres[1574]: ::1 2026-10-07 20:39:14.987 UTC [1574] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-index_advisor> server # [ 132.585525] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 132.589768] postgres[1574]: ::1 2026-10-07 20:39:15.160 UTC [1574] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-index_advisor> server # [ 132.591498] postgres[1574]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-index_advisor> server # [ 132.606434] postgres[1574]: $$ vm-test-run-index_advisor> server # [ 132.615382] postgres[1574]: BEGIN vm-test-run-index_advisor> server # [ 132.620932] postgres[1574]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-index_advisor> server # [ 132.624095] postgres[1574]: vm-test-run-index_advisor> server # [ 132.653201] postgres[1574]: RETURN QUERY vm-test-run-index_advisor> server # [ 132.659106] postgres[1574]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-index_advisor> server # [ 132.674638] postgres[1574]: WHERE usename = p_usename; vm-test-run-index_advisor> server # [ 132.675479] postgres[1574]: END; vm-test-run-index_advisor> server # [ 132.675974] postgres[1574]: $$ LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 132.676305] postgres[1574]: SET search_path = '' vm-test-run-index_advisor> server # [ 132.676577] postgres[1574]: SECURITY DEFINER; vm-test-run-index_advisor> server # [ 132.690254] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 132.691847] postgres[1574]: ::1 2026-10-07 20:39:15.263 UTC [1574] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-index_advisor> server # [ 132.709759] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: REVOKE vm-test-run-index_advisor> server # [ 132.725808] postgres[1574]: ::1 2026-10-07 20:39:15.286 UTC [1574] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-index_advisor> server # [ 132.761570] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: GRANT vm-test-run-index_advisor> server # [ 132.829970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running stat extension... vm-test-run-index_advisor> server # [ 133.264536] postgres[1591]: ::1 2026-10-07 20:39:15.819 UTC [1591] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-index_advisor> server # [ 133.292906] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1588]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-index_advisor> server # [ 133.301516] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1588]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 133.302735] postgres[1591]: ::1 2026-10-07 20:39:15.870 UTC [1591] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-index_advisor> server # [ 134.451653] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1588]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 134.523501] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-index_advisor> server # [ 134.975743] postgres[1600]: ::1 2026-10-07 20:39:17.528 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-index_advisor> server # [ 135.011051] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.017108] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.020768] postgres[1600]: ::1 2026-10-07 20:39:17.562 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 135.034008] postgres[1600]: ::1 2026-10-07 20:39:17.580 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 135.035681] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.038720] postgres[1600]: ::1 2026-10-07 20:39:17.595 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 135.045005] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.056329] postgres[1600]: ::1 2026-10-07 20:39:17.620 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 135.070307] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.074778] postgres[1600]: ::1 2026-10-07 20:39:17.646 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 135.098177] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.113341] postgres[1600]: ::1 2026-10-07 20:39:17.678 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 135.115894] postgres[1600]: ::1 2026-10-07 20:39:17.683 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 135.116741] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.132380] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.148910] postgres[1600]: ::1 2026-10-07 20:39:17.705 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 135.198494] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT vm-test-run-index_advisor> server # [ 135.329918] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: DO vm-test-run-index_advisor> server # [ 135.337838] postgres[1600]: ::1 2026-10-07 20:39:17.900 UTC [1600] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-index_advisor> server # [ 135.340274] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: ALTER ROLE vm-test-run-index_advisor> server # [ 135.410608] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-index_advisor> server # [ 135.850671] postgres[1609]: ::1 2026-10-07 20:39:18.410 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.874580] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 135.881034] postgres[1609]: ::1 2026-10-07 20:39:18.432 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.891562] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 135.899980] postgres[1609]: ::1 2026-10-07 20:39:18.467 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.911715] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 135.913922] postgres[1609]: ::1 2026-10-07 20:39:18.484 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.928180] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE vm-test-run-index_advisor> server # [ 135.934776] postgres[1609]: ::1 2026-10-07 20:39:18.501 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.945783] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE vm-test-run-index_advisor> server # [ 135.947344] postgres[1609]: ::1 2026-10-07 20:39:18.519 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.957856] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE vm-test-run-index_advisor> server # [ 135.958848] postgres[1609]: ::1 2026-10-07 20:39:18.530 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.970430] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE vm-test-run-index_advisor> server # [ 135.981897] postgres[1609]: ::1 2026-10-07 20:39:18.549 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 135.999536] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE vm-test-run-index_advisor> server # [ 136.011017] postgres[1609]: ::1 2026-10-07 20:39:18.580 UTC [1609] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 136.050125] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 136.058038] postgres[1609]: ::1 2026-10-07 20:39:18.626 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 136.084058] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 136.093660] postgres[1609]: ::1 2026-10-07 20:39:18.661 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 136.101072] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 136.102058] postgres[1609]: ::1 2026-10-07 20:39:18.674 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 136.111220] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT vm-test-run-index_advisor> server # [ 136.112059] postgres[1609]: ::1 2026-10-07 20:39:18.684 UTC [1609] 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-index_advisor> server # [ 136.134382] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 136.135275] postgres[1609]: ::1 2026-10-07 20:39:18.707 UTC [1609] 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-index_advisor> server # [ 136.144850] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 136.163545] postgres[1609]: ::1 2026-10-07 20:39:18.729 UTC [1609] 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-index_advisor> server # [ 136.174557] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 136.277024] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-index_advisor> server # [ 136.695571] postgres[1624]: ::1 2026-10-07 20:39:19.253 UTC [1624] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-index_advisor> server # [ 136.763240] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1617]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 136.808722] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-index_advisor> server # [ 137.313855] postgres[1631]: ::1 2026-10-07 20:39:19.863 UTC [1631] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-index_advisor> server # [ 137.350564] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT vm-test-run-index_advisor> server # [ 137.357403] postgres[1631]: ::1 2026-10-07 20:39:19.904 UTC [1631] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 137.359661] postgres[1631]: ::1 2026-10-07 20:39:19.919 UTC [1631] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 137.360887] postgres[1631]: ::1 2026-10-07 20:39:19.926 UTC [1631] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 137.367790] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT vm-test-run-index_advisor> server # [ 137.374692] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT vm-test-run-index_advisor> server # [ 137.375238] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT vm-test-run-index_advisor> server # [ 137.447333] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-index_advisor> server # [ 137.799794] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1632]: DO vm-test-run-index_advisor> server # [ 137.811891] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1632]: DO vm-test-run-index_advisor> server # [ 137.825277] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1632]: DO vm-test-run-index_advisor> server # [ 137.875411] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-index_advisor> server # [ 138.151935] postgres[1635]: ::1 2026-10-07 20:39:20.714 UTC [1635] 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-index_advisor> server # [ 138.174302] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1634]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 138.175619] postgres[1635]: ::1 2026-10-07 20:39:20.736 UTC [1635] 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-index_advisor> server # [ 138.184056] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1634]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 138.187616] postgres[1635]: ::1 2026-10-07 20:39:20.750 UTC [1635] 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-index_advisor> server # [ 138.196692] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1634]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 138.240568] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-index_advisor> server # [ 138.507465] postgres[1637]: ::1 2026-10-07 20:39:21.071 UTC [1637] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-index_advisor> server # [ 138.559495] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1636]: ALTER ROLE vm-test-run-index_advisor> server # [ 138.579829] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-index_advisor> server # [ 139.075321] postgres[1643]: ::1 2026-10-07 20:39:21.629 UTC [1643] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-index_advisor> server # [ 139.101184] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-index_advisor> server # [ 139.102777] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 139.103313] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-index_advisor> server # [ 139.110084] postgres[1643]: ::1 2026-10-07 20:39:21.659 UTC [1643] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-index_advisor> server # [ 139.122613] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP FUNCTION vm-test-run-index_advisor> server # [ 139.123555] postgres[1643]: ::1 2026-10-07 20:39:21.687 UTC [1643] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 139.137324] postgres[1643]: DECLARE vm-test-run-index_advisor> server # [ 139.138959] postgres[1643]: cmd record; vm-test-run-index_advisor> server # [ 139.149038] postgres[1643]: BEGIN vm-test-run-index_advisor> server # [ 139.149652] postgres[1643]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-index_advisor> server # [ 139.150730] postgres[1643]: LOOP vm-test-run-index_advisor> server # [ 139.151104] postgres[1643]: IF cmd.command_tag IN ( vm-test-run-index_advisor> server # [ 139.151798] postgres[1643]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-index_advisor> server # [ 139.152107] postgres[1643]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-index_advisor> server # [ 139.159370] postgres[1643]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-index_advisor> server # [ 139.165094] postgres[1643]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-index_advisor> server # [ 139.176498] postgres[1643]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-index_advisor> server # [ 139.179143] postgres[1643]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-index_advisor> server # [ 139.182574] postgres[1643]: , 'CREATE TRIGGER' vm-test-run-index_advisor> server # [ 139.189802] postgres[1643]: , 'CREATE TYPE' vm-test-run-index_advisor> server # [ 139.190268] postgres[1643]: , 'CREATE RULE' vm-test-run-index_advisor> server # [ 139.190579] postgres[1643]: , 'COMMENT' vm-test-run-index_advisor> server # [ 139.190812] postgres[1643]: ) vm-test-run-index_advisor> server # [ 139.191134] postgres[1643]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-index_advisor> server # [ 139.196736] postgres[1643]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-index_advisor> server # [ 139.197973] postgres[1643]: THEN vm-test-run-index_advisor> server # [ 139.198419] postgres[1643]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 139.200275] postgres[1643]: END IF; vm-test-run-index_advisor> server # [ 139.209625] postgres[1643]: END LOOP; vm-test-run-index_advisor> server # [ 139.210277] postgres[1643]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 139.211014] postgres[1643]: ::1 2026-10-07 20:39:21.733 UTC [1643] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 139.220330] postgres[1643]: DECLARE vm-test-run-index_advisor> server # [ 139.225678] postgres[1643]: obj record; vm-test-run-index_advisor> server # [ 139.226626] postgres[1643]: BEGIN vm-test-run-index_advisor> server # [ 139.239580] postgres[1643]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-index_advisor> server # [ 139.291818] postgres[1643]: LOOP vm-test-run-index_advisor> server # [ 139.297175] postgres[1643]: IF obj.object_type IN ( vm-test-run-index_advisor> server # [ 139.299482] postgres[1643]: 'schema' vm-test-run-index_advisor> server # [ 139.321705] postgres[1643]: , 'table' vm-test-run-index_advisor> server # [ 139.329765] postgres[1643]: , 'foreign table' vm-test-run-index_advisor> server # [ 139.347037] postgres[1643]: , 'view' vm-test-run-index_advisor> server # [ 139.351009] postgres[1643]: , 'materialized view' vm-test-run-index_advisor> server # [ 139.354946] postgres[1643]: , 'function' vm-test-run-index_advisor> server # [ 139.357063] postgres[1643]: , 'trigger' vm-test-run-index_advisor> server # [ 139.358298] postgres[1643]: , 'type' vm-test-run-index_advisor> server # [ 139.363030] postgres[1643]: , 'rule' vm-test-run-index_advisor> server # [ 139.363874] postgres[1643]: ) vm-test-run-index_advisor> server # [ 139.369177] postgres[1643]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-index_advisor> server # [ 139.372203] postgres[1643]: THEN vm-test-run-index_advisor> server # [ 139.373739] postgres[1643]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 139.381555] postgres[1643]: END IF; vm-test-run-index_advisor> server # [ 139.382903] postgres[1643]: END LOOP; vm-test-run-index_advisor> server # [ 139.383556] postgres[1643]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 139.393899] postgres[1643]: ::1 2026-10-07 20:39:21.767 UTC [1643] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-index_advisor> server # [ 139.409177] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 139.411174] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 139.411573] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-index_advisor> server # [ 139.412336] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 139.413870] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 139.414754] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-index_advisor> server # [ 139.421541] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 139.422749] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 139.424212] postgres[1643]: ::1 2026-10-07 20:39:21.787 UTC [1643] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-index_advisor> server # [ 139.431663] postgres[1643]: ON ddl_command_end vm-test-run-index_advisor> server # [ 139.437976] postgres[1643]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-index_advisor> server # [ 139.438636] postgres[1643]: ::1 2026-10-07 20:39:21.801 UTC [1643] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-index_advisor> server # [ 139.439734] postgres[1643]: ::1 2026-10-07 20:39:21.839 UTC [1643] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-index_advisor> server # [ 139.440152] postgres[1643]: ON sql_drop vm-test-run-index_advisor> server # [ 139.440668] postgres[1643]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-index_advisor> server # [ 139.441448] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-index_advisor> server # [ 140.661721] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1649]: DO vm-test-run-index_advisor> server # [ 140.727707] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-index_advisor> server # [ 141.168641] postgres[1671]: ::1 2026-10-07 20:39:23.733 UTC [1671] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-index_advisor> server # [ 141.265259] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 141.282532] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping vm-test-run-index_advisor> server # [ 141.283833] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: DROP FUNCTION vm-test-run-index_advisor> server # [ 141.290221] postgres[1671]: ::1 2026-10-07 20:39:23.814 UTC [1671] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-index_advisor> server # [ 141.305234] postgres[1671]: ::1 2026-10-07 20:39:23.837 UTC [1671] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 141.307573] postgres[1671]: "operationName" text default null, vm-test-run-index_advisor> server # [ 141.309272] postgres[1671]: query text default null, vm-test-run-index_advisor> server # [ 141.325001] postgres[1671]: variables jsonb default null, vm-test-run-index_advisor> server # [ 141.326078] postgres[1671]: extensions jsonb default null vm-test-run-index_advisor> server # [ 141.340009] postgres[1671]: ) vm-test-run-index_advisor> server # [ 141.342875] postgres[1671]: returns jsonb vm-test-run-index_advisor> server # [ 141.346193] postgres[1671]: language plpgsql vm-test-run-index_advisor> server # [ 141.354566] postgres[1671]: as $$ vm-test-run-index_advisor> server # [ 141.355263] postgres[1671]: DECLARE vm-test-run-index_advisor> server # [ 141.356098] postgres[1671]: server_version float; vm-test-run-index_advisor> server # [ 141.370918] postgres[1671]: BEGIN vm-test-run-index_advisor> server # [ 141.378324] postgres[1671]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 141.381239] postgres[1671]: vm-test-run-index_advisor> server # [ 141.383334] postgres[1671]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 141.383958] postgres[1671]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 141.385485] postgres[1671]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 141.387590] postgres[1671]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-index_advisor> server # [ 141.389823] postgres[1671]: ); vm-test-run-index_advisor> server # [ 141.398001] postgres[1671]: ELSE vm-test-run-index_advisor> server # [ 141.399912] postgres[1671]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 141.401350] postgres[1671]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 141.402068] postgres[1671]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-index_advisor> server # [ 141.403922] postgres[1671]: ); vm-test-run-index_advisor> server # [ 141.405817] postgres[1671]: END IF; vm-test-run-index_advisor> server # [ 141.410077] postgres[1671]: END; vm-test-run-index_advisor> server # [ 141.411934] postgres[1671]: $$; vm-test-run-index_advisor> server # [ 141.417571] postgres[1671]: ::1 2026-10-07 20:39:23.904 UTC [1671] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.418853] postgres[1671]: ::1 2026-10-07 20:39:23.922 UTC [1671] 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-index_advisor> server # [ 141.419800] postgres[1671]: ::1 2026-10-07 20:39:23.938 UTC [1671] 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-index_advisor> server # [ 141.427360] postgres[1671]: ::1 2026-10-07 20:39:23.966 UTC [1671] 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-index_advisor> server # [ 141.438814] postgres[1671]: ::1 2026-10-07 20:39:23.986 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 141.439865] postgres[1671]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.443293] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 141.450852] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: GRANT vm-test-run-index_advisor> server # [ 141.451390] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 141.459176] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 141.461561] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 141.462571] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 141.463708] postgres[1671]: ::1 2026-10-07 20:39:24.005 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 141.470689] postgres[1671]: on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.475181] postgres[1671]: ::1 2026-10-07 20:39:24.019 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 141.483857] postgres[1671]: on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.487433] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 141.487914] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 141.488233] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 141.491111] postgres[1671]: ::1 2026-10-07 20:39:24.046 UTC [1671] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 141.492464] postgres[1671]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 141.494147] postgres[1671]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 141.494993] postgres[1671]: AS $func$ vm-test-run-index_advisor> server # [ 141.497249] postgres[1671]: DECLARE vm-test-run-index_advisor> server # [ 141.498084] postgres[1671]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 141.501942] postgres[1671]: BEGIN vm-test-run-index_advisor> server # [ 141.503034] postgres[1671]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 141.505257] postgres[1671]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 141.506702] postgres[1671]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 141.507094] postgres[1671]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 141.507434] postgres[1671]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 141.508189] postgres[1671]: ); vm-test-run-index_advisor> server # [ 141.509838] postgres[1671]: vm-test-run-index_advisor> server # [ 141.510174] postgres[1671]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 141.510631] postgres[1671]: THEN vm-test-run-index_advisor> server # [ 141.511174] postgres[1671]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.514116] postgres[1671]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.515415] postgres[1671]: vm-test-run-index_advisor> server # [ 141.518121] postgres[1671]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.520013] postgres[1671]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.522485] postgres[1671]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.523604] postgres[1671]: vm-test-run-index_advisor> server # [ 141.524423] postgres[1671]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 141.528965] postgres[1671]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 141.530590] postgres[1671]: "operationName" text default null, vm-test-run-index_advisor> server # [ 141.539228] postgres[1671]: query text default null, vm-test-run-index_advisor> server # [ 141.539846] postgres[1671]: variables jsonb default null, vm-test-run-index_advisor> server # [ 141.540529] postgres[1671]: extensions jsonb default null vm-test-run-index_advisor> server # [ 141.540978] postgres[1671]: ) vm-test-run-index_advisor> server # [ 141.541557] postgres[1671]: returns jsonb vm-test-run-index_advisor> server # [ 141.542823] postgres[1671]: language sql vm-test-run-index_advisor> server # [ 141.553680] postgres[1671]: as $$ vm-test-run-index_advisor> server # [ 141.555974] postgres[1671]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-index_advisor> server # [ 141.557814] postgres[1671]: $$; vm-test-run-index_advisor> server # [ 141.558676] postgres[1671]: vm-test-run-index_advisor> server # [ 141.558996] postgres[1671]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 141.559325] postgres[1671]: END IF; vm-test-run-index_advisor> server # [ 141.559732] postgres[1671]: vm-test-run-index_advisor> server # [ 141.559975] postgres[1671]: END; vm-test-run-index_advisor> server # [ 141.560322] postgres[1671]: $func$; vm-test-run-index_advisor> server # [ 141.565327] postgres[1671]: ::1 2026-10-07 20:39:24.060 UTC [1671] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-index_advisor> server # [ 141.566045] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-index_advisor> server # [ 141.567665] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 141.574181] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 141.578911] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: COMMENT vm-test-run-index_advisor> server # [ 141.591007] postgres[1671]: ::1 2026-10-07 20:39:24.078 UTC [1671] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION') vm-test-run-index_advisor> server # [ 141.601795] postgres[1671]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-index_advisor> server # [ 141.613080] postgres[1671]: ::1 2026-10-07 20:39:24.106 UTC [1671] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql'; vm-test-run-index_advisor> server # [ 141.614820] postgres[1671]: ::1 2026-10-07 20:39:24.120 UTC [1671] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-index_advisor> server # [ 141.624060] postgres[1671]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 141.630582] postgres[1671]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 141.631289] postgres[1671]: AS $func$ vm-test-run-index_advisor> server # [ 141.631566] postgres[1671]: DECLARE vm-test-run-index_advisor> server # [ 141.632006] postgres[1671]: graphql_is_dropped bool; vm-test-run-index_advisor> server # [ 141.634152] postgres[1671]: BEGIN vm-test-run-index_advisor> server # [ 141.635123] postgres[1671]: graphql_is_dropped = ( vm-test-run-index_advisor> server # [ 141.637247] postgres[1671]: SELECT ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 141.639601] postgres[1671]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-index_advisor> server # [ 141.642697] postgres[1671]: WHERE ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 141.647579] postgres[1671]: ); vm-test-run-index_advisor> server # [ 141.649322] postgres[1671]: vm-test-run-index_advisor> server # [ 141.650861] postgres[1671]: IF graphql_is_dropped vm-test-run-index_advisor> server # [ 141.654216] postgres[1671]: THEN vm-test-run-index_advisor> server # [ 141.657001] postgres[1671]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 141.658234] postgres[1671]: "operationName" text default null, vm-test-run-index_advisor> server # [ 141.663327] postgres[1671]: query text default null, vm-test-run-index_advisor> server # [ 141.666843] postgres[1671]: variables jsonb default null, vm-test-run-index_advisor> server # [ 141.667520] postgres[1671]: extensions jsonb default null vm-test-run-index_advisor> server # [ 141.668475] postgres[1671]: ) vm-test-run-index_advisor> server # [ 141.669674] postgres[1671]: returns jsonb vm-test-run-index_advisor> server # [ 141.669979] postgres[1671]: language plpgsql vm-test-run-index_advisor> server # [ 141.670869] postgres[1671]: as $$ vm-test-run-index_advisor> server # [ 141.671655] postgres[1671]: DECLARE vm-test-run-index_advisor> server # [ 141.672427] postgres[1671]: server_version float; vm-test-run-index_advisor> server # [ 141.673459] postgres[1671]: BEGIN vm-test-run-index_advisor> server # [ 141.674931] postgres[1671]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 141.675835] postgres[1671]: vm-test-run-index_advisor> server # [ 141.678550] postgres[1671]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 141.679146] postgres[1671]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 141.679785] postgres[1671]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 141.680888] postgres[1671]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-index_advisor> server # [ 141.682250] postgres[1671]: ); vm-test-run-index_advisor> server # [ 141.689460] postgres[1671]: ELSE vm-test-run-index_advisor> server # [ 141.690840] postgres[1671]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 141.691295] postgres[1671]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 141.691623] postgres[1671]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-index_advisor> server # [ 141.693422] postgres[1671]: ); vm-test-run-index_advisor> server # [ 141.693861] postgres[1671]: END IF; vm-test-run-index_advisor> server # [ 141.694297] postgres[1671]: END; vm-test-run-index_advisor> server # [ 141.694963] postgres[1671]: $$; vm-test-run-index_advisor> server # [ 141.695500] postgres[1671]: END IF; vm-test-run-index_advisor> server # [ 141.695766] postgres[1671]: vm-test-run-index_advisor> server # [ 141.697932] postgres[1671]: END; vm-test-run-index_advisor> server # [ 141.699746] postgres[1671]: $func$; vm-test-run-index_advisor> server # [ 141.701354] postgres[1671]: ::1 2026-10-07 20:39:24.251 UTC [1671] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-index_advisor> server # [ 141.701883] postgres[1671]: ::1 2026-10-07 20:39:24.269 UTC [1671] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION') vm-test-run-index_advisor> server # [ 141.702751] postgres[1671]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-index_advisor> server # [ 141.703353] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 141.705383] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-index_advisor> server # [ 141.707576] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 141.712029] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 141.713908] postgres[1671]: ::1 2026-10-07 20:39:24.283 UTC [1671] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql'; vm-test-run-index_advisor> server # [ 141.723644] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: COMMENT vm-test-run-index_advisor> server # [ 141.779914] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-index_advisor> server # [ 142.047213] postgres[1673]: ::1 2026-10-07 20:39:24.616 UTC [1673] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-index_advisor> server # [ 142.059530] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-index_advisor> server # [ 142.070208] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.071146] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-index_advisor> server # [ 142.077657] postgres[1673]: ::1 2026-10-07 20:39:24.630 UTC [1673] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-index_advisor> server # [ 142.081212] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP FUNCTION vm-test-run-index_advisor> server # [ 142.083056] postgres[1673]: ::1 2026-10-07 20:39:24.655 UTC [1673] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 142.085709] postgres[1673]: DECLARE vm-test-run-index_advisor> server # [ 142.087564] postgres[1673]: cmd record; vm-test-run-index_advisor> server # [ 142.088880] postgres[1673]: BEGIN vm-test-run-index_advisor> server # [ 142.090824] postgres[1673]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-index_advisor> server # [ 142.091641] postgres[1673]: LOOP vm-test-run-index_advisor> server # [ 142.093450] postgres[1673]: IF cmd.command_tag IN ( vm-test-run-index_advisor> server # [ 142.094174] postgres[1673]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-index_advisor> server # [ 142.094774] postgres[1673]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-index_advisor> server # [ 142.095103] postgres[1673]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-index_advisor> server # [ 142.097329] postgres[1673]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-index_advisor> server # [ 142.097722] postgres[1673]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-index_advisor> server # [ 142.098178] postgres[1673]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-index_advisor> server # [ 142.099058] postgres[1673]: , 'CREATE TRIGGER' vm-test-run-index_advisor> server # [ 142.100467] postgres[1673]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-index_advisor> server # [ 142.102130] postgres[1673]: , 'CREATE RULE' vm-test-run-index_advisor> server # [ 142.102747] postgres[1673]: , 'COMMENT' vm-test-run-index_advisor> server # [ 142.103487] postgres[1673]: ) vm-test-run-index_advisor> server # [ 142.103743] postgres[1673]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-index_advisor> server # [ 142.104834] postgres[1673]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-index_advisor> server # [ 142.106119] postgres[1673]: THEN vm-test-run-index_advisor> server # [ 142.106930] postgres[1673]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 142.107857] postgres[1673]: END IF; vm-test-run-index_advisor> server # [ 142.108207] postgres[1673]: END LOOP; vm-test-run-index_advisor> server # [ 142.108443] postgres[1673]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 142.140895] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 142.143232] postgres[1673]: ::1 2026-10-07 20:39:24.713 UTC [1673] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 142.144058] postgres[1673]: DECLARE vm-test-run-index_advisor> server # [ 142.148059] postgres[1673]: obj record; vm-test-run-index_advisor> server # [ 142.149314] postgres[1673]: BEGIN vm-test-run-index_advisor> server # [ 142.149653] postgres[1673]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-index_advisor> server # [ 142.150204] postgres[1673]: LOOP vm-test-run-index_advisor> server # [ 142.150572] postgres[1673]: IF obj.object_type IN ( vm-test-run-index_advisor> server # [ 142.151329] postgres[1673]: 'schema' vm-test-run-index_advisor> server # [ 142.151608] postgres[1673]: , 'table' vm-test-run-index_advisor> server # [ 142.152082] postgres[1673]: , 'foreign table' vm-test-run-index_advisor> server # [ 142.153760] postgres[1673]: , 'view' vm-test-run-index_advisor> server # [ 142.155177] postgres[1673]: , 'materialized view' vm-test-run-index_advisor> server # [ 142.155514] postgres[1673]: , 'function' vm-test-run-index_advisor> server # [ 142.155813] postgres[1673]: , 'trigger' vm-test-run-index_advisor> server # [ 142.156028] postgres[1673]: , 'type' vm-test-run-index_advisor> server # [ 142.156576] postgres[1673]: , 'rule' vm-test-run-index_advisor> server # [ 142.156796] postgres[1673]: ) vm-test-run-index_advisor> server # [ 142.156996] postgres[1673]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-index_advisor> server # [ 142.157959] postgres[1673]: THEN vm-test-run-index_advisor> server # [ 142.158982] postgres[1673]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 142.159302] postgres[1673]: END IF; vm-test-run-index_advisor> server # [ 142.159565] postgres[1673]: END LOOP; vm-test-run-index_advisor> server # [ 142.160086] postgres[1673]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 142.180200] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 142.185146] postgres[1673]: ::1 2026-10-07 20:39:24.754 UTC [1673] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-index_advisor> server # [ 142.198006] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.199800] postgres[1673]: ::1 2026-10-07 20:39:24.772 UTC [1673] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-index_advisor> server # [ 142.202536] postgres[1673]: ON ddl_command_end vm-test-run-index_advisor> server # [ 142.205089] postgres[1673]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-index_advisor> server # [ 142.227628] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.238272] postgres[1673]: ::1 2026-10-07 20:39:24.802 UTC [1673] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-index_advisor> server # [ 142.242584] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.248961] postgres[1673]: ::1 2026-10-07 20:39:24.815 UTC [1673] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-index_advisor> server # [ 142.250215] postgres[1673]: ON sql_drop vm-test-run-index_advisor> server # [ 142.251680] postgres[1673]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-index_advisor> server # [ 142.256033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.308932] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-index_advisor> server # [ 142.649774] postgres[1675]: ::1 2026-10-07 20:39:25.196 UTC [1675] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-index_advisor> server # [ 142.694308] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1674]: ALTER ROLE vm-test-run-index_advisor> server # [ 142.727622] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-index_advisor> server # [ 143.199713] postgres[1686]: ::1 2026-10-07 20:39:25.754 UTC [1686] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 143.212430] postgres[1686]: returns event_trigger vm-test-run-index_advisor> server # [ 143.216878] postgres[1686]: language plpgsql vm-test-run-index_advisor> server # [ 143.217393] postgres[1686]: AS $func$ vm-test-run-index_advisor> server # [ 143.217679] postgres[1686]: DECLARE vm-test-run-index_advisor> server # [ 143.218112] postgres[1686]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 143.218543] postgres[1686]: BEGIN vm-test-run-index_advisor> server # [ 143.218906] postgres[1686]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 143.219172] postgres[1686]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 143.219423] postgres[1686]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 143.219674] postgres[1686]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 143.219913] postgres[1686]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 143.227723] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.228466] postgres[1686]: vm-test-run-index_advisor> server # [ 143.235375] postgres[1686]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 143.235861] postgres[1686]: THEN vm-test-run-index_advisor> server # [ 143.236115] postgres[1686]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 143.238755] postgres[1686]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 143.249788] postgres[1686]: vm-test-run-index_advisor> server # [ 143.250755] postgres[1686]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 143.251548] postgres[1686]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 143.251934] postgres[1686]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 143.259904] postgres[1686]: vm-test-run-index_advisor> server # [ 143.273681] postgres[1686]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 143.276404] postgres[1686]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 143.279436] postgres[1686]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 143.293235] postgres[1686]: "operationName" text default null, vm-test-run-index_advisor> server # [ 143.294101] postgres[1686]: query text default null, vm-test-run-index_advisor> server # [ 143.294596] postgres[1686]: variables jsonb default null, vm-test-run-index_advisor> server # [ 143.294918] postgres[1686]: extensions jsonb default null vm-test-run-index_advisor> server # [ 143.295200] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.295446] postgres[1686]: returns jsonb vm-test-run-index_advisor> server # [ 143.295664] postgres[1686]: language sql vm-test-run-index_advisor> server # [ 143.295897] postgres[1686]: as $$ vm-test-run-index_advisor> server # [ 143.308169] postgres[1686]: -- This changed vm-test-run-index_advisor> server # [ 143.310426] postgres[1686]: select graphql.resolve( vm-test-run-index_advisor> server # [ 143.311103] postgres[1686]: query := query, vm-test-run-index_advisor> server # [ 143.311622] postgres[1686]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 143.311954] postgres[1686]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 143.314090] postgres[1686]: extensions := extensions vm-test-run-index_advisor> server # [ 143.318892] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.319552] postgres[1686]: $$; vm-test-run-index_advisor> server # [ 143.320007] postgres[1686]: vm-test-run-index_advisor> server # [ 143.320304] postgres[1686]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 143.334014] postgres[1686]: END IF; vm-test-run-index_advisor> server # [ 143.335028] postgres[1686]: vm-test-run-index_advisor> server # [ 143.335330] postgres[1686]: END; vm-test-run-index_advisor> server # [ 143.335577] postgres[1686]: $func$; vm-test-run-index_advisor> server # [ 143.429591] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 143.431030] postgres[1686]: ::1 2026-10-07 20:39:25.983 UTC [1686] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-index_advisor> server # [ 143.432104] postgres[1686]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 143.432623] postgres[1686]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 143.432931] postgres[1686]: AS $func$ vm-test-run-index_advisor> server # [ 143.447009] postgres[1686]: DECLARE vm-test-run-index_advisor> server # [ 143.455172] postgres[1686]: graphql_is_dropped bool; vm-test-run-index_advisor> server # [ 143.466765] postgres[1686]: BEGIN vm-test-run-index_advisor> server # [ 143.476921] postgres[1686]: graphql_is_dropped = ( vm-test-run-index_advisor> server # [ 143.477761] postgres[1686]: SELECT ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 143.478133] postgres[1686]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-index_advisor> server # [ 143.478562] postgres[1686]: WHERE ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 143.478826] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.479042] postgres[1686]: vm-test-run-index_advisor> server # [ 143.479843] postgres[1686]: IF graphql_is_dropped vm-test-run-index_advisor> server # [ 143.484993] postgres[1686]: THEN vm-test-run-index_advisor> server # [ 143.486009] postgres[1686]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 143.486713] postgres[1686]: "operationName" text default null, vm-test-run-index_advisor> server # [ 143.487518] postgres[1686]: query text default null, vm-test-run-index_advisor> server # [ 143.488010] postgres[1686]: variables jsonb default null, vm-test-run-index_advisor> server # [ 143.493383] postgres[1686]: extensions jsonb default null vm-test-run-index_advisor> server # [ 143.494258] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.494895] postgres[1686]: returns jsonb vm-test-run-index_advisor> server # [ 143.495170] postgres[1686]: language plpgsql vm-test-run-index_advisor> server # [ 143.495644] postgres[1686]: as $$ vm-test-run-index_advisor> server # [ 143.505400] postgres[1686]: DECLARE vm-test-run-index_advisor> server # [ 143.506226] postgres[1686]: server_version float; vm-test-run-index_advisor> server # [ 143.507099] postgres[1686]: BEGIN vm-test-run-index_advisor> server # [ 143.507386] postgres[1686]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 143.508305] postgres[1686]: vm-test-run-index_advisor> server # [ 143.508557] postgres[1686]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 143.508807] postgres[1686]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 143.509256] postgres[1686]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 143.509554] postgres[1686]: jsonb_build_object( vm-test-run-index_advisor> server # [ 143.509801] postgres[1686]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-index_advisor> server # [ 143.513886] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.521410] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.522184] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.522861] postgres[1686]: ELSE vm-test-run-index_advisor> server # [ 143.523724] postgres[1686]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 143.524188] postgres[1686]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 143.533463] postgres[1686]: jsonb_build_object( vm-test-run-index_advisor> server # [ 143.534795] postgres[1686]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-index_advisor> server # [ 143.535387] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.535732] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.550499] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.551791] postgres[1686]: END IF; vm-test-run-index_advisor> server # [ 143.559020] postgres[1686]: END; vm-test-run-index_advisor> server # [ 143.559976] postgres[1686]: $$; vm-test-run-index_advisor> server # [ 143.573191] postgres[1686]: END IF; vm-test-run-index_advisor> server # [ 143.574743] postgres[1686]: vm-test-run-index_advisor> server # [ 143.575785] postgres[1686]: END; vm-test-run-index_advisor> server # [ 143.576081] postgres[1686]: $func$; vm-test-run-index_advisor> server # [ 143.603865] postgres[1686]: ::1 2026-10-07 20:39:26.044 UTC [1686] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 143.611579] postgres[1686]: "operationName" text default null, vm-test-run-index_advisor> server # [ 143.612185] postgres[1686]: query text default null, vm-test-run-index_advisor> server # [ 143.618945] postgres[1686]: variables jsonb default null, vm-test-run-index_advisor> server # [ 143.619457] postgres[1686]: extensions jsonb default null vm-test-run-index_advisor> server # [ 143.623935] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.625220] postgres[1686]: returns jsonb vm-test-run-index_advisor> server # [ 143.625574] postgres[1686]: language plpgsql vm-test-run-index_advisor> server # [ 143.625868] postgres[1686]: as $$ vm-test-run-index_advisor> server # [ 143.629550] postgres[1686]: DECLARE vm-test-run-index_advisor> server # [ 143.630563] postgres[1686]: server_version float; vm-test-run-index_advisor> server # [ 143.631707] postgres[1686]: BEGIN vm-test-run-index_advisor> server # [ 143.631998] postgres[1686]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 143.632274] postgres[1686]: vm-test-run-index_advisor> server # [ 143.639793] postgres[1686]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 143.653236] postgres[1686]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 143.663616] postgres[1686]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 143.670077] postgres[1686]: jsonb_build_object( vm-test-run-index_advisor> server # [ 143.682366] postgres[1686]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-index_advisor> server # [ 143.684332] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.694613] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.695770] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.699678] postgres[1686]: ELSE vm-test-run-index_advisor> server # [ 143.709401] postgres[1686]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 143.710106] postgres[1686]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 143.710721] postgres[1686]: jsonb_build_object( vm-test-run-index_advisor> server # [ 143.711524] postgres[1686]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-index_advisor> server # [ 143.712377] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.727279] postgres[1686]: ) vm-test-run-index_advisor> server # [ 143.741003] postgres[1686]: ); vm-test-run-index_advisor> server # [ 143.742142] postgres[1686]: END IF; vm-test-run-index_advisor> server # [ 143.742981] postgres[1686]: END; vm-test-run-index_advisor> server # [ 143.743278] postgres[1686]: $$; vm-test-run-index_advisor> server # [ 143.743501] postgres[1686]: ::1 2026-10-07 20:39:26.088 UTC [1686] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 143.760682] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 143.763007] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 143.763426] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-index_advisor> server # [ 143.773456] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: DROP EXTENSION vm-test-run-index_advisor> server # [ 145.552253] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: DO vm-test-run-index_advisor> server # [ 145.570013] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-index_advisor> server # [ 145.881978] postgres[1707]: ::1 2026-10-07 20:39:28.436 UTC [1707] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-index_advisor> server # [ 145.908527] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1706]: GRANT ROLE vm-test-run-index_advisor> server # [ 145.928839] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-index_advisor> server # [ 146.234310] postgres[1709]: ::1 2026-10-07 20:39:28.801 UTC [1709] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 146.238653] postgres[1709]: returns event_trigger vm-test-run-index_advisor> server # [ 146.239443] postgres[1709]: language plpgsql vm-test-run-index_advisor> server # [ 146.241741] postgres[1709]: AS $func$ vm-test-run-index_advisor> server # [ 146.246029] postgres[1709]: DECLARE vm-test-run-index_advisor> server # [ 146.247137] postgres[1709]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 146.249363] postgres[1709]: BEGIN vm-test-run-index_advisor> server # [ 146.250080] postgres[1709]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 146.251410] postgres[1709]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 146.252613] postgres[1709]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 146.255873] postgres[1709]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 146.257676] postgres[1709]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 146.258120] postgres[1709]: ); vm-test-run-index_advisor> server # [ 146.259149] postgres[1709]: vm-test-run-index_advisor> server # [ 146.259612] postgres[1709]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 146.261710] postgres[1709]: THEN vm-test-run-index_advisor> server # [ 146.262605] postgres[1709]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 146.265697] postgres[1709]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 146.267436] postgres[1709]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 146.269346] postgres[1709]: "operationName" text default null, vm-test-run-index_advisor> server # [ 146.270217] postgres[1709]: query text default null, vm-test-run-index_advisor> server # [ 146.270770] postgres[1709]: variables jsonb default null, vm-test-run-index_advisor> server # [ 146.271208] postgres[1709]: extensions jsonb default null vm-test-run-index_advisor> server # [ 146.273906] postgres[1709]: ) vm-test-run-index_advisor> server # [ 146.274563] postgres[1709]: returns jsonb vm-test-run-index_advisor> server # [ 146.275348] postgres[1709]: language sql vm-test-run-index_advisor> server # [ 146.275627] postgres[1709]: as $$ vm-test-run-index_advisor> server # [ 146.277108] postgres[1709]: select graphql.resolve( vm-test-run-index_advisor> server # [ 146.278583] postgres[1709]: query := query, vm-test-run-index_advisor> server # [ 146.278933] postgres[1709]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 146.281517] postgres[1709]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 146.282185] postgres[1709]: extensions := extensions vm-test-run-index_advisor> server # [ 146.283356] postgres[1709]: ); vm-test-run-index_advisor> server # [ 146.285394] postgres[1709]: $$; vm-test-run-index_advisor> server # [ 146.286268] postgres[1709]: vm-test-run-index_advisor> server # [ 146.287097] postgres[1709]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-index_advisor> server # [ 146.288775] postgres[1709]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-index_advisor> server # [ 146.290746] postgres[1709]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-index_advisor> server # [ 146.291250] postgres[1709]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.291626] postgres[1709]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.292139] postgres[1709]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.293792] postgres[1709]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.294260] postgres[1709]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.296623] postgres[1709]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.298681] postgres[1709]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 146.299733] postgres[1709]: END IF; vm-test-run-index_advisor> server # [ 146.300990] postgres[1709]: vm-test-run-index_advisor> server # [ 146.302174] postgres[1709]: END; vm-test-run-index_advisor> server # [ 146.303451] postgres[1709]: $func$; vm-test-run-index_advisor> server # [ 146.426321] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1708]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 146.427635] postgres[1709]: ::1 2026-10-07 20:39:28.975 UTC [1709] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 146.521896] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1708]: DROP EXTENSION vm-test-run-index_advisor> server # [ 148.451136] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1708]: DO vm-test-run-index_advisor> server # [ 148.504688] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-index_advisor> server # [ 149.642035] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1734]: DO vm-test-run-index_advisor> server # [ 150.559556] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1734]: DO vm-test-run-index_advisor> server # [ 150.679613] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-index_advisor> server # [ 151.178121] postgres[1761]: ::1 2026-10-07 20:39:33.718 UTC [1761] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-index_advisor> server # [ 151.189221] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1754]: ALTER ROLE vm-test-run-index_advisor> server # [ 151.225776] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-index_advisor> server # [ 151.747867] postgres[1767]: ::1 2026-10-07 20:39:34.305 UTC [1767] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-index_advisor> server # [ 151.778041] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1765]: REVOKE ROLE vm-test-run-index_advisor> server # [ 151.837131] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-index_advisor> server # [ 154.394796] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1768]: DO vm-test-run-index_advisor> server # [ 154.439303] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-index_advisor> server # [ 154.804031] postgres[1795]: ::1 2026-10-07 20:39:37.365 UTC [1795] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-index_advisor> server # [ 154.823291] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1792]: GRANT ROLE vm-test-run-index_advisor> server # [ 154.885295] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-index_advisor> server # [ 155.253335] postgres[1801]: ::1 2026-10-07 20:39:37.813 UTC [1801] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-index_advisor> server # [ 155.300012] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: GRANT vm-test-run-index_advisor> server # [ 155.311839] postgres[1801]: ::1 2026-10-07 20:39:37.873 UTC [1801] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-index_advisor> server # [ 155.349558] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: GRANT vm-test-run-index_advisor> server # [ 155.355708] postgres[1801]: ::1 2026-10-07 20:39:37.926 UTC [1801] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-index_advisor> server # [ 155.365583] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: GRANT vm-test-run-index_advisor> server # [ 155.370221] postgres[1801]: ::1 2026-10-07 20:39:37.942 UTC [1801] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 155.394734] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 155.398947] postgres[1801]: ::1 2026-10-07 20:39:37.969 UTC [1801] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option; vm-test-run-index_advisor> server # [ 155.411440] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 155.420858] postgres[1801]: ::1 2026-10-07 20:39:37.988 UTC [1801] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 155.439714] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 155.508472] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-index_advisor> server # [ 155.841311] postgres[1803]: ::1 2026-10-07 20:39:38.410 UTC [1803] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-index_advisor> server # [ 155.865749] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1802]: GRANT ROLE vm-test-run-index_advisor> server # [ 155.890660] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-index_advisor> server # [ 156.183919] postgres[1805]: ::1 2026-10-07 20:39:38.748 UTC [1805] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-index_advisor> server # [ 156.201065] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1804]: GRANT ROLE vm-test-run-index_advisor> server # [ 156.231545] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-index_advisor> server # [ 156.606721] postgres[1807]: ::1 2026-10-07 20:39:39.152 UTC [1807] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-index_advisor> server # [ 156.613143] postgres[1807]: ::1 2026-10-07 20:39:39.182 UTC [1807] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-index_advisor> server # [ 156.638214] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: ALTER ROLE vm-test-run-index_advisor> server # [ 156.640880] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: ALTER ROLE vm-test-run-index_advisor> server # [ 156.641847] postgres[1807]: ::1 2026-10-07 20:39:39.203 UTC [1807] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-index_advisor> server # [ 156.643236] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: ALTER ROLE vm-test-run-index_advisor> server # [ 156.715713] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: DO vm-test-run-index_advisor> server # [ 156.794055] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-index_advisor> server # [ 157.384752] postgres[1824]: ::1 2026-10-07 20:39:39.938 UTC [1824] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-index_advisor> server # [ 157.417000] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1814]: GRANT ROLE vm-test-run-index_advisor> server # [ 157.423228] postgres[1824]: ::1 2026-10-07 20:39:39.983 UTC [1824] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-index_advisor> server # [ 157.433023] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1814]: REVOKE ROLE vm-test-run-index_advisor> server # [ 157.483846] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-index_advisor> server # [ 158.038052] postgres[1831]: ::1 2026-10-07 20:39:40.599 UTC [1831] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 158.042641] postgres[1831]: returns event_trigger vm-test-run-index_advisor> server # [ 158.049078] postgres[1831]: language plpgsql vm-test-run-index_advisor> server # [ 158.050272] postgres[1831]: AS $func$ vm-test-run-index_advisor> server # [ 158.051533] postgres[1831]: DECLARE vm-test-run-index_advisor> server # [ 158.051906] postgres[1831]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 158.059526] postgres[1831]: BEGIN vm-test-run-index_advisor> server # [ 158.060746] postgres[1831]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 158.061644] postgres[1831]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 158.061956] postgres[1831]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 158.062559] postgres[1831]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 158.063070] postgres[1831]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 158.063746] postgres[1831]: ); vm-test-run-index_advisor> server # [ 158.063974] postgres[1831]: vm-test-run-index_advisor> server # [ 158.069940] postgres[1831]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 158.071458] postgres[1831]: THEN vm-test-run-index_advisor> server # [ 158.073465] postgres[1831]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 158.074173] postgres[1831]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 158.074781] postgres[1831]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 158.075768] postgres[1831]: "operationName" text default null, vm-test-run-index_advisor> server # [ 158.077644] postgres[1831]: query text default null, vm-test-run-index_advisor> server # [ 158.078312] postgres[1831]: variables jsonb default null, vm-test-run-index_advisor> server # [ 158.078808] postgres[1831]: extensions jsonb default null vm-test-run-index_advisor> server # [ 158.079414] postgres[1831]: ) vm-test-run-index_advisor> server # [ 158.079692] postgres[1831]: returns jsonb vm-test-run-index_advisor> server # [ 158.085659] postgres[1831]: language sql vm-test-run-index_advisor> server # [ 158.086123] postgres[1831]: as $$ vm-test-run-index_advisor> server # [ 158.086459] postgres[1831]: select graphql.resolve( vm-test-run-index_advisor> server # [ 158.086728] postgres[1831]: query := query, vm-test-run-index_advisor> server # [ 158.086974] postgres[1831]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 158.087839] postgres[1831]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 158.093316] postgres[1831]: extensions := extensions vm-test-run-index_advisor> server # [ 158.094063] postgres[1831]: ); vm-test-run-index_advisor> server # [ 158.095001] postgres[1831]: $$; vm-test-run-index_advisor> server # [ 158.095272] postgres[1831]: vm-test-run-index_advisor> server # [ 158.095841] postgres[1831]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-index_advisor> server # [ 158.097608] postgres[1831]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-index_advisor> server # [ 158.098039] postgres[1831]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-index_advisor> server # [ 158.098625] postgres[1831]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.099267] postgres[1831]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.105057] postgres[1831]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.106930] postgres[1831]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.109536] postgres[1831]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.110538] postgres[1831]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.111193] postgres[1831]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 158.111542] postgres[1831]: vm-test-run-index_advisor> server # [ 158.111780] postgres[1831]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-index_advisor> server # [ 158.112077] postgres[1831]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-index_advisor> server # [ 158.112359] postgres[1831]: grant usage on schema graphql to postgres with grant option; vm-test-run-index_advisor> server # [ 158.118234] postgres[1831]: END IF; vm-test-run-index_advisor> server # [ 158.118828] postgres[1831]: vm-test-run-index_advisor> server # [ 158.119097] postgres[1831]: END; vm-test-run-index_advisor> server # [ 158.119345] postgres[1831]: $func$; vm-test-run-index_advisor> server # [ 158.167214] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1828]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 158.168034] postgres[1831]: ::1 2026-10-07 20:39:40.736 UTC [1831] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 158.257572] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1828]: DROP EXTENSION vm-test-run-index_advisor> server # [ 160.092834] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1828]: DO vm-test-run-index_advisor> server # [ 160.136009] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-index_advisor> server # [ 160.635139] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: DO vm-test-run-index_advisor> server # [ 160.646134] postgres[1851]: ::1 2026-10-07 20:39:43.201 UTC [1851] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-index_advisor> server # [ 160.647922] postgres[1851]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 160.655832] postgres[1851]: AS $$ vm-test-run-index_advisor> server # [ 160.657620] postgres[1851]: BEGIN vm-test-run-index_advisor> server # [ 160.657982] postgres[1851]: IF EXISTS ( vm-test-run-index_advisor> server # [ 160.658244] postgres[1851]: SELECT vm-test-run-index_advisor> server # [ 160.658625] postgres[1851]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 160.658932] postgres[1851]: JOIN pg_extension AS ext vm-test-run-index_advisor> server # [ 160.659330] postgres[1851]: ON ev.objid = ext.oid vm-test-run-index_advisor> server # [ 160.659597] postgres[1851]: WHERE ext.extname = 'pg_cron' vm-test-run-index_advisor> server # [ 160.666078] postgres[1851]: ) vm-test-run-index_advisor> server # [ 160.669227] postgres[1851]: THEN vm-test-run-index_advisor> server # [ 160.670291] postgres[1851]: grant usage on schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 160.671766] postgres[1851]: vm-test-run-index_advisor> server # [ 160.678012] postgres[1851]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 160.681978] postgres[1851]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 160.682663] postgres[1851]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 160.683022] postgres[1851]: vm-test-run-index_advisor> server # [ 160.683239] postgres[1851]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 160.683514] postgres[1851]: on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 160.683766] postgres[1851]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 160.684781] postgres[1851]: on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 160.685065] postgres[1851]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 160.689119] postgres[1851]: on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 160.689926] postgres[1851]: vm-test-run-index_advisor> server # [ 160.690720] postgres[1851]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 160.691284] postgres[1851]: revoke all on table cron.job from postgres; vm-test-run-index_advisor> server # [ 160.691864] postgres[1851]: grant select on table cron.job to postgres with grant option; vm-test-run-index_advisor> server # [ 160.692225] postgres[1851]: END IF; vm-test-run-index_advisor> server # [ 160.692448] postgres[1851]: END; vm-test-run-index_advisor> server # [ 160.708870] postgres[1851]: $$; vm-test-run-index_advisor> server # [ 160.718302] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 160.719492] postgres[1851]: ::1 2026-10-07 20:39:43.291 UTC [1851] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-index_advisor> server # [ 160.739446] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 160.741227] postgres[1851]: ::1 2026-10-07 20:39:43.313 UTC [1851] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-index_advisor> server # [ 160.761988] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 160.763555] postgres[1851]: ::1 2026-10-07 20:39:43.336 UTC [1851] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-index_advisor> server # [ 160.767842] postgres[1851]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-index_advisor> server # [ 160.770532] postgres[1851]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-index_advisor> server # [ 160.771588] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 160.848994] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-index_advisor> server # [ 161.384000] postgres[1866]: ::1 2026-10-07 20:39:43.936 UTC [1866] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-index_advisor> server # [ 161.423010] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1860]: ALTER ROLE vm-test-run-index_advisor> server # [ 161.464110] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-index_advisor> server # [ 161.905295] postgres[1869]: ::1 2026-10-07 20:39:44.472 UTC [1869] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-index_advisor> server # [ 161.932970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1868]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 161.935611] postgres[1869]: ::1 2026-10-07 20:39:44.507 UTC [1869] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-index_advisor> server # [ 161.943702] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1868]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 161.947356] postgres[1869]: ::1 2026-10-07 20:39:44.519 UTC [1869] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-index_advisor> server # [ 161.955470] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1868]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 162.014180] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-index_advisor> server # [ 162.291816] postgres[1871]: ::1 2026-10-07 20:39:44.854 UTC [1871] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-index_advisor> server # [ 162.303751] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1870]: GRANT ROLE vm-test-run-index_advisor> server # [ 162.337097] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-index_advisor> server # [ 163.603363] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1872]: DO vm-test-run-index_advisor> server # [ 163.635812] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-index_advisor> server # [ 164.268898] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1887]: DO vm-test-run-index_advisor> server # [ 164.270038] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1887]: DO vm-test-run-index_advisor> server # [ 164.314794] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-index_advisor> server # [ 164.753475] postgres[1901]: ::1 2026-10-07 20:39:47.318 UTC [1901] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-index_advisor> server # [ 164.774997] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1897]: ALTER ROLE vm-test-run-index_advisor> server # [ 164.778220] postgres[1901]: ::1 2026-10-07 20:39:47.340 UTC [1901] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-index_advisor> server # [ 164.779696] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1897]: ALTER ROLE vm-test-run-index_advisor> server # [ 164.782297] postgres[1901]: ::1 2026-10-07 20:39:47.351 UTC [1901] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-index_advisor> server # [ 164.790965] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1897]: ALTER ROLE vm-test-run-index_advisor> server # [ 164.831351] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-index_advisor> server # [ 165.240126] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1902]: DO vm-test-run-index_advisor> server # [ 165.283140] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-index_advisor> server # [ 165.730758] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1908]: DO vm-test-run-index_advisor> server # [ 165.751630] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-index_advisor> server # [ 166.136064] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1910]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 166.143556] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1910]: DO vm-test-run-index_advisor> server # [ 166.214612] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-index_advisor> server # [ 166.658714] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1912]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 166.668943] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1912]: GRANT vm-test-run-index_advisor> server # [ 166.708651] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-index_advisor> server # [ 167.357442] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1922]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 167.384903] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1922]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 167.398929] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1922]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 167.495920] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-index_advisor> server # [ 167.947192] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1935]: DO vm-test-run-index_advisor> server # [ 167.977313] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-index_advisor> server # [ 168.387806] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1940]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 168.390687] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1940]: GRANT vm-test-run-index_advisor> server # [ 168.436999] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-index_advisor> server # [ 168.719189] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: REVOKE ROLE vm-test-run-index_advisor> server # [ 168.751664] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: DO vm-test-run-index_advisor> server # [ 168.759488] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: DO vm-test-run-index_advisor> server # [ 168.770391] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: REVOKE ROLE vm-test-run-index_advisor> server # [ 168.782524] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: REVOKE vm-test-run-index_advisor> server # [ 168.805125] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: DO vm-test-run-index_advisor> server # [ 168.864743] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-index_advisor> server # [ 169.360687] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1946]: DO vm-test-run-index_advisor> server # [ 169.401281] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-index_advisor> server # [ 169.861371] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1952]: DO vm-test-run-index_advisor> server # [ 169.910043] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-index_advisor> server # [ 170.463607] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1954]: DO vm-test-run-index_advisor> server # [ 170.498545] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-index_advisor> server # [ 171.029324] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1966]: ALTER ROLE vm-test-run-index_advisor> server # [ 171.099121] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-index_advisor> server # [ 171.566118] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1978]: GRANT ROLE vm-test-run-index_advisor> server # [ 171.587760] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1978]: DO vm-test-run-index_advisor> server # [ 171.629088] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-index_advisor> server # [ 172.074973] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1984]: GRANT vm-test-run-index_advisor> server # [ 172.125470] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-index_advisor> server # [ 172.423048] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 172.432842] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: REVOKE vm-test-run-index_advisor> server # [ 172.445472] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: REVOKE vm-test-run-index_advisor> server # [ 172.469578] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: GRANT vm-test-run-index_advisor> server # [ 172.499408] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-index_advisor> server # [ 172.783780] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1988]: DO vm-test-run-index_advisor> server # [ 172.841710] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-index_advisor> server # [ 173.232146] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1990]: ALTER ROLE vm-test-run-index_advisor> server # [ 173.297928] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-index_advisor> server # [ 173.944633] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1996]: DROP EXTENSION vm-test-run-index_advisor> server # [ 173.974659] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-index_advisor> server # [ 174.547524] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2008]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 174.549353] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2008]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 174.577671] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2008]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 174.669865] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-index_advisor> server # [ 175.146046] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2020]: DO vm-test-run-index_advisor> server # [ 175.210190] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2020]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 175.261086] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql vm-test-run-index_advisor> server # [ 175.699461] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2026]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 175.706497] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2026]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 175.760668] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running postgresql schema... vm-test-run-index_advisor> server # [ 176.023965] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER DATABASE vm-test-run-index_advisor> server # [ 176.032767] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER DATABASE vm-test-run-index_advisor> server # [ 176.101063] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.166791] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.222296] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.279650] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.342843] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.440463] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.605766] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.737762] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.901315] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.918866] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE vm-test-run-index_advisor> server # [ 176.983397] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Database initialization complete vm-test-run-index_advisor> server # [ 177.088464] systemd[1]: Finished Supabase Database Initialization. vm-test-run-index_advisor> server # [ 177.108597] systemd[1]: Reached target Multi-User System. vm-test-run-index_advisor> server # [ 177.143898] systemd[1]: Startup finished in 18.926s (kernel) + 2min 38.127s (userspace) = 2min 57.053s. vm-test-run-index_advisor> server: (finished: waiting for unit supabase-db-init.service, in 178.88 seconds) vm-test-run-index_advisor> subtest: Verify PostgreSQL 15 is our custom build vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.61 seconds) vm-test-run-index_advisor> server: must succeed: readlink -f $(which postgres) vm-test-run-index_advisor> server: (finished: must succeed: readlink -f $(which postgres), in 0.48 seconds) vm-test-run-index_advisor> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 1.10 seconds) vm-test-run-index_advisor> subtest: Verify ansible config loaded vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.59 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.85 seconds) vm-test-run-index_advisor> (finished: subtest: Verify ansible config loaded, in 1.43 seconds) vm-test-run-index_advisor> subtest: Verify init scripts and migrations ran vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;", in 0.82 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;", in 0.58 seconds) vm-test-run-index_advisor> (finished: subtest: Verify init scripts and migrations ran, in 1.39 seconds) vm-test-run-index_advisor> subtest: Check upgrade path with postgresql 15 vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" vm-test-run-index_advisor> server # NOTICE: extension "index_advisor" does not exist, skipping vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" , in 0.61 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" vm-test-run-index_advisor> server # NOTICE: installing required extension "hypopg" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" , in 2.11 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" , in 0.55 seconds) vm-test-run-index_advisor> (finished: subtest: Check upgrade path with postgresql 15, in 3.28 seconds) vm-test-run-index_advisor> subtest: Check the install of the last version of the extension vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" , in 0.68 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" , in 1.66 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" , in 0.57 seconds) vm-test-run-index_advisor> (finished: subtest: Check the install of the last version of the extension, in 2.91 seconds) vm-test-run-index_advisor> subtest: switch to postgresql 17 vm-test-run-index_advisor> server # Checking switch inhibitors... done vm-test-run-index_advisor> server # stopping the following units: postgresql.service, supabase-db-init.service vm-test-run-index_advisor> server # [ 198.026196] nixos[2168]: switching to system configuration /nix/store/p6hpg7fs71y8djdag53jwb1wiq2rsacw-nixos-system-server-test vm-test-run-index_advisor> server # [ 198.098046] systemd[1]: Stopped target Remote File Systems. vm-test-run-index_advisor> server # [ 198.146682] systemd[1]: supabase-db-init.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 198.162136] systemd[1]: Stopped Supabase Database Initialization. vm-test-run-index_advisor> server # [ 198.174502] systemd[1]: supabase-db-init.service: Consumed 8.696s CPU time, 3.5M memory peak, 89.6K incoming IP traffic, 120.7K outgoing IP traffic. vm-test-run-index_advisor> server # [ 198.231788] postgres[1423]: 2026-10-07 20:40:20.798 UTC [1423] LOG: received fast shutdown request vm-test-run-index_advisor> server # [ 198.239708] systemd[1]: Stopping PostgreSQL Database Server... vm-test-run-index_advisor> server # [ 198.254332] postgres[1423]: 2026-10-07 20:40:20.826 UTC [1423] LOG: aborting any active transactions vm-test-run-index_advisor> server # [ 198.317557] postgres[1460]: 2026-10-07 20:40:20.880 UTC [1460] FATAL: terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command vm-test-run-index_advisor> server # [ 198.318625] systemd[1]: Stopped target Local File Systems. vm-test-run-index_advisor> server # [ 198.336036] postgres[1462]: 2026-10-07 20:40:20.867 UTC [1462] LOG: pg_cron scheduler shutting down vm-test-run-index_advisor> server # [ 198.469270] postgres[1423]: 2026-10-07 20:40:21.041 UTC [1423] LOG: background worker "TimescaleDB Background Worker Launcher" (PID 1460) exited with exit code 1 vm-test-run-index_advisor> server # [ 198.516987] postgres[1423]: 2026-10-07 20:40:21.087 UTC [1423] LOG: background worker "logical replication launcher" (PID 1463) exited with exit code 1 vm-test-run-index_advisor> server # [ 198.581347] postgres[1423]: 2026-10-07 20:40:21.150 UTC [1423] LOG: background worker "pg_cron launcher" (PID 1462) exited with exit code 1 vm-test-run-index_advisor> server # [ 198.601850] postgres[1423]: 2026-10-07 20:40:21.172 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1505) exited with exit code 1 vm-test-run-index_advisor> server # [ 198.641419] postgres[1455]: 2026-10-07 20:40:21.186 UTC [1455] LOG: shutting down vm-test-run-index_advisor> server # [ 198.647747] postgres[1455]: 2026-10-07 20:40:21.206 UTC [1455] LOG: checkpoint starting: shutdown immediate vm-test-run-index_advisor> server # [ 199.197218] postgres[1455]: 2026-10-07 20:40:21.766 UTC [1455] LOG: checkpoint complete: wrote 278 buffers (1.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.225 s, sync=0.229 s, total=0.579 s; sync files=140, longest=0.018 s, average=0.002 s; distance=2046 kB, estimate=2046 kB vm-test-run-index_advisor> server # [ 199.364229] postgres[1423]: 2026-10-07 20:40:21.934 UTC [1423] LOG: database system is shut down vm-test-run-index_advisor> server # [ 199.462645] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 199.471447] systemd[1]: Stopped PostgreSQL Database Server. vm-test-run-index_advisor> server # [ 199.481508] systemd[1]: postgresql.service: Consumed 42.535s CPU time, 124.2M memory peak, 54.9M written to disk, 124.9K incoming IP traffic, 93.8K outgoing IP traffic. vm-test-run-index_advisor> server # activating the configuration... vm-test-run-index_advisor> server # setting up /etc... vm-test-run-index_advisor> server # [ 207.750250] systemd[1]: Reload requested from client PID 2168 ('.switch-to-conf') (unit backdoor.service)... vm-test-run-index_advisor> server # [ 207.757919] systemd[1]: Reloading...