these 7 derivations will be built: /nix/store/70sdqqdnwx6afv4zdshk82pxjfl5gxgh-nixos-system-server-test.drv /nix/store/mpb0jm3m74l8rnvhmh66idp5f7n1jb63-nixos-system-server-test.drv /nix/store/m7zar2dkjwlqzw2332bpjx2d4w6fvh3s-closure-info.drv /nix/store/dwz4cw00kb545849z25rkxcwyz1pplkq-run-nixos-vm.drv /nix/store/y7mljpkzqf3zgxp9srgsxix1npvbqdyz-nixos-vm.drv /nix/store/5p0y5lnqpj2iyf0i2h0nww6bagba1bq6-nixos-test-driver-index_advisor.drv /nix/store/anxi02qh2yyf32jrj4cjj7x9wf4c1qk1-vm-test-run-index_advisor.drv building '/nix/store/70sdqqdnwx6afv4zdshk82pxjfl5gxgh-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/70sdqqdnwx6afv4zdshk82pxjfl5gxgh-nixos-system-server-test.drv' building '/nix/store/5p0y5lnqpj2iyf0i2h0nww6bagba1bq6-nixos-test-driver-index_advisor.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/5p0y5lnqpj2iyf0i2h0nww6bagba1bq6-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/anxi02qh2yyf32jrj4cjj7x9wf4c1qk1-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/anxi02qh2yyf32jrj4cjj7x9wf4c1qk1-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.ikn7qPk7nR', 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: 234ae3b6-d5e7-4de2-98a4-ca8a583314a7 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.14 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/9im41iz4mgfwdf5fyng9jp1364k6mdbn-nixos-system-server-test/init regInfo=/nix/store/xv1849s6gakrdhbrf3sljgqz6kxjmg19-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-index_advisor> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/xv1849s6gakrdhbrf3sljgqz6kxjmg19-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.000035] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-index_advisor> server # [ 0.003571] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-index_advisor> server # [ 0.013088] Console: colour dummy device 80x25 vm-test-run-index_advisor> server # [ 0.014430] printk: legacy console [tty0] enabled vm-test-run-index_advisor> server # [ 0.020150] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-index_advisor> server # [ 0.020517] pid_max: default: 32768 minimum: 301 vm-test-run-index_advisor> server # [ 0.022617] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-index_advisor> server # [ 0.024269] landlock: Up and running. vm-test-run-index_advisor> server # [ 0.024333] Yama: becoming mindful. vm-test-run-index_advisor> server # [ 0.028212] LSM support for eBPF active vm-test-run-index_advisor> server # [ 0.031502] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.031674] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.060663] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-index_advisor> server # [ 0.072587] rcu: Hierarchical SRCU implementation. vm-test-run-index_advisor> server # [ 0.072676] rcu: Max phase no-delay instances is 1000. vm-test-run-index_advisor> server # [ 0.074095] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-index_advisor> server # [ 0.081765] fsl-mc MSI: its@8080000 domain created vm-test-run-index_advisor> server # [ 0.083573] EFI services will not be available. vm-test-run-index_advisor> server # [ 0.085494] smp: Bringing up secondary CPUs ... vm-test-run-index_advisor> server # [ 0.089443] Detected PIPT I-cache on CPU1 vm-test-run-index_advisor> server # [ 0.090580] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-index_advisor> server # [ 0.091454] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000 vm-test-run-index_advisor> server # [ 0.093125] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-index_advisor> server # [ 0.096557] smp: Brought up 1 node, 2 CPUs vm-test-run-index_advisor> server # [ 0.096692] SMP: Total of 2 processors activated. vm-test-run-index_advisor> server # [ 0.096736] CPU: All CPU(s) started at EL1 vm-test-run-index_advisor> server # [ 0.096873] CPU features: detected: Branch Target Identification vm-test-run-index_advisor> server # [ 0.096918] CPU features: detected: 32-bit EL0 Support vm-test-run-index_advisor> server # [ 0.096943] CPU features: detected: 32-bit EL1 Support vm-test-run-index_advisor> server # [ 0.096964] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-index_advisor> server # [ 0.096992] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-index_advisor> server # [ 0.097026] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-index_advisor> server # [ 0.097080] CPU features: detected: Common not Private translations vm-test-run-index_advisor> server # [ 0.097102] CPU features: detected: CRC32 instructions vm-test-run-index_advisor> server # [ 0.097121] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-index_advisor> server # [ 0.097150] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-index_advisor> server # [ 0.097172] CPU features: detected: Data independent timing control (DIT) vm-test-run-index_advisor> server # [ 0.097263] CPU features: detected: E0PD vm-test-run-index_advisor> server # [ 0.097368] CPU features: detected: Enhanced Counter Virtualization vm-test-run-index_advisor> server # [ 0.097408] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-index_advisor> server # [ 0.097439] CPU features: detected: Enhanced Privileged Access Never vm-test-run-index_advisor> server # [ 0.097464] CPU features: detected: Enhanced Virtualization Traps vm-test-run-index_advisor> server # [ 0.097489] CPU features: detected: Fine Grained Traps vm-test-run-index_advisor> server # [ 0.097552] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-index_advisor> server # [ 0.097642] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-index_advisor> server # [ 0.097689] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-index_advisor> server # [ 0.097714] CPU features: detected: LSE atomic instructions vm-test-run-index_advisor> server # [ 0.097738] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-index_advisor> server # [ 0.097763] CPU features: detected: Privileged Access Never vm-test-run-index_advisor> server # [ 0.097786] CPU features: detected: RAS Extension Support vm-test-run-index_advisor> server # [ 0.097811] CPU features: detected: Random Number Generator vm-test-run-index_advisor> server # [ 0.097831] CPU features: detected: Speculation barrier (SB) vm-test-run-index_advisor> server # [ 0.097851] CPU features: detected: Stage-2 Force Write-Back vm-test-run-index_advisor> server # [ 0.097871] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-index_advisor> server # [ 0.097899] CPU features: detected: TLB range maintenance instructions vm-test-run-index_advisor> server # [ 0.097924] CPU features: detected: WFx with timeout vm-test-run-index_advisor> server # [ 0.097967] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-index_advisor> server # [ 0.097994] CPU features: detected: Scalable Vector Extension vm-test-run-index_advisor> server # [ 0.102090] alternatives: applying system-wide alternatives vm-test-run-index_advisor> server # [ 0.120554] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-index_advisor> server # [ 0.121139] SVE: maximum available vector length 256 bytes per vector vm-test-run-index_advisor> server # [ 0.121223] SVE: default vector length 64 bytes per vector vm-test-run-index_advisor> server # [ 0.133670] Memory: 3922924K/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.154998] devtmpfs: initialized vm-test-run-index_advisor> server # [ 0.180773] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-index_advisor> server # [ 0.181239] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-index_advisor> server # [ 0.183768] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-index_advisor> server # [ 0.183841] 0 pages in range for non-PLT usage vm-test-run-index_advisor> server # [ 0.183882] 509248 pages in range for PLT usage vm-test-run-index_advisor> server # [ 0.186110] pinctrl core: initialized pinctrl subsystem vm-test-run-index_advisor> server # [ 0.196079] DMI not present or invalid. vm-test-run-index_advisor> server # [ 0.216470] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-index_advisor> server # [ 0.229541] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-index_advisor> server # [ 0.230621] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-index_advisor> server # [ 0.231670] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-index_advisor> server # [ 0.231926] audit: initializing netlink subsys (disabled) vm-test-run-index_advisor> server # [ 0.233817] audit: type=2000 audit(0.164:1): state=initialized audit_enabled=0 res=1 vm-test-run-index_advisor> server # [ 0.239286] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-index_advisor> server # [ 0.239340] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-index_advisor> server # [ 0.239394] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-index_advisor> server # [ 0.239418] thermal_sys: Registered thermal governor 'user_space' vm-test-run-index_advisor> server # [ 0.239443] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-index_advisor> server # [ 0.240257] cpuidle: using governor ladder vm-test-run-index_advisor> server # [ 0.240540] cpuidle: using governor menu vm-test-run-index_advisor> server # [ 0.242328] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-index_advisor> server # [ 0.243546] ASID allocator initialised with 65536 entries vm-test-run-index_advisor> server # [ 0.250503] Serial: AMBA PL011 UART driver vm-test-run-index_advisor> server # [ 0.296435] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-index_advisor> server # [ 0.298595] printk: legacy console [ttyAMA0] enabled vm-test-run-index_advisor> server # [ 0.332865] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.333054] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-index_advisor> server # [ 0.333285] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.333385] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-index_advisor> server # [ 0.333779] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.333871] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-index_advisor> server # [ 0.333959] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.334065] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-index_advisor> server # [ 0.345901] fbcon: Taking over console vm-test-run-index_advisor> server # [ 0.346672] ACPI: Interpreter disabled. vm-test-run-index_advisor> server # [ 0.354034] iommu: Default domain type: Translated vm-test-run-index_advisor> server # [ 0.354230] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-index_advisor> server # [ 0.357262] SCSI subsystem initialized vm-test-run-index_advisor> server # [ 0.359881] usbcore: registered new interface driver usbfs vm-test-run-index_advisor> server # [ 0.360850] usbcore: registered new interface driver hub vm-test-run-index_advisor> server # [ 0.361235] usbcore: registered new device driver usb vm-test-run-index_advisor> server # [ 0.364343] pps_core: LinuxPPS API ver. 1 registered vm-test-run-index_advisor> server # [ 0.364960] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-index_advisor> server # [ 0.365627] PTP clock support registered vm-test-run-index_advisor> server # [ 0.366290] EDAC MC: Ver: 3.0.0 vm-test-run-index_advisor> server # [ 0.368102] scmi_core: SCMI protocol bus registered vm-test-run-index_advisor> server # [ 0.371670] FPGA manager framework vm-test-run-index_advisor> server # [ 0.373070] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-index_advisor> server # [ 0.381344] vgaarb: loaded vm-test-run-index_advisor> server # [ 0.385522] clocksource: Switched to clocksource arch_sys_counter vm-test-run-index_advisor> server # [ 0.426238] VFS: Disk quotas dquot_6.6.0 vm-test-run-index_advisor> server # [ 0.427216] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-index_advisor> server # [ 0.428911] netfs: FS-Cache loaded vm-test-run-index_advisor> server # [ 0.429992] pnp: PnP ACPI: disabled vm-test-run-index_advisor> server # [ 0.454217] NET: Registered PF_INET protocol family vm-test-run-index_advisor> server # [ 0.456077] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-index_advisor> server # [ 0.566451] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-index_advisor> server # [ 0.567512] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-index_advisor> server # [ 0.568460] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-index_advisor> server # [ 0.569753] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-index_advisor> server # [ 0.571123] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-index_advisor> server # [ 0.572930] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-index_advisor> server # [ 0.574124] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.574512] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.576499] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-index_advisor> server # [ 0.576953] NET: Registered PF_XDP protocol family vm-test-run-index_advisor> server # [ 0.577529] PCI: CLS 0 bytes, default 64 vm-test-run-index_advisor> server # [ 0.581084] kvm [1]: HYP mode not available vm-test-run-index_advisor> server # [ 0.583129] Trying to unpack rootfs image as initramfs... vm-test-run-index_advisor> server # [ 0.688349] Initialise system trusted keyrings vm-test-run-index_advisor> server # [ 0.690180] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-index_advisor> server # [ 0.694372] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-index_advisor> server # [ 0.695086] 9p: Installing v9fs 9p2000 file system support vm-test-run-index_advisor> server # [ 0.756078] Key type asymmetric registered vm-test-run-index_advisor> server # [ 0.756314] Asymmetric key parser 'x509' registered vm-test-run-index_advisor> server # [ 0.757096] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-index_advisor> server # [ 0.759395] io scheduler mq-deadline registered vm-test-run-index_advisor> server # [ 0.759624] io scheduler kyber registered vm-test-run-index_advisor> server # [ 0.787519] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-index_advisor> server # [ 0.792370] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-index_advisor> server # [ 0.796323] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-index_advisor> server # [ 0.797183] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-index_advisor> server # [ 0.798642] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-index_advisor> server # [ 0.798869] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-index_advisor> server # [ 0.799420] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-index_advisor> server # [ 0.800136] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-index_advisor> server # [ 0.801614] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-index_advisor> server # [ 0.802661] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-index_advisor> server # [ 0.802846] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-index_advisor> server # [ 0.802995] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-index_advisor> server # [ 0.803101] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-index_advisor> server # [ 0.804252] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.807523] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.808015] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.808257] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.808416] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.808852] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-index_advisor> server # [ 0.809801] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.810302] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.810570] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.810708] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.811198] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.811346] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-index_advisor> server # [ 0.811445] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.811559] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.811979] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.812121] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.812211] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.812319] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.812753] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.812893] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.812987] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.813092] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.813545] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.813682] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-index_advisor> server # [ 0.813770] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.813877] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.815560] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.816013] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.816260] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.816396] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.816510] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-index_advisor> server # [ 0.817013] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.817186] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.817302] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.818693] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.818898] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.819020] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.819548] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.819925] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.820406] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.820587] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-index_advisor> server # [ 0.820678] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.820788] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.821310] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.821470] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.821559] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.821671] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.824318] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-index_advisor> server # [ 0.824622] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-index_advisor> server # [ 0.824800] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.825009] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.825173] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.825308] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.825542] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.825880] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.826307] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.826453] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.826596] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.827161] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.827324] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.827465] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-index_advisor> server # [ 0.827788] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-index_advisor> server # [ 0.828189] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-index_advisor> server # [ 0.828313] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-index_advisor> server # [ 0.828429] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-index_advisor> server # [ 0.828558] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-index_advisor> server # [ 0.828671] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-index_advisor> server # [ 0.828779] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-index_advisor> server # [ 0.828901] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-index_advisor> server # [ 0.829016] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-index_advisor> server # [ 0.829145] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-index_advisor> server # [ 0.829255] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-index_advisor> server # [ 0.829844] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-index_advisor> server # [ 0.829972] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-index_advisor> server # [ 0.830648] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-index_advisor> server # [ 0.830760] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-index_advisor> server # [ 0.830867] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-index_advisor> server # [ 0.830968] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-index_advisor> server # [ 0.831066] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-index_advisor> server # [ 0.831170] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-index_advisor> server # [ 0.831275] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-index_advisor> server # [ 0.831565] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-index_advisor> server # [ 0.831682] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-index_advisor> server # [ 0.831783] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-index_advisor> server # [ 0.832693] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.890625] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.893959] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.896578] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.899338] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.901095] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.903016] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.904798] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.907642] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.909312] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.912114] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.914142] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.930738] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-index_advisor> server # [ 0.942606] msm_serial: driver initialized vm-test-run-index_advisor> server # [ 0.943481] SuperH (H)SCI(F) driver initialized vm-test-run-index_advisor> server # [ 0.944262] STM32 USART driver initialized vm-test-run-index_advisor> server # [ 0.992350] loop: module loaded vm-test-run-index_advisor> server # [ 0.993008] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-index_advisor> server # [ 0.996437] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-index_advisor> server # [ 1.009033] megasas: 07.727.03.00-rc1 vm-test-run-index_advisor> server # [ 1.014920] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-index_advisor> server # [ 1.017083] 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.018992] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-index_advisor> server # [ 1.020402] Using buffer write method vm-test-run-index_advisor> server # [ 1.021383] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-index_advisor> server # [ 1.024163] 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.024479] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-index_advisor> server # [ 1.026497] Using buffer write method vm-test-run-index_advisor> server # [ 1.027100] Concatenating MTD devices: vm-test-run-index_advisor> server # [ 1.027187] (0): "0.flash" vm-test-run-index_advisor> server # [ 1.027248] (1): "0.flash" vm-test-run-index_advisor> server # [ 1.027309] into device "0.flash" vm-test-run-index_advisor> server # [ 1.036252] Freeing initrd memory: 10684K vm-test-run-index_advisor> server # [ 1.058790] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-index_advisor> server # [ 1.071955] thunder_xcv, ver 1.0 vm-test-run-index_advisor> server # [ 1.072231] thunder_bgx, ver 1.0 vm-test-run-index_advisor> server # [ 1.072404] nicpf, ver 1.0 vm-test-run-index_advisor> server # [ 1.075682] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-index_advisor> server # [ 1.075850] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-index_advisor> server # [ 1.076216] hclge is initializing vm-test-run-index_advisor> server # [ 1.076433] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-index_advisor> server # [ 1.076522] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-index_advisor> server # [ 1.076725] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-index_advisor> server # [ 1.076813] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-index_advisor> server # [ 1.077045] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-index_advisor> server # [ 1.077149] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-index_advisor> server # [ 1.077341] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-index_advisor> server # [ 1.077444] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-index_advisor> server # [ 1.079115] sky2: driver version 1.30 vm-test-run-index_advisor> server # [ 1.088247] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-index_advisor> server # [ 1.088809] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-index_advisor> server # [ 1.090354] usbcore: registered new interface driver usb-storage vm-test-run-index_advisor> server # [ 1.091065] usbcore: registered new interface driver usbserial_generic vm-test-run-index_advisor> server # [ 1.091366] usbserial: USB Serial support registered for generic vm-test-run-index_advisor> server # [ 1.093022] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-index_advisor> server # [ 1.101902] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-index_advisor> server # [ 1.102982] rtc-pl031 9010000.pl031: setting system clock to 2026-10-23T15:20:03 UTC (1792768803) vm-test-run-index_advisor> server # [ 1.104960] i2c_dev: i2c /dev entries driver vm-test-run-index_advisor> server # [ 1.106611] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-index_advisor> server # [ 1.113681] hub 1-0:1.0: USB hub found vm-test-run-index_advisor> server # [ 1.114807] hub 1-0:1.0: 6 ports detected vm-test-run-index_advisor> server # [ 1.122488] sdhci: Secure Digital Host Controller Interface driver vm-test-run-index_advisor> server # [ 1.123052] sdhci: Copyright(c) Pierre Ossman vm-test-run-index_advisor> server # [ 1.124622] Synopsys Designware Multimedia Card Interface Driver vm-test-run-index_advisor> server # [ 1.126863] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-index_advisor> server # [ 1.133537] hid: raw HID events driver (C) Jiri Kosina vm-test-run-index_advisor> server # [ 1.135994] usbcore: registered new interface driver usbhid vm-test-run-index_advisor> server # [ 1.136238] usbhid: USB HID core driver vm-test-run-index_advisor> server # [ 1.145212] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-index_advisor> server # [ 1.160890] drop_monitor: Initializing network drop monitor service vm-test-run-index_advisor> server # [ 1.163049] NET: Registered PF_INET6 protocol family vm-test-run-index_advisor> server # [ 1.179571] Segment Routing with IPv6 vm-test-run-index_advisor> server # [ 1.179976] In-situ OAM (IOAM) with IPv6 vm-test-run-index_advisor> server # [ 1.180600] NET: Registered PF_PACKET protocol family vm-test-run-index_advisor> server # [ 1.182460] 9pnet: Installing 9P2000 support vm-test-run-index_advisor> server # [ 1.187627] Key type dns_resolver registered vm-test-run-index_advisor> server # [ 1.226351] registered taskstats version 1 vm-test-run-index_advisor> server # [ 1.228502] Loading compiled-in X.509 certificates vm-test-run-index_advisor> server # [ 1.285875] Demotion targets for Node 0: null vm-test-run-index_advisor> server # [ 1.288652] Key type .fscrypt registered vm-test-run-index_advisor> server # [ 1.288854] Key type fscrypt-provisioning registered vm-test-run-index_advisor> server # [ 1.299668] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-index_advisor> server # [ 1.347740] clk: Disabling unused clocks vm-test-run-index_advisor> server # [ 1.348613] PM: genpd: Disabling unused power domains vm-test-run-index_advisor> server # [ 1.349319] ALSA device list: vm-test-run-index_advisor> server # [ 1.350158] No soundcards found. vm-test-run-index_advisor> server # [ 1.365956] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-index_advisor> server # [ 1.439538] Freeing unused kernel memory: 4416K vm-test-run-index_advisor> server # [ 1.441257] Run /init as init process vm-test-run-index_advisor> server # [ 1.552625] 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.716451] 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.841782] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-index_advisor> server # [ 2.024702] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-index_advisor> server # [ 2.039625] 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.040809] 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.155322] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-index_advisor> server # [ 2.156032] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-index_advisor> server # [ 2.156055] [drm] features: -context_init vm-test-run-index_advisor> server # [ 2.159498] [drm] number of scanouts: 1 vm-test-run-index_advisor> server # [ 2.159700] [drm] number of cap sets: 0 vm-test-run-index_advisor> server # [ 2.168958] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-index_advisor> server # [ 2.204074] Console: switching to colour frame buffer device 160x50 vm-test-run-index_advisor> server # [ 2.216075] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-index_advisor> server # [ 5.223837] EXT4-fs (vda): mounted filesystem 234ae3b6-d5e7-4de2-98a4-ca8a583314a7 r/w with ordered data mode. Quota mode: none. vm-test-run-index_advisor> server # [ 7.617277] EXT4-fs (vda): re-mounted 234ae3b6-d5e7-4de2-98a4-ca8a583314a7. vm-test-run-index_advisor> server # [ 7.634814] booting system configuration /nix/store/9im41iz4mgfwdf5fyng9jp1364k6mdbn-nixos-system-server-test vm-test-run-index_advisor> server # [ 20.635977] 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 # [ 20.646648] systemd[1]: Detected virtualization qemu. vm-test-run-index_advisor> server # [ 20.651052] systemd[1]: Detected architecture arm64. vm-test-run-index_advisor> server # [ 20.655140] systemd[1]: Detected first boot. vm-test-run-index_advisor> server # [ 20.685026] systemd[1]: Initializing machine ID from random generator. vm-test-run-index_advisor> server # [ 20.723190] systemd[1]: Hostname set to . vm-test-run-index_advisor> server # [ 21.947079] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-index_advisor> server # [ 23.315694] systemd[1]: Applying preset policy. vm-test-run-index_advisor> server # [ 26.880317] 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 # [ 30.363629] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-index_advisor> server # [ 30.597088] systemd[1]: Created slice Slice /system/getty. vm-test-run-index_advisor> server # [ 30.612116] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-index_advisor> server # [ 30.625534] systemd[1]: Created slice User and Session Slice. vm-test-run-index_advisor> server # [ 30.634995] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-index_advisor> server # [ 30.641316] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-index_advisor> server # [ 30.647663] systemd[1]: Expecting device /dev/hvc0... vm-test-run-index_advisor> server # [ 30.652847] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-index_advisor> server # [ 30.658076] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-index_advisor> server # [ 30.664039] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-index_advisor> server # [ 30.669676] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-index_advisor> server # [ 30.674203] systemd[1]: Reached target Path Units. vm-test-run-index_advisor> server # [ 30.677016] systemd[1]: Reached target Remote File Systems. vm-test-run-index_advisor> server # [ 30.681766] systemd[1]: Reached target Slice Units. vm-test-run-index_advisor> server # [ 30.687240] systemd[1]: Reached target Swaps. vm-test-run-index_advisor> server # [ 30.726252] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-index_advisor> server # [ 30.753451] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-index_advisor> server # [ 30.765897] systemd[1]: Listening on Journal Audit Socket. vm-test-run-index_advisor> server # [ 30.774084] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-index_advisor> server # [ 30.779953] systemd[1]: Listening on Journal Sockets. vm-test-run-index_advisor> server # [ 30.788781] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-index_advisor> server # [ 30.794036] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 30.799932] systemd[1]: Listening on udev Control Socket. vm-test-run-index_advisor> server # [ 30.805686] systemd[1]: Listening on udev Kernel Socket. vm-test-run-index_advisor> server # [ 30.857824] systemd[1]: Mounting Huge Pages File System... vm-test-run-index_advisor> server # [ 30.908082] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-index_advisor> server # [ 30.960721] systemd[1]: Mounting Kernel Debug File System... vm-test-run-index_advisor> server # [ 31.083856] systemd[1]: Mounting Kernel Trace File System... vm-test-run-index_advisor> server # [ 31.207944] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-index_advisor> server # [ 31.347498] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-index_advisor> server # [ 31.368177] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-index_advisor> server # [ 31.480894] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-index_advisor> server # [ 31.504183] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-index_advisor> server # [ 31.517720] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-index_advisor> server # [ 31.602174] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-index_advisor> server # [ 31.615623] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-index_advisor> server # [ 31.628035] 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 # [ 31.720419] systemd[1]: Starting Journal Service... vm-test-run-index_advisor> server # [ 31.824134] systemd[1]: Starting Load Kernel Modules... vm-test-run-index_advisor> server # [ 31.924482] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-index_advisor> server # [ 31.995136] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-index_advisor> server # [ 32.009159] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 32.068125] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-index_advisor> server # [ 32.160743] systemd[1]: Mounted Huge Pages File System. vm-test-run-index_advisor> server # [ 32.179981] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-index_advisor> server # [ 32.194951] systemd[1]: Mounted Kernel Debug File System. vm-test-run-index_advisor> server # [ 32.214322] systemd[1]: Mounted Kernel Trace File System. vm-test-run-index_advisor> server # [ 32.240050] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-index_advisor> server # [ 32.256732] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-index_advisor> server # [ 32.322622] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-index_advisor> server # [ 32.453239] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 32.468478] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-index_advisor> server # [ 33.085852] systemd[1]: Finished Load Kernel Modules. vm-test-run-index_advisor> server # [ 33.095428] fuse: init (API version 7.41) vm-test-run-index_advisor> server # [ 33.153022] systemd[1]: Starting Firewall... vm-test-run-index_advisor> server # [ 33.187339] EXT4-fs (vda): re-mounted 234ae3b6-d5e7-4de2-98a4-ca8a583314a7. vm-test-run-index_advisor> server # [ 33.209070] systemd[1]: Starting Apply Kernel Variables... vm-test-run-index_advisor> server # [ 33.252324] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 33.275880] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-index_advisor> server # [ 33.292385] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-index_advisor> server # [ 33.340501] systemd[1]: Mounting FUSE Control File System... vm-test-run-index_advisor> server # [ 33.356254] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-index_advisor> server # [ 33.416228] systemd-journald[395]: Collecting audit messages is enabled. vm-test-run-index_advisor> server # [ 33.427175] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-index_advisor> server # [ 33.432481] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 33.645444] systemd[1]: Mounted FUSE Control File System. vm-test-run-index_advisor> server # [ 34.490940] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-index_advisor> server # [ 34.502752] systemd[1]: Reached target First Boot Complete. vm-test-run-index_advisor> server # [ 34.842827] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-index_advisor> server # [ 34.889062] systemd[1]: Finished Apply Kernel Variables. vm-test-run-index_advisor> server # [ 34.999745] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-index_advisor> server # [ 35.292851] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-index_advisor> server # [ 36.060369] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-index_advisor> server # [ 36.081321] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-index_advisor> server # [ 36.133098] systemd[1]: Mounting /run/wrappers... vm-test-run-index_advisor> server # [ 36.190127] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-index_advisor> server # [ 36.396746] systemd[1]: Started Journal Service. vm-test-run-index_advisor> server # [ 36.328929] systemd-modules-load[396]: Module 'atkbd' is built in vm-test-run-index_advisor> server # [ 36.390703] systemd-modules-load[396]: Module 'loop' is built in vm-test-run-index_advisor> server # [ 36.443616] systemd-oomd[397]: No swap; memory pressure usage will be degraded vm-test-run-index_advisor> server # [ 36.509738] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-index_advisor> server # [ 36.670813] systemd[1]: Mounted /run/wrappers. vm-test-run-index_advisor> server # [ 36.810902] systemd[1]: Reached target Local File Systems. vm-test-run-index_advisor> server # [ 36.829764] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-index_advisor> server # [ 36.873536] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-index_advisor> server # [ 36.883279] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-index_advisor> server # [ 36.941157] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-index_advisor> server # [ 37.457163] systemd-journald[395]: Received client request to flush runtime journal. vm-test-run-index_advisor> server # [ 38.071233] systemd-udevd[425]: Using default interface naming scheme 'v258'. vm-test-run-index_advisor> server # [ 38.102489] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-index_advisor> server # [ 38.117900] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-index_advisor> server # [ 38.127619] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-index_advisor> server # [ 38.162245] systemd[1]: Starting Create System Files and Directories... vm-test-run-index_advisor> server # [ 40.141448] systemd[1]: Finished Create System Files and Directories. vm-test-run-index_advisor> server # [ 40.205351] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-index_advisor> server # [ 40.267718] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-index_advisor> server # [ 40.540740] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-index_advisor> server # [ 41.352018] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-index_advisor> server # [ 42.619035] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-index_advisor> server # [ 42.659764] systemd[1]: Starting Update is Completed... vm-test-run-index_advisor> server # [ 43.478965] systemd[1]: Finished Update is Completed. vm-test-run-index_advisor> server # [ 47.421482] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 47.431644] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-index_advisor> server # [ 47.436609] systemd[1]: suid-sgid-wrappers.service: Consumed 2.869s CPU time, 5.6M memory peak. vm-test-run-index_advisor> server # [ 49.899284] systemd[1]: Finished Firewall. vm-test-run-index_advisor> server # [ 51.414972] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-index_advisor> server # [ 51.434537] systemd[1]: Reached target System Initialization. vm-test-run-index_advisor> server # [ 51.436200] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-index_advisor> server # [ 51.451192] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-index_advisor> server # [ 51.457228] systemd[1]: Reached target Timer Units. vm-test-run-index_advisor> server # [ 51.458554] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-index_advisor> server # [ 51.461420] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-index_advisor> server # [ 51.466906] systemd[1]: Listening on Hostname Service Socket. vm-test-run-index_advisor> server # [ 51.468297] systemd[1]: Reached target Socket Units. vm-test-run-index_advisor> server # [ 51.471378] systemd[1]: Reached target Basic System. vm-test-run-index_advisor> server # [ 51.489052] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-index_advisor> server # [ 51.534692] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 51.575269] systemd[1]: Started Reset console on configuration changes. vm-test-run-index_advisor> server # [ 51.621046] systemd[1]: Starting resolvconf update... vm-test-run-index_advisor> server # [ 51.700670] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-index_advisor> server # [ 52.691970] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-index_advisor> server # [ 52.883873] nsncd[630]: Oct 23 15:20:55.326 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-index_advisor> server # [ 52.906575] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 52.917771] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 52.926066] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 52.964613] systemd[1]: Starting User Login Management... vm-test-run-index_advisor> server # [ 53.126405] dbus-daemon[633]: dbus[633]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-index_advisor> server # [ 53.511266] systemd[1]: Started D-Bus System Message Bus. vm-test-run-index_advisor> server # [ 54.308073] systemd-logind[649]: New seat seat0. vm-test-run-index_advisor> server # [ 54.375046] systemd[1]: Started User Login Management. vm-test-run-index_advisor> server # [ 54.404195] systemd[1]: Starting linger-users.service... vm-test-run-index_advisor> server # [ 54.587263] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 54.598537] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-index_advisor> server # [ 54.610810] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 54.623215] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-index_advisor> server # [ 54.625725] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 54.683624] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 54.702446] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 54.855881] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 54.970208] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 54.985059] systemd[1]: Finished linger-users.service. vm-test-run-index_advisor> server # [ 55.443310] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 55.449426] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 55.467867] nsncd[692]: Oct 23 15:20:57.940 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-index_advisor> server # [ 55.475492] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 55.884118] systemd[1]: Finished resolvconf update. vm-test-run-index_advisor> server # [ 55.896722] systemd[1]: Reached target Preparation for Network. vm-test-run-index_advisor> server # [ 55.966209] systemd[1]: Starting DHCP Client... vm-test-run-index_advisor> server # [ 57.384251] dhcpcd[741]: dhcpcd-10.2.4 starting vm-test-run-index_advisor> server # [ 57.475575] dhcpcd[745]: dev: loaded udev vm-test-run-index_advisor> server # [ 57.831352] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-index_advisor> server # [ 58.266824] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-index_advisor> server # [ 58.518650] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-index_advisor> server # [ 58.525348] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-index_advisor> server # [ 58.544696] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-index_advisor> server # [ 58.554178] 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.399300] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-index_advisor> server # [ 59.346813] dhcpcd[745]: eth0: waiting for carrier vm-test-run-index_advisor> server # [ 59.349881] dhcpcd[745]: eth0: carrier acquired vm-test-run-index_advisor> server # [ 59.508956] dhcpcd[745]: DUID 00:01:00:01:32:6e:37:dd:52:54:00:12:34:56 vm-test-run-index_advisor> server # [ 59.511992] dhcpcd[745]: eth0: IAID 00:12:34:56 vm-test-run-index_advisor> server # [ 59.518072] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-index_advisor> server # [ 59.646705] systemd[1]: Found device /dev/hvc0. vm-test-run-index_advisor> server # [ 60.029137] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-index_advisor> server # [ 60.066552] systemd[1]: Started backdoor.service. 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 61.77 seconds) vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 61.77 seconds) vm-test-run-index_advisor> server # [ 61.110199] (udev-worker)[778]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-index_advisor> server # [ 61.174893] (udev-worker)[775]: 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 # [ 61.182216] (udev-worker)[775]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-index_advisor> server # [ 61.470329] dhcpcd[745]: eth0: soliciting an IPv6 router vm-test-run-index_advisor> server # [ 61.486476] dhcpcd[745]: eth0: soliciting a DHCP lease vm-test-run-index_advisor> server # [ 61.522843] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-index_advisor> server # [ 61.529392] dhcpcd[745]: eth0: Router Advertisement from fe80::2 vm-test-run-index_advisor> server # [ 61.551610] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-index_advisor> server # [ 61.569127] dhcpcd[745]: eth0: adding route to fec0::/64 vm-test-run-index_advisor> server # [ 61.571001] dhcpcd[745]: eth0: adding default route via fe80::2 vm-test-run-index_advisor> server # [ 61.585225] dhcpcd[745]: eth0: probing address 10.0.2.15/24 vm-test-run-index_advisor> server # [ 61.914613] dhcpcd[745]: eth0: IAID 00:12:34:56 vm-test-run-index_advisor> server # [ 62.022303] systemd[1]: Found device Virtio network device. vm-test-run-index_advisor> server # [ 62.080238] systemd[1]: Starting Address configuration of eth1... vm-test-run-index_advisor> server # [ 62.161439] dhcpcd[745]: eth0: soliciting a DHCP lease vm-test-run-index_advisor> server # [ 62.169690] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-index_advisor> server # [ 62.171115] dhcpcd[745]: eth0: probing address 10.0.2.15/24 vm-test-run-index_advisor> server # [ 62.439189] dhcpcd[745]: eth0: soliciting an IPv6 router vm-test-run-index_advisor> server # [ 62.927722] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-index_advisor> server # [ 63.083775] network-addresses-eth1-start[797]: adding address 192.168.1.1/24... done vm-test-run-index_advisor> server # [ 63.251186] network-addresses-eth1-start[797]: adding address 2001:db8:1::1/64... done vm-test-run-index_advisor> server # [ 63.470889] systemd[1]: Finished Address configuration of eth1. vm-test-run-index_advisor> server # [ 63.550914] systemd[1]: Starting Networking Setup... vm-test-run-index_advisor> server # [ 65.869603] systemd[1]: Finished Networking Setup. vm-test-run-index_advisor> server # [ 65.886625] systemd[1]: Reached target Network. vm-test-run-index_advisor> server # [ 65.929184] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-index_advisor> server # [ 65.978236] systemd[1]: Starting Permit User Sessions... vm-test-run-index_advisor> server # [ 66.674209] systemd[1]: Finished Permit User Sessions. vm-test-run-index_advisor> server # [ 66.758461] systemd[1]: Started Getty on tty1. vm-test-run-index_advisor> server # [ 66.774024] systemd[1]: Reached target Login Prompts. vm-test-run-index_advisor> server # [ 67.499965] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[911]: Initializing database at /var/lib/postgresql/data vm-test-run-index_advisor> server # [ 67.719695] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-index_advisor> server # [ 67.731731] dhcpcd[745]: eth0: adding route to 10.0.2.0/24 vm-test-run-index_advisor> server # [ 67.733233] dhcpcd[745]: eth0: adding default route via 10.0.2.2 vm-test-run-index_advisor> server # [ 69.745706] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The files belonging to this database system will be owned by user "postgres". vm-test-run-index_advisor> server # [ 69.762850] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: This user must also own the server process. vm-test-run-index_advisor> server # [ 69.854402] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-index_advisor> server # [ 69.855791] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The default database encoding has accordingly been set to "UTF8". vm-test-run-index_advisor> server # [ 69.868185] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The default text search configuration will be set to "english". vm-test-run-index_advisor> server # [ 69.877184] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: Data page checksums are enabled. vm-test-run-index_advisor> server # [ 69.877956] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-index_advisor> server # [ 69.889621] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: creating subdirectories ... ok vm-test-run-index_advisor> server # [ 69.908182] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting dynamic shared memory implementation ... posix vm-test-run-index_advisor> server # [ 70.726200] systemd[1]: Started DHCP Client. vm-test-run-index_advisor> server # [ 71.273088] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting default max_connections ... 100 vm-test-run-index_advisor> server # [ 71.836121] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting default shared_buffers ... 128MB vm-test-run-index_advisor> server # [ 74.513908] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-index_advisor> server # [ 77.923425] mousedev: PS/2 mouse device common for all mice vm-test-run-index_advisor> server # [ 81.654384] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-index_advisor> server # [ 82.370264] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting default time zone ... UTC vm-test-run-index_advisor> server # [ 82.404155] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: creating configuration files ... ok vm-test-run-index_advisor> server # [ 87.070515] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: running bootstrap script ... ok vm-test-run-index_advisor> server # [ 98.000017] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: performing post-bootstrap initialization ... ok vm-test-run-index_advisor> server # [ 100.548683] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: syncing data to disk ... ok vm-test-run-index_advisor> server # [ 100.563752] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-index_advisor> server # [ 100.572352] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: 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 # [ 100.575770] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: Success. You can now start the database server using: vm-test-run-index_advisor> server # [ 100.577331] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: /nix/store/i45yhjwwac2d5ygyv0s5gbg2cgdjclf9-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-index_advisor> server # [ 102.489568] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[911]: Validating PostgreSQL configuration... vm-test-run-index_advisor> server # [ 102.880670] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[1322]: 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 # [ 103.755951] postgres[1325]: 2026-10-23 15:21:46.243 UTC [1325] LOG: pgaudit extension initialized vm-test-run-index_advisor> server # [ 104.879642] postgres[1325]: 2026-10-23 15:21:47.346 UTC [1325] LOG: pgsodium primary server secret key loaded vm-test-run-index_advisor> server # [ 105.396858] postgres[1325]: 2026-10-23 15:21:47.890 UTC [1325] LOG: vault primary server secret key loaded vm-test-run-index_advisor> server # [ 105.721297] postgres[1325]: 2026-10-23 15:21:48.215 UTC [1325] 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 # [ 105.738011] postgres[1325]: 2026-10-23 15:21:48.231 UTC [1325] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-index_advisor> server # [ 105.740308] postgres[1325]: 2026-10-23 15:21:48.232 UTC [1325] LOG: listening on IPv6 address "::", port 5432 vm-test-run-index_advisor> server # [ 105.758771] postgres[1325]: 2026-10-23 15:21:48.253 UTC [1325] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-index_advisor> server # [ 105.857866] postgres[1359]: 2026-10-23 15:21:48.342 UTC [1359] LOG: database system was shut down at 2026-10-23 15:21:40 UTC vm-test-run-index_advisor> server # [ 106.004044] postgres[1325]: 2026-10-23 15:21:48.492 UTC [1325] LOG: database system is ready to accept connections vm-test-run-index_advisor> server # [ 106.048347] postgres[1363]: 2026-10-23 15:21:48.532 UTC [1363] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 106.095680] postgres[1362]: 2026-10-23 15:21:48.558 UTC [1362] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-index_advisor> server # [ 106.123550] systemd[1]: Started PostgreSQL Database Server. vm-test-run-index_advisor> server # [ 106.169574] systemd[1]: Starting Supabase Database Initialization... vm-test-run-index_advisor> server # [ 106.275504] postgres[1325]: 2026-10-23 15:21:48.769 UTC [1325] LOG: background worker "pg_net 0.20.4 worker" (PID 1363) exited with exit code 1 vm-test-run-index_advisor> server # [ 106.508044] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Waiting for PostgreSQL to be ready... vm-test-run-index_advisor> server # [ 106.621788] postgres[1364]: 2026-10-23 15:21:49.096 UTC [1364] LOG: pg_cron scheduler started vm-test-run-index_advisor> server # [ 107.086765] postgres[1374]: ::1 2026-10-23 15:21:49.555 UTC [1374] root@root FATAL: role "root" does not exist vm-test-run-index_advisor> server # [ 107.114265] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: PostgreSQL is ready vm-test-run-index_advisor> server # [ 107.126324] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Creating postgres role... vm-test-run-index_advisor> server # [ 107.342962] postgres[1382]: 2026-10-23 15:21:49.824 UTC [1382] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 107.393146] postgres[1325]: 2026-10-23 15:21:49.878 UTC [1325] LOG: background worker "pg_net 0.20.4 worker" (PID 1382) exited with exit code 1 vm-test-run-index_advisor> server # [ 107.538743] postgres[1384]: ::1 2026-10-23 15:21:50.028 UTC [1384] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-index_advisor> server # [ 107.587669] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1378]: CREATE ROLE vm-test-run-index_advisor> server # [ 107.924164] postgres[1396]: ::1 2026-10-23 15:21:50.385 UTC [1396] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-index_advisor> server # [ 107.969367] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1391]: ALTER DATABASE vm-test-run-index_advisor> server # [ 108.047170] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-index_advisor> server # [ 108.352321] postgres[1405]: ::1 2026-10-23 15:21:50.841 UTC [1405] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-index_advisor> server # [ 108.385901] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE PUBLICATION vm-test-run-index_advisor> server # [ 108.392554] postgres[1405]: ::1 2026-10-23 15:21:50.882 UTC [1405] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-index_advisor> server # [ 108.407923] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE vm-test-run-index_advisor> server # [ 108.413066] postgres[1405]: ::1 2026-10-23 15:21:50.904 UTC [1405] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-index_advisor> server # [ 108.423526] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 108.425238] postgres[1405]: ::1 2026-10-23 15:21:50.915 UTC [1405] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-index_advisor> server # [ 108.437756] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 108.441021] postgres[1405]: ::1 2026-10-23 15:21:50.933 UTC [1405] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-index_advisor> server # [ 108.454063] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE vm-test-run-index_advisor> server # [ 108.456950] postgres[1405]: ::1 2026-10-23 15:21:50.949 UTC [1405] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-index_advisor> server # [ 108.466213] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT vm-test-run-index_advisor> server # [ 108.471406] postgres[1405]: ::1 2026-10-23 15:21:50.963 UTC [1405] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-index_advisor> server # [ 108.481163] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 108.486265] postgres[1405]: ::1 2026-10-23 15:21:50.977 UTC [1405] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-index_advisor> server # [ 108.500939] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE vm-test-run-index_advisor> server # [ 108.503734] postgres[1405]: ::1 2026-10-23 15:21:50.992 UTC [1405] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-index_advisor> server # [ 108.526796] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 108.535188] postgres[1405]: ::1 2026-10-23 15:21:51.026 UTC [1405] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-index_advisor> server # [ 110.922758] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 110.935834] postgres[1405]: ::1 2026-10-23 15:21:53.413 UTC [1405] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-index_advisor> server # [ 111.991099] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 112.007390] postgres[1405]: ::1 2026-10-23 15:21:54.468 UTC [1405] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-index_advisor> server # [ 112.011736] postgres[1405]: ::1 2026-10-23 15:21:54.487 UTC [1405] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-index_advisor> server # [ 112.023390] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 112.024437] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 112.026068] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 112.034217] postgres[1405]: ::1 2026-10-23 15:21:54.500 UTC [1405] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-index_advisor> server # [ 112.037680] postgres[1405]: ::1 2026-10-23 15:21:54.518 UTC [1405] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-index_advisor> server # [ 112.038750] postgres[1405]: ::1 2026-10-23 15:21:54.530 UTC [1405] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-index_advisor> server # [ 112.043221] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE vm-test-run-index_advisor> server # [ 112.051446] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE vm-test-run-index_advisor> server # [ 112.052232] postgres[1405]: ::1 2026-10-23 15:21:54.545 UTC [1405] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-index_advisor> server # [ 112.065370] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE vm-test-run-index_advisor> server # [ 112.073722] postgres[1405]: ::1 2026-10-23 15:21:54.562 UTC [1405] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-index_advisor> server # [ 112.077247] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE vm-test-run-index_advisor> server # [ 112.087121] postgres[1405]: ::1 2026-10-23 15:21:54.576 UTC [1405] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-index_advisor> server # [ 112.098587] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE vm-test-run-index_advisor> server # [ 112.108063] postgres[1405]: ::1 2026-10-23 15:21:54.595 UTC [1405] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 112.121458] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT vm-test-run-index_advisor> server # [ 112.130049] postgres[1405]: ::1 2026-10-23 15:21:54.622 UTC [1405] 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 # [ 112.154975] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 112.156162] postgres[1405]: ::1 2026-10-23 15:21:54.648 UTC [1405] 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 # [ 112.170083] postgres[1405]: ::1 2026-10-23 15:21:54.659 UTC [1405] 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 # [ 112.171953] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 112.185718] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 112.187237] postgres[1405]: ::1 2026-10-23 15:21:54.673 UTC [1405] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 112.197294] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT vm-test-run-index_advisor> server # [ 112.198556] postgres[1405]: ::1 2026-10-23 15:21:54.689 UTC [1405] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-index_advisor> server # [ 112.210460] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE vm-test-run-index_advisor> server # [ 112.211487] postgres[1405]: ::1 2026-10-23 15:21:54.702 UTC [1405] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 112.212061] postgres[1405]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 112.223668] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 112.230751] postgres[1405]: ::1 2026-10-23 15:21:54.718 UTC [1405] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 112.231721] postgres[1405]: on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 112.243145] postgres[1405]: ::1 2026-10-23 15:21:54.735 UTC [1405] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 112.253543] postgres[1405]: on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 112.254858] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 112.255666] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 112.262406] postgres[1405]: ::1 2026-10-23 15:21:54.748 UTC [1405] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-index_advisor> server # [ 112.275212] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE vm-test-run-index_advisor> server # [ 112.279317] postgres[1405]: ::1 2026-10-23 15:21:54.769 UTC [1405] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-index_advisor> server # [ 112.292981] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE vm-test-run-index_advisor> server # [ 112.371550] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-index_advisor> server # [ 112.677327] postgres[1436]: ::1 2026-10-23 15:21:55.161 UTC [1436] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-index_advisor> server # [ 112.691551] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 112.694153] postgres[1436]: ::1 2026-10-23 15:21:55.180 UTC [1436] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-index_advisor> server # [ 112.694889] postgres[1436]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 112.695876] postgres[1436]: id uuid NOT NULL UNIQUE, vm-test-run-index_advisor> server # [ 112.697612] postgres[1436]: aud varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.699654] postgres[1436]: "role" varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.700622] postgres[1436]: email varchar(255) NULL UNIQUE, vm-test-run-index_advisor> server # [ 112.704918] postgres[1436]: encrypted_password varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.705526] postgres[1436]: confirmed_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.705850] postgres[1436]: invited_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.706690] postgres[1436]: confirmation_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.707152] postgres[1436]: confirmation_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.707465] postgres[1436]: recovery_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.708086] postgres[1436]: recovery_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.708413] postgres[1436]: email_change_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.710737] postgres[1436]: email_change varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.711724] postgres[1436]: email_change_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.714216] postgres[1436]: last_sign_in_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.714845] postgres[1436]: raw_app_meta_data jsonb NULL, vm-test-run-index_advisor> server # [ 112.715163] postgres[1436]: raw_user_meta_data jsonb NULL, vm-test-run-index_advisor> server # [ 112.716009] postgres[1436]: is_super_admin bool NULL, vm-test-run-index_advisor> server # [ 112.717503] postgres[1436]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.718238] postgres[1436]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.719019] postgres[1436]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 112.719328] postgres[1436]: ); vm-test-run-index_advisor> server # [ 112.835767] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE vm-test-run-index_advisor> server # [ 112.840914] postgres[1436]: ::1 2026-10-23 15:21:55.335 UTC [1436] 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 # [ 112.878388] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX vm-test-run-index_advisor> server # [ 112.884038] postgres[1436]: ::1 2026-10-23 15:21:55.376 UTC [1436] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-index_advisor> server # [ 112.918578] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX vm-test-run-index_advisor> server # [ 112.923034] postgres[1436]: ::1 2026-10-23 15:21:55.416 UTC [1436] 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 # [ 112.933182] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT vm-test-run-index_advisor> server # [ 112.934263] postgres[1436]: ::1 2026-10-23 15:21:55.425 UTC [1436] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-index_advisor> server # [ 112.935065] postgres[1436]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 112.945130] postgres[1436]: id bigserial NOT NULL, vm-test-run-index_advisor> server # [ 112.946922] postgres[1436]: "token" varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.947379] postgres[1436]: user_id varchar(255) NULL, vm-test-run-index_advisor> server # [ 112.947844] postgres[1436]: revoked bool NULL, vm-test-run-index_advisor> server # [ 112.948345] postgres[1436]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.949539] postgres[1436]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 112.950477] postgres[1436]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 112.953825] postgres[1436]: ); vm-test-run-index_advisor> server # [ 113.030049] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE vm-test-run-index_advisor> server # [ 113.034990] postgres[1436]: ::1 2026-10-23 15:21:55.528 UTC [1436] 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 # [ 113.058480] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX vm-test-run-index_advisor> server # [ 113.062416] postgres[1436]: ::1 2026-10-23 15:21:55.553 UTC [1436] 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 # [ 113.093881] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX vm-test-run-index_advisor> server # [ 113.095489] postgres[1436]: ::1 2026-10-23 15:21:55.589 UTC [1436] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-index_advisor> server # [ 113.125927] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX vm-test-run-index_advisor> server # [ 113.127765] postgres[1436]: ::1 2026-10-23 15:21:55.621 UTC [1436] 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 # [ 113.135876] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT vm-test-run-index_advisor> server # [ 113.141336] postgres[1436]: ::1 2026-10-23 15:21:55.632 UTC [1436] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-index_advisor> server # [ 113.142159] postgres[1436]: id uuid NOT NULL, vm-test-run-index_advisor> server # [ 113.143006] postgres[1436]: uuid uuid NULL, vm-test-run-index_advisor> server # [ 113.143761] postgres[1436]: raw_base_config text NULL, vm-test-run-index_advisor> server # [ 113.146960] postgres[1436]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 113.155641] postgres[1436]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 113.156528] postgres[1436]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 113.157973] postgres[1436]: ); vm-test-run-index_advisor> server # [ 113.202526] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE vm-test-run-index_advisor> server # [ 113.203447] postgres[1436]: ::1 2026-10-23 15:21:55.696 UTC [1436] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-index_advisor> server # [ 113.211369] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT vm-test-run-index_advisor> server # [ 113.213896] postgres[1436]: ::1 2026-10-23 15:21:55.706 UTC [1436] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-index_advisor> server # [ 113.217748] postgres[1436]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 113.218247] postgres[1436]: id uuid NOT NULL, vm-test-run-index_advisor> server # [ 113.218554] postgres[1436]: payload json NULL, vm-test-run-index_advisor> server # [ 113.218994] postgres[1436]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 113.219281] postgres[1436]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 113.220147] postgres[1436]: ); vm-test-run-index_advisor> server # [ 113.277960] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE vm-test-run-index_advisor> server # [ 113.278980] postgres[1436]: ::1 2026-10-23 15:21:55.772 UTC [1436] 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 # [ 113.346940] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX vm-test-run-index_advisor> server # [ 113.347871] postgres[1436]: ::1 2026-10-23 15:21:55.842 UTC [1436] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-index_advisor> server # [ 113.362062] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT vm-test-run-index_advisor> server # [ 113.363830] postgres[1436]: ::1 2026-10-23 15:21:55.857 UTC [1436] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-index_advisor> server # [ 113.372204] postgres[1436]: "version" varchar(255) NOT NULL, vm-test-run-index_advisor> server # [ 113.373606] postgres[1436]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-index_advisor> server # [ 113.374175] postgres[1436]: ); vm-test-run-index_advisor> server # [ 113.448833] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE vm-test-run-index_advisor> server # [ 113.450617] postgres[1436]: ::1 2026-10-23 15:21:55.944 UTC [1436] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-index_advisor> server # [ 113.461471] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT vm-test-run-index_advisor> server # [ 113.501683] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: INSERT 0 7 vm-test-run-index_advisor> server # [ 113.513716] postgres[1436]: ::1 2026-10-23 15:21:55.999 UTC [1436] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-index_advisor> server # [ 113.525292] postgres[1436]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-index_advisor> server # [ 113.526704] postgres[1436]: $$ language sql stable; vm-test-run-index_advisor> server # [ 113.542920] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 113.554818] postgres[1436]: ::1 2026-10-23 15:21:56.041 UTC [1436] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-index_advisor> server # [ 113.559093] postgres[1436]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-index_advisor> server # [ 113.574187] postgres[1436]: $$ language sql stable; vm-test-run-index_advisor> server # [ 113.596981] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 113.613511] postgres[1436]: ::1 2026-10-23 15:21:56.095 UTC [1436] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-index_advisor> server # [ 113.615611] postgres[1436]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-index_advisor> server # [ 113.623661] postgres[1436]: $$ language sql stable; vm-test-run-index_advisor> server # [ 113.634271] postgres[1436]: ::1 2026-10-23 15:21:56.117 UTC [1436] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 113.645514] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 113.646339] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT vm-test-run-index_advisor> server # [ 113.646785] postgres[1436]: ::1 2026-10-23 15:21:56.127 UTC [1436] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 113.647257] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE ROLE vm-test-run-index_advisor> server # [ 113.647609] postgres[1436]: ::1 2026-10-23 15:21:56.141 UTC [1436] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.663940] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT vm-test-run-index_advisor> server # [ 113.682752] postgres[1436]: ::1 2026-10-23 15:21:56.157 UTC [1436] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.690266] postgres[1436]: ::1 2026-10-23 15:21:56.174 UTC [1436] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.711936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT vm-test-run-index_advisor> server # [ 113.719002] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT vm-test-run-index_advisor> server # [ 113.719735] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER ROLE vm-test-run-index_advisor> server # [ 113.720196] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE vm-test-run-index_advisor> server # [ 113.730168] postgres[1436]: ::1 2026-10-23 15:21:56.189 UTC [1436] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-index_advisor> server # [ 113.731638] postgres[1436]: ::1 2026-10-23 15:21:56.200 UTC [1436] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.748540] postgres[1436]: ::1 2026-10-23 15:21:56.214 UTC [1436] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.749595] postgres[1436]: ::1 2026-10-23 15:21:56.230 UTC [1436] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.751412] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE vm-test-run-index_advisor> server # [ 113.751780] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE vm-test-run-index_advisor> server # [ 113.752126] postgres[1436]: ::1 2026-10-23 15:21:56.246 UTC [1436] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.765765] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE vm-test-run-index_advisor> server # [ 113.767400] postgres[1436]: ::1 2026-10-23 15:21:56.258 UTC [1436] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 113.801105] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE vm-test-run-index_advisor> server # [ 113.883418] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-index_advisor> server # [ 114.418811] postgres[1454]: ::1 2026-10-23 15:21:56.896 UTC [1454] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-index_advisor> server # [ 114.430746] postgres[1454]: ::1 2026-10-23 15:21:56.913 UTC [1454] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 114.454372] postgres[1454]: ::1 2026-10-23 15:21:56.924 UTC [1454] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-index_advisor> server # [ 114.485905] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 114.487336] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: CREATE ROLE vm-test-run-index_advisor> server # [ 114.493325] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: ALTER ROLE vm-test-run-index_advisor> server # [ 114.494427] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: GRANT vm-test-run-index_advisor> server # [ 114.494979] postgres[1454]: ::1 2026-10-23 15:21:56.937 UTC [1454] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-index_advisor> server # [ 114.522843] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: DO vm-test-run-index_advisor> server # [ 114.569201] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-index_advisor> server # [ 115.048028] postgres[1464]: ::1 2026-10-23 15:21:57.524 UTC [1464] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-index_advisor> server # [ 115.081971] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: ALTER ROLE vm-test-run-index_advisor> server # [ 115.086310] postgres[1464]: ::1 2026-10-23 15:21:57.561 UTC [1464] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-index_advisor> server # [ 115.087950] postgres[1464]: ::1 2026-10-23 15:21:57.573 UTC [1464] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-index_advisor> server # [ 115.091497] postgres[1464]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 115.098183] postgres[1464]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 115.099605] postgres[1464]: AS $$ vm-test-run-index_advisor> server # [ 115.099948] postgres[1464]: DECLARE vm-test-run-index_advisor> server # [ 115.102457] postgres[1464]: schema_is_cron bool; vm-test-run-index_advisor> server # [ 115.103184] postgres[1464]: BEGIN vm-test-run-index_advisor> server # [ 115.104319] postgres[1464]: schema_is_cron = ( vm-test-run-index_advisor> server # [ 115.104706] postgres[1464]: SELECT n.nspname = 'cron' vm-test-run-index_advisor> server # [ 115.106382] postgres[1464]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 115.106797] postgres[1464]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-index_advisor> server # [ 115.107287] postgres[1464]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 115.109915] postgres[1464]: ); vm-test-run-index_advisor> server # [ 115.111646] postgres[1464]: vm-test-run-index_advisor> server # [ 115.115015] postgres[1464]: IF schema_is_cron vm-test-run-index_advisor> server # [ 115.118509] postgres[1464]: THEN vm-test-run-index_advisor> server # [ 115.118986] postgres[1464]: grant usage on schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 115.119527] postgres[1464]: vm-test-run-index_advisor> server # [ 115.119847] postgres[1464]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 115.121398] postgres[1464]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 115.121983] postgres[1464]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 115.122547] postgres[1464]: vm-test-run-index_advisor> server # [ 115.123307] postgres[1464]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 115.124074] postgres[1464]: on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 115.125784] postgres[1464]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 115.126591] postgres[1464]: on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 115.127561] postgres[1464]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 115.130388] postgres[1464]: on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 115.131002] postgres[1464]: vm-test-run-index_advisor> server # [ 115.132174] postgres[1464]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 115.133392] postgres[1464]: vm-test-run-index_advisor> server # [ 115.134162] postgres[1464]: END IF; vm-test-run-index_advisor> server # [ 115.135121] postgres[1464]: vm-test-run-index_advisor> server # [ 115.135755] postgres[1464]: END; vm-test-run-index_advisor> server # [ 115.138435] postgres[1464]: $$; vm-test-run-index_advisor> server # [ 115.139112] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: ALTER ROLE vm-test-run-index_advisor> server # [ 115.139683] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 115.140260] postgres[1464]: ::1 2026-10-23 15:21:57.617 UTC [1464] 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 # [ 115.143521] postgres[1464]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-index_advisor> server # [ 115.146930] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 115.154732] postgres[1464]: ::1 2026-10-23 15:21:57.644 UTC [1464] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-index_advisor> server # [ 115.162232] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: COMMENT vm-test-run-index_advisor> server # [ 115.165193] postgres[1464]: ::1 2026-10-23 15:21:57.658 UTC [1464] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-index_advisor> server # [ 115.167648] postgres[1464]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 115.169663] postgres[1464]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 115.171080] postgres[1464]: AS $$ vm-test-run-index_advisor> server # [ 115.172950] postgres[1464]: BEGIN vm-test-run-index_advisor> server # [ 115.174572] postgres[1464]: IF EXISTS ( vm-test-run-index_advisor> server # [ 115.176093] postgres[1464]: SELECT 1 vm-test-run-index_advisor> server # [ 115.177596] postgres[1464]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 115.178054] postgres[1464]: JOIN pg_extension AS ext vm-test-run-index_advisor> server # [ 115.178672] postgres[1464]: ON ev.objid = ext.oid vm-test-run-index_advisor> server # [ 115.179559] postgres[1464]: WHERE ext.extname = 'pg_net' vm-test-run-index_advisor> server # [ 115.179874] postgres[1464]: ) vm-test-run-index_advisor> server # [ 115.181641] postgres[1464]: THEN vm-test-run-index_advisor> server # [ 115.182336] postgres[1464]: IF NOT EXISTS ( vm-test-run-index_advisor> server # [ 115.183350] postgres[1464]: SELECT 1 vm-test-run-index_advisor> server # [ 115.185507] postgres[1464]: FROM pg_roles vm-test-run-index_advisor> server # [ 115.186815] postgres[1464]: WHERE rolname = 'supabase_functions_admin' vm-test-run-index_advisor> server # [ 115.187187] postgres[1464]: ) vm-test-run-index_advisor> server # [ 115.188939] postgres[1464]: THEN vm-test-run-index_advisor> server # [ 115.189700] postgres[1464]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 115.190091] postgres[1464]: END IF; vm-test-run-index_advisor> server # [ 115.190354] postgres[1464]: vm-test-run-index_advisor> server # [ 115.190606] postgres[1464]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 115.191415] postgres[1464]: vm-test-run-index_advisor> server # [ 115.191748] postgres[1464]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-index_advisor> server # [ 115.192075] postgres[1464]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-index_advisor> server # [ 115.194019] postgres[1464]: vm-test-run-index_advisor> server # [ 115.195203] postgres[1464]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-index_advisor> server # [ 115.196988] postgres[1464]: 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 # [ 115.198057] postgres[1464]: vm-test-run-index_advisor> server # [ 115.199156] postgres[1464]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-index_advisor> server # [ 115.199589] postgres[1464]: 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 # [ 115.200643] postgres[1464]: vm-test-run-index_advisor> server # [ 115.201332] postgres[1464]: 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 # [ 115.203560] postgres[1464]: 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 # [ 115.206439] postgres[1464]: END IF; vm-test-run-index_advisor> server # [ 115.206992] postgres[1464]: END; vm-test-run-index_advisor> server # [ 115.207318] postgres[1464]: $$; vm-test-run-index_advisor> server # [ 115.207725] postgres[1464]: ::1 2026-10-23 15:21:57.696 UTC [1464] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-index_advisor> server # [ 115.209195] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 115.220967] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: COMMENT vm-test-run-index_advisor> server # [ 115.244355] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: DO vm-test-run-index_advisor> server # [ 115.250126] postgres[1464]: ::1 2026-10-23 15:21:57.739 UTC [1464] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-index_advisor> server # [ 115.254268] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE ROLE vm-test-run-index_advisor> server # [ 115.255194] postgres[1464]: ::1 2026-10-23 15:21:57.749 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-index_advisor> server # [ 115.263045] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.263988] postgres[1464]: ::1 2026-10-23 15:21:57.758 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 115.275122] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.276377] postgres[1464]: ::1 2026-10-23 15:21:57.770 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 115.286833] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.291665] postgres[1464]: ::1 2026-10-23 15:21:57.781 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 115.302838] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.303756] postgres[1464]: ::1 2026-10-23 15:21:57.798 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 115.310037] postgres[1464]: ::1 2026-10-23 15:21:57.801 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 115.315408] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.316281] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.317413] postgres[1464]: ::1 2026-10-23 15:21:57.811 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 115.322049] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.323090] postgres[1464]: ::1 2026-10-23 15:21:57.817 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 115.343841] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.349734] postgres[1464]: ::1 2026-10-23 15:21:57.839 UTC [1464] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 115.376961] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT vm-test-run-index_advisor> server # [ 115.392882] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: DO vm-test-run-index_advisor> server # [ 115.439143] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running pgbouncer auth schema... vm-test-run-index_advisor> server # [ 115.742493] postgres[1466]: ::1 2026-10-23 15:21:58.216 UTC [1466] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-index_advisor> server # [ 115.767601] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: CREATE ROLE vm-test-run-index_advisor> server # [ 115.775370] postgres[1466]: ::1 2026-10-23 15:21:58.242 UTC [1466] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-index_advisor> server # [ 115.781924] postgres[1466]: ::1 2026-10-23 15:21:58.273 UTC [1466] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-index_advisor> server # [ 115.783162] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: REVOKE vm-test-run-index_advisor> server # [ 115.836571] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 115.842976] postgres[1466]: ::1 2026-10-23 15:21:58.335 UTC [1466] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-index_advisor> server # [ 115.853935] postgres[1466]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-index_advisor> server # [ 115.855265] postgres[1466]: $$ vm-test-run-index_advisor> server # [ 115.856672] postgres[1466]: BEGIN vm-test-run-index_advisor> server # [ 115.860658] postgres[1466]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-index_advisor> server # [ 115.865837] postgres[1466]: vm-test-run-index_advisor> server # [ 115.866597] postgres[1466]: RETURN QUERY vm-test-run-index_advisor> server # [ 115.867520] postgres[1466]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-index_advisor> server # [ 115.867894] postgres[1466]: WHERE usename = p_usename; vm-test-run-index_advisor> server # [ 115.878470] postgres[1466]: END; vm-test-run-index_advisor> server # [ 115.879009] postgres[1466]: $$ LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 115.879301] postgres[1466]: SET search_path = '' vm-test-run-index_advisor> server # [ 115.879555] postgres[1466]: SECURITY DEFINER; vm-test-run-index_advisor> server # [ 115.890608] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 115.891562] postgres[1466]: ::1 2026-10-23 15:21:58.380 UTC [1466] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-index_advisor> server # [ 115.906353] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: REVOKE vm-test-run-index_advisor> server # [ 115.926297] postgres[1466]: ::1 2026-10-23 15:21:58.398 UTC [1466] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-index_advisor> server # [ 115.934383] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: GRANT vm-test-run-index_advisor> server # [ 115.976904] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running stat extension... vm-test-run-index_advisor> server # [ 116.380062] postgres[1472]: ::1 2026-10-23 15:21:58.860 UTC [1472] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-index_advisor> server # [ 116.410191] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1471]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-index_advisor> server # [ 116.440087] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1471]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 116.447221] postgres[1472]: ::1 2026-10-23 15:21:58.939 UTC [1472] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-index_advisor> server # [ 118.127984] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1471]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 118.185812] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-index_advisor> server # [ 118.574958] postgres[1498]: ::1 2026-10-23 15:22:01.058 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-index_advisor> server # [ 118.584275] postgres[1498]: ::1 2026-10-23 15:22:01.077 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 118.605423] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.606315] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.606936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.607485] postgres[1498]: ::1 2026-10-23 15:22:01.092 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 118.608209] postgres[1498]: ::1 2026-10-23 15:22:01.100 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 118.633274] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.634204] postgres[1498]: ::1 2026-10-23 15:22:01.126 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 118.639026] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.639960] postgres[1498]: ::1 2026-10-23 15:22:01.133 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 118.651366] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.654225] postgres[1498]: ::1 2026-10-23 15:22:01.145 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 118.655239] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.665478] postgres[1498]: ::1 2026-10-23 15:22:01.151 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 118.681139] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.682172] postgres[1498]: ::1 2026-10-23 15:22:01.173 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 118.715392] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT vm-test-run-index_advisor> server # [ 118.742528] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: DO vm-test-run-index_advisor> server # [ 118.745642] postgres[1498]: ::1 2026-10-23 15:22:01.238 UTC [1498] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-index_advisor> server # [ 118.756966] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: ALTER ROLE vm-test-run-index_advisor> server # [ 118.793932] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-index_advisor> server # [ 119.040743] postgres[1500]: ::1 2026-10-23 15:22:01.529 UTC [1500] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.057658] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.061396] postgres[1500]: ::1 2026-10-23 15:22:01.549 UTC [1500] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.069741] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.070731] postgres[1500]: ::1 2026-10-23 15:22:01.564 UTC [1500] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.078931] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.079810] postgres[1500]: ::1 2026-10-23 15:22:01.574 UTC [1500] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.089706] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE vm-test-run-index_advisor> server # [ 119.090902] postgres[1500]: ::1 2026-10-23 15:22:01.583 UTC [1500] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.101597] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE vm-test-run-index_advisor> server # [ 119.102560] postgres[1500]: ::1 2026-10-23 15:22:01.593 UTC [1500] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.107669] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE vm-test-run-index_advisor> server # [ 119.111755] postgres[1500]: ::1 2026-10-23 15:22:01.603 UTC [1500] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.120316] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE vm-test-run-index_advisor> server # [ 119.123580] postgres[1500]: ::1 2026-10-23 15:22:01.612 UTC [1500] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 119.127389] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE vm-test-run-index_advisor> server # [ 119.133383] postgres[1500]: ::1 2026-10-23 15:22:01.625 UTC [1500] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 119.144976] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.146273] postgres[1500]: ::1 2026-10-23 15:22:01.637 UTC [1500] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 119.156529] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.162150] postgres[1500]: ::1 2026-10-23 15:22:01.655 UTC [1500] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 119.173619] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.175977] postgres[1500]: ::1 2026-10-23 15:22:01.669 UTC [1500] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 119.187239] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT vm-test-run-index_advisor> server # [ 119.195220] postgres[1500]: ::1 2026-10-23 15:22:01.684 UTC [1500] 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 # [ 119.201493] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 119.207107] postgres[1500]: ::1 2026-10-23 15:22:01.698 UTC [1500] 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 # [ 119.215892] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 119.217747] postgres[1500]: ::1 2026-10-23 15:22:01.707 UTC [1500] 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 # [ 119.222983] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 119.263799] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-index_advisor> server # [ 119.547644] postgres[1502]: ::1 2026-10-23 15:22:02.025 UTC [1502] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-index_advisor> server # [ 119.623948] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1501]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 119.642300] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-index_advisor> server # [ 120.074793] postgres[1511]: ::1 2026-10-23 15:22:02.551 UTC [1511] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-index_advisor> server # [ 120.114545] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT vm-test-run-index_advisor> server # [ 120.127280] postgres[1511]: ::1 2026-10-23 15:22:02.594 UTC [1511] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 120.133461] postgres[1511]: ::1 2026-10-23 15:22:02.612 UTC [1511] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 120.145176] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT vm-test-run-index_advisor> server # [ 120.147679] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT vm-test-run-index_advisor> server # [ 120.158240] postgres[1511]: ::1 2026-10-23 15:22:02.624 UTC [1511] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 120.174990] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT vm-test-run-index_advisor> server # [ 120.201270] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-index_advisor> server # [ 120.719568] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1515]: DO vm-test-run-index_advisor> server # [ 120.732903] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1515]: DO vm-test-run-index_advisor> server # [ 120.753263] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1515]: DO vm-test-run-index_advisor> server # [ 120.817225] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-index_advisor> server # [ 121.231264] postgres[1531]: ::1 2026-10-23 15:22:03.721 UTC [1531] 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 # [ 121.275009] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 121.276235] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 121.281725] postgres[1531]: ::1 2026-10-23 15:22:03.756 UTC [1531] 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 # [ 121.283044] postgres[1531]: ::1 2026-10-23 15:22:03.774 UTC [1531] 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 # [ 121.293939] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 121.327266] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-index_advisor> server # [ 121.651496] postgres[1534]: ::1 2026-10-23 15:22:04.140 UTC [1534] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-index_advisor> server # [ 121.700248] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1533]: ALTER ROLE vm-test-run-index_advisor> server # [ 121.737168] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-index_advisor> server # [ 122.058111] postgres[1540]: ::1 2026-10-23 15:22:04.544 UTC [1540] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-index_advisor> server # [ 122.073366] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-index_advisor> server # [ 122.075337] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 122.079129] postgres[1540]: ::1 2026-10-23 15:22:04.565 UTC [1540] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-index_advisor> server # [ 122.089644] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 122.090807] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP FUNCTION vm-test-run-index_advisor> server # [ 122.094560] postgres[1540]: ::1 2026-10-23 15:22:04.587 UTC [1540] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 122.100859] postgres[1540]: DECLARE vm-test-run-index_advisor> server # [ 122.102167] postgres[1540]: cmd record; vm-test-run-index_advisor> server # [ 122.103917] postgres[1540]: BEGIN vm-test-run-index_advisor> server # [ 122.109181] postgres[1540]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-index_advisor> server # [ 122.110226] postgres[1540]: LOOP vm-test-run-index_advisor> server # [ 122.111271] postgres[1540]: IF cmd.command_tag IN ( vm-test-run-index_advisor> server # [ 122.111775] postgres[1540]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-index_advisor> server # [ 122.112324] postgres[1540]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-index_advisor> server # [ 122.116635] postgres[1540]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-index_advisor> server # [ 122.117918] postgres[1540]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-index_advisor> server # [ 122.119326] postgres[1540]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-index_advisor> server # [ 122.125134] postgres[1540]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-index_advisor> server # [ 122.125932] postgres[1540]: , 'CREATE TRIGGER' vm-test-run-index_advisor> server # [ 122.126519] postgres[1540]: , 'CREATE TYPE' vm-test-run-index_advisor> server # [ 122.127422] postgres[1540]: , 'CREATE RULE' vm-test-run-index_advisor> server # [ 122.127785] postgres[1540]: , 'COMMENT' vm-test-run-index_advisor> server # [ 122.131813] postgres[1540]: ) vm-test-run-index_advisor> server # [ 122.141323] postgres[1540]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-index_advisor> server # [ 122.142658] postgres[1540]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-index_advisor> server # [ 122.143589] postgres[1540]: THEN vm-test-run-index_advisor> server # [ 122.150509] postgres[1540]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 122.162261] postgres[1540]: END IF; vm-test-run-index_advisor> server # [ 122.163176] postgres[1540]: END LOOP; vm-test-run-index_advisor> server # [ 122.163967] postgres[1540]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 122.170511] postgres[1540]: ::1 2026-10-23 15:22:04.634 UTC [1540] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 122.172282] postgres[1540]: DECLARE vm-test-run-index_advisor> server # [ 122.176420] postgres[1540]: obj record; vm-test-run-index_advisor> server # [ 122.177073] postgres[1540]: BEGIN vm-test-run-index_advisor> server # [ 122.186514] postgres[1540]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-index_advisor> server # [ 122.188119] postgres[1540]: LOOP vm-test-run-index_advisor> server # [ 122.194094] postgres[1540]: IF obj.object_type IN ( vm-test-run-index_advisor> server # [ 122.194462] postgres[1540]: 'schema' vm-test-run-index_advisor> server # [ 122.195057] postgres[1540]: , 'table' vm-test-run-index_advisor> server # [ 122.195552] postgres[1540]: , 'foreign table' vm-test-run-index_advisor> server # [ 122.195944] postgres[1540]: , 'view' vm-test-run-index_advisor> server # [ 122.196197] postgres[1540]: , 'materialized view' vm-test-run-index_advisor> server # [ 122.202288] postgres[1540]: , 'function' vm-test-run-index_advisor> server # [ 122.205867] postgres[1540]: , 'trigger' vm-test-run-index_advisor> server # [ 122.206574] postgres[1540]: , 'type' vm-test-run-index_advisor> server # [ 122.207110] postgres[1540]: , 'rule' vm-test-run-index_advisor> server # [ 122.207582] postgres[1540]: ) vm-test-run-index_advisor> server # [ 122.207839] postgres[1540]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-index_advisor> server # [ 122.208995] postgres[1540]: THEN vm-test-run-index_advisor> server # [ 122.210018] postgres[1540]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 122.210946] postgres[1540]: END IF; vm-test-run-index_advisor> server # [ 122.213622] postgres[1540]: END LOOP; vm-test-run-index_advisor> server # [ 122.214837] postgres[1540]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 122.215304] postgres[1540]: ::1 2026-10-23 15:22:04.652 UTC [1540] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-index_advisor> server # [ 122.217396] postgres[1540]: ::1 2026-10-23 15:22:04.661 UTC [1540] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-index_advisor> server # [ 122.223199] postgres[1540]: ON ddl_command_end vm-test-run-index_advisor> server # [ 122.224199] postgres[1540]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-index_advisor> server # [ 122.230475] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 122.231770] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 122.239173] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 122.246348] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 122.247051] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 122.247841] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 122.257837] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 122.268936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 122.271155] postgres[1540]: ::1 2026-10-23 15:22:04.677 UTC [1540] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-index_advisor> server # [ 122.285747] postgres[1540]: ::1 2026-10-23 15:22:04.685 UTC [1540] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-index_advisor> server # [ 122.287434] postgres[1540]: ON sql_drop vm-test-run-index_advisor> server # [ 122.298319] postgres[1540]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-index_advisor> server # [ 122.309470] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-index_advisor> server # [ 123.257603] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1541]: DO vm-test-run-index_advisor> server # [ 123.318745] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-index_advisor> server # [ 123.786447] postgres[1558]: ::1 2026-10-23 15:22:06.256 UTC [1558] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-index_advisor> server # [ 123.851912] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 123.861242] postgres[1558]: ::1 2026-10-23 15:22:06.336 UTC [1558] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-index_advisor> server # [ 123.862354] postgres[1558]: ::1 2026-10-23 15:22:06.346 UTC [1558] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 123.863541] postgres[1558]: "operationName" text default null, vm-test-run-index_advisor> server # [ 123.869105] postgres[1558]: query text default null, vm-test-run-index_advisor> server # [ 123.870303] postgres[1558]: variables jsonb default null, vm-test-run-index_advisor> server # [ 123.871737] postgres[1558]: extensions jsonb default null vm-test-run-index_advisor> server # [ 123.872391] postgres[1558]: ) vm-test-run-index_advisor> server # [ 123.885544] postgres[1558]: returns jsonb vm-test-run-index_advisor> server # [ 123.886573] postgres[1558]: language plpgsql vm-test-run-index_advisor> server # [ 123.886933] postgres[1558]: as $$ vm-test-run-index_advisor> server # [ 123.887745] postgres[1558]: DECLARE vm-test-run-index_advisor> server # [ 123.888090] postgres[1558]: server_version float; vm-test-run-index_advisor> server # [ 123.888610] postgres[1558]: BEGIN vm-test-run-index_advisor> server # [ 123.898168] postgres[1558]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 123.899091] postgres[1558]: vm-test-run-index_advisor> server # [ 123.907670] postgres[1558]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 123.915157] postgres[1558]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 123.915703] postgres[1558]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 123.916206] postgres[1558]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-index_advisor> server # [ 123.919283] postgres[1558]: ); vm-test-run-index_advisor> server # [ 123.927099] postgres[1558]: ELSE vm-test-run-index_advisor> server # [ 123.932307] postgres[1558]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 123.933034] postgres[1558]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 123.941162] postgres[1558]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-index_advisor> server # [ 123.942331] postgres[1558]: ); vm-test-run-index_advisor> server # [ 123.943218] postgres[1558]: END IF; vm-test-run-index_advisor> server # [ 123.958207] postgres[1558]: END; vm-test-run-index_advisor> server # [ 123.971535] postgres[1558]: $$; vm-test-run-index_advisor> server # [ 123.978748] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 123.987805] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: DROP FUNCTION vm-test-run-index_advisor> server # [ 123.999103] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 124.013569] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: GRANT vm-test-run-index_advisor> server # [ 124.014526] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.014916] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.015202] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.015474] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.016501] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.026226] postgres[1558]: ::1 2026-10-23 15:22:06.373 UTC [1558] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.027967] postgres[1558]: ::1 2026-10-23 15:22:06.396 UTC [1558] 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 # [ 124.046018] postgres[1558]: ::1 2026-10-23 15:22:06.412 UTC [1558] 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 # [ 124.047328] postgres[1558]: ::1 2026-10-23 15:22:06.425 UTC [1558] 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 # [ 124.060930] postgres[1558]: ::1 2026-10-23 15:22:06.438 UTC [1558] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 124.062405] postgres[1558]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.062989] postgres[1558]: ::1 2026-10-23 15:22:06.458 UTC [1558] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 124.063412] postgres[1558]: on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.064141] postgres[1558]: ::1 2026-10-23 15:22:06.476 UTC [1558] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 124.064550] postgres[1558]: on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.074098] postgres[1558]: ::1 2026-10-23 15:22:06.489 UTC [1558] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 124.074762] postgres[1558]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 124.075213] postgres[1558]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 124.075507] postgres[1558]: AS $func$ vm-test-run-index_advisor> server # [ 124.075775] postgres[1558]: DECLARE vm-test-run-index_advisor> server # [ 124.076035] postgres[1558]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 124.088658] postgres[1558]: BEGIN vm-test-run-index_advisor> server # [ 124.093898] postgres[1558]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 124.101349] postgres[1558]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 124.104284] postgres[1558]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 124.112948] postgres[1558]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 124.119234] postgres[1558]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 124.127092] postgres[1558]: ); vm-test-run-index_advisor> server # [ 124.127624] postgres[1558]: vm-test-run-index_advisor> server # [ 124.127898] postgres[1558]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 124.136831] postgres[1558]: THEN vm-test-run-index_advisor> server # [ 124.139141] postgres[1558]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.149210] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.151176] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 124.171073] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 124.181980] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.182730] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.183387] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: COMMENT vm-test-run-index_advisor> server # [ 124.183982] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 124.199379] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-index_advisor> server # [ 124.212043] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.220853] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.223695] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: COMMENT vm-test-run-index_advisor> server # [ 124.239306] postgres[1558]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.249403] postgres[1558]: vm-test-run-index_advisor> server # [ 124.250035] postgres[1558]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.250908] postgres[1558]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.252006] postgres[1558]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.252480] postgres[1558]: vm-test-run-index_advisor> server # [ 124.263092] postgres[1558]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 124.287373] postgres[1558]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 124.298137] postgres[1558]: "operationName" text default null, vm-test-run-index_advisor> server # [ 124.299915] postgres[1558]: query text default null, vm-test-run-index_advisor> server # [ 124.305941] postgres[1558]: variables jsonb default null, vm-test-run-index_advisor> server # [ 124.308303] postgres[1558]: extensions jsonb default null vm-test-run-index_advisor> server # [ 124.313305] postgres[1558]: ) vm-test-run-index_advisor> server # [ 124.314418] postgres[1558]: returns jsonb vm-test-run-index_advisor> server # [ 124.316605] postgres[1558]: language sql vm-test-run-index_advisor> server # [ 124.321472] postgres[1558]: as $$ vm-test-run-index_advisor> server # [ 124.321852] postgres[1558]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-index_advisor> server # [ 124.322423] postgres[1558]: $$; vm-test-run-index_advisor> server # [ 124.322815] postgres[1558]: vm-test-run-index_advisor> server # [ 124.323061] postgres[1558]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.324210] postgres[1558]: END IF; vm-test-run-index_advisor> server # [ 124.327461] postgres[1558]: vm-test-run-index_advisor> server # [ 124.329190] postgres[1558]: END; vm-test-run-index_advisor> server # [ 124.330259] postgres[1558]: $func$; vm-test-run-index_advisor> server # [ 124.332906] postgres[1558]: ::1 2026-10-23 15:22:06.509 UTC [1558] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-index_advisor> server # [ 124.334181] postgres[1558]: ::1 2026-10-23 15:22:06.518 UTC [1558] 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 # [ 124.335136] postgres[1558]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-index_advisor> server # [ 124.335686] postgres[1558]: ::1 2026-10-23 15:22:06.529 UTC [1558] 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 # [ 124.336100] postgres[1558]: ::1 2026-10-23 15:22:06.552 UTC [1558] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-index_advisor> server # [ 124.336432] postgres[1558]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 124.337303] postgres[1558]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 124.337866] postgres[1558]: AS $func$ vm-test-run-index_advisor> server # [ 124.338822] postgres[1558]: DECLARE vm-test-run-index_advisor> server # [ 124.339088] postgres[1558]: graphql_is_dropped bool; vm-test-run-index_advisor> server # [ 124.342412] postgres[1558]: BEGIN vm-test-run-index_advisor> server # [ 124.343316] postgres[1558]: graphql_is_dropped = ( vm-test-run-index_advisor> server # [ 124.349288] postgres[1558]: SELECT ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 124.349873] postgres[1558]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-index_advisor> server # [ 124.350155] postgres[1558]: WHERE ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 124.350600] postgres[1558]: ); vm-test-run-index_advisor> server # [ 124.350834] postgres[1558]: vm-test-run-index_advisor> server # [ 124.360936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-index_advisor> server # [ 124.362308] postgres[1558]: IF graphql_is_dropped vm-test-run-index_advisor> server # [ 124.363638] postgres[1558]: THEN vm-test-run-index_advisor> server # [ 124.363956] postgres[1558]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 124.364285] postgres[1558]: "operationName" text default null, vm-test-run-index_advisor> server # [ 124.364565] postgres[1558]: query text default null, vm-test-run-index_advisor> server # [ 124.369997] postgres[1558]: variables jsonb default null, vm-test-run-index_advisor> server # [ 124.370499] postgres[1558]: extensions jsonb default null vm-test-run-index_advisor> server # [ 124.371526] postgres[1558]: ) vm-test-run-index_advisor> server # [ 124.372072] postgres[1558]: returns jsonb vm-test-run-index_advisor> server # [ 124.374472] postgres[1558]: language plpgsql vm-test-run-index_advisor> server # [ 124.382277] postgres[1558]: as $$ vm-test-run-index_advisor> server # [ 124.387693] postgres[1558]: DECLARE vm-test-run-index_advisor> server # [ 124.389590] postgres[1558]: server_version float; vm-test-run-index_advisor> server # [ 124.391131] postgres[1558]: BEGIN vm-test-run-index_advisor> server # [ 124.392308] postgres[1558]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 124.398051] postgres[1558]: vm-test-run-index_advisor> server # [ 124.398721] postgres[1558]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 124.399611] postgres[1558]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 124.404972] postgres[1558]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 124.405855] postgres[1558]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-index_advisor> server # [ 124.407018] postgres[1558]: ); vm-test-run-index_advisor> server # [ 124.407395] postgres[1558]: ELSE vm-test-run-index_advisor> server # [ 124.413401] postgres[1558]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 124.414015] postgres[1558]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 124.414558] postgres[1558]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-index_advisor> server # [ 124.415103] postgres[1558]: ); vm-test-run-index_advisor> server # [ 124.415491] postgres[1558]: END IF; vm-test-run-index_advisor> server # [ 124.421247] postgres[1558]: END; vm-test-run-index_advisor> server # [ 124.422688] postgres[1558]: $$; vm-test-run-index_advisor> server # [ 124.423261] postgres[1558]: END IF; vm-test-run-index_advisor> server # [ 124.423637] postgres[1558]: vm-test-run-index_advisor> server # [ 124.424423] postgres[1558]: END; vm-test-run-index_advisor> server # [ 124.430124] postgres[1558]: $func$; vm-test-run-index_advisor> server # [ 124.431447] postgres[1558]: ::1 2026-10-23 15:22:06.601 UTC [1558] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-index_advisor> server # [ 124.432073] postgres[1558]: ::1 2026-10-23 15:22:06.606 UTC [1558] 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 # [ 124.439523] postgres[1558]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-index_advisor> server # [ 124.441846] postgres[1558]: ::1 2026-10-23 15:22:06.619 UTC [1558] 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 # [ 124.709505] postgres[1568]: ::1 2026-10-23 15:22:07.172 UTC [1568] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-index_advisor> server # [ 124.711256] postgres[1568]: ::1 2026-10-23 15:22:07.187 UTC [1568] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-index_advisor> server # [ 124.723580] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 124.729263] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.729769] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 124.731700] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP FUNCTION vm-test-run-index_advisor> server # [ 124.732460] postgres[1568]: ::1 2026-10-23 15:22:07.222 UTC [1568] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 124.737945] postgres[1568]: DECLARE vm-test-run-index_advisor> server # [ 124.738644] postgres[1568]: cmd record; vm-test-run-index_advisor> server # [ 124.739092] postgres[1568]: BEGIN vm-test-run-index_advisor> server # [ 124.739369] postgres[1568]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-index_advisor> server # [ 124.740274] postgres[1568]: LOOP vm-test-run-index_advisor> server # [ 124.746110] postgres[1568]: IF cmd.command_tag IN ( vm-test-run-index_advisor> server # [ 124.746954] postgres[1568]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-index_advisor> server # [ 124.747322] postgres[1568]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-index_advisor> server # [ 124.748101] postgres[1568]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-index_advisor> server # [ 124.748688] postgres[1568]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-index_advisor> server # [ 124.753527] postgres[1568]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-index_advisor> server # [ 124.755197] postgres[1568]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-index_advisor> server # [ 124.755599] postgres[1568]: , 'CREATE TRIGGER' vm-test-run-index_advisor> server # [ 124.760957] postgres[1568]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-index_advisor> server # [ 124.762150] postgres[1568]: , 'CREATE RULE' vm-test-run-index_advisor> server # [ 124.763935] postgres[1568]: , 'COMMENT' vm-test-run-index_advisor> server # [ 124.769717] postgres[1568]: ) vm-test-run-index_advisor> server # [ 124.770178] postgres[1568]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-index_advisor> server # [ 124.770515] postgres[1568]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-index_advisor> server # [ 124.770766] postgres[1568]: THEN vm-test-run-index_advisor> server # [ 124.771653] postgres[1568]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 124.773045] postgres[1568]: END IF; vm-test-run-index_advisor> server # [ 124.773410] postgres[1568]: END LOOP; vm-test-run-index_advisor> server # [ 124.773675] postgres[1568]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 124.811582] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 124.821226] postgres[1568]: ::1 2026-10-23 15:22:07.311 UTC [1568] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 124.822197] postgres[1568]: DECLARE vm-test-run-index_advisor> server # [ 124.822917] postgres[1568]: obj record; vm-test-run-index_advisor> server # [ 124.823394] postgres[1568]: BEGIN vm-test-run-index_advisor> server # [ 124.825207] postgres[1568]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-index_advisor> server # [ 124.825950] postgres[1568]: LOOP vm-test-run-index_advisor> server # [ 124.826366] postgres[1568]: IF obj.object_type IN ( vm-test-run-index_advisor> server # [ 124.827725] postgres[1568]: 'schema' vm-test-run-index_advisor> server # [ 124.833076] postgres[1568]: , 'table' vm-test-run-index_advisor> server # [ 124.833660] postgres[1568]: , 'foreign table' vm-test-run-index_advisor> server # [ 124.833966] postgres[1568]: , 'view' vm-test-run-index_advisor> server # [ 124.834208] postgres[1568]: , 'materialized view' vm-test-run-index_advisor> server # [ 124.834450] postgres[1568]: , 'function' vm-test-run-index_advisor> server # [ 124.835089] postgres[1568]: , 'trigger' vm-test-run-index_advisor> server # [ 124.835365] postgres[1568]: , 'type' vm-test-run-index_advisor> server # [ 124.835614] postgres[1568]: , 'rule' vm-test-run-index_advisor> server # [ 124.835832] postgres[1568]: ) vm-test-run-index_advisor> server # [ 124.836054] postgres[1568]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-index_advisor> server # [ 124.842106] postgres[1568]: THEN vm-test-run-index_advisor> server # [ 124.847170] postgres[1568]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 124.848274] postgres[1568]: END IF; vm-test-run-index_advisor> server # [ 124.853643] postgres[1568]: END LOOP; vm-test-run-index_advisor> server # [ 124.854533] postgres[1568]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 124.855297] postgres[1568]: ::1 2026-10-23 15:22:07.347 UTC [1568] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-index_advisor> server # [ 124.861777] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 124.869103] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.871440] postgres[1568]: ::1 2026-10-23 15:22:07.365 UTC [1568] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-index_advisor> server # [ 124.872138] postgres[1568]: ON ddl_command_end vm-test-run-index_advisor> server # [ 124.876123] postgres[1568]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-index_advisor> server # [ 124.879130] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.879826] postgres[1568]: ::1 2026-10-23 15:22:07.374 UTC [1568] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-index_advisor> server # [ 124.893173] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.899024] postgres[1568]: ::1 2026-10-23 15:22:07.388 UTC [1568] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-index_advisor> server # [ 124.901076] postgres[1568]: ON sql_drop vm-test-run-index_advisor> server # [ 124.901949] postgres[1568]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-index_advisor> server # [ 124.915640] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 124.958874] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-index_advisor> server # [ 125.227326] postgres[1572]: ::1 2026-10-23 15:22:07.717 UTC [1572] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-index_advisor> server # [ 125.250856] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1571]: ALTER ROLE vm-test-run-index_advisor> server # [ 125.284995] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-index_advisor> server # [ 125.527368] postgres[1574]: ::1 2026-10-23 15:22:08.016 UTC [1574] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 125.533532] postgres[1574]: returns event_trigger vm-test-run-index_advisor> server # [ 125.534097] postgres[1574]: language plpgsql vm-test-run-index_advisor> server # [ 125.534412] postgres[1574]: AS $func$ vm-test-run-index_advisor> server # [ 125.534641] postgres[1574]: DECLARE vm-test-run-index_advisor> server # [ 125.534857] postgres[1574]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 125.535087] postgres[1574]: BEGIN vm-test-run-index_advisor> server # [ 125.535517] postgres[1574]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 125.535830] postgres[1574]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 125.538463] postgres[1574]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 125.541657] postgres[1574]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 125.542133] postgres[1574]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 125.543179] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.543627] postgres[1574]: vm-test-run-index_advisor> server # [ 125.544280] postgres[1574]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 125.546197] postgres[1574]: THEN vm-test-run-index_advisor> server # [ 125.546972] postgres[1574]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.547523] postgres[1574]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.548140] postgres[1574]: vm-test-run-index_advisor> server # [ 125.549713] postgres[1574]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.550177] postgres[1574]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.550531] postgres[1574]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.551549] postgres[1574]: vm-test-run-index_advisor> server # [ 125.552221] postgres[1574]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 125.557514] postgres[1574]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 125.558328] postgres[1574]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 125.559100] postgres[1574]: "operationName" text default null, vm-test-run-index_advisor> server # [ 125.560018] postgres[1574]: query text default null, vm-test-run-index_advisor> server # [ 125.561720] postgres[1574]: variables jsonb default null, vm-test-run-index_advisor> server # [ 125.562485] postgres[1574]: extensions jsonb default null vm-test-run-index_advisor> server # [ 125.562786] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.563917] postgres[1574]: returns jsonb vm-test-run-index_advisor> server # [ 125.569190] postgres[1574]: language sql vm-test-run-index_advisor> server # [ 125.570454] postgres[1574]: as $$ vm-test-run-index_advisor> server # [ 125.573400] postgres[1574]: -- This changed vm-test-run-index_advisor> server # [ 125.573933] postgres[1574]: select graphql.resolve( vm-test-run-index_advisor> server # [ 125.574856] postgres[1574]: query := query, vm-test-run-index_advisor> server # [ 125.575673] postgres[1574]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 125.576216] postgres[1574]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 125.577632] postgres[1574]: extensions := extensions vm-test-run-index_advisor> server # [ 125.578033] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.578548] postgres[1574]: $$; vm-test-run-index_advisor> server # [ 125.578835] postgres[1574]: vm-test-run-index_advisor> server # [ 125.579240] postgres[1574]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.580957] postgres[1574]: END IF; vm-test-run-index_advisor> server # [ 125.581446] postgres[1574]: vm-test-run-index_advisor> server # [ 125.581728] postgres[1574]: END; vm-test-run-index_advisor> server # [ 125.582372] postgres[1574]: $func$; vm-test-run-index_advisor> server # [ 125.604261] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 125.610186] postgres[1574]: ::1 2026-10-23 15:22:08.101 UTC [1574] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-index_advisor> server # [ 125.612042] postgres[1574]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 125.615374] postgres[1574]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 125.615923] postgres[1574]: AS $func$ vm-test-run-index_advisor> server # [ 125.617537] postgres[1574]: DECLARE vm-test-run-index_advisor> server # [ 125.617962] postgres[1574]: graphql_is_dropped bool; vm-test-run-index_advisor> server # [ 125.618741] postgres[1574]: BEGIN vm-test-run-index_advisor> server # [ 125.619032] postgres[1574]: graphql_is_dropped = ( vm-test-run-index_advisor> server # [ 125.619322] postgres[1574]: SELECT ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 125.620073] postgres[1574]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-index_advisor> server # [ 125.622742] postgres[1574]: WHERE ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 125.623375] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.623682] postgres[1574]: vm-test-run-index_advisor> server # [ 125.624069] postgres[1574]: IF graphql_is_dropped vm-test-run-index_advisor> server # [ 125.624372] postgres[1574]: THEN vm-test-run-index_advisor> server # [ 125.637265] postgres[1574]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 125.637932] postgres[1574]: "operationName" text default null, vm-test-run-index_advisor> server # [ 125.638680] postgres[1574]: query text default null, vm-test-run-index_advisor> server # [ 125.639443] postgres[1574]: variables jsonb default null, vm-test-run-index_advisor> server # [ 125.646231] postgres[1574]: extensions jsonb default null vm-test-run-index_advisor> server # [ 125.647127] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.647445] postgres[1574]: returns jsonb vm-test-run-index_advisor> server # [ 125.648313] postgres[1574]: language plpgsql vm-test-run-index_advisor> server # [ 125.657767] postgres[1574]: as $$ vm-test-run-index_advisor> server # [ 125.659141] postgres[1574]: DECLARE vm-test-run-index_advisor> server # [ 125.659737] postgres[1574]: server_version float; vm-test-run-index_advisor> server # [ 125.660028] postgres[1574]: BEGIN vm-test-run-index_advisor> server # [ 125.660257] postgres[1574]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 125.669465] postgres[1574]: vm-test-run-index_advisor> server # [ 125.669941] postgres[1574]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 125.670247] postgres[1574]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 125.670654] postgres[1574]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 125.671482] postgres[1574]: jsonb_build_object( vm-test-run-index_advisor> server # [ 125.683768] postgres[1574]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-index_advisor> server # [ 125.693589] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.694205] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.694525] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.695685] postgres[1574]: ELSE vm-test-run-index_advisor> server # [ 125.696073] postgres[1574]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 125.698867] postgres[1574]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 125.700249] postgres[1574]: jsonb_build_object( vm-test-run-index_advisor> server # [ 125.705135] postgres[1574]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-index_advisor> server # [ 125.706371] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.706891] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.707350] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.711151] postgres[1574]: END IF; vm-test-run-index_advisor> server # [ 125.721479] postgres[1574]: END; vm-test-run-index_advisor> server # [ 125.722478] postgres[1574]: $$; vm-test-run-index_advisor> server # [ 125.723088] postgres[1574]: END IF; vm-test-run-index_advisor> server # [ 125.723398] postgres[1574]: vm-test-run-index_advisor> server # [ 125.733898] postgres[1574]: END; vm-test-run-index_advisor> server # [ 125.745560] postgres[1574]: $func$; vm-test-run-index_advisor> server # [ 125.746508] postgres[1574]: ::1 2026-10-23 15:22:08.144 UTC [1574] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 125.748704] postgres[1574]: "operationName" text default null, vm-test-run-index_advisor> server # [ 125.754141] postgres[1574]: query text default null, vm-test-run-index_advisor> server # [ 125.754590] postgres[1574]: variables jsonb default null, vm-test-run-index_advisor> server # [ 125.756078] postgres[1574]: extensions jsonb default null vm-test-run-index_advisor> server # [ 125.770263] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.770845] postgres[1574]: returns jsonb vm-test-run-index_advisor> server # [ 125.771595] postgres[1574]: language plpgsql vm-test-run-index_advisor> server # [ 125.772160] postgres[1574]: as $$ vm-test-run-index_advisor> server # [ 125.773155] postgres[1574]: DECLARE vm-test-run-index_advisor> server # [ 125.790595] postgres[1574]: server_version float; vm-test-run-index_advisor> server # [ 125.793531] postgres[1574]: BEGIN vm-test-run-index_advisor> server # [ 125.795197] postgres[1574]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 125.795916] postgres[1574]: vm-test-run-index_advisor> server # [ 125.796441] postgres[1574]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 125.807296] postgres[1574]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 125.816652] postgres[1574]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 125.818612] postgres[1574]: jsonb_build_object( vm-test-run-index_advisor> server # [ 125.819161] postgres[1574]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-index_advisor> server # [ 125.823402] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.835997] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.842614] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.843038] postgres[1574]: ELSE vm-test-run-index_advisor> server # [ 125.843680] postgres[1574]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 125.844555] postgres[1574]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 125.847988] postgres[1574]: jsonb_build_object( vm-test-run-index_advisor> server # [ 125.850084] postgres[1574]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-index_advisor> server # [ 125.851382] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.852977] postgres[1574]: ) vm-test-run-index_advisor> server # [ 125.853520] postgres[1574]: ); vm-test-run-index_advisor> server # [ 125.853810] postgres[1574]: END IF; vm-test-run-index_advisor> server # [ 125.854401] postgres[1574]: END; vm-test-run-index_advisor> server # [ 125.856812] postgres[1574]: $$; vm-test-run-index_advisor> server # [ 125.857335] postgres[1574]: ::1 2026-10-23 15:22:08.190 UTC [1574] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 125.859703] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 125.860986] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 125.862158] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-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 # [ 125.864627] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: DROP EXTENSION vm-test-run-index_advisor> server # [ 127.959671] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: DO vm-test-run-index_advisor> server # [ 128.015899] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-index_advisor> server # [ 128.380181] postgres[1604]: ::1 2026-10-23 15:22:10.861 UTC [1604] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-index_advisor> server # [ 128.389926] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1603]: GRANT ROLE vm-test-run-index_advisor> server # [ 128.431326] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-index_advisor> server # [ 128.757339] postgres[1606]: ::1 2026-10-23 15:22:11.241 UTC [1606] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 128.758840] postgres[1606]: returns event_trigger vm-test-run-index_advisor> server # [ 128.759625] postgres[1606]: language plpgsql vm-test-run-index_advisor> server # [ 128.760145] postgres[1606]: AS $func$ vm-test-run-index_advisor> server # [ 128.765376] postgres[1606]: DECLARE vm-test-run-index_advisor> server # [ 128.765884] postgres[1606]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 128.766837] postgres[1606]: BEGIN vm-test-run-index_advisor> server # [ 128.767481] postgres[1606]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 128.768204] postgres[1606]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 128.775837] postgres[1606]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 128.781548] postgres[1606]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 128.782868] postgres[1606]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 128.791497] postgres[1606]: ); vm-test-run-index_advisor> server # [ 128.793489] postgres[1606]: vm-test-run-index_advisor> server # [ 128.794873] postgres[1606]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 128.795549] postgres[1606]: THEN vm-test-run-index_advisor> server # [ 128.796898] postgres[1606]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 128.797647] postgres[1606]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 128.798889] postgres[1606]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 128.800065] postgres[1606]: "operationName" text default null, vm-test-run-index_advisor> server # [ 128.802091] postgres[1606]: query text default null, vm-test-run-index_advisor> server # [ 128.802932] postgres[1606]: variables jsonb default null, vm-test-run-index_advisor> server # [ 128.803867] postgres[1606]: extensions jsonb default null vm-test-run-index_advisor> server # [ 128.804422] postgres[1606]: ) vm-test-run-index_advisor> server # [ 128.807677] postgres[1606]: returns jsonb vm-test-run-index_advisor> server # [ 128.809486] postgres[1606]: language sql vm-test-run-index_advisor> server # [ 128.811391] postgres[1606]: as $$ vm-test-run-index_advisor> server # [ 128.811754] postgres[1606]: select graphql.resolve( vm-test-run-index_advisor> server # [ 128.814007] postgres[1606]: query := query, vm-test-run-index_advisor> server # [ 128.814455] postgres[1606]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 128.816266] postgres[1606]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 128.819077] postgres[1606]: extensions := extensions vm-test-run-index_advisor> server # [ 128.819667] postgres[1606]: ); vm-test-run-index_advisor> server # [ 128.820512] postgres[1606]: $$; vm-test-run-index_advisor> server # [ 128.821298] postgres[1606]: vm-test-run-index_advisor> server # [ 128.822343] postgres[1606]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-index_advisor> server # [ 128.823300] postgres[1606]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-index_advisor> server # [ 128.824630] postgres[1606]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-index_advisor> server # [ 128.825960] postgres[1606]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.826887] postgres[1606]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.827273] postgres[1606]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.828141] postgres[1606]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.828904] postgres[1606]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.830410] postgres[1606]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.831507] postgres[1606]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 128.832024] postgres[1606]: END IF; vm-test-run-index_advisor> server # [ 128.833233] postgres[1606]: vm-test-run-index_advisor> server # [ 128.833829] postgres[1606]: END; vm-test-run-index_advisor> server # [ 128.834342] postgres[1606]: $func$; vm-test-run-index_advisor> server # [ 128.866997] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1605]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 128.870294] postgres[1606]: ::1 2026-10-23 15:22:11.359 UTC [1606] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 128.910444] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1605]: DROP EXTENSION vm-test-run-index_advisor> server # [ 130.816410] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1605]: DO vm-test-run-index_advisor> server # [ 130.867830] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql