nixbot

1this derivation will be built:2  /nix/store/8afm5b78h0ria5izgg1nrcibch2zn018-vm-test-run-index_advisor.drv3building '/nix/store/8afm5b78h0ria5izgg1nrcibch2zn018-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder'4building '/nix/store/8afm5b78h0ria5izgg1nrcibch2zn018-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: QEMU running (pid 9)24vm-test-run-index_advisor> server # Formatting '/build/vm-state-server/tmp.ATKXZ5dm5l', fmt=raw size=107374182425vm-test-run-index_advisor> server # Discarding device blocks:      0/262144             done26vm-test-run-index_advisor> server # Creating filesystem with 262144 4k blocks and 65536 inodes27vm-test-run-index_advisor> server # Filesystem UUID: 77a22e31-e8a4-48b5-9a29-da7cd15ba44128vm-test-run-index_advisor> server # Superblock backups stored on blocks:29vm-test-run-index_advisor> server # 	32768, 98304, 163840, 22937630vm-test-run-index_advisor> server # 31vm-test-run-index_advisor> server # Allocating group tables: 0/8   done32vm-test-run-index_advisor> server # Writing inode tables: 0/8   done33vm-test-run-index_advisor> server # Creating journal (8192 blocks): done34vm-test-run-index_advisor> server # Writing superblocks and filesystem accounting information: 0/8   done35vm-test-run-index_advisor> server # 36vm-test-run-index_advisor> server # Virtualisation disk image created.37vm-test-run-index_advisor> (finished: start all VMs, in 0.18 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/kkrfj1cn3xdsg535xj5lxqwwjbh7bk3l-nixos-system-server-test/init regInfo=/nix/store/4xgha2cd9jv2my1ik9lqxxy99glvw2f7-closure-info/registration console=ttyAMA0,115200n8 console=tty073vm-test-run-index_advisor> server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4xgha2cd9jv2my1ik9lqxxy99glvw2f7-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.000039] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns111vm-test-run-index_advisor> server # [    0.004046] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)112vm-test-run-index_advisor> server # [    0.014119] Console: colour dummy device 80x25113vm-test-run-index_advisor> server # [    0.015663] printk: legacy console [tty0] enabled114vm-test-run-index_advisor> server # [    0.021657] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)115vm-test-run-index_advisor> server # [    0.022050] pid_max: default: 32768 minimum: 301116vm-test-run-index_advisor> server # [    0.023906] LSM: initializing lsm=capability,landlock,yama,bpf117vm-test-run-index_advisor> server # [    0.025693] landlock: Up and running.118vm-test-run-index_advisor> server # [    0.025750] Yama: becoming mindful.119vm-test-run-index_advisor> server # [    0.029571] LSM support for eBPF active120vm-test-run-index_advisor> server # [    0.032303] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)121vm-test-run-index_advisor> server # [    0.032474] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)122vm-test-run-index_advisor> server # [    0.063079] cacheinfo: Unable to detect cache hierarchy for CPU 0123vm-test-run-index_advisor> server # [    0.075845] rcu: Hierarchical SRCU implementation.124vm-test-run-index_advisor> server # [    0.075951] rcu: 	Max phase no-delay instances is 1000.125vm-test-run-index_advisor> server # [    0.077377] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level126vm-test-run-index_advisor> server # [    0.084590] fsl-mc MSI: its@8080000 domain created127vm-test-run-index_advisor> server # [    0.086555] EFI services will not be available.128vm-test-run-index_advisor> server # [    0.089127] smp: Bringing up secondary CPUs ...129vm-test-run-index_advisor> server # [    0.094661] Detected PIPT I-cache on CPU1130vm-test-run-index_advisor> server # [    0.095850] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000131vm-test-run-index_advisor> server # [    0.096510] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000132vm-test-run-index_advisor> server # [    0.098044] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]133vm-test-run-index_advisor> server # [    0.101766] smp: Brought up 1 node, 2 CPUs134vm-test-run-index_advisor> server # [    0.102005] SMP: Total of 2 processors activated.135vm-test-run-index_advisor> server # [    0.102072] CPU: All CPU(s) started at EL1136vm-test-run-index_advisor> server # [    0.102348] CPU features: detected: Branch Target Identification137vm-test-run-index_advisor> server # [    0.102430] CPU features: detected: 32-bit EL0 Support138vm-test-run-index_advisor> server # [    0.102503] CPU features: detected: 32-bit EL1 Support139vm-test-run-index_advisor> server # [    0.102533] CPU features: detected: ARMv8.4 Translation Table Level140vm-test-run-index_advisor> server # [    0.102565] CPU features: detected: Instruction cache invalidation not required for I/D coherence141vm-test-run-index_advisor> server # [    0.102602] CPU features: detected: Data cache clean to the PoU not required for I/D coherence142vm-test-run-index_advisor> server # [    0.102660] CPU features: detected: Common not Private translations143vm-test-run-index_advisor> server # [    0.102684] CPU features: detected: CRC32 instructions144vm-test-run-index_advisor> server # [    0.102704] CPU features: detected: Data cache clean to Point of Deep Persistence145vm-test-run-index_advisor> server # [    0.102730] CPU features: detected: Data cache clean to Point of Persistence146vm-test-run-index_advisor> server # [    0.102754] CPU features: detected: Data independent timing control (DIT)147vm-test-run-index_advisor> server # [    0.102777] CPU features: detected: E0PD148vm-test-run-index_advisor> server # [    0.102796] CPU features: detected: Enhanced Counter Virtualization149vm-test-run-index_advisor> server # [    0.102818] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)150vm-test-run-index_advisor> server # [    0.102841] CPU features: detected: Enhanced Privileged Access Never151vm-test-run-index_advisor> server # [    0.102863] CPU features: detected: Enhanced Virtualization Traps152vm-test-run-index_advisor> server # [    0.102885] CPU features: detected: Fine Grained Traps153vm-test-run-index_advisor> server # [    0.102940] CPU features: detected: Generic authentication (IMP DEF algorithm)154vm-test-run-index_advisor> server # [    0.103015] CPU features: detected: RCpc load-acquire (LDAPR)155vm-test-run-index_advisor> server # [    0.103061] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)156vm-test-run-index_advisor> server # [    0.103087] CPU features: detected: LSE atomic instructions157vm-test-run-index_advisor> server # [    0.103108] CPU features: detected: Memory Copy and Memory Set instructions158vm-test-run-index_advisor> server # [    0.103131] CPU features: detected: Privileged Access Never159vm-test-run-index_advisor> server # [    0.103153] CPU features: detected: RAS Extension Support160vm-test-run-index_advisor> server # [    0.103173] CPU features: detected: Random Number Generator161vm-test-run-index_advisor> server # [    0.103215] CPU features: detected: Speculation barrier (SB)162vm-test-run-index_advisor> server # [    0.103237] CPU features: detected: Stage-2 Force Write-Back163vm-test-run-index_advisor> server # [    0.103258] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality164vm-test-run-index_advisor> server # [    0.103283] CPU features: detected: TLB range maintenance instructions165vm-test-run-index_advisor> server # [    0.103306] CPU features: detected: WFx with timeout166vm-test-run-index_advisor> server # [    0.103343] CPU features: detected: Speculative Store Bypassing Safe (SSBS)167vm-test-run-index_advisor> server # [    0.103367] CPU features: detected: Scalable Vector Extension168vm-test-run-index_advisor> server # [    0.110488] alternatives: applying system-wide alternatives169vm-test-run-index_advisor> server # [    0.127779] CPU features: detected: Hardware dirty bit management on CPU0-1170vm-test-run-index_advisor> server # [    0.128426] SVE: maximum available vector length 256 bytes per vector171vm-test-run-index_advisor> server # [    0.128519] SVE: default vector length 64 bytes per vector172vm-test-run-index_advisor> server # [    0.140779] Memory: 3922804K/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.162481] devtmpfs: initialized174vm-test-run-index_advisor> server # [    0.190726] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns175vm-test-run-index_advisor> server # [    0.191270] futex hash table entries: 512 (order: 3, 32768 bytes, linear)176vm-test-run-index_advisor> server # [    0.193479] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL177vm-test-run-index_advisor> server # [    0.193535] 0 pages in range for non-PLT usage178vm-test-run-index_advisor> server # [    0.193571] 509248 pages in range for PLT usage179vm-test-run-index_advisor> server # [    0.196893] pinctrl core: initialized pinctrl subsystem180vm-test-run-index_advisor> server # [    0.208222] DMI not present or invalid.181vm-test-run-index_advisor> server # [    0.227671] NET: Registered PF_NETLINK/PF_ROUTE protocol family182vm-test-run-index_advisor> server # [    0.243292] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations183vm-test-run-index_advisor> server # [    0.244348] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations184vm-test-run-index_advisor> server # [    0.245661] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations185vm-test-run-index_advisor> server # [    0.245949] audit: initializing netlink subsys (disabled)186vm-test-run-index_advisor> server # [    0.248282] audit: type=2000 audit(0.196:1): state=initialized audit_enabled=0 res=1187vm-test-run-index_advisor> server # [    0.254676] thermal_sys: Registered thermal governor 'fair_share'188vm-test-run-index_advisor> server # [    0.254736] thermal_sys: Registered thermal governor 'bang_bang'189vm-test-run-index_advisor> server # [    0.254796] thermal_sys: Registered thermal governor 'step_wise'190vm-test-run-index_advisor> server # [    0.254827] thermal_sys: Registered thermal governor 'user_space'191vm-test-run-index_advisor> server # [    0.254851] thermal_sys: Registered thermal governor 'power_allocator'192vm-test-run-index_advisor> server # [    0.255629] cpuidle: using governor ladder193vm-test-run-index_advisor> server # [    0.256410] cpuidle: using governor menu194vm-test-run-index_advisor> server # [    0.258611] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.195vm-test-run-index_advisor> server # [    0.259442] ASID allocator initialised with 65536 entries196vm-test-run-index_advisor> server # [    0.267368] Serial: AMBA PL011 UART driver197vm-test-run-index_advisor> server # [    0.316982] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1198vm-test-run-index_advisor> server # [    0.318523] printk: legacy console [ttyAMA0] enabled199vm-test-run-index_advisor> server # [    0.359888] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages200vm-test-run-index_advisor> server # [    0.360278] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page201vm-test-run-index_advisor> server # [    0.360393] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages202vm-test-run-index_advisor> server # [    0.360501] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page203vm-test-run-index_advisor> server # [    0.360602] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages204vm-test-run-index_advisor> server # [    0.361228] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page205vm-test-run-index_advisor> server # [    0.361523] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages206vm-test-run-index_advisor> server # [    0.361620] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page207vm-test-run-index_advisor> server # [    0.375478] fbcon: Taking over console208vm-test-run-index_advisor> server # [    0.376138] ACPI: Interpreter disabled.209vm-test-run-index_advisor> server # [    0.384108] iommu: Default domain type: Translated210vm-test-run-index_advisor> server # [    0.384266] iommu: DMA domain TLB invalidation policy: strict mode211vm-test-run-index_advisor> server # [    0.387420] SCSI subsystem initialized212vm-test-run-index_advisor> server # [    0.390553] usbcore: registered new interface driver usbfs213vm-test-run-index_advisor> server # [    0.391088] usbcore: registered new interface driver hub214vm-test-run-index_advisor> server # [    0.392005] usbcore: registered new device driver usb215vm-test-run-index_advisor> server # [    0.395734] pps_core: LinuxPPS API ver. 1 registered216vm-test-run-index_advisor> server # [    0.395878] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>217vm-test-run-index_advisor> server # [    0.396366] PTP clock support registered218vm-test-run-index_advisor> server # [    0.397392] EDAC MC: Ver: 3.0.0219vm-test-run-index_advisor> server # [    0.399167] scmi_core: SCMI protocol bus registered220vm-test-run-index_advisor> server # [    0.403166] FPGA manager framework221vm-test-run-index_advisor> server # [    0.404811] Advanced Linux Sound Architecture Driver Initialized.222vm-test-run-index_advisor> server # [    0.412828] vgaarb: loaded223vm-test-run-index_advisor> server # [    0.417590] clocksource: Switched to clocksource arch_sys_counter224vm-test-run-index_advisor> server # [    0.422884] VFS: Disk quotas dquot_6.6.0225vm-test-run-index_advisor> server # [    0.423133] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)226vm-test-run-index_advisor> server # [    0.424937] netfs: FS-Cache loaded227vm-test-run-index_advisor> server # [    0.431892] pnp: PnP ACPI: disabled228vm-test-run-index_advisor> server # [    0.458471] NET: Registered PF_INET protocol family229vm-test-run-index_advisor> server # [    0.461572] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)230vm-test-run-index_advisor> server # [    0.567439] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)231vm-test-run-index_advisor> server # [    0.567996] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)232vm-test-run-index_advisor> server # [    0.570521] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)233vm-test-run-index_advisor> server # [    0.571913] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)234vm-test-run-index_advisor> server # [    0.572823] TCP: Hash tables configured (established 32768 bind 32768)235vm-test-run-index_advisor> server # [    0.574500] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)236vm-test-run-index_advisor> server # [    0.574925] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)237vm-test-run-index_advisor> server # [    0.575650] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)238vm-test-run-index_advisor> server # [    0.577650] NET: Registered PF_UNIX/PF_LOCAL protocol family239vm-test-run-index_advisor> server # [    0.578226] NET: Registered PF_XDP protocol family240vm-test-run-index_advisor> server # [    0.578643] PCI: CLS 0 bytes, default 64241vm-test-run-index_advisor> server # [    0.581965] kvm [1]: HYP mode not available242vm-test-run-index_advisor> server # [    0.583784] Trying to unpack rootfs image as initramfs...243vm-test-run-index_advisor> server # [    0.680505] Initialise system trusted keyrings244vm-test-run-index_advisor> server # [    0.682278] workingset: timestamp_bits=42 max_order=20 bucket_order=0245vm-test-run-index_advisor> server # [    0.687059] squashfs: version 4.0 (2009/01/31) Phillip Lougher246vm-test-run-index_advisor> server # [    0.688458] 9p: Installing v9fs 9p2000 file system support247vm-test-run-index_advisor> server # [    0.744954] Key type asymmetric registered248vm-test-run-index_advisor> server # [    0.745282] Asymmetric key parser 'x509' registered249vm-test-run-index_advisor> server # [    0.746355] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)250vm-test-run-index_advisor> server # [    0.748301] io scheduler mq-deadline registered251vm-test-run-index_advisor> server # [    0.748464] io scheduler kyber registered252vm-test-run-index_advisor> server # [    0.775012] pl061_gpio 9030000.pl061: PL061 GPIO chip registered253vm-test-run-index_advisor> server # [    0.781304] ledtrig-cpu: registered to indicate activity on CPUs254vm-test-run-index_advisor> server # [    0.785267] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:255vm-test-run-index_advisor> server # [    0.786575] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000256vm-test-run-index_advisor> server # [    0.787123] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000257vm-test-run-index_advisor> server # [    0.787950] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000258vm-test-run-index_advisor> server # [    0.788749] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits259vm-test-run-index_advisor> server # [    0.789324] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]260vm-test-run-index_advisor> server # [    0.792060] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00261vm-test-run-index_advisor> server # [    0.792468] pci_bus 0000:00: root bus resource [bus 00-ff]262vm-test-run-index_advisor> server # [    0.792938] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]263vm-test-run-index_advisor> server # [    0.793458] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]264vm-test-run-index_advisor> server # [    0.794283] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]265vm-test-run-index_advisor> server # [    0.795996] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint266vm-test-run-index_advisor> server # [    0.799974] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint267vm-test-run-index_advisor> server # [    0.800650] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]268vm-test-run-index_advisor> server # [    0.800810] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]269vm-test-run-index_advisor> server # [    0.800946] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270vm-test-run-index_advisor> server # [    0.801107] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]271vm-test-run-index_advisor> server # [    0.802050] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint272vm-test-run-index_advisor> server # [    0.802521] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]273vm-test-run-index_advisor> server # [    0.802698] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]274vm-test-run-index_advisor> server # [    0.802812] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275vm-test-run-index_advisor> server # [    0.803469] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint276vm-test-run-index_advisor> server # [    0.803637] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]277vm-test-run-index_advisor> server # [    0.803732] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]278vm-test-run-index_advisor> server # [    0.803842] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279vm-test-run-index_advisor> server # [    0.804295] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint280vm-test-run-index_advisor> server # [    0.804442] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]281vm-test-run-index_advisor> server # [    0.804536] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]282vm-test-run-index_advisor> server # [    0.804642] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]283vm-test-run-index_advisor> server # [    0.805088] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint284vm-test-run-index_advisor> server # [    0.805244] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]285vm-test-run-index_advisor> server # [    0.805336] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]286vm-test-run-index_advisor> server # [    0.805445] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287vm-test-run-index_advisor> server # [    0.806328] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint288vm-test-run-index_advisor> server # [    0.806812] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]289vm-test-run-index_advisor> server # [    0.807581] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]290vm-test-run-index_advisor> server # [    0.807791] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]291vm-test-run-index_advisor> server # [    0.808297] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint292vm-test-run-index_advisor> server # [    0.808449] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]293vm-test-run-index_advisor> server # [    0.808545] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]294vm-test-run-index_advisor> server # [    0.808654] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]295vm-test-run-index_advisor> server # [    0.808782] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]296vm-test-run-index_advisor> server # [    0.809236] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint297vm-test-run-index_advisor> server # [    0.810094] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]298vm-test-run-index_advisor> server # [    0.810214] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]299vm-test-run-index_advisor> server # [    0.811141] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint300vm-test-run-index_advisor> server # [    0.811585] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]301vm-test-run-index_advisor> server # [    0.811801] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302vm-test-run-index_advisor> server # [    0.812379] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint303vm-test-run-index_advisor> server # [    0.812529] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]304vm-test-run-index_advisor> server # [    0.812950] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint305vm-test-run-index_advisor> server # [    0.813478] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]306vm-test-run-index_advisor> server # [    0.814439] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]307vm-test-run-index_advisor> server # [    0.814828] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]308vm-test-run-index_advisor> server # [    0.815702] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint309vm-test-run-index_advisor> server # [    0.816003] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]310vm-test-run-index_advisor> server # [    0.816100] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]311vm-test-run-index_advisor> server # [    0.816208] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]312vm-test-run-index_advisor> server # [    0.818133] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned313vm-test-run-index_advisor> server # [    0.818653] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned314vm-test-run-index_advisor> server # [    0.819379] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned315vm-test-run-index_advisor> server # [    0.819950] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned316vm-test-run-index_advisor> server # [    0.820135] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned317vm-test-run-index_advisor> server # [    0.820269] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned318vm-test-run-index_advisor> server # [    0.820405] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned319vm-test-run-index_advisor> server # [    0.820541] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned320vm-test-run-index_advisor> server # [    0.820671] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned321vm-test-run-index_advisor> server # [    0.820799] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned322vm-test-run-index_advisor> server # [    0.820927] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned323vm-test-run-index_advisor> server # [    0.821054] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned324vm-test-run-index_advisor> server # [    0.821207] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned325vm-test-run-index_advisor> server # [    0.821337] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned326vm-test-run-index_advisor> server # [    0.821448] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned327vm-test-run-index_advisor> server # [    0.821554] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned328vm-test-run-index_advisor> server # [    0.821657] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned329vm-test-run-index_advisor> server # [    0.821759] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned330vm-test-run-index_advisor> server # [    0.821873] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned331vm-test-run-index_advisor> server # [    0.821987] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned332vm-test-run-index_advisor> server # [    0.822098] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned333vm-test-run-index_advisor> server # [    0.822200] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned334vm-test-run-index_advisor> server # [    0.822306] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned335vm-test-run-index_advisor> server # [    0.822416] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned336vm-test-run-index_advisor> server # [    0.822521] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned337vm-test-run-index_advisor> server # [    0.822635] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned338vm-test-run-index_advisor> server # [    0.822749] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned339vm-test-run-index_advisor> server # [    0.822846] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned340vm-test-run-index_advisor> server # [    0.822941] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned341vm-test-run-index_advisor> server # [    0.823040] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned342vm-test-run-index_advisor> server # [    0.823134] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned343vm-test-run-index_advisor> server # [    0.823573] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned344vm-test-run-index_advisor> server # [    0.823725] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned345vm-test-run-index_advisor> server # [    0.823826] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned346vm-test-run-index_advisor> server # [    0.824130] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]347vm-test-run-index_advisor> server # [    0.824555] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]348vm-test-run-index_advisor> server # [    0.824668] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]349vm-test-run-index_advisor> server # [    0.826178] pci 0000:00:0a.0: enabling device (0000 -> 0002)350vm-test-run-index_advisor> server # [    0.887791] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)351vm-test-run-index_advisor> server # [    0.892827] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)352vm-test-run-index_advisor> server # [    0.896352] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)353vm-test-run-index_advisor> server # [    0.898788] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)354vm-test-run-index_advisor> server # [    0.902210] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)355vm-test-run-index_advisor> server # [    0.905708] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)356vm-test-run-index_advisor> server # [    0.908410] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)357vm-test-run-index_advisor> server # [    0.911345] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)358vm-test-run-index_advisor> server # [    0.913555] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)359vm-test-run-index_advisor> server # [    0.917551] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)360vm-test-run-index_advisor> server # [    0.920110] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)361vm-test-run-index_advisor> server # [    0.936603] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled362vm-test-run-index_advisor> server # [    0.946543] msm_serial: driver initialized363vm-test-run-index_advisor> server # [    0.947755] SuperH (H)SCI(F) driver initialized364vm-test-run-index_advisor> server # [    0.948260] STM32 USART driver initialized365vm-test-run-index_advisor> server # [    1.002344] loop: module loaded366vm-test-run-index_advisor> server # [    1.003754] virtio_blk virtio5: 2/0/0 default/read/poll queues367vm-test-run-index_advisor> server # [    1.008146] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)368vm-test-run-index_advisor> server # [    1.020984] megasas: 07.727.03.00-rc1369vm-test-run-index_advisor> server # [    1.026904] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]370vm-test-run-index_advisor> server # [    1.030587] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000371vm-test-run-index_advisor> server # [    1.031273] Intel/Sharp Extended Query Table at 0x0031372vm-test-run-index_advisor> server # [    1.033091] Using buffer write method373vm-test-run-index_advisor> server # [    1.033769] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]374vm-test-run-index_advisor> server # [    1.036056] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000375vm-test-run-index_advisor> server # [    1.036286] Intel/Sharp Extended Query Table at 0x0031376vm-test-run-index_advisor> server # [    1.038045] Using buffer write method377vm-test-run-index_advisor> server # [    1.038398] Concatenating MTD devices:378vm-test-run-index_advisor> server # [    1.038785] (0): "0.flash"379vm-test-run-index_advisor> server # [    1.038878] (1): "0.flash"380vm-test-run-index_advisor> server # [    1.038943] into device "0.flash"381vm-test-run-index_advisor> server # [    1.062654] Freeing initrd memory: 10684K382vm-test-run-index_advisor> server # [    1.086106] tun: Universal TUN/TAP device driver, 1.6383vm-test-run-index_advisor> server # [    1.100172] thunder_xcv, ver 1.0384vm-test-run-index_advisor> server # [    1.100420] thunder_bgx, ver 1.0385vm-test-run-index_advisor> server # [    1.100610] nicpf, ver 1.0386vm-test-run-index_advisor> server # [    1.103561] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version387vm-test-run-index_advisor> server # [    1.103737] hns3: Copyright (c) 2017 Huawei Corporation.388vm-test-run-index_advisor> server # [    1.104197] hclge is initializing389vm-test-run-index_advisor> server # [    1.105211] e1000: Intel(R) PRO/1000 Network Driver390vm-test-run-index_advisor> server # [    1.105565] e1000: Copyright (c) 1999-2006 Intel Corporation.391vm-test-run-index_advisor> server # [    1.106196] e1000e: Intel(R) PRO/1000 Network Driver392vm-test-run-index_advisor> server # [    1.106414] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.393vm-test-run-index_advisor> server # [    1.106659] igb: Intel(R) Gigabit Ethernet Network Driver394vm-test-run-index_advisor> server # [    1.106758] igb: Copyright (c) 2007-2014 Intel Corporation.395vm-test-run-index_advisor> server # [    1.106983] igbvf: Intel(R) Gigabit Virtual Function Network Driver396vm-test-run-index_advisor> server # [    1.107086] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.397vm-test-run-index_advisor> server # [    1.108206] sky2: driver version 1.30398vm-test-run-index_advisor> server # [    1.117117] ehci-pci 0000:00:0a.0: EHCI Host Controller399vm-test-run-index_advisor> server # [    1.118472] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1400vm-test-run-index_advisor> server # [    1.119306] usbcore: registered new interface driver usb-storage401vm-test-run-index_advisor> server # [    1.120027] usbcore: registered new interface driver usbserial_generic402vm-test-run-index_advisor> server # [    1.120308] usbserial: USB Serial support registered for generic403vm-test-run-index_advisor> server # [    1.121256] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000404vm-test-run-index_advisor> server # [    1.129545] rtc-pl031 9010000.pl031: registered as rtc0405vm-test-run-index_advisor> server # [    1.130868] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00406vm-test-run-index_advisor> server # [    1.131344] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T02:20:26 UTC (1791426026)407vm-test-run-index_advisor> server # [    1.134196] i2c_dev: i2c /dev entries driver408vm-test-run-index_advisor> server # [    1.139260] hub 1-0:1.0: USB hub found409vm-test-run-index_advisor> server # [    1.140427] hub 1-0:1.0: 6 ports detected410vm-test-run-index_advisor> server # [    1.148825] sdhci: Secure Digital Host Controller Interface driver411vm-test-run-index_advisor> server # [    1.148992] sdhci: Copyright(c) Pierre Ossman412vm-test-run-index_advisor> server # [    1.150979] Synopsys Designware Multimedia Card Interface Driver413vm-test-run-index_advisor> server # [    1.153094] sdhci-pltfm: SDHCI platform and OF driver helper414vm-test-run-index_advisor> server # [    1.160020] hid: raw HID events driver (C) Jiri Kosina415vm-test-run-index_advisor> server # [    1.162535] usbcore: registered new interface driver usbhid416vm-test-run-index_advisor> server # [    1.162940] usbhid: USB HID core driver417vm-test-run-index_advisor> server # [    1.171482] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available418vm-test-run-index_advisor> server # [    1.185162] drop_monitor: Initializing network drop monitor service419vm-test-run-index_advisor> server # [    1.187117] NET: Registered PF_INET6 protocol family420vm-test-run-index_advisor> server # [    1.204031] Segment Routing with IPv6421vm-test-run-index_advisor> server # [    1.204436] In-situ OAM (IOAM) with IPv6422vm-test-run-index_advisor> server # [    1.205238] NET: Registered PF_PACKET protocol family423vm-test-run-index_advisor> server # [    1.207243] 9pnet: Installing 9P2000 support424vm-test-run-index_advisor> server # [    1.212020] Key type dns_resolver registered425vm-test-run-index_advisor> server # [    1.248186] registered taskstats version 1426vm-test-run-index_advisor> server # [    1.250488] Loading compiled-in X.509 certificates427vm-test-run-index_advisor> server # [    1.307659] Demotion targets for Node 0: null428vm-test-run-index_advisor> server # [    1.310564] Key type .fscrypt registered429vm-test-run-index_advisor> server # [    1.310715] Key type fscrypt-provisioning registered430vm-test-run-index_advisor> server # [    1.320628] input: gpio-keys as /devices/platform/gpio-keys/input/input0431vm-test-run-index_advisor> server # [    1.362773] clk: Disabling unused clocks432vm-test-run-index_advisor> server # [    1.363147] PM: genpd: Disabling unused power domains433vm-test-run-index_advisor> server # [    1.363484] ALSA device list:434vm-test-run-index_advisor> server # [    1.363930]   No soundcards found.435vm-test-run-index_advisor> server # [    1.398615] usb 1-1: new high-speed USB device number 2 using ehci-pci436vm-test-run-index_advisor> server # [    1.446743] Freeing unused kernel memory: 4416K437vm-test-run-index_advisor> server # [    1.448424] Run /init as init process438vm-test-run-index_advisor> server # [    1.596196] 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.737179] 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.858024] usb 1-2: new high-speed USB device number 3 using ehci-pci441vm-test-run-index_advisor> server # [    2.041555] 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.043853] 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.074056] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev444vm-test-run-index_advisor> server # [    2.212537] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0445vm-test-run-index_advisor> server # [    2.213193] [drm] features: -virgl +edid -resource_blob -host_visible446vm-test-run-index_advisor> server # [    2.213217] [drm] features: -context_init447vm-test-run-index_advisor> server # [    2.216111] [drm] number of scanouts: 1448vm-test-run-index_advisor> server # [    2.216286] [drm] number of cap sets: 0449vm-test-run-index_advisor> server # [    2.226875] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0450vm-test-run-index_advisor> server # [    2.262851] Console: switching to colour frame buffer device 160x50451vm-test-run-index_advisor> server # [    2.275736] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device452vm-test-run-index_advisor> server # [    5.037366] EXT4-fs (vda): mounted filesystem 77a22e31-e8a4-48b5-9a29-da7cd15ba441 r/w with ordered data mode. Quota mode: none.453vm-test-run-index_advisor> server # [    7.424145] EXT4-fs (vda): re-mounted 77a22e31-e8a4-48b5-9a29-da7cd15ba441.454vm-test-run-index_advisor> server # [    7.441390] booting system configuration /nix/store/kkrfj1cn3xdsg535xj5lxqwwjbh7bk3l-nixos-system-server-test455vm-test-run-index_advisor> server # [   19.784695] 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 # [   19.796802] systemd[1]: Detected virtualization qemu.457vm-test-run-index_advisor> server # [   19.800837] systemd[1]: Detected architecture arm64.458vm-test-run-index_advisor> server # [   19.805994] systemd[1]: Detected first boot.459vm-test-run-index_advisor> server # [   19.835033] systemd[1]: Initializing machine ID from random generator.460vm-test-run-index_advisor> server # [   19.875180] systemd[1]: Hostname set to <server>.461vm-test-run-index_advisor> server # [   21.103935] systemd[1]: bpf-restrict-fs: LSM BPF program attached462vm-test-run-index_advisor> server # [   22.511575] systemd[1]: Applying preset policy.463vm-test-run-index_advisor> server # [   26.197117] 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 # [   29.731000] systemd[1]: Queued start job for default target Multi-User System.467vm-test-run-index_advisor> server # [   29.960039] systemd[1]: Created slice Slice /system/getty.468vm-test-run-index_advisor> server # [   29.977599] systemd[1]: Created slice Slice /system/modprobe.469vm-test-run-index_advisor> server # [   29.991094] systemd[1]: Created slice User and Session Slice.470vm-test-run-index_advisor> server # [   29.998780] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.471vm-test-run-index_advisor> server # [   30.003869] systemd[1]: Started Forward Password Requests to Wall Directory Watch.472vm-test-run-index_advisor> server # [   30.009898] systemd[1]: Expecting device /dev/hvc0...473vm-test-run-index_advisor> server # [   30.014677] systemd[1]: Expecting device /dev/ttyAMA0...474vm-test-run-index_advisor> server # [   30.018246] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...475vm-test-run-index_advisor> server # [   30.024099] systemd[1]: Reached target Local Encrypted Volumes.476vm-test-run-index_advisor> server # [   30.028491] systemd[1]: Reached target Virtual Machines and Containers.477vm-test-run-index_advisor> server # [   30.032819] systemd[1]: Reached target Path Units.478vm-test-run-index_advisor> server # [   30.037029] systemd[1]: Reached target Remote File Systems.479vm-test-run-index_advisor> server # [   30.042326] systemd[1]: Reached target Slice Units.480vm-test-run-index_advisor> server # [   30.046397] systemd[1]: Reached target Swaps.481vm-test-run-index_advisor> server # [   30.086268] systemd[1]: Listening on Process Core Dump Socket.482vm-test-run-index_advisor> server # [   30.109438] systemd[1]: Listening on Credential Encryption/Decryption.483vm-test-run-index_advisor> server # [   30.120398] systemd[1]: Listening on Journal Audit Socket.484vm-test-run-index_advisor> server # [   30.127538] systemd[1]: Listening on Journal Socket (/dev/log).485vm-test-run-index_advisor> server # [   30.133457] systemd[1]: Listening on Journal Sockets.486vm-test-run-index_advisor> server # [   30.141932] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.487vm-test-run-index_advisor> server # [   30.147440] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).488vm-test-run-index_advisor> server # [   30.154389] systemd[1]: Listening on udev Control Socket.489vm-test-run-index_advisor> server # [   30.159907] systemd[1]: Listening on udev Kernel Socket.490vm-test-run-index_advisor> server # [   30.210546] systemd[1]: Mounting Huge Pages File System...491vm-test-run-index_advisor> server # [   30.251874] systemd[1]: Mounting POSIX Message Queue File System...492vm-test-run-index_advisor> server # [   30.298040] systemd[1]: Mounting Kernel Debug File System...493vm-test-run-index_advisor> server # [   30.391052] systemd[1]: Mounting Kernel Trace File System...494vm-test-run-index_advisor> server # [   30.511568] systemd[1]: Starting Create List of Static Device Nodes...495vm-test-run-index_advisor> server # [   30.619511] systemd[1]: Starting Load Kernel Module 9pnet_virtio...496vm-test-run-index_advisor> server # [   30.627416] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).497vm-test-run-index_advisor> server # [   30.751952] systemd[1]: Mounting Kernel Configuration File System...498vm-test-run-index_advisor> server # [   30.767871] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).499vm-test-run-index_advisor> server # [   30.784277] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).500vm-test-run-index_advisor> server # [   30.904514] systemd[1]: Starting Load Kernel Module fuse...501vm-test-run-index_advisor> server # [   30.913888] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).502vm-test-run-index_advisor> server # [   30.920104] 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 # [   31.023544] systemd[1]: Starting Journal Service...504vm-test-run-index_advisor> server # [   31.109051] systemd[1]: Starting Load Kernel Modules...505vm-test-run-index_advisor> server # [   31.184809] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...506vm-test-run-index_advisor> server # [   31.245447] systemd[1]: Starting Remount Root and Kernel File Systems...507vm-test-run-index_advisor> server # [   31.254966] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).508vm-test-run-index_advisor> server # [   31.333258] systemd[1]: Starting Coldplug All udev Devices...509vm-test-run-index_advisor> server # [   31.426467] systemd[1]: Mounted Huge Pages File System.510vm-test-run-index_advisor> server # [   31.436140] systemd[1]: Mounted POSIX Message Queue File System.511vm-test-run-index_advisor> server # [   31.448348] systemd[1]: Mounted Kernel Debug File System.512vm-test-run-index_advisor> server # [   31.461363] systemd[1]: Mounted Kernel Trace File System.513vm-test-run-index_advisor> server # [   31.489504] systemd[1]: Finished Create List of Static Device Nodes.514vm-test-run-index_advisor> server # [   31.505119] systemd[1]: Mounted Kernel Configuration File System.515vm-test-run-index_advisor> server # [   31.578413] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...516vm-test-run-index_advisor> server # [   32.272041] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517vm-test-run-index_advisor> server # [   32.292834] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518vm-test-run-index_advisor> server # [   32.462159] systemd[1]: Finished Load Kernel Modules.519vm-test-run-index_advisor> server # [   32.528795] systemd[1]: Starting Firewall...520vm-test-run-index_advisor> server # [   32.566305] EXT4-fs (vda): re-mounted 77a22e31-e8a4-48b5-9a29-da7cd15ba441.521vm-test-run-index_advisor> server # [   32.596657] systemd[1]: Starting Apply Kernel Variables...522vm-test-run-index_advisor> server # [   32.711464] systemd[1]: Finished Remount Root and Kernel File Systems.523vm-test-run-index_advisor> server # [   32.721089] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).524vm-test-run-index_advisor> server # [   32.790485] systemd[1]: Starting Load/Save OS Random Seed...525vm-test-run-index_advisor> server # [   32.802842] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).526vm-test-run-index_advisor> server # [   32.840603] fuse: init (API version 7.41)527vm-test-run-index_advisor> server # [   32.888323] systemd[1]: modprobe@fuse.service: Deactivated successfully.528vm-test-run-index_advisor> server # [   32.899089] systemd[1]: Finished Load Kernel Module fuse.529vm-test-run-index_advisor> server # [   32.953315] systemd[1]: Mounting FUSE Control File System...530vm-test-run-index_advisor> server # [   33.090069] systemd-journald[399]: Collecting audit messages is enabled.531vm-test-run-index_advisor> server # [   33.248514] systemd[1]: Mounted FUSE Control File System.532vm-test-run-index_advisor> server # [   33.679782] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.533vm-test-run-index_advisor> server # [   33.743837] systemd[1]: Starting Create Static Device Nodes in /dev...534vm-test-run-index_advisor> server # [   34.043092] systemd[1]: Finished Load/Save OS Random Seed.535vm-test-run-index_advisor> server # [   34.060748] systemd[1]: Reached target First Boot Complete.536vm-test-run-index_advisor> server # [   34.248332] systemd[1]: Finished Apply Kernel Variables.537vm-test-run-index_advisor> server # [   34.779508] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.538vm-test-run-index_advisor> server # [   35.216807] systemd[1]: Finished Create Static Device Nodes in /dev.539vm-test-run-index_advisor> server # [   35.245315] systemd[1]: Reached target Preparation for Local File Systems.540vm-test-run-index_advisor> server # [   35.295423] systemd[1]: Mounting /run/wrappers...541vm-test-run-index_advisor> server # [   35.356352] systemd[1]: Starting Rule-based Manager for Device Events and Files...542vm-test-run-index_advisor> server # [   35.774416] systemd[1]: Mounted /run/wrappers.543vm-test-run-index_advisor> server # [   35.782369] systemd[1]: Reached target Local File Systems.544vm-test-run-index_advisor> server # [   35.808605] systemd[1]: Listening on Boot Entries Service Socket.545vm-test-run-index_advisor> server # [   35.854921] systemd[1]: Starting Create SUID/SGID Wrappers...546vm-test-run-index_advisor> server # [   35.860586] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.547vm-test-run-index_advisor> server # [   35.912144] systemd[1]: Starting Save Transient machine-id to Disk...548vm-test-run-index_advisor> server # [   36.046634] systemd[1]: Started Journal Service.549vm-test-run-index_advisor> server # [   36.053247] systemd-modules-load[400]: Module 'atkbd' is built in550vm-test-run-index_advisor> server # [   36.075173] systemd-modules-load[400]: Module 'loop' is built in551vm-test-run-index_advisor> server # [   36.135694] systemd-oomd[401]: No swap; memory pressure usage will be degraded552vm-test-run-index_advisor> server # [   36.207944] systemd[1]: Starting Flush Journal to Persistent Storage...553vm-test-run-index_advisor> server # [   36.944548] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.554vm-test-run-index_advisor> server # [   37.006083] systemd[1]: Finished Save Transient machine-id to Disk.555vm-test-run-index_advisor> server # [   37.080162] systemd-udevd[426]: Using default interface naming scheme 'v258'.556vm-test-run-index_advisor> server # [   37.264429] systemd-journald[399]: Received client request to flush runtime journal.557vm-test-run-index_advisor> server # [   37.820149] systemd[1]: Finished Flush Journal to Persistent Storage.558vm-test-run-index_advisor> server # [   37.872180] systemd[1]: Starting Create System Files and Directories...559vm-test-run-index_advisor> server # [   39.803018] systemd[1]: Started Rule-based Manager for Device Events and Files.560vm-test-run-index_advisor> server # [   39.933622] systemd[1]: Finished Create System Files and Directories.561vm-test-run-index_advisor> server # [   40.030461] systemd[1]: Starting Rebuild Journal Catalog...562vm-test-run-index_advisor> server # [   40.086369] systemd[1]: Starting Record System Boot/Shutdown in UTMP...563vm-test-run-index_advisor> server # [   41.242756] systemd[1]: Finished Record System Boot/Shutdown in UTMP.564vm-test-run-index_advisor> server # [   42.097327] systemd[1]: Finished Rebuild Journal Catalog.565vm-test-run-index_advisor> server # [   42.174083] systemd[1]: Starting Update is Completed...566vm-test-run-index_advisor> server # [   42.925654] systemd[1]: Finished Update is Completed.567vm-test-run-index_advisor> server # [   46.528951] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.568vm-test-run-index_advisor> server # [   46.543966] systemd[1]: Finished Create SUID/SGID Wrappers.569vm-test-run-index_advisor> server # [   46.551518] systemd[1]: suid-sgid-wrappers.service: Consumed 2.895s CPU time, 5.6M memory peak.570vm-test-run-index_advisor> server # [   49.168177] systemd[1]: Finished Firewall.571vm-test-run-index_advisor> server # [   50.807349] systemd[1]: Finished Coldplug All udev Devices.572vm-test-run-index_advisor> server # [   50.818993] systemd[1]: Reached target System Initialization.573vm-test-run-index_advisor> server # [   50.830191] systemd[1]: Started Discard unused filesystem blocks once a week.574vm-test-run-index_advisor> server # [   50.832594] systemd[1]: Started Daily Cleanup of Temporary Directories.575vm-test-run-index_advisor> server # [   50.842051] systemd[1]: Reached target Timer Units.576vm-test-run-index_advisor> server # [   50.850732] systemd[1]: Listening on D-Bus System Message Bus Socket.577vm-test-run-index_advisor> server # [   50.861612] systemd[1]: Listening on Nix Daemon Socket.578vm-test-run-index_advisor> server # [   50.865823] systemd[1]: Listening on Hostname Service Socket.579vm-test-run-index_advisor> server # [   50.874322] systemd[1]: Reached target Socket Units.580vm-test-run-index_advisor> server # [   50.882522] systemd[1]: Reached target Basic System.581vm-test-run-index_advisor> server # [   50.916781] systemd[1]: Starting Import lastlog data into lastlog2 database...582vm-test-run-index_advisor> server # [   50.953921] systemd[1]: Starting Name Service Cache Daemon (nsncd)...583vm-test-run-index_advisor> server # [   50.998780] systemd[1]: Started Reset console on configuration changes.584vm-test-run-index_advisor> server # [   51.059587] systemd[1]: Starting resolvconf update...585vm-test-run-index_advisor> server # [   51.145330] systemd[1]: Starting D-Bus System Message Bus...586vm-test-run-index_advisor> server # [   52.300038] systemd[1]: Finished Import lastlog data into lastlog2 database.587vm-test-run-index_advisor> server # [   52.450827] systemd[1]: Started Name Service Cache Daemon (nsncd).588vm-test-run-index_advisor> server # [   52.512556] nsncd[628]: Oct 08 02:21:17.864 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"589vm-test-run-index_advisor> server # [   52.535276] systemd[1]: Reached target Host and Network Name Lookups.590vm-test-run-index_advisor> server # [   52.543093] systemd[1]: Reached target User and Group Name Lookups.591vm-test-run-index_advisor> server # [   52.558543] systemd[1]: Starting User Login Management...592vm-test-run-index_advisor> server # [   52.584074] dbus-daemon[631]: dbus[631]: Unknown username "systemd-timesync" in message bus configuration file593vm-test-run-index_advisor> server # [   53.085573] systemd[1]: Started D-Bus System Message Bus.594vm-test-run-index_advisor> server # [   54.030026] systemd-logind[649]: New seat seat0.595vm-test-run-index_advisor> server # [   54.080997] systemd[1]: Stopped target Host and Network Name Lookups.596vm-test-run-index_advisor> server # [   54.102351] systemd[1]: Stopping Host and Network Name Lookups...597vm-test-run-index_advisor> server # [   54.114196] systemd[1]: Stopped target User and Group Name Lookups.598vm-test-run-index_advisor> server # [   54.131580] systemd[1]: Stopping User and Group Name Lookups...599vm-test-run-index_advisor> server # [   54.158171] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...600vm-test-run-index_advisor> server # [   54.169560] systemd[1]: Started User Login Management.601vm-test-run-index_advisor> server # [   54.189661] systemd[1]: nscd.service: Deactivated successfully.602vm-test-run-index_advisor> server # [   54.203788] systemd[1]: Stopped Name Service Cache Daemon (nsncd).603vm-test-run-index_advisor> server # [   54.265124] systemd[1]: Starting linger-users.service...604vm-test-run-index_advisor> server # [   54.309718] systemd[1]: Starting Name Service Cache Daemon (nsncd)...605vm-test-run-index_advisor> server # [   54.875011] systemd[1]: linger-users.service: Deactivated successfully.606vm-test-run-index_advisor> server # [   54.886850] systemd[1]: Finished linger-users.service.607vm-test-run-index_advisor> server # [   55.042763] systemd[1]: Started Name Service Cache Daemon (nsncd).608vm-test-run-index_advisor> server # [   55.053713] systemd[1]: Reached target Host and Network Name Lookups.609vm-test-run-index_advisor> server # [   55.086821] nsncd[689]: Oct 08 02:21:20.493 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"610vm-test-run-index_advisor> server # [   55.122851] systemd[1]: Reached target User and Group Name Lookups.611vm-test-run-index_advisor> server # [   55.614881] systemd[1]: Finished resolvconf update.612vm-test-run-index_advisor> server # [   55.628696] systemd[1]: Reached target Preparation for Network.613vm-test-run-index_advisor> server # [   55.674634] systemd[1]: Starting DHCP Client...614vm-test-run-index_advisor> server # [   56.906234] dhcpcd[739]: dhcpcd-10.2.4 starting615vm-test-run-index_advisor> server # [   57.038265] dhcpcd[743]: dev: loaded udev616vm-test-run-index_advisor> server # [   57.584531] 8021q: 802.1Q VLAN Support v1.8617vm-test-run-index_advisor> server # [   58.132982] cfg80211: Loading compiled-in X.509 certificates for regulatory database618vm-test-run-index_advisor> server # [   58.368727] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'619vm-test-run-index_advisor> server # [   58.379828] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'620vm-test-run-index_advisor> server # [   58.411010] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2621vm-test-run-index_advisor> server # [   58.422798] cfg80211: failed to load regulatory.db622vm-test-run-index_advisor> server # [   58.747785] systemd[1]: Found device /dev/hvc0.623vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...624vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.625vm-test-run-index_advisor> server # [   59.219186] 8021q: adding VLAN 0 to HW filter on device eth0626vm-test-run-index_advisor> server # [   59.162379] dhcpcd[743]: eth0: waiting for carrier627vm-test-run-index_advisor> server # [   59.167492] dhcpcd[743]: eth0: carrier acquired628vm-test-run-index_advisor> server # [   59.291574] dhcpcd[743]: DUID 00:01:00:01:32:59:ba:a4:52:54:00:12:34:56629vm-test-run-index_advisor> server # [   59.294909] dhcpcd[743]: eth0: IAID 00:12:34:56630vm-test-run-index_advisor> server # [   59.301763] dhcpcd[743]: eth0: adding address fe80::5054:ff:fe12:3456631vm-test-run-index_advisor> server # [   59.387109] systemd[1]: Found device /dev/ttyAMA0.632vm-test-run-index_advisor> server # [   59.433179] systemd[1]: Started backdoor.service.633vm-test-run-index_advisor> server # connecting to host...634vm-test-run-index_advisor> server: Guest shell says: b'Spawning backdoor root shell...\n'635vm-test-run-index_advisor> server: connected to guest root shell636vm-test-run-index_advisor> server: (connecting took 61.19 seconds)637vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 61.19 seconds)638vm-test-run-index_advisor> server # [   61.017308] dhcpcd[743]: eth0: soliciting a DHCP lease639vm-test-run-index_advisor> server # [   61.067802] dhcpcd[743]: eth0: offered 10.0.2.15 from 10.0.2.2640vm-test-run-index_advisor> server # [   61.105736] dhcpcd[743]: eth0: probing address 10.0.2.15/24641vm-test-run-index_advisor> server # [   61.221543] dhcpcd[743]: eth0: soliciting an IPv6 router642vm-test-run-index_advisor> server # [   61.245090] dhcpcd[743]: eth0: Router Advertisement from fe80::2643vm-test-run-index_advisor> server # [   61.246622] dhcpcd[743]: eth0: adding address fec0::5054:ff:fe12:3456/64644vm-test-run-index_advisor> server # [   61.259848] dhcpcd[743]: eth0: adding route to fec0::/64645vm-test-run-index_advisor> server # [   61.263196] dhcpcd[743]: eth0: adding default route via fe80::2646vm-test-run-index_advisor> server # [   61.988873] (udev-worker)[799]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.647vm-test-run-index_advisor> server # [   62.002338] (udev-worker)[795]: Network interface NamePolicy= disabled on kernel command line.648vm-test-run-index_advisor> server # [   62.017603] (udev-worker)[799]: Network interface NamePolicy= disabled on kernel command line.649vm-test-run-index_advisor> server # [   62.778016] systemd[1]: Found device Virtio network device.650vm-test-run-index_advisor> server # [   62.813109] systemd[1]: Starting Address configuration of eth1...651vm-test-run-index_advisor> server # [   63.239775] dhcpcd[743]: eth0: IAID 00:12:34:56652vm-test-run-index_advisor> server # [   63.505193] dhcpcd[743]: eth0: soliciting an IPv6 router653vm-test-run-index_advisor> server # [   63.913507] 8021q: adding VLAN 0 to HW filter on device eth1654vm-test-run-index_advisor> server # [   64.207564] network-addresses-eth1-start[819]: adding address 192.168.1.1/24... done655vm-test-run-index_advisor> server # [   64.378215] dhcpcd[743]: eth0: soliciting a DHCP lease656vm-test-run-index_advisor> server # [   64.380051] dhcpcd[743]: eth0: offered 10.0.2.15 from 10.0.2.2657vm-test-run-index_advisor> server # [   64.392012] dhcpcd[743]: eth0: probing address 10.0.2.15/24658vm-test-run-index_advisor> server # [   64.421256] network-addresses-eth1-start[819]: adding address 2001:db8:1::1/64... done659vm-test-run-index_advisor> server # [   64.681567] systemd[1]: Finished Address configuration of eth1.660vm-test-run-index_advisor> server # [   64.781414] systemd[1]: Starting Networking Setup...661vm-test-run-index_advisor> server # [   66.896698] systemd[1]: Finished Networking Setup.662vm-test-run-index_advisor> server # [   66.902835] systemd[1]: Reached target Network.663vm-test-run-index_advisor> server # [   66.960922] systemd[1]: Starting PostgreSQL Database Server...664vm-test-run-index_advisor> server # [   67.045158] systemd[1]: Starting Permit User Sessions...665vm-test-run-index_advisor> server # [   67.745337] systemd[1]: Finished Permit User Sessions.666vm-test-run-index_advisor> server # [   67.833569] systemd[1]: Started Getty on tty1.667vm-test-run-index_advisor> server # [   67.835138] systemd[1]: Reached target Login Prompts.668vm-test-run-index_advisor> server # [   68.358897] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[935]: Initializing database at /var/lib/postgresql/data669vm-test-run-index_advisor> server # [   68.683421] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys)670vm-test-run-index_advisor> server # [   69.138368] dhcpcd[743]: eth0: leased 10.0.2.15 for 86400 seconds671vm-test-run-index_advisor> server # [   69.139253] dhcpcd[743]: eth0: adding route to 10.0.2.0/24672vm-test-run-index_advisor> server # [   69.140011] dhcpcd[743]: eth0: adding default route via 10.0.2.2673vm-test-run-index_advisor> server # [   70.499570] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: The files belonging to this database system will be owned by user "postgres".674vm-test-run-index_advisor> server # [   70.506052] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: This user must also own the server process.675vm-test-run-index_advisor> server # [   70.604515] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: The database cluster will be initialized with locale "en_US.UTF-8".676vm-test-run-index_advisor> server # [   70.607038] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: The default database encoding has accordingly been set to "UTF8".677vm-test-run-index_advisor> server # [   70.618166] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: The default text search configuration will be set to "english".678vm-test-run-index_advisor> server # [   70.621386] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: Data page checksums are enabled.679vm-test-run-index_advisor> server # [   70.632099] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: fixing permissions on existing directory /var/lib/postgresql/data ... ok680vm-test-run-index_advisor> server # [   70.669274] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: creating subdirectories ... ok681vm-test-run-index_advisor> server # [   70.680167] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: selecting dynamic shared memory implementation ... posix682vm-test-run-index_advisor> server # [   71.952530] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: selecting default max_connections ... 100683vm-test-run-index_advisor> server # [   72.730771] systemd[1]: Started DHCP Client.684vm-test-run-index_advisor> server # [   72.984874] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: selecting default shared_buffers ... 128MB685vm-test-run-index_advisor> server # [   76.198451] mousedev: PS/2 mouse device common for all mice686vm-test-run-index_advisor> server # [   80.606355] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)687vm-test-run-index_advisor> server # [   84.140147] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: selecting default time zone ... UTC688vm-test-run-index_advisor> server # [   84.200076] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: creating configuration files ... ok689vm-test-run-index_advisor> server # [   89.271818] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: running bootstrap script ... ok690vm-test-run-index_advisor> server # [  101.855631] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: performing post-bootstrap initialization ... ok691vm-test-run-index_advisor> server # [  104.972845] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: syncing data to disk ... ok692vm-test-run-index_advisor> server # [  104.994910] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: initdb: warning: enabling "trust" authentication for local connections693vm-test-run-index_advisor> server # [  104.995838] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: 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.694vm-test-run-index_advisor> server # [  105.009958] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]: Success. You can now start the database server using:695vm-test-run-index_advisor> server # [  105.011695] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[941]:     /nix/store/2jmk0ipjl6m8ckpzhqyax62hqwcvkfyh-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start696vm-test-run-index_advisor> server # [  106.893405] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[935]: Validating PostgreSQL configuration...697vm-test-run-index_advisor> server # [  107.351678] 6vbg85406f6rdg1jfmcr4fy57251g3mf-postgresql-init[1357]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault698vm-test-run-index_advisor> server # [  108.798046] postgres[1361]:  2026-10-08 02:22:14.225 UTC [1361] LOG:  pgaudit extension initialized699vm-test-run-index_advisor> server # [  109.866656] postgres[1361]:  2026-10-08 02:22:15.297 UTC [1361] LOG:  pgsodium primary server secret key loaded700vm-test-run-index_advisor> server # [  110.376112] postgres[1361]:  2026-10-08 02:22:15.818 UTC [1361] LOG:  vault primary server secret key loaded701vm-test-run-index_advisor> server # [  110.903852] postgres[1361]:  2026-10-08 02:22:16.344 UTC [1361] LOG:  starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit702vm-test-run-index_advisor> server # [  110.930344] postgres[1361]:  2026-10-08 02:22:16.375 UTC [1361] LOG:  listening on IPv4 address "0.0.0.0", port 5432703vm-test-run-index_advisor> server # [  110.939009] postgres[1361]:  2026-10-08 02:22:16.376 UTC [1361] LOG:  listening on IPv6 address "::", port 5432704vm-test-run-index_advisor> server # [  110.950625] postgres[1361]:  2026-10-08 02:22:16.397 UTC [1361] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"705vm-test-run-index_advisor> server # [  111.095124] postgres[1398]:  2026-10-08 02:22:16.521 UTC [1398] LOG:  database system was shut down at 2026-10-08 02:22:07 UTC706vm-test-run-index_advisor> server # [  111.648299] postgres[1410]:  2026-10-08 02:22:17.074 UTC [1410] FATAL:  role "postgres" does not exist707vm-test-run-index_advisor> server # [  111.654063] postgres[1361]:  2026-10-08 02:22:17.084 UTC [1361] LOG:  database system is ready to accept connections708vm-test-run-index_advisor> server # [  111.742706] postgres[1409]:  2026-10-08 02:22:17.170 UTC [1409] LOG:  TimescaleDB background worker launcher connected to shared catalogs709vm-test-run-index_advisor> server # [  111.755052] systemd[1]: Started PostgreSQL Database Server.710vm-test-run-index_advisor> server # [  111.807217] systemd[1]: Starting Supabase Database Initialization...711vm-test-run-index_advisor> server # [  112.098670] postgres[1361]:  2026-10-08 02:22:17.518 UTC [1361] LOG:  background worker "pg_net 0.20.4 worker" (PID 1410) exited with exit code 1712vm-test-run-index_advisor> server # [  112.435022] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Waiting for PostgreSQL to be ready...713vm-test-run-index_advisor> server # [  112.483861] postgres[1413]:  2026-10-08 02:22:17.906 UTC [1413] LOG:  pg_cron scheduler started714vm-test-run-index_advisor> server # [  113.099295] postgres[1433]: ::1 2026-10-08 02:22:18.535 UTC [1433] root@root FATAL:  role "root" does not exist715vm-test-run-index_advisor> server # [  113.136378] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: PostgreSQL is ready716vm-test-run-index_advisor> server # [  113.137311] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Creating postgres role...717vm-test-run-index_advisor> server # [  113.219475] postgres[1434]:  2026-10-08 02:22:18.641 UTC [1434] FATAL:  role "postgres" does not exist718vm-test-run-index_advisor> server # [  113.281048] postgres[1361]:  2026-10-08 02:22:18.722 UTC [1361] LOG:  background worker "pg_net 0.20.4 worker" (PID 1434) exited with exit code 1719vm-test-run-index_advisor> server # [  113.632581] postgres[1439]: ::1 2026-10-08 02:22:19.070 UTC [1439] supabase_admin@postgres LOG:  statement: CREATE ROLE postgres SUPERUSER LOGIN;720vm-test-run-index_advisor> server # [  113.685223] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1435]: CREATE ROLE721vm-test-run-index_advisor> server # [  114.053358] postgres[1442]: ::1 2026-10-08 02:22:19.495 UTC [1442] supabase_admin@postgres LOG:  statement: ALTER DATABASE postgres OWNER TO postgres;722vm-test-run-index_advisor> server # [  114.079055] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1441]: ALTER DATABASE723vm-test-run-index_advisor> server # [  114.169688] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000000-initial-schema.sql724vm-test-run-index_advisor> server # [  114.470751] postgres[1445]: ::1 2026-10-08 02:22:19.913 UTC [1445] postgres@postgres LOG:  statement: create publication supabase_realtime;725vm-test-run-index_advisor> server # [  114.495928] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE PUBLICATION726vm-test-run-index_advisor> server # [  114.499149] postgres[1445]: ::1 2026-10-08 02:22:19.945 UTC [1445] postgres@postgres LOG:  statement: alter user  supabase_admin with superuser createdb createrole replication bypassrls;727vm-test-run-index_advisor> server # [  114.507004] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER ROLE728vm-test-run-index_advisor> server # [  114.511979] postgres[1445]: ::1 2026-10-08 02:22:19.958 UTC [1445] postgres@postgres LOG:  statement: create user supabase_replication_admin with login replication;729vm-test-run-index_advisor> server # [  114.522200] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE730vm-test-run-index_advisor> server # [  114.523548] postgres[1445]: ::1 2026-10-08 02:22:19.969 UTC [1445] postgres@postgres LOG:  statement: create user supabase_etl_admin with login replication bypassrls;731vm-test-run-index_advisor> server # [  114.535815] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE732vm-test-run-index_advisor> server # [  114.539215] postgres[1445]: ::1 2026-10-08 02:22:19.981 UTC [1445] postgres@postgres LOG:  statement: grant pg_read_all_data to supabase_etl_admin;733vm-test-run-index_advisor> server # [  114.546782] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT ROLE734vm-test-run-index_advisor> server # [  114.556305] postgres[1445]: ::1 2026-10-08 02:22:19.996 UTC [1445] postgres@postgres LOG:  statement: grant create on database postgres to supabase_etl_admin;735vm-test-run-index_advisor> server # [  114.564796] postgres[1445]: ::1 2026-10-08 02:22:20.006 UTC [1445] postgres@postgres LOG:  statement: create role supabase_read_only_user with login bypassrls;736vm-test-run-index_advisor> server # [  114.566489] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT737vm-test-run-index_advisor> server # [  114.567684] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE738vm-test-run-index_advisor> server # [  114.570626] postgres[1445]: ::1 2026-10-08 02:22:20.017 UTC [1445] postgres@postgres LOG:  statement: grant pg_read_all_data to supabase_read_only_user;739vm-test-run-index_advisor> server # [  114.585033] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT ROLE740vm-test-run-index_advisor> server # [  114.587218] postgres[1445]: ::1 2026-10-08 02:22:20.030 UTC [1445] postgres@postgres LOG:  statement: create schema if not exists extensions;741vm-test-run-index_advisor> server # [  114.606708] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE SCHEMA742vm-test-run-index_advisor> server # [  114.607840] postgres[1445]: ::1 2026-10-08 02:22:20.053 UTC [1445] postgres@postgres LOG:  statement: create extension if not exists "uuid-ossp"      with schema extensions;743vm-test-run-index_advisor> server # [  117.642324] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE EXTENSION744vm-test-run-index_advisor> server # [  117.654810] postgres[1445]: ::1 2026-10-08 02:22:23.060 UTC [1445] postgres@postgres LOG:  statement: create extension if not exists pgcrypto         with schema extensions;745vm-test-run-index_advisor> server # [  118.838313] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE EXTENSION746vm-test-run-index_advisor> server # [  118.840262] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE747vm-test-run-index_advisor> server # [  118.866516] postgres[1445]: ::1 2026-10-08 02:22:24.273 UTC [1445] postgres@postgres LOG:  statement: create role anon                nologin noinherit;748vm-test-run-index_advisor> server # [  118.868429] postgres[1445]: ::1 2026-10-08 02:22:24.279 UTC [1445] postgres@postgres LOG:  statement: create role authenticated       nologin noinherit;749vm-test-run-index_advisor> server # [  118.875764] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE750vm-test-run-index_advisor> server # [  118.876377] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE751vm-test-run-index_advisor> server # [  118.876728] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: CREATE ROLE752vm-test-run-index_advisor> server # [  118.883780] postgres[1445]: ::1 2026-10-08 02:22:24.303 UTC [1445] postgres@postgres LOG:  statement: create role service_role        nologin noinherit bypassrls;753vm-test-run-index_advisor> server # [  118.889453] postgres[1445]: ::1 2026-10-08 02:22:24.313 UTC [1445] postgres@postgres LOG:  statement: create user authenticator noinherit;754vm-test-run-index_advisor> server # [  118.891232] postgres[1445]: ::1 2026-10-08 02:22:24.322 UTC [1445] postgres@postgres LOG:  statement: grant anon              to authenticator;755vm-test-run-index_advisor> server # [  118.902687] postgres[1445]: ::1 2026-10-08 02:22:24.330 UTC [1445] postgres@postgres LOG:  statement: grant authenticated     to authenticator;756vm-test-run-index_advisor> server # [  118.903869] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT ROLE757vm-test-run-index_advisor> server # [  118.909711] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT ROLE758vm-test-run-index_advisor> server # [  118.911089] postgres[1445]: ::1 2026-10-08 02:22:24.350 UTC [1445] postgres@postgres LOG:  statement: grant service_role      to authenticator;759vm-test-run-index_advisor> server # [  118.918784] postgres[1445]: ::1 2026-10-08 02:22:24.363 UTC [1445] postgres@postgres LOG:  statement: grant supabase_admin    to authenticator;760vm-test-run-index_advisor> server # [  118.936004] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT ROLE761vm-test-run-index_advisor> server # [  118.955914] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT ROLE762vm-test-run-index_advisor> server # [  118.966474] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT763vm-test-run-index_advisor> server # [  118.967527] postgres[1445]: ::1 2026-10-08 02:22:24.385 UTC [1445] postgres@postgres LOG:  statement: grant usage                     on schema public to postgres, anon, authenticated, service_role;764vm-test-run-index_advisor> server # [  118.977227] postgres[1445]: ::1 2026-10-08 02:22:24.404 UTC [1445] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;765vm-test-run-index_advisor> server # [  119.009447] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER DEFAULT PRIVILEGES766vm-test-run-index_advisor> server # [  119.012558] postgres[1445]: ::1 2026-10-08 02:22:24.459 UTC [1445] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;767vm-test-run-index_advisor> server # [  119.045996] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER DEFAULT PRIVILEGES768vm-test-run-index_advisor> server # [  119.046996] postgres[1445]: ::1 2026-10-08 02:22:24.494 UTC [1445] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;769vm-test-run-index_advisor> server # [  119.055638] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER DEFAULT PRIVILEGES770vm-test-run-index_advisor> server # [  119.058285] postgres[1445]: ::1 2026-10-08 02:22:24.504 UTC [1445] postgres@postgres LOG:  statement: grant usage                     on schema extensions to postgres, anon, authenticated, service_role;771vm-test-run-index_advisor> server # [  119.074404] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: GRANT772vm-test-run-index_advisor> server # [  119.075947] postgres[1445]: ::1 2026-10-08 02:22:24.522 UTC [1445] postgres@postgres LOG:  statement: alter user supabase_admin SET search_path TO public, extensions;773vm-test-run-index_advisor> server # [  119.087987] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER ROLE774vm-test-run-index_advisor> server # [  119.089613] postgres[1445]: ::1 2026-10-08 02:22:24.536 UTC [1445] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all775vm-test-run-index_advisor> server # [  119.091303] postgres[1445]: 	    on sequences to postgres, anon, authenticated, service_role;776vm-test-run-index_advisor> server # [  119.103818] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER DEFAULT PRIVILEGES777vm-test-run-index_advisor> server # [  119.105335] postgres[1445]: ::1 2026-10-08 02:22:24.551 UTC [1445] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all778vm-test-run-index_advisor> server # [  119.106327] postgres[1445]: 	    on tables to postgres, anon, authenticated, service_role;779vm-test-run-index_advisor> server # [  119.120782] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER DEFAULT PRIVILEGES780vm-test-run-index_advisor> server # [  119.121925] postgres[1445]: ::1 2026-10-08 02:22:24.565 UTC [1445] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all781vm-test-run-index_advisor> server # [  119.129227] postgres[1445]: 	    on functions to postgres, anon, authenticated, service_role;782vm-test-run-index_advisor> server # [  119.137252] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER DEFAULT PRIVILEGES783vm-test-run-index_advisor> server # [  119.139321] postgres[1445]: ::1 2026-10-08 02:22:24.580 UTC [1445] postgres@postgres LOG:  statement: alter role anon set statement_timeout = '3s';784vm-test-run-index_advisor> server # [  119.153251] postgres[1445]: ::1 2026-10-08 02:22:24.598 UTC [1445] postgres@postgres LOG:  statement: alter role authenticated set statement_timeout = '8s';785vm-test-run-index_advisor> server # [  119.154957] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER ROLE786vm-test-run-index_advisor> server # [  119.184099] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1443]: ALTER ROLE787vm-test-run-index_advisor> server # [  119.249595] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000001-auth-schema.sql788vm-test-run-index_advisor> server # [  119.641453] postgres[1499]: ::1 2026-10-08 02:22:25.078 UTC [1499] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;789vm-test-run-index_advisor> server # [  119.691276] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE SCHEMA790vm-test-run-index_advisor> server # [  119.703296] postgres[1499]: ::1 2026-10-08 02:22:25.133 UTC [1499] postgres@postgres LOG:  statement: CREATE TABLE auth.users (791vm-test-run-index_advisor> server # [  119.714156] postgres[1499]: 	    instance_id uuid NULL,792vm-test-run-index_advisor> server # [  119.715307] postgres[1499]: 	    id uuid NOT NULL UNIQUE,793vm-test-run-index_advisor> server # [  119.715623] postgres[1499]: 	    aud varchar(255) NULL,794vm-test-run-index_advisor> server # [  119.726352] postgres[1499]: 	    "role" varchar(255) NULL,795vm-test-run-index_advisor> server # [  119.727300] postgres[1499]: 	    email varchar(255) NULL UNIQUE,796vm-test-run-index_advisor> server # [  119.727903] postgres[1499]: 	    encrypted_password varchar(255) NULL,797vm-test-run-index_advisor> server # [  119.735167] postgres[1499]: 	    confirmed_at timestamptz NULL,798vm-test-run-index_advisor> server # [  119.742193] postgres[1499]: 	    invited_at timestamptz NULL,799vm-test-run-index_advisor> server # [  119.742954] postgres[1499]: 	    confirmation_token varchar(255) NULL,800vm-test-run-index_advisor> server # [  119.745243] postgres[1499]: 	    confirmation_sent_at timestamptz NULL,801vm-test-run-index_advisor> server # [  119.755974] postgres[1499]: 	    recovery_token varchar(255) NULL,802vm-test-run-index_advisor> server # [  119.761052] postgres[1499]: 	    recovery_sent_at timestamptz NULL,803vm-test-run-index_advisor> server # [  119.762812] postgres[1499]: 	    email_change_token varchar(255) NULL,804vm-test-run-index_advisor> server # [  119.765674] postgres[1499]: 	    email_change varchar(255) NULL,805vm-test-run-index_advisor> server # [  119.766549] postgres[1499]: 	    email_change_sent_at timestamptz NULL,806vm-test-run-index_advisor> server # [  119.767883] postgres[1499]: 	    last_sign_in_at timestamptz NULL,807vm-test-run-index_advisor> server # [  119.772036] postgres[1499]: 	    raw_app_meta_data jsonb NULL,808vm-test-run-index_advisor> server # [  119.778324] postgres[1499]: 	    raw_user_meta_data jsonb NULL,809vm-test-run-index_advisor> server # [  119.779261] postgres[1499]: 	    is_super_admin bool NULL,810vm-test-run-index_advisor> server # [  119.786416] postgres[1499]: 	    created_at timestamptz NULL,811vm-test-run-index_advisor> server # [  119.790998] postgres[1499]: 	    updated_at timestamptz NULL,812vm-test-run-index_advisor> server # [  119.791424] postgres[1499]: 	    CONSTRAINT users_pkey PRIMARY KEY (id)813vm-test-run-index_advisor> server # [  119.791860] postgres[1499]: 	);814vm-test-run-index_advisor> server # [  119.937938] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE TABLE815vm-test-run-index_advisor> server # [  119.948955] postgres[1499]: ::1 2026-10-08 02:22:25.391 UTC [1499] postgres@postgres LOG:  statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);816vm-test-run-index_advisor> server # [  120.017579] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE INDEX817vm-test-run-index_advisor> server # [  120.019077] postgres[1499]: ::1 2026-10-08 02:22:25.461 UTC [1499] postgres@postgres LOG:  statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);818vm-test-run-index_advisor> server # [  120.062561] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE INDEX819vm-test-run-index_advisor> server # [  120.063927] postgres[1499]: ::1 2026-10-08 02:22:25.507 UTC [1499] postgres@postgres LOG:  statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';820vm-test-run-index_advisor> server # [  120.075400] postgres[1499]: ::1 2026-10-08 02:22:25.521 UTC [1499] postgres@postgres LOG:  statement: CREATE TABLE auth.refresh_tokens (821vm-test-run-index_advisor> server # [  120.079462] postgres[1499]: 	    instance_id uuid NULL,822vm-test-run-index_advisor> server # [  120.083903] postgres[1499]: 	    id bigserial NOT NULL,823vm-test-run-index_advisor> server # [  120.085527] postgres[1499]: 	    "token" varchar(255) NULL,824vm-test-run-index_advisor> server # [  120.086846] postgres[1499]: 	    user_id varchar(255) NULL,825vm-test-run-index_advisor> server # [  120.087493] postgres[1499]: 	    revoked bool NULL,826vm-test-run-index_advisor> server # [  120.087790] postgres[1499]: 	    created_at timestamptz NULL,827vm-test-run-index_advisor> server # [  120.097342] postgres[1499]: 	    updated_at timestamptz NULL,828vm-test-run-index_advisor> server # [  120.098160] postgres[1499]: 	    CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)829vm-test-run-index_advisor> server # [  120.098883] postgres[1499]: 	);830vm-test-run-index_advisor> server # [  120.099398] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: COMMENT831vm-test-run-index_advisor> server # [  120.159703] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE TABLE832vm-test-run-index_advisor> server # [  120.162477] postgres[1499]: ::1 2026-10-08 02:22:25.605 UTC [1499] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);833vm-test-run-index_advisor> server # [  120.197152] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE INDEX834vm-test-run-index_advisor> server # [  120.200623] postgres[1499]: ::1 2026-10-08 02:22:25.646 UTC [1499] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);835vm-test-run-index_advisor> server # [  120.227659] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE INDEX836vm-test-run-index_advisor> server # [  120.230909] postgres[1499]: ::1 2026-10-08 02:22:25.678 UTC [1499] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);837vm-test-run-index_advisor> server # [  120.257841] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE INDEX838vm-test-run-index_advisor> server # [  120.262997] postgres[1499]: ::1 2026-10-08 02:22:25.707 UTC [1499] postgres@postgres LOG:  statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';839vm-test-run-index_advisor> server # [  120.270247] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: COMMENT840vm-test-run-index_advisor> server # [  120.272756] postgres[1499]: ::1 2026-10-08 02:22:25.718 UTC [1499] postgres@postgres LOG:  statement: CREATE TABLE auth.instances (841vm-test-run-index_advisor> server # [  120.273457] postgres[1499]: 	    id uuid NOT NULL,842vm-test-run-index_advisor> server # [  120.274617] postgres[1499]: 	    uuid uuid NULL,843vm-test-run-index_advisor> server # [  120.274989] postgres[1499]: 	    raw_base_config text NULL,844vm-test-run-index_advisor> server # [  120.275278] postgres[1499]: 	    created_at timestamptz NULL,845vm-test-run-index_advisor> server # [  120.280953] postgres[1499]: 	    updated_at timestamptz NULL,846vm-test-run-index_advisor> server # [  120.282238] postgres[1499]: 	    CONSTRAINT instances_pkey PRIMARY KEY (id)847vm-test-run-index_advisor> server # [  120.282766] postgres[1499]: 	);848vm-test-run-index_advisor> server # [  120.333695] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE TABLE849vm-test-run-index_advisor> server # [  120.338238] postgres[1499]: ::1 2026-10-08 02:22:25.782 UTC [1499] postgres@postgres LOG:  statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';850vm-test-run-index_advisor> server # [  120.347776] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: COMMENT851vm-test-run-index_advisor> server # [  120.351097] postgres[1499]: ::1 2026-10-08 02:22:25.798 UTC [1499] postgres@postgres LOG:  statement: CREATE TABLE auth.audit_log_entries (852vm-test-run-index_advisor> server # [  120.354061] postgres[1499]: 	    instance_id uuid NULL,853vm-test-run-index_advisor> server # [  120.357773] postgres[1499]: 	    id uuid NOT NULL,854vm-test-run-index_advisor> server # [  120.358528] postgres[1499]: 	    payload json NULL,855vm-test-run-index_advisor> server # [  120.358944] postgres[1499]: 	    created_at timestamptz NULL,856vm-test-run-index_advisor> server # [  120.359224] postgres[1499]: 	    CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)857vm-test-run-index_advisor> server # [  120.359486] postgres[1499]: 	);858vm-test-run-index_advisor> server # [  120.414800] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE TABLE859vm-test-run-index_advisor> server # [  120.422052] postgres[1499]: ::1 2026-10-08 02:22:25.863 UTC [1499] postgres@postgres LOG:  statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);860vm-test-run-index_advisor> server # [  120.442651] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE INDEX861vm-test-run-index_advisor> server # [  120.449442] postgres[1499]: ::1 2026-10-08 02:22:25.893 UTC [1499] postgres@postgres LOG:  statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';862vm-test-run-index_advisor> server # [  120.461331] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: COMMENT863vm-test-run-index_advisor> server # [  120.462918] postgres[1499]: ::1 2026-10-08 02:22:25.904 UTC [1499] postgres@postgres LOG:  statement: CREATE TABLE auth.schema_migrations (864vm-test-run-index_advisor> server # [  120.463716] postgres[1499]: 	    "version" varchar(255) NOT NULL,865vm-test-run-index_advisor> server # [  120.464633] postgres[1499]: 	    CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")866vm-test-run-index_advisor> server # [  120.465337] postgres[1499]: 	);867vm-test-run-index_advisor> server # [  120.498852] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE TABLE868vm-test-run-index_advisor> server # [  120.500602] postgres[1499]: ::1 2026-10-08 02:22:25.947 UTC [1499] postgres@postgres LOG:  statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';869vm-test-run-index_advisor> server # [  120.515921] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: COMMENT870vm-test-run-index_advisor> server # [  120.536287] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: INSERT 0 7871vm-test-run-index_advisor> server # [  120.539403] postgres[1499]: ::1 2026-10-08 02:22:25.986 UTC [1499] postgres@postgres LOG:  statement: create or replace function auth.uid() returns uuid as $$872vm-test-run-index_advisor> server # [  120.546978] postgres[1499]: 	  select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;873vm-test-run-index_advisor> server # [  120.548005] postgres[1499]: 	$$ language sql stable;874vm-test-run-index_advisor> server # [  120.553370] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE FUNCTION875vm-test-run-index_advisor> server # [  120.556824] postgres[1499]: ::1 2026-10-08 02:22:26.002 UTC [1499] postgres@postgres LOG:  statement: create or replace function auth.role() returns text as $$876vm-test-run-index_advisor> server # [  120.558466] postgres[1499]: 	  select nullif(current_setting('request.jwt.claim.role', true), '')::text;877vm-test-run-index_advisor> server # [  120.559335] postgres[1499]: 	$$ language sql stable;878vm-test-run-index_advisor> server # [  120.570942] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE FUNCTION879vm-test-run-index_advisor> server # [  120.571837] postgres[1499]: ::1 2026-10-08 02:22:26.018 UTC [1499] postgres@postgres LOG:  statement: create or replace function auth.email() returns text as $$880vm-test-run-index_advisor> server # [  120.579598] postgres[1499]: 	  select nullif(current_setting('request.jwt.claim.email', true), '')::text;881vm-test-run-index_advisor> server # [  120.582350] postgres[1499]: 	$$ language sql stable;882vm-test-run-index_advisor> server # [  120.585451] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE FUNCTION883vm-test-run-index_advisor> server # [  120.589577] postgres[1499]: ::1 2026-10-08 02:22:26.034 UTC [1499] postgres@postgres LOG:  statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;884vm-test-run-index_advisor> server # [  120.597402] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: GRANT885vm-test-run-index_advisor> server # [  120.603663] postgres[1499]: ::1 2026-10-08 02:22:26.046 UTC [1499] postgres@postgres LOG:  statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;886vm-test-run-index_advisor> server # [  120.614247] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: CREATE ROLE887vm-test-run-index_advisor> server # [  120.615372] postgres[1499]: ::1 2026-10-08 02:22:26.058 UTC [1499] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;888vm-test-run-index_advisor> server # [  120.619071] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: GRANT889vm-test-run-index_advisor> server # [  120.622554] postgres[1499]: ::1 2026-10-08 02:22:26.069 UTC [1499] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;890vm-test-run-index_advisor> server # [  120.634688] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: GRANT891vm-test-run-index_advisor> server # [  120.655747] postgres[1499]: ::1 2026-10-08 02:22:26.083 UTC [1499] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;892vm-test-run-index_advisor> server # [  120.658737] postgres[1499]: ::1 2026-10-08 02:22:26.092 UTC [1499] postgres@postgres LOG:  statement: ALTER USER supabase_auth_admin SET search_path = "auth";893vm-test-run-index_advisor> server # [  120.659805] postgres[1499]: ::1 2026-10-08 02:22:26.105 UTC [1499] postgres@postgres LOG:  statement: ALTER table "auth".users OWNER TO supabase_auth_admin;894vm-test-run-index_advisor> server # [  120.661085] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: GRANT895vm-test-run-index_advisor> server # [  120.661527] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: ALTER ROLE896vm-test-run-index_advisor> server # [  120.675820] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: ALTER TABLE897vm-test-run-index_advisor> server # [  120.678251] postgres[1499]: ::1 2026-10-08 02:22:26.123 UTC [1499] postgres@postgres LOG:  statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;898vm-test-run-index_advisor> server # [  120.707988] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: ALTER TABLE899vm-test-run-index_advisor> server # [  120.710349] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: ALTER TABLE900vm-test-run-index_advisor> server # [  120.711746] postgres[1499]: ::1 2026-10-08 02:22:26.142 UTC [1499] postgres@postgres LOG:  statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;901vm-test-run-index_advisor> server # [  120.713129] postgres[1499]: ::1 2026-10-08 02:22:26.154 UTC [1499] postgres@postgres LOG:  statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;902vm-test-run-index_advisor> server # [  120.722613] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: ALTER TABLE903vm-test-run-index_advisor> server # [  120.723597] postgres[1499]: ::1 2026-10-08 02:22:26.170 UTC [1499] postgres@postgres LOG:  statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;904vm-test-run-index_advisor> server # [  120.734596] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1496]: ALTER TABLE905vm-test-run-index_advisor> server # [  120.787125] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000002-storage-schema.sql906vm-test-run-index_advisor> server # [  121.087084] postgres[1501]: ::1 2026-10-08 02:22:26.500 UTC [1501] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;907vm-test-run-index_advisor> server # [  121.125257] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1500]: CREATE SCHEMA908vm-test-run-index_advisor> server # [  121.127321] postgres[1501]: ::1 2026-10-08 02:22:26.547 UTC [1501] postgres@postgres LOG:  statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;909vm-test-run-index_advisor> server # [  121.128572] postgres[1501]: ::1 2026-10-08 02:22:26.567 UTC [1501] postgres@postgres LOG:  statement: ALTER USER supabase_storage_admin SET search_path = "storage";910vm-test-run-index_advisor> server # [  121.139897] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1500]: CREATE ROLE911vm-test-run-index_advisor> server # [  121.142919] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1500]: ALTER ROLE912vm-test-run-index_advisor> server # [  121.153017] postgres[1501]: ::1 2026-10-08 02:22:26.576 UTC [1501] postgres@postgres LOG:  statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;913vm-test-run-index_advisor> server # [  121.169542] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1500]: GRANT914vm-test-run-index_advisor> server # [  121.219320] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1500]: DO915vm-test-run-index_advisor> server # [  121.275681] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000003-post-setup.sql916vm-test-run-index_advisor> server # [  121.734984] postgres[1513]: ::1 2026-10-08 02:22:27.149 UTC [1513] postgres@postgres LOG:  statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;917vm-test-run-index_advisor> server # [  121.747961] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: ALTER ROLE918vm-test-run-index_advisor> server # [  121.759120] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: ALTER ROLE919vm-test-run-index_advisor> server # [  121.770984] postgres[1513]: ::1 2026-10-08 02:22:27.184 UTC [1513] postgres@postgres LOG:  statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;920vm-test-run-index_advisor> server # [  121.785111] postgres[1513]: ::1 2026-10-08 02:22:27.194 UTC [1513] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()921vm-test-run-index_advisor> server # [  121.786080] postgres[1513]: 	RETURNS event_trigger922vm-test-run-index_advisor> server # [  121.786505] postgres[1513]: 	LANGUAGE plpgsql923vm-test-run-index_advisor> server # [  121.786766] postgres[1513]: 	AS $$924vm-test-run-index_advisor> server # [  121.787020] postgres[1513]: 	DECLARE925vm-test-run-index_advisor> server # [  121.787275] postgres[1513]: 	  schema_is_cron bool;926vm-test-run-index_advisor> server # [  121.788035] postgres[1513]: 	BEGIN927vm-test-run-index_advisor> server # [  121.797303] postgres[1513]: 	  schema_is_cron = (928vm-test-run-index_advisor> server # [  121.798602] postgres[1513]: 	    SELECT n.nspname = 'cron'929vm-test-run-index_advisor> server # [  121.798991] postgres[1513]: 	    FROM pg_event_trigger_ddl_commands() AS ev930vm-test-run-index_advisor> server # [  121.799444] postgres[1513]: 	    LEFT JOIN pg_catalog.pg_namespace AS n931vm-test-run-index_advisor> server # [  121.799953] postgres[1513]: 	      ON ev.objid = n.oid932vm-test-run-index_advisor> server # [  121.800256] postgres[1513]: 	  );933vm-test-run-index_advisor> server # [  121.810356] postgres[1513]:934vm-test-run-index_advisor> server # [  121.811637] postgres[1513]: 	  IF schema_is_cron935vm-test-run-index_advisor> server # [  121.817953] postgres[1513]: 	  THEN936vm-test-run-index_advisor> server # [  121.818469] postgres[1513]: 	    grant usage on schema cron to postgres with grant option;937vm-test-run-index_advisor> server # [  121.818906] postgres[1513]:938vm-test-run-index_advisor> server # [  121.819182] postgres[1513]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;939vm-test-run-index_advisor> server # [  121.820003] postgres[1513]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;940vm-test-run-index_advisor> server # [  121.825677] postgres[1513]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;941vm-test-run-index_advisor> server # [  121.827084] postgres[1513]:942vm-test-run-index_advisor> server # [  121.827497] postgres[1513]: 	    alter default privileges for user supabase_admin in schema cron grant all943vm-test-run-index_advisor> server # [  121.827865] postgres[1513]: 	        on sequences to postgres with grant option;944vm-test-run-index_advisor> server # [  121.831851] postgres[1513]: 	    alter default privileges for user supabase_admin in schema cron grant all945vm-test-run-index_advisor> server # [  121.844891] postgres[1513]: 	        on tables to postgres with grant option;946vm-test-run-index_advisor> server # [  121.852945] postgres[1513]: 	    alter default privileges for user supabase_admin in schema cron grant all947vm-test-run-index_advisor> server # [  121.860241] postgres[1513]: 	        on functions to postgres with grant option;948vm-test-run-index_advisor> server # [  121.861811] postgres[1513]:949vm-test-run-index_advisor> server # [  121.862327] postgres[1513]: 	    grant all privileges on all tables in schema cron to postgres with grant option;950vm-test-run-index_advisor> server # [  121.863063] postgres[1513]:951vm-test-run-index_advisor> server # [  121.863390] postgres[1513]: 	  END IF;952vm-test-run-index_advisor> server # [  121.864860] postgres[1513]:953vm-test-run-index_advisor> server # [  121.865358] postgres[1513]: 	END;954vm-test-run-index_advisor> server # [  121.865842] postgres[1513]: 	$$;955vm-test-run-index_advisor> server # [  121.866872] postgres[1513]: ::1 2026-10-08 02:22:27.223 UTC [1513] postgres@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')956vm-test-run-index_advisor> server # [  121.868057] postgres[1513]: 	EXECUTE PROCEDURE extensions.grant_pg_cron_access();957vm-test-run-index_advisor> server # [  121.873527] postgres[1513]: ::1 2026-10-08 02:22:27.247 UTC [1513] postgres@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';958vm-test-run-index_advisor> server # [  121.874625] postgres[1513]: ::1 2026-10-08 02:22:27.265 UTC [1513] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()959vm-test-run-index_advisor> server # [  121.877758] postgres[1513]: 	RETURNS event_trigger960vm-test-run-index_advisor> server # [  121.878899] postgres[1513]: 	LANGUAGE plpgsql961vm-test-run-index_advisor> server # [  121.879786] postgres[1513]: 	AS $$962vm-test-run-index_advisor> server # [  121.886104] postgres[1513]: 	BEGIN963vm-test-run-index_advisor> server # [  121.886584] postgres[1513]: 	  IF EXISTS (964vm-test-run-index_advisor> server # [  121.887318] postgres[1513]: 	    SELECT 1965vm-test-run-index_advisor> server # [  121.889118] postgres[1513]: 	    FROM pg_event_trigger_ddl_commands() AS ev966vm-test-run-index_advisor> server # [  121.889588] postgres[1513]: 	    JOIN pg_extension AS ext967vm-test-run-index_advisor> server # [  121.890365] postgres[1513]: 	    ON ev.objid = ext.oid968vm-test-run-index_advisor> server # [  121.890940] postgres[1513]: 	    WHERE ext.extname = 'pg_net'969vm-test-run-index_advisor> server # [  121.891830] postgres[1513]: 	  )970vm-test-run-index_advisor> server # [  121.893712] postgres[1513]: 	  THEN971vm-test-run-index_advisor> server # [  121.894626] postgres[1513]: 	    IF NOT EXISTS (972vm-test-run-index_advisor> server # [  121.895033] postgres[1513]: 	      SELECT 1973vm-test-run-index_advisor> server # [  121.895454] postgres[1513]: 	      FROM pg_roles974vm-test-run-index_advisor> server # [  121.911060] postgres[1513]: 	      WHERE rolname = 'supabase_functions_admin'975vm-test-run-index_advisor> server # [  121.911972] postgres[1513]: 	    )976vm-test-run-index_advisor> server # [  121.913171] postgres[1513]: 	    THEN977vm-test-run-index_advisor> server # [  121.913727] postgres[1513]: 	      CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;978vm-test-run-index_advisor> server # [  121.914785] postgres[1513]: 	    END IF;979vm-test-run-index_advisor> server # [  121.915511] postgres[1513]:980vm-test-run-index_advisor> server # [  121.916186] postgres[1513]: 	    GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;981vm-test-run-index_advisor> server # [  121.934297] postgres[1513]:982vm-test-run-index_advisor> server # [  121.935384] postgres[1513]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;983vm-test-run-index_advisor> server # [  121.973290] postgres[1513]: 	    ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;984vm-test-run-index_advisor> server # [  121.974664] postgres[1513]:985vm-test-run-index_advisor> server # [  121.975182] postgres[1513]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;986vm-test-run-index_advisor> server # [  121.975667] postgres[1513]: 	    ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;987vm-test-run-index_advisor> server # [  121.976664] postgres[1513]:988vm-test-run-index_advisor> server # [  121.977166] postgres[1513]: 	    REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;989vm-test-run-index_advisor> server # [  121.977511] postgres[1513]: 	    REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;990vm-test-run-index_advisor> server # [  121.977813] postgres[1513]:991vm-test-run-index_advisor> server # [  121.978205] postgres[1513]: 	    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;992vm-test-run-index_advisor> server # [  121.979036] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: CREATE FUNCTION993vm-test-run-index_advisor> server # [  121.979857] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: CREATE EVENT TRIGGER994vm-test-run-index_advisor> server # [  121.986688] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: COMMENT995vm-test-run-index_advisor> server # [  121.997104] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: CREATE FUNCTION996vm-test-run-index_advisor> server # [  121.999536] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: COMMENT997vm-test-run-index_advisor> server # [  122.000110] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: DO998vm-test-run-index_advisor> server # [  122.005174] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: CREATE ROLE999vm-test-run-index_advisor> server # [  122.006324] postgres[1513]: 	    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;1000vm-test-run-index_advisor> server # [  122.028942] postgres[1513]: 	  END IF;1001vm-test-run-index_advisor> server # [  122.029809] postgres[1513]: 	END;1002vm-test-run-index_advisor> server # [  122.030202] postgres[1513]: 	$$;1003vm-test-run-index_advisor> server # [  122.030442] postgres[1513]: ::1 2026-10-08 02:22:27.296 UTC [1513] postgres@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1004vm-test-run-index_advisor> server # [  122.031731] postgres[1513]: ::1 2026-10-08 02:22:27.410 UTC [1513] postgres@postgres LOG:  statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1005vm-test-run-index_advisor> server # [  122.054291] postgres[1513]: ::1 2026-10-08 02:22:27.429 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1006vm-test-run-index_advisor> server # [  122.055103] postgres[1513]: ::1 2026-10-08 02:22:27.450 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1007vm-test-run-index_advisor> server # [  122.062644] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1008vm-test-run-index_advisor> server # [  122.069201] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1009vm-test-run-index_advisor> server # [  122.070012] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1010vm-test-run-index_advisor> server # [  122.070408] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1011vm-test-run-index_advisor> server # [  122.071392] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1012vm-test-run-index_advisor> server # [  122.086565] postgres[1513]: ::1 2026-10-08 02:22:27.459 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1013vm-test-run-index_advisor> server # [  122.088541] postgres[1513]: ::1 2026-10-08 02:22:27.469 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1014vm-test-run-index_advisor> server # [  122.090205] postgres[1513]: ::1 2026-10-08 02:22:27.493 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1015vm-test-run-index_advisor> server # [  122.092909] postgres[1513]: ::1 2026-10-08 02:22:27.501 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1016vm-test-run-index_advisor> server # [  122.100883] postgres[1513]: ::1 2026-10-08 02:22:27.517 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1017vm-test-run-index_advisor> server # [  122.102370] postgres[1513]: ::1 2026-10-08 02:22:27.526 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1018vm-test-run-index_advisor> server # [  122.103335] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1019vm-test-run-index_advisor> server # [  122.103888] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1020vm-test-run-index_advisor> server # [  122.174040] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1021vm-test-run-index_advisor> server # [  122.181522] postgres[1513]: ::1 2026-10-08 02:22:27.624 UTC [1513] postgres@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1022vm-test-run-index_advisor> server # [  122.252177] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: GRANT1023vm-test-run-index_advisor> server # [  122.283152] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1509]: DO1024vm-test-run-index_advisor> server # [  122.353297] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running pgbouncer auth schema...1025vm-test-run-index_advisor> server # [  122.723457] postgres[1529]: ::1 2026-10-08 02:22:28.167 UTC [1529] postgres@postgres LOG:  statement: CREATE USER pgbouncer;1026vm-test-run-index_advisor> server # [  122.740973] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1526]: CREATE ROLE1027vm-test-run-index_advisor> server # [  122.748006] postgres[1529]: ::1 2026-10-08 02:22:28.194 UTC [1529] postgres@postgres LOG:  statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1028vm-test-run-index_advisor> server # [  122.759067] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1526]: REVOKE1029vm-test-run-index_advisor> server # [  122.759954] postgres[1529]: ::1 2026-10-08 02:22:28.207 UTC [1529] postgres@postgres LOG:  statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1030vm-test-run-index_advisor> server # [  122.810332] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1526]: CREATE SCHEMA1031vm-test-run-index_advisor> server # [  122.821061] postgres[1529]: ::1 2026-10-08 02:22:28.264 UTC [1529] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1032vm-test-run-index_advisor> server # [  122.822357] postgres[1529]: 	RETURNS TABLE(username TEXT, password TEXT) AS1033vm-test-run-index_advisor> server # [  122.822869] postgres[1529]: 	$$1034vm-test-run-index_advisor> server # [  122.823742] postgres[1529]: 	BEGIN1035vm-test-run-index_advisor> server # [  122.824243] postgres[1529]: 	    RAISE WARNING 'PgBouncer auth request: %', p_usename;1036vm-test-run-index_advisor> server # [  122.833771] postgres[1529]:1037vm-test-run-index_advisor> server # [  122.834462] postgres[1529]: 	    RETURN QUERY1038vm-test-run-index_advisor> server # [  122.834945] postgres[1529]: 	    SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1039vm-test-run-index_advisor> server # [  122.842461] postgres[1529]: 	    WHERE usename = p_usename;1040vm-test-run-index_advisor> server # [  122.844186] postgres[1529]: 	END;1041vm-test-run-index_advisor> server # [  122.849435] postgres[1529]: 	$$ LANGUAGE plpgsql1042vm-test-run-index_advisor> server # [  122.850008] postgres[1529]: 	SET search_path = ''1043vm-test-run-index_advisor> server # [  122.850725] postgres[1529]: 	SECURITY DEFINER;1044vm-test-run-index_advisor> server # [  122.856758] postgres[1529]: ::1 2026-10-08 02:22:28.294 UTC [1529] postgres@postgres LOG:  statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1045vm-test-run-index_advisor> server # [  122.863499] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1526]: CREATE FUNCTION1046vm-test-run-index_advisor> server # [  122.867353] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1526]: REVOKE1047vm-test-run-index_advisor> server # [  122.873055] postgres[1529]: ::1 2026-10-08 02:22:28.306 UTC [1529] postgres@postgres LOG:  statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1048vm-test-run-index_advisor> server # [  122.881252] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1526]: GRANT1049vm-test-run-index_advisor> server # [  122.905044] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running stat extension...1050vm-test-run-index_advisor> server # [  123.159599] postgres[1531]: ::1 2026-10-08 02:22:28.591 UTC [1531] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT exists extensions;1051vm-test-run-index_advisor> server # [  123.183537] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1530]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE:  schema "extensions" already exists, skipping1052vm-test-run-index_advisor> server # [  123.215336] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1530]: CREATE SCHEMA1053vm-test-run-index_advisor> server # [  123.226088] postgres[1531]: ::1 2026-10-08 02:22:28.658 UTC [1531] postgres@postgres LOG:  statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1054vm-test-run-index_advisor> server # [  124.559677] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1530]: CREATE EXTENSION1055vm-test-run-index_advisor> server # [  124.635824] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/10000000000000_demote-postgres.sql1056vm-test-run-index_advisor> server # [  125.011152] postgres[1551]: ::1 2026-10-08 02:22:30.443 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON DATABASE postgres TO postgres;1057vm-test-run-index_advisor> server # [  125.029511] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1058vm-test-run-index_advisor> server # [  125.032355] postgres[1551]: ::1 2026-10-08 02:22:30.472 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON SCHEMA auth TO postgres;1059vm-test-run-index_advisor> server # [  125.050630] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1060vm-test-run-index_advisor> server # [  125.058953] postgres[1551]: ::1 2026-10-08 02:22:30.501 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON SCHEMA extensions TO postgres;1061vm-test-run-index_advisor> server # [  125.077430] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1062vm-test-run-index_advisor> server # [  125.079349] postgres[1551]: ::1 2026-10-08 02:22:30.521 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1063vm-test-run-index_advisor> server # [  125.095474] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1064vm-test-run-index_advisor> server # [  125.105815] postgres[1551]: ::1 2026-10-08 02:22:30.548 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1065vm-test-run-index_advisor> server # [  125.126147] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1066vm-test-run-index_advisor> server # [  125.152823] postgres[1551]: ::1 2026-10-08 02:22:30.577 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1067vm-test-run-index_advisor> server # [  125.180658] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1068vm-test-run-index_advisor> server # [  125.187523] postgres[1551]: ::1 2026-10-08 02:22:30.630 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1069vm-test-run-index_advisor> server # [  125.202771] postgres[1551]: ::1 2026-10-08 02:22:30.643 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1070vm-test-run-index_advisor> server # [  125.203771] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1071vm-test-run-index_advisor> server # [  125.232463] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1072vm-test-run-index_advisor> server # [  125.241179] postgres[1551]: ::1 2026-10-08 02:22:30.685 UTC [1551] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1073vm-test-run-index_advisor> server # [  125.290050] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: GRANT1074vm-test-run-index_advisor> server # [  125.361161] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: DO1075vm-test-run-index_advisor> server # [  125.362488] postgres[1551]: ::1 2026-10-08 02:22:30.806 UTC [1551] supabase_admin@postgres LOG:  statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1076vm-test-run-index_advisor> server # [  125.371789] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1544]: ALTER ROLE1077vm-test-run-index_advisor> server # [  125.464914] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211115181400_update-auth-permissions.sql1078vm-test-run-index_advisor> server # [  125.902371] postgres[1563]: ::1 2026-10-08 02:22:31.329 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1079vm-test-run-index_advisor> server # [  125.951264] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1080vm-test-run-index_advisor> server # [  125.963015] postgres[1563]: ::1 2026-10-08 02:22:31.375 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1081vm-test-run-index_advisor> server # [  125.978139] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1082vm-test-run-index_advisor> server # [  125.981361] postgres[1563]: ::1 2026-10-08 02:22:31.427 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1083vm-test-run-index_advisor> server # [  125.992788] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1084vm-test-run-index_advisor> server # [  125.994080] postgres[1563]: ::1 2026-10-08 02:22:31.440 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1085vm-test-run-index_advisor> server # [  126.000816] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER TABLE1086vm-test-run-index_advisor> server # [  126.006611] postgres[1563]: ::1 2026-10-08 02:22:31.452 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1087vm-test-run-index_advisor> server # [  126.016128] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER TABLE1088vm-test-run-index_advisor> server # [  126.018918] postgres[1563]: ::1 2026-10-08 02:22:31.466 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1089vm-test-run-index_advisor> server # [  126.027867] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER TABLE1090vm-test-run-index_advisor> server # [  126.029691] postgres[1563]: ::1 2026-10-08 02:22:31.475 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1091vm-test-run-index_advisor> server # [  126.035365] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER TABLE1092vm-test-run-index_advisor> server # [  126.036222] postgres[1563]: ::1 2026-10-08 02:22:31.483 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1093vm-test-run-index_advisor> server # [  126.044906] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER TABLE1094vm-test-run-index_advisor> server # [  126.048090] postgres[1563]: ::1 2026-10-08 02:22:31.494 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT USAGE ON SCHEMA auth TO postgres;1095vm-test-run-index_advisor> server # [  126.059376] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1096vm-test-run-index_advisor> server # [  126.063696] postgres[1563]: ::1 2026-10-08 02:22:31.507 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1097vm-test-run-index_advisor> server # [  126.069293] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1098vm-test-run-index_advisor> server # [  126.070854] postgres[1563]: ::1 2026-10-08 02:22:31.518 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1099vm-test-run-index_advisor> server # [  126.081416] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1100vm-test-run-index_advisor> server # [  126.082344] postgres[1563]: ::1 2026-10-08 02:22:31.529 UTC [1563] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1101vm-test-run-index_advisor> server # [  126.097101] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: GRANT1102vm-test-run-index_advisor> server # [  126.098269] postgres[1563]: ::1 2026-10-08 02:22:31.541 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1103vm-test-run-index_advisor> server # [  126.110306] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER DEFAULT PRIVILEGES1104vm-test-run-index_advisor> server # [  126.119113] postgres[1563]: ::1 2026-10-08 02:22:31.560 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1105vm-test-run-index_advisor> server # [  126.123448] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER DEFAULT PRIVILEGES1106vm-test-run-index_advisor> server # [  126.127785] postgres[1563]: ::1 2026-10-08 02:22:31.572 UTC [1563] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1107vm-test-run-index_advisor> server # [  126.136102] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1562]: ALTER DEFAULT PRIVILEGES1108vm-test-run-index_advisor> server # [  126.211849] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211118015519_create-realtime-schema.sql1109vm-test-run-index_advisor> server # [  126.458220] postgres[1565]: ::1 2026-10-08 02:22:31.895 UTC [1565] supabase_admin@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS realtime;1110vm-test-run-index_advisor> server # [  126.477288] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1564]: CREATE SCHEMA1111vm-test-run-index_advisor> server # [  126.522957] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1112vm-test-run-index_advisor> server # [  126.776914] postgres[1567]: ::1 2026-10-08 02:22:32.211 UTC [1567] supabase_admin@postgres LOG:  statement: GRANT USAGE ON SCHEMA realtime TO postgres;1113vm-test-run-index_advisor> server # [  126.789733] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1566]: GRANT1114vm-test-run-index_advisor> server # [  126.791435] postgres[1567]: ::1 2026-10-08 02:22:32.227 UTC [1567] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1115vm-test-run-index_advisor> server # [  126.794679] postgres[1567]: ::1 2026-10-08 02:22:32.234 UTC [1567] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1116vm-test-run-index_advisor> server # [  126.795309] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1566]: GRANT1117vm-test-run-index_advisor> server # [  126.795653] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1566]: GRANT1118vm-test-run-index_advisor> server # [  126.797422] postgres[1567]: ::1 2026-10-08 02:22:32.242 UTC [1567] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1119vm-test-run-index_advisor> server # [  126.803300] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1566]: GRANT1120vm-test-run-index_advisor> server # [  126.838607] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211124212715_update-auth-owner.sql1121vm-test-run-index_advisor> server # [  127.266887] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1568]: DO1122vm-test-run-index_advisor> server # [  127.274313] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1568]: DO1123vm-test-run-index_advisor> server # [  127.287946] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1568]: DO1124vm-test-run-index_advisor> server # [  127.345424] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1125vm-test-run-index_advisor> server # [  127.820102] postgres[1578]: ::1 2026-10-08 02:22:33.262 UTC [1578] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1126vm-test-run-index_advisor> server # [  127.843961] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1574]: ALTER DEFAULT PRIVILEGES1127vm-test-run-index_advisor> server # [  127.862470] postgres[1578]: ::1 2026-10-08 02:22:33.295 UTC [1578] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1128vm-test-run-index_advisor> server # [  127.879555] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1574]: ALTER DEFAULT PRIVILEGES1129vm-test-run-index_advisor> server # [  127.894927] postgres[1578]: ::1 2026-10-08 02:22:33.331 UTC [1578] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1130vm-test-run-index_advisor> server # [  127.914266] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1574]: ALTER DEFAULT PRIVILEGES1131vm-test-run-index_advisor> server # [  127.967452] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1132vm-test-run-index_advisor> server # [  128.419084] postgres[1590]: ::1 2026-10-08 02:22:33.837 UTC [1590] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1133vm-test-run-index_advisor> server # [  128.439555] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1583]: ALTER ROLE1134vm-test-run-index_advisor> server # [  128.512959] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1135vm-test-run-index_advisor> server # [  128.931081] postgres[1599]: ::1 2026-10-08 02:22:34.370 UTC [1599] supabase_admin@postgres LOG:  statement: drop event trigger if exists api_restart;1136vm-test-run-index_advisor> server # [  128.939706] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE:  event trigger "api_restart" does not exist, skipping1137vm-test-run-index_advisor> server # [  128.953656] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: DROP EVENT TRIGGER1138vm-test-run-index_advisor> server # [  128.954927] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE:  function extensions.notify_api_restart() does not exist, skipping1139vm-test-run-index_advisor> server # [  128.956173] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: DROP FUNCTION1140vm-test-run-index_advisor> server # [  128.965626] postgres[1599]: ::1 2026-10-08 02:22:34.390 UTC [1599] supabase_admin@postgres LOG:  statement: drop function if exists extensions.notify_api_restart();1141vm-test-run-index_advisor> server # [  128.966910] postgres[1599]: ::1 2026-10-08 02:22:34.405 UTC [1599] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1142vm-test-run-index_advisor> server # [  128.967686] postgres[1599]: 	DECLARE1143vm-test-run-index_advisor> server # [  128.967981] postgres[1599]: 	  cmd record;1144vm-test-run-index_advisor> server # [  128.968368] postgres[1599]: 	BEGIN1145vm-test-run-index_advisor> server # [  128.976629] postgres[1599]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1146vm-test-run-index_advisor> server # [  128.985488] postgres[1599]: 	  LOOP1147vm-test-run-index_advisor> server # [  128.986718] postgres[1599]: 	    IF cmd.command_tag IN (1148vm-test-run-index_advisor> server # [  128.994802] postgres[1599]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1149vm-test-run-index_advisor> server # [  128.995734] postgres[1599]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1150vm-test-run-index_advisor> server # [  129.003333] postgres[1599]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1151vm-test-run-index_advisor> server # [  129.004927] postgres[1599]: 	    , 'CREATE VIEW', 'ALTER VIEW'1152vm-test-run-index_advisor> server # [  129.006005] postgres[1599]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1153vm-test-run-index_advisor> server # [  129.006682] postgres[1599]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1154vm-test-run-index_advisor> server # [  129.007130] postgres[1599]: 	    , 'CREATE TRIGGER'1155vm-test-run-index_advisor> server # [  129.007815] postgres[1599]: 	    , 'CREATE TYPE'1156vm-test-run-index_advisor> server # [  129.008241] postgres[1599]: 	    , 'CREATE RULE'1157vm-test-run-index_advisor> server # [  129.023715] postgres[1599]: 	    , 'COMMENT'1158vm-test-run-index_advisor> server # [  129.038194] postgres[1599]: 	    )1159vm-test-run-index_advisor> server # [  129.038897] postgres[1599]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1160vm-test-run-index_advisor> server # [  129.039592] postgres[1599]: 	    AND cmd.schema_name is distinct from 'pg_temp'1161vm-test-run-index_advisor> server # [  129.050931] postgres[1599]: 	    THEN1162vm-test-run-index_advisor> server # [  129.051342] postgres[1599]: 	      NOTIFY pgrst, 'reload schema';1163vm-test-run-index_advisor> server # [  129.051623] postgres[1599]: 	    END IF;1164vm-test-run-index_advisor> server # [  129.051912] postgres[1599]: 	  END LOOP;1165vm-test-run-index_advisor> server # [  129.052122] postgres[1599]: 	END; $$ LANGUAGE plpgsql;1166vm-test-run-index_advisor> server # [  129.058940] postgres[1599]: ::1 2026-10-08 02:22:34.457 UTC [1599] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1167vm-test-run-index_advisor> server # [  129.067270] postgres[1599]: 	DECLARE1168vm-test-run-index_advisor> server # [  129.067875] postgres[1599]: 	  obj record;1169vm-test-run-index_advisor> server # [  129.073844] postgres[1599]: 	BEGIN1170vm-test-run-index_advisor> server # [  129.074563] postgres[1599]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1171vm-test-run-index_advisor> server # [  129.089335] postgres[1599]: 	  LOOP1172vm-test-run-index_advisor> server # [  129.090731] postgres[1599]: 	    IF obj.object_type IN (1173vm-test-run-index_advisor> server # [  129.091993] postgres[1599]: 	      'schema'1174vm-test-run-index_advisor> server # [  129.098884] postgres[1599]: 	    , 'table'1175vm-test-run-index_advisor> server # [  129.108849] postgres[1599]: 	    , 'foreign table'1176vm-test-run-index_advisor> server # [  129.110142] postgres[1599]: 	    , 'view'1177vm-test-run-index_advisor> server # [  129.111977] postgres[1599]: 	    , 'materialized view'1178vm-test-run-index_advisor> server # [  129.129299] postgres[1599]: 	    , 'function'1179vm-test-run-index_advisor> server # [  129.131113] postgres[1599]: 	    , 'trigger'1180vm-test-run-index_advisor> server # [  129.131686] postgres[1599]: 	    , 'type'1181vm-test-run-index_advisor> server # [  129.132270] postgres[1599]: 	    , 'rule'1182vm-test-run-index_advisor> server # [  129.133004] postgres[1599]: 	    )1183vm-test-run-index_advisor> server # [  129.144965] postgres[1599]: 	    AND obj.is_temporary IS false -- no pg_temp objects1184vm-test-run-index_advisor> server # [  129.148026] postgres[1599]: 	    THEN1185vm-test-run-index_advisor> server # [  129.163938] postgres[1599]: 	      NOTIFY pgrst, 'reload schema';1186vm-test-run-index_advisor> server # [  129.169917] postgres[1599]: 	    END IF;1187vm-test-run-index_advisor> server # [  129.172003] postgres[1599]: 	  END LOOP;1188vm-test-run-index_advisor> server # [  129.182060] postgres[1599]: 	END; $$ LANGUAGE plpgsql;1189vm-test-run-index_advisor> server # [  129.193636] postgres[1599]: ::1 2026-10-08 02:22:34.482 UTC [1599] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1190vm-test-run-index_advisor> server # [  129.200898] postgres[1599]: ::1 2026-10-08 02:22:34.501 UTC [1599] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_ddl_watch1191vm-test-run-index_advisor> server # [  129.211184] postgres[1599]: 	  ON ddl_command_end1192vm-test-run-index_advisor> server # [  129.211756] postgres[1599]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1193vm-test-run-index_advisor> server # [  129.221809] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: CREATE FUNCTION1194vm-test-run-index_advisor> server # [  129.223944] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: CREATE FUNCTION1195vm-test-run-index_advisor> server # [  129.230558] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE:  event trigger "pgrst_ddl_watch" does not exist, skipping1196vm-test-run-index_advisor> server # [  129.243804] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: DROP EVENT TRIGGER1197vm-test-run-index_advisor> server # [  129.251638] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: CREATE EVENT TRIGGER1198vm-test-run-index_advisor> server # [  129.258587] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE:  event trigger "pgrst_drop_watch" does not exist, skipping1199vm-test-run-index_advisor> server # [  129.261651] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: DROP EVENT TRIGGER1200vm-test-run-index_advisor> server # [  129.271669] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1594]: CREATE EVENT TRIGGER1201vm-test-run-index_advisor> server # [  129.274235] postgres[1599]: ::1 2026-10-08 02:22:34.533 UTC [1599] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1202vm-test-run-index_advisor> server # [  129.276722] postgres[1599]: ::1 2026-10-08 02:22:34.554 UTC [1599] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_drop_watch1203vm-test-run-index_advisor> server # [  129.279143] postgres[1599]: 	  ON sql_drop1204vm-test-run-index_advisor> server # [  129.279686] postgres[1599]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1205vm-test-run-index_advisor> server # [  129.280468] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1206vm-test-run-index_advisor> server # [  130.302695] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1603]: DO1207vm-test-run-index_advisor> server # [  130.339272] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql1208vm-test-run-index_advisor> server # [  130.598616] postgres[1607]: ::1 2026-10-08 02:22:36.033 UTC [1607] supabase_admin@postgres LOG:  statement: create schema if not exists graphql_public;1209vm-test-run-index_advisor> server # [  130.639603] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: CREATE SCHEMA1210vm-test-run-index_advisor> server # [  130.643733] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE:  function graphql_public.graphql(text,text,jsonb) does not exist, skipping1211vm-test-run-index_advisor> server # [  130.653542] postgres[1607]: ::1 2026-10-08 02:22:36.084 UTC [1607] supabase_admin@postgres LOG:  statement: drop function if exists graphql_public.graphql(text, text, jsonb);1212vm-test-run-index_advisor> server # [  130.660251] postgres[1607]: ::1 2026-10-08 02:22:36.094 UTC [1607] supabase_admin@postgres LOG:  statement: create or replace function graphql_public.graphql(1213vm-test-run-index_advisor> server # [  130.661642] postgres[1607]: 	    "operationName" text default null,1214vm-test-run-index_advisor> server # [  130.662226] postgres[1607]: 	    query text default null,1215vm-test-run-index_advisor> server # [  130.662506] postgres[1607]: 	    variables jsonb default null,1216vm-test-run-index_advisor> server # [  130.662771] postgres[1607]: 	    extensions jsonb default null1217vm-test-run-index_advisor> server # [  130.663431] postgres[1607]: 	)1218vm-test-run-index_advisor> server # [  130.664003] postgres[1607]: 	    returns jsonb1219vm-test-run-index_advisor> server # [  130.666088] postgres[1607]: 	    language plpgsql1220vm-test-run-index_advisor> server # [  130.667321] postgres[1607]: 	as $$1221vm-test-run-index_advisor> server # [  130.669943] postgres[1607]: 	    DECLARE1222vm-test-run-index_advisor> server # [  130.670764] postgres[1607]: 	        server_version float;1223vm-test-run-index_advisor> server # [  130.671044] postgres[1607]: 	    BEGIN1224vm-test-run-index_advisor> server # [  130.671513] postgres[1607]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1225vm-test-run-index_advisor> server # [  130.673172] postgres[1607]:1226vm-test-run-index_advisor> server # [  130.673625] postgres[1607]: 	        IF server_version >= 14 THEN1227vm-test-run-index_advisor> server # [  130.674251] postgres[1607]: 	            RETURN jsonb_build_object(1228vm-test-run-index_advisor> server # [  130.675581] postgres[1607]: 	                'data', null::jsonb,1229vm-test-run-index_advisor> server # [  130.676828] postgres[1607]: 	                'errors', array['pg_graphql extension is not enabled.']1230vm-test-run-index_advisor> server # [  130.677607] postgres[1607]: 	            );1231vm-test-run-index_advisor> server # [  130.678195] postgres[1607]: 	        ELSE1232vm-test-run-index_advisor> server # [  130.678474] postgres[1607]: 	            RETURN jsonb_build_object(1233vm-test-run-index_advisor> server # [  130.678710] postgres[1607]: 	                'data', null::jsonb,1234vm-test-run-index_advisor> server # [  130.678947] postgres[1607]: 	                'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1235vm-test-run-index_advisor> server # [  130.679249] postgres[1607]: 	            );1236vm-test-run-index_advisor> server # [  130.682552] postgres[1607]: 	        END IF;1237vm-test-run-index_advisor> server # [  130.683887] postgres[1607]: 	    END;1238vm-test-run-index_advisor> server # [  130.684343] postgres[1607]: 	$$;1239vm-test-run-index_advisor> server # [  130.685739] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: DROP FUNCTION1240vm-test-run-index_advisor> server # [  130.686305] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: CREATE FUNCTION1241vm-test-run-index_advisor> server # [  130.687781] postgres[1607]: ::1 2026-10-08 02:22:36.119 UTC [1607] supabase_admin@postgres LOG:  statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1242vm-test-run-index_advisor> server # [  130.697612] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: GRANT1243vm-test-run-index_advisor> server # [  130.698548] postgres[1607]: ::1 2026-10-08 02:22:36.145 UTC [1607] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1244vm-test-run-index_advisor> server # [  130.709945] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1245vm-test-run-index_advisor> server # [  130.711266] postgres[1607]: ::1 2026-10-08 02:22:36.157 UTC [1607] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1246vm-test-run-index_advisor> server # [  130.721046] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1247vm-test-run-index_advisor> server # [  130.722956] postgres[1607]: ::1 2026-10-08 02:22:36.169 UTC [1607] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1248vm-test-run-index_advisor> server # [  130.734302] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1249vm-test-run-index_advisor> server # [  130.735524] postgres[1607]: ::1 2026-10-08 02:22:36.179 UTC [1607] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1250vm-test-run-index_advisor> server # [  130.736065] postgres[1607]: 	    on sequences to postgres, anon, authenticated, service_role;1251vm-test-run-index_advisor> server # [  130.746350] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1252vm-test-run-index_advisor> server # [  130.758265] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1253vm-test-run-index_advisor> server # [  130.758919] postgres[1607]: ::1 2026-10-08 02:22:36.194 UTC [1607] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1254vm-test-run-index_advisor> server # [  130.760508] postgres[1607]: 	    on tables to postgres, anon, authenticated, service_role;1255vm-test-run-index_advisor> server # [  130.760946] postgres[1607]: ::1 2026-10-08 02:22:36.203 UTC [1607] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1256vm-test-run-index_advisor> server # [  130.761964] postgres[1607]: 	    on functions to postgres, anon, authenticated, service_role;1257vm-test-run-index_advisor> server # [  130.769449] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1258vm-test-run-index_advisor> server # [  130.777818] postgres[1607]: ::1 2026-10-08 02:22:36.221 UTC [1607] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1259vm-test-run-index_advisor> server # [  130.779087] postgres[1607]: 	RETURNS event_trigger1260vm-test-run-index_advisor> server # [  130.779621] postgres[1607]: 	LANGUAGE plpgsql1261vm-test-run-index_advisor> server # [  130.779892] postgres[1607]: 	AS $func$1262vm-test-run-index_advisor> server # [  130.780140] postgres[1607]: 	    DECLARE1263vm-test-run-index_advisor> server # [  130.784893] postgres[1607]: 	    func_is_graphql_resolve bool;1264vm-test-run-index_advisor> server # [  130.786241] postgres[1607]: 	    BEGIN1265vm-test-run-index_advisor> server # [  130.787021] postgres[1607]: 	    func_is_graphql_resolve = (1266vm-test-run-index_advisor> server # [  130.787333] postgres[1607]: 	        SELECT n.proname = 'resolve'1267vm-test-run-index_advisor> server # [  130.787596] postgres[1607]: 	        FROM pg_event_trigger_ddl_commands() AS ev1268vm-test-run-index_advisor> server # [  130.787989] postgres[1607]: 	        LEFT JOIN pg_catalog.pg_proc AS n1269vm-test-run-index_advisor> server # [  130.791442] postgres[1607]: 	        ON ev.objid = n.oid1270vm-test-run-index_advisor> server # [  130.795925] postgres[1607]: 	    );1271vm-test-run-index_advisor> server # [  130.797327] postgres[1607]:1272vm-test-run-index_advisor> server # [  130.797990] postgres[1607]: 	    IF func_is_graphql_resolve1273vm-test-run-index_advisor> server # [  130.798271] postgres[1607]: 	    THEN1274vm-test-run-index_advisor> server # [  130.798505] postgres[1607]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1275vm-test-run-index_advisor> server # [  130.799309] postgres[1607]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1276vm-test-run-index_advisor> server # [  130.800455] postgres[1607]:1277vm-test-run-index_advisor> server # [  130.821454] postgres[1607]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1278vm-test-run-index_advisor> server # [  130.833307] postgres[1607]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1279vm-test-run-index_advisor> server # [  130.834854] postgres[1607]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1280vm-test-run-index_advisor> server # [  130.836100] postgres[1607]:1281vm-test-run-index_advisor> server # [  130.836444] postgres[1607]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1282vm-test-run-index_advisor> server # [  130.846769] postgres[1607]: 	        create or replace function graphql_public.graphql(1283vm-test-run-index_advisor> server # [  130.847582] postgres[1607]: 	            "operationName" text default null,1284vm-test-run-index_advisor> server # [  130.848071] postgres[1607]: 	            query text default null,1285vm-test-run-index_advisor> server # [  130.849530] postgres[1607]: 	            variables jsonb default null,1286vm-test-run-index_advisor> server # [  130.850885] postgres[1607]: 	            extensions jsonb default null1287vm-test-run-index_advisor> server # [  130.858068] postgres[1607]: 	        )1288vm-test-run-index_advisor> server # [  130.863587] postgres[1607]: 	            returns jsonb1289vm-test-run-index_advisor> server # [  130.875047] postgres[1607]: 	            language sql1290vm-test-run-index_advisor> server # [  130.882206] postgres[1607]: 	        as $$1291vm-test-run-index_advisor> server # [  130.883950] postgres[1607]: 	            SELECT graphql.resolve(query, coalesce(variables, '{}'));1292vm-test-run-index_advisor> server # [  130.893700] postgres[1607]: 	        $$;1293vm-test-run-index_advisor> server # [  130.894467] postgres[1607]:1294vm-test-run-index_advisor> server # [  130.894973] postgres[1607]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1295vm-test-run-index_advisor> server # [  130.895453] postgres[1607]: 	    END IF;1296vm-test-run-index_advisor> server # [  130.895763] postgres[1607]:1297vm-test-run-index_advisor> server # [  130.896078] postgres[1607]: 	    END;1298vm-test-run-index_advisor> server # [  130.897352] postgres[1607]: 	$func$;1299vm-test-run-index_advisor> server # [  130.898684] postgres[1607]: ::1 2026-10-08 02:22:36.242 UTC [1607] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1300vm-test-run-index_advisor> server # [  130.899584] postgres[1607]: ::1 2026-10-08 02:22:36.261 UTC [1607] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1301vm-test-run-index_advisor> server # [  130.900241] postgres[1607]: 	EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1302vm-test-run-index_advisor> server # [  130.909468] postgres[1607]: ::1 2026-10-08 02:22:36.287 UTC [1607] supabase_admin@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1303vm-test-run-index_advisor> server # [  130.910500] postgres[1607]: ::1 2026-10-08 02:22:36.325 UTC [1607] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1304vm-test-run-index_advisor> server # [  130.910909] postgres[1607]: 	RETURNS event_trigger1305vm-test-run-index_advisor> server # [  130.911175] postgres[1607]: 	LANGUAGE plpgsql1306vm-test-run-index_advisor> server # [  130.911401] postgres[1607]: 	AS $func$1307vm-test-run-index_advisor> server # [  130.911844] postgres[1607]: 	    DECLARE1308vm-test-run-index_advisor> server # [  130.912210] postgres[1607]: 	    graphql_is_dropped bool;1309vm-test-run-index_advisor> server # [  130.918309] postgres[1607]: 	    BEGIN1310vm-test-run-index_advisor> server # [  130.919314] postgres[1607]: 	    graphql_is_dropped = (1311vm-test-run-index_advisor> server # [  130.919876] postgres[1607]: 	        SELECT ev.schema_name = 'graphql_public'1312vm-test-run-index_advisor> server # [  130.924050] postgres[1607]: 	        FROM pg_event_trigger_dropped_objects() AS ev1313vm-test-run-index_advisor> server # [  130.925247] postgres[1607]: 	        WHERE ev.schema_name = 'graphql_public'1314vm-test-run-index_advisor> server # [  130.926217] postgres[1607]: 	    );1315vm-test-run-index_advisor> server # [  130.927935] postgres[1607]:1316vm-test-run-index_advisor> server # [  130.941685] postgres[1607]: 	    IF graphql_is_dropped1317vm-test-run-index_advisor> server # [  130.942385] postgres[1607]: 	    THEN1318vm-test-run-index_advisor> server # [  130.942705] postgres[1607]: 	        create or replace function graphql_public.graphql(1319vm-test-run-index_advisor> server # [  130.943007] postgres[1607]: 	            "operationName" text default null,1320vm-test-run-index_advisor> server # [  130.943277] postgres[1607]: 	            query text default null,1321vm-test-run-index_advisor> server # [  130.952737] postgres[1607]: 	            variables jsonb default null,1322vm-test-run-index_advisor> server # [  130.953555] postgres[1607]: 	            extensions jsonb default null1323vm-test-run-index_advisor> server # [  130.954103] postgres[1607]: 	        )1324vm-test-run-index_advisor> server # [  130.954381] postgres[1607]: 	            returns jsonb1325vm-test-run-index_advisor> server # [  130.954616] postgres[1607]: 	            language plpgsql1326vm-test-run-index_advisor> server # [  130.955406] postgres[1607]: 	        as $$1327vm-test-run-index_advisor> server # [  130.955738] postgres[1607]: 	            DECLARE1328vm-test-run-index_advisor> server # [  130.968909] postgres[1607]: 	                server_version float;1329vm-test-run-index_advisor> server # [  130.970845] postgres[1607]: 	            BEGIN1330vm-test-run-index_advisor> server # [  130.990233] postgres[1607]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1331vm-test-run-index_advisor> server # [  131.003352] postgres[1607]:1332vm-test-run-index_advisor> server # [  131.006945] postgres[1607]: 	                IF server_version >= 14 THEN1333vm-test-run-index_advisor> server # [  131.020189] postgres[1607]: 	                    RETURN jsonb_build_object(1334vm-test-run-index_advisor> server # [  131.042033] postgres[1607]: 	                        'data', null::jsonb,1335vm-test-run-index_advisor> server # [  131.043705] postgres[1607]: 	                        'errors', array['pg_graphql extension is not enabled.']1336vm-test-run-index_advisor> server # [  131.044075] postgres[1607]: 	                    );1337vm-test-run-index_advisor> server # [  131.044336] postgres[1607]: 	                ELSE1338vm-test-run-index_advisor> server # [  131.054373] postgres[1607]: 	                    RETURN jsonb_build_object(1339vm-test-run-index_advisor> server # [  131.056043] postgres[1607]: 	                        'data', null::jsonb,1340vm-test-run-index_advisor> server # [  131.062556] postgres[1607]: 	                        'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1341vm-test-run-index_advisor> server # [  131.063462] postgres[1607]: 	                    );1342vm-test-run-index_advisor> server # [  131.063791] postgres[1607]: 	                END IF;1343vm-test-run-index_advisor> server # [  131.064074] postgres[1607]: 	            END;1344vm-test-run-index_advisor> server # [  131.079599] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: CREATE FUNCTION1345vm-test-run-index_advisor> server # [  131.084055] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE:  event trigger "issue_pg_graphql_access" does not exist, skipping1346vm-test-run-index_advisor> server # [  131.097731] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: DROP EVENT TRIGGER1347vm-test-run-index_advisor> server # [  131.098666] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: CREATE EVENT TRIGGER1348vm-test-run-index_advisor> server # [  131.099028] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: COMMENT1349vm-test-run-index_advisor> server # [  131.108895] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: CREATE FUNCTION1350vm-test-run-index_advisor> server # [  131.120786] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE:  event trigger "issue_graphql_placeholder" does not exist, skipping1351vm-test-run-index_advisor> server # [  131.123894] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: DROP EVENT TRIGGER1352vm-test-run-index_advisor> server # [  131.127515] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: CREATE EVENT TRIGGER1353vm-test-run-index_advisor> server # [  131.132653] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1606]: COMMENT1354vm-test-run-index_advisor> server # [  131.148247] postgres[1607]: 	        $$;1355vm-test-run-index_advisor> server # [  131.154114] postgres[1607]: 	    END IF;1356vm-test-run-index_advisor> server # [  131.154669] postgres[1607]:1357vm-test-run-index_advisor> server # [  131.155029] postgres[1607]: 	    END;1358vm-test-run-index_advisor> server # [  131.155255] postgres[1607]: 	$func$;1359vm-test-run-index_advisor> server # [  131.156190] postgres[1607]: ::1 2026-10-08 02:22:36.382 UTC [1607] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1360vm-test-run-index_advisor> server # [  131.163026] postgres[1607]: ::1 2026-10-08 02:22:36.389 UTC [1607] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1361vm-test-run-index_advisor> server # [  131.167807] postgres[1607]: 	EXECUTE PROCEDURE extensions.set_graphql_placeholder();1362vm-test-run-index_advisor> server # [  131.174807] postgres[1607]: ::1 2026-10-08 02:22:36.400 UTC [1607] supabase_admin@postgres LOG:  statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1363vm-test-run-index_advisor> server # [  131.183231] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1364vm-test-run-index_advisor> server # [  131.503584] postgres[1621]: ::1 2026-10-08 02:22:36.942 UTC [1621] supabase_admin@postgres LOG:  statement: drop event trigger if exists api_restart;1365vm-test-run-index_advisor> server # [  131.513934] postgres[1621]: ::1 2026-10-08 02:22:36.949 UTC [1621] supabase_admin@postgres LOG:  statement: drop function if exists extensions.notify_api_restart();1366vm-test-run-index_advisor> server # [  131.551342] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE:  event trigger "api_restart" does not exist, skipping1367vm-test-run-index_advisor> server # [  131.557434] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: DROP EVENT TRIGGER1368vm-test-run-index_advisor> server # [  131.558779] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE:  function extensions.notify_api_restart() does not exist, skipping1369vm-test-run-index_advisor> server # [  131.560044] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: DROP FUNCTION1370vm-test-run-index_advisor> server # [  131.568949] postgres[1621]: ::1 2026-10-08 02:22:36.967 UTC [1621] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1371vm-test-run-index_advisor> server # [  131.570314] postgres[1621]: 	DECLARE1372vm-test-run-index_advisor> server # [  131.570965] postgres[1621]: 	  cmd record;1373vm-test-run-index_advisor> server # [  131.571305] postgres[1621]: 	BEGIN1374vm-test-run-index_advisor> server # [  131.571556] postgres[1621]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1375vm-test-run-index_advisor> server # [  131.571820] postgres[1621]: 	  LOOP1376vm-test-run-index_advisor> server # [  131.572053] postgres[1621]: 	    IF cmd.command_tag IN (1377vm-test-run-index_advisor> server # [  131.572283] postgres[1621]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1378vm-test-run-index_advisor> server # [  131.577996] postgres[1621]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1379vm-test-run-index_advisor> server # [  131.582906] postgres[1621]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1380vm-test-run-index_advisor> server # [  131.583679] postgres[1621]: 	    , 'CREATE VIEW', 'ALTER VIEW'1381vm-test-run-index_advisor> server # [  131.590860] postgres[1621]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1382vm-test-run-index_advisor> server # [  131.591419] postgres[1621]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1383vm-test-run-index_advisor> server # [  131.592051] postgres[1621]: 	    , 'CREATE TRIGGER'1384vm-test-run-index_advisor> server # [  131.595416] postgres[1621]: 	    , 'CREATE TYPE', 'ALTER TYPE'1385vm-test-run-index_advisor> server # [  131.601945] postgres[1621]: 	    , 'CREATE RULE'1386vm-test-run-index_advisor> server # [  131.603282] postgres[1621]: 	    , 'COMMENT'1387vm-test-run-index_advisor> server # [  131.606675] postgres[1621]: 	    )1388vm-test-run-index_advisor> server # [  131.611956] postgres[1621]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1389vm-test-run-index_advisor> server # [  131.619626] postgres[1621]: 	    AND cmd.schema_name is distinct from 'pg_temp'1390vm-test-run-index_advisor> server # [  131.624903] postgres[1621]: 	    THEN1391vm-test-run-index_advisor> server # [  131.625400] postgres[1621]: 	      NOTIFY pgrst, 'reload schema';1392vm-test-run-index_advisor> server # [  131.625674] postgres[1621]: 	    END IF;1393vm-test-run-index_advisor> server # [  131.626250] postgres[1621]: 	  END LOOP;1394vm-test-run-index_advisor> server # [  131.627016] postgres[1621]: 	END; $$ LANGUAGE plpgsql;1395vm-test-run-index_advisor> server # [  131.628135] postgres[1621]: ::1 2026-10-08 02:22:37.066 UTC [1621] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1396vm-test-run-index_advisor> server # [  131.637168] postgres[1621]: 	DECLARE1397vm-test-run-index_advisor> server # [  131.640944] postgres[1621]: 	  obj record;1398vm-test-run-index_advisor> server # [  131.641503] postgres[1621]: 	BEGIN1399vm-test-run-index_advisor> server # [  131.642516] postgres[1621]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1400vm-test-run-index_advisor> server # [  131.643735] postgres[1621]: 	  LOOP1401vm-test-run-index_advisor> server # [  131.653820] postgres[1621]: 	    IF obj.object_type IN (1402vm-test-run-index_advisor> server # [  131.657639] postgres[1621]: 	      'schema'1403vm-test-run-index_advisor> server # [  131.658167] postgres[1621]: 	    , 'table'1404vm-test-run-index_advisor> server # [  131.658684] postgres[1621]: 	    , 'foreign table'1405vm-test-run-index_advisor> server # [  131.659008] postgres[1621]: 	    , 'view'1406vm-test-run-index_advisor> server # [  131.659468] postgres[1621]: 	    , 'materialized view'1407vm-test-run-index_advisor> server # [  131.672958] postgres[1621]: 	    , 'function'1408vm-test-run-index_advisor> server # [  131.675291] postgres[1621]: 	    , 'trigger'1409vm-test-run-index_advisor> server # [  131.681338] postgres[1621]: 	    , 'type'1410vm-test-run-index_advisor> server # [  131.689559] postgres[1621]: 	    , 'rule'1411vm-test-run-index_advisor> server # [  131.694192] postgres[1621]: 	    )1412vm-test-run-index_advisor> server # [  131.694512] postgres[1621]: 	    AND obj.is_temporary IS false -- no pg_temp objects1413vm-test-run-index_advisor> server # [  131.694787] postgres[1621]: 	    THEN1414vm-test-run-index_advisor> server # [  131.695017] postgres[1621]: 	      NOTIFY pgrst, 'reload schema';1415vm-test-run-index_advisor> server # [  131.695257] postgres[1621]: 	    END IF;1416vm-test-run-index_advisor> server # [  131.695491] postgres[1621]: 	  END LOOP;1417vm-test-run-index_advisor> server # [  131.696108] postgres[1621]: 	END; $$ LANGUAGE plpgsql;1418vm-test-run-index_advisor> server # [  131.707659] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: CREATE FUNCTION1419vm-test-run-index_advisor> server # [  131.718401] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: CREATE FUNCTION1420vm-test-run-index_advisor> server # [  131.719700] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: DROP EVENT TRIGGER1421vm-test-run-index_advisor> server # [  131.735278] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: CREATE EVENT TRIGGER1422vm-test-run-index_advisor> server # [  131.743606] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: DROP EVENT TRIGGER1423vm-test-run-index_advisor> server # [  131.746635] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1614]: CREATE EVENT TRIGGER1424vm-test-run-index_advisor> server # [  131.795943] postgres[1621]: ::1 2026-10-08 02:22:37.104 UTC [1621] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1425vm-test-run-index_advisor> server # [  131.799109] postgres[1621]: ::1 2026-10-08 02:22:37.117 UTC [1621] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_ddl_watch1426vm-test-run-index_advisor> server # [  131.816881] postgres[1621]: 	  ON ddl_command_end1427vm-test-run-index_advisor> server # [  131.827184] postgres[1621]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1428vm-test-run-index_advisor> server # [  131.834406] postgres[1621]: ::1 2026-10-08 02:22:37.133 UTC [1621] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1429vm-test-run-index_advisor> server # [  131.835732] postgres[1621]: ::1 2026-10-08 02:22:37.142 UTC [1621] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_drop_watch1430vm-test-run-index_advisor> server # [  131.856654] postgres[1621]: 	  ON sql_drop1431vm-test-run-index_advisor> server # [  131.858266] postgres[1621]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1432vm-test-run-index_advisor> server # [  131.874924] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1433vm-test-run-index_advisor> server # [  132.211544] postgres[1628]: ::1 2026-10-08 02:22:37.633 UTC [1628] supabase_admin@postgres LOG:  statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1434vm-test-run-index_advisor> server # [  132.223603] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1624]: ALTER ROLE1435vm-test-run-index_advisor> server # [  132.294805] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1436vm-test-run-index_advisor> server # [  132.761462] postgres[1636]: ::1 2026-10-08 02:22:38.193 UTC [1636] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1437vm-test-run-index_advisor> server # [  132.763542] postgres[1636]: 	    returns event_trigger1438vm-test-run-index_advisor> server # [  132.764637] postgres[1636]: 	    language plpgsql1439vm-test-run-index_advisor> server # [  132.764977] postgres[1636]: 	AS $func$1440vm-test-run-index_advisor> server # [  132.765212] postgres[1636]: 	DECLARE1441vm-test-run-index_advisor> server # [  132.765429] postgres[1636]: 	    func_is_graphql_resolve bool;1442vm-test-run-index_advisor> server # [  132.766782] postgres[1636]: 	BEGIN1443vm-test-run-index_advisor> server # [  132.767337] postgres[1636]: 	    func_is_graphql_resolve = (1444vm-test-run-index_advisor> server # [  132.768551] postgres[1636]: 	        SELECT n.proname = 'resolve'1445vm-test-run-index_advisor> server # [  132.769514] postgres[1636]: 	        FROM pg_event_trigger_ddl_commands() AS ev1446vm-test-run-index_advisor> server # [  132.770308] postgres[1636]: 	        LEFT JOIN pg_catalog.pg_proc AS n1447vm-test-run-index_advisor> server # [  132.770657] postgres[1636]: 	        ON ev.objid = n.oid1448vm-test-run-index_advisor> server # [  132.770988] postgres[1636]: 	    );1449vm-test-run-index_advisor> server # [  132.774083] postgres[1636]:1450vm-test-run-index_advisor> server # [  132.775473] postgres[1636]: 	    IF func_is_graphql_resolve1451vm-test-run-index_advisor> server # [  132.776358] postgres[1636]: 	    THEN1452vm-test-run-index_advisor> server # [  132.776699] postgres[1636]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1453vm-test-run-index_advisor> server # [  132.778345] postgres[1636]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1454vm-test-run-index_advisor> server # [  132.779241] postgres[1636]:1455vm-test-run-index_advisor> server # [  132.780100] postgres[1636]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1456vm-test-run-index_advisor> server # [  132.781410] postgres[1636]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1457vm-test-run-index_advisor> server # [  132.782995] postgres[1636]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1458vm-test-run-index_advisor> server # [  132.783502] postgres[1636]:1459vm-test-run-index_advisor> server # [  132.783765] postgres[1636]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1460vm-test-run-index_advisor> server # [  132.785565] postgres[1636]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1461vm-test-run-index_advisor> server # [  132.786092] postgres[1636]: 	        create or replace function graphql_public.graphql(1462vm-test-run-index_advisor> server # [  132.786415] postgres[1636]: 	            "operationName" text default null,1463vm-test-run-index_advisor> server # [  132.786888] postgres[1636]: 	            query text default null,1464vm-test-run-index_advisor> server # [  132.787634] postgres[1636]: 	            variables jsonb default null,1465vm-test-run-index_advisor> server # [  132.789280] postgres[1636]: 	            extensions jsonb default null1466vm-test-run-index_advisor> server # [  132.789994] postgres[1636]: 	        )1467vm-test-run-index_advisor> server # [  132.790381] postgres[1636]: 	            returns jsonb1468vm-test-run-index_advisor> server # [  132.791113] postgres[1636]: 	            language sql1469vm-test-run-index_advisor> server # [  132.791406] postgres[1636]: 	        as $$1470vm-test-run-index_advisor> server # [  132.791667] postgres[1636]: 	            -- This changed1471vm-test-run-index_advisor> server # [  132.792715] postgres[1636]: 	            select graphql.resolve(1472vm-test-run-index_advisor> server # [  132.793257] postgres[1636]: 	                query := query,1473vm-test-run-index_advisor> server # [  132.793624] postgres[1636]: 	                variables := coalesce(variables, '{}'),1474vm-test-run-index_advisor> server # [  132.794426] postgres[1636]: 	                "operationName" := "operationName",1475vm-test-run-index_advisor> server # [  132.795027] postgres[1636]: 	                extensions := extensions1476vm-test-run-index_advisor> server # [  132.795517] postgres[1636]: 	            );1477vm-test-run-index_advisor> server # [  132.795837] postgres[1636]: 	        $$;1478vm-test-run-index_advisor> server # [  132.797262] postgres[1636]:1479vm-test-run-index_advisor> server # [  132.798010] postgres[1636]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1480vm-test-run-index_advisor> server # [  132.799297] postgres[1636]: 	    END IF;1481vm-test-run-index_advisor> server # [  132.799726] postgres[1636]:1482vm-test-run-index_advisor> server # [  132.800044] postgres[1636]: 	END;1483vm-test-run-index_advisor> server # [  132.801688] postgres[1636]: 	$func$;1484vm-test-run-index_advisor> server # [  132.832426] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1632]: CREATE FUNCTION1485vm-test-run-index_advisor> server # [  132.838276] postgres[1636]: ::1 2026-10-08 02:22:38.281 UTC [1636] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1486vm-test-run-index_advisor> server # [  132.839120] postgres[1636]: 	RETURNS event_trigger1487vm-test-run-index_advisor> server # [  132.839458] postgres[1636]: 	LANGUAGE plpgsql1488vm-test-run-index_advisor> server # [  132.839685] postgres[1636]: 	AS $func$1489vm-test-run-index_advisor> server # [  132.845282] postgres[1636]: 	    DECLARE1490vm-test-run-index_advisor> server # [  132.845993] postgres[1636]: 	    graphql_is_dropped bool;1491vm-test-run-index_advisor> server # [  132.846337] postgres[1636]: 	    BEGIN1492vm-test-run-index_advisor> server # [  132.846559] postgres[1636]: 	    graphql_is_dropped = (1493vm-test-run-index_advisor> server # [  132.847312] postgres[1636]: 	        SELECT ev.schema_name = 'graphql_public'1494vm-test-run-index_advisor> server # [  132.847747] postgres[1636]: 	        FROM pg_event_trigger_dropped_objects() AS ev1495vm-test-run-index_advisor> server # [  132.848149] postgres[1636]: 	        WHERE ev.schema_name = 'graphql_public'1496vm-test-run-index_advisor> server # [  132.854365] postgres[1636]: 	    );1497vm-test-run-index_advisor> server # [  132.855143] postgres[1636]:1498vm-test-run-index_advisor> server # [  132.855878] postgres[1636]: 	    IF graphql_is_dropped1499vm-test-run-index_advisor> server # [  132.856341] postgres[1636]: 	    THEN1500vm-test-run-index_advisor> server # [  132.866661] postgres[1636]: 	        create or replace function graphql_public.graphql(1501vm-test-run-index_advisor> server # [  132.867169] postgres[1636]: 	            "operationName" text default null,1502vm-test-run-index_advisor> server # [  132.867887] postgres[1636]: 	            query text default null,1503vm-test-run-index_advisor> server # [  132.873342] postgres[1636]: 	            variables jsonb default null,1504vm-test-run-index_advisor> server # [  132.874026] postgres[1636]: 	            extensions jsonb default null1505vm-test-run-index_advisor> server # [  132.874349] postgres[1636]: 	        )1506vm-test-run-index_advisor> server # [  132.875083] postgres[1636]: 	            returns jsonb1507vm-test-run-index_advisor> server # [  132.875486] postgres[1636]: 	            language plpgsql1508vm-test-run-index_advisor> server # [  132.881518] postgres[1636]: 	        as $$1509vm-test-run-index_advisor> server # [  132.882427] postgres[1636]: 	            DECLARE1510vm-test-run-index_advisor> server # [  132.883280] postgres[1636]: 	                server_version float;1511vm-test-run-index_advisor> server # [  132.883735] postgres[1636]: 	            BEGIN1512vm-test-run-index_advisor> server # [  132.883989] postgres[1636]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1513vm-test-run-index_advisor> server # [  132.888705] postgres[1636]:1514vm-test-run-index_advisor> server # [  132.893928] postgres[1636]: 	                IF server_version >= 14 THEN1515vm-test-run-index_advisor> server # [  132.894697] postgres[1636]: 	                    RETURN jsonb_build_object(1516vm-test-run-index_advisor> server # [  132.895587] postgres[1636]: 	                        'errors', jsonb_build_array(1517vm-test-run-index_advisor> server # [  132.901715] postgres[1636]: 	                            jsonb_build_object(1518vm-test-run-index_advisor> server # [  132.902541] postgres[1636]: 	                                'message', 'pg_graphql extension is not enabled.'1519vm-test-run-index_advisor> server # [  132.903436] postgres[1636]: 	                            )1520vm-test-run-index_advisor> server # [  132.903735] postgres[1636]: 	                        )1521vm-test-run-index_advisor> server # [  132.911538] postgres[1636]: 	                    );1522vm-test-run-index_advisor> server # [  132.913473] postgres[1636]: 	                ELSE1523vm-test-run-index_advisor> server # [  132.914182] postgres[1636]: 	                    RETURN jsonb_build_object(1524vm-test-run-index_advisor> server # [  132.914685] postgres[1636]: 	                        'errors', jsonb_build_array(1525vm-test-run-index_advisor> server # [  132.921445] postgres[1636]: 	                            jsonb_build_object(1526vm-test-run-index_advisor> server # [  132.922199] postgres[1636]: 	                                'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1527vm-test-run-index_advisor> server # [  132.923728] postgres[1636]: 	                            )1528vm-test-run-index_advisor> server # [  132.928567] postgres[1636]: 	                        )1529vm-test-run-index_advisor> server # [  132.929649] postgres[1636]: 	                    );1530vm-test-run-index_advisor> server # [  132.935404] postgres[1636]: 	                END IF;1531vm-test-run-index_advisor> server # [  132.943056] postgres[1636]: 	            END;1532vm-test-run-index_advisor> server # [  132.945538] postgres[1636]: 	        $$;1533vm-test-run-index_advisor> server # [  132.946690] postgres[1636]: 	    END IF;1534vm-test-run-index_advisor> server # [  132.947709] postgres[1636]:1535vm-test-run-index_advisor> server # [  132.949569] postgres[1636]: 	    END;1536vm-test-run-index_advisor> server # [  132.951179] postgres[1636]: 	$func$;1537vm-test-run-index_advisor> server # [  132.958270] postgres[1636]: ::1 2026-10-08 02:22:38.314 UTC [1636] supabase_admin@postgres LOG:  statement: create or replace function graphql_public.graphql(1538vm-test-run-index_advisor> server # [  132.963904] postgres[1636]: 	    "operationName" text default null,1539vm-test-run-index_advisor> server # [  132.972156] postgres[1636]: 	    query text default null,1540vm-test-run-index_advisor> server # [  132.973991] postgres[1636]: 	    variables jsonb default null,1541vm-test-run-index_advisor> server # [  132.974889] postgres[1636]: 	    extensions jsonb default null1542vm-test-run-index_advisor> server # [  132.975213] postgres[1636]: 	)1543vm-test-run-index_advisor> server # [  132.975447] postgres[1636]: 	    returns jsonb1544vm-test-run-index_advisor> server # [  132.975889] postgres[1636]: 	    language plpgsql1545vm-test-run-index_advisor> server # [  132.977635] postgres[1636]: 	as $$1546vm-test-run-index_advisor> server # [  132.981334] postgres[1636]: 	    DECLARE1547vm-test-run-index_advisor> server # [  132.982740] postgres[1636]: 	        server_version float;1548vm-test-run-index_advisor> server # [  132.984113] postgres[1636]: 	    BEGIN1549vm-test-run-index_advisor> server # [  132.986199] postgres[1636]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1550vm-test-run-index_advisor> server # [  132.988114] postgres[1636]:1551vm-test-run-index_advisor> server # [  132.989661] postgres[1636]: 	        IF server_version >= 14 THEN1552vm-test-run-index_advisor> server # [  132.990540] postgres[1636]: 	            RETURN jsonb_build_object(1553vm-test-run-index_advisor> server # [  132.990916] postgres[1636]: 	                'errors', jsonb_build_array(1554vm-test-run-index_advisor> server # [  132.994083] postgres[1636]: 	                    jsonb_build_object(1555vm-test-run-index_advisor> server # [  132.995414] postgres[1636]: 	                        'message', 'pg_graphql extension is not enabled.'1556vm-test-run-index_advisor> server # [  132.997386] postgres[1636]: 	                    )1557vm-test-run-index_advisor> server # [  132.998731] postgres[1636]: 	                )1558vm-test-run-index_advisor> server # [  133.000793] postgres[1636]: 	            );1559vm-test-run-index_advisor> server # [  133.002905] postgres[1636]: 	        ELSE1560vm-test-run-index_advisor> server # [  133.003379] postgres[1636]: 	            RETURN jsonb_build_object(1561vm-test-run-index_advisor> server # [  133.005051] postgres[1636]: 	                'errors', jsonb_build_array(1562vm-test-run-index_advisor> server # [  133.006231] postgres[1636]: 	                    jsonb_build_object(1563vm-test-run-index_advisor> server # [  133.007703] postgres[1636]: 	                        'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1564vm-test-run-index_advisor> server # [  133.010278] postgres[1636]: 	                    )1565vm-test-run-index_advisor> server # [  133.011183] postgres[1636]: 	                )1566vm-test-run-index_advisor> server # [  133.011682] postgres[1636]: 	            );1567vm-test-run-index_advisor> server # [  133.012059] postgres[1636]: 	        END IF;1568vm-test-run-index_advisor> server # [  133.015096] postgres[1636]: 	    END;1569vm-test-run-index_advisor> server # [  133.015436] postgres[1636]: 	$$;1570vm-test-run-index_advisor> server # [  133.016277] postgres[1636]: ::1 2026-10-08 02:22:38.350 UTC [1636] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1571vm-test-run-index_advisor> server # [  133.020943] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1632]: CREATE FUNCTION1572vm-test-run-index_advisor> server # [  133.022781] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1632]: CREATE FUNCTION1573vm-test-run-index_advisor> server # [  133.024773] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1632]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE:  extension "pg_graphql" does not exist, skipping1574vm-test-run-index_advisor> server # [  133.027538] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1632]: DROP EXTENSION1575vm-test-run-index_advisor> server # [  134.712159] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1632]: DO1576vm-test-run-index_advisor> server # [  134.756030] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1577vm-test-run-index_advisor> server # [  135.231030] postgres[1656]: ::1 2026-10-08 02:22:40.668 UTC [1656] supabase_admin@postgres LOG:  statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1578vm-test-run-index_advisor> server # [  135.252231] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1650]: GRANT ROLE1579vm-test-run-index_advisor> server # [  135.297157] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1580vm-test-run-index_advisor> server # [  135.830608] postgres[1669]: ::1 2026-10-08 02:22:41.256 UTC [1669] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1581vm-test-run-index_advisor> server # [  135.841319] postgres[1669]: 	    returns event_trigger1582vm-test-run-index_advisor> server # [  135.842314] postgres[1669]: 	    language plpgsql1583vm-test-run-index_advisor> server # [  135.842780] postgres[1669]: 	AS $func$1584vm-test-run-index_advisor> server # [  135.843183] postgres[1669]: 	DECLARE1585vm-test-run-index_advisor> server # [  135.843621] postgres[1669]: 	    func_is_graphql_resolve bool;1586vm-test-run-index_advisor> server # [  135.844142] postgres[1669]: 	BEGIN1587vm-test-run-index_advisor> server # [  135.852568] postgres[1669]: 	    func_is_graphql_resolve = (1588vm-test-run-index_advisor> server # [  135.854615] postgres[1669]: 	        SELECT n.proname = 'resolve'1589vm-test-run-index_advisor> server # [  135.855408] postgres[1669]: 	        FROM pg_event_trigger_ddl_commands() AS ev1590vm-test-run-index_advisor> server # [  135.874004] postgres[1669]: 	        LEFT JOIN pg_catalog.pg_proc AS n1591vm-test-run-index_advisor> server # [  135.876077] postgres[1669]: 	        ON ev.objid = n.oid1592vm-test-run-index_advisor> server # [  135.881401] postgres[1669]: 	    );1593vm-test-run-index_advisor> server # [  135.881749] postgres[1669]:1594vm-test-run-index_advisor> server # [  135.882083] postgres[1669]: 	    IF func_is_graphql_resolve1595vm-test-run-index_advisor> server # [  135.882340] postgres[1669]: 	    THEN1596vm-test-run-index_advisor> server # [  135.882542] postgres[1669]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1597vm-test-run-index_advisor> server # [  135.882819] postgres[1669]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1598vm-test-run-index_advisor> server # [  135.883068] postgres[1669]: 	        create or replace function graphql_public.graphql(1599vm-test-run-index_advisor> server # [  135.888573] postgres[1669]: 	            "operationName" text default null,1600vm-test-run-index_advisor> server # [  135.889832] postgres[1669]: 	            query text default null,1601vm-test-run-index_advisor> server # [  135.890231] postgres[1669]: 	            variables jsonb default null,1602vm-test-run-index_advisor> server # [  135.890521] postgres[1669]: 	            extensions jsonb default null1603vm-test-run-index_advisor> server # [  135.890781] postgres[1669]: 	        )1604vm-test-run-index_advisor> server # [  135.891008] postgres[1669]: 	            returns jsonb1605vm-test-run-index_advisor> server # [  135.891230] postgres[1669]: 	            language sql1606vm-test-run-index_advisor> server # [  135.891467] postgres[1669]: 	        as $$1607vm-test-run-index_advisor> server # [  135.891687] postgres[1669]: 	            select graphql.resolve(1608vm-test-run-index_advisor> server # [  135.891928] postgres[1669]: 	                query := query,1609vm-test-run-index_advisor> server # [  135.895713] postgres[1669]: 	                variables := coalesce(variables, '{}'),1610vm-test-run-index_advisor> server # [  135.901696] postgres[1669]: 	                "operationName" := "operationName",1611vm-test-run-index_advisor> server # [  135.903815] postgres[1669]: 	                extensions := extensions1612vm-test-run-index_advisor> server # [  135.913566] postgres[1669]: 	            );1613vm-test-run-index_advisor> server # [  135.914139] postgres[1669]: 	        $$;1614vm-test-run-index_advisor> server # [  135.915688] postgres[1669]:1615vm-test-run-index_advisor> server # [  135.916215] postgres[1669]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1616vm-test-run-index_advisor> server # [  135.922280] postgres[1669]: 	        -- function in the extension so we need to grant permissions on existing entities AND1617vm-test-run-index_advisor> server # [  135.923597] postgres[1669]: 	        -- update default permissions to any others that are created after `graphql.resolve`1618vm-test-run-index_advisor> server # [  135.929582] postgres[1669]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1619vm-test-run-index_advisor> server # [  135.930644] postgres[1669]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1620vm-test-run-index_advisor> server # [  135.931946] postgres[1669]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1621vm-test-run-index_advisor> server # [  135.943864] postgres[1669]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1622vm-test-run-index_advisor> server # [  135.953502] postgres[1669]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1623vm-test-run-index_advisor> server # [  135.955124] postgres[1669]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1624vm-test-run-index_advisor> server # [  135.955686] postgres[1669]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1625vm-test-run-index_advisor> server # [  135.956508] postgres[1669]: 	    END IF;1626vm-test-run-index_advisor> server # [  135.965434] postgres[1669]:1627vm-test-run-index_advisor> server # [  135.966293] postgres[1669]: 	END;1628vm-test-run-index_advisor> server # [  135.967015] postgres[1669]: 	$func$;1629vm-test-run-index_advisor> server # [  135.999612] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1662]: CREATE FUNCTION1630vm-test-run-index_advisor> server # [  136.008787] postgres[1669]: ::1 2026-10-08 02:22:41.444 UTC [1669] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1631vm-test-run-index_advisor> server # [  136.075592] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1662]: DROP EXTENSION1632vm-test-run-index_advisor> server # [  137.997974] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1662]: DO1633vm-test-run-index_advisor> server # [  138.053457] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1634vm-test-run-index_advisor> server # [  139.135436] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1685]: DO1635vm-test-run-index_advisor> server # [  139.895679] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1685]: DO1636vm-test-run-index_advisor> server # [  139.959562] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1637vm-test-run-index_advisor> server # [  140.221448] postgres[1701]: ::1 2026-10-08 02:22:45.659 UTC [1701] supabase_admin@postgres LOG:  statement: alter role authenticator set statement_timeout = '8s';1638vm-test-run-index_advisor> server # [  140.238361] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1700]: ALTER ROLE1639vm-test-run-index_advisor> server # [  140.273672] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20221103090837_revoke_admin.sql1640vm-test-run-index_advisor> server # [  140.546841] postgres[1703]: ::1 2026-10-08 02:22:45.989 UTC [1703] supabase_admin@postgres LOG:  statement: revoke supabase_admin from authenticator;1641vm-test-run-index_advisor> server # [  140.593263] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1702]: REVOKE ROLE1642vm-test-run-index_advisor> server # [  140.614772] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1643vm-test-run-index_advisor> server # [  143.076168] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1704]: DO1644vm-test-run-index_advisor> server # [  143.112729] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1645vm-test-run-index_advisor> server # [  143.461576] postgres[1734]: ::1 2026-10-08 02:22:48.902 UTC [1734] supabase_admin@postgres LOG:  statement: grant anon, authenticated, service_role to postgres;1646vm-test-run-index_advisor> server # [  143.522308] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1730]: GRANT ROLE1647vm-test-run-index_advisor> server # [  143.550363] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1648vm-test-run-index_advisor> server # [  143.978266] postgres[1737]: ::1 2026-10-08 02:22:49.405 UTC [1737] supabase_admin@postgres LOG:  statement: grant all privileges on all tables in schema extensions to postgres with grant option;1649vm-test-run-index_advisor> server # [  144.033588] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1736]: GRANT1650vm-test-run-index_advisor> server # [  144.043774] postgres[1737]: ::1 2026-10-08 02:22:49.472 UTC [1737] supabase_admin@postgres LOG:  statement: grant all privileges on all routines in schema extensions to postgres with grant option;1651vm-test-run-index_advisor> server # [  144.134306] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1736]: GRANT1652vm-test-run-index_advisor> server # [  144.153879] postgres[1737]: ::1 2026-10-08 02:22:49.595 UTC [1737] supabase_admin@postgres LOG:  statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1653vm-test-run-index_advisor> server # [  144.163832] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1736]: GRANT1654vm-test-run-index_advisor> server # [  144.169333] postgres[1737]: ::1 2026-10-08 02:22:49.613 UTC [1737] supabase_admin@postgres LOG:  statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1655vm-test-run-index_advisor> server # [  144.209263] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1736]: ALTER DEFAULT PRIVILEGES1656vm-test-run-index_advisor> server # [  144.211575] postgres[1737]: ::1 2026-10-08 02:22:49.654 UTC [1737] supabase_admin@postgres LOG:  statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1657vm-test-run-index_advisor> server # [  144.228279] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1736]: ALTER DEFAULT PRIVILEGES1658vm-test-run-index_advisor> server # [  144.236790] postgres[1737]: ::1 2026-10-08 02:22:49.682 UTC [1737] supabase_admin@postgres LOG:  statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1659vm-test-run-index_advisor> server # [  144.251122] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1736]: ALTER DEFAULT PRIVILEGES1660vm-test-run-index_advisor> server # [  144.327599] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1661vm-test-run-index_advisor> server # [  144.684078] postgres[1749]: ::1 2026-10-08 02:22:50.122 UTC [1749] supabase_admin@postgres LOG:  statement: grant pg_monitor to postgres;1662vm-test-run-index_advisor> server # [  144.719225] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1745]: GRANT ROLE1663vm-test-run-index_advisor> server # [  144.783805] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1664vm-test-run-index_advisor> server # [  145.200186] postgres[1760]: ::1 2026-10-08 02:22:50.623 UTC [1760] supabase_admin@postgres LOG:  statement: grant anon, authenticated, service_role to supabase_storage_admin;1665vm-test-run-index_advisor> server # [  145.237684] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1754]: GRANT ROLE1666vm-test-run-index_advisor> server # [  145.265972] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1667vm-test-run-index_advisor> server # [  145.699150] postgres[1767]: ::1 2026-10-08 02:22:51.133 UTC [1767] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticated inherit;1668vm-test-run-index_advisor> server # [  145.736239] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1765]: ALTER ROLE1669vm-test-run-index_advisor> server # [  145.751533] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1765]: ALTER ROLE1670vm-test-run-index_advisor> server # [  145.752196] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1765]: ALTER ROLE1671vm-test-run-index_advisor> server # [  145.762126] postgres[1767]: ::1 2026-10-08 02:22:51.162 UTC [1767] supabase_admin@postgres LOG:  statement: ALTER ROLE anon inherit;1672vm-test-run-index_advisor> server # [  145.762970] postgres[1767]: ::1 2026-10-08 02:22:51.175 UTC [1767] supabase_admin@postgres LOG:  statement: ALTER ROLE service_role inherit;1673vm-test-run-index_advisor> server # [  145.805456] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1765]: DO1674vm-test-run-index_advisor> server # [  145.871405] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1675vm-test-run-index_advisor> server # [  146.206841] postgres[1769]: ::1 2026-10-08 02:22:51.646 UTC [1769] supabase_admin@postgres LOG:  statement: grant authenticator to supabase_storage_admin;1676vm-test-run-index_advisor> server # [  146.222646] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1768]: GRANT ROLE1677vm-test-run-index_advisor> server # [  146.224954] postgres[1769]: ::1 2026-10-08 02:22:51.668 UTC [1769] supabase_admin@postgres LOG:  statement: revoke anon, authenticated, service_role from supabase_storage_admin;1678vm-test-run-index_advisor> server # [  146.237170] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1768]: REVOKE ROLE1679vm-test-run-index_advisor> server # [  146.277561] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1680vm-test-run-index_advisor> server # [  146.519014] postgres[1771]: ::1 2026-10-08 02:22:51.955 UTC [1771] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1681vm-test-run-index_advisor> server # [  146.521317] postgres[1771]: 	    returns event_trigger1682vm-test-run-index_advisor> server # [  146.522976] postgres[1771]: 	    language plpgsql1683vm-test-run-index_advisor> server # [  146.523456] postgres[1771]: 	AS $func$1684vm-test-run-index_advisor> server # [  146.524264] postgres[1771]: 	DECLARE1685vm-test-run-index_advisor> server # [  146.526849] postgres[1771]: 	    func_is_graphql_resolve bool;1686vm-test-run-index_advisor> server # [  146.528406] postgres[1771]: 	BEGIN1687vm-test-run-index_advisor> server # [  146.530690] postgres[1771]: 	    func_is_graphql_resolve = (1688vm-test-run-index_advisor> server # [  146.531450] postgres[1771]: 	        SELECT n.proname = 'resolve'1689vm-test-run-index_advisor> server # [  146.532810] postgres[1771]: 	        FROM pg_event_trigger_ddl_commands() AS ev1690vm-test-run-index_advisor> server # [  146.534420] postgres[1771]: 	        LEFT JOIN pg_catalog.pg_proc AS n1691vm-test-run-index_advisor> server # [  146.534827] postgres[1771]: 	        ON ev.objid = n.oid1692vm-test-run-index_advisor> server # [  146.535475] postgres[1771]: 	    );1693vm-test-run-index_advisor> server # [  146.537653] postgres[1771]:1694vm-test-run-index_advisor> server # [  146.538660] postgres[1771]: 	    IF func_is_graphql_resolve1695vm-test-run-index_advisor> server # [  146.539384] postgres[1771]: 	    THEN1696vm-test-run-index_advisor> server # [  146.539692] postgres[1771]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1697vm-test-run-index_advisor> server # [  146.547067] postgres[1771]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1698vm-test-run-index_advisor> server # [  146.548268] postgres[1771]: 	        create or replace function graphql_public.graphql(1699vm-test-run-index_advisor> server # [  146.556146] postgres[1771]: 	            "operationName" text default null,1700vm-test-run-index_advisor> server # [  146.557561] postgres[1771]: 	            query text default null,1701vm-test-run-index_advisor> server # [  146.558157] postgres[1771]: 	            variables jsonb default null,1702vm-test-run-index_advisor> server # [  146.558935] postgres[1771]: 	            extensions jsonb default null1703vm-test-run-index_advisor> server # [  146.559344] postgres[1771]: 	        )1704vm-test-run-index_advisor> server # [  146.560000] postgres[1771]: 	            returns jsonb1705vm-test-run-index_advisor> server # [  146.565065] postgres[1771]: 	            language sql1706vm-test-run-index_advisor> server # [  146.569118] postgres[1771]: 	        as $$1707vm-test-run-index_advisor> server # [  146.570970] postgres[1771]: 	            select graphql.resolve(1708vm-test-run-index_advisor> server # [  146.571582] postgres[1771]: 	                query := query,1709vm-test-run-index_advisor> server # [  146.571948] postgres[1771]: 	                variables := coalesce(variables, '{}'),1710vm-test-run-index_advisor> server # [  146.576881] postgres[1771]: 	                "operationName" := "operationName",1711vm-test-run-index_advisor> server # [  146.579194] postgres[1771]: 	                extensions := extensions1712vm-test-run-index_advisor> server # [  146.583067] postgres[1771]: 	            );1713vm-test-run-index_advisor> server # [  146.584675] postgres[1771]: 	        $$;1714vm-test-run-index_advisor> server # [  146.586703] postgres[1771]:1715vm-test-run-index_advisor> server # [  146.587486] postgres[1771]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1716vm-test-run-index_advisor> server # [  146.590035] postgres[1771]: 	        -- function in the extension so we need to grant permissions on existing entities AND1717vm-test-run-index_advisor> server # [  146.590818] postgres[1771]: 	        -- update default permissions to any others that are created after `graphql.resolve`1718vm-test-run-index_advisor> server # [  146.591564] postgres[1771]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1719vm-test-run-index_advisor> server # [  146.591868] postgres[1771]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1720vm-test-run-index_advisor> server # [  146.592627] postgres[1771]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1721vm-test-run-index_advisor> server # [  146.594332] postgres[1771]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1722vm-test-run-index_advisor> server # [  146.595518] postgres[1771]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1723vm-test-run-index_advisor> server # [  146.596748] postgres[1771]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1724vm-test-run-index_advisor> server # [  146.597130] postgres[1771]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1725vm-test-run-index_advisor> server # [  146.601342] postgres[1771]:1726vm-test-run-index_advisor> server # [  146.601812] postgres[1771]: 	        -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1727vm-test-run-index_advisor> server # [  146.602870] postgres[1771]: 	        grant usage on schema graphql_public to postgres with grant option;1728vm-test-run-index_advisor> server # [  146.603800] postgres[1771]: 	        grant usage on schema graphql to postgres with grant option;1729vm-test-run-index_advisor> server # [  146.608229] postgres[1771]: 	    END IF;1730vm-test-run-index_advisor> server # [  146.609980] postgres[1771]:1731vm-test-run-index_advisor> server # [  146.611299] postgres[1771]: 	END;1732vm-test-run-index_advisor> server # [  146.614078] postgres[1771]: 	$func$;1733vm-test-run-index_advisor> server # [  146.630707] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1770]: CREATE FUNCTION1734vm-test-run-index_advisor> server # [  146.637081] postgres[1771]: ::1 2026-10-08 02:22:52.073 UTC [1771] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1735vm-test-run-index_advisor> server # [  146.679596] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1770]: DROP EXTENSION1736vm-test-run-index_advisor> server # [  148.485607] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1770]: DO1737vm-test-run-index_advisor> server # [  148.525655] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1738vm-test-run-index_advisor> server # [  148.852642] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1791]: DO1739vm-test-run-index_advisor> server # [  148.870371] postgres[1797]: ::1 2026-10-08 02:22:54.302 UTC [1797] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1740vm-test-run-index_advisor> server # [  148.874096] postgres[1797]: 	    LANGUAGE plpgsql1741vm-test-run-index_advisor> server # [  148.876046] postgres[1797]: 	    AS $$1742vm-test-run-index_advisor> server # [  148.879093] postgres[1797]: 	BEGIN1743vm-test-run-index_advisor> server # [  148.879773] postgres[1797]: 	  IF EXISTS (1744vm-test-run-index_advisor> server # [  148.880492] postgres[1797]: 	    SELECT1745vm-test-run-index_advisor> server # [  148.881704] postgres[1797]: 	    FROM pg_event_trigger_ddl_commands() AS ev1746vm-test-run-index_advisor> server # [  148.883526] postgres[1797]: 	    JOIN pg_extension AS ext1747vm-test-run-index_advisor> server # [  148.884258] postgres[1797]: 	    ON ev.objid = ext.oid1748vm-test-run-index_advisor> server # [  148.886900] postgres[1797]: 	    WHERE ext.extname = 'pg_cron'1749vm-test-run-index_advisor> server # [  148.887798] postgres[1797]: 	  )1750vm-test-run-index_advisor> server # [  148.888345] postgres[1797]: 	  THEN1751vm-test-run-index_advisor> server # [  148.888799] postgres[1797]: 	    grant usage on schema cron to postgres with grant option;1752vm-test-run-index_advisor> server # [  148.889938] postgres[1797]:1753vm-test-run-index_advisor> server # [  148.890193] postgres[1797]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;1754vm-test-run-index_advisor> server # [  148.890769] postgres[1797]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;1755vm-test-run-index_advisor> server # [  148.891425] postgres[1797]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;1756vm-test-run-index_advisor> server # [  148.891778] postgres[1797]:1757vm-test-run-index_advisor> server # [  148.891999] postgres[1797]: 	    alter default privileges for user supabase_admin in schema cron grant all1758vm-test-run-index_advisor> server # [  148.893316] postgres[1797]: 	        on sequences to postgres with grant option;1759vm-test-run-index_advisor> server # [  148.894422] postgres[1797]: 	    alter default privileges for user supabase_admin in schema cron grant all1760vm-test-run-index_advisor> server # [  148.895893] postgres[1797]: 	        on tables to postgres with grant option;1761vm-test-run-index_advisor> server # [  148.897431] postgres[1797]: 	    alter default privileges for user supabase_admin in schema cron grant all1762vm-test-run-index_advisor> server # [  148.899103] postgres[1797]: 	        on functions to postgres with grant option;1763vm-test-run-index_advisor> server # [  148.899946] postgres[1797]:1764vm-test-run-index_advisor> server # [  148.901587] postgres[1797]: 	    grant all privileges on all tables in schema cron to postgres with grant option;1765vm-test-run-index_advisor> server # [  148.902541] postgres[1797]: 	    revoke all on table cron.job from postgres;1766vm-test-run-index_advisor> server # [  148.906502] postgres[1797]: 	    grant select on table cron.job to postgres with grant option;1767vm-test-run-index_advisor> server # [  148.907131] postgres[1797]: 	  END IF;1768vm-test-run-index_advisor> server # [  148.907386] postgres[1797]: 	END;1769vm-test-run-index_advisor> server # [  148.909786] postgres[1797]: 	$$;1770vm-test-run-index_advisor> server # [  148.953425] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1791]: CREATE FUNCTION1771vm-test-run-index_advisor> server # [  148.962455] postgres[1797]: ::1 2026-10-08 02:22:54.402 UTC [1797] supabase_admin@postgres LOG:  statement: drop event trigger if exists issue_pg_cron_access;1772vm-test-run-index_advisor> server # [  148.970922] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1791]: DROP EVENT TRIGGER1773vm-test-run-index_advisor> server # [  148.978787] postgres[1797]: ::1 2026-10-08 02:22:54.421 UTC [1797] supabase_admin@postgres LOG:  statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1774vm-test-run-index_advisor> server # [  148.992417] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1791]: ALTER FUNCTION1775vm-test-run-index_advisor> server # [  148.996531] postgres[1797]: ::1 2026-10-08 02:22:54.436 UTC [1797] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1776vm-test-run-index_advisor> server # [  149.009582] postgres[1797]: 	         WHEN TAG IN ('CREATE EXTENSION')1777vm-test-run-index_advisor> server # [  149.010322] postgres[1797]: 	   EXECUTE FUNCTION extensions.grant_pg_cron_access();1778vm-test-run-index_advisor> server # [  149.011369] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1791]: CREATE EVENT TRIGGER1779vm-test-run-index_advisor> server # [  149.038541] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1780vm-test-run-index_advisor> server # [  149.421138] postgres[1801]: ::1 2026-10-08 02:22:54.842 UTC [1801] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticator set lock_timeout to '8s';1781vm-test-run-index_advisor> server # [  149.461394] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1798]: ALTER ROLE1782vm-test-run-index_advisor> server # [  149.495119] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1783vm-test-run-index_advisor> server # [  149.879871] postgres[1805]: ::1 2026-10-08 02:22:55.295 UTC [1805] supabase_admin@postgres LOG:  statement: alter function pg_catalog.lo_export owner to supabase_admin;1784vm-test-run-index_advisor> server # [  149.918664] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1804]: ALTER FUNCTION1785vm-test-run-index_advisor> server # [  149.925849] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1804]: ALTER FUNCTION1786vm-test-run-index_advisor> server # [  149.927451] postgres[1805]: ::1 2026-10-08 02:22:55.358 UTC [1805] supabase_admin@postgres LOG:  statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1787vm-test-run-index_advisor> server # [  149.929262] postgres[1805]: ::1 2026-10-08 02:22:55.372 UTC [1805] supabase_admin@postgres LOG:  statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1788vm-test-run-index_advisor> server # [  149.934869] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1804]: ALTER FUNCTION1789vm-test-run-index_advisor> server # [  149.985772] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1790vm-test-run-index_advisor> server # [  150.340699] postgres[1807]: ::1 2026-10-08 02:22:55.782 UTC [1807] supabase_admin@postgres LOG:  statement: grant pg_read_all_data, pg_signal_backend to postgres;1791vm-test-run-index_advisor> server # [  150.357811] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1806]: GRANT ROLE1792vm-test-run-index_advisor> server # [  150.399394] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20241031003909_create_orioledb.sql1793vm-test-run-index_advisor> server # [  151.592118] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1808]: DO1794vm-test-run-index_advisor> server # [  151.631805] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1795vm-test-run-index_advisor> server # [  152.196073] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1830]: DO1796vm-test-run-index_advisor> server # [  152.204200] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1830]: DO1797vm-test-run-index_advisor> server # [  152.237029] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1798vm-test-run-index_advisor> server # [  152.533569] postgres[1837]: ::1 2026-10-08 02:22:57.970 UTC [1837] supabase_admin@postgres LOG:  statement: alter role supabase_admin set log_statement = none;1799vm-test-run-index_advisor> server # [  152.546606] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1836]: ALTER ROLE1800vm-test-run-index_advisor> server # [  152.554044] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1836]: ALTER ROLE1801vm-test-run-index_advisor> server # [  152.554766] postgres[1837]: ::1 2026-10-08 02:22:57.987 UTC [1837] supabase_admin@postgres LOG:  statement: alter role supabase_auth_admin set log_statement = none;1802vm-test-run-index_advisor> server # [  152.557472] postgres[1837]: ::1 2026-10-08 02:22:57.997 UTC [1837] supabase_admin@postgres LOG:  statement: alter role supabase_storage_admin set log_statement = none;1803vm-test-run-index_advisor> server # [  152.562024] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1836]: ALTER ROLE1804vm-test-run-index_advisor> server # [  152.617614] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1805vm-test-run-index_advisor> server # [  152.909761] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1838]: DO1806vm-test-run-index_advisor> server # [  152.947995] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1807vm-test-run-index_advisor> server # [  153.376476] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1840]: DO1808vm-test-run-index_advisor> server # [  153.395553] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1809vm-test-run-index_advisor> server # [  153.979824] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1846]: CREATE FUNCTION1810vm-test-run-index_advisor> server # [  153.998085] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1846]: DO1811vm-test-run-index_advisor> server # [  154.086308] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1812vm-test-run-index_advisor> server # [  154.494457] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1853]: ALTER FUNCTION1813vm-test-run-index_advisor> server # [  154.504404] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1853]: GRANT1814vm-test-run-index_advisor> server # [  154.547886] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1815vm-test-run-index_advisor> server # [  154.895214] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1866]: DROP EVENT TRIGGER1816vm-test-run-index_advisor> server # [  154.931773] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1866]: ALTER FUNCTION1817vm-test-run-index_advisor> server # [  154.949310] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1866]: CREATE EVENT TRIGGER1818vm-test-run-index_advisor> server # [  154.999179] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1819vm-test-run-index_advisor> server # [  155.305566] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1872]: DO1820vm-test-run-index_advisor> server # [  155.354467] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql1821vm-test-run-index_advisor> server # [  155.713709] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1878]: CREATE FUNCTION1822vm-test-run-index_advisor> server # [  155.736951] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1878]: GRANT1823vm-test-run-index_advisor> server # [  155.770250] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql1824vm-test-run-index_advisor> server # [  156.023253] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1880]: REVOKE ROLE1825vm-test-run-index_advisor> server # [  156.065173] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1880]: DO1826vm-test-run-index_advisor> server # [  156.102032] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1880]: DO1827vm-test-run-index_advisor> server # [  156.111458] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1880]: REVOKE ROLE1828vm-test-run-index_advisor> server # [  156.122805] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1880]: REVOKE1829vm-test-run-index_advisor> server # [  156.149711] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1880]: DO1830vm-test-run-index_advisor> server # [  156.183971] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql1831vm-test-run-index_advisor> server # [  156.491326] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1882]: DO1832vm-test-run-index_advisor> server # [  156.547630] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql1833vm-test-run-index_advisor> server # [  157.108550] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1884]: DO1834vm-test-run-index_advisor> server # [  157.161372] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql1835vm-test-run-index_advisor> server # [  157.718985] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1895]: DO1836vm-test-run-index_advisor> server # [  157.777267] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql1837vm-test-run-index_advisor> server # [  158.217816] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1905]: ALTER ROLE1838vm-test-run-index_advisor> server # [  158.233473] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20251001204436_predefined_role_grants.sql1839vm-test-run-index_advisor> server # [  158.582731] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1914]: GRANT ROLE1840vm-test-run-index_advisor> server # [  158.597568] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1914]: DO1841vm-test-run-index_advisor> server # [  158.645496] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql1842vm-test-run-index_advisor> server # [  159.066671] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1916]: GRANT1843vm-test-run-index_advisor> server # [  159.109710] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql1844vm-test-run-index_advisor> server # [  159.563432] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1921]: CREATE FUNCTION1845vm-test-run-index_advisor> server # [  159.585473] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1921]: REVOKE1846vm-test-run-index_advisor> server # [  159.602967] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1921]: REVOKE1847vm-test-run-index_advisor> server # [  159.618921] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1921]: GRANT1848vm-test-run-index_advisor> server # [  159.668063] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260211120934_supabase_privileged_role.sql1849vm-test-run-index_advisor> server # [  160.079696] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1924]: DO1850vm-test-run-index_advisor> server # [  160.128113] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql1851vm-test-run-index_advisor> server # [  160.626762] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1928]: ALTER ROLE1852vm-test-run-index_advisor> server # [  160.670221] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql1853vm-test-run-index_advisor> server # [  161.367950] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1939]: DROP EXTENSION1854vm-test-run-index_advisor> server # [  161.444313] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql1855vm-test-run-index_advisor> server # [  161.893552] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1954]: CREATE FUNCTION1856vm-test-run-index_advisor> server # [  161.913700] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1954]: DROP EVENT TRIGGER1857vm-test-run-index_advisor> server # [  161.918920] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1954]: CREATE EVENT TRIGGER1858vm-test-run-index_advisor> server # [  161.976711] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql1859vm-test-run-index_advisor> server # [  162.248517] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1956]: DO1860vm-test-run-index_advisor> server # [  162.275459] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1956]: CREATE FUNCTION1861vm-test-run-index_advisor> server # [  162.311730] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Running postgresql schema...1862vm-test-run-index_advisor> server # [  162.624073] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER DATABASE1863vm-test-run-index_advisor> server # [  162.633649] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER DATABASE1864vm-test-run-index_advisor> server # [  162.696906] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1865vm-test-run-index_advisor> server # [  162.772440] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1866vm-test-run-index_advisor> server # [  162.882323] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1867vm-test-run-index_advisor> server # [  163.045435] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1868vm-test-run-index_advisor> server # [  163.150449] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1869vm-test-run-index_advisor> server # [  163.241122] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1870vm-test-run-index_advisor> server # [  163.329512] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1871vm-test-run-index_advisor> server # [  163.397503] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1872vm-test-run-index_advisor> server # [  163.525675] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1873vm-test-run-index_advisor> server # [  163.540970] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1958]: ALTER ROLE1874vm-test-run-index_advisor> server # [  163.639649] xrrjh43i9f75as4ybbwpqap65s9n1x8c-supabase-db-init[1419]: Database initialization complete1875vm-test-run-index_advisor> server # [  163.700927] systemd[1]: Finished Supabase Database Initialization.1876vm-test-run-index_advisor> server # [  163.702875] systemd[1]: Reached target Multi-User System.1877vm-test-run-index_advisor> server # [  163.703839] systemd[1]: Startup finished in 18.998s (kernel) + 2min 24.697s (userspace) = 2min 43.695s.1878vm-test-run-index_advisor> server: (finished: waiting for unit supabase-db-init.service, in 166.04 seconds)1879vm-test-run-index_advisor> subtest: Verify PostgreSQL 15 is our custom build1880vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();"1881vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.54 seconds)1882vm-test-run-index_advisor> server: must succeed: readlink -f $(which postgres)1883vm-test-run-index_advisor> server: (finished: must succeed: readlink -f $(which postgres), in 0.65 seconds)1884vm-test-run-index_advisor> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 1.19 seconds)1885vm-test-run-index_advisor> subtest: Verify ansible config loaded1886vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;"1887vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.74 seconds)1888vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;"1889vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.49 seconds)1890vm-test-run-index_advisor> (finished: subtest: Verify ansible config loaded, in 1.23 seconds)1891vm-test-run-index_advisor> subtest: Verify init scripts and migrations ran1892vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;"1893vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;", in 0.53 seconds)1894vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;"1895vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;", in 0.52 seconds)1896vm-test-run-index_advisor> (finished: subtest: Verify init scripts and migrations ran, in 1.05 seconds)1897vm-test-run-index_advisor> subtest: Check upgrade path with postgresql 151898vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1899vm-test-run-index_advisor> server # NOTICE:  extension "index_advisor" does not exist, skipping1900vm-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.72 seconds)1901vm-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;" 1902vm-test-run-index_advisor> server # NOTICE:  installing required extension "hypopg"1903vm-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.70 seconds)1904vm-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';" 1905vm-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.53 seconds)1906vm-test-run-index_advisor> (finished: subtest: Check upgrade path with postgresql 15, in 2.95 seconds)1907vm-test-run-index_advisor> subtest: Check the install of the last version of the extension1908vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1909vm-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.96 seconds)1910vm-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;" 1911vm-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.56 seconds)1912vm-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';" 1913vm-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.48 seconds)1914vm-test-run-index_advisor> (finished: subtest: Check the install of the last version of the extension, in 3.00 seconds)1915vm-test-run-index_advisor> subtest: switch to postgresql 171916vm-test-run-index_advisor> server # Checking switch inhibitors... done1917vm-test-run-index_advisor> server # stopping the following units: [  183.479870] nixos[2102]: switching to system configuration /nix/store/fkrsmyxyfs14c6mmfvwcfsi05bby8694-nixos-system-server-test1918vm-test-run-index_advisor> server # postgresql.service, supabase-db-init.service1919vm-test-run-index_advisor> server # [  183.511748] systemd[1]: Stopped target Local File Systems.1920vm-test-run-index_advisor> server # [  183.541317] systemd[1]: supabase-db-init.service: Deactivated successfully.1921vm-test-run-index_advisor> server # [  183.542854] systemd[1]: Stopped Supabase Database Initialization.1922vm-test-run-index_advisor> server # [  183.543701] systemd[1]: supabase-db-init.service: Consumed 8.068s CPU time, 3.5M memory peak, 87.2K incoming IP traffic, 118.4K outgoing IP traffic.1923vm-test-run-index_advisor> server # [  183.575338] systemd[1]: Stopped target Remote File Systems.1924vm-test-run-index_advisor> server # [  183.597028] postgres[1361]:  2026-10-08 02:23:29.038 UTC [1361] LOG:  received fast shutdown request1925vm-test-run-index_advisor> server # [  183.599349] systemd[1]: Stopping PostgreSQL Database Server...1926vm-test-run-index_advisor> server # [  183.627665] postgres[1361]:  2026-10-08 02:23:29.073 UTC [1361] LOG:  aborting any active transactions1927vm-test-run-index_advisor> server # [  183.678115] postgres[1413]:  2026-10-08 02:23:29.096 UTC [1413] LOG:  pg_cron scheduler shutting down1928vm-test-run-index_advisor> server # [  183.681194] postgres[1409]:  2026-10-08 02:23:29.105 UTC [1409] FATAL:  terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command1929vm-test-run-index_advisor> server # [  183.824169] postgres[1361]:  2026-10-08 02:23:29.270 UTC [1361] LOG:  background worker "TimescaleDB Background Worker Launcher" (PID 1409) exited with exit code 11930vm-test-run-index_advisor> server # [  183.830522] postgres[1361]:  2026-10-08 02:23:29.270 UTC [1361] LOG:  background worker "pg_cron launcher" (PID 1413) exited with exit code 11931vm-test-run-index_advisor> server # [  183.833978] postgres[1361]:  2026-10-08 02:23:29.270 UTC [1361] LOG:  background worker "logical replication launcher" (PID 1415) exited with exit code 11932vm-test-run-index_advisor> server # [  183.834744] postgres[1361]:  2026-10-08 02:23:29.270 UTC [1361] LOG:  background worker "pg_net 0.20.4 worker" (PID 1444) exited with exit code 11933vm-test-run-index_advisor> server # [  183.851482] postgres[1396]:  2026-10-08 02:23:29.291 UTC [1396] LOG:  shutting down1934vm-test-run-index_advisor> server # [  183.858475] postgres[1396]:  2026-10-08 02:23:29.305 UTC [1396] LOG:  checkpoint starting: shutdown immediate1935vm-test-run-index_advisor> server # [  184.268945] postgres[1396]:  2026-10-08 02:23:29.713 UTC [1396] LOG:  checkpoint complete: wrote 285 buffers (1.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.120 s, sync=0.223 s, total=0.420 s; sync files=140, longest=0.015 s, average=0.002 s; distance=2102 kB, estimate=2102 kB1936vm-test-run-index_advisor> server # [  184.494491] postgres[1361]:  2026-10-08 02:23:29.940 UTC [1361] LOG:  database system is shut down1937vm-test-run-index_advisor> server # [  184.691279] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31938vm-test-run-index_advisor> server # [  184.632562] systemd[1]: postgresql.service: Deactivated successfully.1939vm-test-run-index_advisor> server # [  184.648867] systemd[1]: Stopped PostgreSQL Database Server.1940vm-test-run-index_advisor> server # [  184.662513] systemd[1]: postgresql.service: Consumed 38.740s CPU time, 125M memory peak, 54.8M written to disk, 122.4K incoming IP traffic, 91.3K outgoing IP traffic.1941vm-test-run-index_advisor> server # activating the configuration...1942vm-test-run-index_advisor> server # setting up /etc...1943vm-test-run-index_advisor> server # [  192.414483] systemd[1]: Reload requested from client PID 2102 ('.switch-to-conf') (unit backdoor.service)...1944vm-test-run-index_advisor> server # [  192.428983] systemd[1]: Reloading...