nixbot

1this derivation will be built:2  /nix/store/8kgicvp4xr6wzdjzidx3sgxkrr0q7m3p-vm-test-run-index_advisor.drv3building '/nix/store/8kgicvp4xr6wzdjzidx3sgxkrr0q7m3p-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder'4building '/nix/store/8kgicvp4xr6wzdjzidx3sgxkrr0q7m3p-vm-test-run-index_advisor.drv'5vm-test-run-index_advisor> Machine state will be reset. To keep it, pass --keep-vm-state6vm-test-run-index_advisor> start all VLans7vm-test-run-index_advisor> start vlan8vm-test-run-index_advisor> running vlan (pid 7; ctl /build/vde1.ctl)9vm-test-run-index_advisor> (finished: start all VLans, in 0.00 seconds)10vm-test-run-index_advisor> Test will time out and terminate in 3600 seconds11vm-test-run-index_advisor> run the VM test script12vm-test-run-index_advisor> additionally exposed symbols:13vm-test-run-index_advisor>     server,14vm-test-run-index_advisor>     vlan1,15vm-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, debug16vm-test-run-index_advisor> start all VMs17vm-test-run-index_advisor> server: starting vm18vm-test-run-index_advisor> mke2fs 1.47.3 (8-Jul-2025)19vm-test-run-index_advisor> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory20vm-test-run-index_advisor> qemu-system-aarch64: failed to initialize kvm: No such file or directory21vm-test-run-index_advisor> qemu-system-aarch64: falling back to tcg22vm-test-run-index_advisor> server # Disk image does not exist, creating the virtualisation disk image...23vm-test-run-index_advisor> server # Formatting '/build/vm-state-server/tmp.7mSsmnhsiS', fmt=raw size=107374182424vm-test-run-index_advisor> server # Discarding device blocks:      0/262144             done25vm-test-run-index_advisor> server # Creating filesystem with 262144 4k blocks and 65536 inodes26vm-test-run-index_advisor> server # Filesystem UUID: afbf7685-dbf9-4cad-9cf1-055240f4a83b27vm-test-run-index_advisor> server # Superblock backups stored on blocks:28vm-test-run-index_advisor> server # 	32768, 98304, 163840, 22937629vm-test-run-index_advisor> server # 30vm-test-run-index_advisor> server # Allocating group tables: 0/8   done31vm-test-run-index_advisor> server # Writing inode tables: 0/8   done32vm-test-run-index_advisor> server # Creating journal (8192 blocks): done33vm-test-run-index_advisor> server # Writing superblocks and filesystem accounting information: 0/8   done34vm-test-run-index_advisor> server # 35vm-test-run-index_advisor> server # Virtualisation disk image created.36vm-test-run-index_advisor> server: QEMU running (pid 9)37vm-test-run-index_advisor> (finished: start all VMs, in 0.16 seconds)38vm-test-run-index_advisor> server: waiting for unit supabase-db-init.service39vm-test-run-index_advisor> server: waiting for the VM to finish booting40vm-test-run-index_advisor> server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]41vm-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 202542vm-test-run-index_advisor> server # [    0.000000] KASLR enabled43vm-test-run-index_advisor> server # [    0.000000] random: crng init done44vm-test-run-index_advisor> server # [    0.000000] Machine model: linux,dummy-virt45vm-test-run-index_advisor> server # [    0.000000] efi: UEFI not found.46vm-test-run-index_advisor> server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT47vm-test-run-index_advisor> server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]48vm-test-run-index_advisor> server # [    0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]49vm-test-run-index_advisor> server # [    0.000000] Zone ranges:50vm-test-run-index_advisor> server # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]51vm-test-run-index_advisor> server # [    0.000000]   DMA32    empty52vm-test-run-index_advisor> server # [    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]53vm-test-run-index_advisor> server # [    0.000000]   Device   empty54vm-test-run-index_advisor> server # [    0.000000] Movable zone start for each node55vm-test-run-index_advisor> server # [    0.000000] Early memory node ranges56vm-test-run-index_advisor> server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]57vm-test-run-index_advisor> server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]58vm-test-run-index_advisor> server # [    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -159vm-test-run-index_advisor> server # [    0.000000] psci: probing for conduit method from DT.60vm-test-run-index_advisor> server # [    0.000000] psci: PSCIv1.1 detected in firmware.61vm-test-run-index_advisor> server # [    0.000000] psci: Using standard PSCI v0.2 function IDs62vm-test-run-index_advisor> server # [    0.000000] psci: Trusted OS migration not required63vm-test-run-index_advisor> server # [    0.000000] psci: SMC Calling Convention v1.064vm-test-run-index_advisor> server # [    0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u36044865vm-test-run-index_advisor> server # [    0.000000] Detected PIPT I-cache on CPU066vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)67vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: GIC system register CPU interface68vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: HCRX_EL2 register69vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)70vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: Spectre-v471vm-test-run-index_advisor> server # [    0.000000] alternatives: applying boot alternatives72vm-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=tty073vm-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.74vm-test-run-index_advisor> server # [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)75vm-test-run-index_advisor> server # [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)76vm-test-run-index_advisor> server # [    0.000000] Fallback order for Node 0: 077vm-test-run-index_advisor> server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 104857678vm-test-run-index_advisor> server # [    0.000000] Policy zone: Normal79vm-test-run-index_advisor> server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off80vm-test-run-index_advisor> server # [    0.000000] software IO TLB: area num 2.81vm-test-run-index_advisor> server # [    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)82vm-test-run-index_advisor> server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=183vm-test-run-index_advisor> server # [    0.000000] allocated 8388608 bytes of page_ext84vm-test-run-index_advisor> server # [    0.000000] ftrace: allocating 72257 entries in 283 pages85vm-test-run-index_advisor> server # [    0.000000] ftrace: allocated 283 pages with 5 groups86vm-test-run-index_advisor> server # [    0.000000] rcu: Hierarchical RCU implementation.87vm-test-run-index_advisor> server # [    0.000000] rcu: 	RCU event tracing is enabled.88vm-test-run-index_advisor> server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.89vm-test-run-index_advisor> server # [    0.000000] 	Rude variant of Tasks RCU enabled.90vm-test-run-index_advisor> server # [    0.000000] 	Tracing variant of Tasks RCU enabled.91vm-test-run-index_advisor> server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.92vm-test-run-index_advisor> server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=293vm-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.94vm-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.95vm-test-run-index_advisor> server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 096vm-test-run-index_advisor> server # [    0.000000] GICv3: 256 SPIs implemented97vm-test-run-index_advisor> server # [    0.000000] GICv3: 0 Extended SPIs implemented98vm-test-run-index_advisor> server # [    0.000000] Root IRQ handler: gic_handle_irq99vm-test-run-index_advisor> server # [    0.000000] GICv3: GICv3 features: 16 PPIs100vm-test-run-index_advisor> server # [    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0101vm-test-run-index_advisor> server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000102vm-test-run-index_advisor> server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]103vm-test-run-index_advisor> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1)104vm-test-run-index_advisor> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1)105vm-test-run-index_advisor> server # [    0.000000] GICv3: using LPI property table @0x0000000100b00000106vm-test-run-index_advisor> server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000107vm-test-run-index_advisor> server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.108vm-test-run-index_advisor> server # [    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).109vm-test-run-index_advisor> server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns110vm-test-run-index_advisor> server # [    0.000041] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns111vm-test-run-index_advisor> server # [    0.004307] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)112vm-test-run-index_advisor> server # [    0.015192] Console: colour dummy device 80x25113vm-test-run-index_advisor> server # [    0.016853] printk: legacy console [tty0] enabled114vm-test-run-index_advisor> server # [    0.022567] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)115vm-test-run-index_advisor> server # [    0.022985] pid_max: default: 32768 minimum: 301116vm-test-run-index_advisor> server # [    0.024886] LSM: initializing lsm=capability,landlock,yama,bpf117vm-test-run-index_advisor> server # [    0.026773] landlock: Up and running.118vm-test-run-index_advisor> server # [    0.026841] Yama: becoming mindful.119vm-test-run-index_advisor> server # [    0.030693] LSM support for eBPF active120vm-test-run-index_advisor> server # [    0.034115] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)121vm-test-run-index_advisor> server # [    0.034477] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)122vm-test-run-index_advisor> server # [    0.065008] cacheinfo: Unable to detect cache hierarchy for CPU 0123vm-test-run-index_advisor> server # [    0.076429] rcu: Hierarchical SRCU implementation.124vm-test-run-index_advisor> server # [    0.076666] rcu: 	Max phase no-delay instances is 1000.125vm-test-run-index_advisor> server # [    0.078626] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level126vm-test-run-index_advisor> server # [    0.086732] fsl-mc MSI: its@8080000 domain created127vm-test-run-index_advisor> server # [    0.088894] EFI services will not be available.128vm-test-run-index_advisor> server # [    0.090539] smp: Bringing up secondary CPUs ...129vm-test-run-index_advisor> server # [    0.096095] Detected PIPT I-cache on CPU1130vm-test-run-index_advisor> server # [    0.097355] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000131vm-test-run-index_advisor> server # [    0.098049] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000132vm-test-run-index_advisor> server # [    0.099608] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]133vm-test-run-index_advisor> server # [    0.104321] smp: Brought up 1 node, 2 CPUs134vm-test-run-index_advisor> server # [    0.104551] SMP: Total of 2 processors activated.135vm-test-run-index_advisor> server # [    0.104607] CPU: All CPU(s) started at EL1136vm-test-run-index_advisor> server # [    0.104772] CPU features: detected: Branch Target Identification137vm-test-run-index_advisor> server # [    0.104825] CPU features: detected: 32-bit EL0 Support138vm-test-run-index_advisor> server # [    0.104853] CPU features: detected: 32-bit EL1 Support139vm-test-run-index_advisor> server # [    0.105025] CPU features: detected: ARMv8.4 Translation Table Level140vm-test-run-index_advisor> server # [    0.105063] CPU features: detected: Instruction cache invalidation not required for I/D coherence141vm-test-run-index_advisor> server # [    0.105102] CPU features: detected: Data cache clean to the PoU not required for I/D coherence142vm-test-run-index_advisor> server # [    0.105168] CPU features: detected: Common not Private translations143vm-test-run-index_advisor> server # [    0.105191] CPU features: detected: CRC32 instructions144vm-test-run-index_advisor> server # [    0.105212] CPU features: detected: Data cache clean to Point of Deep Persistence145vm-test-run-index_advisor> server # [    0.105238] CPU features: detected: Data cache clean to Point of Persistence146vm-test-run-index_advisor> server # [    0.105261] CPU features: detected: Data independent timing control (DIT)147vm-test-run-index_advisor> server # [    0.105286] CPU features: detected: E0PD148vm-test-run-index_advisor> server # [    0.105307] CPU features: detected: Enhanced Counter Virtualization149vm-test-run-index_advisor> server # [    0.105329] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)150vm-test-run-index_advisor> server # [    0.105352] CPU features: detected: Enhanced Privileged Access Never151vm-test-run-index_advisor> server # [    0.105375] CPU features: detected: Enhanced Virtualization Traps152vm-test-run-index_advisor> server # [    0.105402] CPU features: detected: Fine Grained Traps153vm-test-run-index_advisor> server # [    0.105459] CPU features: detected: Generic authentication (IMP DEF algorithm)154vm-test-run-index_advisor> server # [    0.105541] CPU features: detected: RCpc load-acquire (LDAPR)155vm-test-run-index_advisor> server # [    0.105583] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)156vm-test-run-index_advisor> server # [    0.105607] CPU features: detected: LSE atomic instructions157vm-test-run-index_advisor> server # [    0.105628] CPU features: detected: Memory Copy and Memory Set instructions158vm-test-run-index_advisor> server # [    0.105651] CPU features: detected: Privileged Access Never159vm-test-run-index_advisor> server # [    0.105672] CPU features: detected: RAS Extension Support160vm-test-run-index_advisor> server # [    0.105697] CPU features: detected: Random Number Generator161vm-test-run-index_advisor> server # [    0.105717] CPU features: detected: Speculation barrier (SB)162vm-test-run-index_advisor> server # [    0.105738] CPU features: detected: Stage-2 Force Write-Back163vm-test-run-index_advisor> server # [    0.105764] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality164vm-test-run-index_advisor> server # [    0.105796] CPU features: detected: TLB range maintenance instructions165vm-test-run-index_advisor> server # [    0.105823] CPU features: detected: WFx with timeout166vm-test-run-index_advisor> server # [    0.105876] CPU features: detected: Speculative Store Bypassing Safe (SSBS)167vm-test-run-index_advisor> server # [    0.105904] CPU features: detected: Scalable Vector Extension168vm-test-run-index_advisor> server # [    0.111199] alternatives: applying system-wide alternatives169vm-test-run-index_advisor> server # [    0.129727] CPU features: detected: Hardware dirty bit management on CPU0-1170vm-test-run-index_advisor> server # [    0.130337] SVE: maximum available vector length 256 bytes per vector171vm-test-run-index_advisor> server # [    0.130428] SVE: default vector length 64 bytes per vector172vm-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)173vm-test-run-index_advisor> server # [    0.166863] devtmpfs: initialized174vm-test-run-index_advisor> server # [    0.195841] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns175vm-test-run-index_advisor> server # [    0.196354] futex hash table entries: 512 (order: 3, 32768 bytes, linear)176vm-test-run-index_advisor> server # [    0.198849] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL177vm-test-run-index_advisor> server # [    0.198930] 0 pages in range for non-PLT usage178vm-test-run-index_advisor> server # [    0.198974] 509248 pages in range for PLT usage179vm-test-run-index_advisor> server # [    0.202102] pinctrl core: initialized pinctrl subsystem180vm-test-run-index_advisor> server # [    0.213881] DMI not present or invalid.181vm-test-run-index_advisor> server # [    0.232806] NET: Registered PF_NETLINK/PF_ROUTE protocol family182vm-test-run-index_advisor> server # [    0.244432] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations183vm-test-run-index_advisor> server # [    0.246097] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations184vm-test-run-index_advisor> server # [    0.247582] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations185vm-test-run-index_advisor> server # [    0.247868] audit: initializing netlink subsys (disabled)186vm-test-run-index_advisor> server # [    0.250973] audit: type=2000 audit(0.200:1): state=initialized audit_enabled=0 res=1187vm-test-run-index_advisor> server # [    0.258007] thermal_sys: Registered thermal governor 'fair_share'188vm-test-run-index_advisor> server # [    0.258066] thermal_sys: Registered thermal governor 'bang_bang'189vm-test-run-index_advisor> server # [    0.258122] thermal_sys: Registered thermal governor 'step_wise'190vm-test-run-index_advisor> server # [    0.258148] thermal_sys: Registered thermal governor 'user_space'191vm-test-run-index_advisor> server # [    0.258172] thermal_sys: Registered thermal governor 'power_allocator'192vm-test-run-index_advisor> server # [    0.259214] cpuidle: using governor ladder193vm-test-run-index_advisor> server # [    0.259900] cpuidle: using governor menu194vm-test-run-index_advisor> server # [    0.262486] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.195vm-test-run-index_advisor> server # [    0.263534] ASID allocator initialised with 65536 entries196vm-test-run-index_advisor> server # [    0.270846] Serial: AMBA PL011 UART driver197vm-test-run-index_advisor> server # [    0.322947] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1198vm-test-run-index_advisor> server # [    0.324005] printk: legacy console [ttyAMA0] enabled199vm-test-run-index_advisor> server # [    0.361108] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages200vm-test-run-index_advisor> server # [    0.361292] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page201vm-test-run-index_advisor> server # [    0.361398] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages202vm-test-run-index_advisor> server # [    0.361523] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page203vm-test-run-index_advisor> server # [    0.361626] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages204vm-test-run-index_advisor> server # [    0.361721] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page205vm-test-run-index_advisor> server # [    0.361817] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages206vm-test-run-index_advisor> server # [    0.361909] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page207vm-test-run-index_advisor> server # [    0.375056] fbcon: Taking over console208vm-test-run-index_advisor> server # [    0.375677] ACPI: Interpreter disabled.209vm-test-run-index_advisor> server # [    0.383570] iommu: Default domain type: Translated210vm-test-run-index_advisor> server # [    0.383809] iommu: DMA domain TLB invalidation policy: strict mode211vm-test-run-index_advisor> server # [    0.387226] SCSI subsystem initialized212vm-test-run-index_advisor> server # [    0.389443] usbcore: registered new interface driver usbfs213vm-test-run-index_advisor> server # [    0.389786] usbcore: registered new interface driver hub214vm-test-run-index_advisor> server # [    0.390914] usbcore: registered new device driver usb215vm-test-run-index_advisor> server # [    0.393943] pps_core: LinuxPPS API ver. 1 registered216vm-test-run-index_advisor> server # [    0.394082] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>217vm-test-run-index_advisor> server # [    0.394263] PTP clock support registered218vm-test-run-index_advisor> server # [    0.394623] EDAC MC: Ver: 3.0.0219vm-test-run-index_advisor> server # [    0.396380] scmi_core: SCMI protocol bus registered220vm-test-run-index_advisor> server # [    0.400661] FPGA manager framework221vm-test-run-index_advisor> server # [    0.402087] Advanced Linux Sound Architecture Driver Initialized.222vm-test-run-index_advisor> server # [    0.410731] vgaarb: loaded223vm-test-run-index_advisor> server # [    0.415240] clocksource: Switched to clocksource arch_sys_counter224vm-test-run-index_advisor> server # [    0.420846] VFS: Disk quotas dquot_6.6.0225vm-test-run-index_advisor> server # [    0.421340] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)226vm-test-run-index_advisor> server # [    0.423939] netfs: FS-Cache loaded227vm-test-run-index_advisor> server # [    0.425449] pnp: PnP ACPI: disabled228vm-test-run-index_advisor> server # [    0.452338] NET: Registered PF_INET protocol family229vm-test-run-index_advisor> server # [    0.454152] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)230vm-test-run-index_advisor> server # [    0.574765] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)231vm-test-run-index_advisor> server # [    0.575479] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)232vm-test-run-index_advisor> server # [    0.575772] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)233vm-test-run-index_advisor> server # [    0.576889] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)234vm-test-run-index_advisor> server # [    0.577616] TCP: Hash tables configured (established 32768 bind 32768)235vm-test-run-index_advisor> server # [    0.578958] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)236vm-test-run-index_advisor> server # [    0.580369] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)237vm-test-run-index_advisor> server # [    0.581257] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)238vm-test-run-index_advisor> server # [    0.583367] NET: Registered PF_UNIX/PF_LOCAL protocol family239vm-test-run-index_advisor> server # [    0.584387] NET: Registered PF_XDP protocol family240vm-test-run-index_advisor> server # [    0.584889] PCI: CLS 0 bytes, default 64241vm-test-run-index_advisor> server # [    0.587943] kvm [1]: HYP mode not available242vm-test-run-index_advisor> server # [    0.593552] Trying to unpack rootfs image as initramfs...243vm-test-run-index_advisor> server # [    0.700144] Initialise system trusted keyrings244vm-test-run-index_advisor> server # [    0.701642] workingset: timestamp_bits=42 max_order=20 bucket_order=0245vm-test-run-index_advisor> server # [    0.706364] squashfs: version 4.0 (2009/01/31) Phillip Lougher246vm-test-run-index_advisor> server # [    0.707175] 9p: Installing v9fs 9p2000 file system support247vm-test-run-index_advisor> server # [    0.769951] Key type asymmetric registered248vm-test-run-index_advisor> server # [    0.770183] Asymmetric key parser 'x509' registered249vm-test-run-index_advisor> server # [    0.770691] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)250vm-test-run-index_advisor> server # [    0.773079] io scheduler mq-deadline registered251vm-test-run-index_advisor> server # [    0.773531] io scheduler kyber registered252vm-test-run-index_advisor> server # [    0.807105] pl061_gpio 9030000.pl061: PL061 GPIO chip registered253vm-test-run-index_advisor> server # [    0.813126] ledtrig-cpu: registered to indicate activity on CPUs254vm-test-run-index_advisor> server # [    0.817987] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:255vm-test-run-index_advisor> server # [    0.818878] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000256vm-test-run-index_advisor> server # [    0.820365] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000257vm-test-run-index_advisor> server # [    0.820703] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000258vm-test-run-index_advisor> server # [    0.821817] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits259vm-test-run-index_advisor> server # [    0.822399] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]260vm-test-run-index_advisor> server # [    0.825150] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00261vm-test-run-index_advisor> server # [    0.825575] pci_bus 0000:00: root bus resource [bus 00-ff]262vm-test-run-index_advisor> server # [    0.826136] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]263vm-test-run-index_advisor> server # [    0.826264] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]264vm-test-run-index_advisor> server # [    0.826372] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]265vm-test-run-index_advisor> server # [    0.827977] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint266vm-test-run-index_advisor> server # [    0.833163] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint267vm-test-run-index_advisor> server # [    0.833542] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]268vm-test-run-index_advisor> server # [    0.833679] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]269vm-test-run-index_advisor> server # [    0.833821] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270vm-test-run-index_advisor> server # [    0.834394] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]271vm-test-run-index_advisor> server # [    0.836444] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint272vm-test-run-index_advisor> server # [    0.836728] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]273vm-test-run-index_advisor> server # [    0.836856] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]274vm-test-run-index_advisor> server # [    0.837928] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275vm-test-run-index_advisor> server # [    0.838554] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint276vm-test-run-index_advisor> server # [    0.839921] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]277vm-test-run-index_advisor> server # [    0.840326] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]278vm-test-run-index_advisor> server # [    0.840455] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279vm-test-run-index_advisor> server # [    0.841281] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint280vm-test-run-index_advisor> server # [    0.841447] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]281vm-test-run-index_advisor> server # [    0.841578] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]282vm-test-run-index_advisor> server # [    0.842181] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]283vm-test-run-index_advisor> server # [    0.843256] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint284vm-test-run-index_advisor> server # [    0.843418] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]285vm-test-run-index_advisor> server # [    0.843519] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]286vm-test-run-index_advisor> server # [    0.843629] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287vm-test-run-index_advisor> server # [    0.845283] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint288vm-test-run-index_advisor> server # [    0.845489] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]289vm-test-run-index_advisor> server # [    0.845589] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]290vm-test-run-index_advisor> server # [    0.845855] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]291vm-test-run-index_advisor> server # [    0.846428] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint292vm-test-run-index_advisor> server # [    0.846587] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]293vm-test-run-index_advisor> server # [    0.846684] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]294vm-test-run-index_advisor> server # [    0.846794] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]295vm-test-run-index_advisor> server # [    0.846904] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]296vm-test-run-index_advisor> server # [    0.847364] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint297vm-test-run-index_advisor> server # [    0.847514] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]298vm-test-run-index_advisor> server # [    0.847630] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]299vm-test-run-index_advisor> server # [    0.849582] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint300vm-test-run-index_advisor> server # [    0.850091] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]301vm-test-run-index_advisor> server # [    0.850586] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302vm-test-run-index_advisor> server # [    0.852250] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint303vm-test-run-index_advisor> server # [    0.852444] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]304vm-test-run-index_advisor> server # [    0.853893] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint305vm-test-run-index_advisor> server # [    0.854268] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]306vm-test-run-index_advisor> server # [    0.854740] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]307vm-test-run-index_advisor> server # [    0.855185] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]308vm-test-run-index_advisor> server # [    0.857308] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint309vm-test-run-index_advisor> server # [    0.857750] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]310vm-test-run-index_advisor> server # [    0.858480] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]311vm-test-run-index_advisor> server # [    0.858621] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]312vm-test-run-index_advisor> server # [    0.861426] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned313vm-test-run-index_advisor> server # [    0.861722] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned314vm-test-run-index_advisor> server # [    0.862338] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned315vm-test-run-index_advisor> server # [    0.864148] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned316vm-test-run-index_advisor> server # [    0.864618] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned317vm-test-run-index_advisor> server # [    0.864774] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned318vm-test-run-index_advisor> server # [    0.865557] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned319vm-test-run-index_advisor> server # [    0.866010] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned320vm-test-run-index_advisor> server # [    0.866169] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned321vm-test-run-index_advisor> server # [    0.866757] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned322vm-test-run-index_advisor> server # [    0.867892] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned323vm-test-run-index_advisor> server # [    0.868294] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned324vm-test-run-index_advisor> server # [    0.869010] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned325vm-test-run-index_advisor> server # [    0.869166] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned326vm-test-run-index_advisor> server # [    0.869290] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned327vm-test-run-index_advisor> server # [    0.869405] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned328vm-test-run-index_advisor> server # [    0.869512] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned329vm-test-run-index_advisor> server # [    0.869622] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned330vm-test-run-index_advisor> server # [    0.869737] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned331vm-test-run-index_advisor> server # [    0.869841] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned332vm-test-run-index_advisor> server # [    0.870695] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned333vm-test-run-index_advisor> server # [    0.870845] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned334vm-test-run-index_advisor> server # [    0.871127] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned335vm-test-run-index_advisor> server # [    0.872159] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned336vm-test-run-index_advisor> server # [    0.872376] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned337vm-test-run-index_advisor> server # [    0.872608] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned338vm-test-run-index_advisor> server # [    0.873457] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned339vm-test-run-index_advisor> server # [    0.873585] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned340vm-test-run-index_advisor> server # [    0.873692] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned341vm-test-run-index_advisor> server # [    0.873798] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned342vm-test-run-index_advisor> server # [    0.873902] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned343vm-test-run-index_advisor> server # [    0.874006] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned344vm-test-run-index_advisor> server # [    0.874112] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned345vm-test-run-index_advisor> server # [    0.874879] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned346vm-test-run-index_advisor> server # [    0.876146] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]347vm-test-run-index_advisor> server # [    0.876307] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]348vm-test-run-index_advisor> server # [    0.876408] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]349vm-test-run-index_advisor> server # [    0.878303] pci 0000:00:0a.0: enabling device (0000 -> 0002)350vm-test-run-index_advisor> server # [    0.941121] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)351vm-test-run-index_advisor> server # [    0.946586] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)352vm-test-run-index_advisor> server # [    0.948983] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)353vm-test-run-index_advisor> server # [    0.951621] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)354vm-test-run-index_advisor> server # [    0.953784] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)355vm-test-run-index_advisor> server # [    0.957633] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)356vm-test-run-index_advisor> server # [    0.960408] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)357vm-test-run-index_advisor> server # [    0.962100] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)358vm-test-run-index_advisor> server # [    0.964057] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)359vm-test-run-index_advisor> server # [    0.966097] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)360vm-test-run-index_advisor> server # [    0.969487] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)361vm-test-run-index_advisor> server # [    0.988435] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled362vm-test-run-index_advisor> server # [    0.999229] msm_serial: driver initialized363vm-test-run-index_advisor> server # [    1.000088] SuperH (H)SCI(F) driver initialized364vm-test-run-index_advisor> server # [    1.000672] STM32 USART driver initialized365vm-test-run-index_advisor> server # [    1.057818] Freeing initrd memory: 10684K366vm-test-run-index_advisor> server # [    1.059846] loop: module loaded367vm-test-run-index_advisor> server # [    1.061212] virtio_blk virtio5: 2/0/0 default/read/poll queues368vm-test-run-index_advisor> server # [    1.065354] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)369vm-test-run-index_advisor> server # [    1.078579] megasas: 07.727.03.00-rc1370vm-test-run-index_advisor> server # [    1.085597] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]371vm-test-run-index_advisor> server # [    1.089105] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000372vm-test-run-index_advisor> server # [    1.090218] Intel/Sharp Extended Query Table at 0x0031373vm-test-run-index_advisor> server # [    1.092866] Using buffer write method374vm-test-run-index_advisor> server # [    1.093573] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]375vm-test-run-index_advisor> server # [    1.094950] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000376vm-test-run-index_advisor> server # [    1.095703] Intel/Sharp Extended Query Table at 0x0031377vm-test-run-index_advisor> server # [    1.097530] Using buffer write method378vm-test-run-index_advisor> server # [    1.097786] Concatenating MTD devices:379vm-test-run-index_advisor> server # [    1.097875] (0): "0.flash"380vm-test-run-index_advisor> server # [    1.097941] (1): "0.flash"381vm-test-run-index_advisor> server # [    1.098002] into device "0.flash"382vm-test-run-index_advisor> server # [    1.125451] tun: Universal TUN/TAP device driver, 1.6383vm-test-run-index_advisor> server # [    1.137393] thunder_xcv, ver 1.0384vm-test-run-index_advisor> server # [    1.137649] thunder_bgx, ver 1.0385vm-test-run-index_advisor> server # [    1.137819] nicpf, ver 1.0386vm-test-run-index_advisor> server # [    1.141339] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version387vm-test-run-index_advisor> server # [    1.141542] hns3: Copyright (c) 2017 Huawei Corporation.388vm-test-run-index_advisor> server # [    1.141973] hclge is initializing389vm-test-run-index_advisor> server # [    1.142249] e1000: Intel(R) PRO/1000 Network Driver390vm-test-run-index_advisor> server # [    1.142344] e1000: Copyright (c) 1999-2006 Intel Corporation.391vm-test-run-index_advisor> server # [    1.142578] e1000e: Intel(R) PRO/1000 Network Driver392vm-test-run-index_advisor> server # [    1.142661] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.393vm-test-run-index_advisor> server # [    1.142864] igb: Intel(R) Gigabit Ethernet Network Driver394vm-test-run-index_advisor> server # [    1.142954] igb: Copyright (c) 2007-2014 Intel Corporation.395vm-test-run-index_advisor> server # [    1.143133] igbvf: Intel(R) Gigabit Virtual Function Network Driver396vm-test-run-index_advisor> server # [    1.143234] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.397vm-test-run-index_advisor> server # [    1.144422] sky2: driver version 1.30398vm-test-run-index_advisor> server # [    1.153423] ehci-pci 0000:00:0a.0: EHCI Host Controller399vm-test-run-index_advisor> server # [    1.153998] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1400vm-test-run-index_advisor> server # [    1.156562] usbcore: registered new interface driver usb-storage401vm-test-run-index_advisor> server # [    1.157198] usbcore: registered new interface driver usbserial_generic402vm-test-run-index_advisor> server # [    1.157256] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000403vm-test-run-index_advisor> server # [    1.157913] usbserial: USB Serial support registered for generic404vm-test-run-index_advisor> server # [    1.167668] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00405vm-test-run-index_advisor> server # [    1.169087] rtc-pl031 9010000.pl031: registered as rtc0406vm-test-run-index_advisor> server # [    1.170102] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T21:43:52 UTC (1792705432)407vm-test-run-index_advisor> server # [    1.172731] i2c_dev: i2c /dev entries driver408vm-test-run-index_advisor> server # [    1.174771] hub 1-0:1.0: USB hub found409vm-test-run-index_advisor> server # [    1.175437] hub 1-0:1.0: 6 ports detected410vm-test-run-index_advisor> server # [    1.189925] sdhci: Secure Digital Host Controller Interface driver411vm-test-run-index_advisor> server # [    1.190085] sdhci: Copyright(c) Pierre Ossman412vm-test-run-index_advisor> server # [    1.192510] Synopsys Designware Multimedia Card Interface Driver413vm-test-run-index_advisor> server # [    1.194614] sdhci-pltfm: SDHCI platform and OF driver helper414vm-test-run-index_advisor> server # [    1.202549] hid: raw HID events driver (C) Jiri Kosina415vm-test-run-index_advisor> server # [    1.205258] usbcore: registered new interface driver usbhid416vm-test-run-index_advisor> server # [    1.205400] usbhid: USB HID core driver417vm-test-run-index_advisor> server # [    1.214911] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available418vm-test-run-index_advisor> server # [    1.231063] drop_monitor: Initializing network drop monitor service419vm-test-run-index_advisor> server # [    1.233293] NET: Registered PF_INET6 protocol family420vm-test-run-index_advisor> server # [    1.252297] Segment Routing with IPv6421vm-test-run-index_advisor> server # [    1.252849] In-situ OAM (IOAM) with IPv6422vm-test-run-index_advisor> server # [    1.253533] NET: Registered PF_PACKET protocol family423vm-test-run-index_advisor> server # [    1.255833] 9pnet: Installing 9P2000 support424vm-test-run-index_advisor> server # [    1.260646] Key type dns_resolver registered425vm-test-run-index_advisor> server # [    1.298510] registered taskstats version 1426vm-test-run-index_advisor> server # [    1.301608] Loading compiled-in X.509 certificates427vm-test-run-index_advisor> server # [    1.366258] Demotion targets for Node 0: null428vm-test-run-index_advisor> server # [    1.368413] Key type .fscrypt registered429vm-test-run-index_advisor> server # [    1.368567] Key type fscrypt-provisioning registered430vm-test-run-index_advisor> server # [    1.378494] input: gpio-keys as /devices/platform/gpio-keys/input/input0431vm-test-run-index_advisor> server # [    1.424371] usb 1-1: new high-speed USB device number 2 using ehci-pci432vm-test-run-index_advisor> server # [    1.425941] clk: Disabling unused clocks433vm-test-run-index_advisor> server # [    1.426527] PM: genpd: Disabling unused power domains434vm-test-run-index_advisor> server # [    1.426853] ALSA device list:435vm-test-run-index_advisor> server # [    1.426970]   No soundcards found.436vm-test-run-index_advisor> server # [    1.512029] Freeing unused kernel memory: 4416K437vm-test-run-index_advisor> server # [    1.513316] Run /init as init process438vm-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/input1439vm-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/input0440vm-test-run-index_advisor> server # [    1.880869] usb 1-2: new high-speed USB device number 3 using ehci-pci441vm-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/input2442vm-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/input0443vm-test-run-index_advisor> server # [    2.130315] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev444vm-test-run-index_advisor> server # [    2.264652] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0445vm-test-run-index_advisor> server # [    2.265377] [drm] features: -virgl +edid -resource_blob -host_visible446vm-test-run-index_advisor> server # [    2.265401] [drm] features: -context_init447vm-test-run-index_advisor> server # [    2.267212] [drm] number of scanouts: 1448vm-test-run-index_advisor> server # [    2.267322] [drm] number of cap sets: 0449vm-test-run-index_advisor> server # [    2.278617] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0450vm-test-run-index_advisor> server # [    2.312688] Console: switching to colour frame buffer device 160x50451vm-test-run-index_advisor> server # [    2.324243] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device452vm-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.453vm-test-run-index_advisor> server # [    7.961540] EXT4-fs (vda): re-mounted afbf7685-dbf9-4cad-9cf1-055240f4a83b.454vm-test-run-index_advisor> server # [    7.981787] booting system configuration /nix/store/7k8ylb9h0y3gvmdxydaznvwgxdplazyq-nixos-system-server-test455vm-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)456vm-test-run-index_advisor> server # [   21.737664] systemd[1]: Detected virtualization qemu.457vm-test-run-index_advisor> server # [   21.742720] systemd[1]: Detected architecture arm64.458vm-test-run-index_advisor> server # [   21.747496] systemd[1]: Detected first boot.459vm-test-run-index_advisor> server # [   21.777876] systemd[1]: Initializing machine ID from random generator.460vm-test-run-index_advisor> server # [   21.813996] systemd[1]: Hostname set to <server>.461vm-test-run-index_advisor> server # [   23.070486] systemd[1]: bpf-restrict-fs: LSM BPF program attached462vm-test-run-index_advisor> server # [   24.581403] systemd[1]: Applying preset policy.463vm-test-run-index_advisor> server # [   28.713437] systemd[1]: Populated /etc with preset unit settings.464vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...465vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.466vm-test-run-index_advisor> server # [   32.534500] systemd[1]: Queued start job for default target Multi-User System.467vm-test-run-index_advisor> server # [   32.760500] systemd[1]: Created slice Slice /system/getty.468vm-test-run-index_advisor> server # [   32.777078] systemd[1]: Created slice Slice /system/modprobe.469vm-test-run-index_advisor> server # [   32.790711] systemd[1]: Created slice User and Session Slice.470vm-test-run-index_advisor> server # [   32.801044] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.471vm-test-run-index_advisor> server # [   32.806823] systemd[1]: Started Forward Password Requests to Wall Directory Watch.472vm-test-run-index_advisor> server # [   32.813582] systemd[1]: Expecting device /dev/hvc0...473vm-test-run-index_advisor> server # [   32.818184] systemd[1]: Expecting device /dev/ttyAMA0...474vm-test-run-index_advisor> server # [   32.822779] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...475vm-test-run-index_advisor> server # [   32.827860] systemd[1]: Reached target Local Encrypted Volumes.476vm-test-run-index_advisor> server # [   32.832439] systemd[1]: Reached target Virtual Machines and Containers.477vm-test-run-index_advisor> server # [   32.837356] systemd[1]: Reached target Path Units.478vm-test-run-index_advisor> server # [   32.842381] systemd[1]: Reached target Remote File Systems.479vm-test-run-index_advisor> server # [   32.846074] systemd[1]: Reached target Slice Units.480vm-test-run-index_advisor> server # [   32.851676] systemd[1]: Reached target Swaps.481vm-test-run-index_advisor> server # [   32.889697] systemd[1]: Listening on Process Core Dump Socket.482vm-test-run-index_advisor> server # [   32.914980] systemd[1]: Listening on Credential Encryption/Decryption.483vm-test-run-index_advisor> server # [   32.926975] systemd[1]: Listening on Journal Audit Socket.484vm-test-run-index_advisor> server # [   32.934394] systemd[1]: Listening on Journal Socket (/dev/log).485vm-test-run-index_advisor> server # [   32.940919] systemd[1]: Listening on Journal Sockets.486vm-test-run-index_advisor> server # [   32.949450] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.487vm-test-run-index_advisor> server # [   32.953945] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).488vm-test-run-index_advisor> server # [   32.961122] systemd[1]: Listening on udev Control Socket.489vm-test-run-index_advisor> server # [   32.966669] systemd[1]: Listening on udev Kernel Socket.490vm-test-run-index_advisor> server # [   33.017236] systemd[1]: Mounting Huge Pages File System...491vm-test-run-index_advisor> server # [   33.052418] systemd[1]: Mounting POSIX Message Queue File System...492vm-test-run-index_advisor> server # [   33.096362] systemd[1]: Mounting Kernel Debug File System...493vm-test-run-index_advisor> server # [   33.174814] systemd[1]: Mounting Kernel Trace File System...494vm-test-run-index_advisor> server # [   33.297332] systemd[1]: Starting Create List of Static Device Nodes...495vm-test-run-index_advisor> server # [   33.390238] systemd[1]: Starting Load Kernel Module 9pnet_virtio...496vm-test-run-index_advisor> server # [   33.405556] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).497vm-test-run-index_advisor> server # [   33.564920] systemd[1]: Mounting Kernel Configuration File System...498vm-test-run-index_advisor> server # [   33.590890] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).499vm-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).500vm-test-run-index_advisor> server # [   33.737328] systemd[1]: Starting Load Kernel Module fuse...501vm-test-run-index_advisor> server # [   33.750077] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).502vm-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).503vm-test-run-index_advisor> server # [   33.896707] systemd[1]: Starting Journal Service...504vm-test-run-index_advisor> server # [   33.997630] systemd[1]: Starting Load Kernel Modules...505vm-test-run-index_advisor> server # [   34.065363] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...506vm-test-run-index_advisor> server # [   34.123315] systemd[1]: Starting Remount Root and Kernel File Systems...507vm-test-run-index_advisor> server # [   34.132921] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).508vm-test-run-index_advisor> server # [   34.179054] systemd[1]: Starting Coldplug All udev Devices...509vm-test-run-index_advisor> server # [   34.252355] systemd[1]: Mounted Huge Pages File System.510vm-test-run-index_advisor> server # [   34.259403] systemd[1]: Mounted POSIX Message Queue File System.511vm-test-run-index_advisor> server # [   34.273362] systemd[1]: Mounted Kernel Debug File System.512vm-test-run-index_advisor> server # [   34.290728] systemd[1]: Mounted Kernel Trace File System.513vm-test-run-index_advisor> server # [   34.313884] systemd[1]: Finished Create List of Static Device Nodes.514vm-test-run-index_advisor> server # [   34.335889] systemd[1]: Mounted Kernel Configuration File System.515vm-test-run-index_advisor> server # [   34.426153] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...516vm-test-run-index_advisor> server # [   34.679679] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517vm-test-run-index_advisor> server # [   34.698497] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518vm-test-run-index_advisor> server # [   35.267320] fuse: init (API version 7.41)519vm-test-run-index_advisor> server # [   35.364263] systemd[1]: modprobe@fuse.service: Deactivated successfully.520vm-test-run-index_advisor> server # [   35.382095] systemd[1]: Finished Load Kernel Module fuse.521vm-test-run-index_advisor> server # [   35.404721] systemd[1]: Finished Load Kernel Modules.522vm-test-run-index_advisor> server # [   35.453801] systemd[1]: Mounting FUSE Control File System...523vm-test-run-index_advisor> server # [   35.478944] EXT4-fs (vda): re-mounted afbf7685-dbf9-4cad-9cf1-055240f4a83b.524vm-test-run-index_advisor> server # [   35.565478] systemd[1]: Starting Firewall...525vm-test-run-index_advisor> server # [   35.670727] systemd[1]: Starting Apply Kernel Variables...526vm-test-run-index_advisor> server # [   35.729061] systemd[1]: Finished Remount Root and Kernel File Systems.527vm-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).528vm-test-run-index_advisor> server # [   35.812397] systemd[1]: Starting Load/Save OS Random Seed...529vm-test-run-index_advisor> server # [   35.820143] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).530vm-test-run-index_advisor> server # [   35.854544] systemd[1]: Mounted FUSE Control File System.531vm-test-run-index_advisor> server # [   36.005629] systemd-journald[395]: Collecting audit messages is enabled.532vm-test-run-index_advisor> server # [   36.652594] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.533vm-test-run-index_advisor> server # [   36.733187] systemd[1]: Starting Create Static Device Nodes in /dev...534vm-test-run-index_advisor> server # [   36.812718] systemd[1]: Finished Load/Save OS Random Seed.535vm-test-run-index_advisor> server # [   36.822439] systemd[1]: Reached target First Boot Complete.536vm-test-run-index_advisor> server # [   37.165372] systemd[1]: Finished Apply Kernel Variables.537vm-test-run-index_advisor> server # [   37.485353] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.538vm-test-run-index_advisor> server # [   38.009333] systemd[1]: Finished Create Static Device Nodes in /dev.539vm-test-run-index_advisor> server # [   38.031493] systemd[1]: Reached target Preparation for Local File Systems.540vm-test-run-index_advisor> server # [   38.083432] systemd[1]: Mounting /run/wrappers...541vm-test-run-index_advisor> server # [   38.138017] systemd[1]: Starting Rule-based Manager for Device Events and Files...542vm-test-run-index_advisor> server # [   38.518431] systemd[1]: Mounted /run/wrappers.543vm-test-run-index_advisor> server # [   38.534980] systemd[1]: Reached target Local File Systems.544vm-test-run-index_advisor> server # [   38.565897] systemd[1]: Listening on Boot Entries Service Socket.545vm-test-run-index_advisor> server # [   38.612355] systemd[1]: Starting Create SUID/SGID Wrappers...546vm-test-run-index_advisor> server # [   38.618243] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.547vm-test-run-index_advisor> server # [   38.689676] systemd[1]: Starting Save Transient machine-id to Disk...548vm-test-run-index_advisor> server # [   38.933151] systemd[1]: Started Journal Service.549vm-test-run-index_advisor> server # [   38.913132] systemd-modules-load[396]: Module 'atkbd' is built in550vm-test-run-index_advisor> server # [   38.933247] systemd-modules-load[396]: Module 'loop' is built in551vm-test-run-index_advisor> server # [   38.991171] systemd-oomd[397]: No swap; memory pressure usage will be degraded552vm-test-run-index_advisor> server # [   39.050133] systemd[1]: Starting Flush Journal to Persistent Storage...553vm-test-run-index_advisor> server # [   39.738633] systemd-udevd[422]: Using default interface naming scheme 'v258'.554vm-test-run-index_advisor> server # [   39.801543] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.555vm-test-run-index_advisor> server # [   39.866908] systemd[1]: Finished Save Transient machine-id to Disk.556vm-test-run-index_advisor> server # [   40.045312] systemd-journald[395]: Received client request to flush runtime journal.557vm-test-run-index_advisor> server # [   40.577632] systemd[1]: Finished Flush Journal to Persistent Storage.558vm-test-run-index_advisor> server # [   40.599171] systemd[1]: Starting Create System Files and Directories...559vm-test-run-index_advisor> server # [   42.464410] systemd[1]: Started Rule-based Manager for Device Events and Files.560vm-test-run-index_advisor> server # [   42.528057] systemd[1]: Finished Create System Files and Directories.561vm-test-run-index_advisor> server # [   42.621052] systemd[1]: Starting Rebuild Journal Catalog...562vm-test-run-index_advisor> server # [   42.689535] systemd[1]: Starting Record System Boot/Shutdown in UTMP...563vm-test-run-index_advisor> server # [   43.943147] systemd[1]: Finished Record System Boot/Shutdown in UTMP.564vm-test-run-index_advisor> server # [   45.039284] systemd[1]: Finished Rebuild Journal Catalog.565vm-test-run-index_advisor> server # [   45.090082] systemd[1]: Starting Update is Completed...566vm-test-run-index_advisor> server # [   45.773806] systemd[1]: Finished Update is Completed.567vm-test-run-index_advisor> server # [   49.261709] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.568vm-test-run-index_advisor> server # [   49.272580] systemd[1]: Finished Create SUID/SGID Wrappers.569vm-test-run-index_advisor> server # [   49.274578] systemd[1]: suid-sgid-wrappers.service: Consumed 2.856s CPU time, 5.6M memory peak.570vm-test-run-index_advisor> server # [   52.258493] systemd[1]: Finished Firewall.571vm-test-run-index_advisor> server # [   53.715652] systemd[1]: Finished Coldplug All udev Devices.572vm-test-run-index_advisor> server # [   53.726036] systemd[1]: Reached target System Initialization.573vm-test-run-index_advisor> server # [   53.737752] systemd[1]: Started Discard unused filesystem blocks once a week.574vm-test-run-index_advisor> server # [   53.745700] systemd[1]: Started Daily Cleanup of Temporary Directories.575vm-test-run-index_advisor> server # [   53.750898] systemd[1]: Reached target Timer Units.576vm-test-run-index_advisor> server # [   53.773022] systemd[1]: Listening on D-Bus System Message Bus Socket.577vm-test-run-index_advisor> server # [   53.775118] systemd[1]: Listening on Nix Daemon Socket.578vm-test-run-index_advisor> server # [   53.785870] systemd[1]: Listening on Hostname Service Socket.579vm-test-run-index_advisor> server # [   53.790461] systemd[1]: Reached target Socket Units.580vm-test-run-index_advisor> server # [   53.792246] systemd[1]: Reached target Basic System.581vm-test-run-index_advisor> server # [   53.803768] systemd[1]: Starting Import lastlog data into lastlog2 database...582vm-test-run-index_advisor> server # [   53.846988] systemd[1]: Starting Name Service Cache Daemon (nsncd)...583vm-test-run-index_advisor> server # [   53.919273] systemd[1]: Started Reset console on configuration changes.584vm-test-run-index_advisor> server # [   53.977072] systemd[1]: Starting resolvconf update...585vm-test-run-index_advisor> server # [   54.049029] systemd[1]: Starting D-Bus System Message Bus...586vm-test-run-index_advisor> server # [   55.043398] systemd[1]: Finished Import lastlog data into lastlog2 database.587vm-test-run-index_advisor> server # [   55.173250] systemd[1]: Started Name Service Cache Daemon (nsncd).588vm-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"589vm-test-run-index_advisor> server # [   55.231571] systemd[1]: Reached target Host and Network Name Lookups.590vm-test-run-index_advisor> server # [   55.245895] systemd[1]: Reached target User and Group Name Lookups.591vm-test-run-index_advisor> server # [   55.285228] systemd[1]: Starting User Login Management...592vm-test-run-index_advisor> server # [   55.514880] dbus-daemon[632]: dbus[632]: Unknown username "systemd-timesync" in message bus configuration file593vm-test-run-index_advisor> server # [   55.926520] systemd[1]: Started D-Bus System Message Bus.594vm-test-run-index_advisor> server # [   56.787544] systemd-logind[649]: New seat seat0.595vm-test-run-index_advisor> server # [   56.845050] systemd[1]: Started User Login Management.596vm-test-run-index_advisor> server # [   56.883006] systemd[1]: Starting linger-users.service...597vm-test-run-index_advisor> server # [   56.942115] systemd[1]: Stopped target Host and Network Name Lookups.598vm-test-run-index_advisor> server # [   56.954713] systemd[1]: Stopping Host and Network Name Lookups...599vm-test-run-index_advisor> server # [   56.958332] systemd[1]: Stopped target User and Group Name Lookups.600vm-test-run-index_advisor> server # [   56.965104] systemd[1]: Stopping User and Group Name Lookups...601vm-test-run-index_advisor> server # [   56.972071] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...602vm-test-run-index_advisor> server # [   57.047329] systemd[1]: nscd.service: Deactivated successfully.603vm-test-run-index_advisor> server # [   57.073416] systemd[1]: Stopped Name Service Cache Daemon (nsncd).604vm-test-run-index_advisor> server # [   57.201167] systemd[1]: Starting Name Service Cache Daemon (nsncd)...605vm-test-run-index_advisor> server # [   57.535875] systemd[1]: linger-users.service: Deactivated successfully.606vm-test-run-index_advisor> server # [   57.546708] systemd[1]: Finished linger-users.service.607vm-test-run-index_advisor> server # [   57.896013] systemd[1]: Started Name Service Cache Daemon (nsncd).608vm-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"609vm-test-run-index_advisor> server # [   57.929593] systemd[1]: Reached target Host and Network Name Lookups.610vm-test-run-index_advisor> server # [   57.931712] systemd[1]: Reached target User and Group Name Lookups.611vm-test-run-index_advisor> server # [   58.423520] systemd[1]: Finished resolvconf update.612vm-test-run-index_advisor> server # [   58.441986] systemd[1]: Reached target Preparation for Network.613vm-test-run-index_advisor> server # [   58.482229] systemd[1]: Starting DHCP Client...614vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...615vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.616vm-test-run-index_advisor> server # [   59.670217] dhcpcd[740]: dhcpcd-10.2.4 starting617vm-test-run-index_advisor> server # [   59.787118] dhcpcd[745]: dev: loaded udev618vm-test-run-index_advisor> server # [   60.272731] 8021q: 802.1Q VLAN Support v1.8619vm-test-run-index_advisor> server # [   60.837856] cfg80211: Loading compiled-in X.509 certificates for regulatory database620vm-test-run-index_advisor> server # [   61.094990] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'621vm-test-run-index_advisor> server # [   61.106135] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'622vm-test-run-index_advisor> server # [   61.124707] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2623vm-test-run-index_advisor> server # [   61.131869] cfg80211: failed to load regulatory.db624vm-test-run-index_advisor> server # [   61.490894] systemd[1]: Found device /dev/hvc0.625vm-test-run-index_advisor> server # [   61.974009] 8021q: adding VLAN 0 to HW filter on device eth0626vm-test-run-index_advisor> server # [   61.919299] dhcpcd[745]: eth0: waiting for carrier627vm-test-run-index_advisor> server # [   61.946924] dhcpcd[745]: eth0: carrier acquired628vm-test-run-index_advisor> server # [   62.077919] dhcpcd[745]: DUID 00:01:00:01:32:6d:40:55:52:54:00:12:34:56629vm-test-run-index_advisor> server # [   62.082413] dhcpcd[745]: eth0: IAID 00:12:34:56630vm-test-run-index_advisor> server # [   62.089172] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456631vm-test-run-index_advisor> server # [   62.532883] dhcpcd[745]: eth0: soliciting a DHCP lease632vm-test-run-index_advisor> server # [   62.573696] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2633vm-test-run-index_advisor> server # [   62.605917] dhcpcd[745]: eth0: probing address 10.0.2.15/24634vm-test-run-index_advisor> server # [   63.129635] systemd[1]: Found device /dev/ttyAMA0.635vm-test-run-index_advisor> server # [   63.153912] systemd[1]: Started backdoor.service.636vm-test-run-index_advisor> server # connecting to host...637vm-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.638vm-test-run-index_advisor> server # [   63.797025] (udev-worker)[784]: Network interface NamePolicy= disabled on kernel command line.639vm-test-run-index_advisor> server: Guest shell says: b'Spawning backdoor root shell...\n'640vm-test-run-index_advisor> server: connected to guest root shell641vm-test-run-index_advisor> server: (connecting took 64.96 seconds)642vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 64.96 seconds)643vm-test-run-index_advisor> server # [   64.495417] dhcpcd[745]: eth0: soliciting an IPv6 router644vm-test-run-index_advisor> server # [   64.517307] dhcpcd[745]: eth0: Router Advertisement from fe80::2645vm-test-run-index_advisor> server # [   64.527246] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64646vm-test-run-index_advisor> server # [   64.537925] dhcpcd[745]: eth0: adding route to fec0::/64647vm-test-run-index_advisor> server # [   64.539078] dhcpcd[745]: eth0: adding default route via fe80::2648vm-test-run-index_advisor> server # [   64.869813] (udev-worker)[792]: Network interface NamePolicy= disabled on kernel command line.649vm-test-run-index_advisor> server # [   65.608660] systemd[1]: Found device Virtio network device.650vm-test-run-index_advisor> server # [   65.691841] systemd[1]: Starting Address configuration of eth1...651vm-test-run-index_advisor> server # [   66.391300] dhcpcd[745]: eth0: IAID 00:12:34:56652vm-test-run-index_advisor> server # [   66.540532] 8021q: adding VLAN 0 to HW filter on device eth1653vm-test-run-index_advisor> server # [   66.635399] network-addresses-eth1-start[818]: adding address 192.168.1.1/24... done654vm-test-run-index_advisor> server # [   66.790954] dhcpcd[745]: eth0: soliciting a DHCP lease655vm-test-run-index_advisor> server # [   66.801979] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2656vm-test-run-index_advisor> server # [   66.803138] dhcpcd[745]: eth0: probing address 10.0.2.15/24657vm-test-run-index_advisor> server # [   66.858770] network-addresses-eth1-start[818]: adding address 2001:db8:1::1/64... done658vm-test-run-index_advisor> server # [   66.877512] dhcpcd[745]: eth0: soliciting an IPv6 router659vm-test-run-index_advisor> server # [   67.228814] systemd[1]: Finished Address configuration of eth1.660vm-test-run-index_advisor> server # [   67.341348] systemd[1]: Starting Networking Setup...661vm-test-run-index_advisor> server # [   68.374848] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds662vm-test-run-index_advisor> server # [   68.402612] dhcpcd[745]: eth0: adding route to 10.0.2.0/24663vm-test-run-index_advisor> server # [   68.403959] dhcpcd[745]: eth0: adding default route via 10.0.2.2664vm-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 permitted665vm-test-run-index_advisor> server # [   69.042651] dhcpcd[876]: clearing stale lock pid 857666vm-test-run-index_advisor> server # [   70.121046] systemd[1]: Finished Networking Setup.667vm-test-run-index_advisor> server # [   70.122464] systemd[1]: Reached target Network.668vm-test-run-index_advisor> server # [   70.152318] systemd[1]: Starting PostgreSQL Database Server...669vm-test-run-index_advisor> server # [   70.214380] systemd[1]: Starting Permit User Sessions...670vm-test-run-index_advisor> server # [   71.010404] systemd[1]: Finished Permit User Sessions.671vm-test-run-index_advisor> server # [   71.103082] systemd[1]: Started Getty on tty1.672vm-test-run-index_advisor> server # [   71.131484] systemd[1]: Reached target Login Prompts.673vm-test-run-index_advisor> server # [   71.810376] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[959]: Initializing database at /var/lib/postgresql/data674vm-test-run-index_advisor> server # [   72.468492] systemd[1]: Started DHCP Client.675vm-test-run-index_advisor> server # [   72.914568] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys)676vm-test-run-index_advisor> server # [   73.559926] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The files belonging to this database system will be owned by user "postgres".677vm-test-run-index_advisor> server # [   73.562292] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: This user must also own the server process.678vm-test-run-index_advisor> server # [   73.710103] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The database cluster will be initialized with locale "en_US.UTF-8".679vm-test-run-index_advisor> server # [   73.718008] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The default database encoding has accordingly been set to "UTF8".680vm-test-run-index_advisor> server # [   73.719805] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: The default text search configuration will be set to "english".681vm-test-run-index_advisor> server # [   73.726571] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: Data page checksums are enabled.682vm-test-run-index_advisor> server # [   73.727226] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: fixing permissions on existing directory /var/lib/postgresql/data ... ok683vm-test-run-index_advisor> server # [   73.735197] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: creating subdirectories ... ok684vm-test-run-index_advisor> server # [   73.740354] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting dynamic shared memory implementation ... posix685vm-test-run-index_advisor> server # [   74.709410] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting default max_connections ... 100686vm-test-run-index_advisor> server # [   75.663303] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting default shared_buffers ... 128MB687vm-test-run-index_advisor> server # [   77.236659] mousedev: PS/2 mouse device common for all mice688vm-test-run-index_advisor> server # [   80.779223] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)689vm-test-run-index_advisor> server # [   85.777775] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: selecting default time zone ... UTC690vm-test-run-index_advisor> server # [   85.821193] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: creating configuration files ... ok691vm-test-run-index_advisor> server # [   89.333650] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: running bootstrap script ... ok692vm-test-run-index_advisor> server # [  100.630512] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: performing post-bootstrap initialization ... ok693vm-test-run-index_advisor> server # [  102.777199] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: syncing data to disk ... ok694vm-test-run-index_advisor> server # [  102.782754] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: initdb: warning: enabling "trust" authentication for local connections695vm-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.696vm-test-run-index_advisor> server # [  102.786334] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[968]: Success. You can now start the database server using:697vm-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 start698vm-test-run-index_advisor> server # [  104.207402] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[959]: Validating PostgreSQL configuration...699vm-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_vault700vm-test-run-index_advisor> server # [  105.453193] postgres[1333]:  2026-10-22 21:45:36.844 UTC [1333] LOG:  pgaudit extension initialized701vm-test-run-index_advisor> server # [  106.254445] postgres[1333]:  2026-10-22 21:45:37.648 UTC [1333] LOG:  pgsodium primary server secret key loaded702vm-test-run-index_advisor> server # [  106.924790] postgres[1333]:  2026-10-22 21:45:38.312 UTC [1333] LOG:  vault primary server secret key loaded703vm-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-bit704vm-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 5432705vm-test-run-index_advisor> server # [  107.637769] postgres[1333]:  2026-10-22 21:45:39.036 UTC [1333] LOG:  listening on IPv6 address "::", port 5432706vm-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"707vm-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 UTC708vm-test-run-index_advisor> server # [  107.944429] postgres[1372]:  2026-10-22 21:45:39.334 UTC [1372] FATAL:  role "postgres" does not exist709vm-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 connections710vm-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 catalogs711vm-test-run-index_advisor> server # [  108.057585] systemd[1]: Started PostgreSQL Database Server.712vm-test-run-index_advisor> server # [  108.090391] systemd[1]: Starting Supabase Database Initialization...713vm-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 1714vm-test-run-index_advisor> server # [  108.375823] postgres[1373]:  2026-10-22 21:45:39.773 UTC [1373] LOG:  pg_cron scheduler started715vm-test-run-index_advisor> server # [  108.418397] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Waiting for PostgreSQL to be ready...716vm-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 exist717vm-test-run-index_advisor> server # [  109.237013] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: PostgreSQL is ready718vm-test-run-index_advisor> server # [  109.239209] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Creating postgres role...719vm-test-run-index_advisor> server # [  109.342734] postgres[1384]:  2026-10-22 21:45:40.708 UTC [1384] FATAL:  role "postgres" does not exist720vm-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 1721vm-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;722vm-test-run-index_advisor> server # [  109.797896] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1385]: CREATE ROLE723vm-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;724vm-test-run-index_advisor> server # [  110.197217] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1398]: ALTER DATABASE725vm-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.sql726vm-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;727vm-test-run-index_advisor> server # [  110.810381] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE PUBLICATION728vm-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;729vm-test-run-index_advisor> server # [  110.817043] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE730vm-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;731vm-test-run-index_advisor> server # [  110.833017] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE732vm-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;733vm-test-run-index_advisor> server # [  110.847140] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE734vm-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;735vm-test-run-index_advisor> server # [  110.859872] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE736vm-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;737vm-test-run-index_advisor> server # [  110.872432] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT738vm-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;739vm-test-run-index_advisor> server # [  110.879929] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE740vm-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;741vm-test-run-index_advisor> server # [  110.890376] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE742vm-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;743vm-test-run-index_advisor> server # [  110.905932] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE SCHEMA744vm-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;745vm-test-run-index_advisor> server # [  113.539322] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE EXTENSION746vm-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;747vm-test-run-index_advisor> server # [  114.425951] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE EXTENSION748vm-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;749vm-test-run-index_advisor> server # [  114.439452] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE750vm-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;751vm-test-run-index_advisor> server # [  114.449780] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE752vm-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;753vm-test-run-index_advisor> server # [  114.465931] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE754vm-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;755vm-test-run-index_advisor> server # [  114.479917] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: CREATE ROLE756vm-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;757vm-test-run-index_advisor> server # [  114.496757] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE758vm-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;759vm-test-run-index_advisor> server # [  114.517825] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE760vm-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;761vm-test-run-index_advisor> server # [  114.534364] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE762vm-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;763vm-test-run-index_advisor> server # [  114.550696] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT ROLE764vm-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;765vm-test-run-index_advisor> server # [  114.571592] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT766vm-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;767vm-test-run-index_advisor> server # [  114.611275] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES768vm-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;769vm-test-run-index_advisor> server # [  114.633543] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES770vm-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;771vm-test-run-index_advisor> server # [  114.647968] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES772vm-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;773vm-test-run-index_advisor> server # [  114.664579] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: GRANT774vm-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;775vm-test-run-index_advisor> server # [  114.674933] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE776vm-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 all777vm-test-run-index_advisor> server # [  114.680666] postgres[1415]: 	    on sequences to postgres, anon, authenticated, service_role;778vm-test-run-index_advisor> server # [  114.690039] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES779vm-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 all780vm-test-run-index_advisor> server # [  114.691355] postgres[1415]: 	    on tables to postgres, anon, authenticated, service_role;781vm-test-run-index_advisor> server # [  114.698969] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES782vm-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 all783vm-test-run-index_advisor> server # [  114.703308] postgres[1415]: 	    on functions to postgres, anon, authenticated, service_role;784vm-test-run-index_advisor> server # [  114.714247] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER DEFAULT PRIVILEGES785vm-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';786vm-test-run-index_advisor> server # [  114.721737] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE787vm-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';788vm-test-run-index_advisor> server # [  114.733271] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1411]: ALTER ROLE789vm-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.sql790vm-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;791vm-test-run-index_advisor> server # [  115.046231] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE SCHEMA792vm-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 (793vm-test-run-index_advisor> server # [  115.050684] postgres[1445]: 	    instance_id uuid NULL,794vm-test-run-index_advisor> server # [  115.051647] postgres[1445]: 	    id uuid NOT NULL UNIQUE,795vm-test-run-index_advisor> server # [  115.061065] postgres[1445]: 	    aud varchar(255) NULL,796vm-test-run-index_advisor> server # [  115.062091] postgres[1445]: 	    "role" varchar(255) NULL,797vm-test-run-index_advisor> server # [  115.063675] postgres[1445]: 	    email varchar(255) NULL UNIQUE,798vm-test-run-index_advisor> server # [  115.067775] postgres[1445]: 	    encrypted_password varchar(255) NULL,799vm-test-run-index_advisor> server # [  115.072064] postgres[1445]: 	    confirmed_at timestamptz NULL,800vm-test-run-index_advisor> server # [  115.073650] postgres[1445]: 	    invited_at timestamptz NULL,801vm-test-run-index_advisor> server # [  115.074882] postgres[1445]: 	    confirmation_token varchar(255) NULL,802vm-test-run-index_advisor> server # [  115.075329] postgres[1445]: 	    confirmation_sent_at timestamptz NULL,803vm-test-run-index_advisor> server # [  115.078884] postgres[1445]: 	    recovery_token varchar(255) NULL,804vm-test-run-index_advisor> server # [  115.080481] postgres[1445]: 	    recovery_sent_at timestamptz NULL,805vm-test-run-index_advisor> server # [  115.081838] postgres[1445]: 	    email_change_token varchar(255) NULL,806vm-test-run-index_advisor> server # [  115.082206] postgres[1445]: 	    email_change varchar(255) NULL,807vm-test-run-index_advisor> server # [  115.083178] postgres[1445]: 	    email_change_sent_at timestamptz NULL,808vm-test-run-index_advisor> server # [  115.083757] postgres[1445]: 	    last_sign_in_at timestamptz NULL,809vm-test-run-index_advisor> server # [  115.084993] postgres[1445]: 	    raw_app_meta_data jsonb NULL,810vm-test-run-index_advisor> server # [  115.086270] postgres[1445]: 	    raw_user_meta_data jsonb NULL,811vm-test-run-index_advisor> server # [  115.087018] postgres[1445]: 	    is_super_admin bool NULL,812vm-test-run-index_advisor> server # [  115.087600] postgres[1445]: 	    created_at timestamptz NULL,813vm-test-run-index_advisor> server # [  115.087893] postgres[1445]: 	    updated_at timestamptz NULL,814vm-test-run-index_advisor> server # [  115.089256] postgres[1445]: 	    CONSTRAINT users_pkey PRIMARY KEY (id)815vm-test-run-index_advisor> server # [  115.090950] postgres[1445]: 	);816vm-test-run-index_advisor> server # [  115.178284] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE817vm-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);818vm-test-run-index_advisor> server # [  115.214845] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX819vm-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);820vm-test-run-index_advisor> server # [  115.241885] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX821vm-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.';822vm-test-run-index_advisor> server # [  115.250579] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT823vm-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 (824vm-test-run-index_advisor> server # [  115.252739] postgres[1445]: 	    instance_id uuid NULL,825vm-test-run-index_advisor> server # [  115.257546] postgres[1445]: 	    id bigserial NOT NULL,826vm-test-run-index_advisor> server # [  115.258414] postgres[1445]: 	    "token" varchar(255) NULL,827vm-test-run-index_advisor> server # [  115.259130] postgres[1445]: 	    user_id varchar(255) NULL,828vm-test-run-index_advisor> server # [  115.259879] postgres[1445]: 	    revoked bool NULL,829vm-test-run-index_advisor> server # [  115.264163] postgres[1445]: 	    created_at timestamptz NULL,830vm-test-run-index_advisor> server # [  115.268060] postgres[1445]: 	    updated_at timestamptz NULL,831vm-test-run-index_advisor> server # [  115.268462] postgres[1445]: 	    CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)832vm-test-run-index_advisor> server # [  115.270250] postgres[1445]: 	);833vm-test-run-index_advisor> server # [  115.339211] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE834vm-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);835vm-test-run-index_advisor> server # [  115.367626] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX836vm-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);837vm-test-run-index_advisor> server # [  115.394922] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX838vm-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);839vm-test-run-index_advisor> server # [  115.445239] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX840vm-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.';841vm-test-run-index_advisor> server # [  115.463458] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT842vm-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 (843vm-test-run-index_advisor> server # [  115.489470] postgres[1445]: 	    id uuid NOT NULL,844vm-test-run-index_advisor> server # [  115.491835] postgres[1445]: 	    uuid uuid NULL,845vm-test-run-index_advisor> server # [  115.498331] postgres[1445]: 	    raw_base_config text NULL,846vm-test-run-index_advisor> server # [  115.506554] postgres[1445]: 	    created_at timestamptz NULL,847vm-test-run-index_advisor> server # [  115.519607] postgres[1445]: 	    updated_at timestamptz NULL,848vm-test-run-index_advisor> server # [  115.522516] postgres[1445]: 	    CONSTRAINT instances_pkey PRIMARY KEY (id)849vm-test-run-index_advisor> server # [  115.523326] postgres[1445]: 	);850vm-test-run-index_advisor> server # [  115.588921] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE851vm-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.';852vm-test-run-index_advisor> server # [  115.609887] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT853vm-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 (854vm-test-run-index_advisor> server # [  115.620762] postgres[1445]: 	    instance_id uuid NULL,855vm-test-run-index_advisor> server # [  115.629498] postgres[1445]: 	    id uuid NOT NULL,856vm-test-run-index_advisor> server # [  115.631126] postgres[1445]: 	    payload json NULL,857vm-test-run-index_advisor> server # [  115.636904] postgres[1445]: 	    created_at timestamptz NULL,858vm-test-run-index_advisor> server # [  115.638213] postgres[1445]: 	    CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)859vm-test-run-index_advisor> server # [  115.639347] postgres[1445]: 	);860vm-test-run-index_advisor> server # [  115.724405] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE861vm-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);862vm-test-run-index_advisor> server # [  115.793400] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE INDEX863vm-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.';864vm-test-run-index_advisor> server # [  115.811036] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT865vm-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 (866vm-test-run-index_advisor> server # [  115.826001] postgres[1445]: 	    "version" varchar(255) NOT NULL,867vm-test-run-index_advisor> server # [  115.833684] postgres[1445]: 	    CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")868vm-test-run-index_advisor> server # [  115.834515] postgres[1445]: 	);869vm-test-run-index_advisor> server # [  115.887412] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE TABLE870vm-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.';871vm-test-run-index_advisor> server # [  115.906147] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: COMMENT872vm-test-run-index_advisor> server # [  115.936899] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: INSERT 0 7873vm-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 $$874vm-test-run-index_advisor> server # [  115.946343] postgres[1445]: 	  select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;875vm-test-run-index_advisor> server # [  115.955255] postgres[1445]: 	$$ language sql stable;876vm-test-run-index_advisor> server # [  115.966432] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE FUNCTION877vm-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 $$878vm-test-run-index_advisor> server # [  115.975020] postgres[1445]: 	  select nullif(current_setting('request.jwt.claim.role', true), '')::text;879vm-test-run-index_advisor> server # [  115.981848] postgres[1445]: 	$$ language sql stable;880vm-test-run-index_advisor> server # [  115.992787] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE FUNCTION881vm-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 $$882vm-test-run-index_advisor> server # [  115.995704] postgres[1445]: 	  select nullif(current_setting('request.jwt.claim.email', true), '')::text;883vm-test-run-index_advisor> server # [  116.005751] postgres[1445]: 	$$ language sql stable;884vm-test-run-index_advisor> server # [  116.011760] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE FUNCTION885vm-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;886vm-test-run-index_advisor> server # [  116.026871] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT887vm-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;888vm-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;889vm-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;890vm-test-run-index_advisor> server # [  116.071118] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: CREATE ROLE891vm-test-run-index_advisor> server # [  116.072392] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT892vm-test-run-index_advisor> server # [  116.085891] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT893vm-test-run-index_advisor> server # [  116.086813] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: GRANT894vm-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;895vm-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";896vm-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;897vm-test-run-index_advisor> server # [  116.114891] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER ROLE898vm-test-run-index_advisor> server # [  116.130402] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE899vm-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;900vm-test-run-index_advisor> server # [  116.151579] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE901vm-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;902vm-test-run-index_advisor> server # [  116.167450] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE903vm-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;904vm-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;905vm-test-run-index_advisor> server # [  116.206792] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE906vm-test-run-index_advisor> server # [  116.232466] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1444]: ALTER TABLE907vm-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.sql908vm-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;909vm-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;910vm-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";911vm-test-run-index_advisor> server # [  116.635630] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: CREATE SCHEMA912vm-test-run-index_advisor> server # [  116.649369] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: CREATE ROLE913vm-test-run-index_advisor> server # [  116.650192] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: ALTER ROLE914vm-test-run-index_advisor> server # [  116.650708] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: GRANT915vm-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;916vm-test-run-index_advisor> server # [  116.688994] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1466]: DO917vm-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.sql918vm-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;919vm-test-run-index_advisor> server # [  117.092470] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: ALTER ROLE920vm-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;921vm-test-run-index_advisor> server # [  117.098684] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: ALTER ROLE922vm-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()923vm-test-run-index_advisor> server # [  117.101005] postgres[1473]: 	RETURNS event_trigger924vm-test-run-index_advisor> server # [  117.101434] postgres[1473]: 	LANGUAGE plpgsql925vm-test-run-index_advisor> server # [  117.101760] postgres[1473]: 	AS $$926vm-test-run-index_advisor> server # [  117.102218] postgres[1473]: 	DECLARE927vm-test-run-index_advisor> server # [  117.102725] postgres[1473]: 	  schema_is_cron bool;928vm-test-run-index_advisor> server # [  117.103199] postgres[1473]: 	BEGIN929vm-test-run-index_advisor> server # [  117.103454] postgres[1473]: 	  schema_is_cron = (930vm-test-run-index_advisor> server # [  117.104085] postgres[1473]: 	    SELECT n.nspname = 'cron'931vm-test-run-index_advisor> server # [  117.106161] postgres[1473]: 	    FROM pg_event_trigger_ddl_commands() AS ev932vm-test-run-index_advisor> server # [  117.106705] postgres[1473]: 	    LEFT JOIN pg_catalog.pg_namespace AS n933vm-test-run-index_advisor> server # [  117.107110] postgres[1473]: 	      ON ev.objid = n.oid934vm-test-run-index_advisor> server # [  117.107965] postgres[1473]: 	  );935vm-test-run-index_advisor> server # [  117.108261] postgres[1473]:936vm-test-run-index_advisor> server # [  117.109105] postgres[1473]: 	  IF schema_is_cron937vm-test-run-index_advisor> server # [  117.110144] postgres[1473]: 	  THEN938vm-test-run-index_advisor> server # [  117.110449] postgres[1473]: 	    grant usage on schema cron to postgres with grant option;939vm-test-run-index_advisor> server # [  117.111082] postgres[1473]:940vm-test-run-index_advisor> server # [  117.111332] postgres[1473]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;941vm-test-run-index_advisor> server # [  117.112155] postgres[1473]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;942vm-test-run-index_advisor> server # [  117.112905] postgres[1473]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;943vm-test-run-index_advisor> server # [  117.114104] postgres[1473]:944vm-test-run-index_advisor> server # [  117.114604] postgres[1473]: 	    alter default privileges for user supabase_admin in schema cron grant all945vm-test-run-index_advisor> server # [  117.115955] postgres[1473]: 	        on sequences to postgres with grant option;946vm-test-run-index_advisor> server # [  117.117673] postgres[1473]: 	    alter default privileges for user supabase_admin in schema cron grant all947vm-test-run-index_advisor> server # [  117.118038] postgres[1473]: 	        on tables to postgres with grant option;948vm-test-run-index_advisor> server # [  117.119541] postgres[1473]: 	    alter default privileges for user supabase_admin in schema cron grant all949vm-test-run-index_advisor> server # [  117.120031] postgres[1473]: 	        on functions to postgres with grant option;950vm-test-run-index_advisor> server # [  117.121411] postgres[1473]:951vm-test-run-index_advisor> server # [  117.122178] postgres[1473]: 	    grant all privileges on all tables in schema cron to postgres with grant option;952vm-test-run-index_advisor> server # [  117.122784] postgres[1473]:953vm-test-run-index_advisor> server # [  117.123816] postgres[1473]: 	  END IF;954vm-test-run-index_advisor> server # [  117.125221] postgres[1473]:955vm-test-run-index_advisor> server # [  117.125618] postgres[1473]: 	END;956vm-test-run-index_advisor> server # [  117.126504] postgres[1473]: 	$$;957vm-test-run-index_advisor> server # [  117.141744] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE FUNCTION958vm-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')959vm-test-run-index_advisor> server # [  117.155046] postgres[1473]: 	EXECUTE PROCEDURE extensions.grant_pg_cron_access();960vm-test-run-index_advisor> server # [  117.155837] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE EVENT TRIGGER961vm-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';962vm-test-run-index_advisor> server # [  117.168381] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: COMMENT963vm-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()964vm-test-run-index_advisor> server # [  117.178378] postgres[1473]: 	RETURNS event_trigger965vm-test-run-index_advisor> server # [  117.178808] postgres[1473]: 	LANGUAGE plpgsql966vm-test-run-index_advisor> server # [  117.179079] postgres[1473]: 	AS $$967vm-test-run-index_advisor> server # [  117.183146] postgres[1473]: 	BEGIN968vm-test-run-index_advisor> server # [  117.184641] postgres[1473]: 	  IF EXISTS (969vm-test-run-index_advisor> server # [  117.189299] postgres[1473]: 	    SELECT 1970vm-test-run-index_advisor> server # [  117.190379] postgres[1473]: 	    FROM pg_event_trigger_ddl_commands() AS ev971vm-test-run-index_advisor> server # [  117.191568] postgres[1473]: 	    JOIN pg_extension AS ext972vm-test-run-index_advisor> server # [  117.198109] postgres[1473]: 	    ON ev.objid = ext.oid973vm-test-run-index_advisor> server # [  117.199105] postgres[1473]: 	    WHERE ext.extname = 'pg_net'974vm-test-run-index_advisor> server # [  117.199511] postgres[1473]: 	  )975vm-test-run-index_advisor> server # [  117.199814] postgres[1473]: 	  THEN976vm-test-run-index_advisor> server # [  117.205310] postgres[1473]: 	    IF NOT EXISTS (977vm-test-run-index_advisor> server # [  117.206683] postgres[1473]: 	      SELECT 1978vm-test-run-index_advisor> server # [  117.207758] postgres[1473]: 	      FROM pg_roles979vm-test-run-index_advisor> server # [  117.208061] postgres[1473]: 	      WHERE rolname = 'supabase_functions_admin'980vm-test-run-index_advisor> server # [  117.210147] postgres[1473]: 	    )981vm-test-run-index_advisor> server # [  117.210719] postgres[1473]: 	    THEN982vm-test-run-index_advisor> server # [  117.214484] postgres[1473]: 	      CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;983vm-test-run-index_advisor> server # [  117.215186] postgres[1473]: 	    END IF;984vm-test-run-index_advisor> server # [  117.215903] postgres[1473]:985vm-test-run-index_advisor> server # [  117.217120] postgres[1473]: 	    GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;986vm-test-run-index_advisor> server # [  117.222589] postgres[1473]:987vm-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;988vm-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;989vm-test-run-index_advisor> server # [  117.238913] postgres[1473]:990vm-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;991vm-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;992vm-test-run-index_advisor> server # [  117.256087] postgres[1473]:993vm-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;994vm-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;995vm-test-run-index_advisor> server # [  117.270326] postgres[1473]:996vm-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;997vm-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;998vm-test-run-index_advisor> server # [  117.283068] postgres[1473]: 	  END IF;999vm-test-run-index_advisor> server # [  117.283636] postgres[1473]: 	END;1000vm-test-run-index_advisor> server # [  117.284003] postgres[1473]: 	$$;1001vm-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';1002vm-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;1003vm-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;1004vm-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;1005vm-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;1006vm-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;1007vm-test-run-index_advisor> server # [  117.319811] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE FUNCTION1008vm-test-run-index_advisor> server # [  117.325326] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: COMMENT1009vm-test-run-index_advisor> server # [  117.325972] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: DO1010vm-test-run-index_advisor> server # [  117.326267] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: CREATE ROLE1011vm-test-run-index_advisor> server # [  117.326527] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1012vm-test-run-index_advisor> server # [  117.326824] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1013vm-test-run-index_advisor> server # [  117.327436] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1014vm-test-run-index_advisor> server # [  117.327725] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1015vm-test-run-index_advisor> server # [  117.327968] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1016vm-test-run-index_advisor> server # [  117.328205] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1017vm-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;1018vm-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;1019vm-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;1020vm-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;1021vm-test-run-index_advisor> server # [  117.350150] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1022vm-test-run-index_advisor> server # [  117.350829] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1023vm-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;1024vm-test-run-index_advisor> server # [  117.380970] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: GRANT1025vm-test-run-index_advisor> server # [  117.398610] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1470]: DO1026vm-test-run-index_advisor> server # [  117.439506] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running pgbouncer auth schema...1027vm-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;1028vm-test-run-index_advisor> server # [  117.684204] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: CREATE ROLE1029vm-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;1030vm-test-run-index_advisor> server # [  117.690523] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: REVOKE1031vm-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;1032vm-test-run-index_advisor> server # [  117.733252] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: CREATE SCHEMA1033vm-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)1034vm-test-run-index_advisor> server # [  117.737821] postgres[1475]: 	RETURNS TABLE(username TEXT, password TEXT) AS1035vm-test-run-index_advisor> server # [  117.738890] postgres[1475]: 	$$1036vm-test-run-index_advisor> server # [  117.739827] postgres[1475]: 	BEGIN1037vm-test-run-index_advisor> server # [  117.740194] postgres[1475]: 	    RAISE WARNING 'PgBouncer auth request: %', p_usename;1038vm-test-run-index_advisor> server # [  117.751731] postgres[1475]:1039vm-test-run-index_advisor> server # [  117.754499] postgres[1475]: 	    RETURN QUERY1040vm-test-run-index_advisor> server # [  117.756604] postgres[1475]: 	    SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1041vm-test-run-index_advisor> server # [  117.757409] postgres[1475]: 	    WHERE usename = p_usename;1042vm-test-run-index_advisor> server # [  117.758749] postgres[1475]: 	END;1043vm-test-run-index_advisor> server # [  117.759210] postgres[1475]: 	$$ LANGUAGE plpgsql1044vm-test-run-index_advisor> server # [  117.759823] postgres[1475]: 	SET search_path = ''1045vm-test-run-index_advisor> server # [  117.762086] postgres[1475]: 	SECURITY DEFINER;1046vm-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;1047vm-test-run-index_advisor> server # [  117.769911] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: CREATE FUNCTION1048vm-test-run-index_advisor> server # [  117.771165] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: REVOKE1049vm-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;1050vm-test-run-index_advisor> server # [  117.781206] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1474]: GRANT1051vm-test-run-index_advisor> server # [  117.819499] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running stat extension...1052vm-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;1053vm-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, skipping1054vm-test-run-index_advisor> server # [  118.229613] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1476]: CREATE SCHEMA1055vm-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;1056vm-test-run-index_advisor> server # [  119.589444] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1476]: CREATE EXTENSION1057vm-test-run-index_advisor> server # [  119.646180] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql1058vm-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;1059vm-test-run-index_advisor> server # [  120.019738] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1060vm-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;1061vm-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;1062vm-test-run-index_advisor> server # [  120.051653] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1063vm-test-run-index_advisor> server # [  120.052236] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1064vm-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;1065vm-test-run-index_advisor> server # [  120.064132] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1066vm-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;1067vm-test-run-index_advisor> server # [  120.080064] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1068vm-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;1069vm-test-run-index_advisor> server # [  120.098280] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1070vm-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;1071vm-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;1072vm-test-run-index_advisor> server # [  120.114055] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1073vm-test-run-index_advisor> server # [  120.134325] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1074vm-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;1075vm-test-run-index_advisor> server # [  120.213836] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: GRANT1076vm-test-run-index_advisor> server # [  120.267780] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: DO1077vm-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;1078vm-test-run-index_advisor> server # [  120.286491] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1502]: ALTER ROLE1079vm-test-run-index_advisor> server # [  120.336902] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql1080vm-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;1081vm-test-run-index_advisor> server # [  120.698108] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1082vm-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;1083vm-test-run-index_advisor> server # [  120.709125] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1084vm-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;1085vm-test-run-index_advisor> server # [  120.726348] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1086vm-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;1087vm-test-run-index_advisor> server # [  120.736021] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE1088vm-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;1089vm-test-run-index_advisor> server # [  120.748709] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE1090vm-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;1091vm-test-run-index_advisor> server # [  120.758202] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE1092vm-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;1093vm-test-run-index_advisor> server # [  120.771049] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE1094vm-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;1095vm-test-run-index_advisor> server # [  120.783180] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER TABLE1096vm-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;1097vm-test-run-index_advisor> server # [  120.798063] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1098vm-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;1099vm-test-run-index_advisor> server # [  120.814069] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1100vm-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;1101vm-test-run-index_advisor> server # [  120.829785] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1102vm-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;1103vm-test-run-index_advisor> server # [  120.842270] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: GRANT1104vm-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;1105vm-test-run-index_advisor> server # [  120.858279] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER DEFAULT PRIVILEGES1106vm-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;1107vm-test-run-index_advisor> server # [  120.869944] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER DEFAULT PRIVILEGES1108vm-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;1109vm-test-run-index_advisor> server # [  120.886870] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1508]: ALTER DEFAULT PRIVILEGES1110vm-test-run-index_advisor> server # [  120.932943] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql1111vm-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;1112vm-test-run-index_advisor> server # [  121.195753] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1510]: CREATE SCHEMA1113vm-test-run-index_advisor> server # [  121.224852] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1114vm-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;1115vm-test-run-index_advisor> server # [  121.497909] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT1116vm-test-run-index_advisor> server # [  121.499083] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT1117vm-test-run-index_advisor> server # [  121.499850] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT1118vm-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;1119vm-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;1120vm-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;1121vm-test-run-index_advisor> server # [  121.550779] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1512]: GRANT1122vm-test-run-index_advisor> server # [  121.555513] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql1123vm-test-run-index_advisor> server # [  121.991466] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1514]: DO1124vm-test-run-index_advisor> server # [  122.005032] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1514]: DO1125vm-test-run-index_advisor> server # [  122.033496] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1514]: DO1126vm-test-run-index_advisor> server # [  122.083478] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1127vm-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;1128vm-test-run-index_advisor> server # [  122.624868] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES1129vm-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;1130vm-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;1131vm-test-run-index_advisor> server # [  122.650663] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES1132vm-test-run-index_advisor> server # [  122.652484] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES1133vm-test-run-index_advisor> server # [  122.714110] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1134vm-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';1135vm-test-run-index_advisor> server # [  123.137640] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1538]: ALTER ROLE1136vm-test-run-index_advisor> server # [  123.147393] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1137vm-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;1138vm-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();1139vm-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 $$1140vm-test-run-index_advisor> server # [  123.379287] postgres[1545]: 	DECLARE1141vm-test-run-index_advisor> server # [  123.379937] postgres[1545]: 	  cmd record;1142vm-test-run-index_advisor> server # [  123.383293] postgres[1545]: 	BEGIN1143vm-test-run-index_advisor> server # [  123.383829] postgres[1545]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1144vm-test-run-index_advisor> server # [  123.392994] postgres[1545]: 	  LOOP1145vm-test-run-index_advisor> server # [  123.393737] postgres[1545]: 	    IF cmd.command_tag IN (1146vm-test-run-index_advisor> server # [  123.394747] postgres[1545]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1147vm-test-run-index_advisor> server # [  123.395477] postgres[1545]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1148vm-test-run-index_advisor> server # [  123.396095] postgres[1545]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1149vm-test-run-index_advisor> server # [  123.400238] postgres[1545]: 	    , 'CREATE VIEW', 'ALTER VIEW'1150vm-test-run-index_advisor> server # [  123.406677] postgres[1545]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1151vm-test-run-index_advisor> server # [  123.407166] postgres[1545]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1152vm-test-run-index_advisor> server # [  123.407618] postgres[1545]: 	    , 'CREATE TRIGGER'1153vm-test-run-index_advisor> server # [  123.408024] postgres[1545]: 	    , 'CREATE TYPE'1154vm-test-run-index_advisor> server # [  123.417164] postgres[1545]: 	    , 'CREATE RULE'1155vm-test-run-index_advisor> server # [  123.418101] postgres[1545]: 	    , 'COMMENT'1156vm-test-run-index_advisor> server # [  123.419340] postgres[1545]: 	    )1157vm-test-run-index_advisor> server # [  123.419940] postgres[1545]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1158vm-test-run-index_advisor> server # [  123.422309] postgres[1545]: 	    AND cmd.schema_name is distinct from 'pg_temp'1159vm-test-run-index_advisor> server # [  123.422860] postgres[1545]: 	    THEN1160vm-test-run-index_advisor> server # [  123.428936] postgres[1545]: 	      NOTIFY pgrst, 'reload schema';1161vm-test-run-index_advisor> server # [  123.429856] postgres[1545]: 	    END IF;1162vm-test-run-index_advisor> server # [  123.430365] postgres[1545]: 	  END LOOP;1163vm-test-run-index_advisor> server # [  123.430673] postgres[1545]: 	END; $$ LANGUAGE plpgsql;1164vm-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, skipping1165vm-test-run-index_advisor> server # [  123.435782] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP EVENT TRIGGER1166vm-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, skipping1167vm-test-run-index_advisor> server # [  123.446418] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP FUNCTION1168vm-test-run-index_advisor> server # [  123.448068] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE FUNCTION1169vm-test-run-index_advisor> server # [  123.449305] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE FUNCTION1170vm-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, skipping1171vm-test-run-index_advisor> server # [  123.450598] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP EVENT TRIGGER1172vm-test-run-index_advisor> server # [  123.451614] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE EVENT TRIGGER1173vm-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, skipping1174vm-test-run-index_advisor> server # [  123.454760] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: DROP EVENT TRIGGER1175vm-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 $$1176vm-test-run-index_advisor> server # [  123.456003] postgres[1545]: 	DECLARE1177vm-test-run-index_advisor> server # [  123.456372] postgres[1545]: 	  obj record;1178vm-test-run-index_advisor> server # [  123.456623] postgres[1545]: 	BEGIN1179vm-test-run-index_advisor> server # [  123.457935] postgres[1545]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1180vm-test-run-index_advisor> server # [  123.458674] postgres[1545]: 	  LOOP1181vm-test-run-index_advisor> server # [  123.459373] postgres[1545]: 	    IF obj.object_type IN (1182vm-test-run-index_advisor> server # [  123.459921] postgres[1545]: 	      'schema'1183vm-test-run-index_advisor> server # [  123.462351] postgres[1545]: 	    , 'table'1184vm-test-run-index_advisor> server # [  123.462835] postgres[1545]: 	    , 'foreign table'1185vm-test-run-index_advisor> server # [  123.463219] postgres[1545]: 	    , 'view'1186vm-test-run-index_advisor> server # [  123.463479] postgres[1545]: 	    , 'materialized view'1187vm-test-run-index_advisor> server # [  123.466032] postgres[1545]: 	    , 'function'1188vm-test-run-index_advisor> server # [  123.468024] postgres[1545]: 	    , 'trigger'1189vm-test-run-index_advisor> server # [  123.470314] postgres[1545]: 	    , 'type'1190vm-test-run-index_advisor> server # [  123.471742] postgres[1545]: 	    , 'rule'1191vm-test-run-index_advisor> server # [  123.473090] postgres[1545]: 	    )1192vm-test-run-index_advisor> server # [  123.473952] postgres[1545]: 	    AND obj.is_temporary IS false -- no pg_temp objects1193vm-test-run-index_advisor> server # [  123.474548] postgres[1545]: 	    THEN1194vm-test-run-index_advisor> server # [  123.475003] postgres[1545]: 	      NOTIFY pgrst, 'reload schema';1195vm-test-run-index_advisor> server # [  123.475280] postgres[1545]: 	    END IF;1196vm-test-run-index_advisor> server # [  123.475658] postgres[1545]: 	  END LOOP;1197vm-test-run-index_advisor> server # [  123.475951] postgres[1545]: 	END; $$ LANGUAGE plpgsql;1198vm-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;1199vm-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_watch1200vm-test-run-index_advisor> server # [  123.478877] postgres[1545]: 	  ON ddl_command_end1201vm-test-run-index_advisor> server # [  123.480821] postgres[1545]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1202vm-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;1203vm-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_watch1204vm-test-run-index_advisor> server # [  123.490676] postgres[1545]: 	  ON sql_drop1205vm-test-run-index_advisor> server # [  123.503227] postgres[1545]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1206vm-test-run-index_advisor> server # [  123.511298] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1544]: CREATE EVENT TRIGGER1207vm-test-run-index_advisor> server # [  123.518869] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1208vm-test-run-index_advisor> server # [  124.457486] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1547]: DO1209vm-test-run-index_advisor> server # [  124.506695] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql1210vm-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;1211vm-test-run-index_advisor> server # [  124.789724] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE SCHEMA1212vm-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, skipping1213vm-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);1214vm-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(1215vm-test-run-index_advisor> server # [  124.808034] postgres[1553]: 	    "operationName" text default null,1216vm-test-run-index_advisor> server # [  124.808400] postgres[1553]: 	    query text default null,1217vm-test-run-index_advisor> server # [  124.810755] postgres[1553]: 	    variables jsonb default null,1218vm-test-run-index_advisor> server # [  124.811735] postgres[1553]: 	    extensions jsonb default null1219vm-test-run-index_advisor> server # [  124.812134] postgres[1553]: 	)1220vm-test-run-index_advisor> server # [  124.812378] postgres[1553]: 	    returns jsonb1221vm-test-run-index_advisor> server # [  124.814236] postgres[1553]: 	    language plpgsql1222vm-test-run-index_advisor> server # [  124.815601] postgres[1553]: 	as $$1223vm-test-run-index_advisor> server # [  124.817067] postgres[1553]: 	    DECLARE1224vm-test-run-index_advisor> server # [  124.818855] postgres[1553]: 	        server_version float;1225vm-test-run-index_advisor> server # [  124.819328] postgres[1553]: 	    BEGIN1226vm-test-run-index_advisor> server # [  124.821205] postgres[1553]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1227vm-test-run-index_advisor> server # [  124.821920] postgres[1553]:1228vm-test-run-index_advisor> server # [  124.822330] postgres[1553]: 	        IF server_version >= 14 THEN1229vm-test-run-index_advisor> server # [  124.823398] postgres[1553]: 	            RETURN jsonb_build_object(1230vm-test-run-index_advisor> server # [  124.824800] postgres[1553]: 	                'data', null::jsonb,1231vm-test-run-index_advisor> server # [  124.825562] postgres[1553]: 	                'errors', array['pg_graphql extension is not enabled.']1232vm-test-run-index_advisor> server # [  124.826282] postgres[1553]: 	            );1233vm-test-run-index_advisor> server # [  124.826578] postgres[1553]: 	        ELSE1234vm-test-run-index_advisor> server # [  124.826819] postgres[1553]: 	            RETURN jsonb_build_object(1235vm-test-run-index_advisor> server # [  124.827344] postgres[1553]: 	                'data', null::jsonb,1236vm-test-run-index_advisor> server # [  124.829206] postgres[1553]: 	                'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1237vm-test-run-index_advisor> server # [  124.830688] postgres[1553]: 	            );1238vm-test-run-index_advisor> server # [  124.831997] postgres[1553]: 	        END IF;1239vm-test-run-index_advisor> server # [  124.833129] postgres[1553]: 	    END;1240vm-test-run-index_advisor> server # [  124.833728] postgres[1553]: 	$$;1241vm-test-run-index_advisor> server # [  124.834905] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: DROP FUNCTION1242vm-test-run-index_advisor> server # [  124.841909] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE FUNCTION1243vm-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;1244vm-test-run-index_advisor> server # [  124.882380] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: GRANT1245vm-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;1246vm-test-run-index_advisor> server # [  124.917607] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES1247vm-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;1248vm-test-run-index_advisor> server # [  124.945945] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES1249vm-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;1250vm-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 all1251vm-test-run-index_advisor> server # [  124.994377] postgres[1553]: 	    on sequences to postgres, anon, authenticated, service_role;1252vm-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 all1253vm-test-run-index_advisor> server # [  124.995962] postgres[1553]: 	    on tables to postgres, anon, authenticated, service_role;1254vm-test-run-index_advisor> server # [  125.006311] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES1255vm-test-run-index_advisor> server # [  125.013522] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES1256vm-test-run-index_advisor> server # [  125.015635] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES1257vm-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 all1258vm-test-run-index_advisor> server # [  125.026739] postgres[1553]: 	    on functions to postgres, anon, authenticated, service_role;1259vm-test-run-index_advisor> server # [  125.027819] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: ALTER DEFAULT PRIVILEGES1260vm-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()1261vm-test-run-index_advisor> server # [  125.035593] postgres[1553]: 	RETURNS event_trigger1262vm-test-run-index_advisor> server # [  125.036787] postgres[1553]: 	LANGUAGE plpgsql1263vm-test-run-index_advisor> server # [  125.038107] postgres[1553]: 	AS $func$1264vm-test-run-index_advisor> server # [  125.039191] postgres[1553]: 	    DECLARE1265vm-test-run-index_advisor> server # [  125.046897] postgres[1553]: 	    func_is_graphql_resolve bool;1266vm-test-run-index_advisor> server # [  125.049447] postgres[1553]: 	    BEGIN1267vm-test-run-index_advisor> server # [  125.050286] postgres[1553]: 	    func_is_graphql_resolve = (1268vm-test-run-index_advisor> server # [  125.050830] postgres[1553]: 	        SELECT n.proname = 'resolve'1269vm-test-run-index_advisor> server # [  125.051123] postgres[1553]: 	        FROM pg_event_trigger_ddl_commands() AS ev1270vm-test-run-index_advisor> server # [  125.051943] postgres[1553]: 	        LEFT JOIN pg_catalog.pg_proc AS n1271vm-test-run-index_advisor> server # [  125.052270] postgres[1553]: 	        ON ev.objid = n.oid1272vm-test-run-index_advisor> server # [  125.066481] postgres[1553]: 	    );1273vm-test-run-index_advisor> server # [  125.066919] postgres[1553]:1274vm-test-run-index_advisor> server # [  125.067364] postgres[1553]: 	    IF func_is_graphql_resolve1275vm-test-run-index_advisor> server # [  125.067652] postgres[1553]: 	    THEN1276vm-test-run-index_advisor> server # [  125.068108] postgres[1553]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1277vm-test-run-index_advisor> server # [  125.069741] postgres[1553]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1278vm-test-run-index_advisor> server # [  125.070634] postgres[1553]:1279vm-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;1280vm-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;1281vm-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;1282vm-test-run-index_advisor> server # [  125.085761] postgres[1553]:1283vm-test-run-index_advisor> server # [  125.086895] postgres[1553]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1284vm-test-run-index_advisor> server # [  125.087948] postgres[1553]: 	        create or replace function graphql_public.graphql(1285vm-test-run-index_advisor> server # [  125.089806] postgres[1553]: 	            "operationName" text default null,1286vm-test-run-index_advisor> server # [  125.090289] postgres[1553]: 	            query text default null,1287vm-test-run-index_advisor> server # [  125.090846] postgres[1553]: 	            variables jsonb default null,1288vm-test-run-index_advisor> server # [  125.091534] postgres[1553]: 	            extensions jsonb default null1289vm-test-run-index_advisor> server # [  125.097992] postgres[1553]: 	        )1290vm-test-run-index_advisor> server # [  125.098838] postgres[1553]: 	            returns jsonb1291vm-test-run-index_advisor> server # [  125.099983] postgres[1553]: 	            language sql1292vm-test-run-index_advisor> server # [  125.106638] postgres[1553]: 	        as $$1293vm-test-run-index_advisor> server # [  125.107825] postgres[1553]: 	            SELECT graphql.resolve(query, coalesce(variables, '{}'));1294vm-test-run-index_advisor> server # [  125.109577] postgres[1553]: 	        $$;1295vm-test-run-index_advisor> server # [  125.109965] postgres[1553]:1296vm-test-run-index_advisor> server # [  125.110210] postgres[1553]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1297vm-test-run-index_advisor> server # [  125.110632] postgres[1553]: 	    END IF;1298vm-test-run-index_advisor> server # [  125.111104] postgres[1553]:1299vm-test-run-index_advisor> server # [  125.111702] postgres[1553]: 	    END;1300vm-test-run-index_advisor> server # [  125.117877] postgres[1553]: 	$func$;1301vm-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;1302vm-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')1303vm-test-run-index_advisor> server # [  125.125314] postgres[1553]: 	EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1304vm-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';1305vm-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()1306vm-test-run-index_advisor> server # [  125.137685] postgres[1553]: 	RETURNS event_trigger1307vm-test-run-index_advisor> server # [  125.138118] postgres[1553]: 	LANGUAGE plpgsql1308vm-test-run-index_advisor> server # [  125.138359] postgres[1553]: 	AS $func$1309vm-test-run-index_advisor> server # [  125.138628] postgres[1553]: 	    DECLARE1310vm-test-run-index_advisor> server # [  125.139028] postgres[1553]: 	    graphql_is_dropped bool;1311vm-test-run-index_advisor> server # [  125.141582] postgres[1553]: 	    BEGIN1312vm-test-run-index_advisor> server # [  125.142480] postgres[1553]: 	    graphql_is_dropped = (1313vm-test-run-index_advisor> server # [  125.142882] postgres[1553]: 	        SELECT ev.schema_name = 'graphql_public'1314vm-test-run-index_advisor> server # [  125.143330] postgres[1553]: 	        FROM pg_event_trigger_dropped_objects() AS ev1315vm-test-run-index_advisor> server # [  125.144005] postgres[1553]: 	        WHERE ev.schema_name = 'graphql_public'1316vm-test-run-index_advisor> server # [  125.154005] postgres[1553]: 	    );1317vm-test-run-index_advisor> server # [  125.154601] postgres[1553]:1318vm-test-run-index_advisor> server # [  125.155779] postgres[1553]: 	    IF graphql_is_dropped1319vm-test-run-index_advisor> server # [  125.156462] postgres[1553]: 	    THEN1320vm-test-run-index_advisor> server # [  125.157861] postgres[1553]: 	        create or replace function graphql_public.graphql(1321vm-test-run-index_advisor> server # [  125.158810] postgres[1553]: 	            "operationName" text default null,1322vm-test-run-index_advisor> server # [  125.159367] postgres[1553]: 	            query text default null,1323vm-test-run-index_advisor> server # [  125.159762] postgres[1553]: 	            variables jsonb default null,1324vm-test-run-index_advisor> server # [  125.160062] postgres[1553]: 	            extensions jsonb default null1325vm-test-run-index_advisor> server # [  125.161394] postgres[1553]: 	        )1326vm-test-run-index_advisor> server # [  125.161844] postgres[1553]: 	            returns jsonb1327vm-test-run-index_advisor> server # [  125.162657] postgres[1553]: 	            language plpgsql1328vm-test-run-index_advisor> server # [  125.163531] postgres[1553]: 	        as $$1329vm-test-run-index_advisor> server # [  125.167313] postgres[1553]: 	            DECLARE1330vm-test-run-index_advisor> server # [  125.167969] postgres[1553]: 	                server_version float;1331vm-test-run-index_advisor> server # [  125.174462] postgres[1553]: 	            BEGIN1332vm-test-run-index_advisor> server # [  125.175110] postgres[1553]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1333vm-test-run-index_advisor> server # [  125.181403] postgres[1553]:1334vm-test-run-index_advisor> server # [  125.182884] postgres[1553]: 	                IF server_version >= 14 THEN1335vm-test-run-index_advisor> server # [  125.183259] postgres[1553]: 	                    RETURN jsonb_build_object(1336vm-test-run-index_advisor> server # [  125.183785] postgres[1553]: 	                        'data', null::jsonb,1337vm-test-run-index_advisor> server # [  125.184361] postgres[1553]: 	                        'errors', array['pg_graphql extension is not enabled.']1338vm-test-run-index_advisor> server # [  125.189832] postgres[1553]: 	                    );1339vm-test-run-index_advisor> server # [  125.190758] postgres[1553]: 	                ELSE1340vm-test-run-index_advisor> server # [  125.191424] postgres[1553]: 	                    RETURN jsonb_build_object(1341vm-test-run-index_advisor> server # [  125.197834] postgres[1553]: 	                        'data', null::jsonb,1342vm-test-run-index_advisor> server # [  125.198583] postgres[1553]: 	                        'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1343vm-test-run-index_advisor> server # [  125.199725] postgres[1553]: 	                    );1344vm-test-run-index_advisor> server # [  125.200044] postgres[1553]: 	                END IF;1345vm-test-run-index_advisor> server # [  125.200285] postgres[1553]: 	            END;1346vm-test-run-index_advisor> server # [  125.206503] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE FUNCTION1347vm-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, skipping1348vm-test-run-index_advisor> server # [  125.210316] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: DROP EVENT TRIGGER1349vm-test-run-index_advisor> server # [  125.210672] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE EVENT TRIGGER1350vm-test-run-index_advisor> server # [  125.211500] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: COMMENT1351vm-test-run-index_advisor> server # [  125.227592] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE FUNCTION1352vm-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, skipping1353vm-test-run-index_advisor> server # [  125.241878] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: DROP EVENT TRIGGER1354vm-test-run-index_advisor> server # [  125.242545] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: CREATE EVENT TRIGGER1355vm-test-run-index_advisor> server # [  125.243735] postgres[1553]: 	        $$;1356vm-test-run-index_advisor> server # [  125.258176] postgres[1553]: 	    END IF;1357vm-test-run-index_advisor> server # [  125.274764] postgres[1553]:1358vm-test-run-index_advisor> server # [  125.279171] postgres[1553]: 	    END;1359vm-test-run-index_advisor> server # [  125.279873] postgres[1553]: 	$func$;1360vm-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;1361vm-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')1362vm-test-run-index_advisor> server # [  125.295147] postgres[1553]: 	EXECUTE PROCEDURE extensions.set_graphql_placeholder();1363vm-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';1364vm-test-run-index_advisor> server # [  125.303820] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1552]: COMMENT1365vm-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.sql1366vm-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;1367vm-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();1368vm-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, skipping1369vm-test-run-index_advisor> server # [  125.794438] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP EVENT TRIGGER1370vm-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, skipping1371vm-test-run-index_advisor> server # [  125.809552] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP FUNCTION1372vm-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 $$1373vm-test-run-index_advisor> server # [  125.826945] postgres[1571]: 	DECLARE1374vm-test-run-index_advisor> server # [  125.828174] postgres[1571]: 	  cmd record;1375vm-test-run-index_advisor> server # [  125.833548] postgres[1571]: 	BEGIN1376vm-test-run-index_advisor> server # [  125.834151] postgres[1571]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1377vm-test-run-index_advisor> server # [  125.834695] postgres[1571]: 	  LOOP1378vm-test-run-index_advisor> server # [  125.835006] postgres[1571]: 	    IF cmd.command_tag IN (1379vm-test-run-index_advisor> server # [  125.835474] postgres[1571]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1380vm-test-run-index_advisor> server # [  125.835795] postgres[1571]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1381vm-test-run-index_advisor> server # [  125.845268] postgres[1571]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1382vm-test-run-index_advisor> server # [  125.853159] postgres[1571]: 	    , 'CREATE VIEW', 'ALTER VIEW'1383vm-test-run-index_advisor> server # [  125.853949] postgres[1571]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1384vm-test-run-index_advisor> server # [  125.855268] postgres[1571]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1385vm-test-run-index_advisor> server # [  125.862368] postgres[1571]: 	    , 'CREATE TRIGGER'1386vm-test-run-index_advisor> server # [  125.879365] postgres[1571]: 	    , 'CREATE TYPE', 'ALTER TYPE'1387vm-test-run-index_advisor> server # [  125.879865] postgres[1571]: 	    , 'CREATE RULE'1388vm-test-run-index_advisor> server # [  125.880151] postgres[1571]: 	    , 'COMMENT'1389vm-test-run-index_advisor> server # [  125.883910] postgres[1571]: 	    )1390vm-test-run-index_advisor> server # [  125.885644] postgres[1571]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1391vm-test-run-index_advisor> server # [  125.893406] postgres[1571]: 	    AND cmd.schema_name is distinct from 'pg_temp'1392vm-test-run-index_advisor> server # [  125.894685] postgres[1571]: 	    THEN1393vm-test-run-index_advisor> server # [  125.895054] postgres[1571]: 	      NOTIFY pgrst, 'reload schema';1394vm-test-run-index_advisor> server # [  125.895458] postgres[1571]: 	    END IF;1395vm-test-run-index_advisor> server # [  125.895816] postgres[1571]: 	  END LOOP;1396vm-test-run-index_advisor> server # [  125.906328] postgres[1571]: 	END; $$ LANGUAGE plpgsql;1397vm-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 $$1398vm-test-run-index_advisor> server # [  125.915798] postgres[1571]: 	DECLARE1399vm-test-run-index_advisor> server # [  125.920172] postgres[1571]: 	  obj record;1400vm-test-run-index_advisor> server # [  125.927799] postgres[1571]: 	BEGIN1401vm-test-run-index_advisor> server # [  125.933951] postgres[1571]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1402vm-test-run-index_advisor> server # [  125.940746] postgres[1571]: 	  LOOP1403vm-test-run-index_advisor> server # [  125.942249] postgres[1571]: 	    IF obj.object_type IN (1404vm-test-run-index_advisor> server # [  125.943727] postgres[1571]: 	      'schema'1405vm-test-run-index_advisor> server # [  125.955280] postgres[1571]: 	    , 'table'1406vm-test-run-index_advisor> server # [  125.956876] postgres[1571]: 	    , 'foreign table'1407vm-test-run-index_advisor> server # [  125.958177] postgres[1571]: 	    , 'view'1408vm-test-run-index_advisor> server # [  125.958763] postgres[1571]: 	    , 'materialized view'1409vm-test-run-index_advisor> server # [  125.959643] postgres[1571]: 	    , 'function'1410vm-test-run-index_advisor> server # [  125.978284] postgres[1571]: 	    , 'trigger'1411vm-test-run-index_advisor> server # [  125.979723] postgres[1571]: 	    , 'type'1412vm-test-run-index_advisor> server # [  125.990308] postgres[1571]: 	    , 'rule'1413vm-test-run-index_advisor> server # [  125.991537] postgres[1571]: 	    )1414vm-test-run-index_advisor> server # [  125.991928] postgres[1571]: 	    AND obj.is_temporary IS false -- no pg_temp objects1415vm-test-run-index_advisor> server # [  126.006452] postgres[1571]: 	    THEN1416vm-test-run-index_advisor> server # [  126.006933] postgres[1571]: 	      NOTIFY pgrst, 'reload schema';1417vm-test-run-index_advisor> server # [  126.007244] postgres[1571]: 	    END IF;1418vm-test-run-index_advisor> server # [  126.007493] postgres[1571]: 	  END LOOP;1419vm-test-run-index_advisor> server # [  126.007727] postgres[1571]: 	END; $$ LANGUAGE plpgsql;1420vm-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;1421vm-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_watch1422vm-test-run-index_advisor> server # [  126.059451] postgres[1571]: 	  ON ddl_command_end1423vm-test-run-index_advisor> server # [  126.063839] postgres[1571]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1424vm-test-run-index_advisor> server # [  126.070074] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE FUNCTION1425vm-test-run-index_advisor> server # [  126.070767] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE FUNCTION1426vm-test-run-index_advisor> server # [  126.071991] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP EVENT TRIGGER1427vm-test-run-index_advisor> server # [  126.091202] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE EVENT TRIGGER1428vm-test-run-index_advisor> server # [  126.098156] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: DROP EVENT TRIGGER1429vm-test-run-index_advisor> server # [  126.099766] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1562]: CREATE EVENT TRIGGER1430vm-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;1431vm-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_watch1432vm-test-run-index_advisor> server # [  126.121188] postgres[1571]: 	  ON sql_drop1433vm-test-run-index_advisor> server # [  126.122456] postgres[1571]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1434vm-test-run-index_advisor> server # [  126.133944] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1435vm-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;1436vm-test-run-index_advisor> server # [  126.479668] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1574]: ALTER ROLE1437vm-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.sql1438vm-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()1439vm-test-run-index_advisor> server # [  126.776829] postgres[1583]: 	    returns event_trigger1440vm-test-run-index_advisor> server # [  126.778108] postgres[1583]: 	    language plpgsql1441vm-test-run-index_advisor> server # [  126.778654] postgres[1583]: 	AS $func$1442vm-test-run-index_advisor> server # [  126.779616] postgres[1583]: 	DECLARE1443vm-test-run-index_advisor> server # [  126.779954] postgres[1583]: 	    func_is_graphql_resolve bool;1444vm-test-run-index_advisor> server # [  126.786958] postgres[1583]: 	BEGIN1445vm-test-run-index_advisor> server # [  126.788358] postgres[1583]: 	    func_is_graphql_resolve = (1446vm-test-run-index_advisor> server # [  126.794262] postgres[1583]: 	        SELECT n.proname = 'resolve'1447vm-test-run-index_advisor> server # [  126.794905] postgres[1583]: 	        FROM pg_event_trigger_ddl_commands() AS ev1448vm-test-run-index_advisor> server # [  126.795572] postgres[1583]: 	        LEFT JOIN pg_catalog.pg_proc AS n1449vm-test-run-index_advisor> server # [  126.802037] postgres[1583]: 	        ON ev.objid = n.oid1450vm-test-run-index_advisor> server # [  126.802637] postgres[1583]: 	    );1451vm-test-run-index_advisor> server # [  126.803042] postgres[1583]:1452vm-test-run-index_advisor> server # [  126.803317] postgres[1583]: 	    IF func_is_graphql_resolve1453vm-test-run-index_advisor> server # [  126.803576] postgres[1583]: 	    THEN1454vm-test-run-index_advisor> server # [  126.803808] postgres[1583]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1455vm-test-run-index_advisor> server # [  126.808008] postgres[1583]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1456vm-test-run-index_advisor> server # [  126.809449] postgres[1583]:1457vm-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;1458vm-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;1459vm-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;1460vm-test-run-index_advisor> server # [  126.819060] postgres[1583]:1461vm-test-run-index_advisor> server # [  126.820027] postgres[1583]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1462vm-test-run-index_advisor> server # [  126.822649] postgres[1583]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1463vm-test-run-index_advisor> server # [  126.823075] postgres[1583]: 	        create or replace function graphql_public.graphql(1464vm-test-run-index_advisor> server # [  126.823709] postgres[1583]: 	            "operationName" text default null,1465vm-test-run-index_advisor> server # [  126.828803] postgres[1583]: 	            query text default null,1466vm-test-run-index_advisor> server # [  126.830317] postgres[1583]: 	            variables jsonb default null,1467vm-test-run-index_advisor> server # [  126.830782] postgres[1583]: 	            extensions jsonb default null1468vm-test-run-index_advisor> server # [  126.831099] postgres[1583]: 	        )1469vm-test-run-index_advisor> server # [  126.831618] postgres[1583]: 	            returns jsonb1470vm-test-run-index_advisor> server # [  126.832097] postgres[1583]: 	            language sql1471vm-test-run-index_advisor> server # [  126.837751] postgres[1583]: 	        as $$1472vm-test-run-index_advisor> server # [  126.838504] postgres[1583]: 	            -- This changed1473vm-test-run-index_advisor> server # [  126.839156] postgres[1583]: 	            select graphql.resolve(1474vm-test-run-index_advisor> server # [  126.839845] postgres[1583]: 	                query := query,1475vm-test-run-index_advisor> server # [  126.843889] postgres[1583]: 	                variables := coalesce(variables, '{}'),1476vm-test-run-index_advisor> server # [  126.844534] postgres[1583]: 	                "operationName" := "operationName",1477vm-test-run-index_advisor> server # [  126.850885] postgres[1583]: 	                extensions := extensions1478vm-test-run-index_advisor> server # [  126.852795] postgres[1583]: 	            );1479vm-test-run-index_advisor> server # [  126.853284] postgres[1583]: 	        $$;1480vm-test-run-index_advisor> server # [  126.855022] postgres[1583]:1481vm-test-run-index_advisor> server # [  126.855674] postgres[1583]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1482vm-test-run-index_advisor> server # [  126.857581] postgres[1583]: 	    END IF;1483vm-test-run-index_advisor> server # [  126.858431] postgres[1583]:1484vm-test-run-index_advisor> server # [  126.859217] postgres[1583]: 	END;1485vm-test-run-index_advisor> server # [  126.860311] postgres[1583]: 	$func$;1486vm-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()1487vm-test-run-index_advisor> server # [  126.864096] postgres[1583]: 	RETURNS event_trigger1488vm-test-run-index_advisor> server # [  126.865278] postgres[1583]: 	LANGUAGE plpgsql1489vm-test-run-index_advisor> server # [  126.866161] postgres[1583]: 	AS $func$1490vm-test-run-index_advisor> server # [  126.866521] postgres[1583]: 	    DECLARE1491vm-test-run-index_advisor> server # [  126.866812] postgres[1583]: 	    graphql_is_dropped bool;1492vm-test-run-index_advisor> server # [  126.867774] postgres[1583]: 	    BEGIN1493vm-test-run-index_advisor> server # [  126.869852] postgres[1583]: 	    graphql_is_dropped = (1494vm-test-run-index_advisor> server # [  126.871238] postgres[1583]: 	        SELECT ev.schema_name = 'graphql_public'1495vm-test-run-index_advisor> server # [  126.871622] postgres[1583]: 	        FROM pg_event_trigger_dropped_objects() AS ev1496vm-test-run-index_advisor> server # [  126.874232] postgres[1583]: 	        WHERE ev.schema_name = 'graphql_public'1497vm-test-run-index_advisor> server # [  126.875608] postgres[1583]: 	    );1498vm-test-run-index_advisor> server # [  126.875933] postgres[1583]:1499vm-test-run-index_advisor> server # [  126.877609] postgres[1583]: 	    IF graphql_is_dropped1500vm-test-run-index_advisor> server # [  126.878591] postgres[1583]: 	    THEN1501vm-test-run-index_advisor> server # [  126.878878] postgres[1583]: 	        create or replace function graphql_public.graphql(1502vm-test-run-index_advisor> server # [  126.880163] postgres[1583]: 	            "operationName" text default null,1503vm-test-run-index_advisor> server # [  126.882038] postgres[1583]: 	            query text default null,1504vm-test-run-index_advisor> server # [  126.882826] postgres[1583]: 	            variables jsonb default null,1505vm-test-run-index_advisor> server # [  126.884091] postgres[1583]: 	            extensions jsonb default null1506vm-test-run-index_advisor> server # [  126.885762] postgres[1583]: 	        )1507vm-test-run-index_advisor> server # [  126.886522] postgres[1583]: 	            returns jsonb1508vm-test-run-index_advisor> server # [  126.886881] postgres[1583]: 	            language plpgsql1509vm-test-run-index_advisor> server # [  126.889051] postgres[1583]: 	        as $$1510vm-test-run-index_advisor> server # [  126.889737] postgres[1583]: 	            DECLARE1511vm-test-run-index_advisor> server # [  126.890057] postgres[1583]: 	                server_version float;1512vm-test-run-index_advisor> server # [  126.890334] postgres[1583]: 	            BEGIN1513vm-test-run-index_advisor> server # [  126.890597] postgres[1583]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1514vm-test-run-index_advisor> server # [  126.890887] postgres[1583]:1515vm-test-run-index_advisor> server # [  126.891101] postgres[1583]: 	                IF server_version >= 14 THEN1516vm-test-run-index_advisor> server # [  126.891340] postgres[1583]: 	                    RETURN jsonb_build_object(1517vm-test-run-index_advisor> server # [  126.891583] postgres[1583]: 	                        'errors', jsonb_build_array(1518vm-test-run-index_advisor> server # [  126.891842] postgres[1583]: 	                            jsonb_build_object(1519vm-test-run-index_advisor> server # [  126.892088] postgres[1583]: 	                                'message', 'pg_graphql extension is not enabled.'1520vm-test-run-index_advisor> server # [  126.892679] postgres[1583]: 	                            )1521vm-test-run-index_advisor> server # [  126.893550] postgres[1583]: 	                        )1522vm-test-run-index_advisor> server # [  126.893872] postgres[1583]: 	                    );1523vm-test-run-index_advisor> server # [  126.894945] postgres[1583]: 	                ELSE1524vm-test-run-index_advisor> server # [  126.895485] postgres[1583]: 	                    RETURN jsonb_build_object(1525vm-test-run-index_advisor> server # [  126.896147] postgres[1583]: 	                        'errors', jsonb_build_array(1526vm-test-run-index_advisor> server # [  126.898085] postgres[1583]: 	                            jsonb_build_object(1527vm-test-run-index_advisor> server # [  126.898918] postgres[1583]: 	                                'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1528vm-test-run-index_advisor> server # [  126.900224] postgres[1583]: 	                            )1529vm-test-run-index_advisor> server # [  126.901721] postgres[1583]: 	                        )1530vm-test-run-index_advisor> server # [  126.902323] postgres[1583]: 	                    );1531vm-test-run-index_advisor> server # [  126.902588] postgres[1583]: 	                END IF;1532vm-test-run-index_advisor> server # [  126.902821] postgres[1583]: 	            END;1533vm-test-run-index_advisor> server # [  126.903038] postgres[1583]: 	        $$;1534vm-test-run-index_advisor> server # [  126.903248] postgres[1583]: 	    END IF;1535vm-test-run-index_advisor> server # [  126.903456] postgres[1583]:1536vm-test-run-index_advisor> server # [  126.903660] postgres[1583]: 	    END;1537vm-test-run-index_advisor> server # [  126.904149] postgres[1583]: 	$func$;1538vm-test-run-index_advisor> server # [  126.909316] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: CREATE FUNCTION1539vm-test-run-index_advisor> server # [  126.915768] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: CREATE FUNCTION1540vm-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(1541vm-test-run-index_advisor> server # [  126.919961] postgres[1583]: 	    "operationName" text default null,1542vm-test-run-index_advisor> server # [  126.925242] postgres[1583]: 	    query text default null,1543vm-test-run-index_advisor> server # [  126.926250] postgres[1583]: 	    variables jsonb default null,1544vm-test-run-index_advisor> server # [  126.926770] postgres[1583]: 	    extensions jsonb default null1545vm-test-run-index_advisor> server # [  126.927333] postgres[1583]: 	)1546vm-test-run-index_advisor> server # [  126.928228] postgres[1583]: 	    returns jsonb1547vm-test-run-index_advisor> server # [  126.929767] postgres[1583]: 	    language plpgsql1548vm-test-run-index_advisor> server # [  126.930076] postgres[1583]: 	as $$1549vm-test-run-index_advisor> server # [  126.930298] postgres[1583]: 	    DECLARE1550vm-test-run-index_advisor> server # [  126.930513] postgres[1583]: 	        server_version float;1551vm-test-run-index_advisor> server # [  126.930734] postgres[1583]: 	    BEGIN1552vm-test-run-index_advisor> server # [  126.930940] postgres[1583]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1553vm-test-run-index_advisor> server # [  126.931833] postgres[1583]:1554vm-test-run-index_advisor> server # [  126.932588] postgres[1583]: 	        IF server_version >= 14 THEN1555vm-test-run-index_advisor> server # [  126.932883] postgres[1583]: 	            RETURN jsonb_build_object(1556vm-test-run-index_advisor> server # [  126.933133] postgres[1583]: 	                'errors', jsonb_build_array(1557vm-test-run-index_advisor> server # [  126.933618] postgres[1583]: 	                    jsonb_build_object(1558vm-test-run-index_advisor> server # [  126.934249] postgres[1583]: 	                        'message', 'pg_graphql extension is not enabled.'1559vm-test-run-index_advisor> server # [  126.935064] postgres[1583]: 	                    )1560vm-test-run-index_advisor> server # [  126.935334] postgres[1583]: 	                )1561vm-test-run-index_advisor> server # [  126.935553] postgres[1583]: 	            );1562vm-test-run-index_advisor> server # [  126.935761] postgres[1583]: 	        ELSE1563vm-test-run-index_advisor> server # [  126.935961] postgres[1583]: 	            RETURN jsonb_build_object(1564vm-test-run-index_advisor> server # [  126.936185] postgres[1583]: 	                'errors', jsonb_build_array(1565vm-test-run-index_advisor> server # [  126.936427] postgres[1583]: 	                    jsonb_build_object(1566vm-test-run-index_advisor> server # [  126.936667] postgres[1583]: 	                        'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1567vm-test-run-index_advisor> server # [  126.936931] postgres[1583]: 	                    )1568vm-test-run-index_advisor> server # [  126.937133] postgres[1583]: 	                )1569vm-test-run-index_advisor> server # [  126.937341] postgres[1583]: 	            );1570vm-test-run-index_advisor> server # [  126.939179] postgres[1583]: 	        END IF;1571vm-test-run-index_advisor> server # [  126.939724] postgres[1583]: 	    END;1572vm-test-run-index_advisor> server # [  126.941747] postgres[1583]: 	$$;1573vm-test-run-index_advisor> server # [  126.951666] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: CREATE FUNCTION1574vm-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;1575vm-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, skipping1576vm-test-run-index_advisor> server # [  126.966051] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: DROP EXTENSION1577vm-test-run-index_advisor> server # [  128.970272] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: DO1578vm-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.sql1579vm-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;1580vm-test-run-index_advisor> server # [  129.390719] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1602]: GRANT ROLE1581vm-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.sql1582vm-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()1583vm-test-run-index_advisor> server # [  129.899196] postgres[1615]: 	    returns event_trigger1584vm-test-run-index_advisor> server # [  129.899717] postgres[1615]: 	    language plpgsql1585vm-test-run-index_advisor> server # [  129.909159] postgres[1615]: 	AS $func$1586vm-test-run-index_advisor> server # [  129.914780] postgres[1615]: 	DECLARE1587vm-test-run-index_advisor> server # [  129.915334] postgres[1615]: 	    func_is_graphql_resolve bool;1588vm-test-run-index_advisor> server # [  129.915855] postgres[1615]: 	BEGIN1589vm-test-run-index_advisor> server # [  129.916134] postgres[1615]: 	    func_is_graphql_resolve = (1590vm-test-run-index_advisor> server # [  129.922044] postgres[1615]: 	        SELECT n.proname = 'resolve'1591vm-test-run-index_advisor> server # [  129.922642] postgres[1615]: 	        FROM pg_event_trigger_ddl_commands() AS ev1592vm-test-run-index_advisor> server # [  129.922981] postgres[1615]: 	        LEFT JOIN pg_catalog.pg_proc AS n1593vm-test-run-index_advisor> server # [  129.923792] postgres[1615]: 	        ON ev.objid = n.oid1594vm-test-run-index_advisor> server # [  129.924296] postgres[1615]: 	    );1595vm-test-run-index_advisor> server # [  129.933425] postgres[1615]:1596vm-test-run-index_advisor> server # [  129.934263] postgres[1615]: 	    IF func_is_graphql_resolve1597vm-test-run-index_advisor> server # [  129.934547] postgres[1615]: 	    THEN1598vm-test-run-index_advisor> server # [  129.935124] postgres[1615]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1599vm-test-run-index_advisor> server # [  129.941006] postgres[1615]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1600vm-test-run-index_advisor> server # [  129.945784] postgres[1615]: 	        create or replace function graphql_public.graphql(1601vm-test-run-index_advisor> server # [  129.947287] postgres[1615]: 	            "operationName" text default null,1602vm-test-run-index_advisor> server # [  129.953401] postgres[1615]: 	            query text default null,1603vm-test-run-index_advisor> server # [  129.954304] postgres[1615]: 	            variables jsonb default null,1604vm-test-run-index_advisor> server # [  129.955904] postgres[1615]: 	            extensions jsonb default null1605vm-test-run-index_advisor> server # [  129.961686] postgres[1615]: 	        )1606vm-test-run-index_advisor> server # [  129.962261] postgres[1615]: 	            returns jsonb1607vm-test-run-index_advisor> server # [  129.963117] postgres[1615]: 	            language sql1608vm-test-run-index_advisor> server # [  129.963561] postgres[1615]: 	        as $$1609vm-test-run-index_advisor> server # [  129.963846] postgres[1615]: 	            select graphql.resolve(1610vm-test-run-index_advisor> server # [  129.964315] postgres[1615]: 	                query := query,1611vm-test-run-index_advisor> server # [  129.969415] postgres[1615]: 	                variables := coalesce(variables, '{}'),1612vm-test-run-index_advisor> server # [  129.970372] postgres[1615]: 	                "operationName" := "operationName",1613vm-test-run-index_advisor> server # [  129.970917] postgres[1615]: 	                extensions := extensions1614vm-test-run-index_advisor> server # [  129.976956] postgres[1615]: 	            );1615vm-test-run-index_advisor> server # [  129.978404] postgres[1615]: 	        $$;1616vm-test-run-index_advisor> server # [  129.978988] postgres[1615]:1617vm-test-run-index_advisor> server # [  129.979270] postgres[1615]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1618vm-test-run-index_advisor> server # [  129.985898] postgres[1615]: 	        -- function in the extension so we need to grant permissions on existing entities AND1619vm-test-run-index_advisor> server # [  129.986872] postgres[1615]: 	        -- update default permissions to any others that are created after `graphql.resolve`1620vm-test-run-index_advisor> server # [  129.987462] postgres[1615]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1621vm-test-run-index_advisor> server # [  129.988214] postgres[1615]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1622vm-test-run-index_advisor> server # [  130.001048] postgres[1615]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1623vm-test-run-index_advisor> server # [  130.001886] postgres[1615]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1624vm-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;1625vm-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;1626vm-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;1627vm-test-run-index_advisor> server # [  130.020053] postgres[1615]: 	    END IF;1628vm-test-run-index_advisor> server # [  130.022439] postgres[1615]:1629vm-test-run-index_advisor> server # [  130.024015] postgres[1615]: 	END;1630vm-test-run-index_advisor> server # [  130.026981] postgres[1615]: 	$func$;1631vm-test-run-index_advisor> server # [  130.059807] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1613]: CREATE FUNCTION1632vm-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;1633vm-test-run-index_advisor> server # [  130.141528] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1613]: DROP EXTENSION1634vm-test-run-index_advisor> server # [  131.743709] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1613]: DO1635vm-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.sql1636vm-test-run-index_advisor> server # [  132.966157] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1627]: DO1637vm-test-run-index_advisor> server # [  133.677237] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1627]: DO1638vm-test-run-index_advisor> server # [  133.718326] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1639vm-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';1640vm-test-run-index_advisor> server # [  134.024573] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1646]: ALTER ROLE1641vm-test-run-index_advisor> server # [  134.066456] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql1642vm-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;1643vm-test-run-index_advisor> server # [  134.335270] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1648]: REVOKE ROLE1644vm-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.sql1645vm-test-run-index_advisor> server # [  136.560784] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1650]: DO1646vm-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.sql1647vm-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;1648vm-test-run-index_advisor> server # [  136.907274] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1676]: GRANT ROLE1649vm-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.sql1650vm-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;1651vm-test-run-index_advisor> server # [  137.514317] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT1652vm-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;1653vm-test-run-index_advisor> server # [  137.549357] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT1654vm-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;1655vm-test-run-index_advisor> server # [  137.559584] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT1656vm-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;1657vm-test-run-index_advisor> server # [  137.590845] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: ALTER DEFAULT PRIVILEGES1658vm-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;1659vm-test-run-index_advisor> server # [  137.605847] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: ALTER DEFAULT PRIVILEGES1660vm-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;1661vm-test-run-index_advisor> server # [  137.637027] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: ALTER DEFAULT PRIVILEGES1662vm-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.sql1663vm-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;1664vm-test-run-index_advisor> server # [  138.164004] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1690]: GRANT ROLE1665vm-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.sql1666vm-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;1667vm-test-run-index_advisor> server # [  138.582660] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1701]: GRANT ROLE1668vm-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.sql1669vm-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;1670vm-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;1671vm-test-run-index_advisor> server # [  138.946693] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: ALTER ROLE1672vm-test-run-index_advisor> server # [  138.948199] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: ALTER ROLE1673vm-test-run-index_advisor> server # [  138.961093] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: ALTER ROLE1674vm-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;1675vm-test-run-index_advisor> server # [  138.978867] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1708]: DO1676vm-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.sql1677vm-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;1678vm-test-run-index_advisor> server # [  139.351346] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1714]: GRANT ROLE1679vm-test-run-index_advisor> server # [  139.369318] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1714]: REVOKE ROLE1680vm-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;1681vm-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.sql1682vm-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()1683vm-test-run-index_advisor> server # [  139.706635] postgres[1717]: 	    returns event_trigger1684vm-test-run-index_advisor> server # [  139.707721] postgres[1717]: 	    language plpgsql1685vm-test-run-index_advisor> server # [  139.708131] postgres[1717]: 	AS $func$1686vm-test-run-index_advisor> server # [  139.711773] postgres[1717]: 	DECLARE1687vm-test-run-index_advisor> server # [  139.718297] postgres[1717]: 	    func_is_graphql_resolve bool;1688vm-test-run-index_advisor> server # [  139.719178] postgres[1717]: 	BEGIN1689vm-test-run-index_advisor> server # [  139.726781] postgres[1717]: 	    func_is_graphql_resolve = (1690vm-test-run-index_advisor> server # [  139.727667] postgres[1717]: 	        SELECT n.proname = 'resolve'1691vm-test-run-index_advisor> server # [  139.729581] postgres[1717]: 	        FROM pg_event_trigger_ddl_commands() AS ev1692vm-test-run-index_advisor> server # [  139.730047] postgres[1717]: 	        LEFT JOIN pg_catalog.pg_proc AS n1693vm-test-run-index_advisor> server # [  139.730475] postgres[1717]: 	        ON ev.objid = n.oid1694vm-test-run-index_advisor> server # [  139.730908] postgres[1717]: 	    );1695vm-test-run-index_advisor> server # [  139.731836] postgres[1717]:1696vm-test-run-index_advisor> server # [  139.739409] postgres[1717]: 	    IF func_is_graphql_resolve1697vm-test-run-index_advisor> server # [  139.740141] postgres[1717]: 	    THEN1698vm-test-run-index_advisor> server # [  139.745789] postgres[1717]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1699vm-test-run-index_advisor> server # [  139.746850] postgres[1717]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1700vm-test-run-index_advisor> server # [  139.747198] postgres[1717]: 	        create or replace function graphql_public.graphql(1701vm-test-run-index_advisor> server # [  139.747536] postgres[1717]: 	            "operationName" text default null,1702vm-test-run-index_advisor> server # [  139.748140] postgres[1717]: 	            query text default null,1703vm-test-run-index_advisor> server # [  139.762355] postgres[1717]: 	            variables jsonb default null,1704vm-test-run-index_advisor> server # [  139.763079] postgres[1717]: 	            extensions jsonb default null1705vm-test-run-index_advisor> server # [  139.763671] postgres[1717]: 	        )1706vm-test-run-index_advisor> server # [  139.763985] postgres[1717]: 	            returns jsonb1707vm-test-run-index_advisor> server # [  139.764380] postgres[1717]: 	            language sql1708vm-test-run-index_advisor> server # [  139.766017] postgres[1717]: 	        as $$1709vm-test-run-index_advisor> server # [  139.766407] postgres[1717]: 	            select graphql.resolve(1710vm-test-run-index_advisor> server # [  139.767016] postgres[1717]: 	                query := query,1711vm-test-run-index_advisor> server # [  139.767302] postgres[1717]: 	                variables := coalesce(variables, '{}'),1712vm-test-run-index_advisor> server # [  139.767568] postgres[1717]: 	                "operationName" := "operationName",1713vm-test-run-index_advisor> server # [  139.767897] postgres[1717]: 	                extensions := extensions1714vm-test-run-index_advisor> server # [  139.768299] postgres[1717]: 	            );1715vm-test-run-index_advisor> server # [  139.774906] postgres[1717]: 	        $$;1716vm-test-run-index_advisor> server # [  139.776372] postgres[1717]:1717vm-test-run-index_advisor> server # [  139.777796] postgres[1717]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1718vm-test-run-index_advisor> server # [  139.778218] postgres[1717]: 	        -- function in the extension so we need to grant permissions on existing entities AND1719vm-test-run-index_advisor> server # [  139.778524] postgres[1717]: 	        -- update default permissions to any others that are created after `graphql.resolve`1720vm-test-run-index_advisor> server # [  139.778814] postgres[1717]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1721vm-test-run-index_advisor> server # [  139.779089] postgres[1717]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1722vm-test-run-index_advisor> server # [  139.779373] postgres[1717]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1723vm-test-run-index_advisor> server # [  139.779647] postgres[1717]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1724vm-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;1725vm-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;1726vm-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;1727vm-test-run-index_advisor> server # [  139.785575] postgres[1717]:1728vm-test-run-index_advisor> server # [  139.786201] postgres[1717]: 	        -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1729vm-test-run-index_advisor> server # [  139.786663] postgres[1717]: 	        grant usage on schema graphql_public to postgres with grant option;1730vm-test-run-index_advisor> server # [  139.787350] postgres[1717]: 	        grant usage on schema graphql to postgres with grant option;1731vm-test-run-index_advisor> server # [  139.787682] postgres[1717]: 	    END IF;1732vm-test-run-index_advisor> server # [  139.787903] postgres[1717]:1733vm-test-run-index_advisor> server # [  139.788099] postgres[1717]: 	END;1734vm-test-run-index_advisor> server # [  139.789684] postgres[1717]: 	$func$;1735vm-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;1736vm-test-run-index_advisor> server # [  139.794162] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: CREATE FUNCTION1737vm-test-run-index_advisor> server # [  139.830286] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: DROP EXTENSION1738vm-test-run-index_advisor> server # [  141.571561] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: DO1739vm-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.sql1740vm-test-run-index_advisor> server # [  142.006468] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: DO1741vm-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_trigger1742vm-test-run-index_advisor> server # [  142.031170] postgres[1742]: 	    LANGUAGE plpgsql1743vm-test-run-index_advisor> server # [  142.032262] postgres[1742]: 	    AS $$1744vm-test-run-index_advisor> server # [  142.042487] postgres[1742]: 	BEGIN1745vm-test-run-index_advisor> server # [  142.043177] postgres[1742]: 	  IF EXISTS (1746vm-test-run-index_advisor> server # [  142.043696] postgres[1742]: 	    SELECT1747vm-test-run-index_advisor> server # [  142.054087] postgres[1742]: 	    FROM pg_event_trigger_ddl_commands() AS ev1748vm-test-run-index_advisor> server # [  142.055656] postgres[1742]: 	    JOIN pg_extension AS ext1749vm-test-run-index_advisor> server # [  142.055989] postgres[1742]: 	    ON ev.objid = ext.oid1750vm-test-run-index_advisor> server # [  142.061286] postgres[1742]: 	    WHERE ext.extname = 'pg_cron'1751vm-test-run-index_advisor> server # [  142.062765] postgres[1742]: 	  )1752vm-test-run-index_advisor> server # [  142.063108] postgres[1742]: 	  THEN1753vm-test-run-index_advisor> server # [  142.063488] postgres[1742]: 	    grant usage on schema cron to postgres with grant option;1754vm-test-run-index_advisor> server # [  142.063851] postgres[1742]:1755vm-test-run-index_advisor> server # [  142.064105] postgres[1742]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;1756vm-test-run-index_advisor> server # [  142.070056] postgres[1742]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;1757vm-test-run-index_advisor> server # [  142.071563] postgres[1742]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;1758vm-test-run-index_advisor> server # [  142.072212] postgres[1742]:1759vm-test-run-index_advisor> server # [  142.076995] postgres[1742]: 	    alter default privileges for user supabase_admin in schema cron grant all1760vm-test-run-index_advisor> server # [  142.078709] postgres[1742]: 	        on sequences to postgres with grant option;1761vm-test-run-index_advisor> server # [  142.079599] postgres[1742]: 	    alter default privileges for user supabase_admin in schema cron grant all1762vm-test-run-index_advisor> server # [  142.080361] postgres[1742]: 	        on tables to postgres with grant option;1763vm-test-run-index_advisor> server # [  142.087735] postgres[1742]: 	    alter default privileges for user supabase_admin in schema cron grant all1764vm-test-run-index_advisor> server # [  142.093712] postgres[1742]: 	        on functions to postgres with grant option;1765vm-test-run-index_advisor> server # [  142.094536] postgres[1742]:1766vm-test-run-index_advisor> server # [  142.095126] postgres[1742]: 	    grant all privileges on all tables in schema cron to postgres with grant option;1767vm-test-run-index_advisor> server # [  142.096139] postgres[1742]: 	    revoke all on table cron.job from postgres;1768vm-test-run-index_advisor> server # [  142.110013] postgres[1742]: 	    grant select on table cron.job to postgres with grant option;1769vm-test-run-index_advisor> server # [  142.111045] postgres[1742]: 	  END IF;1770vm-test-run-index_advisor> server # [  142.111798] postgres[1742]: 	END;1771vm-test-run-index_advisor> server # [  142.112070] postgres[1742]: 	$$;1772vm-test-run-index_advisor> server # [  142.127168] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: CREATE FUNCTION1773vm-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;1774vm-test-run-index_advisor> server # [  142.145139] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: DROP EVENT TRIGGER1775vm-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;1776vm-test-run-index_advisor> server # [  142.164934] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: ALTER FUNCTION1777vm-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_end1778vm-test-run-index_advisor> server # [  142.167463] postgres[1742]: 	         WHEN TAG IN ('CREATE EXTENSION')1779vm-test-run-index_advisor> server # [  142.167854] postgres[1742]: 	   EXECUTE FUNCTION extensions.grant_pg_cron_access();1780vm-test-run-index_advisor> server # [  142.174797] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1736]: CREATE EVENT TRIGGER1781vm-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.sql1782vm-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';1783vm-test-run-index_advisor> server # [  142.459220] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1744]: ALTER ROLE1784vm-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.sql1785vm-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;1786vm-test-run-index_advisor> server # [  142.851079] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1746]: ALTER FUNCTION1787vm-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;1788vm-test-run-index_advisor> server # [  142.869406] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1746]: ALTER FUNCTION1789vm-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;1790vm-test-run-index_advisor> server # [  142.875732] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1746]: ALTER FUNCTION1791vm-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.sql1792vm-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;1793vm-test-run-index_advisor> server # [  143.250795] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1752]: GRANT ROLE1794vm-test-run-index_advisor> server # [  143.277745] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql1795vm-test-run-index_advisor> server # [  144.134702] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1754]: DO1796vm-test-run-index_advisor> server # [  144.177522] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1797vm-test-run-index_advisor> server # [  144.783379] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1766]: DO1798vm-test-run-index_advisor> server # [  144.794836] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1766]: DO1799vm-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.sql1800vm-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;1801vm-test-run-index_advisor> server # [  145.274582] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1781]: ALTER ROLE1802vm-test-run-index_advisor> server # [  145.276015] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1781]: ALTER ROLE1803vm-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;1804vm-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;1805vm-test-run-index_advisor> server # [  145.286515] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1781]: ALTER ROLE1806vm-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.sql1807vm-test-run-index_advisor> server # [  145.620882] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1784]: DO1808vm-test-run-index_advisor> server # [  145.643939] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1809vm-test-run-index_advisor> server # [  145.867946] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1786]: DO1810vm-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.sql1811vm-test-run-index_advisor> server # [  146.166577] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1788]: CREATE FUNCTION1812vm-test-run-index_advisor> server # [  146.176689] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1788]: DO1813vm-test-run-index_advisor> server # [  146.217257] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1814vm-test-run-index_advisor> server # [  146.695464] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1791]: ALTER FUNCTION1815vm-test-run-index_advisor> server # [  146.708762] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1791]: GRANT1816vm-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.sql1817vm-test-run-index_advisor> server # [  147.238015] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1800]: DROP EVENT TRIGGER1818vm-test-run-index_advisor> server # [  147.312988] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1800]: ALTER FUNCTION1819vm-test-run-index_advisor> server # [  147.335740] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1800]: CREATE EVENT TRIGGER1820vm-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.sql1821vm-test-run-index_advisor> server # [  147.846064] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1811]: DO1822vm-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.sql1823vm-test-run-index_advisor> server # [  148.281556] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1820]: CREATE FUNCTION1824vm-test-run-index_advisor> server # [  148.298296] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1820]: GRANT1825vm-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.sql1826vm-test-run-index_advisor> server # [  148.740343] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: REVOKE ROLE1827vm-test-run-index_advisor> server # [  148.778044] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: DO1828vm-test-run-index_advisor> server # [  148.790280] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: DO1829vm-test-run-index_advisor> server # [  148.805165] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: REVOKE ROLE1830vm-test-run-index_advisor> server # [  148.819498] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: REVOKE1831vm-test-run-index_advisor> server # [  148.874630] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1826]: DO1832vm-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.sql1833vm-test-run-index_advisor> server # [  149.155996] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1828]: DO1834vm-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.sql1835vm-test-run-index_advisor> server # [  149.450972] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1830]: DO1836vm-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.sql1837vm-test-run-index_advisor> server # [  149.790639] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1832]: DO1838vm-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.sql1839vm-test-run-index_advisor> server # [  150.269358] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1838]: ALTER ROLE1840vm-test-run-index_advisor> server # [  150.283910] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql1841vm-test-run-index_advisor> server # [  150.735222] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1849]: GRANT ROLE1842vm-test-run-index_advisor> server # [  150.769477] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1849]: DO1843vm-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.sql1844vm-test-run-index_advisor> server # [  151.187772] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1857]: GRANT1845vm-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.sql1846vm-test-run-index_advisor> server # [  151.527152] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: CREATE FUNCTION1847vm-test-run-index_advisor> server # [  151.534785] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: REVOKE1848vm-test-run-index_advisor> server # [  151.547505] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: REVOKE1849vm-test-run-index_advisor> server # [  151.569585] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1864]: GRANT1850vm-test-run-index_advisor> server # [  151.609328] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql1851vm-test-run-index_advisor> server # [  151.947583] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1866]: DO1852vm-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.sql1853vm-test-run-index_advisor> server # [  152.387297] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1872]: ALTER ROLE1854vm-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.sql1855vm-test-run-index_advisor> server # [  152.973887] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1874]: DROP EXTENSION1856vm-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.sql1857vm-test-run-index_advisor> server # [  153.563557] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1885]: CREATE FUNCTION1858vm-test-run-index_advisor> server # [  153.601182] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1885]: DROP EVENT TRIGGER1859vm-test-run-index_advisor> server # [  153.617332] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1885]: CREATE EVENT TRIGGER1860vm-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.sql1861vm-test-run-index_advisor> server # [  154.173791] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1901]: DO1862vm-test-run-index_advisor> server # [  154.175023] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1901]: CREATE FUNCTION1863vm-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.sql1864vm-test-run-index_advisor> server # [  154.508975] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1904]: CREATE FUNCTION1865vm-test-run-index_advisor> server # [  154.517274] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1904]: CREATE FUNCTION1866vm-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.sql1867vm-test-run-index_advisor> server # [  154.831269] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION1868vm-test-run-index_advisor> server # [  154.838526] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION1869vm-test-run-index_advisor> server # [  154.853270] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION1870vm-test-run-index_advisor> server # [  154.866808] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION1871vm-test-run-index_advisor> server # [  154.878942] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION1872vm-test-run-index_advisor> server # [  154.890263] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1906]: CREATE FUNCTION1873vm-test-run-index_advisor> server # [  154.925205] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Running postgresql schema...1874vm-test-run-index_advisor> server # [  155.174566] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER DATABASE1875vm-test-run-index_advisor> server # [  155.178133] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER DATABASE1876vm-test-run-index_advisor> server # [  155.240092] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1877vm-test-run-index_advisor> server # [  155.302879] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1878vm-test-run-index_advisor> server # [  155.397828] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1879vm-test-run-index_advisor> server # [  155.469714] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1880vm-test-run-index_advisor> server # [  155.593651] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1881vm-test-run-index_advisor> server # [  155.681027] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1882vm-test-run-index_advisor> server # [  155.763024] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1883vm-test-run-index_advisor> server # [  155.849908] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1884vm-test-run-index_advisor> server # [  155.926131] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1885vm-test-run-index_advisor> server # [  155.932929] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1908]: ALTER ROLE1886vm-test-run-index_advisor> server # [  156.010779] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1375]: Database initialization complete1887vm-test-run-index_advisor> server # [  156.063634] systemd[1]: Finished Supabase Database Initialization.1888vm-test-run-index_advisor> server # [  156.094040] systemd[1]: Reached target Multi-User System.1889vm-test-run-index_advisor> server # [  156.107974] systemd[1]: Startup finished in 20.922s (kernel) + 2min 15.173s (userspace) = 2min 36.095s.1890vm-test-run-index_advisor> server # [  157.165331] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31891vm-test-run-index_advisor> server: (finished: waiting for unit supabase-db-init.service, in 158.80 seconds)1892vm-test-run-index_advisor> subtest: Check upgrade path with postgresql 151893vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1894vm-test-run-index_advisor> server # NOTICE:  extension "index_advisor" does not exist, skipping1895vm-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)1896vm-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;" 1897vm-test-run-index_advisor> server # NOTICE:  installing required extension "hypopg"1898vm-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)1899vm-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';" 1900vm-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)1901vm-test-run-index_advisor> (finished: subtest: Check upgrade path with postgresql 15, in 2.90 seconds)1902vm-test-run-index_advisor> subtest: Check the install of the last version of the extension1903vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1904vm-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)1905vm-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;" 1906vm-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)1907vm-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';" 1908vm-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)1909vm-test-run-index_advisor> (finished: subtest: Check the install of the last version of the extension, in 2.54 seconds)1910vm-test-run-index_advisor> subtest: switch to postgresql 171911vm-test-run-index_advisor> server # Checking switch inhibitors... done