this derivation will be built: /nix/store/8kgicvp4xr6wzdjzidx3sgxkrr0q7m3p-vm-test-run-index_advisor.drv building '/nix/store/8kgicvp4xr6wzdjzidx3sgxkrr0q7m3p-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/8kgicvp4xr6wzdjzidx3sgxkrr0q7m3p-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.7mSsmnhsiS', 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: afbf7685-dbf9-4cad-9cf1-055240f4a83b 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.16 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/7k8ylb9h0y3gvmdxydaznvwgxdplazyq-nixos-system-server-test/init regInfo=/nix/store/6mggk2siv8z8m7bmnv8hgks7w6zcg2cl-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-index_advisor> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/6mggk2siv8z8m7bmnv8hgks7w6zcg2cl-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.000041] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-index_advisor> server # [ 0.004307] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-index_advisor> server # [ 0.015192] Console: colour dummy device 80x25 vm-test-run-index_advisor> server # [ 0.016853] printk: legacy console [tty0] enabled vm-test-run-index_advisor> server # [ 0.022567] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-index_advisor> server # [ 0.022985] pid_max: default: 32768 minimum: 301 vm-test-run-index_advisor> server # [ 0.024886] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-index_advisor> server # [ 0.026773] landlock: Up and running. vm-test-run-index_advisor> server # [ 0.026841] Yama: becoming mindful. vm-test-run-index_advisor> server # [ 0.030693] LSM support for eBPF active vm-test-run-index_advisor> server # [ 0.034115] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.034477] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.065008] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-index_advisor> server # [ 0.076429] rcu: Hierarchical SRCU implementation. vm-test-run-index_advisor> server # [ 0.076666] rcu: Max phase no-delay instances is 1000. vm-test-run-index_advisor> server # [ 0.078626] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-index_advisor> server # [ 0.086732] fsl-mc MSI: its@8080000 domain created vm-test-run-index_advisor> server # [ 0.088894] EFI services will not be available. vm-test-run-index_advisor> server # [ 0.090539] smp: Bringing up secondary CPUs ... vm-test-run-index_advisor> server # [ 0.096095] Detected PIPT I-cache on CPU1 vm-test-run-index_advisor> server # [ 0.097355] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-index_advisor> server # [ 0.098049] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000 vm-test-run-index_advisor> server # [ 0.099608] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-index_advisor> server # [ 0.104321] smp: Brought up 1 node, 2 CPUs vm-test-run-index_advisor> server # [ 0.104551] SMP: Total of 2 processors activated. vm-test-run-index_advisor> server # [ 0.104607] CPU: All CPU(s) started at EL1 vm-test-run-index_advisor> server # [ 0.104772] CPU features: detected: Branch Target Identification vm-test-run-index_advisor> server # [ 0.104825] CPU features: detected: 32-bit EL0 Support vm-test-run-index_advisor> server # [ 0.104853] CPU features: detected: 32-bit EL1 Support vm-test-run-index_advisor> server # [ 0.105025] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-index_advisor> server # [ 0.105063] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-index_advisor> server # [ 0.105102] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-index_advisor> server # [ 0.105168] CPU features: detected: Common not Private translations vm-test-run-index_advisor> server # [ 0.105191] CPU features: detected: CRC32 instructions vm-test-run-index_advisor> server # [ 0.105212] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-index_advisor> server # [ 0.105238] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-index_advisor> server # [ 0.105261] CPU features: detected: Data independent timing control (DIT) vm-test-run-index_advisor> server # [ 0.105286] CPU features: detected: E0PD vm-test-run-index_advisor> server # [ 0.105307] CPU features: detected: Enhanced Counter Virtualization vm-test-run-index_advisor> server # [ 0.105329] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-index_advisor> server # [ 0.105352] CPU features: detected: Enhanced Privileged Access Never vm-test-run-index_advisor> server # [ 0.105375] CPU features: detected: Enhanced Virtualization Traps vm-test-run-index_advisor> server # [ 0.105402] CPU features: detected: Fine Grained Traps vm-test-run-index_advisor> server # [ 0.105459] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-index_advisor> server # [ 0.105541] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-index_advisor> server # [ 0.105583] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-index_advisor> server # [ 0.105607] CPU features: detected: LSE atomic instructions vm-test-run-index_advisor> server # [ 0.105628] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-index_advisor> server # [ 0.105651] CPU features: detected: Privileged Access Never vm-test-run-index_advisor> server # [ 0.105672] CPU features: detected: RAS Extension Support vm-test-run-index_advisor> server # [ 0.105697] CPU features: detected: Random Number Generator vm-test-run-index_advisor> server # [ 0.105717] CPU features: detected: Speculation barrier (SB) vm-test-run-index_advisor> server # [ 0.105738] CPU features: detected: Stage-2 Force Write-Back vm-test-run-index_advisor> server # [ 0.105764] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-index_advisor> server # [ 0.105796] CPU features: detected: TLB range maintenance instructions vm-test-run-index_advisor> server # [ 0.105823] CPU features: detected: WFx with timeout vm-test-run-index_advisor> server # [ 0.105876] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-index_advisor> server # [ 0.105904] CPU features: detected: Scalable Vector Extension vm-test-run-index_advisor> server # [ 0.111199] alternatives: applying system-wide alternatives vm-test-run-index_advisor> server # [ 0.129727] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-index_advisor> server # [ 0.130337] SVE: maximum available vector length 256 bytes per vector vm-test-run-index_advisor> server # [ 0.130428] SVE: default vector length 64 bytes per vector vm-test-run-index_advisor> server # [ 0.143889] Memory: 3922856K/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.166863] devtmpfs: initialized vm-test-run-index_advisor> server # [ 0.195841] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-index_advisor> server # [ 0.196354] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-index_advisor> server # [ 0.198849] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-index_advisor> server # [ 0.198930] 0 pages in range for non-PLT usage vm-test-run-index_advisor> server # [ 0.198974] 509248 pages in range for PLT usage vm-test-run-index_advisor> server # [ 0.202102] pinctrl core: initialized pinctrl subsystem vm-test-run-index_advisor> server # [ 0.213881] DMI not present or invalid. vm-test-run-index_advisor> server # [ 0.232806] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-index_advisor> server # [ 0.244432] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-index_advisor> server # [ 0.246097] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-index_advisor> server # [ 0.247582] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-index_advisor> server # [ 0.247868] audit: initializing netlink subsys (disabled) vm-test-run-index_advisor> server # [ 0.250973] audit: type=2000 audit(0.200:1): state=initialized audit_enabled=0 res=1 vm-test-run-index_advisor> server # [ 0.258007] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-index_advisor> server # [ 0.258066] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-index_advisor> server # [ 0.258122] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-index_advisor> server # [ 0.258148] thermal_sys: Registered thermal governor 'user_space' vm-test-run-index_advisor> server # [ 0.258172] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-index_advisor> server # [ 0.259214] cpuidle: using governor ladder vm-test-run-index_advisor> server # [ 0.259900] cpuidle: using governor menu vm-test-run-index_advisor> server # [ 0.262486] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-index_advisor> server # [ 0.263534] ASID allocator initialised with 65536 entries vm-test-run-index_advisor> server # [ 0.270846] Serial: AMBA PL011 UART driver vm-test-run-index_advisor> server # [ 0.322947] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-index_advisor> server # [ 0.324005] printk: legacy console [ttyAMA0] enabled vm-test-run-index_advisor> server # [ 0.361108] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.361292] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-index_advisor> server # [ 0.361398] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.361523] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-index_advisor> server # [ 0.361626] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.361721] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-index_advisor> server # [ 0.361817] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-index_advisor> server # [ 0.361909] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-index_advisor> server # [ 0.375056] fbcon: Taking over console vm-test-run-index_advisor> server # [ 0.375677] ACPI: Interpreter disabled. vm-test-run-index_advisor> server # [ 0.383570] iommu: Default domain type: Translated vm-test-run-index_advisor> server # [ 0.383809] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-index_advisor> server # [ 0.387226] SCSI subsystem initialized vm-test-run-index_advisor> server # [ 0.389443] usbcore: registered new interface driver usbfs vm-test-run-index_advisor> server # [ 0.389786] usbcore: registered new interface driver hub vm-test-run-index_advisor> server # [ 0.390914] usbcore: registered new device driver usb vm-test-run-index_advisor> server # [ 0.393943] pps_core: LinuxPPS API ver. 1 registered vm-test-run-index_advisor> server # [ 0.394082] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-index_advisor> server # [ 0.394263] PTP clock support registered vm-test-run-index_advisor> server # [ 0.394623] EDAC MC: Ver: 3.0.0 vm-test-run-index_advisor> server # [ 0.396380] scmi_core: SCMI protocol bus registered vm-test-run-index_advisor> server # [ 0.400661] FPGA manager framework vm-test-run-index_advisor> server # [ 0.402087] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-index_advisor> server # [ 0.410731] vgaarb: loaded vm-test-run-index_advisor> server # [ 0.415240] clocksource: Switched to clocksource arch_sys_counter vm-test-run-index_advisor> server # [ 0.420846] VFS: Disk quotas dquot_6.6.0 vm-test-run-index_advisor> server # [ 0.421340] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-index_advisor> server # [ 0.423939] netfs: FS-Cache loaded vm-test-run-index_advisor> server # [ 0.425449] pnp: PnP ACPI: disabled vm-test-run-index_advisor> server # [ 0.452338] NET: Registered PF_INET protocol family vm-test-run-index_advisor> server # [ 0.454152] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-index_advisor> server # [ 0.574765] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-index_advisor> server # [ 0.575479] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-index_advisor> server # [ 0.575772] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-index_advisor> server # [ 0.576889] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-index_advisor> server # [ 0.577616] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-index_advisor> server # [ 0.578958] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-index_advisor> server # [ 0.580369] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.581257] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-index_advisor> server # [ 0.583367] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-index_advisor> server # [ 0.584387] NET: Registered PF_XDP protocol family vm-test-run-index_advisor> server # [ 0.584889] PCI: CLS 0 bytes, default 64 vm-test-run-index_advisor> server # [ 0.587943] kvm [1]: HYP mode not available vm-test-run-index_advisor> server # [ 0.593552] Trying to unpack rootfs image as initramfs... vm-test-run-index_advisor> server # [ 0.700144] Initialise system trusted keyrings vm-test-run-index_advisor> server # [ 0.701642] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-index_advisor> server # [ 0.706364] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-index_advisor> server # [ 0.707175] 9p: Installing v9fs 9p2000 file system support vm-test-run-index_advisor> server # [ 0.769951] Key type asymmetric registered vm-test-run-index_advisor> server # [ 0.770183] Asymmetric key parser 'x509' registered vm-test-run-index_advisor> server # [ 0.770691] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-index_advisor> server # [ 0.773079] io scheduler mq-deadline registered vm-test-run-index_advisor> server # [ 0.773531] io scheduler kyber registered vm-test-run-index_advisor> server # [ 0.807105] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-index_advisor> server # [ 0.813126] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-index_advisor> server # [ 0.817987] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-index_advisor> server # [ 0.818878] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-index_advisor> server # [ 0.820365] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-index_advisor> server # [ 0.820703] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-index_advisor> server # [ 0.821817] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-index_advisor> server # [ 0.822399] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-index_advisor> server # [ 0.825150] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-index_advisor> server # [ 0.825575] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-index_advisor> server # [ 0.826136] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-index_advisor> server # [ 0.826264] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-index_advisor> server # [ 0.826372] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-index_advisor> server # [ 0.827977] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.833163] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.833542] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.833679] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.833821] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.834394] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-index_advisor> server # [ 0.836444] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.836728] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.836856] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.837928] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.838554] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.839921] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-index_advisor> server # [ 0.840326] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.840455] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.841281] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.841447] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.841578] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.842181] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.843256] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.843418] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.843519] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.843629] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.845283] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.845489] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-index_advisor> server # [ 0.845589] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.845855] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.846428] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.846587] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.846684] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.846794] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.846904] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-index_advisor> server # [ 0.847364] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.847514] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.847630] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.849582] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.850091] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.850586] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.852250] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.852444] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.853893] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.854268] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-index_advisor> server # [ 0.854740] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.855185] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.857308] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-index_advisor> server # [ 0.857750] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-index_advisor> server # [ 0.858480] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-index_advisor> server # [ 0.858621] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-index_advisor> server # [ 0.861426] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-index_advisor> server # [ 0.861722] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-index_advisor> server # [ 0.862338] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.864148] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.864618] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.864774] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.865557] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.866010] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.866169] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.866757] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.867892] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.868294] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.869010] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-index_advisor> server # [ 0.869166] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-index_advisor> server # [ 0.869290] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-index_advisor> server # [ 0.869405] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-index_advisor> server # [ 0.869512] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-index_advisor> server # [ 0.869622] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-index_advisor> server # [ 0.869737] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-index_advisor> server # [ 0.869841] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-index_advisor> server # [ 0.870695] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-index_advisor> server # [ 0.870845] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-index_advisor> server # [ 0.871127] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-index_advisor> server # [ 0.872159] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-index_advisor> server # [ 0.872376] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-index_advisor> server # [ 0.872608] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-index_advisor> server # [ 0.873457] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-index_advisor> server # [ 0.873585] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-index_advisor> server # [ 0.873692] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-index_advisor> server # [ 0.873798] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-index_advisor> server # [ 0.873902] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-index_advisor> server # [ 0.874006] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-index_advisor> server # [ 0.874112] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-index_advisor> server # [ 0.874879] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-index_advisor> server # [ 0.876146] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-index_advisor> server # [ 0.876307] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-index_advisor> server # [ 0.876408] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-index_advisor> server # [ 0.878303] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.941121] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.946586] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.948983] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.951621] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.953784] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.957633] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.960408] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.962100] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.964057] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-index_advisor> server # [ 0.966097] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.969487] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-index_advisor> server # [ 0.988435] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-index_advisor> server # [ 0.999229] msm_serial: driver initialized vm-test-run-index_advisor> server # [ 1.000088] SuperH (H)SCI(F) driver initialized vm-test-run-index_advisor> server # [ 1.000672] STM32 USART driver initialized vm-test-run-index_advisor> server # [ 1.057818] Freeing initrd memory: 10684K vm-test-run-index_advisor> server # [ 1.059846] loop: module loaded vm-test-run-index_advisor> server # [ 1.061212] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-index_advisor> server # [ 1.065354] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-index_advisor> server # [ 1.078579] megasas: 07.727.03.00-rc1 vm-test-run-index_advisor> server # [ 1.085597] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-index_advisor> server # [ 1.089105] 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.090218] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-index_advisor> server # [ 1.092866] Using buffer write method vm-test-run-index_advisor> server # [ 1.093573] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-index_advisor> server # [ 1.094950] 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.095703] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-index_advisor> server # [ 1.097530] Using buffer write method vm-test-run-index_advisor> server # [ 1.097786] Concatenating MTD devices: vm-test-run-index_advisor> server # [ 1.097875] (0): "0.flash" vm-test-run-index_advisor> server # [ 1.097941] (1): "0.flash" vm-test-run-index_advisor> server # [ 1.098002] into device "0.flash" vm-test-run-index_advisor> server # [ 1.125451] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-index_advisor> server # [ 1.137393] thunder_xcv, ver 1.0 vm-test-run-index_advisor> server # [ 1.137649] thunder_bgx, ver 1.0 vm-test-run-index_advisor> server # [ 1.137819] nicpf, ver 1.0 vm-test-run-index_advisor> server # [ 1.141339] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-index_advisor> server # [ 1.141542] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-index_advisor> server # [ 1.141973] hclge is initializing vm-test-run-index_advisor> server # [ 1.142249] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-index_advisor> server # [ 1.142344] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-index_advisor> server # [ 1.142578] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-index_advisor> server # [ 1.142661] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-index_advisor> server # [ 1.142864] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-index_advisor> server # [ 1.142954] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-index_advisor> server # [ 1.143133] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-index_advisor> server # [ 1.143234] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-index_advisor> server # [ 1.144422] sky2: driver version 1.30 vm-test-run-index_advisor> server # [ 1.153423] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-index_advisor> server # [ 1.153998] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-index_advisor> server # [ 1.156562] usbcore: registered new interface driver usb-storage vm-test-run-index_advisor> server # [ 1.157198] usbcore: registered new interface driver usbserial_generic vm-test-run-index_advisor> server # [ 1.157256] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-index_advisor> server # [ 1.157913] usbserial: USB Serial support registered for generic vm-test-run-index_advisor> server # [ 1.167668] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-index_advisor> server # [ 1.169087] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-index_advisor> server # [ 1.170102] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T21:43:52 UTC (1792705432) vm-test-run-index_advisor> server # [ 1.172731] i2c_dev: i2c /dev entries driver vm-test-run-index_advisor> server # [ 1.174771] hub 1-0:1.0: USB hub found vm-test-run-index_advisor> server # [ 1.175437] hub 1-0:1.0: 6 ports detected vm-test-run-index_advisor> server # [ 1.189925] sdhci: Secure Digital Host Controller Interface driver vm-test-run-index_advisor> server # [ 1.190085] sdhci: Copyright(c) Pierre Ossman vm-test-run-index_advisor> server # [ 1.192510] Synopsys Designware Multimedia Card Interface Driver vm-test-run-index_advisor> server # [ 1.194614] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-index_advisor> server # [ 1.202549] hid: raw HID events driver (C) Jiri Kosina vm-test-run-index_advisor> server # [ 1.205258] usbcore: registered new interface driver usbhid vm-test-run-index_advisor> server # [ 1.205400] usbhid: USB HID core driver vm-test-run-index_advisor> server # [ 1.214911] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-index_advisor> server # [ 1.231063] drop_monitor: Initializing network drop monitor service vm-test-run-index_advisor> server # [ 1.233293] NET: Registered PF_INET6 protocol family vm-test-run-index_advisor> server # [ 1.252297] Segment Routing with IPv6 vm-test-run-index_advisor> server # [ 1.252849] In-situ OAM (IOAM) with IPv6 vm-test-run-index_advisor> server # [ 1.253533] NET: Registered PF_PACKET protocol family vm-test-run-index_advisor> server # [ 1.255833] 9pnet: Installing 9P2000 support vm-test-run-index_advisor> server # [ 1.260646] Key type dns_resolver registered vm-test-run-index_advisor> server # [ 1.298510] registered taskstats version 1 vm-test-run-index_advisor> server # [ 1.301608] Loading compiled-in X.509 certificates vm-test-run-index_advisor> server # [ 1.366258] Demotion targets for Node 0: null vm-test-run-index_advisor> server # [ 1.368413] Key type .fscrypt registered vm-test-run-index_advisor> server # [ 1.368567] Key type fscrypt-provisioning registered vm-test-run-index_advisor> server # [ 1.378494] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-index_advisor> server # [ 1.424371] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-index_advisor> server # [ 1.425941] clk: Disabling unused clocks vm-test-run-index_advisor> server # [ 1.426527] PM: genpd: Disabling unused power domains vm-test-run-index_advisor> server # [ 1.426853] ALSA device list: vm-test-run-index_advisor> server # [ 1.426970] No soundcards found. vm-test-run-index_advisor> server # [ 1.512029] Freeing unused kernel memory: 4416K vm-test-run-index_advisor> server # [ 1.513316] Run /init as init process vm-test-run-index_advisor> server # [ 1.613486] 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.758005] 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.880869] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-index_advisor> server # [ 2.057269] 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.062919] 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.130315] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-index_advisor> server # [ 2.264652] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-index_advisor> server # [ 2.265377] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-index_advisor> server # [ 2.265401] [drm] features: -context_init vm-test-run-index_advisor> server # [ 2.267212] [drm] number of scanouts: 1 vm-test-run-index_advisor> server # [ 2.267322] [drm] number of cap sets: 0 vm-test-run-index_advisor> server # [ 2.278617] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-index_advisor> server # [ 2.312688] Console: switching to colour frame buffer device 160x50 vm-test-run-index_advisor> server # [ 2.324243] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-index_advisor> server # [ 5.291635] EXT4-fs (vda): mounted filesystem afbf7685-dbf9-4cad-9cf1-055240f4a83b r/w with ordered data mode. Quota mode: none. vm-test-run-index_advisor> server # [ 7.961540] EXT4-fs (vda): re-mounted afbf7685-dbf9-4cad-9cf1-055240f4a83b. vm-test-run-index_advisor> server # [ 7.981787] booting system configuration /nix/store/7k8ylb9h0y3gvmdxydaznvwgxdplazyq-nixos-system-server-test vm-test-run-index_advisor> server # [ 21.727433] 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 # [ 21.737664] systemd[1]: Detected virtualization qemu. vm-test-run-index_advisor> server # [ 21.742720] systemd[1]: Detected architecture arm64. vm-test-run-index_advisor> server # [ 21.747496] systemd[1]: Detected first boot. vm-test-run-index_advisor> server # [ 21.777876] systemd[1]: Initializing machine ID from random generator. vm-test-run-index_advisor> server # [ 21.813996] systemd[1]: Hostname set to . vm-test-run-index_advisor> server # [ 23.070486] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-index_advisor> server # [ 24.581403] systemd[1]: Applying preset policy. vm-test-run-index_advisor> server # [ 28.713437] 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 # [ 32.534500] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-index_advisor> server # [ 32.760500] systemd[1]: Created slice Slice /system/getty. vm-test-run-index_advisor> server # [ 32.777078] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-index_advisor> server # [ 32.790711] systemd[1]: Created slice User and Session Slice. vm-test-run-index_advisor> server # [ 32.801044] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-index_advisor> server # [ 32.806823] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-index_advisor> server # [ 32.813582] systemd[1]: Expecting device /dev/hvc0... vm-test-run-index_advisor> server # [ 32.818184] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-index_advisor> server # [ 32.822779] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-index_advisor> server # [ 32.827860] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-index_advisor> server # [ 32.832439] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-index_advisor> server # [ 32.837356] systemd[1]: Reached target Path Units. vm-test-run-index_advisor> server # [ 32.842381] systemd[1]: Reached target Remote File Systems. vm-test-run-index_advisor> server # [ 32.846074] systemd[1]: Reached target Slice Units. vm-test-run-index_advisor> server # [ 32.851676] systemd[1]: Reached target Swaps. vm-test-run-index_advisor> server # [ 32.889697] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-index_advisor> server # [ 32.914980] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-index_advisor> server # [ 32.926975] systemd[1]: Listening on Journal Audit Socket. vm-test-run-index_advisor> server # [ 32.934394] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-index_advisor> server # [ 32.940919] systemd[1]: Listening on Journal Sockets. vm-test-run-index_advisor> server # [ 32.949450] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-index_advisor> server # [ 32.953945] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 32.961122] systemd[1]: Listening on udev Control Socket. vm-test-run-index_advisor> server # [ 32.966669] systemd[1]: Listening on udev Kernel Socket. vm-test-run-index_advisor> server # [ 33.017236] systemd[1]: Mounting Huge Pages File System... vm-test-run-index_advisor> server # [ 33.052418] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-index_advisor> server # [ 33.096362] systemd[1]: Mounting Kernel Debug File System... vm-test-run-index_advisor> server # [ 33.174814] systemd[1]: Mounting Kernel Trace File System... vm-test-run-index_advisor> server # [ 33.297332] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-index_advisor> server # [ 33.390238] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-index_advisor> server # [ 33.405556] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-index_advisor> server # [ 33.564920] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-index_advisor> server # [ 33.590890] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-index_advisor> server # [ 33.625160] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-index_advisor> server # [ 33.737328] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-index_advisor> server # [ 33.750077] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-index_advisor> server # [ 33.765199] 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 # [ 33.896707] systemd[1]: Starting Journal Service... vm-test-run-index_advisor> server # [ 33.997630] systemd[1]: Starting Load Kernel Modules... vm-test-run-index_advisor> server # [ 34.065363] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-index_advisor> server # [ 34.123315] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-index_advisor> server # [ 34.132921] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 34.179054] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-index_advisor> server # [ 34.252355] systemd[1]: Mounted Huge Pages File System. vm-test-run-index_advisor> server # [ 34.259403] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-index_advisor> server # [ 34.273362] systemd[1]: Mounted Kernel Debug File System. vm-test-run-index_advisor> server # [ 34.290728] systemd[1]: Mounted Kernel Trace File System. vm-test-run-index_advisor> server # [ 34.313884] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-index_advisor> server # [ 34.335889] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-index_advisor> server # [ 34.426153] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-index_advisor> server # [ 34.679679] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 34.698497] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-index_advisor> server # [ 35.267320] fuse: init (API version 7.41) vm-test-run-index_advisor> server # [ 35.364263] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 35.382095] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-index_advisor> server # [ 35.404721] systemd[1]: Finished Load Kernel Modules. vm-test-run-index_advisor> server # [ 35.453801] systemd[1]: Mounting FUSE Control File System... vm-test-run-index_advisor> server # [ 35.478944] EXT4-fs (vda): re-mounted afbf7685-dbf9-4cad-9cf1-055240f4a83b. vm-test-run-index_advisor> server # [ 35.565478] systemd[1]: Starting Firewall... vm-test-run-index_advisor> server # [ 35.670727] systemd[1]: Starting Apply Kernel Variables... vm-test-run-index_advisor> server # [ 35.729061] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-index_advisor> server # [ 35.745457] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-index_advisor> server # [ 35.812397] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-index_advisor> server # [ 35.820143] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-index_advisor> server # [ 35.854544] systemd[1]: Mounted FUSE Control File System. vm-test-run-index_advisor> server # [ 36.005629] systemd-journald[395]: Collecting audit messages is enabled. vm-test-run-index_advisor> server # [ 36.652594] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-index_advisor> server # [ 36.733187] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-index_advisor> server # [ 36.812718] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-index_advisor> server # [ 36.822439] systemd[1]: Reached target First Boot Complete. vm-test-run-index_advisor> server # [ 37.165372] systemd[1]: Finished Apply Kernel Variables. vm-test-run-index_advisor> server # [ 37.485353] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-index_advisor> server # [ 38.009333] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-index_advisor> server # [ 38.031493] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-index_advisor> server # [ 38.083432] systemd[1]: Mounting /run/wrappers... vm-test-run-index_advisor> server # [ 38.138017] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-index_advisor> server # [ 38.518431] systemd[1]: Mounted /run/wrappers. vm-test-run-index_advisor> server # [ 38.534980] systemd[1]: Reached target Local File Systems. vm-test-run-index_advisor> server # [ 38.565897] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-index_advisor> server # [ 38.612355] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-index_advisor> server # [ 38.618243] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-index_advisor> server # [ 38.689676] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-index_advisor> server # [ 38.933151] systemd[1]: Started Journal Service. vm-test-run-index_advisor> server # [ 38.913132] systemd-modules-load[396]: Module 'atkbd' is built in vm-test-run-index_advisor> server # [ 38.933247] systemd-modules-load[396]: Module 'loop' is built in vm-test-run-index_advisor> server # [ 38.991171] systemd-oomd[397]: No swap; memory pressure usage will be degraded vm-test-run-index_advisor> server # [ 39.050133] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-index_advisor> server # [ 39.738633] systemd-udevd[422]: Using default interface naming scheme 'v258'. vm-test-run-index_advisor> server # [ 39.801543] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-index_advisor> server # [ 39.866908] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-index_advisor> server # [ 40.045312] systemd-journald[395]: Received client request to flush runtime journal. vm-test-run-index_advisor> server # [ 40.577632] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-index_advisor> server # [ 40.599171] systemd[1]: Starting Create System Files and Directories... vm-test-run-index_advisor> server # [ 42.464410] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-index_advisor> server # [ 42.528057] systemd[1]: Finished Create System Files and Directories. vm-test-run-index_advisor> server # [ 42.621052] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-index_advisor> server # [ 42.689535] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-index_advisor> server # [ 43.943147] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-index_advisor> server # [ 45.039284] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-index_advisor> server # [ 45.090082] systemd[1]: Starting Update is Completed... vm-test-run-index_advisor> server # [ 45.773806] systemd[1]: Finished Update is Completed. vm-test-run-index_advisor> server # [ 49.261709] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 49.272580] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-index_advisor> server # [ 49.274578] systemd[1]: suid-sgid-wrappers.service: Consumed 2.856s CPU time, 5.6M memory peak. vm-test-run-index_advisor> server # [ 52.258493] systemd[1]: Finished Firewall. vm-test-run-index_advisor> server # [ 53.715652] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-index_advisor> server # [ 53.726036] systemd[1]: Reached target System Initialization. vm-test-run-index_advisor> server # [ 53.737752] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-index_advisor> server # [ 53.745700] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-index_advisor> server # [ 53.750898] systemd[1]: Reached target Timer Units. vm-test-run-index_advisor> server # [ 53.773022] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-index_advisor> server # [ 53.775118] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-index_advisor> server # [ 53.785870] systemd[1]: Listening on Hostname Service Socket. vm-test-run-index_advisor> server # [ 53.790461] systemd[1]: Reached target Socket Units. vm-test-run-index_advisor> server # [ 53.792246] systemd[1]: Reached target Basic System. vm-test-run-index_advisor> server # [ 53.803768] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-index_advisor> server # [ 53.846988] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 53.919273] systemd[1]: Started Reset console on configuration changes. vm-test-run-index_advisor> server # [ 53.977072] systemd[1]: Starting resolvconf update... vm-test-run-index_advisor> server # [ 54.049029] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-index_advisor> server # [ 55.043398] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-index_advisor> server # [ 55.173250] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 55.218806] nsncd[629]: Oct 22 21:44:46.551 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.231571] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 55.245895] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 55.285228] systemd[1]: Starting User Login Management... vm-test-run-index_advisor> server # [ 55.514880] dbus-daemon[632]: dbus[632]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-index_advisor> server # [ 55.926520] systemd[1]: Started D-Bus System Message Bus. vm-test-run-index_advisor> server # [ 56.787544] systemd-logind[649]: New seat seat0. vm-test-run-index_advisor> server # [ 56.845050] systemd[1]: Started User Login Management. vm-test-run-index_advisor> server # [ 56.883006] systemd[1]: Starting linger-users.service... vm-test-run-index_advisor> server # [ 56.942115] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 56.954713] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-index_advisor> server # [ 56.958332] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 56.965104] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-index_advisor> server # [ 56.972071] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 57.047329] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 57.073416] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 57.201167] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-index_advisor> server # [ 57.535875] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-index_advisor> server # [ 57.546708] systemd[1]: Finished linger-users.service. vm-test-run-index_advisor> server # [ 57.896013] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-index_advisor> server # [ 57.923883] nsncd[690]: Oct 22 21:44:49.291 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-index_advisor> server # [ 57.929593] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-index_advisor> server # [ 57.931712] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-index_advisor> server # [ 58.423520] systemd[1]: Finished resolvconf update. vm-test-run-index_advisor> server # [ 58.441986] systemd[1]: Reached target Preparation for Network. vm-test-run-index_advisor> server # [ 58.482229] systemd[1]: Starting DHCP Client... 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.670217] dhcpcd[740]: dhcpcd-10.2.4 starting vm-test-run-index_advisor> server # [ 59.787118] dhcpcd[745]: dev: loaded udev vm-test-run-index_advisor> server # [ 60.272731] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-index_advisor> server # [ 60.837856] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-index_advisor> server # [ 61.094990] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-index_advisor> server # [ 61.106135] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-index_advisor> server # [ 61.124707] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-index_advisor> server # [ 61.131869] cfg80211: failed to load regulatory.db vm-test-run-index_advisor> server # [ 61.490894] systemd[1]: Found device /dev/hvc0. vm-test-run-index_advisor> server # [ 61.974009] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-index_advisor> server # [ 61.919299] dhcpcd[745]: eth0: waiting for carrier vm-test-run-index_advisor> server # [ 61.946924] dhcpcd[745]: eth0: carrier acquired vm-test-run-index_advisor> server # [ 62.077919] dhcpcd[745]: DUID 00:01:00:01:32:6d:40:55:52:54:00:12:34:56 vm-test-run-index_advisor> server # [ 62.082413] dhcpcd[745]: eth0: IAID 00:12:34:56 vm-test-run-index_advisor> server # [ 62.089172] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-index_advisor> server # [ 62.532883] dhcpcd[745]: eth0: soliciting a DHCP lease vm-test-run-index_advisor> server # [ 62.573696] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-index_advisor> server # [ 62.605917] dhcpcd[745]: eth0: probing address 10.0.2.15/24 vm-test-run-index_advisor> server # [ 63.129635] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-index_advisor> server # [ 63.153912] systemd[1]: Started backdoor.service. vm-test-run-index_advisor> server # connecting to host... vm-test-run-index_advisor> server # [ 63.775775] (udev-worker)[784]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-index_advisor> server # [ 63.797025] (udev-worker)[784]: Network interface NamePolicy= disabled on kernel command line. 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 64.96 seconds) vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 64.96 seconds) vm-test-run-index_advisor> server # [ 64.495417] dhcpcd[745]: eth0: soliciting an IPv6 router vm-test-run-index_advisor> server # [ 64.517307] dhcpcd[745]: eth0: Router Advertisement from fe80::2 vm-test-run-index_advisor> server # [ 64.527246] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-index_advisor> server # [ 64.537925] dhcpcd[745]: eth0: adding route to fec0::/64 vm-test-run-index_advisor> server # [ 64.539078] dhcpcd[745]: eth0: adding default route via fe80::2 vm-test-run-index_advisor> server # [ 64.869813] (udev-worker)[792]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-index_advisor> server # [ 65.608660] systemd[1]: Found device Virtio network device. vm-test-run-index_advisor> server # [ 65.691841] systemd[1]: Starting Address configuration of eth1... vm-test-run-index_advisor> server # [ 66.391300] dhcpcd[745]: eth0: IAID 00:12:34:56 vm-test-run-index_advisor> server # [ 66.540532] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-index_advisor> server # [ 66.635399] network-addresses-eth1-start[818]: adding address 192.168.1.1/24... done vm-test-run-index_advisor> server # [ 66.790954] dhcpcd[745]: eth0: soliciting a DHCP lease vm-test-run-index_advisor> server # [ 66.801979] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-index_advisor> server # [ 66.803138] dhcpcd[745]: eth0: probing address 10.0.2.15/24 vm-test-run-index_advisor> server # [ 66.858770] network-addresses-eth1-start[818]: adding address 2001:db8:1::1/64... done vm-test-run-index_advisor> server # [ 66.877512] dhcpcd[745]: eth0: soliciting an IPv6 router vm-test-run-index_advisor> server # [ 67.228814] systemd[1]: Finished Address configuration of eth1. vm-test-run-index_advisor> server # [ 67.341348] systemd[1]: Starting Networking Setup... vm-test-run-index_advisor> server # [ 68.374848] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-index_advisor> server # [ 68.402612] dhcpcd[745]: eth0: adding route to 10.0.2.0/24 vm-test-run-index_advisor> server # [ 68.403959] dhcpcd[745]: eth0: adding default route via 10.0.2.2 vm-test-run-index_advisor> server # [ 69.035581] dhcpcd[876]: /nix/store/hsj50yr7hr62ql9xaynbihkhfhbgprcn-openresolv-3.17.0/sbin/.resolvconf-wrapped: line 1221: kill: (857) - Operation not permitted vm-test-run-index_advisor> server # [ 69.042651] dhcpcd[876]: clearing stale lock pid 857 vm-test-run-index_advisor> server # [ 70.121046] systemd[1]: Finished Networking Setup. vm-test-run-index_advisor> server # [ 70.122464] systemd[1]: Reached target Network. vm-test-run-index_advisor> server # [ 70.152318] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-index_advisor> server # [ 70.214380] systemd[1]: Starting Permit User Sessions... vm-test-run-index_advisor> server # [ 71.010404] systemd[1]: Finished Permit User Sessions. vm-test-run-index_advisor> server # [ 71.103082] systemd[1]: Started Getty on tty1. vm-test-run-index_advisor> server # [ 71.131484] systemd[1]: Reached target Login Prompts. vm-test-run-index_advisor> server # [ 71.810376] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[959]: Initializing database at /var/lib/postgresql/data vm-test-run-index_advisor> server # [ 72.468492] systemd[1]: Started DHCP Client. vm-test-run-index_advisor> server # [ 72.914568] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-index_advisor> server # [ 73.559926] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The files belonging to this database system will be owned by user "postgres". vm-test-run-index_advisor> server # [ 73.562292] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: This user must also own the server process. vm-test-run-index_advisor> server # [ 73.710103] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-index_advisor> server # [ 73.718008] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The default database encoding has accordingly been set to "UTF8". vm-test-run-index_advisor> server # [ 73.719805] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The default text search configuration will be set to "english". vm-test-run-index_advisor> server # [ 73.726571] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: Data page checksums are enabled. vm-test-run-index_advisor> server # [ 73.727226] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-index_advisor> server # [ 73.735197] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: creating subdirectories ... ok vm-test-run-index_advisor> server # [ 73.740354] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting dynamic shared memory implementation ... posix vm-test-run-index_advisor> server # [ 74.709410] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting default max_connections ... 100 vm-test-run-index_advisor> server # [ 75.663303] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting default shared_buffers ... 128MB vm-test-run-index_advisor> server # [ 77.236659] mousedev: PS/2 mouse device common for all mice vm-test-run-index_advisor> server # [ 80.779223] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-index_advisor> server # [ 85.777775] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting default time zone ... UTC vm-test-run-index_advisor> server # [ 85.821193] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: creating configuration files ... ok vm-test-run-index_advisor> server # [ 89.333650] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: running bootstrap script ... ok vm-test-run-index_advisor> server # [ 100.630512] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: performing post-bootstrap initialization ... ok vm-test-run-index_advisor> server # [ 102.777199] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: syncing data to disk ... ok vm-test-run-index_advisor> server # [ 102.782754] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-index_advisor> server # [ 102.784122] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: 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 # [ 102.786334] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: Success. You can now start the database server using: vm-test-run-index_advisor> server # [ 102.788893] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: /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 # [ 104.207402] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[959]: Validating PostgreSQL configuration... vm-test-run-index_advisor> server # [ 104.674854] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[1327]: 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 # [ 105.453193] postgres[1333]: 2026-10-22 21:45:36.844 UTC [1333] LOG: pgaudit extension initialized vm-test-run-index_advisor> server # [ 106.254445] postgres[1333]: 2026-10-22 21:45:37.648 UTC [1333] LOG: pgsodium primary server secret key loaded vm-test-run-index_advisor> server # [ 106.924790] postgres[1333]: 2026-10-22 21:45:38.312 UTC [1333] LOG: vault primary server secret key loaded vm-test-run-index_advisor> server # [ 107.621699] postgres[1333]: 2026-10-22 21:45:39.022 UTC [1333] 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 # [ 107.635551] postgres[1333]: 2026-10-22 21:45:39.035 UTC [1333] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-index_advisor> server # [ 107.637769] postgres[1333]: 2026-10-22 21:45:39.036 UTC [1333] LOG: listening on IPv6 address "::", port 5432 vm-test-run-index_advisor> server # [ 107.653055] postgres[1333]: 2026-10-22 21:45:39.052 UTC [1333] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-index_advisor> server # [ 107.739843] postgres[1368]: 2026-10-22 21:45:39.137 UTC [1368] LOG: database system was shut down at 2026-10-22 21:45:31 UTC vm-test-run-index_advisor> server # [ 107.944429] postgres[1372]: 2026-10-22 21:45:39.334 UTC [1372] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 107.981287] postgres[1333]: 2026-10-22 21:45:39.365 UTC [1333] LOG: database system is ready to accept connections vm-test-run-index_advisor> server # [ 108.020751] postgres[1371]: 2026-10-22 21:45:39.404 UTC [1371] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-index_advisor> server # [ 108.057585] systemd[1]: Started PostgreSQL Database Server. vm-test-run-index_advisor> server # [ 108.090391] systemd[1]: Starting Supabase Database Initialization... vm-test-run-index_advisor> server # [ 108.129321] postgres[1333]: 2026-10-22 21:45:39.522 UTC [1333] LOG: background worker "pg_net 0.20.4 worker" (PID 1372) exited with exit code 1 vm-test-run-index_advisor> server # [ 108.375823] postgres[1373]: 2026-10-22 21:45:39.773 UTC [1373] LOG: pg_cron scheduler started vm-test-run-index_advisor> server # [ 108.418397] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Waiting for PostgreSQL to be ready... vm-test-run-index_advisor> server # [ 109.177349] postgres[1383]: ::1 2026-10-22 21:45:40.553 UTC [1383] root@root FATAL: role "root" does not exist vm-test-run-index_advisor> server # [ 109.237013] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: PostgreSQL is ready vm-test-run-index_advisor> server # [ 109.239209] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Creating postgres role... vm-test-run-index_advisor> server # [ 109.342734] postgres[1384]: 2026-10-22 21:45:40.708 UTC [1384] FATAL: role "postgres" does not exist vm-test-run-index_advisor> server # [ 109.414750] postgres[1333]: 2026-10-22 21:45:40.791 UTC [1333] LOG: background worker "pg_net 0.20.4 worker" (PID 1384) exited with exit code 1 vm-test-run-index_advisor> server # [ 109.734172] postgres[1391]: ::1 2026-10-22 21:45:41.122 UTC [1391] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-index_advisor> server # [ 109.797896] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1385]: CREATE ROLE vm-test-run-index_advisor> server # [ 110.152873] postgres[1403]: ::1 2026-10-22 21:45:41.543 UTC [1403] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-index_advisor> server # [ 110.197217] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1398]: ALTER DATABASE vm-test-run-index_advisor> server # [ 110.287674] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-index_advisor> server # [ 110.771840] postgres[1415]: ::1 2026-10-22 21:45:42.157 UTC [1415] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-index_advisor> server # [ 110.810381] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE PUBLICATION vm-test-run-index_advisor> server # [ 110.811568] postgres[1415]: ::1 2026-10-22 21:45:42.207 UTC [1415] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-index_advisor> server # [ 110.817043] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE vm-test-run-index_advisor> server # [ 110.820833] postgres[1415]: ::1 2026-10-22 21:45:42.220 UTC [1415] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-index_advisor> server # [ 110.833017] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 110.834203] postgres[1415]: ::1 2026-10-22 21:45:42.231 UTC [1415] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-index_advisor> server # [ 110.847140] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 110.854264] postgres[1415]: ::1 2026-10-22 21:45:42.242 UTC [1415] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-index_advisor> server # [ 110.859872] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE vm-test-run-index_advisor> server # [ 110.862046] postgres[1415]: ::1 2026-10-22 21:45:42.258 UTC [1415] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-index_advisor> server # [ 110.872432] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT vm-test-run-index_advisor> server # [ 110.878361] postgres[1415]: ::1 2026-10-22 21:45:42.271 UTC [1415] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-index_advisor> server # [ 110.879929] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 110.886041] postgres[1415]: ::1 2026-10-22 21:45:42.283 UTC [1415] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-index_advisor> server # [ 110.890376] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE vm-test-run-index_advisor> server # [ 110.896758] postgres[1415]: ::1 2026-10-22 21:45:42.293 UTC [1415] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-index_advisor> server # [ 110.905932] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 110.913016] postgres[1415]: ::1 2026-10-22 21:45:42.312 UTC [1415] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-index_advisor> server # [ 113.539322] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 113.555545] postgres[1415]: ::1 2026-10-22 21:45:44.929 UTC [1415] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-index_advisor> server # [ 114.425951] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 114.427430] postgres[1415]: ::1 2026-10-22 21:45:45.824 UTC [1415] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-index_advisor> server # [ 114.439452] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 114.444968] postgres[1415]: ::1 2026-10-22 21:45:45.837 UTC [1415] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-index_advisor> server # [ 114.449780] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 114.450706] postgres[1415]: ::1 2026-10-22 21:45:45.851 UTC [1415] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-index_advisor> server # [ 114.465931] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 114.473407] postgres[1415]: ::1 2026-10-22 21:45:45.869 UTC [1415] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-index_advisor> server # [ 114.479917] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE vm-test-run-index_advisor> server # [ 114.484988] postgres[1415]: ::1 2026-10-22 21:45:45.881 UTC [1415] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-index_advisor> server # [ 114.496757] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE vm-test-run-index_advisor> server # [ 114.503122] postgres[1415]: ::1 2026-10-22 21:45:45.895 UTC [1415] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-index_advisor> server # [ 114.517825] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE vm-test-run-index_advisor> server # [ 114.521833] postgres[1415]: ::1 2026-10-22 21:45:45.921 UTC [1415] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-index_advisor> server # [ 114.534364] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE vm-test-run-index_advisor> server # [ 114.535799] postgres[1415]: ::1 2026-10-22 21:45:45.932 UTC [1415] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-index_advisor> server # [ 114.550696] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE vm-test-run-index_advisor> server # [ 114.560817] postgres[1415]: ::1 2026-10-22 21:45:45.956 UTC [1415] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 114.571592] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT vm-test-run-index_advisor> server # [ 114.582779] postgres[1415]: ::1 2026-10-22 21:45:45.974 UTC [1415] 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 # [ 114.611275] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 114.617279] postgres[1415]: ::1 2026-10-22 21:45:46.015 UTC [1415] 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 # [ 114.633543] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 114.634426] postgres[1415]: ::1 2026-10-22 21:45:46.032 UTC [1415] 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 # [ 114.647968] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 114.655468] postgres[1415]: ::1 2026-10-22 21:45:46.047 UTC [1415] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 114.664579] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT vm-test-run-index_advisor> server # [ 114.666689] postgres[1415]: ::1 2026-10-22 21:45:46.062 UTC [1415] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-index_advisor> server # [ 114.674933] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE vm-test-run-index_advisor> server # [ 114.677900] postgres[1415]: ::1 2026-10-22 21:45:46.073 UTC [1415] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 114.680666] postgres[1415]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 114.690039] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 114.690871] postgres[1415]: ::1 2026-10-22 21:45:46.088 UTC [1415] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 114.691355] postgres[1415]: on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 114.698969] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 114.702432] postgres[1415]: ::1 2026-10-22 21:45:46.102 UTC [1415] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-index_advisor> server # [ 114.703308] postgres[1415]: on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 114.714247] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 114.715144] postgres[1415]: ::1 2026-10-22 21:45:46.115 UTC [1415] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-index_advisor> server # [ 114.721737] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE vm-test-run-index_advisor> server # [ 114.723788] postgres[1415]: ::1 2026-10-22 21:45:46.124 UTC [1415] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-index_advisor> server # [ 114.733271] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE vm-test-run-index_advisor> server # [ 114.790064] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-index_advisor> server # [ 115.028158] postgres[1445]: ::1 2026-10-22 21:45:46.425 UTC [1445] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-index_advisor> server # [ 115.046231] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 115.047644] postgres[1445]: ::1 2026-10-22 21:45:46.443 UTC [1445] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-index_advisor> server # [ 115.050684] postgres[1445]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 115.051647] postgres[1445]: id uuid NOT NULL UNIQUE, vm-test-run-index_advisor> server # [ 115.061065] postgres[1445]: aud varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.062091] postgres[1445]: "role" varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.063675] postgres[1445]: email varchar(255) NULL UNIQUE, vm-test-run-index_advisor> server # [ 115.067775] postgres[1445]: encrypted_password varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.072064] postgres[1445]: confirmed_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.073650] postgres[1445]: invited_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.074882] postgres[1445]: confirmation_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.075329] postgres[1445]: confirmation_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.078884] postgres[1445]: recovery_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.080481] postgres[1445]: recovery_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.081838] postgres[1445]: email_change_token varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.082206] postgres[1445]: email_change varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.083178] postgres[1445]: email_change_sent_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.083757] postgres[1445]: last_sign_in_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.084993] postgres[1445]: raw_app_meta_data jsonb NULL, vm-test-run-index_advisor> server # [ 115.086270] postgres[1445]: raw_user_meta_data jsonb NULL, vm-test-run-index_advisor> server # [ 115.087018] postgres[1445]: is_super_admin bool NULL, vm-test-run-index_advisor> server # [ 115.087600] postgres[1445]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.087893] postgres[1445]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.089256] postgres[1445]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 115.090950] postgres[1445]: ); vm-test-run-index_advisor> server # [ 115.178284] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE vm-test-run-index_advisor> server # [ 115.182625] postgres[1445]: ::1 2026-10-22 21:45:46.582 UTC [1445] 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 # [ 115.214845] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX vm-test-run-index_advisor> server # [ 115.215851] postgres[1445]: ::1 2026-10-22 21:45:46.616 UTC [1445] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-index_advisor> server # [ 115.241885] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX vm-test-run-index_advisor> server # [ 115.242808] postgres[1445]: ::1 2026-10-22 21:45:46.643 UTC [1445] 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 # [ 115.250579] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT vm-test-run-index_advisor> server # [ 115.252062] postgres[1445]: ::1 2026-10-22 21:45:46.653 UTC [1445] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-index_advisor> server # [ 115.252739] postgres[1445]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 115.257546] postgres[1445]: id bigserial NOT NULL, vm-test-run-index_advisor> server # [ 115.258414] postgres[1445]: "token" varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.259130] postgres[1445]: user_id varchar(255) NULL, vm-test-run-index_advisor> server # [ 115.259879] postgres[1445]: revoked bool NULL, vm-test-run-index_advisor> server # [ 115.264163] postgres[1445]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.268060] postgres[1445]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.268462] postgres[1445]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 115.270250] postgres[1445]: ); vm-test-run-index_advisor> server # [ 115.339211] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE vm-test-run-index_advisor> server # [ 115.341163] postgres[1445]: ::1 2026-10-22 21:45:46.740 UTC [1445] 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 # [ 115.367626] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX vm-test-run-index_advisor> server # [ 115.370334] postgres[1445]: ::1 2026-10-22 21:45:46.769 UTC [1445] 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 # [ 115.394922] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX vm-test-run-index_advisor> server # [ 115.403507] postgres[1445]: ::1 2026-10-22 21:45:46.797 UTC [1445] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-index_advisor> server # [ 115.445239] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX vm-test-run-index_advisor> server # [ 115.446613] postgres[1445]: ::1 2026-10-22 21:45:46.847 UTC [1445] 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 # [ 115.463458] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT vm-test-run-index_advisor> server # [ 115.482730] postgres[1445]: ::1 2026-10-22 21:45:46.867 UTC [1445] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-index_advisor> server # [ 115.489470] postgres[1445]: id uuid NOT NULL, vm-test-run-index_advisor> server # [ 115.491835] postgres[1445]: uuid uuid NULL, vm-test-run-index_advisor> server # [ 115.498331] postgres[1445]: raw_base_config text NULL, vm-test-run-index_advisor> server # [ 115.506554] postgres[1445]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.519607] postgres[1445]: updated_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.522516] postgres[1445]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 115.523326] postgres[1445]: ); vm-test-run-index_advisor> server # [ 115.588921] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE vm-test-run-index_advisor> server # [ 115.592017] postgres[1445]: ::1 2026-10-22 21:45:46.987 UTC [1445] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-index_advisor> server # [ 115.609887] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT vm-test-run-index_advisor> server # [ 115.618531] postgres[1445]: ::1 2026-10-22 21:45:47.016 UTC [1445] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-index_advisor> server # [ 115.620762] postgres[1445]: instance_id uuid NULL, vm-test-run-index_advisor> server # [ 115.629498] postgres[1445]: id uuid NOT NULL, vm-test-run-index_advisor> server # [ 115.631126] postgres[1445]: payload json NULL, vm-test-run-index_advisor> server # [ 115.636904] postgres[1445]: created_at timestamptz NULL, vm-test-run-index_advisor> server # [ 115.638213] postgres[1445]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-index_advisor> server # [ 115.639347] postgres[1445]: ); vm-test-run-index_advisor> server # [ 115.724405] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE vm-test-run-index_advisor> server # [ 115.736813] postgres[1445]: ::1 2026-10-22 21:45:47.130 UTC [1445] 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 # [ 115.793400] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX vm-test-run-index_advisor> server # [ 115.795863] postgres[1445]: ::1 2026-10-22 21:45:47.188 UTC [1445] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-index_advisor> server # [ 115.811036] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT vm-test-run-index_advisor> server # [ 115.818016] postgres[1445]: ::1 2026-10-22 21:45:47.206 UTC [1445] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-index_advisor> server # [ 115.826001] postgres[1445]: "version" varchar(255) NOT NULL, vm-test-run-index_advisor> server # [ 115.833684] postgres[1445]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-index_advisor> server # [ 115.834515] postgres[1445]: ); vm-test-run-index_advisor> server # [ 115.887412] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE vm-test-run-index_advisor> server # [ 115.897432] postgres[1445]: ::1 2026-10-22 21:45:47.284 UTC [1445] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-index_advisor> server # [ 115.906147] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT vm-test-run-index_advisor> server # [ 115.936899] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: INSERT 0 7 vm-test-run-index_advisor> server # [ 115.939509] postgres[1445]: ::1 2026-10-22 21:45:47.335 UTC [1445] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-index_advisor> server # [ 115.946343] postgres[1445]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-index_advisor> server # [ 115.955255] postgres[1445]: $$ language sql stable; vm-test-run-index_advisor> server # [ 115.966432] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 115.972701] postgres[1445]: ::1 2026-10-22 21:45:47.370 UTC [1445] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-index_advisor> server # [ 115.975020] postgres[1445]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-index_advisor> server # [ 115.981848] postgres[1445]: $$ language sql stable; vm-test-run-index_advisor> server # [ 115.992787] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 115.995000] postgres[1445]: ::1 2026-10-22 21:45:47.391 UTC [1445] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-index_advisor> server # [ 115.995704] postgres[1445]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-index_advisor> server # [ 116.005751] postgres[1445]: $$ language sql stable; vm-test-run-index_advisor> server # [ 116.011760] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 116.022065] postgres[1445]: ::1 2026-10-22 21:45:47.410 UTC [1445] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 116.026871] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT vm-test-run-index_advisor> server # [ 116.047575] postgres[1445]: ::1 2026-10-22 21:45:47.427 UTC [1445] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 116.053232] postgres[1445]: ::1 2026-10-22 21:45:47.437 UTC [1445] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.054020] postgres[1445]: ::1 2026-10-22 21:45:47.447 UTC [1445] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.071118] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE ROLE vm-test-run-index_advisor> server # [ 116.072392] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT vm-test-run-index_advisor> server # [ 116.085891] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT vm-test-run-index_advisor> server # [ 116.086813] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT vm-test-run-index_advisor> server # [ 116.087592] postgres[1445]: ::1 2026-10-22 21:45:47.468 UTC [1445] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.101751] postgres[1445]: ::1 2026-10-22 21:45:47.478 UTC [1445] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-index_advisor> server # [ 116.113702] postgres[1445]: ::1 2026-10-22 21:45:47.496 UTC [1445] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.114891] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER ROLE vm-test-run-index_advisor> server # [ 116.130402] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE vm-test-run-index_advisor> server # [ 116.132777] postgres[1445]: ::1 2026-10-22 21:45:47.532 UTC [1445] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.151579] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE vm-test-run-index_advisor> server # [ 116.153143] postgres[1445]: ::1 2026-10-22 21:45:47.553 UTC [1445] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.167450] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE vm-test-run-index_advisor> server # [ 116.174566] postgres[1445]: ::1 2026-10-22 21:45:47.565 UTC [1445] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.197738] postgres[1445]: ::1 2026-10-22 21:45:47.579 UTC [1445] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 116.206792] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE vm-test-run-index_advisor> server # [ 116.232466] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE vm-test-run-index_advisor> server # [ 116.276293] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-index_advisor> server # [ 116.615297] postgres[1468]: ::1 2026-10-22 21:45:47.991 UTC [1468] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-index_advisor> server # [ 116.618013] postgres[1468]: ::1 2026-10-22 21:45:48.001 UTC [1468] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 116.619088] postgres[1468]: ::1 2026-10-22 21:45:48.019 UTC [1468] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-index_advisor> server # [ 116.635630] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 116.649369] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: CREATE ROLE vm-test-run-index_advisor> server # [ 116.650192] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: ALTER ROLE vm-test-run-index_advisor> server # [ 116.650708] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: GRANT vm-test-run-index_advisor> server # [ 116.651829] postgres[1468]: ::1 2026-10-22 21:45:48.029 UTC [1468] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-index_advisor> server # [ 116.688994] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: DO vm-test-run-index_advisor> server # [ 116.737994] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-index_advisor> server # [ 117.072641] postgres[1473]: ::1 2026-10-22 21:45:48.465 UTC [1473] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-index_advisor> server # [ 117.092470] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: ALTER ROLE vm-test-run-index_advisor> server # [ 117.095116] postgres[1473]: ::1 2026-10-22 21:45:48.486 UTC [1473] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-index_advisor> server # [ 117.098684] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: ALTER ROLE vm-test-run-index_advisor> server # [ 117.099618] postgres[1473]: ::1 2026-10-22 21:45:48.500 UTC [1473] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-index_advisor> server # [ 117.101005] postgres[1473]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 117.101434] postgres[1473]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 117.101760] postgres[1473]: AS $$ vm-test-run-index_advisor> server # [ 117.102218] postgres[1473]: DECLARE vm-test-run-index_advisor> server # [ 117.102725] postgres[1473]: schema_is_cron bool; vm-test-run-index_advisor> server # [ 117.103199] postgres[1473]: BEGIN vm-test-run-index_advisor> server # [ 117.103454] postgres[1473]: schema_is_cron = ( vm-test-run-index_advisor> server # [ 117.104085] postgres[1473]: SELECT n.nspname = 'cron' vm-test-run-index_advisor> server # [ 117.106161] postgres[1473]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 117.106705] postgres[1473]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-index_advisor> server # [ 117.107110] postgres[1473]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 117.107965] postgres[1473]: ); vm-test-run-index_advisor> server # [ 117.108261] postgres[1473]: vm-test-run-index_advisor> server # [ 117.109105] postgres[1473]: IF schema_is_cron vm-test-run-index_advisor> server # [ 117.110144] postgres[1473]: THEN vm-test-run-index_advisor> server # [ 117.110449] postgres[1473]: grant usage on schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 117.111082] postgres[1473]: vm-test-run-index_advisor> server # [ 117.111332] postgres[1473]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 117.112155] postgres[1473]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 117.112905] postgres[1473]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 117.114104] postgres[1473]: vm-test-run-index_advisor> server # [ 117.114604] postgres[1473]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 117.115955] postgres[1473]: on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 117.117673] postgres[1473]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 117.118038] postgres[1473]: on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 117.119541] postgres[1473]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 117.120031] postgres[1473]: on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 117.121411] postgres[1473]: vm-test-run-index_advisor> server # [ 117.122178] postgres[1473]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 117.122784] postgres[1473]: vm-test-run-index_advisor> server # [ 117.123816] postgres[1473]: END IF; vm-test-run-index_advisor> server # [ 117.125221] postgres[1473]: vm-test-run-index_advisor> server # [ 117.125618] postgres[1473]: END; vm-test-run-index_advisor> server # [ 117.126504] postgres[1473]: $$; vm-test-run-index_advisor> server # [ 117.141744] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 117.147415] postgres[1473]: ::1 2026-10-22 21:45:48.545 UTC [1473] 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 # [ 117.155046] postgres[1473]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-index_advisor> server # [ 117.155837] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 117.161020] postgres[1473]: ::1 2026-10-22 21:45:48.557 UTC [1473] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-index_advisor> server # [ 117.168381] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: COMMENT vm-test-run-index_advisor> server # [ 117.173638] postgres[1473]: ::1 2026-10-22 21:45:48.568 UTC [1473] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-index_advisor> server # [ 117.178378] postgres[1473]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 117.178808] postgres[1473]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 117.179079] postgres[1473]: AS $$ vm-test-run-index_advisor> server # [ 117.183146] postgres[1473]: BEGIN vm-test-run-index_advisor> server # [ 117.184641] postgres[1473]: IF EXISTS ( vm-test-run-index_advisor> server # [ 117.189299] postgres[1473]: SELECT 1 vm-test-run-index_advisor> server # [ 117.190379] postgres[1473]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 117.191568] postgres[1473]: JOIN pg_extension AS ext vm-test-run-index_advisor> server # [ 117.198109] postgres[1473]: ON ev.objid = ext.oid vm-test-run-index_advisor> server # [ 117.199105] postgres[1473]: WHERE ext.extname = 'pg_net' vm-test-run-index_advisor> server # [ 117.199511] postgres[1473]: ) vm-test-run-index_advisor> server # [ 117.199814] postgres[1473]: THEN vm-test-run-index_advisor> server # [ 117.205310] postgres[1473]: IF NOT EXISTS ( vm-test-run-index_advisor> server # [ 117.206683] postgres[1473]: SELECT 1 vm-test-run-index_advisor> server # [ 117.207758] postgres[1473]: FROM pg_roles vm-test-run-index_advisor> server # [ 117.208061] postgres[1473]: WHERE rolname = 'supabase_functions_admin' vm-test-run-index_advisor> server # [ 117.210147] postgres[1473]: ) vm-test-run-index_advisor> server # [ 117.210719] postgres[1473]: THEN vm-test-run-index_advisor> server # [ 117.214484] postgres[1473]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-index_advisor> server # [ 117.215186] postgres[1473]: END IF; vm-test-run-index_advisor> server # [ 117.215903] postgres[1473]: vm-test-run-index_advisor> server # [ 117.217120] postgres[1473]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 117.222589] postgres[1473]: vm-test-run-index_advisor> server # [ 117.223213] postgres[1473]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-index_advisor> server # [ 117.237983] postgres[1473]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-index_advisor> server # [ 117.238913] postgres[1473]: vm-test-run-index_advisor> server # [ 117.239225] postgres[1473]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-index_advisor> server # [ 117.254967] postgres[1473]: 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 # [ 117.256087] postgres[1473]: vm-test-run-index_advisor> server # [ 117.262117] postgres[1473]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-index_advisor> server # [ 117.269003] postgres[1473]: 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 # [ 117.270326] postgres[1473]: vm-test-run-index_advisor> server # [ 117.271689] postgres[1473]: 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 # [ 117.281842] postgres[1473]: 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 # [ 117.283068] postgres[1473]: END IF; vm-test-run-index_advisor> server # [ 117.283636] postgres[1473]: END; vm-test-run-index_advisor> server # [ 117.284003] postgres[1473]: $$; vm-test-run-index_advisor> server # [ 117.290851] postgres[1473]: ::1 2026-10-22 21:45:48.579 UTC [1473] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-index_advisor> server # [ 117.298696] postgres[1473]: ::1 2026-10-22 21:45:48.615 UTC [1473] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-index_advisor> server # [ 117.299663] postgres[1473]: ::1 2026-10-22 21:45:48.636 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-index_advisor> server # [ 117.300145] postgres[1473]: ::1 2026-10-22 21:45:48.652 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 117.303830] postgres[1473]: ::1 2026-10-22 21:45:48.668 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 117.310634] postgres[1473]: ::1 2026-10-22 21:45:48.680 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 117.319811] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 117.325326] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: COMMENT vm-test-run-index_advisor> server # [ 117.325972] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: DO vm-test-run-index_advisor> server # [ 117.326267] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE ROLE vm-test-run-index_advisor> server # [ 117.326527] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.326824] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.327436] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.327725] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.327968] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.328205] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.334915] postgres[1473]: ::1 2026-10-22 21:45:48.696 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 117.341904] postgres[1473]: ::1 2026-10-22 21:45:48.707 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 117.342780] postgres[1473]: ::1 2026-10-22 21:45:48.716 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 117.345988] postgres[1473]: ::1 2026-10-22 21:45:48.727 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-index_advisor> server # [ 117.350150] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.350829] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.351245] postgres[1473]: ::1 2026-10-22 21:45:48.750 UTC [1473] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-index_advisor> server # [ 117.380970] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT vm-test-run-index_advisor> server # [ 117.398610] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: DO vm-test-run-index_advisor> server # [ 117.439506] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running pgbouncer auth schema... vm-test-run-index_advisor> server # [ 117.668650] postgres[1475]: ::1 2026-10-22 21:45:49.065 UTC [1475] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-index_advisor> server # [ 117.684204] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: CREATE ROLE vm-test-run-index_advisor> server # [ 117.685719] postgres[1475]: ::1 2026-10-22 21:45:49.082 UTC [1475] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-index_advisor> server # [ 117.690523] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: REVOKE vm-test-run-index_advisor> server # [ 117.694406] postgres[1475]: ::1 2026-10-22 21:45:49.093 UTC [1475] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-index_advisor> server # [ 117.733252] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 117.735824] postgres[1475]: ::1 2026-10-22 21:45:49.131 UTC [1475] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-index_advisor> server # [ 117.737821] postgres[1475]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-index_advisor> server # [ 117.738890] postgres[1475]: $$ vm-test-run-index_advisor> server # [ 117.739827] postgres[1475]: BEGIN vm-test-run-index_advisor> server # [ 117.740194] postgres[1475]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-index_advisor> server # [ 117.751731] postgres[1475]: vm-test-run-index_advisor> server # [ 117.754499] postgres[1475]: RETURN QUERY vm-test-run-index_advisor> server # [ 117.756604] postgres[1475]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-index_advisor> server # [ 117.757409] postgres[1475]: WHERE usename = p_usename; vm-test-run-index_advisor> server # [ 117.758749] postgres[1475]: END; vm-test-run-index_advisor> server # [ 117.759210] postgres[1475]: $$ LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 117.759823] postgres[1475]: SET search_path = '' vm-test-run-index_advisor> server # [ 117.762086] postgres[1475]: SECURITY DEFINER; vm-test-run-index_advisor> server # [ 117.763684] postgres[1475]: ::1 2026-10-22 21:45:49.149 UTC [1475] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-index_advisor> server # [ 117.769911] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 117.771165] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: REVOKE vm-test-run-index_advisor> server # [ 117.772019] postgres[1475]: ::1 2026-10-22 21:45:49.167 UTC [1475] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-index_advisor> server # [ 117.781206] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: GRANT vm-test-run-index_advisor> server # [ 117.819499] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running stat extension... vm-test-run-index_advisor> server # [ 118.180683] postgres[1480]: ::1 2026-10-22 21:45:49.573 UTC [1480] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-index_advisor> server # [ 118.195896] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1476]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-index_advisor> server # [ 118.229613] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1476]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 118.230954] postgres[1480]: ::1 2026-10-22 21:45:49.628 UTC [1480] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-index_advisor> server # [ 119.589444] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1476]: CREATE EXTENSION vm-test-run-index_advisor> server # [ 119.646180] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-index_advisor> server # [ 119.997349] postgres[1505]: ::1 2026-10-22 21:45:51.380 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-index_advisor> server # [ 120.019738] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.022891] postgres[1505]: ::1 2026-10-22 21:45:51.406 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 120.040460] postgres[1505]: ::1 2026-10-22 21:45:51.431 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 120.051653] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.052236] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.052803] postgres[1505]: ::1 2026-10-22 21:45:51.445 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 120.064132] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.069830] postgres[1505]: ::1 2026-10-22 21:45:51.467 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 120.080064] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.086524] postgres[1505]: ::1 2026-10-22 21:45:51.483 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 120.098280] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.109949] postgres[1505]: ::1 2026-10-22 21:45:51.499 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 120.111185] postgres[1505]: ::1 2026-10-22 21:45:51.503 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 120.114055] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.134325] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.149071] postgres[1505]: ::1 2026-10-22 21:45:51.539 UTC [1505] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-index_advisor> server # [ 120.213836] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT vm-test-run-index_advisor> server # [ 120.267780] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: DO vm-test-run-index_advisor> server # [ 120.271202] postgres[1505]: ::1 2026-10-22 21:45:51.664 UTC [1505] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-index_advisor> server # [ 120.286491] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: ALTER ROLE vm-test-run-index_advisor> server # [ 120.336902] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-index_advisor> server # [ 120.679794] postgres[1509]: ::1 2026-10-22 21:45:52.076 UTC [1509] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.698108] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.699140] postgres[1509]: ::1 2026-10-22 21:45:52.097 UTC [1509] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.709125] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.713601] postgres[1509]: ::1 2026-10-22 21:45:52.113 UTC [1509] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.726348] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.727230] postgres[1509]: ::1 2026-10-22 21:45:52.127 UTC [1509] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.736021] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE vm-test-run-index_advisor> server # [ 120.742043] postgres[1509]: ::1 2026-10-22 21:45:52.138 UTC [1509] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.748709] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE vm-test-run-index_advisor> server # [ 120.753057] postgres[1509]: ::1 2026-10-22 21:45:52.151 UTC [1509] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.758202] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE vm-test-run-index_advisor> server # [ 120.761711] postgres[1509]: ::1 2026-10-22 21:45:52.162 UTC [1509] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.771049] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE vm-test-run-index_advisor> server # [ 120.776966] postgres[1509]: ::1 2026-10-22 21:45:52.177 UTC [1509] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-index_advisor> server # [ 120.783180] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE vm-test-run-index_advisor> server # [ 120.787319] postgres[1509]: ::1 2026-10-22 21:45:52.188 UTC [1509] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-index_advisor> server # [ 120.798063] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.805969] postgres[1509]: ::1 2026-10-22 21:45:52.203 UTC [1509] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 120.814069] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.818664] postgres[1509]: ::1 2026-10-22 21:45:52.217 UTC [1509] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 120.829785] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.830833] postgres[1509]: ::1 2026-10-22 21:45:52.227 UTC [1509] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 120.842270] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT vm-test-run-index_advisor> server # [ 120.846568] postgres[1509]: ::1 2026-10-22 21:45:52.246 UTC [1509] 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 # [ 120.858279] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 120.862062] postgres[1509]: ::1 2026-10-22 21:45:52.261 UTC [1509] 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 # [ 120.869944] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 120.881818] postgres[1509]: ::1 2026-10-22 21:45:52.274 UTC [1509] 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 # [ 120.886870] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 120.932943] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-index_advisor> server # [ 121.172659] postgres[1511]: ::1 2026-10-22 21:45:52.567 UTC [1511] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-index_advisor> server # [ 121.195753] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1510]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 121.224852] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-index_advisor> server # [ 121.475520] postgres[1513]: ::1 2026-10-22 21:45:52.869 UTC [1513] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-index_advisor> server # [ 121.497909] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT vm-test-run-index_advisor> server # [ 121.499083] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT vm-test-run-index_advisor> server # [ 121.499850] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT vm-test-run-index_advisor> server # [ 121.514439] postgres[1513]: ::1 2026-10-22 21:45:52.890 UTC [1513] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 121.520142] postgres[1513]: ::1 2026-10-22 21:45:52.895 UTC [1513] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 121.545966] postgres[1513]: ::1 2026-10-22 21:45:52.900 UTC [1513] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-index_advisor> server # [ 121.550779] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT vm-test-run-index_advisor> server # [ 121.555513] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-index_advisor> server # [ 121.991466] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1514]: DO vm-test-run-index_advisor> server # [ 122.005032] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1514]: DO vm-test-run-index_advisor> server # [ 122.033496] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1514]: DO vm-test-run-index_advisor> server # [ 122.083478] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-index_advisor> server # [ 122.571291] postgres[1532]: ::1 2026-10-22 21:45:53.955 UTC [1532] 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 # [ 122.624868] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 122.632151] postgres[1532]: ::1 2026-10-22 21:45:54.003 UTC [1532] 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 # [ 122.639860] postgres[1532]: ::1 2026-10-22 21:45:54.024 UTC [1532] 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 # [ 122.650663] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 122.652484] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 122.714110] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-index_advisor> server # [ 123.070222] postgres[1543]: ::1 2026-10-22 21:45:54.464 UTC [1543] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-index_advisor> server # [ 123.137640] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1538]: ALTER ROLE vm-test-run-index_advisor> server # [ 123.147393] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-index_advisor> server # [ 123.369622] postgres[1545]: ::1 2026-10-22 21:45:54.753 UTC [1545] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-index_advisor> server # [ 123.371147] postgres[1545]: ::1 2026-10-22 21:45:54.760 UTC [1545] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-index_advisor> server # [ 123.372398] postgres[1545]: ::1 2026-10-22 21:45:54.765 UTC [1545] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 123.379287] postgres[1545]: DECLARE vm-test-run-index_advisor> server # [ 123.379937] postgres[1545]: cmd record; vm-test-run-index_advisor> server # [ 123.383293] postgres[1545]: BEGIN vm-test-run-index_advisor> server # [ 123.383829] postgres[1545]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-index_advisor> server # [ 123.392994] postgres[1545]: LOOP vm-test-run-index_advisor> server # [ 123.393737] postgres[1545]: IF cmd.command_tag IN ( vm-test-run-index_advisor> server # [ 123.394747] postgres[1545]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-index_advisor> server # [ 123.395477] postgres[1545]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-index_advisor> server # [ 123.396095] postgres[1545]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-index_advisor> server # [ 123.400238] postgres[1545]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-index_advisor> server # [ 123.406677] postgres[1545]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-index_advisor> server # [ 123.407166] postgres[1545]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-index_advisor> server # [ 123.407618] postgres[1545]: , 'CREATE TRIGGER' vm-test-run-index_advisor> server # [ 123.408024] postgres[1545]: , 'CREATE TYPE' vm-test-run-index_advisor> server # [ 123.417164] postgres[1545]: , 'CREATE RULE' vm-test-run-index_advisor> server # [ 123.418101] postgres[1545]: , 'COMMENT' vm-test-run-index_advisor> server # [ 123.419340] postgres[1545]: ) vm-test-run-index_advisor> server # [ 123.419940] postgres[1545]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-index_advisor> server # [ 123.422309] postgres[1545]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-index_advisor> server # [ 123.422860] postgres[1545]: THEN vm-test-run-index_advisor> server # [ 123.428936] postgres[1545]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 123.429856] postgres[1545]: END IF; vm-test-run-index_advisor> server # [ 123.430365] postgres[1545]: END LOOP; vm-test-run-index_advisor> server # [ 123.430673] postgres[1545]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 123.434759] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-index_advisor> server # [ 123.435782] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 123.442419] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-index_advisor> server # [ 123.446418] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP FUNCTION vm-test-run-index_advisor> server # [ 123.448068] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 123.449305] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 123.449891] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-index_advisor> server # [ 123.450598] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 123.451614] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 123.453746] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-index_advisor> server # [ 123.454760] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 123.455467] postgres[1545]: ::1 2026-10-22 21:45:54.787 UTC [1545] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 123.456003] postgres[1545]: DECLARE vm-test-run-index_advisor> server # [ 123.456372] postgres[1545]: obj record; vm-test-run-index_advisor> server # [ 123.456623] postgres[1545]: BEGIN vm-test-run-index_advisor> server # [ 123.457935] postgres[1545]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-index_advisor> server # [ 123.458674] postgres[1545]: LOOP vm-test-run-index_advisor> server # [ 123.459373] postgres[1545]: IF obj.object_type IN ( vm-test-run-index_advisor> server # [ 123.459921] postgres[1545]: 'schema' vm-test-run-index_advisor> server # [ 123.462351] postgres[1545]: , 'table' vm-test-run-index_advisor> server # [ 123.462835] postgres[1545]: , 'foreign table' vm-test-run-index_advisor> server # [ 123.463219] postgres[1545]: , 'view' vm-test-run-index_advisor> server # [ 123.463479] postgres[1545]: , 'materialized view' vm-test-run-index_advisor> server # [ 123.466032] postgres[1545]: , 'function' vm-test-run-index_advisor> server # [ 123.468024] postgres[1545]: , 'trigger' vm-test-run-index_advisor> server # [ 123.470314] postgres[1545]: , 'type' vm-test-run-index_advisor> server # [ 123.471742] postgres[1545]: , 'rule' vm-test-run-index_advisor> server # [ 123.473090] postgres[1545]: ) vm-test-run-index_advisor> server # [ 123.473952] postgres[1545]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-index_advisor> server # [ 123.474548] postgres[1545]: THEN vm-test-run-index_advisor> server # [ 123.475003] postgres[1545]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 123.475280] postgres[1545]: END IF; vm-test-run-index_advisor> server # [ 123.475658] postgres[1545]: END LOOP; vm-test-run-index_advisor> server # [ 123.475951] postgres[1545]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 123.476736] postgres[1545]: ::1 2026-10-22 21:45:54.804 UTC [1545] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-index_advisor> server # [ 123.477271] postgres[1545]: ::1 2026-10-22 21:45:54.812 UTC [1545] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-index_advisor> server # [ 123.478877] postgres[1545]: ON ddl_command_end vm-test-run-index_advisor> server # [ 123.480821] postgres[1545]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-index_advisor> server # [ 123.481182] postgres[1545]: ::1 2026-10-22 21:45:54.826 UTC [1545] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-index_advisor> server # [ 123.489575] postgres[1545]: ::1 2026-10-22 21:45:54.839 UTC [1545] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-index_advisor> server # [ 123.490676] postgres[1545]: ON sql_drop vm-test-run-index_advisor> server # [ 123.503227] postgres[1545]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-index_advisor> server # [ 123.511298] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 123.518869] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-index_advisor> server # [ 124.457486] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1547]: DO vm-test-run-index_advisor> server # [ 124.506695] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-index_advisor> server # [ 124.760957] postgres[1553]: ::1 2026-10-22 21:45:56.151 UTC [1553] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-index_advisor> server # [ 124.789724] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE SCHEMA vm-test-run-index_advisor> server # [ 124.793289] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping vm-test-run-index_advisor> server # [ 124.802642] postgres[1553]: ::1 2026-10-22 21:45:56.188 UTC [1553] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-index_advisor> server # [ 124.807024] postgres[1553]: ::1 2026-10-22 21:45:56.198 UTC [1553] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 124.808034] postgres[1553]: "operationName" text default null, vm-test-run-index_advisor> server # [ 124.808400] postgres[1553]: query text default null, vm-test-run-index_advisor> server # [ 124.810755] postgres[1553]: variables jsonb default null, vm-test-run-index_advisor> server # [ 124.811735] postgres[1553]: extensions jsonb default null vm-test-run-index_advisor> server # [ 124.812134] postgres[1553]: ) vm-test-run-index_advisor> server # [ 124.812378] postgres[1553]: returns jsonb vm-test-run-index_advisor> server # [ 124.814236] postgres[1553]: language plpgsql vm-test-run-index_advisor> server # [ 124.815601] postgres[1553]: as $$ vm-test-run-index_advisor> server # [ 124.817067] postgres[1553]: DECLARE vm-test-run-index_advisor> server # [ 124.818855] postgres[1553]: server_version float; vm-test-run-index_advisor> server # [ 124.819328] postgres[1553]: BEGIN vm-test-run-index_advisor> server # [ 124.821205] postgres[1553]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 124.821920] postgres[1553]: vm-test-run-index_advisor> server # [ 124.822330] postgres[1553]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 124.823398] postgres[1553]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 124.824800] postgres[1553]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 124.825562] postgres[1553]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-index_advisor> server # [ 124.826282] postgres[1553]: ); vm-test-run-index_advisor> server # [ 124.826578] postgres[1553]: ELSE vm-test-run-index_advisor> server # [ 124.826819] postgres[1553]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 124.827344] postgres[1553]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 124.829206] postgres[1553]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-index_advisor> server # [ 124.830688] postgres[1553]: ); vm-test-run-index_advisor> server # [ 124.831997] postgres[1553]: END IF; vm-test-run-index_advisor> server # [ 124.833129] postgres[1553]: END; vm-test-run-index_advisor> server # [ 124.833728] postgres[1553]: $$; vm-test-run-index_advisor> server # [ 124.834905] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: DROP FUNCTION vm-test-run-index_advisor> server # [ 124.841909] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 124.854603] postgres[1553]: ::1 2026-10-22 21:45:56.247 UTC [1553] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.882380] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: GRANT vm-test-run-index_advisor> server # [ 124.890287] postgres[1553]: ::1 2026-10-22 21:45:56.279 UTC [1553] 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.917607] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.931690] postgres[1553]: ::1 2026-10-22 21:45:56.322 UTC [1553] 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.945945] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 124.966224] postgres[1553]: ::1 2026-10-22 21:45:56.343 UTC [1553] 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.985925] postgres[1553]: ::1 2026-10-22 21:45:56.370 UTC [1553] 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.994377] postgres[1553]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 124.994903] postgres[1553]: ::1 2026-10-22 21:45:56.384 UTC [1553] 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.995962] postgres[1553]: on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.006311] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 125.013522] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 125.015635] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 125.019013] postgres[1553]: ::1 2026-10-22 21:45:56.411 UTC [1553] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-index_advisor> server # [ 125.026739] postgres[1553]: on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.027819] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 125.034434] postgres[1553]: ::1 2026-10-22 21:45:56.431 UTC [1553] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 125.035593] postgres[1553]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 125.036787] postgres[1553]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 125.038107] postgres[1553]: AS $func$ vm-test-run-index_advisor> server # [ 125.039191] postgres[1553]: DECLARE vm-test-run-index_advisor> server # [ 125.046897] postgres[1553]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 125.049447] postgres[1553]: BEGIN vm-test-run-index_advisor> server # [ 125.050286] postgres[1553]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 125.050830] postgres[1553]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 125.051123] postgres[1553]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 125.051943] postgres[1553]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 125.052270] postgres[1553]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 125.066481] postgres[1553]: ); vm-test-run-index_advisor> server # [ 125.066919] postgres[1553]: vm-test-run-index_advisor> server # [ 125.067364] postgres[1553]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 125.067652] postgres[1553]: THEN vm-test-run-index_advisor> server # [ 125.068108] postgres[1553]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.069741] postgres[1553]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.070634] postgres[1553]: vm-test-run-index_advisor> server # [ 125.072679] postgres[1553]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.074276] postgres[1553]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.075910] postgres[1553]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.085761] postgres[1553]: vm-test-run-index_advisor> server # [ 125.086895] postgres[1553]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 125.087948] postgres[1553]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 125.089806] postgres[1553]: "operationName" text default null, vm-test-run-index_advisor> server # [ 125.090289] postgres[1553]: query text default null, vm-test-run-index_advisor> server # [ 125.090846] postgres[1553]: variables jsonb default null, vm-test-run-index_advisor> server # [ 125.091534] postgres[1553]: extensions jsonb default null vm-test-run-index_advisor> server # [ 125.097992] postgres[1553]: ) vm-test-run-index_advisor> server # [ 125.098838] postgres[1553]: returns jsonb vm-test-run-index_advisor> server # [ 125.099983] postgres[1553]: language sql vm-test-run-index_advisor> server # [ 125.106638] postgres[1553]: as $$ vm-test-run-index_advisor> server # [ 125.107825] postgres[1553]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-index_advisor> server # [ 125.109577] postgres[1553]: $$; vm-test-run-index_advisor> server # [ 125.109965] postgres[1553]: vm-test-run-index_advisor> server # [ 125.110210] postgres[1553]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 125.110632] postgres[1553]: END IF; vm-test-run-index_advisor> server # [ 125.111104] postgres[1553]: vm-test-run-index_advisor> server # [ 125.111702] postgres[1553]: END; vm-test-run-index_advisor> server # [ 125.117877] postgres[1553]: $func$; vm-test-run-index_advisor> server # [ 125.118489] postgres[1553]: ::1 2026-10-22 21:45:56.454 UTC [1553] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-index_advisor> server # [ 125.119344] postgres[1553]: ::1 2026-10-22 21:45:56.467 UTC [1553] 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 # [ 125.125314] postgres[1553]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-index_advisor> server # [ 125.126623] postgres[1553]: ::1 2026-10-22 21:45:56.482 UTC [1553] 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 # [ 125.128813] postgres[1553]: ::1 2026-10-22 21:45:56.500 UTC [1553] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-index_advisor> server # [ 125.137685] postgres[1553]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 125.138118] postgres[1553]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 125.138359] postgres[1553]: AS $func$ vm-test-run-index_advisor> server # [ 125.138628] postgres[1553]: DECLARE vm-test-run-index_advisor> server # [ 125.139028] postgres[1553]: graphql_is_dropped bool; vm-test-run-index_advisor> server # [ 125.141582] postgres[1553]: BEGIN vm-test-run-index_advisor> server # [ 125.142480] postgres[1553]: graphql_is_dropped = ( vm-test-run-index_advisor> server # [ 125.142882] postgres[1553]: SELECT ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 125.143330] postgres[1553]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-index_advisor> server # [ 125.144005] postgres[1553]: WHERE ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 125.154005] postgres[1553]: ); vm-test-run-index_advisor> server # [ 125.154601] postgres[1553]: vm-test-run-index_advisor> server # [ 125.155779] postgres[1553]: IF graphql_is_dropped vm-test-run-index_advisor> server # [ 125.156462] postgres[1553]: THEN vm-test-run-index_advisor> server # [ 125.157861] postgres[1553]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 125.158810] postgres[1553]: "operationName" text default null, vm-test-run-index_advisor> server # [ 125.159367] postgres[1553]: query text default null, vm-test-run-index_advisor> server # [ 125.159762] postgres[1553]: variables jsonb default null, vm-test-run-index_advisor> server # [ 125.160062] postgres[1553]: extensions jsonb default null vm-test-run-index_advisor> server # [ 125.161394] postgres[1553]: ) vm-test-run-index_advisor> server # [ 125.161844] postgres[1553]: returns jsonb vm-test-run-index_advisor> server # [ 125.162657] postgres[1553]: language plpgsql vm-test-run-index_advisor> server # [ 125.163531] postgres[1553]: as $$ vm-test-run-index_advisor> server # [ 125.167313] postgres[1553]: DECLARE vm-test-run-index_advisor> server # [ 125.167969] postgres[1553]: server_version float; vm-test-run-index_advisor> server # [ 125.174462] postgres[1553]: BEGIN vm-test-run-index_advisor> server # [ 125.175110] postgres[1553]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 125.181403] postgres[1553]: vm-test-run-index_advisor> server # [ 125.182884] postgres[1553]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 125.183259] postgres[1553]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 125.183785] postgres[1553]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 125.184361] postgres[1553]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-index_advisor> server # [ 125.189832] postgres[1553]: ); vm-test-run-index_advisor> server # [ 125.190758] postgres[1553]: ELSE vm-test-run-index_advisor> server # [ 125.191424] postgres[1553]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 125.197834] postgres[1553]: 'data', null::jsonb, vm-test-run-index_advisor> server # [ 125.198583] postgres[1553]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-index_advisor> server # [ 125.199725] postgres[1553]: ); vm-test-run-index_advisor> server # [ 125.200044] postgres[1553]: END IF; vm-test-run-index_advisor> server # [ 125.200285] postgres[1553]: END; vm-test-run-index_advisor> server # [ 125.206503] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 125.208365] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-index_advisor> server # [ 125.210316] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 125.210672] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 125.211500] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: COMMENT vm-test-run-index_advisor> server # [ 125.227592] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 125.241100] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-index_advisor> server # [ 125.241878] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 125.242545] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 125.243735] postgres[1553]: $$; vm-test-run-index_advisor> server # [ 125.258176] postgres[1553]: END IF; vm-test-run-index_advisor> server # [ 125.274764] postgres[1553]: vm-test-run-index_advisor> server # [ 125.279171] postgres[1553]: END; vm-test-run-index_advisor> server # [ 125.279873] postgres[1553]: $func$; vm-test-run-index_advisor> server # [ 125.290875] postgres[1553]: ::1 2026-10-22 21:45:56.577 UTC [1553] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-index_advisor> server # [ 125.293651] postgres[1553]: ::1 2026-10-22 21:45:56.591 UTC [1553] 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 # [ 125.295147] postgres[1553]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-index_advisor> server # [ 125.296066] postgres[1553]: ::1 2026-10-22 21:45:56.601 UTC [1553] 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 # [ 125.303820] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: COMMENT vm-test-run-index_advisor> server # [ 125.310010] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-index_advisor> server # [ 125.768873] postgres[1571]: ::1 2026-10-22 21:45:57.148 UTC [1571] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-index_advisor> server # [ 125.770035] postgres[1571]: ::1 2026-10-22 21:45:57.163 UTC [1571] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-index_advisor> server # [ 125.791260] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-index_advisor> server # [ 125.794438] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 125.795791] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-index_advisor> server # [ 125.809552] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP FUNCTION vm-test-run-index_advisor> server # [ 125.810443] postgres[1571]: ::1 2026-10-22 21:45:57.186 UTC [1571] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 125.826945] postgres[1571]: DECLARE vm-test-run-index_advisor> server # [ 125.828174] postgres[1571]: cmd record; vm-test-run-index_advisor> server # [ 125.833548] postgres[1571]: BEGIN vm-test-run-index_advisor> server # [ 125.834151] postgres[1571]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-index_advisor> server # [ 125.834695] postgres[1571]: LOOP vm-test-run-index_advisor> server # [ 125.835006] postgres[1571]: IF cmd.command_tag IN ( vm-test-run-index_advisor> server # [ 125.835474] postgres[1571]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-index_advisor> server # [ 125.835795] postgres[1571]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-index_advisor> server # [ 125.845268] postgres[1571]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-index_advisor> server # [ 125.853159] postgres[1571]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-index_advisor> server # [ 125.853949] postgres[1571]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-index_advisor> server # [ 125.855268] postgres[1571]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-index_advisor> server # [ 125.862368] postgres[1571]: , 'CREATE TRIGGER' vm-test-run-index_advisor> server # [ 125.879365] postgres[1571]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-index_advisor> server # [ 125.879865] postgres[1571]: , 'CREATE RULE' vm-test-run-index_advisor> server # [ 125.880151] postgres[1571]: , 'COMMENT' vm-test-run-index_advisor> server # [ 125.883910] postgres[1571]: ) vm-test-run-index_advisor> server # [ 125.885644] postgres[1571]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-index_advisor> server # [ 125.893406] postgres[1571]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-index_advisor> server # [ 125.894685] postgres[1571]: THEN vm-test-run-index_advisor> server # [ 125.895054] postgres[1571]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 125.895458] postgres[1571]: END IF; vm-test-run-index_advisor> server # [ 125.895816] postgres[1571]: END LOOP; vm-test-run-index_advisor> server # [ 125.906328] postgres[1571]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 125.914938] postgres[1571]: ::1 2026-10-22 21:45:57.263 UTC [1571] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-index_advisor> server # [ 125.915798] postgres[1571]: DECLARE vm-test-run-index_advisor> server # [ 125.920172] postgres[1571]: obj record; vm-test-run-index_advisor> server # [ 125.927799] postgres[1571]: BEGIN vm-test-run-index_advisor> server # [ 125.933951] postgres[1571]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-index_advisor> server # [ 125.940746] postgres[1571]: LOOP vm-test-run-index_advisor> server # [ 125.942249] postgres[1571]: IF obj.object_type IN ( vm-test-run-index_advisor> server # [ 125.943727] postgres[1571]: 'schema' vm-test-run-index_advisor> server # [ 125.955280] postgres[1571]: , 'table' vm-test-run-index_advisor> server # [ 125.956876] postgres[1571]: , 'foreign table' vm-test-run-index_advisor> server # [ 125.958177] postgres[1571]: , 'view' vm-test-run-index_advisor> server # [ 125.958763] postgres[1571]: , 'materialized view' vm-test-run-index_advisor> server # [ 125.959643] postgres[1571]: , 'function' vm-test-run-index_advisor> server # [ 125.978284] postgres[1571]: , 'trigger' vm-test-run-index_advisor> server # [ 125.979723] postgres[1571]: , 'type' vm-test-run-index_advisor> server # [ 125.990308] postgres[1571]: , 'rule' vm-test-run-index_advisor> server # [ 125.991537] postgres[1571]: ) vm-test-run-index_advisor> server # [ 125.991928] postgres[1571]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-index_advisor> server # [ 126.006452] postgres[1571]: THEN vm-test-run-index_advisor> server # [ 126.006933] postgres[1571]: NOTIFY pgrst, 'reload schema'; vm-test-run-index_advisor> server # [ 126.007244] postgres[1571]: END IF; vm-test-run-index_advisor> server # [ 126.007493] postgres[1571]: END LOOP; vm-test-run-index_advisor> server # [ 126.007727] postgres[1571]: END; $$ LANGUAGE plpgsql; vm-test-run-index_advisor> server # [ 126.007972] postgres[1571]: ::1 2026-10-22 21:45:57.298 UTC [1571] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-index_advisor> server # [ 126.051578] postgres[1571]: ::1 2026-10-22 21:45:57.315 UTC [1571] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-index_advisor> server # [ 126.059451] postgres[1571]: ON ddl_command_end vm-test-run-index_advisor> server # [ 126.063839] postgres[1571]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-index_advisor> server # [ 126.070074] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 126.070767] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 126.071991] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 126.091202] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 126.098156] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 126.099766] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 126.109979] postgres[1571]: ::1 2026-10-22 21:45:57.331 UTC [1571] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-index_advisor> server # [ 126.111153] postgres[1571]: ::1 2026-10-22 21:45:57.339 UTC [1571] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-index_advisor> server # [ 126.121188] postgres[1571]: ON sql_drop vm-test-run-index_advisor> server # [ 126.122456] postgres[1571]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-index_advisor> server # [ 126.133944] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-index_advisor> server # [ 126.460122] postgres[1579]: ::1 2026-10-22 21:45:57.833 UTC [1579] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-index_advisor> server # [ 126.479668] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1574]: ALTER ROLE vm-test-run-index_advisor> server # [ 126.510597] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-index_advisor> server # [ 126.770865] postgres[1583]: ::1 2026-10-22 21:45:58.161 UTC [1583] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 126.776829] postgres[1583]: returns event_trigger vm-test-run-index_advisor> server # [ 126.778108] postgres[1583]: language plpgsql vm-test-run-index_advisor> server # [ 126.778654] postgres[1583]: AS $func$ vm-test-run-index_advisor> server # [ 126.779616] postgres[1583]: DECLARE vm-test-run-index_advisor> server # [ 126.779954] postgres[1583]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 126.786958] postgres[1583]: BEGIN vm-test-run-index_advisor> server # [ 126.788358] postgres[1583]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 126.794262] postgres[1583]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 126.794905] postgres[1583]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 126.795572] postgres[1583]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 126.802037] postgres[1583]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 126.802637] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.803042] postgres[1583]: vm-test-run-index_advisor> server # [ 126.803317] postgres[1583]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 126.803576] postgres[1583]: THEN vm-test-run-index_advisor> server # [ 126.803808] postgres[1583]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 126.808008] postgres[1583]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 126.809449] postgres[1583]: vm-test-run-index_advisor> server # [ 126.810352] postgres[1583]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 126.811592] postgres[1583]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 126.818111] postgres[1583]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 126.819060] postgres[1583]: vm-test-run-index_advisor> server # [ 126.820027] postgres[1583]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 126.822649] postgres[1583]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 126.823075] postgres[1583]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 126.823709] postgres[1583]: "operationName" text default null, vm-test-run-index_advisor> server # [ 126.828803] postgres[1583]: query text default null, vm-test-run-index_advisor> server # [ 126.830317] postgres[1583]: variables jsonb default null, vm-test-run-index_advisor> server # [ 126.830782] postgres[1583]: extensions jsonb default null vm-test-run-index_advisor> server # [ 126.831099] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.831618] postgres[1583]: returns jsonb vm-test-run-index_advisor> server # [ 126.832097] postgres[1583]: language sql vm-test-run-index_advisor> server # [ 126.837751] postgres[1583]: as $$ vm-test-run-index_advisor> server # [ 126.838504] postgres[1583]: -- This changed vm-test-run-index_advisor> server # [ 126.839156] postgres[1583]: select graphql.resolve( vm-test-run-index_advisor> server # [ 126.839845] postgres[1583]: query := query, vm-test-run-index_advisor> server # [ 126.843889] postgres[1583]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 126.844534] postgres[1583]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 126.850885] postgres[1583]: extensions := extensions vm-test-run-index_advisor> server # [ 126.852795] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.853284] postgres[1583]: $$; vm-test-run-index_advisor> server # [ 126.855022] postgres[1583]: vm-test-run-index_advisor> server # [ 126.855674] postgres[1583]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 126.857581] postgres[1583]: END IF; vm-test-run-index_advisor> server # [ 126.858431] postgres[1583]: vm-test-run-index_advisor> server # [ 126.859217] postgres[1583]: END; vm-test-run-index_advisor> server # [ 126.860311] postgres[1583]: $func$; vm-test-run-index_advisor> server # [ 126.862412] postgres[1583]: ::1 2026-10-22 21:45:58.259 UTC [1583] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-index_advisor> server # [ 126.864096] postgres[1583]: RETURNS event_trigger vm-test-run-index_advisor> server # [ 126.865278] postgres[1583]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 126.866161] postgres[1583]: AS $func$ vm-test-run-index_advisor> server # [ 126.866521] postgres[1583]: DECLARE vm-test-run-index_advisor> server # [ 126.866812] postgres[1583]: graphql_is_dropped bool; vm-test-run-index_advisor> server # [ 126.867774] postgres[1583]: BEGIN vm-test-run-index_advisor> server # [ 126.869852] postgres[1583]: graphql_is_dropped = ( vm-test-run-index_advisor> server # [ 126.871238] postgres[1583]: SELECT ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 126.871622] postgres[1583]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-index_advisor> server # [ 126.874232] postgres[1583]: WHERE ev.schema_name = 'graphql_public' vm-test-run-index_advisor> server # [ 126.875608] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.875933] postgres[1583]: vm-test-run-index_advisor> server # [ 126.877609] postgres[1583]: IF graphql_is_dropped vm-test-run-index_advisor> server # [ 126.878591] postgres[1583]: THEN vm-test-run-index_advisor> server # [ 126.878878] postgres[1583]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 126.880163] postgres[1583]: "operationName" text default null, vm-test-run-index_advisor> server # [ 126.882038] postgres[1583]: query text default null, vm-test-run-index_advisor> server # [ 126.882826] postgres[1583]: variables jsonb default null, vm-test-run-index_advisor> server # [ 126.884091] postgres[1583]: extensions jsonb default null vm-test-run-index_advisor> server # [ 126.885762] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.886522] postgres[1583]: returns jsonb vm-test-run-index_advisor> server # [ 126.886881] postgres[1583]: language plpgsql vm-test-run-index_advisor> server # [ 126.889051] postgres[1583]: as $$ vm-test-run-index_advisor> server # [ 126.889737] postgres[1583]: DECLARE vm-test-run-index_advisor> server # [ 126.890057] postgres[1583]: server_version float; vm-test-run-index_advisor> server # [ 126.890334] postgres[1583]: BEGIN vm-test-run-index_advisor> server # [ 126.890597] postgres[1583]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 126.890887] postgres[1583]: vm-test-run-index_advisor> server # [ 126.891101] postgres[1583]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 126.891340] postgres[1583]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 126.891583] postgres[1583]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 126.891842] postgres[1583]: jsonb_build_object( vm-test-run-index_advisor> server # [ 126.892088] postgres[1583]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-index_advisor> server # [ 126.892679] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.893550] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.893872] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.894945] postgres[1583]: ELSE vm-test-run-index_advisor> server # [ 126.895485] postgres[1583]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 126.896147] postgres[1583]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 126.898085] postgres[1583]: jsonb_build_object( vm-test-run-index_advisor> server # [ 126.898918] postgres[1583]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-index_advisor> server # [ 126.900224] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.901721] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.902323] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.902588] postgres[1583]: END IF; vm-test-run-index_advisor> server # [ 126.902821] postgres[1583]: END; vm-test-run-index_advisor> server # [ 126.903038] postgres[1583]: $$; vm-test-run-index_advisor> server # [ 126.903248] postgres[1583]: END IF; vm-test-run-index_advisor> server # [ 126.903456] postgres[1583]: vm-test-run-index_advisor> server # [ 126.903660] postgres[1583]: END; vm-test-run-index_advisor> server # [ 126.904149] postgres[1583]: $func$; vm-test-run-index_advisor> server # [ 126.909316] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 126.915768] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 126.918261] postgres[1583]: ::1 2026-10-22 21:45:58.319 UTC [1583] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 126.919961] postgres[1583]: "operationName" text default null, vm-test-run-index_advisor> server # [ 126.925242] postgres[1583]: query text default null, vm-test-run-index_advisor> server # [ 126.926250] postgres[1583]: variables jsonb default null, vm-test-run-index_advisor> server # [ 126.926770] postgres[1583]: extensions jsonb default null vm-test-run-index_advisor> server # [ 126.927333] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.928228] postgres[1583]: returns jsonb vm-test-run-index_advisor> server # [ 126.929767] postgres[1583]: language plpgsql vm-test-run-index_advisor> server # [ 126.930076] postgres[1583]: as $$ vm-test-run-index_advisor> server # [ 126.930298] postgres[1583]: DECLARE vm-test-run-index_advisor> server # [ 126.930513] postgres[1583]: server_version float; vm-test-run-index_advisor> server # [ 126.930734] postgres[1583]: BEGIN vm-test-run-index_advisor> server # [ 126.930940] postgres[1583]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-index_advisor> server # [ 126.931833] postgres[1583]: vm-test-run-index_advisor> server # [ 126.932588] postgres[1583]: IF server_version >= 14 THEN vm-test-run-index_advisor> server # [ 126.932883] postgres[1583]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 126.933133] postgres[1583]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 126.933618] postgres[1583]: jsonb_build_object( vm-test-run-index_advisor> server # [ 126.934249] postgres[1583]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-index_advisor> server # [ 126.935064] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.935334] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.935553] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.935761] postgres[1583]: ELSE vm-test-run-index_advisor> server # [ 126.935961] postgres[1583]: RETURN jsonb_build_object( vm-test-run-index_advisor> server # [ 126.936185] postgres[1583]: 'errors', jsonb_build_array( vm-test-run-index_advisor> server # [ 126.936427] postgres[1583]: jsonb_build_object( vm-test-run-index_advisor> server # [ 126.936667] postgres[1583]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-index_advisor> server # [ 126.936931] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.937133] postgres[1583]: ) vm-test-run-index_advisor> server # [ 126.937341] postgres[1583]: ); vm-test-run-index_advisor> server # [ 126.939179] postgres[1583]: END IF; vm-test-run-index_advisor> server # [ 126.939724] postgres[1583]: END; vm-test-run-index_advisor> server # [ 126.941747] postgres[1583]: $$; vm-test-run-index_advisor> server # [ 126.951666] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 126.955888] postgres[1583]: ::1 2026-10-22 21:45:58.355 UTC [1583] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 126.957438] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-index_advisor> server # [ 126.966051] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: DROP EXTENSION vm-test-run-index_advisor> server # [ 128.970272] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: DO vm-test-run-index_advisor> server # [ 128.975734] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-index_advisor> server # [ 129.347897] postgres[1610]: ::1 2026-10-22 21:46:00.730 UTC [1610] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-index_advisor> server # [ 129.390719] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1602]: GRANT ROLE vm-test-run-index_advisor> server # [ 129.391792] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-index_advisor> server # [ 129.896981] postgres[1615]: ::1 2026-10-22 21:46:01.287 UTC [1615] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 129.899196] postgres[1615]: returns event_trigger vm-test-run-index_advisor> server # [ 129.899717] postgres[1615]: language plpgsql vm-test-run-index_advisor> server # [ 129.909159] postgres[1615]: AS $func$ vm-test-run-index_advisor> server # [ 129.914780] postgres[1615]: DECLARE vm-test-run-index_advisor> server # [ 129.915334] postgres[1615]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 129.915855] postgres[1615]: BEGIN vm-test-run-index_advisor> server # [ 129.916134] postgres[1615]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 129.922044] postgres[1615]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 129.922642] postgres[1615]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 129.922981] postgres[1615]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 129.923792] postgres[1615]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 129.924296] postgres[1615]: ); vm-test-run-index_advisor> server # [ 129.933425] postgres[1615]: vm-test-run-index_advisor> server # [ 129.934263] postgres[1615]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 129.934547] postgres[1615]: THEN vm-test-run-index_advisor> server # [ 129.935124] postgres[1615]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 129.941006] postgres[1615]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 129.945784] postgres[1615]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 129.947287] postgres[1615]: "operationName" text default null, vm-test-run-index_advisor> server # [ 129.953401] postgres[1615]: query text default null, vm-test-run-index_advisor> server # [ 129.954304] postgres[1615]: variables jsonb default null, vm-test-run-index_advisor> server # [ 129.955904] postgres[1615]: extensions jsonb default null vm-test-run-index_advisor> server # [ 129.961686] postgres[1615]: ) vm-test-run-index_advisor> server # [ 129.962261] postgres[1615]: returns jsonb vm-test-run-index_advisor> server # [ 129.963117] postgres[1615]: language sql vm-test-run-index_advisor> server # [ 129.963561] postgres[1615]: as $$ vm-test-run-index_advisor> server # [ 129.963846] postgres[1615]: select graphql.resolve( vm-test-run-index_advisor> server # [ 129.964315] postgres[1615]: query := query, vm-test-run-index_advisor> server # [ 129.969415] postgres[1615]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 129.970372] postgres[1615]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 129.970917] postgres[1615]: extensions := extensions vm-test-run-index_advisor> server # [ 129.976956] postgres[1615]: ); vm-test-run-index_advisor> server # [ 129.978404] postgres[1615]: $$; vm-test-run-index_advisor> server # [ 129.978988] postgres[1615]: vm-test-run-index_advisor> server # [ 129.979270] postgres[1615]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-index_advisor> server # [ 129.985898] postgres[1615]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-index_advisor> server # [ 129.986872] postgres[1615]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-index_advisor> server # [ 129.987462] postgres[1615]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 129.988214] postgres[1615]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.001048] postgres[1615]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.001886] postgres[1615]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.003134] postgres[1615]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.004096] postgres[1615]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.016570] postgres[1615]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 130.020053] postgres[1615]: END IF; vm-test-run-index_advisor> server # [ 130.022439] postgres[1615]: vm-test-run-index_advisor> server # [ 130.024015] postgres[1615]: END; vm-test-run-index_advisor> server # [ 130.026981] postgres[1615]: $func$; vm-test-run-index_advisor> server # [ 130.059807] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1613]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 130.062563] postgres[1615]: ::1 2026-10-22 21:46:01.454 UTC [1615] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 130.141528] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1613]: DROP EXTENSION vm-test-run-index_advisor> server # [ 131.743709] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1613]: DO vm-test-run-index_advisor> server # [ 131.811339] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-index_advisor> server # [ 132.966157] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1627]: DO vm-test-run-index_advisor> server # [ 133.677237] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1627]: DO vm-test-run-index_advisor> server # [ 133.718326] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-index_advisor> server # [ 134.000083] postgres[1647]: ::1 2026-10-22 21:46:05.392 UTC [1647] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-index_advisor> server # [ 134.024573] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1646]: ALTER ROLE vm-test-run-index_advisor> server # [ 134.066456] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-index_advisor> server # [ 134.322271] postgres[1649]: ::1 2026-10-22 21:46:05.703 UTC [1649] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-index_advisor> server # [ 134.335270] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1648]: REVOKE ROLE vm-test-run-index_advisor> server # [ 134.363504] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-index_advisor> server # [ 136.560784] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1650]: DO vm-test-run-index_advisor> server # [ 136.600544] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-index_advisor> server # [ 136.848087] postgres[1677]: ::1 2026-10-22 21:46:08.245 UTC [1677] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-index_advisor> server # [ 136.907274] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1676]: GRANT ROLE vm-test-run-index_advisor> server # [ 136.937407] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-index_advisor> server # [ 137.409620] postgres[1683]: ::1 2026-10-22 21:46:08.791 UTC [1683] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-index_advisor> server # [ 137.514317] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT vm-test-run-index_advisor> server # [ 137.515849] postgres[1683]: ::1 2026-10-22 21:46:08.881 UTC [1683] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-index_advisor> server # [ 137.549357] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT vm-test-run-index_advisor> server # [ 137.552050] postgres[1683]: ::1 2026-10-22 21:46:08.948 UTC [1683] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-index_advisor> server # [ 137.559584] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT vm-test-run-index_advisor> server # [ 137.574567] postgres[1683]: ::1 2026-10-22 21:46:08.967 UTC [1683] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 137.590845] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 137.592205] postgres[1683]: ::1 2026-10-22 21:46:08.986 UTC [1683] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option; vm-test-run-index_advisor> server # [ 137.605847] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 137.613927] postgres[1683]: ::1 2026-10-22 21:46:09.009 UTC [1683] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 137.637027] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: ALTER DEFAULT PRIVILEGES vm-test-run-index_advisor> server # [ 137.707918] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-index_advisor> server # [ 138.110876] postgres[1695]: ::1 2026-10-22 21:46:09.499 UTC [1695] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-index_advisor> server # [ 138.164004] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1690]: GRANT ROLE vm-test-run-index_advisor> server # [ 138.187815] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-index_advisor> server # [ 138.557989] postgres[1707]: ::1 2026-10-22 21:46:09.948 UTC [1707] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-index_advisor> server # [ 138.582660] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1701]: GRANT ROLE vm-test-run-index_advisor> server # [ 138.610736] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-index_advisor> server # [ 138.917032] postgres[1712]: ::1 2026-10-22 21:46:10.302 UTC [1712] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-index_advisor> server # [ 138.918946] postgres[1712]: ::1 2026-10-22 21:46:10.315 UTC [1712] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-index_advisor> server # [ 138.946693] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: ALTER ROLE vm-test-run-index_advisor> server # [ 138.948199] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: ALTER ROLE vm-test-run-index_advisor> server # [ 138.961093] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: ALTER ROLE vm-test-run-index_advisor> server # [ 138.964270] postgres[1712]: ::1 2026-10-22 21:46:10.323 UTC [1712] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-index_advisor> server # [ 138.978867] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: DO vm-test-run-index_advisor> server # [ 139.017666] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-index_advisor> server # [ 139.317886] postgres[1715]: ::1 2026-10-22 21:46:10.701 UTC [1715] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-index_advisor> server # [ 139.351346] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1714]: GRANT ROLE vm-test-run-index_advisor> server # [ 139.369318] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1714]: REVOKE ROLE vm-test-run-index_advisor> server # [ 139.370657] postgres[1715]: ::1 2026-10-22 21:46:10.727 UTC [1715] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-index_advisor> server # [ 139.386773] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-index_advisor> server # [ 139.702034] postgres[1717]: ::1 2026-10-22 21:46:11.096 UTC [1717] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-index_advisor> server # [ 139.706635] postgres[1717]: returns event_trigger vm-test-run-index_advisor> server # [ 139.707721] postgres[1717]: language plpgsql vm-test-run-index_advisor> server # [ 139.708131] postgres[1717]: AS $func$ vm-test-run-index_advisor> server # [ 139.711773] postgres[1717]: DECLARE vm-test-run-index_advisor> server # [ 139.718297] postgres[1717]: func_is_graphql_resolve bool; vm-test-run-index_advisor> server # [ 139.719178] postgres[1717]: BEGIN vm-test-run-index_advisor> server # [ 139.726781] postgres[1717]: func_is_graphql_resolve = ( vm-test-run-index_advisor> server # [ 139.727667] postgres[1717]: SELECT n.proname = 'resolve' vm-test-run-index_advisor> server # [ 139.729581] postgres[1717]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 139.730047] postgres[1717]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-index_advisor> server # [ 139.730475] postgres[1717]: ON ev.objid = n.oid vm-test-run-index_advisor> server # [ 139.730908] postgres[1717]: ); vm-test-run-index_advisor> server # [ 139.731836] postgres[1717]: vm-test-run-index_advisor> server # [ 139.739409] postgres[1717]: IF func_is_graphql_resolve vm-test-run-index_advisor> server # [ 139.740141] postgres[1717]: THEN vm-test-run-index_advisor> server # [ 139.745789] postgres[1717]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-index_advisor> server # [ 139.746850] postgres[1717]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-index_advisor> server # [ 139.747198] postgres[1717]: create or replace function graphql_public.graphql( vm-test-run-index_advisor> server # [ 139.747536] postgres[1717]: "operationName" text default null, vm-test-run-index_advisor> server # [ 139.748140] postgres[1717]: query text default null, vm-test-run-index_advisor> server # [ 139.762355] postgres[1717]: variables jsonb default null, vm-test-run-index_advisor> server # [ 139.763079] postgres[1717]: extensions jsonb default null vm-test-run-index_advisor> server # [ 139.763671] postgres[1717]: ) vm-test-run-index_advisor> server # [ 139.763985] postgres[1717]: returns jsonb vm-test-run-index_advisor> server # [ 139.764380] postgres[1717]: language sql vm-test-run-index_advisor> server # [ 139.766017] postgres[1717]: as $$ vm-test-run-index_advisor> server # [ 139.766407] postgres[1717]: select graphql.resolve( vm-test-run-index_advisor> server # [ 139.767016] postgres[1717]: query := query, vm-test-run-index_advisor> server # [ 139.767302] postgres[1717]: variables := coalesce(variables, '{}'), vm-test-run-index_advisor> server # [ 139.767568] postgres[1717]: "operationName" := "operationName", vm-test-run-index_advisor> server # [ 139.767897] postgres[1717]: extensions := extensions vm-test-run-index_advisor> server # [ 139.768299] postgres[1717]: ); vm-test-run-index_advisor> server # [ 139.774906] postgres[1717]: $$; vm-test-run-index_advisor> server # [ 139.776372] postgres[1717]: vm-test-run-index_advisor> server # [ 139.777796] postgres[1717]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-index_advisor> server # [ 139.778218] postgres[1717]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-index_advisor> server # [ 139.778524] postgres[1717]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-index_advisor> server # [ 139.778814] postgres[1717]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.779089] postgres[1717]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.779373] postgres[1717]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.779647] postgres[1717]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.780233] postgres[1717]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.782193] postgres[1717]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.783037] postgres[1717]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-index_advisor> server # [ 139.785575] postgres[1717]: vm-test-run-index_advisor> server # [ 139.786201] postgres[1717]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-index_advisor> server # [ 139.786663] postgres[1717]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-index_advisor> server # [ 139.787350] postgres[1717]: grant usage on schema graphql to postgres with grant option; vm-test-run-index_advisor> server # [ 139.787682] postgres[1717]: END IF; vm-test-run-index_advisor> server # [ 139.787903] postgres[1717]: vm-test-run-index_advisor> server # [ 139.788099] postgres[1717]: END; vm-test-run-index_advisor> server # [ 139.789684] postgres[1717]: $func$; vm-test-run-index_advisor> server # [ 139.790233] postgres[1717]: ::1 2026-10-22 21:46:11.171 UTC [1717] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-index_advisor> server # [ 139.794162] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 139.830286] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: DROP EXTENSION vm-test-run-index_advisor> server # [ 141.571561] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: DO vm-test-run-index_advisor> server # [ 141.605686] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-index_advisor> server # [ 142.006468] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: DO vm-test-run-index_advisor> server # [ 142.020562] postgres[1742]: ::1 2026-10-22 21:46:13.404 UTC [1742] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-index_advisor> server # [ 142.031170] postgres[1742]: LANGUAGE plpgsql vm-test-run-index_advisor> server # [ 142.032262] postgres[1742]: AS $$ vm-test-run-index_advisor> server # [ 142.042487] postgres[1742]: BEGIN vm-test-run-index_advisor> server # [ 142.043177] postgres[1742]: IF EXISTS ( vm-test-run-index_advisor> server # [ 142.043696] postgres[1742]: SELECT vm-test-run-index_advisor> server # [ 142.054087] postgres[1742]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-index_advisor> server # [ 142.055656] postgres[1742]: JOIN pg_extension AS ext vm-test-run-index_advisor> server # [ 142.055989] postgres[1742]: ON ev.objid = ext.oid vm-test-run-index_advisor> server # [ 142.061286] postgres[1742]: WHERE ext.extname = 'pg_cron' vm-test-run-index_advisor> server # [ 142.062765] postgres[1742]: ) vm-test-run-index_advisor> server # [ 142.063108] postgres[1742]: THEN vm-test-run-index_advisor> server # [ 142.063488] postgres[1742]: grant usage on schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 142.063851] postgres[1742]: vm-test-run-index_advisor> server # [ 142.064105] postgres[1742]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 142.070056] postgres[1742]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 142.071563] postgres[1742]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 142.072212] postgres[1742]: vm-test-run-index_advisor> server # [ 142.076995] postgres[1742]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 142.078709] postgres[1742]: on sequences to postgres with grant option; vm-test-run-index_advisor> server # [ 142.079599] postgres[1742]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 142.080361] postgres[1742]: on tables to postgres with grant option; vm-test-run-index_advisor> server # [ 142.087735] postgres[1742]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-index_advisor> server # [ 142.093712] postgres[1742]: on functions to postgres with grant option; vm-test-run-index_advisor> server # [ 142.094536] postgres[1742]: vm-test-run-index_advisor> server # [ 142.095126] postgres[1742]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-index_advisor> server # [ 142.096139] postgres[1742]: revoke all on table cron.job from postgres; vm-test-run-index_advisor> server # [ 142.110013] postgres[1742]: grant select on table cron.job to postgres with grant option; vm-test-run-index_advisor> server # [ 142.111045] postgres[1742]: END IF; vm-test-run-index_advisor> server # [ 142.111798] postgres[1742]: END; vm-test-run-index_advisor> server # [ 142.112070] postgres[1742]: $$; vm-test-run-index_advisor> server # [ 142.127168] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 142.136261] postgres[1742]: ::1 2026-10-22 21:46:13.531 UTC [1742] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-index_advisor> server # [ 142.145139] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.146905] postgres[1742]: ::1 2026-10-22 21:46:13.544 UTC [1742] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-index_advisor> server # [ 142.164934] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 142.166438] postgres[1742]: ::1 2026-10-22 21:46:13.562 UTC [1742] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-index_advisor> server # [ 142.167463] postgres[1742]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-index_advisor> server # [ 142.167854] postgres[1742]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-index_advisor> server # [ 142.174797] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 142.213031] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-index_advisor> server # [ 142.449542] postgres[1745]: ::1 2026-10-22 21:46:13.844 UTC [1745] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-index_advisor> server # [ 142.459220] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1744]: ALTER ROLE vm-test-run-index_advisor> server # [ 142.498830] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-index_advisor> server # [ 142.814732] postgres[1751]: ::1 2026-10-22 21:46:14.206 UTC [1751] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-index_advisor> server # [ 142.851079] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1746]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 142.853762] postgres[1751]: ::1 2026-10-22 21:46:14.249 UTC [1751] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-index_advisor> server # [ 142.869406] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1746]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 142.870861] postgres[1751]: ::1 2026-10-22 21:46:14.263 UTC [1751] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-index_advisor> server # [ 142.875732] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1746]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 142.927492] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-index_advisor> server # [ 143.203096] postgres[1753]: ::1 2026-10-22 21:46:14.599 UTC [1753] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-index_advisor> server # [ 143.250795] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1752]: GRANT ROLE vm-test-run-index_advisor> server # [ 143.277745] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-index_advisor> server # [ 144.134702] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1754]: DO vm-test-run-index_advisor> server # [ 144.177522] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-index_advisor> server # [ 144.783379] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1766]: DO vm-test-run-index_advisor> server # [ 144.794836] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1766]: DO vm-test-run-index_advisor> server # [ 144.833134] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-index_advisor> server # [ 145.265925] postgres[1783]: ::1 2026-10-22 21:46:16.658 UTC [1783] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-index_advisor> server # [ 145.274582] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1781]: ALTER ROLE vm-test-run-index_advisor> server # [ 145.276015] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1781]: ALTER ROLE vm-test-run-index_advisor> server # [ 145.278217] postgres[1783]: ::1 2026-10-22 21:46:16.671 UTC [1783] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-index_advisor> server # [ 145.279249] postgres[1783]: ::1 2026-10-22 21:46:16.678 UTC [1783] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-index_advisor> server # [ 145.286515] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1781]: ALTER ROLE vm-test-run-index_advisor> server # [ 145.338461] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-index_advisor> server # [ 145.620882] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1784]: DO vm-test-run-index_advisor> server # [ 145.643939] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-index_advisor> server # [ 145.867946] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1786]: DO vm-test-run-index_advisor> server # [ 145.905708] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-index_advisor> server # [ 146.166577] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1788]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 146.176689] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1788]: DO vm-test-run-index_advisor> server # [ 146.217257] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-index_advisor> server # [ 146.695464] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1791]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 146.708762] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1791]: GRANT vm-test-run-index_advisor> server # [ 146.776989] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-index_advisor> server # [ 147.238015] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1800]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 147.312988] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1800]: ALTER FUNCTION vm-test-run-index_advisor> server # [ 147.335740] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1800]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 147.379844] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-index_advisor> server # [ 147.846064] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1811]: DO vm-test-run-index_advisor> server # [ 147.891230] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-index_advisor> server # [ 148.281556] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1820]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 148.298296] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1820]: GRANT vm-test-run-index_advisor> server # [ 148.339598] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-index_advisor> server # [ 148.740343] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: REVOKE ROLE vm-test-run-index_advisor> server # [ 148.778044] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: DO vm-test-run-index_advisor> server # [ 148.790280] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: DO vm-test-run-index_advisor> server # [ 148.805165] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: REVOKE ROLE vm-test-run-index_advisor> server # [ 148.819498] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: REVOKE vm-test-run-index_advisor> server # [ 148.874630] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: DO vm-test-run-index_advisor> server # [ 148.923868] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-index_advisor> server # [ 149.155996] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1828]: DO vm-test-run-index_advisor> server # [ 149.194500] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-index_advisor> server # [ 149.450972] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1830]: DO vm-test-run-index_advisor> server # [ 149.485738] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-index_advisor> server # [ 149.790639] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1832]: DO vm-test-run-index_advisor> server # [ 149.821383] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-index_advisor> server # [ 150.269358] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1838]: ALTER ROLE vm-test-run-index_advisor> server # [ 150.283910] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-index_advisor> server # [ 150.735222] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1849]: GRANT ROLE vm-test-run-index_advisor> server # [ 150.769477] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1849]: DO vm-test-run-index_advisor> server # [ 150.817284] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-index_advisor> server # [ 151.187772] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1857]: GRANT vm-test-run-index_advisor> server # [ 151.205521] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-index_advisor> server # [ 151.527152] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 151.534785] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: REVOKE vm-test-run-index_advisor> server # [ 151.547505] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: REVOKE vm-test-run-index_advisor> server # [ 151.569585] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: GRANT vm-test-run-index_advisor> server # [ 151.609328] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-index_advisor> server # [ 151.947583] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1866]: DO vm-test-run-index_advisor> server # [ 151.998844] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-index_advisor> server # [ 152.387297] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1872]: ALTER ROLE vm-test-run-index_advisor> server # [ 152.430276] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-index_advisor> server # [ 152.973887] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1874]: DROP EXTENSION vm-test-run-index_advisor> server # [ 152.998584] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-index_advisor> server # [ 153.563557] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1885]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 153.601182] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1885]: DROP EVENT TRIGGER vm-test-run-index_advisor> server # [ 153.617332] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1885]: CREATE EVENT TRIGGER vm-test-run-index_advisor> server # [ 153.670667] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-index_advisor> server # [ 154.173791] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1901]: DO vm-test-run-index_advisor> server # [ 154.175023] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1901]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.233786] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql vm-test-run-index_advisor> server # [ 154.508975] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1904]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.517274] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1904]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.557630] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20261016000001_search_path_for_supa_functions.sql vm-test-run-index_advisor> server # [ 154.831269] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.838526] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.853270] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.866808] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.878942] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.890263] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION vm-test-run-index_advisor> server # [ 154.925205] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running postgresql schema... vm-test-run-index_advisor> server # [ 155.174566] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER DATABASE vm-test-run-index_advisor> server # [ 155.178133] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER DATABASE vm-test-run-index_advisor> server # [ 155.240092] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.302879] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.397828] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.469714] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.593651] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.681027] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.763024] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.849908] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.926131] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 155.932929] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE vm-test-run-index_advisor> server # [ 156.010779] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Database initialization complete vm-test-run-index_advisor> server # [ 156.063634] systemd[1]: Finished Supabase Database Initialization. vm-test-run-index_advisor> server # [ 156.094040] systemd[1]: Reached target Multi-User System. vm-test-run-index_advisor> server # [ 156.107974] systemd[1]: Startup finished in 20.922s (kernel) + 2min 15.173s (userspace) = 2min 36.095s. vm-test-run-index_advisor> server # [ 157.165331] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 vm-test-run-index_advisor> server: (finished: waiting for unit supabase-db-init.service, in 158.80 seconds) vm-test-run-index_advisor> subtest: Check upgrade path with postgresql 15 vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" vm-test-run-index_advisor> server # NOTICE: extension "index_advisor" does not exist, skipping vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" , in 0.46 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" vm-test-run-index_advisor> server # NOTICE: installing required extension "hypopg" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" , in 1.80 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" , in 0.64 seconds) vm-test-run-index_advisor> (finished: subtest: Check upgrade path with postgresql 15, in 2.90 seconds) vm-test-run-index_advisor> subtest: Check the install of the last version of the extension vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" , in 0.49 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" , in 1.36 seconds) vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" , in 0.69 seconds) vm-test-run-index_advisor> (finished: subtest: Check the install of the last version of the extension, in 2.54 seconds) vm-test-run-index_advisor> subtest: switch to postgresql 17 vm-test-run-index_advisor> server # Checking switch inhibitors... done