nixbot

1these 2 derivations will be built:2  /nix/store/spc99lyqb2lxphcyja0g4nnvvfil2mrf-nixos-test-driver-index_advisor.drv3  /nix/store/vwy22l5ip3hxwcqqwi9zlswrghw8yyab-vm-test-run-index_advisor.drv4building '/nix/store/spc99lyqb2lxphcyja0g4nnvvfil2mrf-nixos-test-driver-index_advisor.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'5building '/nix/store/vwy22l5ip3hxwcqqwi9zlswrghw8yyab-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder'6building '/nix/store/vwy22l5ip3hxwcqqwi9zlswrghw8yyab-vm-test-run-index_advisor.drv'7vm-test-run-index_advisor> Machine state will be reset. To keep it, pass --keep-vm-state8vm-test-run-index_advisor> start all VLans9vm-test-run-index_advisor> start vlan10vm-test-run-index_advisor> running vlan (pid 7; ctl /build/vde1.ctl)11vm-test-run-index_advisor> (finished: start all VLans, in 0.00 seconds)12vm-test-run-index_advisor> Test will time out and terminate in 3600 seconds13vm-test-run-index_advisor> run the VM test script14vm-test-run-index_advisor> additionally exposed symbols:15vm-test-run-index_advisor>     server,16vm-test-run-index_advisor>     vlan1,17vm-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, debug18vm-test-run-index_advisor> start all VMs19vm-test-run-index_advisor> server: starting vm20vm-test-run-index_advisor> mke2fs 1.47.3 (8-Jul-2025)21vm-test-run-index_advisor> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory22vm-test-run-index_advisor> qemu-system-aarch64: failed to initialize kvm: No such file or directory23vm-test-run-index_advisor> qemu-system-aarch64: falling back to tcg24vm-test-run-index_advisor> server # Disk image does not exist, creating the virtualisation disk image...25vm-test-run-index_advisor> server # Formatting '/build/vm-state-server/tmp.e50JqGwDML', fmt=raw size=107374182426vm-test-run-index_advisor> server # Discarding device blocks:      0/262144             done27vm-test-run-index_advisor> server # Creating filesystem with 262144 4k blocks and 65536 inodes28vm-test-run-index_advisor> server # Filesystem UUID: 6e9adac6-1f55-4efa-a7e9-0c83d36a5d9429vm-test-run-index_advisor> server # Superblock backups stored on blocks:30vm-test-run-index_advisor> server # 	32768, 98304, 163840, 22937631vm-test-run-index_advisor> server # 32vm-test-run-index_advisor> server # Allocating group tables: 0/8   done33vm-test-run-index_advisor> server # Writing inode tables: 0/8   done34vm-test-run-index_advisor> server # Creating journal (8192 blocks): done35vm-test-run-index_advisor> server # Writing superblocks and filesystem accounting information: 0/8   done36vm-test-run-index_advisor> server # 37vm-test-run-index_advisor> server # Virtualisation disk image created.38vm-test-run-index_advisor> server: QEMU running (pid 9)39vm-test-run-index_advisor> (finished: start all VMs, in 0.21 seconds)40vm-test-run-index_advisor> server: waiting for unit supabase-db-init.service41vm-test-run-index_advisor> server: waiting for the VM to finish booting42vm-test-run-index_advisor> server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]43vm-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 202544vm-test-run-index_advisor> server # [    0.000000] KASLR enabled45vm-test-run-index_advisor> server # [    0.000000] random: crng init done46vm-test-run-index_advisor> server # [    0.000000] Machine model: linux,dummy-virt47vm-test-run-index_advisor> server # [    0.000000] efi: UEFI not found.48vm-test-run-index_advisor> server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT49vm-test-run-index_advisor> server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]50vm-test-run-index_advisor> server # [    0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]51vm-test-run-index_advisor> server # [    0.000000] Zone ranges:52vm-test-run-index_advisor> server # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]53vm-test-run-index_advisor> server # [    0.000000]   DMA32    empty54vm-test-run-index_advisor> server # [    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]55vm-test-run-index_advisor> server # [    0.000000]   Device   empty56vm-test-run-index_advisor> server # [    0.000000] Movable zone start for each node57vm-test-run-index_advisor> server # [    0.000000] Early memory node ranges58vm-test-run-index_advisor> server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]59vm-test-run-index_advisor> server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]60vm-test-run-index_advisor> server # [    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -161vm-test-run-index_advisor> server # [    0.000000] psci: probing for conduit method from DT.62vm-test-run-index_advisor> server # [    0.000000] psci: PSCIv1.1 detected in firmware.63vm-test-run-index_advisor> server # [    0.000000] psci: Using standard PSCI v0.2 function IDs64vm-test-run-index_advisor> server # [    0.000000] psci: Trusted OS migration not required65vm-test-run-index_advisor> server # [    0.000000] psci: SMC Calling Convention v1.066vm-test-run-index_advisor> server # [    0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u36044867vm-test-run-index_advisor> server # [    0.000000] Detected PIPT I-cache on CPU068vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)69vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: GIC system register CPU interface70vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: HCRX_EL2 register71vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)72vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: Spectre-v473vm-test-run-index_advisor> server # [    0.000000] alternatives: applying boot alternatives74vm-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/r6b5ms8ljsq7h4mh74y3rmsvys24p8lv-nixos-system-server-test/init regInfo=/nix/store/q12apq53nfvjny8wlfj5y7ryi9dabyc1-closure-info/registration console=ttyAMA0,115200n8 console=tty075vm-test-run-index_advisor> server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/q12apq53nfvjny8wlfj5y7ryi9dabyc1-closure-info/registration", will be passed to user space.76vm-test-run-index_advisor> server # [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)77vm-test-run-index_advisor> server # [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)78vm-test-run-index_advisor> server # [    0.000000] Fallback order for Node 0: 079vm-test-run-index_advisor> server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 104857680vm-test-run-index_advisor> server # [    0.000000] Policy zone: Normal81vm-test-run-index_advisor> server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off82vm-test-run-index_advisor> server # [    0.000000] software IO TLB: area num 2.83vm-test-run-index_advisor> server # [    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)84vm-test-run-index_advisor> server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=185vm-test-run-index_advisor> server # [    0.000000] allocated 8388608 bytes of page_ext86vm-test-run-index_advisor> server # [    0.000000] ftrace: allocating 72257 entries in 283 pages87vm-test-run-index_advisor> server # [    0.000000] ftrace: allocated 283 pages with 5 groups88vm-test-run-index_advisor> server # [    0.000000] rcu: Hierarchical RCU implementation.89vm-test-run-index_advisor> server # [    0.000000] rcu: 	RCU event tracing is enabled.90vm-test-run-index_advisor> server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.91vm-test-run-index_advisor> server # [    0.000000] 	Rude variant of Tasks RCU enabled.92vm-test-run-index_advisor> server # [    0.000000] 	Tracing variant of Tasks RCU enabled.93vm-test-run-index_advisor> server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.94vm-test-run-index_advisor> server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=295vm-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.96vm-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.97vm-test-run-index_advisor> server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 098vm-test-run-index_advisor> server # [    0.000000] GICv3: 256 SPIs implemented99vm-test-run-index_advisor> server # [    0.000000] GICv3: 0 Extended SPIs implemented100vm-test-run-index_advisor> server # [    0.000000] Root IRQ handler: gic_handle_irq101vm-test-run-index_advisor> server # [    0.000000] GICv3: GICv3 features: 16 PPIs102vm-test-run-index_advisor> server # [    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0103vm-test-run-index_advisor> server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000104vm-test-run-index_advisor> server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]105vm-test-run-index_advisor> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1)106vm-test-run-index_advisor> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1)107vm-test-run-index_advisor> server # [    0.000000] GICv3: using LPI property table @0x0000000100b00000108vm-test-run-index_advisor> server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000109vm-test-run-index_advisor> server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.110vm-test-run-index_advisor> server # [    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).111vm-test-run-index_advisor> server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns112vm-test-run-index_advisor> server # [    0.000041] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns113vm-test-run-index_advisor> server # [    0.004516] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)114vm-test-run-index_advisor> server # [    0.014710] Console: colour dummy device 80x25115vm-test-run-index_advisor> server # [    0.016217] printk: legacy console [tty0] enabled116vm-test-run-index_advisor> server # [    0.022462] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)117vm-test-run-index_advisor> server # [    0.023222] pid_max: default: 32768 minimum: 301118vm-test-run-index_advisor> server # [    0.025467] LSM: initializing lsm=capability,landlock,yama,bpf119vm-test-run-index_advisor> server # [    0.026968] landlock: Up and running.120vm-test-run-index_advisor> server # [    0.027077] Yama: becoming mindful.121vm-test-run-index_advisor> server # [    0.031597] LSM support for eBPF active122vm-test-run-index_advisor> server # [    0.035789] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)123vm-test-run-index_advisor> server # [    0.035979] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)124vm-test-run-index_advisor> server # [    0.067459] cacheinfo: Unable to detect cache hierarchy for CPU 0125vm-test-run-index_advisor> server # [    0.080886] rcu: Hierarchical SRCU implementation.126vm-test-run-index_advisor> server # [    0.080984] rcu: 	Max phase no-delay instances is 1000.127vm-test-run-index_advisor> server # [    0.083120] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level128vm-test-run-index_advisor> server # [    0.091447] fsl-mc MSI: its@8080000 domain created129vm-test-run-index_advisor> server # [    0.093529] EFI services will not be available.130vm-test-run-index_advisor> server # [    0.095471] smp: Bringing up secondary CPUs ...131vm-test-run-index_advisor> server # [    0.100713] Detected PIPT I-cache on CPU1132vm-test-run-index_advisor> server # [    0.101916] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000133vm-test-run-index_advisor> server # [    0.102664] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000134vm-test-run-index_advisor> server # [    0.104222] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]135vm-test-run-index_advisor> server # [    0.108184] smp: Brought up 1 node, 2 CPUs136vm-test-run-index_advisor> server # [    0.108330] SMP: Total of 2 processors activated.137vm-test-run-index_advisor> server # [    0.108373] CPU: All CPU(s) started at EL1138vm-test-run-index_advisor> server # [    0.108531] CPU features: detected: Branch Target Identification139vm-test-run-index_advisor> server # [    0.108584] CPU features: detected: 32-bit EL0 Support140vm-test-run-index_advisor> server # [    0.108611] CPU features: detected: 32-bit EL1 Support141vm-test-run-index_advisor> server # [    0.108634] CPU features: detected: ARMv8.4 Translation Table Level142vm-test-run-index_advisor> server # [    0.108667] CPU features: detected: Instruction cache invalidation not required for I/D coherence143vm-test-run-index_advisor> server # [    0.108706] CPU features: detected: Data cache clean to the PoU not required for I/D coherence144vm-test-run-index_advisor> server # [    0.108768] CPU features: detected: Common not Private translations145vm-test-run-index_advisor> server # [    0.108791] CPU features: detected: CRC32 instructions146vm-test-run-index_advisor> server # [    0.108812] CPU features: detected: Data cache clean to Point of Deep Persistence147vm-test-run-index_advisor> server # [    0.108838] CPU features: detected: Data cache clean to Point of Persistence148vm-test-run-index_advisor> server # [    0.108861] CPU features: detected: Data independent timing control (DIT)149vm-test-run-index_advisor> server # [    0.108884] CPU features: detected: E0PD150vm-test-run-index_advisor> server # [    0.108902] CPU features: detected: Enhanced Counter Virtualization151vm-test-run-index_advisor> server # [    0.108924] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)152vm-test-run-index_advisor> server # [    0.108948] CPU features: detected: Enhanced Privileged Access Never153vm-test-run-index_advisor> server # [    0.108970] CPU features: detected: Enhanced Virtualization Traps154vm-test-run-index_advisor> server # [    0.108992] CPU features: detected: Fine Grained Traps155vm-test-run-index_advisor> server # [    0.109043] CPU features: detected: Generic authentication (IMP DEF algorithm)156vm-test-run-index_advisor> server # [    0.109118] CPU features: detected: RCpc load-acquire (LDAPR)157vm-test-run-index_advisor> server # [    0.109161] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)158vm-test-run-index_advisor> server # [    0.109185] CPU features: detected: LSE atomic instructions159vm-test-run-index_advisor> server # [    0.109206] CPU features: detected: Memory Copy and Memory Set instructions160vm-test-run-index_advisor> server # [    0.109229] CPU features: detected: Privileged Access Never161vm-test-run-index_advisor> server # [    0.109250] CPU features: detected: RAS Extension Support162vm-test-run-index_advisor> server # [    0.109271] CPU features: detected: Random Number Generator163vm-test-run-index_advisor> server # [    0.109294] CPU features: detected: Speculation barrier (SB)164vm-test-run-index_advisor> server # [    0.109320] CPU features: detected: Stage-2 Force Write-Back165vm-test-run-index_advisor> server # [    0.109344] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality166vm-test-run-index_advisor> server # [    0.109372] CPU features: detected: TLB range maintenance instructions167vm-test-run-index_advisor> server # [    0.109399] CPU features: detected: WFx with timeout168vm-test-run-index_advisor> server # [    0.109445] CPU features: detected: Speculative Store Bypassing Safe (SSBS)169vm-test-run-index_advisor> server # [    0.109477] CPU features: detected: Scalable Vector Extension170vm-test-run-index_advisor> server # [    0.115876] alternatives: applying system-wide alternatives171vm-test-run-index_advisor> server # [    0.134741] CPU features: detected: Hardware dirty bit management on CPU0-1172vm-test-run-index_advisor> server # [    0.135526] SVE: maximum available vector length 256 bytes per vector173vm-test-run-index_advisor> server # [    0.135610] SVE: default vector length 64 bytes per vector174vm-test-run-index_advisor> server # [    0.149224] Memory: 3922832K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved)175vm-test-run-index_advisor> server # [    0.172821] devtmpfs: initialized176vm-test-run-index_advisor> server # [    0.204081] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns177vm-test-run-index_advisor> server # [    0.204584] futex hash table entries: 512 (order: 3, 32768 bytes, linear)178vm-test-run-index_advisor> server # [    0.207266] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL179vm-test-run-index_advisor> server # [    0.207345] 0 pages in range for non-PLT usage180vm-test-run-index_advisor> server # [    0.207390] 509248 pages in range for PLT usage181vm-test-run-index_advisor> server # [    0.209853] pinctrl core: initialized pinctrl subsystem182vm-test-run-index_advisor> server # [    0.221803] DMI not present or invalid.183vm-test-run-index_advisor> server # [    0.244461] NET: Registered PF_NETLINK/PF_ROUTE protocol family184vm-test-run-index_advisor> server # [    0.256968] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations185vm-test-run-index_advisor> server # [    0.258787] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations186vm-test-run-index_advisor> server # [    0.260083] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations187vm-test-run-index_advisor> server # [    0.260351] audit: initializing netlink subsys (disabled)188vm-test-run-index_advisor> server # [    0.262574] audit: type=2000 audit(0.208:1): state=initialized audit_enabled=0 res=1189vm-test-run-index_advisor> server # [    0.268818] thermal_sys: Registered thermal governor 'fair_share'190vm-test-run-index_advisor> server # [    0.268876] thermal_sys: Registered thermal governor 'bang_bang'191vm-test-run-index_advisor> server # [    0.268942] thermal_sys: Registered thermal governor 'step_wise'192vm-test-run-index_advisor> server # [    0.268975] thermal_sys: Registered thermal governor 'user_space'193vm-test-run-index_advisor> server # [    0.269005] thermal_sys: Registered thermal governor 'power_allocator'194vm-test-run-index_advisor> server # [    0.269879] cpuidle: using governor ladder195vm-test-run-index_advisor> server # [    0.270799] cpuidle: using governor menu196vm-test-run-index_advisor> server # [    0.273210] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.197vm-test-run-index_advisor> server # [    0.274390] ASID allocator initialised with 65536 entries198vm-test-run-index_advisor> server # [    0.281993] Serial: AMBA PL011 UART driver199vm-test-run-index_advisor> server # [    0.332951] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1200vm-test-run-index_advisor> server # [    0.335473] printk: legacy console [ttyAMA0] enabled201vm-test-run-index_advisor> server # [    0.373581] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages202vm-test-run-index_advisor> server # [    0.373762] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page203vm-test-run-index_advisor> server # [    0.373869] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages204vm-test-run-index_advisor> server # [    0.373969] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page205vm-test-run-index_advisor> server # [    0.374059] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages206vm-test-run-index_advisor> server # [    0.374155] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page207vm-test-run-index_advisor> server # [    0.374704] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages208vm-test-run-index_advisor> server # [    0.374814] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page209vm-test-run-index_advisor> server # [    0.388492] fbcon: Taking over console210vm-test-run-index_advisor> server # [    0.389263] ACPI: Interpreter disabled.211vm-test-run-index_advisor> server # [    0.396610] iommu: Default domain type: Translated212vm-test-run-index_advisor> server # [    0.396769] iommu: DMA domain TLB invalidation policy: strict mode213vm-test-run-index_advisor> server # [    0.406039] SCSI subsystem initialized214vm-test-run-index_advisor> server # [    0.409292] usbcore: registered new interface driver usbfs215vm-test-run-index_advisor> server # [    0.409649] usbcore: registered new interface driver hub216vm-test-run-index_advisor> server # [    0.409988] usbcore: registered new device driver usb217vm-test-run-index_advisor> server # [    0.412340] pps_core: LinuxPPS API ver. 1 registered218vm-test-run-index_advisor> server # [    0.412456] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>219vm-test-run-index_advisor> server # [    0.413007] PTP clock support registered220vm-test-run-index_advisor> server # [    0.413456] EDAC MC: Ver: 3.0.0221vm-test-run-index_advisor> server # [    0.415365] scmi_core: SCMI protocol bus registered222vm-test-run-index_advisor> server # [    0.420036] FPGA manager framework223vm-test-run-index_advisor> server # [    0.421114] Advanced Linux Sound Architecture Driver Initialized.224vm-test-run-index_advisor> server # [    0.430814] vgaarb: loaded225vm-test-run-index_advisor> server # [    0.435369] clocksource: Switched to clocksource arch_sys_counter226vm-test-run-index_advisor> server # [    0.440898] VFS: Disk quotas dquot_6.6.0227vm-test-run-index_advisor> server # [    0.441199] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)228vm-test-run-index_advisor> server # [    0.442950] netfs: FS-Cache loaded229vm-test-run-index_advisor> server # [    0.445110] pnp: PnP ACPI: disabled230vm-test-run-index_advisor> server # [    0.469930] NET: Registered PF_INET protocol family231vm-test-run-index_advisor> server # [    0.471051] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)232vm-test-run-index_advisor> server # [    0.588368] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)233vm-test-run-index_advisor> server # [    0.588746] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)234vm-test-run-index_advisor> server # [    0.589050] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)235vm-test-run-index_advisor> server # [    0.589907] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)236vm-test-run-index_advisor> server # [    0.590987] TCP: Hash tables configured (established 32768 bind 32768)237vm-test-run-index_advisor> server # [    0.592921] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)238vm-test-run-index_advisor> server # [    0.593419] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)239vm-test-run-index_advisor> server # [    0.594469] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)240vm-test-run-index_advisor> server # [    0.596347] NET: Registered PF_UNIX/PF_LOCAL protocol family241vm-test-run-index_advisor> server # [    0.596828] NET: Registered PF_XDP protocol family242vm-test-run-index_advisor> server # [    0.597354] PCI: CLS 0 bytes, default 64243vm-test-run-index_advisor> server # [    0.599630] kvm [1]: HYP mode not available244vm-test-run-index_advisor> server # [    0.601868] Trying to unpack rootfs image as initramfs...245vm-test-run-index_advisor> server # [    0.709843] Initialise system trusted keyrings246vm-test-run-index_advisor> server # [    0.712088] workingset: timestamp_bits=42 max_order=20 bucket_order=0247vm-test-run-index_advisor> server # [    0.716427] squashfs: version 4.0 (2009/01/31) Phillip Lougher248vm-test-run-index_advisor> server # [    0.718255] 9p: Installing v9fs 9p2000 file system support249vm-test-run-index_advisor> server # [    0.781970] Key type asymmetric registered250vm-test-run-index_advisor> server # [    0.782629] Asymmetric key parser 'x509' registered251vm-test-run-index_advisor> server # [    0.784235] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)252vm-test-run-index_advisor> server # [    0.785715] io scheduler mq-deadline registered253vm-test-run-index_advisor> server # [    0.785881] io scheduler kyber registered254vm-test-run-index_advisor> server # [    0.814439] pl061_gpio 9030000.pl061: PL061 GPIO chip registered255vm-test-run-index_advisor> server # [    0.820364] ledtrig-cpu: registered to indicate activity on CPUs256vm-test-run-index_advisor> server # [    0.824910] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:257vm-test-run-index_advisor> server # [    0.825589] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000258vm-test-run-index_advisor> server # [    0.826834] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000259vm-test-run-index_advisor> server # [    0.828042] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000260vm-test-run-index_advisor> server # [    0.829285] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits261vm-test-run-index_advisor> server # [    0.829840] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]262vm-test-run-index_advisor> server # [    0.833850] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00263vm-test-run-index_advisor> server # [    0.834860] pci_bus 0000:00: root bus resource [bus 00-ff]264vm-test-run-index_advisor> server # [    0.836361] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]265vm-test-run-index_advisor> server # [    0.837054] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]266vm-test-run-index_advisor> server # [    0.837610] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]267vm-test-run-index_advisor> server # [    0.840354] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint268vm-test-run-index_advisor> server # [    0.844554] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint269vm-test-run-index_advisor> server # [    0.844933] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]270vm-test-run-index_advisor> server # [    0.845248] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]271vm-test-run-index_advisor> server # [    0.845386] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]272vm-test-run-index_advisor> server # [    0.845545] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]273vm-test-run-index_advisor> server # [    0.846500] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint274vm-test-run-index_advisor> server # [    0.846678] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]275vm-test-run-index_advisor> server # [    0.846778] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]276vm-test-run-index_advisor> server # [    0.846897] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]277vm-test-run-index_advisor> server # [    0.848339] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint278vm-test-run-index_advisor> server # [    0.848650] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]279vm-test-run-index_advisor> server # [    0.848767] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]280vm-test-run-index_advisor> server # [    0.848878] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]281vm-test-run-index_advisor> server # [    0.851227] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint282vm-test-run-index_advisor> server # [    0.852047] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]283vm-test-run-index_advisor> server # [    0.852338] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]284vm-test-run-index_advisor> server # [    0.852459] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]285vm-test-run-index_advisor> server # [    0.853411] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint286vm-test-run-index_advisor> server # [    0.853592] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]287vm-test-run-index_advisor> server # [    0.854451] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]288vm-test-run-index_advisor> server # [    0.854583] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]289vm-test-run-index_advisor> server # [    0.856465] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint290vm-test-run-index_advisor> server # [    0.856697] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]291vm-test-run-index_advisor> server # [    0.856918] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]292vm-test-run-index_advisor> server # [    0.857675] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293vm-test-run-index_advisor> server # [    0.859130] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint294vm-test-run-index_advisor> server # [    0.859300] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]295vm-test-run-index_advisor> server # [    0.859396] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]296vm-test-run-index_advisor> server # [    0.859506] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297vm-test-run-index_advisor> server # [    0.859619] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]298vm-test-run-index_advisor> server # [    0.861591] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint299vm-test-run-index_advisor> server # [    0.861772] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]300vm-test-run-index_advisor> server # [    0.861886] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301vm-test-run-index_advisor> server # [    0.862394] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint302vm-test-run-index_advisor> server # [    0.862541] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]303vm-test-run-index_advisor> server # [    0.862650] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]304vm-test-run-index_advisor> server # [    0.863180] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint305vm-test-run-index_advisor> server # [    0.863865] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]306vm-test-run-index_advisor> server # [    0.865516] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint307vm-test-run-index_advisor> server # [    0.866075] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]308vm-test-run-index_advisor> server # [    0.866178] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]309vm-test-run-index_advisor> server # [    0.866512] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]310vm-test-run-index_advisor> server # [    0.867992] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint311vm-test-run-index_advisor> server # [    0.868174] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]312vm-test-run-index_advisor> server # [    0.868276] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]313vm-test-run-index_advisor> server # [    0.868511] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]314vm-test-run-index_advisor> server # [    0.871557] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned315vm-test-run-index_advisor> server # [    0.871834] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned316vm-test-run-index_advisor> server # [    0.872853] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned317vm-test-run-index_advisor> server # [    0.873098] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned318vm-test-run-index_advisor> server # [    0.873255] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned319vm-test-run-index_advisor> server # [    0.873398] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned320vm-test-run-index_advisor> server # [    0.873530] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned321vm-test-run-index_advisor> server # [    0.873660] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned322vm-test-run-index_advisor> server # [    0.873796] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned323vm-test-run-index_advisor> server # [    0.874668] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned324vm-test-run-index_advisor> server # [    0.875421] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned325vm-test-run-index_advisor> server # [    0.876679] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned326vm-test-run-index_advisor> server # [    0.876931] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned327vm-test-run-index_advisor> server # [    0.877085] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned328vm-test-run-index_advisor> server # [    0.877240] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned329vm-test-run-index_advisor> server # [    0.877930] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned330vm-test-run-index_advisor> server # [    0.878188] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned331vm-test-run-index_advisor> server # [    0.879144] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned332vm-test-run-index_advisor> server # [    0.879975] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned333vm-test-run-index_advisor> server # [    0.880475] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned334vm-test-run-index_advisor> server # [    0.881305] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned335vm-test-run-index_advisor> server # [    0.881739] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned336vm-test-run-index_advisor> server # [    0.881878] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned337vm-test-run-index_advisor> server # [    0.881989] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned338vm-test-run-index_advisor> server # [    0.882099] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned339vm-test-run-index_advisor> server # [    0.882433] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned340vm-test-run-index_advisor> server # [    0.882806] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned341vm-test-run-index_advisor> server # [    0.882912] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned342vm-test-run-index_advisor> server # [    0.884128] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned343vm-test-run-index_advisor> server # [    0.884905] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned344vm-test-run-index_advisor> server # [    0.885035] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned345vm-test-run-index_advisor> server # [    0.885143] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned346vm-test-run-index_advisor> server # [    0.885599] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned347vm-test-run-index_advisor> server # [    0.885702] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned348vm-test-run-index_advisor> server # [    0.886018] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]349vm-test-run-index_advisor> server # [    0.886142] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]350vm-test-run-index_advisor> server # [    0.886387] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]351vm-test-run-index_advisor> server # [    0.889309] pci 0000:00:0a.0: enabling device (0000 -> 0002)352vm-test-run-index_advisor> server # [    0.950766] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)353vm-test-run-index_advisor> server # [    0.955677] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)354vm-test-run-index_advisor> server # [    0.959178] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)355vm-test-run-index_advisor> server # [    0.962038] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)356vm-test-run-index_advisor> server # [    0.964331] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)357vm-test-run-index_advisor> server # [    0.967169] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)358vm-test-run-index_advisor> server # [    0.970178] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)359vm-test-run-index_advisor> server # [    0.972423] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)360vm-test-run-index_advisor> server # [    0.974633] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)361vm-test-run-index_advisor> server # [    0.977592] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)362vm-test-run-index_advisor> server # [    0.981864] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)363vm-test-run-index_advisor> server # [    0.999225] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled364vm-test-run-index_advisor> server # [    1.008840] msm_serial: driver initialized365vm-test-run-index_advisor> server # [    1.009849] SuperH (H)SCI(F) driver initialized366vm-test-run-index_advisor> server # [    1.010649] STM32 USART driver initialized367vm-test-run-index_advisor> server # [    1.066578] loop: module loaded368vm-test-run-index_advisor> server # [    1.067182] virtio_blk virtio5: 2/0/0 default/read/poll queues369vm-test-run-index_advisor> server # [    1.073296] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)370vm-test-run-index_advisor> server # [    1.088811] megasas: 07.727.03.00-rc1371vm-test-run-index_advisor> server # [    1.094804] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]372vm-test-run-index_advisor> server # [    1.098278] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000373vm-test-run-index_advisor> server # [    1.099127] Intel/Sharp Extended Query Table at 0x0031374vm-test-run-index_advisor> server # [    1.101801] Using buffer write method375vm-test-run-index_advisor> server # [    1.101949] Freeing initrd memory: 10684K376vm-test-run-index_advisor> server # [    1.102665] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]377vm-test-run-index_advisor> server # [    1.103928] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000378vm-test-run-index_advisor> server # [    1.105487] Intel/Sharp Extended Query Table at 0x0031379vm-test-run-index_advisor> server # [    1.107145] Using buffer write method380vm-test-run-index_advisor> server # [    1.108321] Concatenating MTD devices:381vm-test-run-index_advisor> server # [    1.108415] (0): "0.flash"382vm-test-run-index_advisor> server # [    1.108480] (1): "0.flash"383vm-test-run-index_advisor> server # [    1.108539] into device "0.flash"384vm-test-run-index_advisor> server # [    1.135750] tun: Universal TUN/TAP device driver, 1.6385vm-test-run-index_advisor> server # [    1.148847] thunder_xcv, ver 1.0386vm-test-run-index_advisor> server # [    1.149136] thunder_bgx, ver 1.0387vm-test-run-index_advisor> server # [    1.149315] nicpf, ver 1.0388vm-test-run-index_advisor> server # [    1.153241] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version389vm-test-run-index_advisor> server # [    1.153416] hns3: Copyright (c) 2017 Huawei Corporation.390vm-test-run-index_advisor> server # [    1.153793] hclge is initializing391vm-test-run-index_advisor> server # [    1.154010] e1000: Intel(R) PRO/1000 Network Driver392vm-test-run-index_advisor> server # [    1.154414] e1000: Copyright (c) 1999-2006 Intel Corporation.393vm-test-run-index_advisor> server # [    1.154665] e1000e: Intel(R) PRO/1000 Network Driver394vm-test-run-index_advisor> server # [    1.154776] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.395vm-test-run-index_advisor> server # [    1.155001] igb: Intel(R) Gigabit Ethernet Network Driver396vm-test-run-index_advisor> server # [    1.155426] igb: Copyright (c) 2007-2014 Intel Corporation.397vm-test-run-index_advisor> server # [    1.155641] igbvf: Intel(R) Gigabit Virtual Function Network Driver398vm-test-run-index_advisor> server # [    1.156319] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.399vm-test-run-index_advisor> server # [    1.157214] sky2: driver version 1.30400vm-test-run-index_advisor> server # [    1.166961] ehci-pci 0000:00:0a.0: EHCI Host Controller401vm-test-run-index_advisor> server # [    1.168062] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1402vm-test-run-index_advisor> server # [    1.168890] usbcore: registered new interface driver usb-storage403vm-test-run-index_advisor> server # [    1.169402] usbcore: registered new interface driver usbserial_generic404vm-test-run-index_advisor> server # [    1.170706] usbserial: USB Serial support registered for generic405vm-test-run-index_advisor> server # [    1.172330] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000406vm-test-run-index_advisor> server # [    1.181196] rtc-pl031 9010000.pl031: registered as rtc0407vm-test-run-index_advisor> server # [    1.181831] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T02:45:43 UTC (1791427543)408vm-test-run-index_advisor> server # [    1.184212] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00409vm-test-run-index_advisor> server # [    1.184912] i2c_dev: i2c /dev entries driver410vm-test-run-index_advisor> server # [    1.193778] hub 1-0:1.0: USB hub found411vm-test-run-index_advisor> server # [    1.194621] hub 1-0:1.0: 6 ports detected412vm-test-run-index_advisor> server # [    1.201807] sdhci: Secure Digital Host Controller Interface driver413vm-test-run-index_advisor> server # [    1.202033] sdhci: Copyright(c) Pierre Ossman414vm-test-run-index_advisor> server # [    1.204056] Synopsys Designware Multimedia Card Interface Driver415vm-test-run-index_advisor> server # [    1.206306] sdhci-pltfm: SDHCI platform and OF driver helper416vm-test-run-index_advisor> server # [    1.214102] hid: raw HID events driver (C) Jiri Kosina417vm-test-run-index_advisor> server # [    1.217167] usbcore: registered new interface driver usbhid418vm-test-run-index_advisor> server # [    1.217315] usbhid: USB HID core driver419vm-test-run-index_advisor> server # [    1.226772] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available420vm-test-run-index_advisor> server # [    1.241814] drop_monitor: Initializing network drop monitor service421vm-test-run-index_advisor> server # [    1.243312] NET: Registered PF_INET6 protocol family422vm-test-run-index_advisor> server # [    1.263783] Segment Routing with IPv6423vm-test-run-index_advisor> server # [    1.264142] In-situ OAM (IOAM) with IPv6424vm-test-run-index_advisor> server # [    1.264751] NET: Registered PF_PACKET protocol family425vm-test-run-index_advisor> server # [    1.265990] 9pnet: Installing 9P2000 support426vm-test-run-index_advisor> server # [    1.272568] Key type dns_resolver registered427vm-test-run-index_advisor> server # [    1.309835] registered taskstats version 1428vm-test-run-index_advisor> server # [    1.313281] Loading compiled-in X.509 certificates429vm-test-run-index_advisor> server # [    1.374440] Demotion targets for Node 0: null430vm-test-run-index_advisor> server # [    1.377832] Key type .fscrypt registered431vm-test-run-index_advisor> server # [    1.378123] Key type fscrypt-provisioning registered432vm-test-run-index_advisor> server # [    1.390769] input: gpio-keys as /devices/platform/gpio-keys/input/input0433vm-test-run-index_advisor> server # [    1.438491] clk: Disabling unused clocks434vm-test-run-index_advisor> server # [    1.438776] PM: genpd: Disabling unused power domains435vm-test-run-index_advisor> server # [    1.439177] ALSA device list:436vm-test-run-index_advisor> server # [    1.439296]   No soundcards found.437vm-test-run-index_advisor> server # [    1.443855] usb 1-1: new high-speed USB device number 2 using ehci-pci438vm-test-run-index_advisor> server # [    1.527586] Freeing unused kernel memory: 4416K439vm-test-run-index_advisor> server # [    1.530114] Run /init as init process440vm-test-run-index_advisor> server # [    1.658701] 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/input1441vm-test-run-index_advisor> server # [    1.798557] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0442vm-test-run-index_advisor> server # [    1.920823] usb 1-2: new high-speed USB device number 3 using ehci-pci443vm-test-run-index_advisor> server # [    2.096589] 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/input2444vm-test-run-index_advisor> server # [    2.101153] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0445vm-test-run-index_advisor> server # [    2.210674] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev446vm-test-run-index_advisor> server # [    2.358748] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0447vm-test-run-index_advisor> server # [    2.359802] [drm] features: -virgl +edid -resource_blob -host_visible448vm-test-run-index_advisor> server # [    2.359825] [drm] features: -context_init449vm-test-run-index_advisor> server # [    2.362177] [drm] number of scanouts: 1450vm-test-run-index_advisor> server # [    2.362424] [drm] number of cap sets: 0451vm-test-run-index_advisor> server # [    2.374782] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0452vm-test-run-index_advisor> server # [    2.409789] Console: switching to colour frame buffer device 160x50453vm-test-run-index_advisor> server # [    2.422966] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device454vm-test-run-index_advisor> server # [    5.702557] EXT4-fs (vda): mounted filesystem 6e9adac6-1f55-4efa-a7e9-0c83d36a5d94 r/w with ordered data mode. Quota mode: none.455vm-test-run-index_advisor> server # [    8.636541] EXT4-fs (vda): re-mounted 6e9adac6-1f55-4efa-a7e9-0c83d36a5d94.456vm-test-run-index_advisor> server # [    8.656218] booting system configuration /nix/store/r6b5ms8ljsq7h4mh74y3rmsvys24p8lv-nixos-system-server-test457vm-test-run-index_advisor> server # [   23.618593] 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)458vm-test-run-index_advisor> server # [   23.629723] systemd[1]: Detected virtualization qemu.459vm-test-run-index_advisor> server # [   23.634837] systemd[1]: Detected architecture arm64.460vm-test-run-index_advisor> server # [   23.641230] systemd[1]: Detected first boot.461vm-test-run-index_advisor> server # [   23.676313] systemd[1]: Initializing machine ID from random generator.462vm-test-run-index_advisor> server # [   23.715735] systemd[1]: Hostname set to <server>.463vm-test-run-index_advisor> server # [   25.049627] systemd[1]: bpf-restrict-fs: LSM BPF program attached464vm-test-run-index_advisor> server # [   26.720355] systemd[1]: Applying preset policy.465vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...466vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.467vm-test-run-index_advisor> server # [   31.070321] systemd[1]: Populated /etc with preset unit settings.468vm-test-run-index_advisor> server # [   35.089043] systemd[1]: Queued start job for default target Multi-User System.469vm-test-run-index_advisor> server # [   35.343221] systemd[1]: Created slice Slice /system/getty.470vm-test-run-index_advisor> server # [   35.360734] systemd[1]: Created slice Slice /system/modprobe.471vm-test-run-index_advisor> server # [   35.373610] systemd[1]: Created slice User and Session Slice.472vm-test-run-index_advisor> server # [   35.383153] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.473vm-test-run-index_advisor> server # [   35.389645] systemd[1]: Started Forward Password Requests to Wall Directory Watch.474vm-test-run-index_advisor> server # [   35.395490] systemd[1]: Expecting device /dev/hvc0...475vm-test-run-index_advisor> server # [   35.400165] systemd[1]: Expecting device /dev/ttyAMA0...476vm-test-run-index_advisor> server # [   35.404288] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...477vm-test-run-index_advisor> server # [   35.407583] systemd[1]: Reached target Local Encrypted Volumes.478vm-test-run-index_advisor> server # [   35.413922] systemd[1]: Reached target Virtual Machines and Containers.479vm-test-run-index_advisor> server # [   35.419243] systemd[1]: Reached target Path Units.480vm-test-run-index_advisor> server # [   35.423709] systemd[1]: Reached target Remote File Systems.481vm-test-run-index_advisor> server # [   35.428936] systemd[1]: Reached target Slice Units.482vm-test-run-index_advisor> server # [   35.432366] systemd[1]: Reached target Swaps.483vm-test-run-index_advisor> server # [   35.471424] systemd[1]: Listening on Process Core Dump Socket.484vm-test-run-index_advisor> server # [   35.499185] systemd[1]: Listening on Credential Encryption/Decryption.485vm-test-run-index_advisor> server # [   35.509995] systemd[1]: Listening on Journal Audit Socket.486vm-test-run-index_advisor> server # [   35.517078] systemd[1]: Listening on Journal Socket (/dev/log).487vm-test-run-index_advisor> server # [   35.523220] systemd[1]: Listening on Journal Sockets.488vm-test-run-index_advisor> server # [   35.533139] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.489vm-test-run-index_advisor> server # [   35.538154] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).490vm-test-run-index_advisor> server # [   35.544954] systemd[1]: Listening on udev Control Socket.491vm-test-run-index_advisor> server # [   35.550837] systemd[1]: Listening on udev Kernel Socket.492vm-test-run-index_advisor> server # [   35.604994] systemd[1]: Mounting Huge Pages File System...493vm-test-run-index_advisor> server # [   35.653731] systemd[1]: Mounting POSIX Message Queue File System...494vm-test-run-index_advisor> server # [   35.707535] systemd[1]: Mounting Kernel Debug File System...495vm-test-run-index_advisor> server # [   35.753219] systemd[1]: Mounting Kernel Trace File System...496vm-test-run-index_advisor> server # [   35.867382] systemd[1]: Starting Create List of Static Device Nodes...497vm-test-run-index_advisor> server # [   35.930386] systemd[1]: Starting Load Kernel Module 9pnet_virtio...498vm-test-run-index_advisor> server # [   35.949075] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).499vm-test-run-index_advisor> server # [   36.070156] systemd[1]: Mounting Kernel Configuration File System...500vm-test-run-index_advisor> server # [   36.080412] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).501vm-test-run-index_advisor> server # [   36.089335] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).502vm-test-run-index_advisor> server # [   36.283076] systemd[1]: Starting Load Kernel Module fuse...503vm-test-run-index_advisor> server # [   36.301017] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).504vm-test-run-index_advisor> server # [   36.312006] 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).505vm-test-run-index_advisor> server # [   36.519024] systemd[1]: Starting Journal Service...506vm-test-run-index_advisor> server # [   36.601598] systemd[1]: Starting Load Kernel Modules...507vm-test-run-index_advisor> server # [   36.677195] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...508vm-test-run-index_advisor> server # [   36.752784] systemd[1]: Starting Remount Root and Kernel File Systems...509vm-test-run-index_advisor> server # [   36.759017] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).510vm-test-run-index_advisor> server # [   36.885748] systemd[1]: Starting Coldplug All udev Devices...511vm-test-run-index_advisor> server # [   36.973405] systemd[1]: Mounted Huge Pages File System.512vm-test-run-index_advisor> server # [   36.982972] systemd[1]: Mounted POSIX Message Queue File System.513vm-test-run-index_advisor> server # [   36.998362] systemd[1]: Mounted Kernel Debug File System.514vm-test-run-index_advisor> server # [   37.009275] systemd[1]: Mounted Kernel Trace File System.515vm-test-run-index_advisor> server # [   37.038519] systemd[1]: Finished Create List of Static Device Nodes.516vm-test-run-index_advisor> server # [   37.059203] systemd[1]: Mounted Kernel Configuration File System.517vm-test-run-index_advisor> server # [   37.137222] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...518vm-test-run-index_advisor> server # [   37.618114] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.519vm-test-run-index_advisor> server # [   37.637463] systemd[1]: Finished Load Kernel Module 9pnet_virtio.520vm-test-run-index_advisor> server # [   38.141549] systemd[1]: Finished Load Kernel Modules.521vm-test-run-index_advisor> server # [   38.213584] systemd[1]: Starting Firewall...522vm-test-run-index_advisor> server # [   38.236419] EXT4-fs (vda): re-mounted 6e9adac6-1f55-4efa-a7e9-0c83d36a5d94.523vm-test-run-index_advisor> server # [   38.258409] systemd[1]: Starting Apply Kernel Variables...524vm-test-run-index_advisor> server # [   38.330736] fuse: init (API version 7.41)525vm-test-run-index_advisor> server # [   38.389834] systemd[1]: Finished Remount Root and Kernel File Systems.526vm-test-run-index_advisor> server # [   38.398844] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).527vm-test-run-index_advisor> server # [   38.413544] systemd-journald[398]: Collecting audit messages is enabled.528vm-test-run-index_advisor> server # [   38.456044] systemd[1]: Starting Load/Save OS Random Seed...529vm-test-run-index_advisor> server # [   38.460473] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).530vm-test-run-index_advisor> server # [   38.483803] systemd[1]: modprobe@fuse.service: Deactivated successfully.531vm-test-run-index_advisor> server # [   38.499144] systemd[1]: Finished Load Kernel Module fuse.532vm-test-run-index_advisor> server # [   38.550349] systemd[1]: Mounting FUSE Control File System...533vm-test-run-index_advisor> server # [   38.852131] systemd[1]: Mounted FUSE Control File System.534vm-test-run-index_advisor> server # [   39.755421] systemd[1]: Finished Load/Save OS Random Seed.535vm-test-run-index_advisor> server # [   39.773404] systemd[1]: Reached target First Boot Complete.536vm-test-run-index_advisor> server # [   40.014001] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.537vm-test-run-index_advisor> server # [   40.105930] systemd[1]: Starting Create Static Device Nodes in /dev...538vm-test-run-index_advisor> server # [   40.134812] systemd[1]: Finished Apply Kernel Variables.539vm-test-run-index_advisor> server # [   40.567990] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.540vm-test-run-index_advisor> server # [   41.420640] systemd[1]: Finished Create Static Device Nodes in /dev.541vm-test-run-index_advisor> server # [   41.442537] systemd[1]: Reached target Preparation for Local File Systems.542vm-test-run-index_advisor> server # [   41.507151] systemd[1]: Mounting /run/wrappers...543vm-test-run-index_advisor> server # [   41.554036] systemd[1]: Starting Rule-based Manager for Device Events and Files...544vm-test-run-index_advisor> server # [   41.817760] systemd[1]: Started Journal Service.545vm-test-run-index_advisor> server # [   41.773228] systemd-modules-load[399]: Module 'atkbd' is built in546vm-test-run-index_advisor> server # [   41.787658] systemd-modules-load[399]: Module 'loop' is built in547vm-test-run-index_advisor> server # [   41.800022] systemd-oomd[400]: No swap; memory pressure usage will be degraded548vm-test-run-index_advisor> server # [   41.859830] systemd[1]: Starting Flush Journal to Persistent Storage...549vm-test-run-index_advisor> server # [   42.035464] systemd[1]: Mounted /run/wrappers.550vm-test-run-index_advisor> server # [   42.204004] systemd[1]: Reached target Local File Systems.551vm-test-run-index_advisor> server # [   42.234211] systemd[1]: Listening on Boot Entries Service Socket.552vm-test-run-index_advisor> server # [   42.311225] systemd[1]: Starting Create SUID/SGID Wrappers...553vm-test-run-index_advisor> server # [   42.317756] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.554vm-test-run-index_advisor> server # [   42.358009] systemd[1]: Starting Save Transient machine-id to Disk...555vm-test-run-index_advisor> server # [   42.854640] systemd-journald[398]: Received client request to flush runtime journal.556vm-test-run-index_advisor> server # [   43.745094] systemd-udevd[427]: Using default interface naming scheme 'v258'.557vm-test-run-index_advisor> server # [   43.818643] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.558vm-test-run-index_advisor> server # [   43.859673] systemd[1]: Finished Save Transient machine-id to Disk.559vm-test-run-index_advisor> server # [   43.889542] systemd[1]: Finished Flush Journal to Persistent Storage.560vm-test-run-index_advisor> server # [   43.904548] systemd[1]: Starting Create System Files and Directories...561vm-test-run-index_advisor> server # [   46.310385] systemd[1]: Finished Create System Files and Directories.562vm-test-run-index_advisor> server # [   46.368274] systemd[1]: Starting Rebuild Journal Catalog...563vm-test-run-index_advisor> server # [   46.407880] systemd[1]: Starting Record System Boot/Shutdown in UTMP...564vm-test-run-index_advisor> server # [   46.418029] systemd[1]: Started Rule-based Manager for Device Events and Files.565vm-test-run-index_advisor> server # [   47.983723] systemd[1]: Finished Record System Boot/Shutdown in UTMP.566vm-test-run-index_advisor> server # [   49.122301] systemd[1]: Finished Rebuild Journal Catalog.567vm-test-run-index_advisor> server # [   49.151832] systemd[1]: Starting Update is Completed...568vm-test-run-index_advisor> server # [   50.023588] systemd[1]: Finished Update is Completed.569vm-test-run-index_advisor> server # [   54.125659] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.570vm-test-run-index_advisor> server # [   54.134241] systemd[1]: Finished Create SUID/SGID Wrappers.571vm-test-run-index_advisor> server # [   54.138356] systemd[1]: suid-sgid-wrappers.service: Consumed 3.192s CPU time, 5.6M memory peak.572vm-test-run-index_advisor> server # [   57.723310] systemd[1]: Finished Firewall.573vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...574vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.575vm-test-run-index_advisor> server # [   59.488778] systemd[1]: Finished Coldplug All udev Devices.576vm-test-run-index_advisor> server # [   59.502522] systemd[1]: Reached target System Initialization.577vm-test-run-index_advisor> server # [   59.522052] systemd[1]: Started Discard unused filesystem blocks once a week.578vm-test-run-index_advisor> server # [   59.534706] systemd[1]: Started Daily Cleanup of Temporary Directories.579vm-test-run-index_advisor> server # [   59.542783] systemd[1]: Reached target Timer Units.580vm-test-run-index_advisor> server # [   59.549978] systemd[1]: Listening on D-Bus System Message Bus Socket.581vm-test-run-index_advisor> server # [   59.553959] systemd[1]: Listening on Nix Daemon Socket.582vm-test-run-index_advisor> server # [   59.557606] systemd[1]: Listening on Hostname Service Socket.583vm-test-run-index_advisor> server # [   59.561479] systemd[1]: Reached target Socket Units.584vm-test-run-index_advisor> server # [   59.563167] systemd[1]: Reached target Basic System.585vm-test-run-index_advisor> server # [   59.578089] systemd[1]: Starting Import lastlog data into lastlog2 database...586vm-test-run-index_advisor> server # [   59.616447] systemd[1]: Starting Name Service Cache Daemon (nsncd)...587vm-test-run-index_advisor> server # [   59.677468] systemd[1]: Started Reset console on configuration changes.588vm-test-run-index_advisor> server # [   59.740638] systemd[1]: Starting resolvconf update...589vm-test-run-index_advisor> server # [   59.813676] systemd[1]: Starting D-Bus System Message Bus...590vm-test-run-index_advisor> server # [   60.971110] systemd[1]: Finished Import lastlog data into lastlog2 database.591vm-test-run-index_advisor> server # [   61.294326] systemd[1]: Started Name Service Cache Daemon (nsncd).592vm-test-run-index_advisor> server # [   61.305170] systemd[1]: Reached target Host and Network Name Lookups.593vm-test-run-index_advisor> server # [   61.308071] systemd[1]: Reached target User and Group Name Lookups.594vm-test-run-index_advisor> server # [   61.321780] nsncd[636]: Oct 08 02:46:43.658 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"595vm-test-run-index_advisor> server # [   61.405054] systemd[1]: Starting User Login Management...596vm-test-run-index_advisor> server # [   61.451453] dbus-daemon[639]: dbus[639]: Unknown username "systemd-timesync" in message bus configuration file597vm-test-run-index_advisor> server # [   62.091431] systemd[1]: Started D-Bus System Message Bus.598vm-test-run-index_advisor> server # [   63.064165] systemd-logind[657]: New seat seat0.599vm-test-run-index_advisor> server # [   63.111740] systemd[1]: Started User Login Management.600vm-test-run-index_advisor> server # [   63.144967] systemd[1]: Starting linger-users.service...601vm-test-run-index_advisor> server # [   63.247231] systemd[1]: Stopped target Host and Network Name Lookups.602vm-test-run-index_advisor> server # [   63.253757] systemd[1]: Stopping Host and Network Name Lookups...603vm-test-run-index_advisor> server # [   63.255899] systemd[1]: Stopped target User and Group Name Lookups.604vm-test-run-index_advisor> server # [   63.273026] systemd[1]: Stopping User and Group Name Lookups...605vm-test-run-index_advisor> server # [   63.282747] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...606vm-test-run-index_advisor> server # [   63.338240] systemd[1]: nscd.service: Deactivated successfully.607vm-test-run-index_advisor> server # [   63.354693] systemd[1]: Stopped Name Service Cache Daemon (nsncd).608vm-test-run-index_advisor> server # [   63.493291] systemd[1]: Starting Name Service Cache Daemon (nsncd)...609vm-test-run-index_advisor> server # [   63.840904] systemd[1]: linger-users.service: Deactivated successfully.610vm-test-run-index_advisor> server # [   63.855035] systemd[1]: Finished linger-users.service.611vm-test-run-index_advisor> server # [   64.293260] systemd[1]: Started Name Service Cache Daemon (nsncd).612vm-test-run-index_advisor> server # [   64.307915] systemd[1]: Reached target Host and Network Name Lookups.613vm-test-run-index_advisor> server # [   64.329902] nsncd[696]: Oct 08 02:46:46.682 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"614vm-test-run-index_advisor> server # [   64.332043] systemd[1]: Reached target User and Group Name Lookups.615vm-test-run-index_advisor> server # [   64.880471] systemd[1]: Finished resolvconf update.616vm-test-run-index_advisor> server # [   64.913324] systemd[1]: Reached target Preparation for Network.617vm-test-run-index_advisor> server # [   65.031279] systemd[1]: Starting DHCP Client...618vm-test-run-index_advisor> server # [   66.588203] dhcpcd[747]: dhcpcd-10.2.4 starting619vm-test-run-index_advisor> server # [   66.686703] dhcpcd[751]: dev: loaded udev620vm-test-run-index_advisor> server # [   67.060120] 8021q: 802.1Q VLAN Support v1.8621vm-test-run-index_advisor> server # [   67.736530] cfg80211: Loading compiled-in X.509 certificates for regulatory database622vm-test-run-index_advisor> server # [   68.122150] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'623vm-test-run-index_advisor> server # [   68.129214] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'624vm-test-run-index_advisor> server # [   68.161842] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2625vm-test-run-index_advisor> server # [   68.170866] cfg80211: failed to load regulatory.db626vm-test-run-index_advisor> server # [   69.049057] 8021q: adding VLAN 0 to HW filter on device eth0627vm-test-run-index_advisor> server # [   68.999543] dhcpcd[751]: eth0: waiting for carrier628vm-test-run-index_advisor> server # [   69.014549] systemd[1]: Found device /dev/hvc0.629vm-test-run-index_advisor> server # [   69.019665] dhcpcd[751]: eth0: carrier acquired630vm-test-run-index_advisor> server # [   69.181825] dhcpcd[751]: DUID 00:01:00:01:32:59:c0:9b:52:54:00:12:34:56631vm-test-run-index_advisor> server # [   69.184546] dhcpcd[751]: eth0: IAID 00:12:34:56632vm-test-run-index_advisor> server # [   69.187389] dhcpcd[751]: eth0: adding address fe80::5054:ff:fe12:3456633vm-test-run-index_advisor> server # [   70.889094] systemd[1]: Found device /dev/ttyAMA0.634vm-test-run-index_advisor> server # [   70.924657] systemd[1]: Started backdoor.service.635vm-test-run-index_advisor> server # [   71.187753] dhcpcd[751]: eth0: soliciting a DHCP lease636vm-test-run-index_advisor> server # [   71.224956] dhcpcd[751]: eth0: offered 10.0.2.15 from 10.0.2.2637vm-test-run-index_advisor> server # [   71.247990] dhcpcd[751]: eth0: probing address 10.0.2.15/24638vm-test-run-index_advisor> server # connecting to host...639vm-test-run-index_advisor> server # [   71.619664] dhcpcd[751]: eth0: soliciting an IPv6 router640vm-test-run-index_advisor> server # [   71.646185] dhcpcd[751]: eth0: Router Advertisement from fe80::2641vm-test-run-index_advisor> server # [   71.647297] dhcpcd[751]: eth0: adding address fec0::5054:ff:fe12:3456/64642vm-test-run-index_advisor> server # [   71.658146] dhcpcd[751]: eth0: adding route to fec0::/64643vm-test-run-index_advisor> server # [   71.665289] dhcpcd[751]: eth0: adding default route via fe80::2644vm-test-run-index_advisor> server: Guest shell says: b'Spawning backdoor root shell...\n'645vm-test-run-index_advisor> server: connected to guest root shell646vm-test-run-index_advisor> server: (connecting took 72.81 seconds)647vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 72.81 seconds)648vm-test-run-index_advisor> server # [   71.893201] (udev-worker)[792]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.649vm-test-run-index_advisor> server # [   71.927367] (udev-worker)[792]: Network interface NamePolicy= disabled on kernel command line.650vm-test-run-index_advisor> server # [   71.967023] (udev-worker)[795]: Network interface NamePolicy= disabled on kernel command line.651vm-test-run-index_advisor> server # [   72.948139] systemd[1]: Found device Virtio network device.652vm-test-run-index_advisor> server # [   72.991783] systemd[1]: Starting Address configuration of eth1...653vm-test-run-index_advisor> server # [   74.024092] dhcpcd[751]: eth0: IAID 00:12:34:56654vm-test-run-index_advisor> server # [   74.240678] 8021q: adding VLAN 0 to HW filter on device eth1655vm-test-run-index_advisor> server # [   74.296713] dhcpcd[751]: eth0: soliciting an IPv6 router656vm-test-run-index_advisor> server # [   74.397424] network-addresses-eth1-start[820]: adding address 192.168.1.1/24... done657vm-test-run-index_advisor> server # [   74.587982] network-addresses-eth1-start[820]: adding address 2001:db8:1::1/64... done658vm-test-run-index_advisor> server # [   74.929068] systemd[1]: Finished Address configuration of eth1.659vm-test-run-index_advisor> server # [   74.984941] systemd[1]: Starting Networking Setup...660vm-test-run-index_advisor> server # [   75.362373] dhcpcd[751]: eth0: soliciting a DHCP lease661vm-test-run-index_advisor> server # [   75.364825] dhcpcd[751]: eth0: offered 10.0.2.15 from 10.0.2.2662vm-test-run-index_advisor> server # [   75.376161] dhcpcd[751]: eth0: probing address 10.0.2.15/24663vm-test-run-index_advisor> server # [   76.009316] dhcpcd[751]: eth0: leased 10.0.2.15 for 86400 seconds664vm-test-run-index_advisor> server # [   76.031926] dhcpcd[751]: eth0: adding route to 10.0.2.0/24665vm-test-run-index_advisor> server # [   76.043840] dhcpcd[751]: eth0: adding default route via 10.0.2.2666vm-test-run-index_advisor> server # [   76.899197] dhcpcd[881]: /nix/store/hsj50yr7hr62ql9xaynbihkhfhbgprcn-openresolv-3.17.0/sbin/.resolvconf-wrapped: line 1221: kill: (865) - Operation not permitted667vm-test-run-index_advisor> server # [   76.908098] dhcpcd[881]: clearing stale lock pid 865668vm-test-run-index_advisor> server # [   78.719925] systemd[1]: Finished Networking Setup.669vm-test-run-index_advisor> server # [   78.739721] systemd[1]: Reached target Network.670vm-test-run-index_advisor> server # [   78.769749] systemd[1]: Starting PostgreSQL Database Server...671vm-test-run-index_advisor> server # [   78.815993] systemd[1]: Starting Permit User Sessions...672vm-test-run-index_advisor> server # [   79.835739] systemd[1]: Finished Permit User Sessions.673vm-test-run-index_advisor> server # [   79.877768] systemd[1]: Started Getty on tty1.674vm-test-run-index_advisor> server # [   79.897035] systemd[1]: Reached target Login Prompts.675vm-test-run-index_advisor> server # [   80.615395] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[973]: Initializing database at /var/lib/postgresql/data676vm-test-run-index_advisor> server # [   80.787674] systemd[1]: Started DHCP Client.677vm-test-run-index_advisor> server # [   82.521666] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: The files belonging to this database system will be owned by user "postgres".678vm-test-run-index_advisor> server # [   82.525931] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: This user must also own the server process.679vm-test-run-index_advisor> server # [   82.590313] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: The database cluster will be initialized with locale "en_US.UTF-8".680vm-test-run-index_advisor> server # [   82.598935] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: The default database encoding has accordingly been set to "UTF8".681vm-test-run-index_advisor> server # [   82.613406] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: The default text search configuration will be set to "english".682vm-test-run-index_advisor> server # [   82.614829] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: Data page checksums are enabled.683vm-test-run-index_advisor> server # [   82.616230] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: fixing permissions on existing directory /var/lib/postgresql/data ... ok684vm-test-run-index_advisor> server # [   82.628951] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: creating subdirectories ... ok685vm-test-run-index_advisor> server # [   82.638465] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: selecting dynamic shared memory implementation ... posix686vm-test-run-index_advisor> server # [   84.109991] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: selecting default max_connections ... 100687vm-test-run-index_advisor> server # [   84.786623] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: selecting default shared_buffers ... 128MB688vm-test-run-index_advisor> server # [   87.295988] systemd-logind[657]: Watching system buttons on /dev/input/event0 (gpio-keys)689vm-test-run-index_advisor> server # [   96.692307] mousedev: PS/2 mouse device common for all mice690vm-test-run-index_advisor> server # [   96.871241] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: selecting default time zone ... UTC691vm-test-run-index_advisor> server # [   96.917288] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: creating configuration files ... ok692vm-test-run-index_advisor> server # [  100.356223] systemd-logind[657]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)693vm-test-run-index_advisor> server # [  101.954291] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: running bootstrap script ... ok694vm-test-run-index_advisor> server # [  115.536896] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: performing post-bootstrap initialization ... ok695vm-test-run-index_advisor> server # [  118.374774] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: syncing data to disk ... ok696vm-test-run-index_advisor> server # [  118.383878] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: initdb: warning: enabling "trust" authentication for local connections697vm-test-run-index_advisor> server # [  118.392712] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: 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.698vm-test-run-index_advisor> server # [  118.395447] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]: Success. You can now start the database server using:699vm-test-run-index_advisor> server # [  118.403697] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[979]:     /nix/store/igdwz7j5z2n00jxixwr7qxvdawj02d4p-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start700vm-test-run-index_advisor> server # [  120.422693] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[973]: Validating PostgreSQL configuration...701vm-test-run-index_advisor> server # [  120.938093] 8p221d51kpskn3br0c9aipq97qi5zx4d-postgresql-init[1369]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault702vm-test-run-index_advisor> server # [  122.067012] postgres[1371]:  2026-10-08 02:47:44.427 UTC [1371] LOG:  pgaudit extension initialized703vm-test-run-index_advisor> server # [  123.070398] postgres[1371]:  2026-10-08 02:47:45.435 UTC [1371] LOG:  pgsodium primary server secret key loaded704vm-test-run-index_advisor> server # [  123.717146] postgres[1371]:  2026-10-08 02:47:46.109 UTC [1371] LOG:  vault primary server secret key loaded705vm-test-run-index_advisor> server # [  124.114843] postgres[1371]:  2026-10-08 02:47:46.499 UTC [1371] LOG:  starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit706vm-test-run-index_advisor> server # [  124.125559] postgres[1371]:  2026-10-08 02:47:46.516 UTC [1371] LOG:  listening on IPv4 address "0.0.0.0", port 5432707vm-test-run-index_advisor> server # [  124.127589] postgres[1371]:  2026-10-08 02:47:46.517 UTC [1371] LOG:  listening on IPv6 address "::", port 5432708vm-test-run-index_advisor> server # [  124.150108] postgres[1371]:  2026-10-08 02:47:46.542 UTC [1371] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"709vm-test-run-index_advisor> server # [  124.252877] postgres[1405]:  2026-10-08 02:47:46.636 UTC [1405] LOG:  database system was shut down at 2026-10-08 02:47:37 UTC710vm-test-run-index_advisor> server # [  124.446243] postgres[1409]:  2026-10-08 02:47:46.830 UTC [1409] FATAL:  role "postgres" does not exist711vm-test-run-index_advisor> server # [  124.458919] postgres[1371]:  2026-10-08 02:47:46.852 UTC [1371] LOG:  database system is ready to accept connections712vm-test-run-index_advisor> server # [  124.508185] postgres[1408]:  2026-10-08 02:47:46.893 UTC [1408] LOG:  TimescaleDB background worker launcher connected to shared catalogs713vm-test-run-index_advisor> server # [  124.533965] systemd[1]: Started PostgreSQL Database Server.714vm-test-run-index_advisor> server # [  124.586061] systemd[1]: Starting Supabase Database Initialization...715vm-test-run-index_advisor> server # [  124.705812] postgres[1371]:  2026-10-08 02:47:47.084 UTC [1371] LOG:  background worker "pg_net 0.20.4 worker" (PID 1409) exited with exit code 1716vm-test-run-index_advisor> server # [  124.957919] postgres[1410]:  2026-10-08 02:47:47.316 UTC [1410] LOG:  pg_cron scheduler started717vm-test-run-index_advisor> server # [  125.154954] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Waiting for PostgreSQL to be ready...718vm-test-run-index_advisor> server # [  125.880006] postgres[1426]:  2026-10-08 02:47:48.232 UTC [1426] FATAL:  role "postgres" does not exist719vm-test-run-index_advisor> server # [  126.043695] postgres[1371]:  2026-10-08 02:47:48.408 UTC [1371] LOG:  background worker "pg_net 0.20.4 worker" (PID 1426) exited with exit code 1720vm-test-run-index_advisor> server # [  126.089228] postgres[1429]: ::1 2026-10-08 02:47:48.467 UTC [1429] root@root FATAL:  role "root" does not exist721vm-test-run-index_advisor> server # [  126.118500] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: PostgreSQL is ready722vm-test-run-index_advisor> server # [  126.126692] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Creating postgres role...723vm-test-run-index_advisor> server # [  126.672031] postgres[1439]: ::1 2026-10-08 02:47:49.049 UTC [1439] supabase_admin@postgres LOG:  statement: CREATE ROLE postgres SUPERUSER LOGIN;724vm-test-run-index_advisor> server # [  126.725727] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1432]: CREATE ROLE725vm-test-run-index_advisor> server # [  127.346148] postgres[1450]: ::1 2026-10-08 02:47:49.732 UTC [1450] supabase_admin@postgres LOG:  statement: ALTER DATABASE postgres OWNER TO postgres;726vm-test-run-index_advisor> server # [  127.385189] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1446]: ALTER DATABASE727vm-test-run-index_advisor> server # [  127.462929] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000000-initial-schema.sql728vm-test-run-index_advisor> server # [  127.822685] postgres[1452]: ::1 2026-10-08 02:47:50.208 UTC [1452] postgres@postgres LOG:  statement: create publication supabase_realtime;729vm-test-run-index_advisor> server # [  127.851721] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE PUBLICATION730vm-test-run-index_advisor> server # [  127.854729] postgres[1452]: ::1 2026-10-08 02:47:50.233 UTC [1452] postgres@postgres LOG:  statement: alter user  supabase_admin with superuser createdb createrole replication bypassrls;731vm-test-run-index_advisor> server # [  127.855651] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER ROLE732vm-test-run-index_advisor> server # [  127.857546] postgres[1452]: ::1 2026-10-08 02:47:50.248 UTC [1452] postgres@postgres LOG:  statement: create user supabase_replication_admin with login replication;733vm-test-run-index_advisor> server # [  127.866912] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE734vm-test-run-index_advisor> server # [  127.870646] postgres[1452]: ::1 2026-10-08 02:47:50.263 UTC [1452] postgres@postgres LOG:  statement: create user supabase_etl_admin with login replication bypassrls;735vm-test-run-index_advisor> server # [  127.881439] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE736vm-test-run-index_advisor> server # [  127.883499] postgres[1452]: ::1 2026-10-08 02:47:50.271 UTC [1452] postgres@postgres LOG:  statement: grant pg_read_all_data to supabase_etl_admin;737vm-test-run-index_advisor> server # [  127.894649] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT ROLE738vm-test-run-index_advisor> server # [  127.898860] postgres[1452]: ::1 2026-10-08 02:47:50.290 UTC [1452] postgres@postgres LOG:  statement: grant create on database postgres to supabase_etl_admin;739vm-test-run-index_advisor> server # [  127.905823] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT740vm-test-run-index_advisor> server # [  127.907005] postgres[1452]: ::1 2026-10-08 02:47:50.299 UTC [1452] postgres@postgres LOG:  statement: create role supabase_read_only_user with login bypassrls;741vm-test-run-index_advisor> server # [  127.917308] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE742vm-test-run-index_advisor> server # [  127.919211] postgres[1452]: ::1 2026-10-08 02:47:50.309 UTC [1452] postgres@postgres LOG:  statement: grant pg_read_all_data to supabase_read_only_user;743vm-test-run-index_advisor> server # [  127.929232] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT ROLE744vm-test-run-index_advisor> server # [  127.930120] postgres[1452]: ::1 2026-10-08 02:47:50.323 UTC [1452] postgres@postgres LOG:  statement: create schema if not exists extensions;745vm-test-run-index_advisor> server # [  127.942591] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE SCHEMA746vm-test-run-index_advisor> server # [  127.943956] postgres[1452]: ::1 2026-10-08 02:47:50.336 UTC [1452] postgres@postgres LOG:  statement: create extension if not exists "uuid-ossp"      with schema extensions;747vm-test-run-index_advisor> server # [  130.811493] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE EXTENSION748vm-test-run-index_advisor> server # [  130.817522] postgres[1452]: ::1 2026-10-08 02:47:53.197 UTC [1452] postgres@postgres LOG:  statement: create extension if not exists pgcrypto         with schema extensions;749vm-test-run-index_advisor> server # [  131.868567] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE EXTENSION750vm-test-run-index_advisor> server # [  131.885063] postgres[1452]: ::1 2026-10-08 02:47:54.265 UTC [1452] postgres@postgres LOG:  statement: create role anon                nologin noinherit;751vm-test-run-index_advisor> server # [  131.886690] postgres[1452]: ::1 2026-10-08 02:47:54.275 UTC [1452] postgres@postgres LOG:  statement: create role authenticated       nologin noinherit;752vm-test-run-index_advisor> server # [  131.887294] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE753vm-test-run-index_advisor> server # [  131.892538] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE754vm-test-run-index_advisor> server # [  131.893434] postgres[1452]: ::1 2026-10-08 02:47:54.286 UTC [1452] postgres@postgres LOG:  statement: create role service_role        nologin noinherit bypassrls;755vm-test-run-index_advisor> server # [  131.901957] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE756vm-test-run-index_advisor> server # [  131.905870] postgres[1452]: ::1 2026-10-08 02:47:54.297 UTC [1452] postgres@postgres LOG:  statement: create user authenticator noinherit;757vm-test-run-index_advisor> server # [  131.912061] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: CREATE ROLE758vm-test-run-index_advisor> server # [  131.914348] postgres[1452]: ::1 2026-10-08 02:47:54.307 UTC [1452] postgres@postgres LOG:  statement: grant anon              to authenticator;759vm-test-run-index_advisor> server # [  131.925047] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT ROLE760vm-test-run-index_advisor> server # [  131.927185] postgres[1452]: ::1 2026-10-08 02:47:54.317 UTC [1452] postgres@postgres LOG:  statement: grant authenticated     to authenticator;761vm-test-run-index_advisor> server # [  131.941450] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT ROLE762vm-test-run-index_advisor> server # [  131.942412] postgres[1452]: ::1 2026-10-08 02:47:54.334 UTC [1452] postgres@postgres LOG:  statement: grant service_role      to authenticator;763vm-test-run-index_advisor> server # [  131.950645] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT ROLE764vm-test-run-index_advisor> server # [  131.952663] postgres[1452]: ::1 2026-10-08 02:47:54.346 UTC [1452] postgres@postgres LOG:  statement: grant supabase_admin    to authenticator;765vm-test-run-index_advisor> server # [  131.964402] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT ROLE766vm-test-run-index_advisor> server # [  131.966688] postgres[1452]: ::1 2026-10-08 02:47:54.358 UTC [1452] postgres@postgres LOG:  statement: grant usage                     on schema public to postgres, anon, authenticated, service_role;767vm-test-run-index_advisor> server # [  131.980832] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT768vm-test-run-index_advisor> server # [  131.986701] postgres[1452]: ::1 2026-10-08 02:47:54.379 UTC [1452] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;769vm-test-run-index_advisor> server # [  132.014512] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER DEFAULT PRIVILEGES770vm-test-run-index_advisor> server # [  132.015658] postgres[1452]: ::1 2026-10-08 02:47:54.408 UTC [1452] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;771vm-test-run-index_advisor> server # [  132.031659] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER DEFAULT PRIVILEGES772vm-test-run-index_advisor> server # [  132.035448] postgres[1452]: ::1 2026-10-08 02:47:54.428 UTC [1452] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;773vm-test-run-index_advisor> server # [  132.050275] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER DEFAULT PRIVILEGES774vm-test-run-index_advisor> server # [  132.051709] postgres[1452]: ::1 2026-10-08 02:47:54.444 UTC [1452] postgres@postgres LOG:  statement: grant usage                     on schema extensions to postgres, anon, authenticated, service_role;775vm-test-run-index_advisor> server # [  132.060085] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: GRANT776vm-test-run-index_advisor> server # [  132.062783] postgres[1452]: ::1 2026-10-08 02:47:54.455 UTC [1452] postgres@postgres LOG:  statement: alter user supabase_admin SET search_path TO public, extensions;777vm-test-run-index_advisor> server # [  132.070940] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER ROLE778vm-test-run-index_advisor> server # [  132.072774] postgres[1452]: ::1 2026-10-08 02:47:54.465 UTC [1452] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all779vm-test-run-index_advisor> server # [  132.074870] postgres[1452]: 	    on sequences to postgres, anon, authenticated, service_role;780vm-test-run-index_advisor> server # [  132.083275] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER DEFAULT PRIVILEGES781vm-test-run-index_advisor> server # [  132.089975] postgres[1452]: ::1 2026-10-08 02:47:54.476 UTC [1452] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all782vm-test-run-index_advisor> server # [  132.095033] postgres[1452]: 	    on tables to postgres, anon, authenticated, service_role;783vm-test-run-index_advisor> server # [  132.097808] postgres[1452]: ::1 2026-10-08 02:47:54.488 UTC [1452] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all784vm-test-run-index_advisor> server # [  132.099069] postgres[1452]: 	    on functions to postgres, anon, authenticated, service_role;785vm-test-run-index_advisor> server # [  132.099598] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER DEFAULT PRIVILEGES786vm-test-run-index_advisor> server # [  132.109011] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER DEFAULT PRIVILEGES787vm-test-run-index_advisor> server # [  132.109983] postgres[1452]: ::1 2026-10-08 02:47:54.503 UTC [1452] postgres@postgres LOG:  statement: alter role anon set statement_timeout = '3s';788vm-test-run-index_advisor> server # [  132.119021] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER ROLE789vm-test-run-index_advisor> server # [  132.124368] postgres[1452]: ::1 2026-10-08 02:47:54.512 UTC [1452] postgres@postgres LOG:  statement: alter role authenticated set statement_timeout = '8s';790vm-test-run-index_advisor> server # [  132.133384] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1451]: ALTER ROLE791vm-test-run-index_advisor> server # [  132.190370] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000001-auth-schema.sql792vm-test-run-index_advisor> server # [  132.582873] postgres[1484]: ::1 2026-10-08 02:47:54.955 UTC [1484] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;793vm-test-run-index_advisor> server # [  132.591839] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE SCHEMA794vm-test-run-index_advisor> server # [  132.595614] postgres[1484]: ::1 2026-10-08 02:47:54.984 UTC [1484] postgres@postgres LOG:  statement: CREATE TABLE auth.users (795vm-test-run-index_advisor> server # [  132.601567] postgres[1484]: 	    instance_id uuid NULL,796vm-test-run-index_advisor> server # [  132.601918] postgres[1484]: 	    id uuid NOT NULL UNIQUE,797vm-test-run-index_advisor> server # [  132.602178] postgres[1484]: 	    aud varchar(255) NULL,798vm-test-run-index_advisor> server # [  132.602574] postgres[1484]: 	    "role" varchar(255) NULL,799vm-test-run-index_advisor> server # [  132.602856] postgres[1484]: 	    email varchar(255) NULL UNIQUE,800vm-test-run-index_advisor> server # [  132.609117] postgres[1484]: 	    encrypted_password varchar(255) NULL,801vm-test-run-index_advisor> server # [  132.610262] postgres[1484]: 	    confirmed_at timestamptz NULL,802vm-test-run-index_advisor> server # [  132.610753] postgres[1484]: 	    invited_at timestamptz NULL,803vm-test-run-index_advisor> server # [  132.617822] postgres[1484]: 	    confirmation_token varchar(255) NULL,804vm-test-run-index_advisor> server # [  132.619691] postgres[1484]: 	    confirmation_sent_at timestamptz NULL,805vm-test-run-index_advisor> server # [  132.621720] postgres[1484]: 	    recovery_token varchar(255) NULL,806vm-test-run-index_advisor> server # [  132.622913] postgres[1484]: 	    recovery_sent_at timestamptz NULL,807vm-test-run-index_advisor> server # [  132.623846] postgres[1484]: 	    email_change_token varchar(255) NULL,808vm-test-run-index_advisor> server # [  132.629830] postgres[1484]: 	    email_change varchar(255) NULL,809vm-test-run-index_advisor> server # [  132.630313] postgres[1484]: 	    email_change_sent_at timestamptz NULL,810vm-test-run-index_advisor> server # [  132.630627] postgres[1484]: 	    last_sign_in_at timestamptz NULL,811vm-test-run-index_advisor> server # [  132.631335] postgres[1484]: 	    raw_app_meta_data jsonb NULL,812vm-test-run-index_advisor> server # [  132.631656] postgres[1484]: 	    raw_user_meta_data jsonb NULL,813vm-test-run-index_advisor> server # [  132.631909] postgres[1484]: 	    is_super_admin bool NULL,814vm-test-run-index_advisor> server # [  132.637516] postgres[1484]: 	    created_at timestamptz NULL,815vm-test-run-index_advisor> server # [  132.638255] postgres[1484]: 	    updated_at timestamptz NULL,816vm-test-run-index_advisor> server # [  132.639379] postgres[1484]: 	    CONSTRAINT users_pkey PRIMARY KEY (id)817vm-test-run-index_advisor> server # [  132.640037] postgres[1484]: 	);818vm-test-run-index_advisor> server # [  132.807327] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE TABLE819vm-test-run-index_advisor> server # [  132.813383] postgres[1484]: ::1 2026-10-08 02:47:55.205 UTC [1484] postgres@postgres LOG:  statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);820vm-test-run-index_advisor> server # [  132.888035] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE INDEX821vm-test-run-index_advisor> server # [  132.905988] postgres[1484]: ::1 2026-10-08 02:47:55.281 UTC [1484] postgres@postgres LOG:  statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);822vm-test-run-index_advisor> server # [  132.917477] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE INDEX823vm-test-run-index_advisor> server # [  132.918424] postgres[1484]: ::1 2026-10-08 02:47:55.311 UTC [1484] postgres@postgres LOG:  statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';824vm-test-run-index_advisor> server # [  132.926095] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: COMMENT825vm-test-run-index_advisor> server # [  132.927536] postgres[1484]: ::1 2026-10-08 02:47:55.320 UTC [1484] postgres@postgres LOG:  statement: CREATE TABLE auth.refresh_tokens (826vm-test-run-index_advisor> server # [  132.937288] postgres[1484]: 	    instance_id uuid NULL,827vm-test-run-index_advisor> server # [  132.937987] postgres[1484]: 	    id bigserial NOT NULL,828vm-test-run-index_advisor> server # [  132.938292] postgres[1484]: 	    "token" varchar(255) NULL,829vm-test-run-index_advisor> server # [  132.938730] postgres[1484]: 	    user_id varchar(255) NULL,830vm-test-run-index_advisor> server # [  132.939049] postgres[1484]: 	    revoked bool NULL,831vm-test-run-index_advisor> server # [  132.947735] postgres[1484]: 	    created_at timestamptz NULL,832vm-test-run-index_advisor> server # [  132.953704] postgres[1484]: 	    updated_at timestamptz NULL,833vm-test-run-index_advisor> server # [  132.954495] postgres[1484]: 	    CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)834vm-test-run-index_advisor> server # [  132.955817] postgres[1484]: 	);835vm-test-run-index_advisor> server # [  133.069916] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE TABLE836vm-test-run-index_advisor> server # [  133.070965] postgres[1484]: ::1 2026-10-08 02:47:55.461 UTC [1484] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);837vm-test-run-index_advisor> server # [  133.122060] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE INDEX838vm-test-run-index_advisor> server # [  133.123541] postgres[1484]: ::1 2026-10-08 02:47:55.505 UTC [1484] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);839vm-test-run-index_advisor> server # [  133.191010] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE INDEX840vm-test-run-index_advisor> server # [  133.198302] postgres[1484]: ::1 2026-10-08 02:47:55.588 UTC [1484] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);841vm-test-run-index_advisor> server # [  133.242718] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE INDEX842vm-test-run-index_advisor> server # [  133.248797] postgres[1484]: ::1 2026-10-08 02:47:55.639 UTC [1484] postgres@postgres LOG:  statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';843vm-test-run-index_advisor> server # [  133.274997] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: COMMENT844vm-test-run-index_advisor> server # [  133.287755] postgres[1484]: ::1 2026-10-08 02:47:55.680 UTC [1484] postgres@postgres LOG:  statement: CREATE TABLE auth.instances (845vm-test-run-index_advisor> server # [  133.290765] postgres[1484]: 	    id uuid NOT NULL,846vm-test-run-index_advisor> server # [  133.291853] postgres[1484]: 	    uuid uuid NULL,847vm-test-run-index_advisor> server # [  133.292318] postgres[1484]: 	    raw_base_config text NULL,848vm-test-run-index_advisor> server # [  133.302590] postgres[1484]: 	    created_at timestamptz NULL,849vm-test-run-index_advisor> server # [  133.303462] postgres[1484]: 	    updated_at timestamptz NULL,850vm-test-run-index_advisor> server # [  133.309100] postgres[1484]: 	    CONSTRAINT instances_pkey PRIMARY KEY (id)851vm-test-run-index_advisor> server # [  133.309631] postgres[1484]: 	);852vm-test-run-index_advisor> server # [  133.496858] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE TABLE853vm-test-run-index_advisor> server # [  133.501129] postgres[1484]: ::1 2026-10-08 02:47:55.892 UTC [1484] postgres@postgres LOG:  statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';854vm-test-run-index_advisor> server # [  133.519745] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: COMMENT855vm-test-run-index_advisor> server # [  133.526239] postgres[1484]: ::1 2026-10-08 02:47:55.913 UTC [1484] postgres@postgres LOG:  statement: CREATE TABLE auth.audit_log_entries (856vm-test-run-index_advisor> server # [  133.527475] postgres[1484]: 	    instance_id uuid NULL,857vm-test-run-index_advisor> server # [  133.528617] postgres[1484]: 	    id uuid NOT NULL,858vm-test-run-index_advisor> server # [  133.528936] postgres[1484]: 	    payload json NULL,859vm-test-run-index_advisor> server # [  133.534695] postgres[1484]: 	    created_at timestamptz NULL,860vm-test-run-index_advisor> server # [  133.540883] postgres[1484]: 	    CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)861vm-test-run-index_advisor> server # [  133.542235] postgres[1484]: 	);862vm-test-run-index_advisor> server # [  133.746488] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE TABLE863vm-test-run-index_advisor> server # [  133.775762] postgres[1484]: ::1 2026-10-08 02:47:56.121 UTC [1484] postgres@postgres LOG:  statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);864vm-test-run-index_advisor> server # [  133.788988] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE INDEX865vm-test-run-index_advisor> server # [  133.793440] postgres[1484]: ::1 2026-10-08 02:47:56.183 UTC [1484] postgres@postgres LOG:  statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';866vm-test-run-index_advisor> server # [  133.805076] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: COMMENT867vm-test-run-index_advisor> server # [  133.806877] postgres[1484]: ::1 2026-10-08 02:47:56.197 UTC [1484] postgres@postgres LOG:  statement: CREATE TABLE auth.schema_migrations (868vm-test-run-index_advisor> server # [  133.809003] postgres[1484]: 	    "version" varchar(255) NOT NULL,869vm-test-run-index_advisor> server # [  133.821884] postgres[1484]: 	    CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")870vm-test-run-index_advisor> server # [  133.822736] postgres[1484]: 	);871vm-test-run-index_advisor> server # [  133.856839] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE TABLE872vm-test-run-index_advisor> server # [  133.859346] postgres[1484]: ::1 2026-10-08 02:47:56.248 UTC [1484] postgres@postgres LOG:  statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';873vm-test-run-index_advisor> server # [  133.869976] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: COMMENT874vm-test-run-index_advisor> server # [  133.897197] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: INSERT 0 7875vm-test-run-index_advisor> server # [  133.898139] postgres[1484]: ::1 2026-10-08 02:47:56.288 UTC [1484] postgres@postgres LOG:  statement: create or replace function auth.uid() returns uuid as $$876vm-test-run-index_advisor> server # [  133.899752] postgres[1484]: 	  select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;877vm-test-run-index_advisor> server # [  133.905781] postgres[1484]: 	$$ language sql stable;878vm-test-run-index_advisor> server # [  133.925506] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE FUNCTION879vm-test-run-index_advisor> server # [  133.928077] postgres[1484]: ::1 2026-10-08 02:47:56.315 UTC [1484] postgres@postgres LOG:  statement: create or replace function auth.role() returns text as $$880vm-test-run-index_advisor> server # [  133.935698] postgres[1484]: 	  select nullif(current_setting('request.jwt.claim.role', true), '')::text;881vm-test-run-index_advisor> server # [  133.938297] postgres[1484]: 	$$ language sql stable;882vm-test-run-index_advisor> server # [  133.950392] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE FUNCTION883vm-test-run-index_advisor> server # [  133.959229] postgres[1484]: ::1 2026-10-08 02:47:56.339 UTC [1484] postgres@postgres LOG:  statement: create or replace function auth.email() returns text as $$884vm-test-run-index_advisor> server # [  133.962855] postgres[1484]: 	  select nullif(current_setting('request.jwt.claim.email', true), '')::text;885vm-test-run-index_advisor> server # [  133.974159] postgres[1484]: 	$$ language sql stable;886vm-test-run-index_advisor> server # [  133.975019] postgres[1484]: ::1 2026-10-08 02:47:56.361 UTC [1484] postgres@postgres LOG:  statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;887vm-test-run-index_advisor> server # [  133.983850] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE FUNCTION888vm-test-run-index_advisor> server # [  133.991001] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: GRANT889vm-test-run-index_advisor> server # [  133.993828] postgres[1484]: ::1 2026-10-08 02:47:56.378 UTC [1484] postgres@postgres LOG:  statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;890vm-test-run-index_advisor> server # [  134.003365] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: CREATE ROLE891vm-test-run-index_advisor> server # [  134.014095] postgres[1484]: ::1 2026-10-08 02:47:56.396 UTC [1484] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;892vm-test-run-index_advisor> server # [  134.020101] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: GRANT893vm-test-run-index_advisor> server # [  134.021739] postgres[1484]: ::1 2026-10-08 02:47:56.415 UTC [1484] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;894vm-test-run-index_advisor> server # [  134.054094] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: GRANT895vm-test-run-index_advisor> server # [  134.060580] postgres[1484]: ::1 2026-10-08 02:47:56.447 UTC [1484] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;896vm-test-run-index_advisor> server # [  134.074792] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: GRANT897vm-test-run-index_advisor> server # [  134.077371] postgres[1484]: ::1 2026-10-08 02:47:56.470 UTC [1484] postgres@postgres LOG:  statement: ALTER USER supabase_auth_admin SET search_path = "auth";898vm-test-run-index_advisor> server # [  134.090915] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: ALTER ROLE899vm-test-run-index_advisor> server # [  134.093375] postgres[1484]: ::1 2026-10-08 02:47:56.485 UTC [1484] postgres@postgres LOG:  statement: ALTER table "auth".users OWNER TO supabase_auth_admin;900vm-test-run-index_advisor> server # [  134.115314] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: ALTER TABLE901vm-test-run-index_advisor> server # [  134.122910] postgres[1484]: ::1 2026-10-08 02:47:56.511 UTC [1484] postgres@postgres LOG:  statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;902vm-test-run-index_advisor> server # [  134.133605] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: ALTER TABLE903vm-test-run-index_advisor> server # [  134.137338] postgres[1484]: ::1 2026-10-08 02:47:56.528 UTC [1484] postgres@postgres LOG:  statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;904vm-test-run-index_advisor> server # [  134.147075] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: ALTER TABLE905vm-test-run-index_advisor> server # [  134.152036] postgres[1484]: ::1 2026-10-08 02:47:56.543 UTC [1484] postgres@postgres LOG:  statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;906vm-test-run-index_advisor> server # [  134.165321] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: ALTER TABLE907vm-test-run-index_advisor> server # [  134.166195] postgres[1484]: ::1 2026-10-08 02:47:56.555 UTC [1484] postgres@postgres LOG:  statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;908vm-test-run-index_advisor> server # [  134.171389] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1481]: ALTER TABLE909vm-test-run-index_advisor> server # [  134.217551] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000002-storage-schema.sql910vm-test-run-index_advisor> server # [  134.458733] postgres[1508]: ::1 2026-10-08 02:47:56.846 UTC [1508] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;911vm-test-run-index_advisor> server # [  134.476763] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1507]: CREATE SCHEMA912vm-test-run-index_advisor> server # [  134.478146] postgres[1508]: ::1 2026-10-08 02:47:56.868 UTC [1508] postgres@postgres LOG:  statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;913vm-test-run-index_advisor> server # [  134.489160] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1507]: CREATE ROLE914vm-test-run-index_advisor> server # [  134.490084] postgres[1508]: ::1 2026-10-08 02:47:56.879 UTC [1508] postgres@postgres LOG:  statement: ALTER USER supabase_storage_admin SET search_path = "storage";915vm-test-run-index_advisor> server # [  134.495764] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1507]: ALTER ROLE916vm-test-run-index_advisor> server # [  134.501177] postgres[1508]: ::1 2026-10-08 02:47:56.891 UTC [1508] postgres@postgres LOG:  statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;917vm-test-run-index_advisor> server # [  134.507604] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1507]: GRANT918vm-test-run-index_advisor> server # [  134.539302] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1507]: DO919vm-test-run-index_advisor> server # [  134.572067] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000003-post-setup.sql920vm-test-run-index_advisor> server # [  134.784092] postgres[1510]: ::1 2026-10-08 02:47:57.173 UTC [1510] postgres@postgres LOG:  statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;921vm-test-run-index_advisor> server # [  134.805679] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: ALTER ROLE922vm-test-run-index_advisor> server # [  134.807049] postgres[1510]: ::1 2026-10-08 02:47:57.192 UTC [1510] postgres@postgres LOG:  statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;923vm-test-run-index_advisor> server # [  134.811559] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: ALTER ROLE924vm-test-run-index_advisor> server # [  134.813149] postgres[1510]: ::1 2026-10-08 02:47:57.203 UTC [1510] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()925vm-test-run-index_advisor> server # [  134.813935] postgres[1510]: 	RETURNS event_trigger926vm-test-run-index_advisor> server # [  134.814790] postgres[1510]: 	LANGUAGE plpgsql927vm-test-run-index_advisor> server # [  134.815358] postgres[1510]: 	AS $$928vm-test-run-index_advisor> server # [  134.815659] postgres[1510]: 	DECLARE929vm-test-run-index_advisor> server # [  134.816485] postgres[1510]: 	  schema_is_cron bool;930vm-test-run-index_advisor> server # [  134.821834] postgres[1510]: 	BEGIN931vm-test-run-index_advisor> server # [  134.822363] postgres[1510]: 	  schema_is_cron = (932vm-test-run-index_advisor> server # [  134.824017] postgres[1510]: 	    SELECT n.nspname = 'cron'933vm-test-run-index_advisor> server # [  134.829364] postgres[1510]: 	    FROM pg_event_trigger_ddl_commands() AS ev934vm-test-run-index_advisor> server # [  134.830051] postgres[1510]: 	    LEFT JOIN pg_catalog.pg_namespace AS n935vm-test-run-index_advisor> server # [  134.830847] postgres[1510]: 	      ON ev.objid = n.oid936vm-test-run-index_advisor> server # [  134.831430] postgres[1510]: 	  );937vm-test-run-index_advisor> server # [  134.831978] postgres[1510]:938vm-test-run-index_advisor> server # [  134.833103] postgres[1510]: 	  IF schema_is_cron939vm-test-run-index_advisor> server # [  134.839283] postgres[1510]: 	  THEN940vm-test-run-index_advisor> server # [  134.839918] postgres[1510]: 	    grant usage on schema cron to postgres with grant option;941vm-test-run-index_advisor> server # [  134.840399] postgres[1510]:942vm-test-run-index_advisor> server # [  134.841489] postgres[1510]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;943vm-test-run-index_advisor> server # [  134.842592] postgres[1510]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;944vm-test-run-index_advisor> server # [  134.846385] postgres[1510]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;945vm-test-run-index_advisor> server # [  134.847278] postgres[1510]:946vm-test-run-index_advisor> server # [  134.853507] postgres[1510]: 	    alter default privileges for user supabase_admin in schema cron grant all947vm-test-run-index_advisor> server # [  134.854205] postgres[1510]: 	        on sequences to postgres with grant option;948vm-test-run-index_advisor> server # [  134.854562] postgres[1510]: 	    alter default privileges for user supabase_admin in schema cron grant all949vm-test-run-index_advisor> server # [  134.855468] postgres[1510]: 	        on tables to postgres with grant option;950vm-test-run-index_advisor> server # [  134.855805] postgres[1510]: 	    alter default privileges for user supabase_admin in schema cron grant all951vm-test-run-index_advisor> server # [  134.861681] postgres[1510]: 	        on functions to postgres with grant option;952vm-test-run-index_advisor> server # [  134.862365] postgres[1510]:953vm-test-run-index_advisor> server # [  134.862666] postgres[1510]: 	    grant all privileges on all tables in schema cron to postgres with grant option;954vm-test-run-index_advisor> server # [  134.863494] postgres[1510]:955vm-test-run-index_advisor> server # [  134.863807] postgres[1510]: 	  END IF;956vm-test-run-index_advisor> server # [  134.865537] postgres[1510]:957vm-test-run-index_advisor> server # [  134.866293] postgres[1510]: 	END;958vm-test-run-index_advisor> server # [  134.873444] postgres[1510]: 	$$;959vm-test-run-index_advisor> server # [  134.874340] postgres[1510]: ::1 2026-10-08 02:47:57.244 UTC [1510] postgres@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')960vm-test-run-index_advisor> server # [  134.875313] postgres[1510]: 	EXECUTE PROCEDURE extensions.grant_pg_cron_access();961vm-test-run-index_advisor> server # [  134.875657] postgres[1510]: ::1 2026-10-08 02:47:57.265 UTC [1510] postgres@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';962vm-test-run-index_advisor> server # [  134.892026] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: CREATE FUNCTION963vm-test-run-index_advisor> server # [  134.893653] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: CREATE EVENT TRIGGER964vm-test-run-index_advisor> server # [  134.894302] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: COMMENT965vm-test-run-index_advisor> server # [  134.895298] postgres[1510]: ::1 2026-10-08 02:47:57.276 UTC [1510] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()966vm-test-run-index_advisor> server # [  134.901946] postgres[1510]: 	RETURNS event_trigger967vm-test-run-index_advisor> server # [  134.907738] postgres[1510]: 	LANGUAGE plpgsql968vm-test-run-index_advisor> server # [  134.909634] postgres[1510]: 	AS $$969vm-test-run-index_advisor> server # [  134.910229] postgres[1510]: 	BEGIN970vm-test-run-index_advisor> server # [  134.910729] postgres[1510]: 	  IF EXISTS (971vm-test-run-index_advisor> server # [  134.911001] postgres[1510]: 	    SELECT 1972vm-test-run-index_advisor> server # [  134.914820] postgres[1510]: 	    FROM pg_event_trigger_ddl_commands() AS ev973vm-test-run-index_advisor> server # [  134.915560] postgres[1510]: 	    JOIN pg_extension AS ext974vm-test-run-index_advisor> server # [  134.916229] postgres[1510]: 	    ON ev.objid = ext.oid975vm-test-run-index_advisor> server # [  134.917180] postgres[1510]: 	    WHERE ext.extname = 'pg_net'976vm-test-run-index_advisor> server # [  134.919800] postgres[1510]: 	  )977vm-test-run-index_advisor> server # [  134.920382] postgres[1510]: 	  THEN978vm-test-run-index_advisor> server # [  134.923147] postgres[1510]: 	    IF NOT EXISTS (979vm-test-run-index_advisor> server # [  134.923933] postgres[1510]: 	      SELECT 1980vm-test-run-index_advisor> server # [  134.925186] postgres[1510]: 	      FROM pg_roles981vm-test-run-index_advisor> server # [  134.925578] postgres[1510]: 	      WHERE rolname = 'supabase_functions_admin'982vm-test-run-index_advisor> server # [  134.926982] postgres[1510]: 	    )983vm-test-run-index_advisor> server # [  134.927425] postgres[1510]: 	    THEN984vm-test-run-index_advisor> server # [  134.927902] postgres[1510]: 	      CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;985vm-test-run-index_advisor> server # [  134.929088] postgres[1510]: 	    END IF;986vm-test-run-index_advisor> server # [  134.930822] postgres[1510]:987vm-test-run-index_advisor> server # [  134.934135] postgres[1510]: 	    GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;988vm-test-run-index_advisor> server # [  134.935447] postgres[1510]:989vm-test-run-index_advisor> server # [  134.936728] postgres[1510]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;990vm-test-run-index_advisor> server # [  134.937623] postgres[1510]: 	    ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;991vm-test-run-index_advisor> server # [  134.938017] postgres[1510]:992vm-test-run-index_advisor> server # [  134.938518] postgres[1510]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;993vm-test-run-index_advisor> server # [  134.939363] postgres[1510]: 	    ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;994vm-test-run-index_advisor> server # [  134.940044] postgres[1510]:995vm-test-run-index_advisor> server # [  134.941484] postgres[1510]: 	    REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;996vm-test-run-index_advisor> server # [  134.942224] postgres[1510]: 	    REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;997vm-test-run-index_advisor> server # [  134.946212] postgres[1510]:998vm-test-run-index_advisor> server # [  134.948716] postgres[1510]: 	    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;999vm-test-run-index_advisor> server # [  134.950531] postgres[1510]: 	    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 # [  134.951281] postgres[1510]: 	  END IF;1001vm-test-run-index_advisor> server # [  134.952539] postgres[1510]: 	END;1002vm-test-run-index_advisor> server # [  134.953780] postgres[1510]: 	$$;1003vm-test-run-index_advisor> server # [  134.954933] postgres[1510]: ::1 2026-10-08 02:47:57.291 UTC [1510] postgres@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1004vm-test-run-index_advisor> server # [  134.955915] postgres[1510]: ::1 2026-10-08 02:47:57.334 UTC [1510] postgres@postgres LOG:  statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1005vm-test-run-index_advisor> server # [  134.957981] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: CREATE FUNCTION1006vm-test-run-index_advisor> server # [  134.958853] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: COMMENT1007vm-test-run-index_advisor> server # [  134.959308] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: DO1008vm-test-run-index_advisor> server # [  134.959599] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: CREATE ROLE1009vm-test-run-index_advisor> server # [  134.959961] postgres[1510]: ::1 2026-10-08 02:47:57.349 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1010vm-test-run-index_advisor> server # [  134.970127] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1011vm-test-run-index_advisor> server # [  134.972892] postgres[1510]: ::1 2026-10-08 02:47:57.365 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1012vm-test-run-index_advisor> server # [  134.981363] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1013vm-test-run-index_advisor> server # [  134.982854] postgres[1510]: ::1 2026-10-08 02:47:57.376 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1014vm-test-run-index_advisor> server # [  134.997861] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1015vm-test-run-index_advisor> server # [  135.004851] postgres[1510]: ::1 2026-10-08 02:47:57.394 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1016vm-test-run-index_advisor> server # [  135.024585] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1017vm-test-run-index_advisor> server # [  135.026544] postgres[1510]: ::1 2026-10-08 02:47:57.417 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1018vm-test-run-index_advisor> server # [  135.036684] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1019vm-test-run-index_advisor> server # [  135.039046] postgres[1510]: ::1 2026-10-08 02:47:57.427 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1020vm-test-run-index_advisor> server # [  135.044741] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1021vm-test-run-index_advisor> server # [  135.051779] postgres[1510]: ::1 2026-10-08 02:47:57.441 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1022vm-test-run-index_advisor> server # [  135.060384] postgres[1510]: ::1 2026-10-08 02:47:57.450 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1023vm-test-run-index_advisor> server # [  135.061668] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1024vm-test-run-index_advisor> server # [  135.086061] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1025vm-test-run-index_advisor> server # [  135.087912] postgres[1510]: ::1 2026-10-08 02:47:57.480 UTC [1510] postgres@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1026vm-test-run-index_advisor> server # [  135.161388] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: GRANT1027vm-test-run-index_advisor> server # [  135.222035] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1509]: DO1028vm-test-run-index_advisor> server # [  135.265071] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running pgbouncer auth schema...1029vm-test-run-index_advisor> server # [  135.714996] postgres[1516]: ::1 2026-10-08 02:47:58.055 UTC [1516] postgres@postgres LOG:  statement: CREATE USER pgbouncer;1030vm-test-run-index_advisor> server # [  135.739784] postgres[1516]: ::1 2026-10-08 02:47:58.091 UTC [1516] postgres@postgres LOG:  statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1031vm-test-run-index_advisor> server # [  135.771562] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1515]: CREATE ROLE1032vm-test-run-index_advisor> server # [  135.784806] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1515]: REVOKE1033vm-test-run-index_advisor> server # [  135.807802] postgres[1516]: ::1 2026-10-08 02:47:58.146 UTC [1516] postgres@postgres LOG:  statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1034vm-test-run-index_advisor> server # [  135.826628] postgres[1516]: ::1 2026-10-08 02:47:58.212 UTC [1516] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1035vm-test-run-index_advisor> server # [  135.849475] postgres[1516]: 	RETURNS TABLE(username TEXT, password TEXT) AS1036vm-test-run-index_advisor> server # [  135.851849] postgres[1516]: 	$$1037vm-test-run-index_advisor> server # [  135.862490] postgres[1516]: 	BEGIN1038vm-test-run-index_advisor> server # [  135.870663] postgres[1516]: 	    RAISE WARNING 'PgBouncer auth request: %', p_usename;1039vm-test-run-index_advisor> server # [  135.876685] postgres[1516]:1040vm-test-run-index_advisor> server # [  135.891196] postgres[1516]: 	    RETURN QUERY1041vm-test-run-index_advisor> server # [  135.897885] postgres[1516]: 	    SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1042vm-test-run-index_advisor> server # [  135.900140] postgres[1516]: 	    WHERE usename = p_usename;1043vm-test-run-index_advisor> server # [  135.910072] postgres[1516]: 	END;1044vm-test-run-index_advisor> server # [  135.911470] postgres[1516]: 	$$ LANGUAGE plpgsql1045vm-test-run-index_advisor> server # [  135.918500] postgres[1516]: 	SET search_path = ''1046vm-test-run-index_advisor> server # [  135.930820] postgres[1516]: 	SECURITY DEFINER;1047vm-test-run-index_advisor> server # [  135.933377] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1515]: CREATE SCHEMA1048vm-test-run-index_advisor> server # [  135.947993] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1515]: CREATE FUNCTION1049vm-test-run-index_advisor> server # [  135.962685] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1515]: REVOKE1050vm-test-run-index_advisor> server # [  135.978788] postgres[1516]: ::1 2026-10-08 02:47:58.279 UTC [1516] postgres@postgres LOG:  statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1051vm-test-run-index_advisor> server # [  136.021288] postgres[1516]: ::1 2026-10-08 02:47:58.306 UTC [1516] postgres@postgres LOG:  statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1052vm-test-run-index_advisor> server # [  136.041230] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1515]: GRANT1053vm-test-run-index_advisor> server # [  136.043456] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running stat extension...1054vm-test-run-index_advisor> server # [  136.453540] postgres[1529]: ::1 2026-10-08 02:47:58.825 UTC [1529] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT exists extensions;1055vm-test-run-index_advisor> server # [  136.467999] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1524]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE:  schema "extensions" already exists, skipping1056vm-test-run-index_advisor> server # [  136.506723] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1524]: CREATE SCHEMA1057vm-test-run-index_advisor> server # [  136.518791] postgres[1529]: ::1 2026-10-08 02:47:58.907 UTC [1529] postgres@postgres LOG:  statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1058vm-test-run-index_advisor> server # [  137.826217] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1524]: CREATE EXTENSION1059vm-test-run-index_advisor> server # [  137.881201] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/10000000000000_demote-postgres.sql1060vm-test-run-index_advisor> server # [  138.098347] postgres[1544]: ::1 2026-10-08 02:48:00.481 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON DATABASE postgres TO postgres;1061vm-test-run-index_advisor> server # [  138.110598] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1062vm-test-run-index_advisor> server # [  138.111718] postgres[1544]: ::1 2026-10-08 02:48:00.501 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON SCHEMA auth TO postgres;1063vm-test-run-index_advisor> server # [  138.121333] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1064vm-test-run-index_advisor> server # [  138.123858] postgres[1544]: ::1 2026-10-08 02:48:00.517 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON SCHEMA extensions TO postgres;1065vm-test-run-index_advisor> server # [  138.133201] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1066vm-test-run-index_advisor> server # [  138.133998] postgres[1544]: ::1 2026-10-08 02:48:00.525 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1067vm-test-run-index_advisor> server # [  138.145148] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1068vm-test-run-index_advisor> server # [  138.146117] postgres[1544]: ::1 2026-10-08 02:48:00.536 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1069vm-test-run-index_advisor> server # [  138.153522] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1070vm-test-run-index_advisor> server # [  138.156905] postgres[1544]: ::1 2026-10-08 02:48:00.549 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1071vm-test-run-index_advisor> server # [  138.166340] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1072vm-test-run-index_advisor> server # [  138.169920] postgres[1544]: ::1 2026-10-08 02:48:00.561 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1073vm-test-run-index_advisor> server # [  138.173736] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1074vm-test-run-index_advisor> server # [  138.177015] postgres[1544]: ::1 2026-10-08 02:48:00.569 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1075vm-test-run-index_advisor> server # [  138.190949] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1076vm-test-run-index_advisor> server # [  138.196719] postgres[1544]: ::1 2026-10-08 02:48:00.585 UTC [1544] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1077vm-test-run-index_advisor> server # [  138.223644] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: GRANT1078vm-test-run-index_advisor> server # [  138.247867] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: DO1079vm-test-run-index_advisor> server # [  138.251726] postgres[1544]: ::1 2026-10-08 02:48:00.643 UTC [1544] supabase_admin@postgres LOG:  statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1080vm-test-run-index_advisor> server # [  138.261941] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1543]: ALTER ROLE1081vm-test-run-index_advisor> server # [  138.309551] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211115181400_update-auth-permissions.sql1082vm-test-run-index_advisor> server # [  138.580272] postgres[1546]: ::1 2026-10-08 02:48:00.969 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1083vm-test-run-index_advisor> server # [  138.601994] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1084vm-test-run-index_advisor> server # [  138.603297] postgres[1546]: ::1 2026-10-08 02:48:00.991 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1085vm-test-run-index_advisor> server # [  138.645987] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1086vm-test-run-index_advisor> server # [  138.649420] postgres[1546]: ::1 2026-10-08 02:48:01.026 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1087vm-test-run-index_advisor> server # [  138.663009] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1088vm-test-run-index_advisor> server # [  138.675749] postgres[1546]: ::1 2026-10-08 02:48:01.067 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1089vm-test-run-index_advisor> server # [  138.685370] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER TABLE1090vm-test-run-index_advisor> server # [  138.693152] postgres[1546]: ::1 2026-10-08 02:48:01.081 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1091vm-test-run-index_advisor> server # [  138.715048] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER TABLE1092vm-test-run-index_advisor> server # [  138.717717] postgres[1546]: ::1 2026-10-08 02:48:01.104 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1093vm-test-run-index_advisor> server # [  138.729637] postgres[1546]: ::1 2026-10-08 02:48:01.120 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1094vm-test-run-index_advisor> server # [  138.733562] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER TABLE1095vm-test-run-index_advisor> server # [  138.744892] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER TABLE1096vm-test-run-index_advisor> server # [  138.746014] postgres[1546]: ::1 2026-10-08 02:48:01.138 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1097vm-test-run-index_advisor> server # [  138.759138] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER TABLE1098vm-test-run-index_advisor> server # [  138.760060] postgres[1546]: ::1 2026-10-08 02:48:01.153 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT USAGE ON SCHEMA auth TO postgres;1099vm-test-run-index_advisor> server # [  138.769785] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1100vm-test-run-index_advisor> server # [  138.795123] postgres[1546]: ::1 2026-10-08 02:48:01.171 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1101vm-test-run-index_advisor> server # [  138.824697] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1102vm-test-run-index_advisor> server # [  138.837858] postgres[1546]: ::1 2026-10-08 02:48:01.222 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1103vm-test-run-index_advisor> server # [  138.855298] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1104vm-test-run-index_advisor> server # [  138.858492] postgres[1546]: ::1 2026-10-08 02:48:01.251 UTC [1546] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1105vm-test-run-index_advisor> server # [  138.920007] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: GRANT1106vm-test-run-index_advisor> server # [  138.932125] postgres[1546]: ::1 2026-10-08 02:48:01.315 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1107vm-test-run-index_advisor> server # [  139.016950] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER DEFAULT PRIVILEGES1108vm-test-run-index_advisor> server # [  139.019439] postgres[1546]: ::1 2026-10-08 02:48:01.406 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1109vm-test-run-index_advisor> server # [  139.049262] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER DEFAULT PRIVILEGES1110vm-test-run-index_advisor> server # [  139.052306] postgres[1546]: ::1 2026-10-08 02:48:01.439 UTC [1546] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1111vm-test-run-index_advisor> server # [  139.079552] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1545]: ALTER DEFAULT PRIVILEGES1112vm-test-run-index_advisor> server # [  139.149824] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211118015519_create-realtime-schema.sql1113vm-test-run-index_advisor> server # [  139.814423] postgres[1566]: ::1 2026-10-08 02:48:02.179 UTC [1566] supabase_admin@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS realtime;1114vm-test-run-index_advisor> server # [  139.879298] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1561]: CREATE SCHEMA1115vm-test-run-index_advisor> server # [  139.940331] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1116vm-test-run-index_advisor> server # [  140.408420] postgres[1574]: ::1 2026-10-08 02:48:02.792 UTC [1574] supabase_admin@postgres LOG:  statement: GRANT USAGE ON SCHEMA realtime TO postgres;1117vm-test-run-index_advisor> server # [  140.440258] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1570]: GRANT1118vm-test-run-index_advisor> server # [  140.449515] postgres[1574]: ::1 2026-10-08 02:48:02.824 UTC [1574] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1119vm-test-run-index_advisor> server # [  140.450725] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1570]: GRANT1120vm-test-run-index_advisor> server # [  140.452539] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1570]: GRANT1121vm-test-run-index_advisor> server # [  140.458664] postgres[1574]: ::1 2026-10-08 02:48:02.839 UTC [1574] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1122vm-test-run-index_advisor> server # [  140.459491] postgres[1574]: ::1 2026-10-08 02:48:02.848 UTC [1574] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1123vm-test-run-index_advisor> server # [  140.465026] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1570]: GRANT1124vm-test-run-index_advisor> server # [  140.519947] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211124212715_update-auth-owner.sql1125vm-test-run-index_advisor> server # [  140.786643] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1575]: DO1126vm-test-run-index_advisor> server # [  140.794829] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1575]: DO1127vm-test-run-index_advisor> server # [  140.806333] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1575]: DO1128vm-test-run-index_advisor> server # [  140.856044] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1129vm-test-run-index_advisor> server # [  141.124176] postgres[1578]: ::1 2026-10-08 02:48:03.513 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;1130vm-test-run-index_advisor> server # [  141.143503] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES1131vm-test-run-index_advisor> server # [  141.146037] postgres[1578]: ::1 2026-10-08 02:48:03.536 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;1132vm-test-run-index_advisor> server # [  141.153188] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES1133vm-test-run-index_advisor> server # [  141.156816] postgres[1578]: ::1 2026-10-08 02:48:03.548 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;1134vm-test-run-index_advisor> server # [  141.163550] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES1135vm-test-run-index_advisor> server # [  141.197286] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1136vm-test-run-index_advisor> server # [  141.582667] postgres[1583]: ::1 2026-10-08 02:48:03.960 UTC [1583] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1137vm-test-run-index_advisor> server # [  141.616867] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1579]: ALTER ROLE1138vm-test-run-index_advisor> server # [  141.678939] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1139vm-test-run-index_advisor> server # [  142.197297] postgres[1590]: ::1 2026-10-08 02:48:04.554 UTC [1590] supabase_admin@postgres LOG:  statement: drop event trigger if exists api_restart;1140vm-test-run-index_advisor> server # [  142.198364] postgres[1590]: ::1 2026-10-08 02:48:04.568 UTC [1590] supabase_admin@postgres LOG:  statement: drop function if exists extensions.notify_api_restart();1141vm-test-run-index_advisor> server # [  142.199366] postgres[1590]: ::1 2026-10-08 02:48:04.580 UTC [1590] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1142vm-test-run-index_advisor> server # [  142.205723] postgres[1590]: 	DECLARE1143vm-test-run-index_advisor> server # [  142.206392] postgres[1590]: 	  cmd record;1144vm-test-run-index_advisor> server # [  142.206834] postgres[1590]: 	BEGIN1145vm-test-run-index_advisor> server # [  142.207489] postgres[1590]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1146vm-test-run-index_advisor> server # [  142.207791] postgres[1590]: 	  LOOP1147vm-test-run-index_advisor> server # [  142.208033] postgres[1590]: 	    IF cmd.command_tag IN (1148vm-test-run-index_advisor> server # [  142.208492] postgres[1590]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1149vm-test-run-index_advisor> server # [  142.217656] postgres[1590]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1150vm-test-run-index_advisor> server # [  142.218962] postgres[1590]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1151vm-test-run-index_advisor> server # [  142.229631] postgres[1590]: 	    , 'CREATE VIEW', 'ALTER VIEW'1152vm-test-run-index_advisor> server # [  142.230339] postgres[1590]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1153vm-test-run-index_advisor> server # [  142.230689] postgres[1590]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1154vm-test-run-index_advisor> server # [  142.230950] postgres[1590]: 	    , 'CREATE TRIGGER'1155vm-test-run-index_advisor> server # [  142.231432] postgres[1590]: 	    , 'CREATE TYPE'1156vm-test-run-index_advisor> server # [  142.241815] postgres[1590]: 	    , 'CREATE RULE'1157vm-test-run-index_advisor> server # [  142.242263] postgres[1590]: 	    , 'COMMENT'1158vm-test-run-index_advisor> server # [  142.242545] postgres[1590]: 	    )1159vm-test-run-index_advisor> server # [  142.242773] postgres[1590]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1160vm-test-run-index_advisor> server # [  142.243619] postgres[1590]: 	    AND cmd.schema_name is distinct from 'pg_temp'1161vm-test-run-index_advisor> server # [  142.248836] postgres[1590]: 	    THEN1162vm-test-run-index_advisor> server # [  142.249551] postgres[1590]: 	      NOTIFY pgrst, 'reload schema';1163vm-test-run-index_advisor> server # [  142.250234] postgres[1590]: 	    END IF;1164vm-test-run-index_advisor> server # [  142.250828] postgres[1590]: 	  END LOOP;1165vm-test-run-index_advisor> server # [  142.251742] postgres[1590]: 	END; $$ LANGUAGE plpgsql;1166vm-test-run-index_advisor> server # [  142.276247] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE:  event trigger "api_restart" does not exist, skipping1167vm-test-run-index_advisor> server # [  142.282113] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: DROP EVENT TRIGGER1168vm-test-run-index_advisor> server # [  142.283728] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE:  function extensions.notify_api_restart() does not exist, skipping1169vm-test-run-index_advisor> server # [  142.292702] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: DROP FUNCTION1170vm-test-run-index_advisor> server # [  142.293722] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: CREATE FUNCTION1171vm-test-run-index_advisor> server # [  142.294054] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: CREATE FUNCTION1172vm-test-run-index_advisor> server # [  142.305608] postgres[1590]: ::1 2026-10-08 02:48:04.616 UTC [1590] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1173vm-test-run-index_advisor> server # [  142.310172] postgres[1590]: 	DECLARE1174vm-test-run-index_advisor> server # [  142.310949] postgres[1590]: 	  obj record;1175vm-test-run-index_advisor> server # [  142.311992] postgres[1590]: 	BEGIN1176vm-test-run-index_advisor> server # [  142.326597] postgres[1590]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1177vm-test-run-index_advisor> server # [  142.327900] postgres[1590]: 	  LOOP1178vm-test-run-index_advisor> server # [  142.337017] postgres[1590]: 	    IF obj.object_type IN (1179vm-test-run-index_advisor> server # [  142.346386] postgres[1590]: 	      'schema'1180vm-test-run-index_advisor> server # [  142.369911] postgres[1590]: 	    , 'table'1181vm-test-run-index_advisor> server # [  142.370964] postgres[1590]: 	    , 'foreign table'1182vm-test-run-index_advisor> server # [  142.389165] postgres[1590]: 	    , 'view'1183vm-test-run-index_advisor> server # [  142.398782] postgres[1590]: 	    , 'materialized view'1184vm-test-run-index_advisor> server # [  142.399530] postgres[1590]: 	    , 'function'1185vm-test-run-index_advisor> server # [  142.400041] postgres[1590]: 	    , 'trigger'1186vm-test-run-index_advisor> server # [  142.405139] postgres[1590]: 	    , 'type'1187vm-test-run-index_advisor> server # [  142.406374] postgres[1590]: 	    , 'rule'1188vm-test-run-index_advisor> server # [  142.420852] postgres[1590]: 	    )1189vm-test-run-index_advisor> server # [  142.429614] postgres[1590]: 	    AND obj.is_temporary IS false -- no pg_temp objects1190vm-test-run-index_advisor> server # [  142.430466] postgres[1590]: 	    THEN1191vm-test-run-index_advisor> server # [  142.434728] postgres[1590]: 	      NOTIFY pgrst, 'reload schema';1192vm-test-run-index_advisor> server # [  142.436057] postgres[1590]: 	    END IF;1193vm-test-run-index_advisor> server # [  142.436603] postgres[1590]: 	  END LOOP;1194vm-test-run-index_advisor> server # [  142.437124] postgres[1590]: 	END; $$ LANGUAGE plpgsql;1195vm-test-run-index_advisor> server # [  142.445549] postgres[1590]: ::1 2026-10-08 02:48:04.636 UTC [1590] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1196vm-test-run-index_advisor> server # [  142.447246] postgres[1590]: ::1 2026-10-08 02:48:04.655 UTC [1590] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_ddl_watch1197vm-test-run-index_advisor> server # [  142.459499] postgres[1590]: 	  ON ddl_command_end1198vm-test-run-index_advisor> server # [  142.464162] postgres[1590]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1199vm-test-run-index_advisor> server # [  142.466071] postgres[1590]: ::1 2026-10-08 02:48:04.680 UTC [1590] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1200vm-test-run-index_advisor> server # [  142.478712] postgres[1590]: ::1 2026-10-08 02:48:04.692 UTC [1590] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_drop_watch1201vm-test-run-index_advisor> server # [  142.479666] postgres[1590]: 	  ON sql_drop1202vm-test-run-index_advisor> server # [  142.481778] postgres[1590]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1203vm-test-run-index_advisor> server # [  142.482762] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE:  event trigger "pgrst_ddl_watch" does not exist, skipping1204vm-test-run-index_advisor> server # [  142.483401] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: DROP EVENT TRIGGER1205vm-test-run-index_advisor> server # [  142.484032] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: CREATE EVENT TRIGGER1206vm-test-run-index_advisor> server # [  142.484366] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE:  event trigger "pgrst_drop_watch" does not exist, skipping1207vm-test-run-index_advisor> server # [  142.487728] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: DROP EVENT TRIGGER1208vm-test-run-index_advisor> server # [  142.490384] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1585]: CREATE EVENT TRIGGER1209vm-test-run-index_advisor> server # [  142.493508] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1210vm-test-run-index_advisor> server # [  143.758802] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1594]: DO1211vm-test-run-index_advisor> server # [  143.790652] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql1212vm-test-run-index_advisor> server # [  144.144853] postgres[1614]: ::1 2026-10-08 02:48:06.527 UTC [1614] supabase_admin@postgres LOG:  statement: create schema if not exists graphql_public;1213vm-test-run-index_advisor> server # [  144.192162] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: CREATE SCHEMA1214vm-test-run-index_advisor> server # [  144.195966] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE:  function graphql_public.graphql(text,text,jsonb) does not exist, skipping1215vm-test-run-index_advisor> server # [  144.198399] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: DROP FUNCTION1216vm-test-run-index_advisor> server # [  144.199766] postgres[1614]: ::1 2026-10-08 02:48:06.570 UTC [1614] supabase_admin@postgres LOG:  statement: drop function if exists graphql_public.graphql(text, text, jsonb);1217vm-test-run-index_advisor> server # [  144.201312] postgres[1614]: ::1 2026-10-08 02:48:06.593 UTC [1614] supabase_admin@postgres LOG:  statement: create or replace function graphql_public.graphql(1218vm-test-run-index_advisor> server # [  144.202623] postgres[1614]: 	    "operationName" text default null,1219vm-test-run-index_advisor> server # [  144.205117] postgres[1614]: 	    query text default null,1220vm-test-run-index_advisor> server # [  144.205495] postgres[1614]: 	    variables jsonb default null,1221vm-test-run-index_advisor> server # [  144.205793] postgres[1614]: 	    extensions jsonb default null1222vm-test-run-index_advisor> server # [  144.206257] postgres[1614]: 	)1223vm-test-run-index_advisor> server # [  144.207280] postgres[1614]: 	    returns jsonb1224vm-test-run-index_advisor> server # [  144.207783] postgres[1614]: 	    language plpgsql1225vm-test-run-index_advisor> server # [  144.208096] postgres[1614]: 	as $$1226vm-test-run-index_advisor> server # [  144.213371] postgres[1614]: 	    DECLARE1227vm-test-run-index_advisor> server # [  144.220660] postgres[1614]: 	        server_version float;1228vm-test-run-index_advisor> server # [  144.222149] postgres[1614]: 	    BEGIN1229vm-test-run-index_advisor> server # [  144.223667] postgres[1614]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1230vm-test-run-index_advisor> server # [  144.227198] postgres[1614]:1231vm-test-run-index_advisor> server # [  144.227802] postgres[1614]: 	        IF server_version >= 14 THEN1232vm-test-run-index_advisor> server # [  144.229626] postgres[1614]: 	            RETURN jsonb_build_object(1233vm-test-run-index_advisor> server # [  144.232196] postgres[1614]: 	                'data', null::jsonb,1234vm-test-run-index_advisor> server # [  144.233750] postgres[1614]: 	                'errors', array['pg_graphql extension is not enabled.']1235vm-test-run-index_advisor> server # [  144.234566] postgres[1614]: 	            );1236vm-test-run-index_advisor> server # [  144.235985] postgres[1614]: 	        ELSE1237vm-test-run-index_advisor> server # [  144.238239] postgres[1614]: 	            RETURN jsonb_build_object(1238vm-test-run-index_advisor> server # [  144.239448] postgres[1614]: 	                'data', null::jsonb,1239vm-test-run-index_advisor> server # [  144.240895] postgres[1614]: 	                'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1240vm-test-run-index_advisor> server # [  144.241911] postgres[1614]: 	            );1241vm-test-run-index_advisor> server # [  144.242954] postgres[1614]: 	        END IF;1242vm-test-run-index_advisor> server # [  144.243352] postgres[1614]: 	    END;1243vm-test-run-index_advisor> server # [  144.245346] postgres[1614]: 	$$;1244vm-test-run-index_advisor> server # [  144.260000] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: CREATE FUNCTION1245vm-test-run-index_advisor> server # [  144.263560] postgres[1614]: ::1 2026-10-08 02:48:06.652 UTC [1614] supabase_admin@postgres LOG:  statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1246vm-test-run-index_advisor> server # [  144.277941] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: GRANT1247vm-test-run-index_advisor> server # [  144.278913] postgres[1614]: ::1 2026-10-08 02:48:06.668 UTC [1614] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1248vm-test-run-index_advisor> server # [  144.288608] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: ALTER DEFAULT PRIVILEGES1249vm-test-run-index_advisor> server # [  144.289793] postgres[1614]: ::1 2026-10-08 02:48:06.681 UTC [1614] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1250vm-test-run-index_advisor> server # [  144.299208] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: ALTER DEFAULT PRIVILEGES1251vm-test-run-index_advisor> server # [  144.302142] postgres[1614]: ::1 2026-10-08 02:48:06.692 UTC [1614] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1252vm-test-run-index_advisor> server # [  144.309496] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: ALTER DEFAULT PRIVILEGES1253vm-test-run-index_advisor> server # [  144.313044] postgres[1614]: ::1 2026-10-08 02:48:06.705 UTC [1614] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1254vm-test-run-index_advisor> server # [  144.315351] postgres[1614]: 	    on sequences to postgres, anon, authenticated, service_role;1255vm-test-run-index_advisor> server # [  144.330553] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: ALTER DEFAULT PRIVILEGES1256vm-test-run-index_advisor> server # [  144.337457] postgres[1614]: ::1 2026-10-08 02:48:06.727 UTC [1614] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1257vm-test-run-index_advisor> server # [  144.338500] postgres[1614]: 	    on tables to postgres, anon, authenticated, service_role;1258vm-test-run-index_advisor> server # [  144.342711] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: ALTER DEFAULT PRIVILEGES1259vm-test-run-index_advisor> server # [  144.347904] postgres[1614]: ::1 2026-10-08 02:48:06.739 UTC [1614] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1260vm-test-run-index_advisor> server # [  144.352251] postgres[1614]: 	    on functions to postgres, anon, authenticated, service_role;1261vm-test-run-index_advisor> server # [  144.357120] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: ALTER DEFAULT PRIVILEGES1262vm-test-run-index_advisor> server # [  144.365114] postgres[1614]: ::1 2026-10-08 02:48:06.755 UTC [1614] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1263vm-test-run-index_advisor> server # [  144.367066] postgres[1614]: 	RETURNS event_trigger1264vm-test-run-index_advisor> server # [  144.367748] postgres[1614]: 	LANGUAGE plpgsql1265vm-test-run-index_advisor> server # [  144.368120] postgres[1614]: 	AS $func$1266vm-test-run-index_advisor> server # [  144.369158] postgres[1614]: 	    DECLARE1267vm-test-run-index_advisor> server # [  144.376128] postgres[1614]: 	    func_is_graphql_resolve bool;1268vm-test-run-index_advisor> server # [  144.378071] postgres[1614]: 	    BEGIN1269vm-test-run-index_advisor> server # [  144.379167] postgres[1614]: 	    func_is_graphql_resolve = (1270vm-test-run-index_advisor> server # [  144.386560] postgres[1614]: 	        SELECT n.proname = 'resolve'1271vm-test-run-index_advisor> server # [  144.387523] postgres[1614]: 	        FROM pg_event_trigger_ddl_commands() AS ev1272vm-test-run-index_advisor> server # [  144.391893] postgres[1614]: 	        LEFT JOIN pg_catalog.pg_proc AS n1273vm-test-run-index_advisor> server # [  144.392477] postgres[1614]: 	        ON ev.objid = n.oid1274vm-test-run-index_advisor> server # [  144.400086] postgres[1614]: 	    );1275vm-test-run-index_advisor> server # [  144.407343] postgres[1614]:1276vm-test-run-index_advisor> server # [  144.409783] postgres[1614]: 	    IF func_is_graphql_resolve1277vm-test-run-index_advisor> server # [  144.410256] postgres[1614]: 	    THEN1278vm-test-run-index_advisor> server # [  144.410513] postgres[1614]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1279vm-test-run-index_advisor> server # [  144.410814] postgres[1614]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1280vm-test-run-index_advisor> server # [  144.411353] postgres[1614]:1281vm-test-run-index_advisor> server # [  144.411834] postgres[1614]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1282vm-test-run-index_advisor> server # [  144.423779] postgres[1614]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1283vm-test-run-index_advisor> server # [  144.425675] postgres[1614]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1284vm-test-run-index_advisor> server # [  144.431611] postgres[1614]:1285vm-test-run-index_advisor> server # [  144.432158] postgres[1614]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1286vm-test-run-index_advisor> server # [  144.442182] postgres[1614]: 	        create or replace function graphql_public.graphql(1287vm-test-run-index_advisor> server # [  144.442824] postgres[1614]: 	            "operationName" text default null,1288vm-test-run-index_advisor> server # [  144.443443] postgres[1614]: 	            query text default null,1289vm-test-run-index_advisor> server # [  144.443743] postgres[1614]: 	            variables jsonb default null,1290vm-test-run-index_advisor> server # [  144.444496] postgres[1614]: 	            extensions jsonb default null1291vm-test-run-index_advisor> server # [  144.449844] postgres[1614]: 	        )1292vm-test-run-index_advisor> server # [  144.450481] postgres[1614]: 	            returns jsonb1293vm-test-run-index_advisor> server # [  144.450789] postgres[1614]: 	            language sql1294vm-test-run-index_advisor> server # [  144.454241] postgres[1614]: 	        as $$1295vm-test-run-index_advisor> server # [  144.462994] postgres[1614]: 	            SELECT graphql.resolve(query, coalesce(variables, '{}'));1296vm-test-run-index_advisor> server # [  144.473058] postgres[1614]: 	        $$;1297vm-test-run-index_advisor> server # [  144.473763] postgres[1614]:1298vm-test-run-index_advisor> server # [  144.475792] postgres[1614]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1299vm-test-run-index_advisor> server # [  144.479074] postgres[1614]: 	    END IF;1300vm-test-run-index_advisor> server # [  144.479718] postgres[1614]:1301vm-test-run-index_advisor> server # [  144.480091] postgres[1614]: 	    END;1302vm-test-run-index_advisor> server # [  144.486714] postgres[1614]: 	$func$;1303vm-test-run-index_advisor> server # [  144.494819] postgres[1614]: ::1 2026-10-08 02:48:06.777 UTC [1614] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1304vm-test-run-index_advisor> server # [  144.507334] postgres[1614]: ::1 2026-10-08 02:48:06.787 UTC [1614] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1305vm-test-run-index_advisor> server # [  144.509757] postgres[1614]: 	EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1306vm-test-run-index_advisor> server # [  144.510341] postgres[1614]: ::1 2026-10-08 02:48:06.796 UTC [1614] supabase_admin@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1307vm-test-run-index_advisor> server # [  144.510855] postgres[1614]: ::1 2026-10-08 02:48:06.811 UTC [1614] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1308vm-test-run-index_advisor> server # [  144.518589] postgres[1614]: 	RETURNS event_trigger1309vm-test-run-index_advisor> server # [  144.519339] postgres[1614]: 	LANGUAGE plpgsql1310vm-test-run-index_advisor> server # [  144.519711] postgres[1614]: 	AS $func$1311vm-test-run-index_advisor> server # [  144.519998] postgres[1614]: 	    DECLARE1312vm-test-run-index_advisor> server # [  144.522115] postgres[1614]: 	    graphql_is_dropped bool;1313vm-test-run-index_advisor> server # [  144.523040] postgres[1614]: 	    BEGIN1314vm-test-run-index_advisor> server # [  144.530569] postgres[1614]: 	    graphql_is_dropped = (1315vm-test-run-index_advisor> server # [  144.537248] postgres[1614]: 	        SELECT ev.schema_name = 'graphql_public'1316vm-test-run-index_advisor> server # [  144.538806] postgres[1614]: 	        FROM pg_event_trigger_dropped_objects() AS ev1317vm-test-run-index_advisor> server # [  144.539645] postgres[1614]: 	        WHERE ev.schema_name = 'graphql_public'1318vm-test-run-index_advisor> server # [  144.540050] postgres[1614]: 	    );1319vm-test-run-index_advisor> server # [  144.540683] postgres[1614]:1320vm-test-run-index_advisor> server # [  144.549050] postgres[1614]: 	    IF graphql_is_dropped1321vm-test-run-index_advisor> server # [  144.550823] postgres[1614]: 	    THEN1322vm-test-run-index_advisor> server # [  144.552135] postgres[1614]: 	        create or replace function graphql_public.graphql(1323vm-test-run-index_advisor> server # [  144.558338] postgres[1614]: 	            "operationName" text default null,1324vm-test-run-index_advisor> server # [  144.558802] postgres[1614]: 	            query text default null,1325vm-test-run-index_advisor> server # [  144.559160] postgres[1614]: 	            variables jsonb default null,1326vm-test-run-index_advisor> server # [  144.559452] postgres[1614]: 	            extensions jsonb default null1327vm-test-run-index_advisor> server # [  144.559821] postgres[1614]: 	        )1328vm-test-run-index_advisor> server # [  144.560269] postgres[1614]: 	            returns jsonb1329vm-test-run-index_advisor> server # [  144.572990] postgres[1614]: 	            language plpgsql1330vm-test-run-index_advisor> server # [  144.573959] postgres[1614]: 	        as $$1331vm-test-run-index_advisor> server # [  144.574852] postgres[1614]: 	            DECLARE1332vm-test-run-index_advisor> server # [  144.575504] postgres[1614]: 	                server_version float;1333vm-test-run-index_advisor> server # [  144.576478] postgres[1614]: 	            BEGIN1334vm-test-run-index_advisor> server # [  144.582129] postgres[1614]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1335vm-test-run-index_advisor> server # [  144.582996] postgres[1614]:1336vm-test-run-index_advisor> server # [  144.583627] postgres[1614]: 	                IF server_version >= 14 THEN1337vm-test-run-index_advisor> server # [  144.589534] postgres[1614]: 	                    RETURN jsonb_build_object(1338vm-test-run-index_advisor> server # [  144.590224] postgres[1614]: 	                        'data', null::jsonb,1339vm-test-run-index_advisor> server # [  144.591002] postgres[1614]: 	                        'errors', array['pg_graphql extension is not enabled.']1340vm-test-run-index_advisor> server # [  144.594907] postgres[1614]: 	                    );1341vm-test-run-index_advisor> server # [  144.600204] postgres[1614]: 	                ELSE1342vm-test-run-index_advisor> server # [  144.602425] postgres[1614]: 	                    RETURN jsonb_build_object(1343vm-test-run-index_advisor> server # [  144.602877] postgres[1614]: 	                        'data', null::jsonb,1344vm-test-run-index_advisor> server # [  144.603454] postgres[1614]: 	                        'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1345vm-test-run-index_advisor> server # [  144.604122] postgres[1614]: 	                    );1346vm-test-run-index_advisor> server # [  144.611896] postgres[1614]: 	                END IF;1347vm-test-run-index_advisor> server # [  144.616833] postgres[1614]: 	            END;1348vm-test-run-index_advisor> server # [  144.617516] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: CREATE FUNCTION1349vm-test-run-index_advisor> server # [  144.618140] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE:  event trigger "issue_pg_graphql_access" does not exist, skipping1350vm-test-run-index_advisor> server # [  144.618926] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: DROP EVENT TRIGGER1351vm-test-run-index_advisor> server # [  144.619765] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: CREATE EVENT TRIGGER1352vm-test-run-index_advisor> server # [  144.624786] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: COMMENT1353vm-test-run-index_advisor> server # [  144.626549] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: CREATE FUNCTION1354vm-test-run-index_advisor> server # [  144.632174] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE:  event trigger "issue_graphql_placeholder" does not exist, skipping1355vm-test-run-index_advisor> server # [  144.642461] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: DROP EVENT TRIGGER1356vm-test-run-index_advisor> server # [  144.646451] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: CREATE EVENT TRIGGER1357vm-test-run-index_advisor> server # [  144.647295] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1613]: COMMENT1358vm-test-run-index_advisor> server # [  144.654545] postgres[1614]: 	        $$;1359vm-test-run-index_advisor> server # [  144.662240] postgres[1614]: 	    END IF;1360vm-test-run-index_advisor> server # [  144.667826] postgres[1614]:1361vm-test-run-index_advisor> server # [  144.677269] postgres[1614]: 	    END;1362vm-test-run-index_advisor> server # [  144.677795] postgres[1614]: 	$func$;1363vm-test-run-index_advisor> server # [  144.678753] postgres[1614]: ::1 2026-10-08 02:48:06.851 UTC [1614] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1364vm-test-run-index_advisor> server # [  144.679642] postgres[1614]: ::1 2026-10-08 02:48:06.859 UTC [1614] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1365vm-test-run-index_advisor> server # [  144.689301] postgres[1614]: 	EXECUTE PROCEDURE extensions.set_graphql_placeholder();1366vm-test-run-index_advisor> server # [  144.690481] postgres[1614]: ::1 2026-10-08 02:48:06.866 UTC [1614] supabase_admin@postgres LOG:  statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1367vm-test-run-index_advisor> server # [  144.691926] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1368vm-test-run-index_advisor> server # [  144.848783] postgres[1616]: ::1 2026-10-08 02:48:07.232 UTC [1616] supabase_admin@postgres LOG:  statement: drop event trigger if exists api_restart;1369vm-test-run-index_advisor> server # [  144.850917] postgres[1616]: ::1 2026-10-08 02:48:07.240 UTC [1616] supabase_admin@postgres LOG:  statement: drop function if exists extensions.notify_api_restart();1370vm-test-run-index_advisor> server # [  144.867687] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE:  event trigger "api_restart" does not exist, skipping1371vm-test-run-index_advisor> server # [  144.873623] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: DROP EVENT TRIGGER1372vm-test-run-index_advisor> server # [  144.874621] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE:  function extensions.notify_api_restart() does not exist, skipping1373vm-test-run-index_advisor> server # [  144.879978] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: DROP FUNCTION1374vm-test-run-index_advisor> server # [  144.881691] postgres[1616]: ::1 2026-10-08 02:48:07.259 UTC [1616] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1375vm-test-run-index_advisor> server # [  144.883444] postgres[1616]: 	DECLARE1376vm-test-run-index_advisor> server # [  144.884105] postgres[1616]: 	  cmd record;1377vm-test-run-index_advisor> server # [  144.887405] postgres[1616]: 	BEGIN1378vm-test-run-index_advisor> server # [  144.895567] postgres[1616]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1379vm-test-run-index_advisor> server # [  144.896365] postgres[1616]: 	  LOOP1380vm-test-run-index_advisor> server # [  144.899060] postgres[1616]: 	    IF cmd.command_tag IN (1381vm-test-run-index_advisor> server # [  144.905676] postgres[1616]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1382vm-test-run-index_advisor> server # [  144.906200] postgres[1616]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1383vm-test-run-index_advisor> server # [  144.906557] postgres[1616]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1384vm-test-run-index_advisor> server # [  144.907397] postgres[1616]: 	    , 'CREATE VIEW', 'ALTER VIEW'1385vm-test-run-index_advisor> server # [  144.909910] postgres[1616]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1386vm-test-run-index_advisor> server # [  144.910512] postgres[1616]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1387vm-test-run-index_advisor> server # [  144.911033] postgres[1616]: 	    , 'CREATE TRIGGER'1388vm-test-run-index_advisor> server # [  144.911630] postgres[1616]: 	    , 'CREATE TYPE', 'ALTER TYPE'1389vm-test-run-index_advisor> server # [  144.914876] postgres[1616]: 	    , 'CREATE RULE'1390vm-test-run-index_advisor> server # [  144.916154] postgres[1616]: 	    , 'COMMENT'1391vm-test-run-index_advisor> server # [  144.917190] postgres[1616]: 	    )1392vm-test-run-index_advisor> server # [  144.917763] postgres[1616]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1393vm-test-run-index_advisor> server # [  144.918106] postgres[1616]: 	    AND cmd.schema_name is distinct from 'pg_temp'1394vm-test-run-index_advisor> server # [  144.918841] postgres[1616]: 	    THEN1395vm-test-run-index_advisor> server # [  144.919276] postgres[1616]: 	      NOTIFY pgrst, 'reload schema';1396vm-test-run-index_advisor> server # [  144.919609] postgres[1616]: 	    END IF;1397vm-test-run-index_advisor> server # [  144.920092] postgres[1616]: 	  END LOOP;1398vm-test-run-index_advisor> server # [  144.920341] postgres[1616]: 	END; $$ LANGUAGE plpgsql;1399vm-test-run-index_advisor> server # [  144.922572] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: CREATE FUNCTION1400vm-test-run-index_advisor> server # [  144.923804] postgres[1616]: ::1 2026-10-08 02:48:07.316 UTC [1616] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1401vm-test-run-index_advisor> server # [  144.924261] postgres[1616]: 	DECLARE1402vm-test-run-index_advisor> server # [  144.924510] postgres[1616]: 	  obj record;1403vm-test-run-index_advisor> server # [  144.924736] postgres[1616]: 	BEGIN1404vm-test-run-index_advisor> server # [  144.925050] postgres[1616]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1405vm-test-run-index_advisor> server # [  144.927082] postgres[1616]: 	  LOOP1406vm-test-run-index_advisor> server # [  144.929422] postgres[1616]: 	    IF obj.object_type IN (1407vm-test-run-index_advisor> server # [  144.931338] postgres[1616]: 	      'schema'1408vm-test-run-index_advisor> server # [  144.932075] postgres[1616]: 	    , 'table'1409vm-test-run-index_advisor> server # [  144.932804] postgres[1616]: 	    , 'foreign table'1410vm-test-run-index_advisor> server # [  144.934487] postgres[1616]: 	    , 'view'1411vm-test-run-index_advisor> server # [  144.935588] postgres[1616]: 	    , 'materialized view'1412vm-test-run-index_advisor> server # [  144.935947] postgres[1616]: 	    , 'function'1413vm-test-run-index_advisor> server # [  144.937309] postgres[1616]: 	    , 'trigger'1414vm-test-run-index_advisor> server # [  144.938719] postgres[1616]: 	    , 'type'1415vm-test-run-index_advisor> server # [  144.941475] postgres[1616]: 	    , 'rule'1416vm-test-run-index_advisor> server # [  144.942898] postgres[1616]: 	    )1417vm-test-run-index_advisor> server # [  144.943751] postgres[1616]: 	    AND obj.is_temporary IS false -- no pg_temp objects1418vm-test-run-index_advisor> server # [  144.944090] postgres[1616]: 	    THEN1419vm-test-run-index_advisor> server # [  144.944332] postgres[1616]: 	      NOTIFY pgrst, 'reload schema';1420vm-test-run-index_advisor> server # [  144.944576] postgres[1616]: 	    END IF;1421vm-test-run-index_advisor> server # [  144.946198] postgres[1616]: 	  END LOOP;1422vm-test-run-index_advisor> server # [  144.948047] postgres[1616]: 	END; $$ LANGUAGE plpgsql;1423vm-test-run-index_advisor> server # [  144.964027] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: CREATE FUNCTION1424vm-test-run-index_advisor> server # [  144.968199] postgres[1616]: ::1 2026-10-08 02:48:07.359 UTC [1616] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1425vm-test-run-index_advisor> server # [  144.982970] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: DROP EVENT TRIGGER1426vm-test-run-index_advisor> server # [  144.987008] postgres[1616]: ::1 2026-10-08 02:48:07.379 UTC [1616] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_ddl_watch1427vm-test-run-index_advisor> server # [  144.987844] postgres[1616]: 	  ON ddl_command_end1428vm-test-run-index_advisor> server # [  144.989829] postgres[1616]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1429vm-test-run-index_advisor> server # [  144.997688] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: CREATE EVENT TRIGGER1430vm-test-run-index_advisor> server # [  145.004435] postgres[1616]: ::1 2026-10-08 02:48:07.395 UTC [1616] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1431vm-test-run-index_advisor> server # [  145.011700] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: DROP EVENT TRIGGER1432vm-test-run-index_advisor> server # [  145.014754] postgres[1616]: ::1 2026-10-08 02:48:07.405 UTC [1616] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_drop_watch1433vm-test-run-index_advisor> server # [  145.015703] postgres[1616]: 	  ON sql_drop1434vm-test-run-index_advisor> server # [  145.017211] postgres[1616]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1435vm-test-run-index_advisor> server # [  145.025835] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1615]: CREATE EVENT TRIGGER1436vm-test-run-index_advisor> server # [  145.075019] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1437vm-test-run-index_advisor> server # [  145.466734] postgres[1621]: ::1 2026-10-08 02:48:07.839 UTC [1621] supabase_admin@postgres LOG:  statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1438vm-test-run-index_advisor> server # [  145.490037] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1617]: ALTER ROLE1439vm-test-run-index_advisor> server # [  145.540010] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1440vm-test-run-index_advisor> server # [  146.094276] postgres[1632]: ::1 2026-10-08 02:48:08.461 UTC [1632] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1441vm-test-run-index_advisor> server # [  146.114314] postgres[1632]: 	    returns event_trigger1442vm-test-run-index_advisor> server # [  146.120475] postgres[1632]: 	    language plpgsql1443vm-test-run-index_advisor> server # [  146.135684] postgres[1632]: 	AS $func$1444vm-test-run-index_advisor> server # [  146.143748] postgres[1632]: 	DECLARE1445vm-test-run-index_advisor> server # [  146.195500] postgres[1632]: 	    func_is_graphql_resolve bool;1446vm-test-run-index_advisor> server # [  146.214100] postgres[1632]: 	BEGIN1447vm-test-run-index_advisor> server # [  146.214883] postgres[1632]: 	    func_is_graphql_resolve = (1448vm-test-run-index_advisor> server # [  146.215269] postgres[1632]: 	        SELECT n.proname = 'resolve'1449vm-test-run-index_advisor> server # [  146.215648] postgres[1632]: 	        FROM pg_event_trigger_ddl_commands() AS ev1450vm-test-run-index_advisor> server # [  146.225315] postgres[1632]: 	        LEFT JOIN pg_catalog.pg_proc AS n1451vm-test-run-index_advisor> server # [  146.227718] postgres[1632]: 	        ON ev.objid = n.oid1452vm-test-run-index_advisor> server # [  146.241465] postgres[1632]: 	    );1453vm-test-run-index_advisor> server # [  146.242643] postgres[1632]:1454vm-test-run-index_advisor> server # [  146.243052] postgres[1632]: 	    IF func_is_graphql_resolve1455vm-test-run-index_advisor> server # [  146.244026] postgres[1632]: 	    THEN1456vm-test-run-index_advisor> server # [  146.262394] postgres[1632]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1457vm-test-run-index_advisor> server # [  146.270977] postgres[1632]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1458vm-test-run-index_advisor> server # [  146.271947] postgres[1632]:1459vm-test-run-index_advisor> server # [  146.279621] postgres[1632]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1460vm-test-run-index_advisor> server # [  146.283979] postgres[1632]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1461vm-test-run-index_advisor> server # [  146.285808] postgres[1632]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1462vm-test-run-index_advisor> server # [  146.286701] postgres[1632]:1463vm-test-run-index_advisor> server # [  146.287373] postgres[1632]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1464vm-test-run-index_advisor> server # [  146.289166] postgres[1632]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1465vm-test-run-index_advisor> server # [  146.290826] postgres[1632]: 	        create or replace function graphql_public.graphql(1466vm-test-run-index_advisor> server # [  146.291477] postgres[1632]: 	            "operationName" text default null,1467vm-test-run-index_advisor> server # [  146.293285] postgres[1632]: 	            query text default null,1468vm-test-run-index_advisor> server # [  146.293928] postgres[1632]: 	            variables jsonb default null,1469vm-test-run-index_advisor> server # [  146.294972] postgres[1632]: 	            extensions jsonb default null1470vm-test-run-index_advisor> server # [  146.297402] postgres[1632]: 	        )1471vm-test-run-index_advisor> server # [  146.297894] postgres[1632]: 	            returns jsonb1472vm-test-run-index_advisor> server # [  146.298511] postgres[1632]: 	            language sql1473vm-test-run-index_advisor> server # [  146.298802] postgres[1632]: 	        as $$1474vm-test-run-index_advisor> server # [  146.301441] postgres[1632]: 	            -- This changed1475vm-test-run-index_advisor> server # [  146.302166] postgres[1632]: 	            select graphql.resolve(1476vm-test-run-index_advisor> server # [  146.302918] postgres[1632]: 	                query := query,1477vm-test-run-index_advisor> server # [  146.303585] postgres[1632]: 	                variables := coalesce(variables, '{}'),1478vm-test-run-index_advisor> server # [  146.304863] postgres[1632]: 	                "operationName" := "operationName",1479vm-test-run-index_advisor> server # [  146.306473] postgres[1632]: 	                extensions := extensions1480vm-test-run-index_advisor> server # [  146.307877] postgres[1632]: 	            );1481vm-test-run-index_advisor> server # [  146.309668] postgres[1632]: 	        $$;1482vm-test-run-index_advisor> server # [  146.310112] postgres[1632]:1483vm-test-run-index_advisor> server # [  146.310394] postgres[1632]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1484vm-test-run-index_advisor> server # [  146.317276] postgres[1632]: 	    END IF;1485vm-test-run-index_advisor> server # [  146.319907] postgres[1632]:1486vm-test-run-index_advisor> server # [  146.321494] postgres[1632]: 	END;1487vm-test-run-index_advisor> server # [  146.322437] postgres[1632]: 	$func$;1488vm-test-run-index_advisor> server # [  146.324869] postgres[1632]: ::1 2026-10-08 02:48:08.627 UTC [1632] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1489vm-test-run-index_advisor> server # [  146.326609] postgres[1632]: 	RETURNS event_trigger1490vm-test-run-index_advisor> server # [  146.327333] postgres[1632]: 	LANGUAGE plpgsql1491vm-test-run-index_advisor> server # [  146.327651] postgres[1632]: 	AS $func$1492vm-test-run-index_advisor> server # [  146.329667] postgres[1632]: 	    DECLARE1493vm-test-run-index_advisor> server # [  146.330572] postgres[1632]: 	    graphql_is_dropped bool;1494vm-test-run-index_advisor> server # [  146.331321] postgres[1632]: 	    BEGIN1495vm-test-run-index_advisor> server # [  146.331613] postgres[1632]: 	    graphql_is_dropped = (1496vm-test-run-index_advisor> server # [  146.331858] postgres[1632]: 	        SELECT ev.schema_name = 'graphql_public'1497vm-test-run-index_advisor> server # [  146.333523] postgres[1632]: 	        FROM pg_event_trigger_dropped_objects() AS ev1498vm-test-run-index_advisor> server # [  146.334383] postgres[1632]: 	        WHERE ev.schema_name = 'graphql_public'1499vm-test-run-index_advisor> server # [  146.335028] postgres[1632]: 	    );1500vm-test-run-index_advisor> server # [  146.336170] postgres[1632]:1501vm-test-run-index_advisor> server # [  146.337321] postgres[1632]: 	    IF graphql_is_dropped1502vm-test-run-index_advisor> server # [  146.337652] postgres[1632]: 	    THEN1503vm-test-run-index_advisor> server # [  146.338246] postgres[1632]: 	        create or replace function graphql_public.graphql(1504vm-test-run-index_advisor> server # [  146.338544] postgres[1632]: 	            "operationName" text default null,1505vm-test-run-index_advisor> server # [  146.338934] postgres[1632]: 	            query text default null,1506vm-test-run-index_advisor> server # [  146.339597] postgres[1632]: 	            variables jsonb default null,1507vm-test-run-index_advisor> server # [  146.339904] postgres[1632]: 	            extensions jsonb default null1508vm-test-run-index_advisor> server # [  146.341622] postgres[1632]: 	        )1509vm-test-run-index_advisor> server # [  146.342016] postgres[1632]: 	            returns jsonb1510vm-test-run-index_advisor> server # [  146.342296] postgres[1632]: 	            language plpgsql1511vm-test-run-index_advisor> server # [  146.342937] postgres[1632]: 	        as $$1512vm-test-run-index_advisor> server # [  146.353828] postgres[1632]: 	            DECLARE1513vm-test-run-index_advisor> server # [  146.354607] postgres[1632]: 	                server_version float;1514vm-test-run-index_advisor> server # [  146.354928] postgres[1632]: 	            BEGIN1515vm-test-run-index_advisor> server # [  146.355731] postgres[1632]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1516vm-test-run-index_advisor> server # [  146.359367] postgres[1632]:1517vm-test-run-index_advisor> server # [  146.362152] postgres[1632]: 	                IF server_version >= 14 THEN1518vm-test-run-index_advisor> server # [  146.363722] postgres[1632]: 	                    RETURN jsonb_build_object(1519vm-test-run-index_advisor> server # [  146.369165] postgres[1632]: 	                        'errors', jsonb_build_array(1520vm-test-run-index_advisor> server # [  146.369967] postgres[1632]: 	                            jsonb_build_object(1521vm-test-run-index_advisor> server # [  146.371993] postgres[1632]: 	                                'message', 'pg_graphql extension is not enabled.'1522vm-test-run-index_advisor> server # [  146.377527] postgres[1632]: 	                            )1523vm-test-run-index_advisor> server # [  146.385110] postgres[1632]: 	                        )1524vm-test-run-index_advisor> server # [  146.387347] postgres[1632]: 	                    );1525vm-test-run-index_advisor> server # [  146.394942] postgres[1632]: 	                ELSE1526vm-test-run-index_advisor> server # [  146.397124] postgres[1632]: 	                    RETURN jsonb_build_object(1527vm-test-run-index_advisor> server # [  146.397711] postgres[1632]: 	                        'errors', jsonb_build_array(1528vm-test-run-index_advisor> server # [  146.399189] postgres[1632]: 	                            jsonb_build_object(1529vm-test-run-index_advisor> server # [  146.399592] postgres[1632]: 	                                'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1530vm-test-run-index_advisor> server # [  146.402229] postgres[1632]: 	                            )1531vm-test-run-index_advisor> server # [  146.402714] postgres[1632]: 	                        )1532vm-test-run-index_advisor> server # [  146.410864] postgres[1632]: 	                    );1533vm-test-run-index_advisor> server # [  146.412114] postgres[1632]: 	                END IF;1534vm-test-run-index_advisor> server # [  146.413916] postgres[1632]: 	            END;1535vm-test-run-index_advisor> server # [  146.414853] postgres[1632]: 	        $$;1536vm-test-run-index_advisor> server # [  146.420865] postgres[1632]: 	    END IF;1537vm-test-run-index_advisor> server # [  146.423944] postgres[1632]:1538vm-test-run-index_advisor> server # [  146.433332] postgres[1632]: 	    END;1539vm-test-run-index_advisor> server # [  146.434821] postgres[1632]: 	$func$;1540vm-test-run-index_advisor> server # [  146.441255] postgres[1632]: ::1 2026-10-08 02:48:08.659 UTC [1632] supabase_admin@postgres LOG:  statement: create or replace function graphql_public.graphql(1541vm-test-run-index_advisor> server # [  146.444216] postgres[1632]: 	    "operationName" text default null,1542vm-test-run-index_advisor> server # [  146.449526] postgres[1632]: 	    query text default null,1543vm-test-run-index_advisor> server # [  146.451245] postgres[1632]: 	    variables jsonb default null,1544vm-test-run-index_advisor> server # [  146.478522] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1625]: CREATE FUNCTION1545vm-test-run-index_advisor> server # [  146.483004] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1625]: CREATE FUNCTION1546vm-test-run-index_advisor> server # [  146.484005] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1625]: CREATE FUNCTION1547vm-test-run-index_advisor> server # [  146.489137] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1625]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE:  extension "pg_graphql" does not exist, skipping1548vm-test-run-index_advisor> server # [  146.492911] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1625]: DROP EXTENSION1549vm-test-run-index_advisor> server # [  146.496380] postgres[1632]: 	    extensions jsonb default null1550vm-test-run-index_advisor> server # [  146.502431] postgres[1632]: 	)1551vm-test-run-index_advisor> server # [  146.505079] postgres[1632]: 	    returns jsonb1552vm-test-run-index_advisor> server # [  146.506324] postgres[1632]: 	    language plpgsql1553vm-test-run-index_advisor> server # [  146.506899] postgres[1632]: 	as $$1554vm-test-run-index_advisor> server # [  146.507479] postgres[1632]: 	    DECLARE1555vm-test-run-index_advisor> server # [  146.508169] postgres[1632]: 	        server_version float;1556vm-test-run-index_advisor> server # [  146.508695] postgres[1632]: 	    BEGIN1557vm-test-run-index_advisor> server # [  146.513547] postgres[1632]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1558vm-test-run-index_advisor> server # [  146.516205] postgres[1632]:1559vm-test-run-index_advisor> server # [  146.522667] postgres[1632]: 	        IF server_version >= 14 THEN1560vm-test-run-index_advisor> server # [  146.530272] postgres[1632]: 	            RETURN jsonb_build_object(1561vm-test-run-index_advisor> server # [  146.531605] postgres[1632]: 	                'errors', jsonb_build_array(1562vm-test-run-index_advisor> server # [  146.536919] postgres[1632]: 	                    jsonb_build_object(1563vm-test-run-index_advisor> server # [  146.541777] postgres[1632]: 	                        'message', 'pg_graphql extension is not enabled.'1564vm-test-run-index_advisor> server # [  146.549418] postgres[1632]: 	                    )1565vm-test-run-index_advisor> server # [  146.557350] postgres[1632]: 	                )1566vm-test-run-index_advisor> server # [  146.561168] postgres[1632]: 	            );1567vm-test-run-index_advisor> server # [  146.568666] postgres[1632]: 	        ELSE1568vm-test-run-index_advisor> server # [  146.573058] postgres[1632]: 	            RETURN jsonb_build_object(1569vm-test-run-index_advisor> server # [  146.574866] postgres[1632]: 	                'errors', jsonb_build_array(1570vm-test-run-index_advisor> server # [  146.575910] postgres[1632]: 	                    jsonb_build_object(1571vm-test-run-index_advisor> server # [  146.581291] postgres[1632]: 	                        'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1572vm-test-run-index_advisor> server # [  146.589185] postgres[1632]: 	                    )1573vm-test-run-index_advisor> server # [  146.590796] postgres[1632]: 	                )1574vm-test-run-index_advisor> server # [  146.592083] postgres[1632]: 	            );1575vm-test-run-index_advisor> server # [  146.594961] postgres[1632]: 	        END IF;1576vm-test-run-index_advisor> server # [  146.595434] postgres[1632]: 	    END;1577vm-test-run-index_advisor> server # [  146.595720] postgres[1632]: 	$$;1578vm-test-run-index_advisor> server # [  146.601628] postgres[1632]: ::1 2026-10-08 02:48:08.714 UTC [1632] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1579vm-test-run-index_advisor> server # [  148.393300] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1625]: DO1580vm-test-run-index_advisor> server # [  148.446289] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1581vm-test-run-index_advisor> server # [  148.710944] postgres[1650]: ::1 2026-10-08 02:48:11.090 UTC [1650] supabase_admin@postgres LOG:  statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1582vm-test-run-index_advisor> server # [  148.725734] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1649]: GRANT ROLE1583vm-test-run-index_advisor> server # [  148.770604] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1584vm-test-run-index_advisor> server # [  149.078532] postgres[1652]: ::1 2026-10-08 02:48:11.467 UTC [1652] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1585vm-test-run-index_advisor> server # [  149.082239] postgres[1652]: 	    returns event_trigger1586vm-test-run-index_advisor> server # [  149.083619] postgres[1652]: 	    language plpgsql1587vm-test-run-index_advisor> server # [  149.086003] postgres[1652]: 	AS $func$1588vm-test-run-index_advisor> server # [  149.087145] postgres[1652]: 	DECLARE1589vm-test-run-index_advisor> server # [  149.088034] postgres[1652]: 	    func_is_graphql_resolve bool;1590vm-test-run-index_advisor> server # [  149.090162] postgres[1652]: 	BEGIN1591vm-test-run-index_advisor> server # [  149.090848] postgres[1652]: 	    func_is_graphql_resolve = (1592vm-test-run-index_advisor> server # [  149.091710] postgres[1652]: 	        SELECT n.proname = 'resolve'1593vm-test-run-index_advisor> server # [  149.093794] postgres[1652]: 	        FROM pg_event_trigger_ddl_commands() AS ev1594vm-test-run-index_advisor> server # [  149.094961] postgres[1652]: 	        LEFT JOIN pg_catalog.pg_proc AS n1595vm-test-run-index_advisor> server # [  149.096994] postgres[1652]: 	        ON ev.objid = n.oid1596vm-test-run-index_advisor> server # [  149.097767] postgres[1652]: 	    );1597vm-test-run-index_advisor> server # [  149.098054] postgres[1652]:1598vm-test-run-index_advisor> server # [  149.098303] postgres[1652]: 	    IF func_is_graphql_resolve1599vm-test-run-index_advisor> server # [  149.098537] postgres[1652]: 	    THEN1600vm-test-run-index_advisor> server # [  149.098745] postgres[1652]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1601vm-test-run-index_advisor> server # [  149.099244] postgres[1652]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1602vm-test-run-index_advisor> server # [  149.099886] postgres[1652]: 	        create or replace function graphql_public.graphql(1603vm-test-run-index_advisor> server # [  149.101173] postgres[1652]: 	            "operationName" text default null,1604vm-test-run-index_advisor> server # [  149.102178] postgres[1652]: 	            query text default null,1605vm-test-run-index_advisor> server # [  149.102738] postgres[1652]: 	            variables jsonb default null,1606vm-test-run-index_advisor> server # [  149.103251] postgres[1652]: 	            extensions jsonb default null1607vm-test-run-index_advisor> server # [  149.103670] postgres[1652]: 	        )1608vm-test-run-index_advisor> server # [  149.103971] postgres[1652]: 	            returns jsonb1609vm-test-run-index_advisor> server # [  149.104695] postgres[1652]: 	            language sql1610vm-test-run-index_advisor> server # [  149.105106] postgres[1652]: 	        as $$1611vm-test-run-index_advisor> server # [  149.106316] postgres[1652]: 	            select graphql.resolve(1612vm-test-run-index_advisor> server # [  149.108649] postgres[1652]: 	                query := query,1613vm-test-run-index_advisor> server # [  149.109768] postgres[1652]: 	                variables := coalesce(variables, '{}'),1614vm-test-run-index_advisor> server # [  149.110383] postgres[1652]: 	                "operationName" := "operationName",1615vm-test-run-index_advisor> server # [  149.111010] postgres[1652]: 	                extensions := extensions1616vm-test-run-index_advisor> server # [  149.112567] postgres[1652]: 	            );1617vm-test-run-index_advisor> server # [  149.113547] postgres[1652]: 	        $$;1618vm-test-run-index_advisor> server # [  149.114269] postgres[1652]:1619vm-test-run-index_advisor> server # [  149.115351] postgres[1652]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1620vm-test-run-index_advisor> server # [  149.116249] postgres[1652]: 	        -- function in the extension so we need to grant permissions on existing entities AND1621vm-test-run-index_advisor> server # [  149.116666] postgres[1652]: 	        -- update default permissions to any others that are created after `graphql.resolve`1622vm-test-run-index_advisor> server # [  149.121298] postgres[1652]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1623vm-test-run-index_advisor> server # [  149.122751] postgres[1652]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1624vm-test-run-index_advisor> server # [  149.124024] postgres[1652]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1625vm-test-run-index_advisor> server # [  149.130587] postgres[1652]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1626vm-test-run-index_advisor> server # [  149.132352] postgres[1652]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1627vm-test-run-index_advisor> server # [  149.134135] postgres[1652]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1628vm-test-run-index_advisor> server # [  149.135643] postgres[1652]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1629vm-test-run-index_advisor> server # [  149.137904] postgres[1652]: 	    END IF;1630vm-test-run-index_advisor> server # [  149.138431] postgres[1652]:1631vm-test-run-index_advisor> server # [  149.138705] postgres[1652]: 	END;1632vm-test-run-index_advisor> server # [  149.141617] postgres[1652]: 	$func$;1633vm-test-run-index_advisor> server # [  149.265289] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1651]: CREATE FUNCTION1634vm-test-run-index_advisor> server # [  149.266368] postgres[1652]: ::1 2026-10-08 02:48:11.640 UTC [1652] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1635vm-test-run-index_advisor> server # [  149.380803] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1651]: DROP EXTENSION1636vm-test-run-index_advisor> server # [  151.212099] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1651]: DO1637vm-test-run-index_advisor> server # [  151.275027] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1638vm-test-run-index_advisor> server # [  152.414620] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1677]: DO1639vm-test-run-index_advisor> server # [  153.246624] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1677]: DO1640vm-test-run-index_advisor> server # [  153.306859] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1641vm-test-run-index_advisor> server # [  153.816294] postgres[1702]: ::1 2026-10-08 02:48:16.199 UTC [1702] supabase_admin@postgres LOG:  statement: alter role authenticator set statement_timeout = '8s';1642vm-test-run-index_advisor> server # [  153.864345] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1694]: ALTER ROLE1643vm-test-run-index_advisor> server # [  153.883177] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221103090837_revoke_admin.sql1644vm-test-run-index_advisor> server # [  154.295943] postgres[1710]: ::1 2026-10-08 02:48:16.638 UTC [1710] supabase_admin@postgres LOG:  statement: revoke supabase_admin from authenticator;1645vm-test-run-index_advisor> server # [  154.332498] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1706]: REVOKE ROLE1646vm-test-run-index_advisor> server # [  154.335428] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1647vm-test-run-index_advisor> server # [  156.716612] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1711]: DO1648vm-test-run-index_advisor> server # [  156.733797] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1649vm-test-run-index_advisor> server # [  157.030843] postgres[1738]: ::1 2026-10-08 02:48:19.419 UTC [1738] supabase_admin@postgres LOG:  statement: grant anon, authenticated, service_role to postgres;1650vm-test-run-index_advisor> server # [  157.051027] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1733]: GRANT ROLE1651vm-test-run-index_advisor> server # [  157.086421] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1652vm-test-run-index_advisor> server # [  157.322094] postgres[1740]: ::1 2026-10-08 02:48:19.707 UTC [1740] supabase_admin@postgres LOG:  statement: grant all privileges on all tables in schema extensions to postgres with grant option;1653vm-test-run-index_advisor> server # [  157.353905] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1739]: GRANT1654vm-test-run-index_advisor> server # [  157.355984] postgres[1740]: ::1 2026-10-08 02:48:19.743 UTC [1740] supabase_admin@postgres LOG:  statement: grant all privileges on all routines in schema extensions to postgres with grant option;1655vm-test-run-index_advisor> server # [  157.422958] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1739]: GRANT1656vm-test-run-index_advisor> server # [  157.433539] postgres[1740]: ::1 2026-10-08 02:48:19.824 UTC [1740] supabase_admin@postgres LOG:  statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1657vm-test-run-index_advisor> server # [  157.456036] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1739]: GRANT1658vm-test-run-index_advisor> server # [  157.463943] postgres[1740]: ::1 2026-10-08 02:48:19.855 UTC [1740] supabase_admin@postgres LOG:  statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1659vm-test-run-index_advisor> server # [  157.488057] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1739]: ALTER DEFAULT PRIVILEGES1660vm-test-run-index_advisor> server # [  157.497670] postgres[1740]: ::1 2026-10-08 02:48:19.885 UTC [1740] supabase_admin@postgres LOG:  statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1661vm-test-run-index_advisor> server # [  157.515843] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1739]: ALTER DEFAULT PRIVILEGES1662vm-test-run-index_advisor> server # [  157.517448] postgres[1740]: ::1 2026-10-08 02:48:19.909 UTC [1740] supabase_admin@postgres LOG:  statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1663vm-test-run-index_advisor> server # [  157.543446] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1739]: ALTER DEFAULT PRIVILEGES1664vm-test-run-index_advisor> server # [  157.586157] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1665vm-test-run-index_advisor> server # [  157.887407] postgres[1746]: ::1 2026-10-08 02:48:20.267 UTC [1746] supabase_admin@postgres LOG:  statement: grant pg_monitor to postgres;1666vm-test-run-index_advisor> server # [  157.913815] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1745]: GRANT ROLE1667vm-test-run-index_advisor> server # [  157.948616] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1668vm-test-run-index_advisor> server # [  158.203392] postgres[1748]: ::1 2026-10-08 02:48:20.592 UTC [1748] supabase_admin@postgres LOG:  statement: grant anon, authenticated, service_role to supabase_storage_admin;1669vm-test-run-index_advisor> server # [  158.222147] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1747]: GRANT ROLE1670vm-test-run-index_advisor> server # [  158.261096] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1671vm-test-run-index_advisor> server # [  158.495432] postgres[1750]: ::1 2026-10-08 02:48:20.879 UTC [1750] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticated inherit;1672vm-test-run-index_advisor> server # [  158.505624] postgres[1750]: ::1 2026-10-08 02:48:20.896 UTC [1750] supabase_admin@postgres LOG:  statement: ALTER ROLE anon inherit;1673vm-test-run-index_advisor> server # [  158.516311] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1749]: ALTER ROLE1674vm-test-run-index_advisor> server # [  158.526112] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1749]: ALTER ROLE1675vm-test-run-index_advisor> server # [  158.526692] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1749]: ALTER ROLE1676vm-test-run-index_advisor> server # [  158.527253] postgres[1750]: ::1 2026-10-08 02:48:20.904 UTC [1750] supabase_admin@postgres LOG:  statement: ALTER ROLE service_role inherit;1677vm-test-run-index_advisor> server # [  158.547014] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1749]: DO1678vm-test-run-index_advisor> server # [  158.589324] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1679vm-test-run-index_advisor> server # [  158.815334] postgres[1752]: ::1 2026-10-08 02:48:21.203 UTC [1752] supabase_admin@postgres LOG:  statement: grant authenticator to supabase_storage_admin;1680vm-test-run-index_advisor> server # [  158.833963] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1751]: GRANT ROLE1681vm-test-run-index_advisor> server # [  158.836143] postgres[1752]: ::1 2026-10-08 02:48:21.219 UTC [1752] supabase_admin@postgres LOG:  statement: revoke anon, authenticated, service_role from supabase_storage_admin;1682vm-test-run-index_advisor> server # [  158.844779] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1751]: REVOKE ROLE1683vm-test-run-index_advisor> server # [  158.880025] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1684vm-test-run-index_advisor> server # [  159.277661] postgres[1757]: ::1 2026-10-08 02:48:21.654 UTC [1757] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1685vm-test-run-index_advisor> server # [  159.286606] postgres[1757]: 	    returns event_trigger1686vm-test-run-index_advisor> server # [  159.297780] postgres[1757]: 	    language plpgsql1687vm-test-run-index_advisor> server # [  159.298214] postgres[1757]: 	AS $func$1688vm-test-run-index_advisor> server # [  159.299563] postgres[1757]: 	DECLARE1689vm-test-run-index_advisor> server # [  159.299913] postgres[1757]: 	    func_is_graphql_resolve bool;1690vm-test-run-index_advisor> server # [  159.308950] postgres[1757]: 	BEGIN1691vm-test-run-index_advisor> server # [  159.318722] postgres[1757]: 	    func_is_graphql_resolve = (1692vm-test-run-index_advisor> server # [  159.319841] postgres[1757]: 	        SELECT n.proname = 'resolve'1693vm-test-run-index_advisor> server # [  159.320451] postgres[1757]: 	        FROM pg_event_trigger_ddl_commands() AS ev1694vm-test-run-index_advisor> server # [  159.336937] postgres[1757]: 	        LEFT JOIN pg_catalog.pg_proc AS n1695vm-test-run-index_advisor> server # [  159.338684] postgres[1757]: 	        ON ev.objid = n.oid1696vm-test-run-index_advisor> server # [  159.339582] postgres[1757]: 	    );1697vm-test-run-index_advisor> server # [  159.340236] postgres[1757]:1698vm-test-run-index_advisor> server # [  159.352995] postgres[1757]: 	    IF func_is_graphql_resolve1699vm-test-run-index_advisor> server # [  159.353646] postgres[1757]: 	    THEN1700vm-test-run-index_advisor> server # [  159.360820] postgres[1757]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1701vm-test-run-index_advisor> server # [  159.366699] postgres[1757]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1702vm-test-run-index_advisor> server # [  159.378233] postgres[1757]: 	        create or replace function graphql_public.graphql(1703vm-test-run-index_advisor> server # [  159.380311] postgres[1757]: 	            "operationName" text default null,1704vm-test-run-index_advisor> server # [  159.390042] postgres[1757]: 	            query text default null,1705vm-test-run-index_advisor> server # [  159.391221] postgres[1757]: 	            variables jsonb default null,1706vm-test-run-index_advisor> server # [  159.397025] postgres[1757]: 	            extensions jsonb default null1707vm-test-run-index_advisor> server # [  159.413144] postgres[1757]: 	        )1708vm-test-run-index_advisor> server # [  159.414076] postgres[1757]: 	            returns jsonb1709vm-test-run-index_advisor> server # [  159.415554] postgres[1757]: 	            language sql1710vm-test-run-index_advisor> server # [  159.419062] postgres[1757]: 	        as $$1711vm-test-run-index_advisor> server # [  159.425585] postgres[1757]: 	            select graphql.resolve(1712vm-test-run-index_advisor> server # [  159.426307] postgres[1757]: 	                query := query,1713vm-test-run-index_advisor> server # [  159.427369] postgres[1757]: 	                variables := coalesce(variables, '{}'),1714vm-test-run-index_advisor> server # [  159.428396] postgres[1757]: 	                "operationName" := "operationName",1715vm-test-run-index_advisor> server # [  159.442852] postgres[1757]: 	                extensions := extensions1716vm-test-run-index_advisor> server # [  159.443751] postgres[1757]: 	            );1717vm-test-run-index_advisor> server # [  159.452165] postgres[1757]: 	        $$;1718vm-test-run-index_advisor> server # [  159.467369] postgres[1757]:1719vm-test-run-index_advisor> server # [  159.469796] postgres[1757]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1720vm-test-run-index_advisor> server # [  159.472072] postgres[1757]: 	        -- function in the extension so we need to grant permissions on existing entities AND1721vm-test-run-index_advisor> server # [  159.478888] postgres[1757]: 	        -- update default permissions to any others that are created after `graphql.resolve`1722vm-test-run-index_advisor> server # [  159.488372] postgres[1757]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1723vm-test-run-index_advisor> server # [  159.493181] postgres[1757]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1724vm-test-run-index_advisor> server # [  159.494270] postgres[1757]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1725vm-test-run-index_advisor> server # [  159.495184] postgres[1757]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1726vm-test-run-index_advisor> server # [  159.501247] postgres[1757]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1727vm-test-run-index_advisor> server # [  159.503567] postgres[1757]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1728vm-test-run-index_advisor> server # [  159.514469] postgres[1757]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1729vm-test-run-index_advisor> server # [  159.521609] postgres[1757]:1730vm-test-run-index_advisor> server # [  159.522717] postgres[1757]: 	        -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1731vm-test-run-index_advisor> server # [  159.523612] postgres[1757]: 	        grant usage on schema graphql_public to postgres with grant option;1732vm-test-run-index_advisor> server # [  159.530657] postgres[1757]: 	        grant usage on schema graphql to postgres with grant option;1733vm-test-run-index_advisor> server # [  159.542863] postgres[1757]: 	    END IF;1734vm-test-run-index_advisor> server # [  159.550033] postgres[1757]:1735vm-test-run-index_advisor> server # [  159.557428] postgres[1757]: 	END;1736vm-test-run-index_advisor> server # [  159.559513] postgres[1757]: 	$func$;1737vm-test-run-index_advisor> server # [  159.570245] postgres[1757]: ::1 2026-10-08 02:48:21.891 UTC [1757] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1738vm-test-run-index_advisor> server # [  159.587978] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1753]: CREATE FUNCTION1739vm-test-run-index_advisor> server # [  159.650277] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1753]: DROP EXTENSION1740vm-test-run-index_advisor> server # [  161.530049] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1753]: DO1741vm-test-run-index_advisor> server # [  161.549106] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1742vm-test-run-index_advisor> server # [  161.864337] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1783]: DO1743vm-test-run-index_advisor> server # [  161.873491] postgres[1784]: ::1 2026-10-08 02:48:24.250 UTC [1784] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1744vm-test-run-index_advisor> server # [  161.875956] postgres[1784]: 	    LANGUAGE plpgsql1745vm-test-run-index_advisor> server # [  161.877599] postgres[1784]: 	    AS $$1746vm-test-run-index_advisor> server # [  161.879110] postgres[1784]: 	BEGIN1747vm-test-run-index_advisor> server # [  161.879705] postgres[1784]: 	  IF EXISTS (1748vm-test-run-index_advisor> server # [  161.881869] postgres[1784]: 	    SELECT1749vm-test-run-index_advisor> server # [  161.882480] postgres[1784]: 	    FROM pg_event_trigger_ddl_commands() AS ev1750vm-test-run-index_advisor> server # [  161.883068] postgres[1784]: 	    JOIN pg_extension AS ext1751vm-test-run-index_advisor> server # [  161.883874] postgres[1784]: 	    ON ev.objid = ext.oid1752vm-test-run-index_advisor> server # [  161.885791] postgres[1784]: 	    WHERE ext.extname = 'pg_cron'1753vm-test-run-index_advisor> server # [  161.886373] postgres[1784]: 	  )1754vm-test-run-index_advisor> server # [  161.888036] postgres[1784]: 	  THEN1755vm-test-run-index_advisor> server # [  161.890236] postgres[1784]: 	    grant usage on schema cron to postgres with grant option;1756vm-test-run-index_advisor> server # [  161.891303] postgres[1784]:1757vm-test-run-index_advisor> server # [  161.891623] postgres[1784]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;1758vm-test-run-index_advisor> server # [  161.893332] postgres[1784]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;1759vm-test-run-index_advisor> server # [  161.893707] postgres[1784]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;1760vm-test-run-index_advisor> server # [  161.894016] postgres[1784]:1761vm-test-run-index_advisor> server # [  161.894230] postgres[1784]: 	    alter default privileges for user supabase_admin in schema cron grant all1762vm-test-run-index_advisor> server # [  161.895335] postgres[1784]: 	        on sequences to postgres with grant option;1763vm-test-run-index_advisor> server # [  161.897599] postgres[1784]: 	    alter default privileges for user supabase_admin in schema cron grant all1764vm-test-run-index_advisor> server # [  161.899284] postgres[1784]: 	        on tables to postgres with grant option;1765vm-test-run-index_advisor> server # [  161.900008] postgres[1784]: 	    alter default privileges for user supabase_admin in schema cron grant all1766vm-test-run-index_advisor> server # [  161.901979] postgres[1784]: 	        on functions to postgres with grant option;1767vm-test-run-index_advisor> server # [  161.903606] postgres[1784]:1768vm-test-run-index_advisor> server # [  161.903914] postgres[1784]: 	    grant all privileges on all tables in schema cron to postgres with grant option;1769vm-test-run-index_advisor> server # [  161.905819] postgres[1784]: 	    revoke all on table cron.job from postgres;1770vm-test-run-index_advisor> server # [  161.906928] postgres[1784]: 	    grant select on table cron.job to postgres with grant option;1771vm-test-run-index_advisor> server # [  161.909969] postgres[1784]: 	  END IF;1772vm-test-run-index_advisor> server # [  161.910762] postgres[1784]: 	END;1773vm-test-run-index_advisor> server # [  161.911054] postgres[1784]: 	$$;1774vm-test-run-index_advisor> server # [  161.929947] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1783]: CREATE FUNCTION1775vm-test-run-index_advisor> server # [  161.933641] postgres[1784]: ::1 2026-10-08 02:48:24.327 UTC [1784] supabase_admin@postgres LOG:  statement: drop event trigger if exists issue_pg_cron_access;1776vm-test-run-index_advisor> server # [  161.946769] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1783]: DROP EVENT TRIGGER1777vm-test-run-index_advisor> server # [  161.953147] postgres[1784]: ::1 2026-10-08 02:48:24.343 UTC [1784] supabase_admin@postgres LOG:  statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1778vm-test-run-index_advisor> server # [  161.961864] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1783]: ALTER FUNCTION1779vm-test-run-index_advisor> server # [  161.964800] postgres[1784]: ::1 2026-10-08 02:48:24.357 UTC [1784] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1780vm-test-run-index_advisor> server # [  161.966223] postgres[1784]: 	         WHEN TAG IN ('CREATE EXTENSION')1781vm-test-run-index_advisor> server # [  161.966922] postgres[1784]: 	   EXECUTE FUNCTION extensions.grant_pg_cron_access();1782vm-test-run-index_advisor> server # [  161.975746] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1783]: CREATE EVENT TRIGGER1783vm-test-run-index_advisor> server # [  162.018506] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1784vm-test-run-index_advisor> server # [  162.274494] postgres[1786]: ::1 2026-10-08 02:48:24.660 UTC [1786] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticator set lock_timeout to '8s';1785vm-test-run-index_advisor> server # [  162.287783] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1785]: ALTER ROLE1786vm-test-run-index_advisor> server # [  162.316830] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1787vm-test-run-index_advisor> server # [  162.659376] postgres[1789]: ::1 2026-10-08 02:48:25.039 UTC [1789] supabase_admin@postgres LOG:  statement: alter function pg_catalog.lo_export owner to supabase_admin;1788vm-test-run-index_advisor> server # [  162.750322] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1787]: ALTER FUNCTION1789vm-test-run-index_advisor> server # [  162.758559] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1787]: ALTER FUNCTION1790vm-test-run-index_advisor> server # [  162.759759] postgres[1789]: ::1 2026-10-08 02:48:25.111 UTC [1789] supabase_admin@postgres LOG:  statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1791vm-test-run-index_advisor> server # [  162.765069] postgres[1789]: ::1 2026-10-08 02:48:25.146 UTC [1789] supabase_admin@postgres LOG:  statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1792vm-test-run-index_advisor> server # [  162.782140] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1787]: ALTER FUNCTION1793vm-test-run-index_advisor> server # [  162.847052] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1794vm-test-run-index_advisor> server # [  163.409362] postgres[1805]: ::1 2026-10-08 02:48:25.759 UTC [1805] supabase_admin@postgres LOG:  statement: grant pg_read_all_data, pg_signal_backend to postgres;1795vm-test-run-index_advisor> server # [  163.450119] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1797]: GRANT ROLE1796vm-test-run-index_advisor> server # [  163.451893] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241031003909_create_orioledb.sql1797vm-test-run-index_advisor> server # [  164.621885] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1807]: DO1798vm-test-run-index_advisor> server # [  164.667801] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1799vm-test-run-index_advisor> server # [  165.087379] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1817]: DO1800vm-test-run-index_advisor> server # [  165.119068] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1817]: DO1801vm-test-run-index_advisor> server # [  165.168556] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1802vm-test-run-index_advisor> server # [  165.569896] postgres[1824]: ::1 2026-10-08 02:48:27.953 UTC [1824] supabase_admin@postgres LOG:  statement: alter role supabase_admin set log_statement = none;1803vm-test-run-index_advisor> server # [  165.595414] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1823]: ALTER ROLE1804vm-test-run-index_advisor> server # [  165.607562] postgres[1824]: ::1 2026-10-08 02:48:27.981 UTC [1824] supabase_admin@postgres LOG:  statement: alter role supabase_auth_admin set log_statement = none;1805vm-test-run-index_advisor> server # [  165.608914] postgres[1824]: ::1 2026-10-08 02:48:27.996 UTC [1824] supabase_admin@postgres LOG:  statement: alter role supabase_storage_admin set log_statement = none;1806vm-test-run-index_advisor> server # [  165.645710] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1823]: ALTER ROLE1807vm-test-run-index_advisor> server # [  165.646816] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1823]: ALTER ROLE1808vm-test-run-index_advisor> server # [  165.676190] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1809vm-test-run-index_advisor> server # [  166.019835] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1825]: DO1810vm-test-run-index_advisor> server # [  166.058540] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1811vm-test-run-index_advisor> server # [  166.515760] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1827]: DO1812vm-test-run-index_advisor> server # [  166.560941] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1813vm-test-run-index_advisor> server # [  167.329845] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1838]: CREATE FUNCTION1814vm-test-run-index_advisor> server # [  167.349206] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1838]: DO1815vm-test-run-index_advisor> server # [  167.399996] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1816vm-test-run-index_advisor> server # [  167.882476] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1854]: ALTER FUNCTION1817vm-test-run-index_advisor> server # [  167.893993] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1854]: GRANT1818vm-test-run-index_advisor> server # [  167.931649] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1819vm-test-run-index_advisor> server # [  168.202203] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1857]: DROP EVENT TRIGGER1820vm-test-run-index_advisor> server # [  168.230046] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1857]: ALTER FUNCTION1821vm-test-run-index_advisor> server # [  168.245767] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1857]: CREATE EVENT TRIGGER1822vm-test-run-index_advisor> server # [  168.289465] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1823vm-test-run-index_advisor> server # [  168.543485] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1859]: DO1824vm-test-run-index_advisor> server # [  168.577607] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql1825vm-test-run-index_advisor> server # [  169.043747] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1861]: CREATE FUNCTION1826vm-test-run-index_advisor> server # [  169.054447] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1861]: GRANT1827vm-test-run-index_advisor> server # [  169.110531] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql1828vm-test-run-index_advisor> server # [  169.574222] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1867]: REVOKE ROLE1829vm-test-run-index_advisor> server # [  169.629299] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1867]: DO1830vm-test-run-index_advisor> server # [  169.682878] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1867]: DO1831vm-test-run-index_advisor> server # [  169.703005] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1867]: REVOKE ROLE1832vm-test-run-index_advisor> server # [  169.732787] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1867]: REVOKE1833vm-test-run-index_advisor> server # [  169.793686] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1867]: DO1834vm-test-run-index_advisor> server # [  169.849848] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql1835vm-test-run-index_advisor> server # [  170.305974] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1878]: DO1836vm-test-run-index_advisor> server # [  170.346779] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql1837vm-test-run-index_advisor> server # [  170.783386] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1889]: DO1838vm-test-run-index_advisor> server # [  170.819158] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql1839vm-test-run-index_advisor> server # [  171.309583] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1896]: DO1840vm-test-run-index_advisor> server # [  171.336691] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql1841vm-test-run-index_advisor> server # [  171.684147] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1899]: ALTER ROLE1842vm-test-run-index_advisor> server # [  171.711420] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251001204436_predefined_role_grants.sql1843vm-test-run-index_advisor> server # [  171.972940] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1901]: GRANT ROLE1844vm-test-run-index_advisor> server # [  171.979409] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1901]: DO1845vm-test-run-index_advisor> server # [  172.022399] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql1846vm-test-run-index_advisor> server # [  172.350280] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1903]: GRANT1847vm-test-run-index_advisor> server # [  172.356373] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql1848vm-test-run-index_advisor> server # [  172.901051] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1905]: CREATE FUNCTION1849vm-test-run-index_advisor> server # [  172.917514] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1905]: REVOKE1850vm-test-run-index_advisor> server # [  172.934735] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1905]: REVOKE1851vm-test-run-index_advisor> server # [  172.953048] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1905]: GRANT1852vm-test-run-index_advisor> server # [  172.998412] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260211120934_supabase_privileged_role.sql1853vm-test-run-index_advisor> server # [  173.515666] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1918]: DO1854vm-test-run-index_advisor> server # [  173.532220] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql1855vm-test-run-index_advisor> server # [  173.856663] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1929]: ALTER ROLE1856vm-test-run-index_advisor> server # [  173.887217] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql1857vm-test-run-index_advisor> server # [  174.181017] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1935]: DROP EXTENSION1858vm-test-run-index_advisor> server # [  174.206232] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql1859vm-test-run-index_advisor> server # [  174.535750] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1937]: CREATE FUNCTION1860vm-test-run-index_advisor> server # [  174.537597] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1937]: DROP EVENT TRIGGER1861vm-test-run-index_advisor> server # [  174.561357] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1937]: CREATE EVENT TRIGGER1862vm-test-run-index_advisor> server # [  174.615268] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql1863vm-test-run-index_advisor> server # [  174.966812] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1942]: DO1864vm-test-run-index_advisor> server # [  174.994029] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1942]: CREATE FUNCTION1865vm-test-run-index_advisor> server # [  175.039564] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql1866vm-test-run-index_advisor> server # [  175.432754] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1945]: CREATE FUNCTION1867vm-test-run-index_advisor> server # [  175.457152] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1945]: CREATE FUNCTION1868vm-test-run-index_advisor> server # [  175.488007] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Running postgresql schema...1869vm-test-run-index_advisor> server # [  175.805340] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER DATABASE1870vm-test-run-index_advisor> server # [  175.811672] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER DATABASE1871vm-test-run-index_advisor> server # [  175.905886] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1872vm-test-run-index_advisor> server # [  175.986183] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1873vm-test-run-index_advisor> server # [  176.042995] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1874vm-test-run-index_advisor> server # [  176.106749] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1875vm-test-run-index_advisor> server # [  176.170953] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1876vm-test-run-index_advisor> server # [  176.257396] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1877vm-test-run-index_advisor> server # [  176.311733] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1878vm-test-run-index_advisor> server # [  176.385579] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1879vm-test-run-index_advisor> server # [  176.466086] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1880vm-test-run-index_advisor> server # [  176.473902] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1957]: ALTER ROLE1881vm-test-run-index_advisor> server # [  176.541320] dz3bhry48s844pz0vma7yzlr62670w72-supabase-db-init[1412]: Database initialization complete1882vm-test-run-index_advisor> server # [  176.583647] systemd[1]: Finished Supabase Database Initialization.1883vm-test-run-index_advisor> server # [  176.588809] systemd[1]: Reached target Multi-User System.1884vm-test-run-index_advisor> server # [  176.594648] systemd[1]: Startup finished in 22.681s (kernel) + 2min 33.904s (userspace) = 2min 56.586s.1885vm-test-run-index_advisor> server: (finished: waiting for unit supabase-db-init.service, in 177.81 seconds)1886vm-test-run-index_advisor> subtest: Verify PostgreSQL 15 is our custom build1887vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();"1888vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.54 seconds)1889vm-test-run-index_advisor> server: must succeed: readlink -f $(which postgres)1890vm-test-run-index_advisor> server: (finished: must succeed: readlink -f $(which postgres), in 0.43 seconds)1891vm-test-run-index_advisor> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 0.97 seconds)1892vm-test-run-index_advisor> subtest: Verify ansible config loaded1893vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;"1894vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.53 seconds)1895vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;"1896vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.78 seconds)1897vm-test-run-index_advisor> (finished: subtest: Verify ansible config loaded, in 1.31 seconds)1898vm-test-run-index_advisor> subtest: Verify init scripts and migrations ran1899vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;"1900vm-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.67 seconds)1901vm-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;"1902vm-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.55 seconds)1903vm-test-run-index_advisor> (finished: subtest: Verify init scripts and migrations ran, in 1.21 seconds)1904vm-test-run-index_advisor> subtest: Check upgrade path with postgresql 151905vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1906vm-test-run-index_advisor> server # NOTICE:  extension "index_advisor" does not exist, skipping1907vm-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.50 seconds)1908vm-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;" 1909vm-test-run-index_advisor> server # NOTICE:  installing required extension "hypopg"1910vm-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.91 seconds)1911vm-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';" 1912vm-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.51 seconds)1913vm-test-run-index_advisor> (finished: subtest: Check upgrade path with postgresql 15, in 2.92 seconds)1914vm-test-run-index_advisor> subtest: Check the install of the last version of the extension1915vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1916vm-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.47 seconds)1917vm-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;" 1918vm-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.49 seconds)1919vm-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';" 1920vm-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.71 seconds)1921vm-test-run-index_advisor> (finished: subtest: Check the install of the last version of the extension, in 2.67 seconds)1922vm-test-run-index_advisor> subtest: switch to postgresql 171923vm-test-run-index_advisor> server # Checking switch inhibitors... done1924vm-test-run-index_advisor> server # stopping the following units: postgresql.service, supabase-db-init.service1925vm-test-run-index_advisor> server # [  194.351406] nixos[2087]: switching to system configuration /nix/store/kiw75yhvkmcswvlc1msgl4996j37qw3w-nixos-system-server-test1926vm-test-run-index_advisor> server # [  194.378968] systemd[1]: Stopped target Remote File Systems.1927vm-test-run-index_advisor> server # [  194.409131] systemd[1]: supabase-db-init.service: Deactivated successfully.1928vm-test-run-index_advisor> server # [  194.410823] systemd[1]: Stopped Supabase Database Initialization.1929vm-test-run-index_advisor> server # [  194.416042] systemd[1]: supabase-db-init.service: Consumed 7.757s CPU time, 3.5M memory peak, 88.3K incoming IP traffic, 121.4K outgoing IP traffic.1930vm-test-run-index_advisor> server # [  194.434841] postgres[1371]:  2026-10-08 02:48:56.814 UTC [1371] LOG:  received fast shutdown request1931vm-test-run-index_advisor> server # [  194.441925] postgres[1371]:  2026-10-08 02:48:56.833 UTC [1371] LOG:  aborting any active transactions1932vm-test-run-index_advisor> server # [  194.442898] systemd[1]: Stopping PostgreSQL Database Server...1933vm-test-run-index_advisor> server # [  194.474170] postgres[1408]:  2026-10-08 02:48:56.846 UTC [1408] FATAL:  terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command1934vm-test-run-index_advisor> server # [  194.489003] postgres[1410]:  2026-10-08 02:48:56.871 UTC [1410] LOG:  pg_cron scheduler shutting down1935vm-test-run-index_advisor> server # [  194.573078] systemd[1]: Stopped target Local File Systems.1936vm-test-run-index_advisor> server # [  194.682151] postgres[1371]:  2026-10-08 02:48:57.068 UTC [1371] LOG:  background worker "TimescaleDB Background Worker Launcher" (PID 1408) exited with exit code 11937vm-test-run-index_advisor> server # [  194.733899] postgres[1371]:  2026-10-08 02:48:57.126 UTC [1371] LOG:  background worker "pg_cron launcher" (PID 1410) exited with exit code 11938vm-test-run-index_advisor> server # [  194.734741] postgres[1371]:  2026-10-08 02:48:57.127 UTC [1371] LOG:  background worker "pg_net 0.20.4 worker" (PID 1448) exited with exit code 11939vm-test-run-index_advisor> server # [  194.740959] postgres[1371]:  2026-10-08 02:48:57.132 UTC [1371] LOG:  background worker "logical replication launcher" (PID 1411) exited with exit code 11940vm-test-run-index_advisor> server # [  194.756275] postgres[1403]:  2026-10-08 02:48:57.143 UTC [1403] LOG:  shutting down1941vm-test-run-index_advisor> server # [  194.766235] postgres[1403]:  2026-10-08 02:48:57.159 UTC [1403] LOG:  checkpoint starting: shutdown immediate1942vm-test-run-index_advisor> server # [  195.096216] postgres[1403]:  2026-10-08 02:48:57.489 UTC [1403] LOG:  checkpoint complete: wrote 280 buffers (1.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.108 s, sync=0.164 s, total=0.346 s; sync files=140, longest=0.017 s, average=0.002 s; distance=2048 kB, estimate=2048 kB1943vm-test-run-index_advisor> server # [  195.234133] postgres[1371]:  2026-10-08 02:48:57.625 UTC [1371] LOG:  database system is shut down1944vm-test-run-index_advisor> server # [  195.330795] systemd[1]: postgresql.service: Deactivated successfully.1945vm-test-run-index_advisor> server # [  195.336998] systemd[1]: Stopped PostgreSQL Database Server.1946vm-test-run-index_advisor> server # [  195.338041] systemd[1]: postgresql.service: Consumed 39.274s CPU time, 125.5M memory peak, 55M written to disk, 125.5K incoming IP traffic, 92.5K outgoing IP traffic.1947vm-test-run-index_advisor> server # activating the configuration...1948vm-test-run-index_advisor> server # [  198.392967] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31949vm-test-run-index_advisor> server # setting up /etc...1950vm-test-run-index_advisor> server # [  201.758928] systemd[1]: Reload requested from client PID 2087 ('.switch-to-conf') (unit backdoor.service)...1951vm-test-run-index_advisor> server # [  201.761949] systemd[1]: Reloading...1952vm-test-run-index_advisor> server # [  206.696803] systemd[1]: Reloading finished in 4920 ms.1953vm-test-run-index_advisor> server # restarting sysinit-reactivation.target1954vm-test-run-index_advisor> server # [  207.076089] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1955vm-test-run-index_advisor> server # [  208.511860] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1956vm-test-run-index_advisor> server # [  208.529874] systemd[1]: Reached target Reactivate sysinit units.1957vm-test-run-index_advisor> server # reloading the following units: dbus.service1958vm-test-run-index_advisor> server # [  208.603522] systemd[1]: Reloading D-Bus System Message Bus...1959vm-test-run-index_advisor> server # [  209.074706] dbus-daemon[639]: Unknown username "systemd-timesync" in message bus configuration file1960vm-test-run-index_advisor> server # [  209.280755] dbus-daemon[639]: [system] Reloaded configuration1961vm-test-run-index_advisor> server # [  209.305642] dbus-send[2266]: method return time=1791427751.678977 sender=org.freedesktop.DBus -> destination=:1.5 serial=3 reply_serial=21962vm-test-run-index_advisor> server # [  209.379977] dbus-daemon[639]: Unknown username "systemd-timesync" in message bus configuration file1963vm-test-run-index_advisor> server # [  209.617097] dbus-daemon[639]: [system] Reloaded configuration1964vm-test-run-index_advisor> server # [  209.622692] systemd[1]: Reloaded D-Bus System Message Bus.1965vm-test-run-index_advisor> server # starting the following units: postgresql.service, supabase-db-init.service1966vm-test-run-index_advisor> server # [  209.780983] systemd[1]: Starting PostgreSQL Major Version Migration...1967vm-test-run-index_advisor> server # [  209.839835] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).1968vm-test-run-index_advisor> server # [  209.846208] systemd[1]: Reached target Local File Systems.1969vm-test-run-index_advisor> server # [  209.955977] systemd[1]: Reached target Remote File Systems.1970vm-test-run-index_advisor> server # [  210.107906] systemd[1]: Starting linger-users.service...1971vm-test-run-index_advisor> server # [  210.161212] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).1972vm-test-run-index_advisor> server # [  210.218043] systemd[1]: Starting Create SUID/SGID Wrappers...1973vm-test-run-index_advisor> server # [  210.219797] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.1974vm-test-run-index_advisor> server # [  210.236861] 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).1975vm-test-run-index_advisor> server # [  210.264939] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2287]: Starting pg_upgrade from 15 to 171976vm-test-run-index_advisor> server # [  210.266145] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).1977vm-test-run-index_advisor> server # [  210.267796] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1978vm-test-run-index_advisor> server # [  210.290567] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1979vm-test-run-index_advisor> server # [  210.345735] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1980vm-test-run-index_advisor> server # [  210.730599] systemd[1]: linger-users.service: Deactivated successfully.1981vm-test-run-index_advisor> server # [  210.742783] systemd[1]: Finished linger-users.service.1982vm-test-run-index_advisor> server # [  211.699605] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: The files belonging to this database system will be owned by user "postgres".1983vm-test-run-index_advisor> server # [  211.716066] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: This user must also own the server process.1984vm-test-run-index_advisor> server # [  211.802388] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: The database cluster will be initialized with locale "en_US.UTF-8".1985vm-test-run-index_advisor> server # [  211.803943] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: The default database encoding has accordingly been set to "UTF8".1986vm-test-run-index_advisor> server # [  211.809476] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: The default text search configuration will be set to "english".1987vm-test-run-index_advisor> server # [  211.810484] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: Data page checksums are enabled.1988vm-test-run-index_advisor> server # [  211.811077] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: fixing permissions on existing directory /var/lib/postgresql/data-17 ... ok1989vm-test-run-index_advisor> server # [  211.827415] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: creating subdirectories ... ok1990vm-test-run-index_advisor> server # [  211.840074] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: selecting dynamic shared memory implementation ... posix1991vm-test-run-index_advisor> server # [  212.934281] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: selecting default "max_connections" ... 1001992vm-test-run-index_advisor> server # [  213.905031] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: selecting default "shared_buffers" ... 128MB1993vm-test-run-index_advisor> server # [  217.540631] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1994vm-test-run-index_advisor> server # [  217.553524] systemd[1]: Finished Create SUID/SGID Wrappers.1995vm-test-run-index_advisor> server # [  217.569213] systemd[1]: suid-sgid-wrappers.service: Consumed 2.918s CPU time, 3.3M memory peak.1996vm-test-run-index_advisor> server # [  219.856913] systemd[1]: Starting Virtual Console Setup...1997vm-test-run-index_advisor> server # [  220.201253] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1998vm-test-run-index_advisor> server # [  220.224998] systemd[1]: Stopped Virtual Console Setup.1999vm-test-run-index_advisor> server # [  220.286439] systemd[1]: Starting Virtual Console Setup...2000vm-test-run-index_advisor> server # [  222.866871] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2001vm-test-run-index_advisor> server # [  224.730909] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: selecting default time zone ... UTC2002vm-test-run-index_advisor> server # [  224.764199] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: creating configuration files ... ok2003vm-test-run-index_advisor> server # [  225.149923] systemd[1]: Finished Virtual Console Setup.2004vm-test-run-index_advisor> server # [  226.849792] systemd-logind[657]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2005vm-test-run-index_advisor> server # [  227.482584] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: running bootstrap script ... ok2006vm-test-run-index_advisor> server # [  232.369454] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: performing post-bootstrap initialization ... ok2007vm-test-run-index_advisor> server # [  233.549655] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: syncing data to disk ... ok2008vm-test-run-index_advisor> server # [  233.551270] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: initdb: warning: enabling "trust" authentication for local connections2009vm-test-run-index_advisor> server # [  233.552551] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: 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.2010vm-test-run-index_advisor> server # [  233.554039] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]: Success. You can now start the database server using:2011vm-test-run-index_advisor> server # [  233.556274] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2291]:     /nix/store/j44h1fl3aidnvj3wh7kscaynxlmgs293-postgresql-and-plugins-17.11/bin/pg_ctl -D /var/lib/postgresql/data-17 -l logfile start2012vm-test-run-index_advisor> server # [  236.872426] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Performing Consistency Checks2013vm-test-run-index_advisor> server # [  236.934627] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: -----------------------------2014vm-test-run-index_advisor> server # [  236.954010] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking cluster versions                                     ok2015vm-test-run-index_advisor> server # [  239.478118] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking database user is the install user                    ok2016vm-test-run-index_advisor> server # [  239.532045] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking database connection settings                         ok2017vm-test-run-index_advisor> server # [  239.628751] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking for prepared transactions                            ok2018vm-test-run-index_advisor> server # [  239.638485] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking for contrib/isn with bigint-passing mismatch         ok2019vm-test-run-index_advisor> server # [  240.214743] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking data type usage                                      ok2020vm-test-run-index_advisor> server # [  240.402232] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking for not-null constraint inconsistencies              ok2021vm-test-run-index_advisor> server # [  240.933738] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Creating dump of global objects                               ok2022vm-test-run-index_advisor> server # [  243.201667] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Creating dump of database schemas                             ok2023vm-test-run-index_advisor> server # [  245.933213] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking for presence of required libraries                   ok2024vm-test-run-index_advisor> server # [  246.003963] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking database user is the install user                    ok2025vm-test-run-index_advisor> server # [  246.077102] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking for prepared transactions                            ok2026vm-test-run-index_advisor> server # [  246.089064] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Checking for new cluster tablespace directories               ok2027vm-test-run-index_advisor> server # [  246.090549] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: If pg_upgrade fails after this point, you must re-initdb the2028vm-test-run-index_advisor> server # [  246.091327] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: new cluster before continuing.2029vm-test-run-index_advisor> server # [  246.091992] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Performing Upgrade2030vm-test-run-index_advisor> server # [  246.094443] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: ------------------2031vm-test-run-index_advisor> server # [  246.141060] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Setting locale and encoding for new cluster                   ok2032vm-test-run-index_advisor> server # [  248.158783] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Analyzing all rows in the new cluster                         ok2033vm-test-run-index_advisor> server # [  249.056970] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Freezing all rows in the new cluster                          ok2034vm-test-run-index_advisor> server # [  249.378144] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Deleting files from new pg_xact                               ok2035vm-test-run-index_advisor> server # [  249.483450] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Copying old pg_xact to new server                             ok2036vm-test-run-index_advisor> server # [  249.759054] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Setting oldest XID for new cluster                            ok2037vm-test-run-index_advisor> server # [  250.575985] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Setting next transaction ID and epoch for new cluster         ok2038vm-test-run-index_advisor> server # [  250.582463] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Deleting files from new pg_multixact/offsets                  ok2039vm-test-run-index_advisor> server # [  250.678878] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Copying old pg_multixact/offsets to new server                ok2040vm-test-run-index_advisor> server # [  250.681803] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Deleting files from new pg_multixact/members                  ok2041vm-test-run-index_advisor> server # [  250.780659] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Copying old pg_multixact/members to new server                ok2042vm-test-run-index_advisor> server # [  251.065882] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Setting next multixact ID and offset for new cluster          ok2043vm-test-run-index_advisor> server # [  251.350181] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Resetting WAL archives                                        ok2044vm-test-run-index_advisor> server # [  253.253949] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Setting frozenxid and minmxid counters in new cluster         ok2045vm-test-run-index_advisor> server # [  254.145040] 49jli7b1sj2ialdxwishdr7w3x0k5pk3-postgresql-migrate[2483]: Restoring global objects in the new cluster                   ok