nixbot

1these 7 derivations will be built:2  /nix/store/70sdqqdnwx6afv4zdshk82pxjfl5gxgh-nixos-system-server-test.drv3  /nix/store/mpb0jm3m74l8rnvhmh66idp5f7n1jb63-nixos-system-server-test.drv4  /nix/store/m7zar2dkjwlqzw2332bpjx2d4w6fvh3s-closure-info.drv5  /nix/store/dwz4cw00kb545849z25rkxcwyz1pplkq-run-nixos-vm.drv6  /nix/store/y7mljpkzqf3zgxp9srgsxix1npvbqdyz-nixos-vm.drv7  /nix/store/5p0y5lnqpj2iyf0i2h0nww6bagba1bq6-nixos-test-driver-index_advisor.drv8  /nix/store/anxi02qh2yyf32jrj4cjj7x9wf4c1qk1-vm-test-run-index_advisor.drv9building '/nix/store/70sdqqdnwx6afv4zdshk82pxjfl5gxgh-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'10building '/nix/store/70sdqqdnwx6afv4zdshk82pxjfl5gxgh-nixos-system-server-test.drv'11building '/nix/store/5p0y5lnqpj2iyf0i2h0nww6bagba1bq6-nixos-test-driver-index_advisor.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'12building '/nix/store/5p0y5lnqpj2iyf0i2h0nww6bagba1bq6-nixos-test-driver-index_advisor.drv'13nixos-test-driver-index_advisor> Running type check (enable/disable: config.skipTypeCheck)14nixos-test-driver-index_advisor> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck15nixos-test-driver-index_advisor> Success: no issues found in 1 source file16nixos-test-driver-index_advisor> additionally exposed symbols:17nixos-test-driver-index_advisor>     ,18nixos-test-driver-index_advisor>     ,19nixos-test-driver-index_advisor>     start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug20nixos-test-driver-index_advisor> Linting test script (enable/disable: config.skipLint)21nixos-test-driver-index_advisor> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint22building '/nix/store/anxi02qh2yyf32jrj4cjj7x9wf4c1qk1-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder'23building '/nix/store/anxi02qh2yyf32jrj4cjj7x9wf4c1qk1-vm-test-run-index_advisor.drv'24vm-test-run-index_advisor> Machine state will be reset. To keep it, pass --keep-vm-state25vm-test-run-index_advisor> start all VLans26vm-test-run-index_advisor> start vlan27vm-test-run-index_advisor> running vlan (pid 7; ctl /build/vde1.ctl)28vm-test-run-index_advisor> (finished: start all VLans, in 0.00 seconds)29vm-test-run-index_advisor> Test will time out and terminate in 3600 seconds30vm-test-run-index_advisor> run the VM test script31vm-test-run-index_advisor> additionally exposed symbols:32vm-test-run-index_advisor>     server,33vm-test-run-index_advisor>     vlan1,34vm-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, debug35vm-test-run-index_advisor> start all VMs36vm-test-run-index_advisor> server: starting vm37vm-test-run-index_advisor> mke2fs 1.47.3 (8-Jul-2025)38vm-test-run-index_advisor> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory39vm-test-run-index_advisor> qemu-system-aarch64: failed to initialize kvm: No such file or directory40vm-test-run-index_advisor> qemu-system-aarch64: falling back to tcg41vm-test-run-index_advisor> server # Disk image does not exist, creating the virtualisation disk image...42vm-test-run-index_advisor> server # Formatting '/build/vm-state-server/tmp.ikn7qPk7nR', fmt=raw size=107374182443vm-test-run-index_advisor> server # Discarding device blocks:      0/262144             done44vm-test-run-index_advisor> server # Creating filesystem with 262144 4k blocks and 65536 inodes45vm-test-run-index_advisor> server # Filesystem UUID: 234ae3b6-d5e7-4de2-98a4-ca8a583314a746vm-test-run-index_advisor> server # Superblock backups stored on blocks:47vm-test-run-index_advisor> server # 	32768, 98304, 163840, 22937648vm-test-run-index_advisor> server # 49vm-test-run-index_advisor> server # Allocating group tables: 0/8   done50vm-test-run-index_advisor> server # Writing inode tables: 0/8   done51vm-test-run-index_advisor> server # Creating journal (8192 blocks): done52vm-test-run-index_advisor> server # Writing superblocks and filesystem accounting information: 0/8   done53vm-test-run-index_advisor> server # 54vm-test-run-index_advisor> server # Virtualisation disk image created.55vm-test-run-index_advisor> server: QEMU running (pid 9)56vm-test-run-index_advisor> (finished: start all VMs, in 0.14 seconds)57vm-test-run-index_advisor> server: waiting for unit supabase-db-init.service58vm-test-run-index_advisor> server: waiting for the VM to finish booting59vm-test-run-index_advisor> server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]60vm-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 202561vm-test-run-index_advisor> server # [    0.000000] KASLR enabled62vm-test-run-index_advisor> server # [    0.000000] random: crng init done63vm-test-run-index_advisor> server # [    0.000000] Machine model: linux,dummy-virt64vm-test-run-index_advisor> server # [    0.000000] efi: UEFI not found.65vm-test-run-index_advisor> server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT66vm-test-run-index_advisor> server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]67vm-test-run-index_advisor> server # [    0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]68vm-test-run-index_advisor> server # [    0.000000] Zone ranges:69vm-test-run-index_advisor> server # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]70vm-test-run-index_advisor> server # [    0.000000]   DMA32    empty71vm-test-run-index_advisor> server # [    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]72vm-test-run-index_advisor> server # [    0.000000]   Device   empty73vm-test-run-index_advisor> server # [    0.000000] Movable zone start for each node74vm-test-run-index_advisor> server # [    0.000000] Early memory node ranges75vm-test-run-index_advisor> server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]76vm-test-run-index_advisor> server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]77vm-test-run-index_advisor> server # [    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -178vm-test-run-index_advisor> server # [    0.000000] psci: probing for conduit method from DT.79vm-test-run-index_advisor> server # [    0.000000] psci: PSCIv1.1 detected in firmware.80vm-test-run-index_advisor> server # [    0.000000] psci: Using standard PSCI v0.2 function IDs81vm-test-run-index_advisor> server # [    0.000000] psci: Trusted OS migration not required82vm-test-run-index_advisor> server # [    0.000000] psci: SMC Calling Convention v1.083vm-test-run-index_advisor> server # [    0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u36044884vm-test-run-index_advisor> server # [    0.000000] Detected PIPT I-cache on CPU085vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)86vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: GIC system register CPU interface87vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: HCRX_EL2 register88vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)89vm-test-run-index_advisor> server # [    0.000000] CPU features: detected: Spectre-v490vm-test-run-index_advisor> server # [    0.000000] alternatives: applying boot alternatives91vm-test-run-index_advisor> server # [    0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/9im41iz4mgfwdf5fyng9jp1364k6mdbn-nixos-system-server-test/init regInfo=/nix/store/xv1849s6gakrdhbrf3sljgqz6kxjmg19-closure-info/registration console=ttyAMA0,115200n8 console=tty092vm-test-run-index_advisor> server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/xv1849s6gakrdhbrf3sljgqz6kxjmg19-closure-info/registration", will be passed to user space.93vm-test-run-index_advisor> server # [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)94vm-test-run-index_advisor> server # [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)95vm-test-run-index_advisor> server # [    0.000000] Fallback order for Node 0: 096vm-test-run-index_advisor> server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 104857697vm-test-run-index_advisor> server # [    0.000000] Policy zone: Normal98vm-test-run-index_advisor> server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off99vm-test-run-index_advisor> server # [    0.000000] software IO TLB: area num 2.100vm-test-run-index_advisor> server # [    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)101vm-test-run-index_advisor> server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1102vm-test-run-index_advisor> server # [    0.000000] allocated 8388608 bytes of page_ext103vm-test-run-index_advisor> server # [    0.000000] ftrace: allocating 72257 entries in 283 pages104vm-test-run-index_advisor> server # [    0.000000] ftrace: allocated 283 pages with 5 groups105vm-test-run-index_advisor> server # [    0.000000] rcu: Hierarchical RCU implementation.106vm-test-run-index_advisor> server # [    0.000000] rcu: 	RCU event tracing is enabled.107vm-test-run-index_advisor> server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.108vm-test-run-index_advisor> server # [    0.000000] 	Rude variant of Tasks RCU enabled.109vm-test-run-index_advisor> server # [    0.000000] 	Tracing variant of Tasks RCU enabled.110vm-test-run-index_advisor> server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.111vm-test-run-index_advisor> server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2112vm-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.113vm-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.114vm-test-run-index_advisor> server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0115vm-test-run-index_advisor> server # [    0.000000] GICv3: 256 SPIs implemented116vm-test-run-index_advisor> server # [    0.000000] GICv3: 0 Extended SPIs implemented117vm-test-run-index_advisor> server # [    0.000000] Root IRQ handler: gic_handle_irq118vm-test-run-index_advisor> server # [    0.000000] GICv3: GICv3 features: 16 PPIs119vm-test-run-index_advisor> server # [    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0120vm-test-run-index_advisor> server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000121vm-test-run-index_advisor> server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]122vm-test-run-index_advisor> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1)123vm-test-run-index_advisor> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1)124vm-test-run-index_advisor> server # [    0.000000] GICv3: using LPI property table @0x0000000100b00000125vm-test-run-index_advisor> server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000126vm-test-run-index_advisor> server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.127vm-test-run-index_advisor> server # [    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).128vm-test-run-index_advisor> server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns129vm-test-run-index_advisor> server # [    0.000035] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns130vm-test-run-index_advisor> server # [    0.003571] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)131vm-test-run-index_advisor> server # [    0.013088] Console: colour dummy device 80x25132vm-test-run-index_advisor> server # [    0.014430] printk: legacy console [tty0] enabled133vm-test-run-index_advisor> server # [    0.020150] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)134vm-test-run-index_advisor> server # [    0.020517] pid_max: default: 32768 minimum: 301135vm-test-run-index_advisor> server # [    0.022617] LSM: initializing lsm=capability,landlock,yama,bpf136vm-test-run-index_advisor> server # [    0.024269] landlock: Up and running.137vm-test-run-index_advisor> server # [    0.024333] Yama: becoming mindful.138vm-test-run-index_advisor> server # [    0.028212] LSM support for eBPF active139vm-test-run-index_advisor> server # [    0.031502] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)140vm-test-run-index_advisor> server # [    0.031674] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)141vm-test-run-index_advisor> server # [    0.060663] cacheinfo: Unable to detect cache hierarchy for CPU 0142vm-test-run-index_advisor> server # [    0.072587] rcu: Hierarchical SRCU implementation.143vm-test-run-index_advisor> server # [    0.072676] rcu: 	Max phase no-delay instances is 1000.144vm-test-run-index_advisor> server # [    0.074095] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level145vm-test-run-index_advisor> server # [    0.081765] fsl-mc MSI: its@8080000 domain created146vm-test-run-index_advisor> server # [    0.083573] EFI services will not be available.147vm-test-run-index_advisor> server # [    0.085494] smp: Bringing up secondary CPUs ...148vm-test-run-index_advisor> server # [    0.089443] Detected PIPT I-cache on CPU1149vm-test-run-index_advisor> server # [    0.090580] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000150vm-test-run-index_advisor> server # [    0.091454] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000151vm-test-run-index_advisor> server # [    0.093125] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]152vm-test-run-index_advisor> server # [    0.096557] smp: Brought up 1 node, 2 CPUs153vm-test-run-index_advisor> server # [    0.096692] SMP: Total of 2 processors activated.154vm-test-run-index_advisor> server # [    0.096736] CPU: All CPU(s) started at EL1155vm-test-run-index_advisor> server # [    0.096873] CPU features: detected: Branch Target Identification156vm-test-run-index_advisor> server # [    0.096918] CPU features: detected: 32-bit EL0 Support157vm-test-run-index_advisor> server # [    0.096943] CPU features: detected: 32-bit EL1 Support158vm-test-run-index_advisor> server # [    0.096964] CPU features: detected: ARMv8.4 Translation Table Level159vm-test-run-index_advisor> server # [    0.096992] CPU features: detected: Instruction cache invalidation not required for I/D coherence160vm-test-run-index_advisor> server # [    0.097026] CPU features: detected: Data cache clean to the PoU not required for I/D coherence161vm-test-run-index_advisor> server # [    0.097080] CPU features: detected: Common not Private translations162vm-test-run-index_advisor> server # [    0.097102] CPU features: detected: CRC32 instructions163vm-test-run-index_advisor> server # [    0.097121] CPU features: detected: Data cache clean to Point of Deep Persistence164vm-test-run-index_advisor> server # [    0.097150] CPU features: detected: Data cache clean to Point of Persistence165vm-test-run-index_advisor> server # [    0.097172] CPU features: detected: Data independent timing control (DIT)166vm-test-run-index_advisor> server # [    0.097263] CPU features: detected: E0PD167vm-test-run-index_advisor> server # [    0.097368] CPU features: detected: Enhanced Counter Virtualization168vm-test-run-index_advisor> server # [    0.097408] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)169vm-test-run-index_advisor> server # [    0.097439] CPU features: detected: Enhanced Privileged Access Never170vm-test-run-index_advisor> server # [    0.097464] CPU features: detected: Enhanced Virtualization Traps171vm-test-run-index_advisor> server # [    0.097489] CPU features: detected: Fine Grained Traps172vm-test-run-index_advisor> server # [    0.097552] CPU features: detected: Generic authentication (IMP DEF algorithm)173vm-test-run-index_advisor> server # [    0.097642] CPU features: detected: RCpc load-acquire (LDAPR)174vm-test-run-index_advisor> server # [    0.097689] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)175vm-test-run-index_advisor> server # [    0.097714] CPU features: detected: LSE atomic instructions176vm-test-run-index_advisor> server # [    0.097738] CPU features: detected: Memory Copy and Memory Set instructions177vm-test-run-index_advisor> server # [    0.097763] CPU features: detected: Privileged Access Never178vm-test-run-index_advisor> server # [    0.097786] CPU features: detected: RAS Extension Support179vm-test-run-index_advisor> server # [    0.097811] CPU features: detected: Random Number Generator180vm-test-run-index_advisor> server # [    0.097831] CPU features: detected: Speculation barrier (SB)181vm-test-run-index_advisor> server # [    0.097851] CPU features: detected: Stage-2 Force Write-Back182vm-test-run-index_advisor> server # [    0.097871] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality183vm-test-run-index_advisor> server # [    0.097899] CPU features: detected: TLB range maintenance instructions184vm-test-run-index_advisor> server # [    0.097924] CPU features: detected: WFx with timeout185vm-test-run-index_advisor> server # [    0.097967] CPU features: detected: Speculative Store Bypassing Safe (SSBS)186vm-test-run-index_advisor> server # [    0.097994] CPU features: detected: Scalable Vector Extension187vm-test-run-index_advisor> server # [    0.102090] alternatives: applying system-wide alternatives188vm-test-run-index_advisor> server # [    0.120554] CPU features: detected: Hardware dirty bit management on CPU0-1189vm-test-run-index_advisor> server # [    0.121139] SVE: maximum available vector length 256 bytes per vector190vm-test-run-index_advisor> server # [    0.121223] SVE: default vector length 64 bytes per vector191vm-test-run-index_advisor> server # [    0.133670] Memory: 3922924K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved)192vm-test-run-index_advisor> server # [    0.154998] devtmpfs: initialized193vm-test-run-index_advisor> server # [    0.180773] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns194vm-test-run-index_advisor> server # [    0.181239] futex hash table entries: 512 (order: 3, 32768 bytes, linear)195vm-test-run-index_advisor> server # [    0.183768] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL196vm-test-run-index_advisor> server # [    0.183841] 0 pages in range for non-PLT usage197vm-test-run-index_advisor> server # [    0.183882] 509248 pages in range for PLT usage198vm-test-run-index_advisor> server # [    0.186110] pinctrl core: initialized pinctrl subsystem199vm-test-run-index_advisor> server # [    0.196079] DMI not present or invalid.200vm-test-run-index_advisor> server # [    0.216470] NET: Registered PF_NETLINK/PF_ROUTE protocol family201vm-test-run-index_advisor> server # [    0.229541] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations202vm-test-run-index_advisor> server # [    0.230621] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations203vm-test-run-index_advisor> server # [    0.231670] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations204vm-test-run-index_advisor> server # [    0.231926] audit: initializing netlink subsys (disabled)205vm-test-run-index_advisor> server # [    0.233817] audit: type=2000 audit(0.164:1): state=initialized audit_enabled=0 res=1206vm-test-run-index_advisor> server # [    0.239286] thermal_sys: Registered thermal governor 'fair_share'207vm-test-run-index_advisor> server # [    0.239340] thermal_sys: Registered thermal governor 'bang_bang'208vm-test-run-index_advisor> server # [    0.239394] thermal_sys: Registered thermal governor 'step_wise'209vm-test-run-index_advisor> server # [    0.239418] thermal_sys: Registered thermal governor 'user_space'210vm-test-run-index_advisor> server # [    0.239443] thermal_sys: Registered thermal governor 'power_allocator'211vm-test-run-index_advisor> server # [    0.240257] cpuidle: using governor ladder212vm-test-run-index_advisor> server # [    0.240540] cpuidle: using governor menu213vm-test-run-index_advisor> server # [    0.242328] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.214vm-test-run-index_advisor> server # [    0.243546] ASID allocator initialised with 65536 entries215vm-test-run-index_advisor> server # [    0.250503] Serial: AMBA PL011 UART driver216vm-test-run-index_advisor> server # [    0.296435] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1217vm-test-run-index_advisor> server # [    0.298595] printk: legacy console [ttyAMA0] enabled218vm-test-run-index_advisor> server # [    0.332865] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages219vm-test-run-index_advisor> server # [    0.333054] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page220vm-test-run-index_advisor> server # [    0.333285] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages221vm-test-run-index_advisor> server # [    0.333385] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page222vm-test-run-index_advisor> server # [    0.333779] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages223vm-test-run-index_advisor> server # [    0.333871] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page224vm-test-run-index_advisor> server # [    0.333959] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages225vm-test-run-index_advisor> server # [    0.334065] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page226vm-test-run-index_advisor> server # [    0.345901] fbcon: Taking over console227vm-test-run-index_advisor> server # [    0.346672] ACPI: Interpreter disabled.228vm-test-run-index_advisor> server # [    0.354034] iommu: Default domain type: Translated229vm-test-run-index_advisor> server # [    0.354230] iommu: DMA domain TLB invalidation policy: strict mode230vm-test-run-index_advisor> server # [    0.357262] SCSI subsystem initialized231vm-test-run-index_advisor> server # [    0.359881] usbcore: registered new interface driver usbfs232vm-test-run-index_advisor> server # [    0.360850] usbcore: registered new interface driver hub233vm-test-run-index_advisor> server # [    0.361235] usbcore: registered new device driver usb234vm-test-run-index_advisor> server # [    0.364343] pps_core: LinuxPPS API ver. 1 registered235vm-test-run-index_advisor> server # [    0.364960] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>236vm-test-run-index_advisor> server # [    0.365627] PTP clock support registered237vm-test-run-index_advisor> server # [    0.366290] EDAC MC: Ver: 3.0.0238vm-test-run-index_advisor> server # [    0.368102] scmi_core: SCMI protocol bus registered239vm-test-run-index_advisor> server # [    0.371670] FPGA manager framework240vm-test-run-index_advisor> server # [    0.373070] Advanced Linux Sound Architecture Driver Initialized.241vm-test-run-index_advisor> server # [    0.381344] vgaarb: loaded242vm-test-run-index_advisor> server # [    0.385522] clocksource: Switched to clocksource arch_sys_counter243vm-test-run-index_advisor> server # [    0.426238] VFS: Disk quotas dquot_6.6.0244vm-test-run-index_advisor> server # [    0.427216] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)245vm-test-run-index_advisor> server # [    0.428911] netfs: FS-Cache loaded246vm-test-run-index_advisor> server # [    0.429992] pnp: PnP ACPI: disabled247vm-test-run-index_advisor> server # [    0.454217] NET: Registered PF_INET protocol family248vm-test-run-index_advisor> server # [    0.456077] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)249vm-test-run-index_advisor> server # [    0.566451] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)250vm-test-run-index_advisor> server # [    0.567512] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)251vm-test-run-index_advisor> server # [    0.568460] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)252vm-test-run-index_advisor> server # [    0.569753] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)253vm-test-run-index_advisor> server # [    0.571123] TCP: Hash tables configured (established 32768 bind 32768)254vm-test-run-index_advisor> server # [    0.572930] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)255vm-test-run-index_advisor> server # [    0.574124] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)256vm-test-run-index_advisor> server # [    0.574512] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)257vm-test-run-index_advisor> server # [    0.576499] NET: Registered PF_UNIX/PF_LOCAL protocol family258vm-test-run-index_advisor> server # [    0.576953] NET: Registered PF_XDP protocol family259vm-test-run-index_advisor> server # [    0.577529] PCI: CLS 0 bytes, default 64260vm-test-run-index_advisor> server # [    0.581084] kvm [1]: HYP mode not available261vm-test-run-index_advisor> server # [    0.583129] Trying to unpack rootfs image as initramfs...262vm-test-run-index_advisor> server # [    0.688349] Initialise system trusted keyrings263vm-test-run-index_advisor> server # [    0.690180] workingset: timestamp_bits=42 max_order=20 bucket_order=0264vm-test-run-index_advisor> server # [    0.694372] squashfs: version 4.0 (2009/01/31) Phillip Lougher265vm-test-run-index_advisor> server # [    0.695086] 9p: Installing v9fs 9p2000 file system support266vm-test-run-index_advisor> server # [    0.756078] Key type asymmetric registered267vm-test-run-index_advisor> server # [    0.756314] Asymmetric key parser 'x509' registered268vm-test-run-index_advisor> server # [    0.757096] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)269vm-test-run-index_advisor> server # [    0.759395] io scheduler mq-deadline registered270vm-test-run-index_advisor> server # [    0.759624] io scheduler kyber registered271vm-test-run-index_advisor> server # [    0.787519] pl061_gpio 9030000.pl061: PL061 GPIO chip registered272vm-test-run-index_advisor> server # [    0.792370] ledtrig-cpu: registered to indicate activity on CPUs273vm-test-run-index_advisor> server # [    0.796323] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:274vm-test-run-index_advisor> server # [    0.797183] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000275vm-test-run-index_advisor> server # [    0.798642] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000276vm-test-run-index_advisor> server # [    0.798869] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000277vm-test-run-index_advisor> server # [    0.799420] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits278vm-test-run-index_advisor> server # [    0.800136] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]279vm-test-run-index_advisor> server # [    0.801614] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00280vm-test-run-index_advisor> server # [    0.802661] pci_bus 0000:00: root bus resource [bus 00-ff]281vm-test-run-index_advisor> server # [    0.802846] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]282vm-test-run-index_advisor> server # [    0.802995] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]283vm-test-run-index_advisor> server # [    0.803101] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]284vm-test-run-index_advisor> server # [    0.804252] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint285vm-test-run-index_advisor> server # [    0.807523] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint286vm-test-run-index_advisor> server # [    0.808015] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]287vm-test-run-index_advisor> server # [    0.808257] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]288vm-test-run-index_advisor> server # [    0.808416] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]289vm-test-run-index_advisor> server # [    0.808852] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]290vm-test-run-index_advisor> server # [    0.809801] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint291vm-test-run-index_advisor> server # [    0.810302] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]292vm-test-run-index_advisor> server # [    0.810570] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]293vm-test-run-index_advisor> server # [    0.810708] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294vm-test-run-index_advisor> server # [    0.811198] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295vm-test-run-index_advisor> server # [    0.811346] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]296vm-test-run-index_advisor> server # [    0.811445] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]297vm-test-run-index_advisor> server # [    0.811559] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298vm-test-run-index_advisor> server # [    0.811979] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint299vm-test-run-index_advisor> server # [    0.812121] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]300vm-test-run-index_advisor> server # [    0.812211] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]301vm-test-run-index_advisor> server # [    0.812319] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302vm-test-run-index_advisor> server # [    0.812753] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint303vm-test-run-index_advisor> server # [    0.812893] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]304vm-test-run-index_advisor> server # [    0.812987] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]305vm-test-run-index_advisor> server # [    0.813092] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]306vm-test-run-index_advisor> server # [    0.813545] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint307vm-test-run-index_advisor> server # [    0.813682] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]308vm-test-run-index_advisor> server # [    0.813770] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]309vm-test-run-index_advisor> server # [    0.813877] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]310vm-test-run-index_advisor> server # [    0.815560] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint311vm-test-run-index_advisor> server # [    0.816013] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]312vm-test-run-index_advisor> server # [    0.816260] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]313vm-test-run-index_advisor> server # [    0.816396] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]314vm-test-run-index_advisor> server # [    0.816510] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]315vm-test-run-index_advisor> server # [    0.817013] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint316vm-test-run-index_advisor> server # [    0.817186] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]317vm-test-run-index_advisor> server # [    0.817302] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]318vm-test-run-index_advisor> server # [    0.818693] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint319vm-test-run-index_advisor> server # [    0.818898] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]320vm-test-run-index_advisor> server # [    0.819020] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]321vm-test-run-index_advisor> server # [    0.819548] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint322vm-test-run-index_advisor> server # [    0.819925] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]323vm-test-run-index_advisor> server # [    0.820406] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint324vm-test-run-index_advisor> server # [    0.820587] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]325vm-test-run-index_advisor> server # [    0.820678] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]326vm-test-run-index_advisor> server # [    0.820788] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]327vm-test-run-index_advisor> server # [    0.821310] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint328vm-test-run-index_advisor> server # [    0.821470] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]329vm-test-run-index_advisor> server # [    0.821559] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]330vm-test-run-index_advisor> server # [    0.821671] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]331vm-test-run-index_advisor> server # [    0.824318] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned332vm-test-run-index_advisor> server # [    0.824622] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned333vm-test-run-index_advisor> server # [    0.824800] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned334vm-test-run-index_advisor> server # [    0.825009] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned335vm-test-run-index_advisor> server # [    0.825173] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned336vm-test-run-index_advisor> server # [    0.825308] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned337vm-test-run-index_advisor> server # [    0.825542] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned338vm-test-run-index_advisor> server # [    0.825880] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned339vm-test-run-index_advisor> server # [    0.826307] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned340vm-test-run-index_advisor> server # [    0.826453] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned341vm-test-run-index_advisor> server # [    0.826596] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned342vm-test-run-index_advisor> server # [    0.827161] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned343vm-test-run-index_advisor> server # [    0.827324] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned344vm-test-run-index_advisor> server # [    0.827465] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned345vm-test-run-index_advisor> server # [    0.827788] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned346vm-test-run-index_advisor> server # [    0.828189] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned347vm-test-run-index_advisor> server # [    0.828313] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned348vm-test-run-index_advisor> server # [    0.828429] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned349vm-test-run-index_advisor> server # [    0.828558] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned350vm-test-run-index_advisor> server # [    0.828671] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned351vm-test-run-index_advisor> server # [    0.828779] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned352vm-test-run-index_advisor> server # [    0.828901] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned353vm-test-run-index_advisor> server # [    0.829016] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned354vm-test-run-index_advisor> server # [    0.829145] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned355vm-test-run-index_advisor> server # [    0.829255] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned356vm-test-run-index_advisor> server # [    0.829844] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned357vm-test-run-index_advisor> server # [    0.829972] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned358vm-test-run-index_advisor> server # [    0.830648] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned359vm-test-run-index_advisor> server # [    0.830760] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned360vm-test-run-index_advisor> server # [    0.830867] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned361vm-test-run-index_advisor> server # [    0.830968] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned362vm-test-run-index_advisor> server # [    0.831066] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned363vm-test-run-index_advisor> server # [    0.831170] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned364vm-test-run-index_advisor> server # [    0.831275] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned365vm-test-run-index_advisor> server # [    0.831565] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]366vm-test-run-index_advisor> server # [    0.831682] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]367vm-test-run-index_advisor> server # [    0.831783] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]368vm-test-run-index_advisor> server # [    0.832693] pci 0000:00:0a.0: enabling device (0000 -> 0002)369vm-test-run-index_advisor> server # [    0.890625] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)370vm-test-run-index_advisor> server # [    0.893959] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)371vm-test-run-index_advisor> server # [    0.896578] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)372vm-test-run-index_advisor> server # [    0.899338] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)373vm-test-run-index_advisor> server # [    0.901095] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)374vm-test-run-index_advisor> server # [    0.903016] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)375vm-test-run-index_advisor> server # [    0.904798] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)376vm-test-run-index_advisor> server # [    0.907642] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)377vm-test-run-index_advisor> server # [    0.909312] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)378vm-test-run-index_advisor> server # [    0.912114] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)379vm-test-run-index_advisor> server # [    0.914142] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)380vm-test-run-index_advisor> server # [    0.930738] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled381vm-test-run-index_advisor> server # [    0.942606] msm_serial: driver initialized382vm-test-run-index_advisor> server # [    0.943481] SuperH (H)SCI(F) driver initialized383vm-test-run-index_advisor> server # [    0.944262] STM32 USART driver initialized384vm-test-run-index_advisor> server # [    0.992350] loop: module loaded385vm-test-run-index_advisor> server # [    0.993008] virtio_blk virtio5: 2/0/0 default/read/poll queues386vm-test-run-index_advisor> server # [    0.996437] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)387vm-test-run-index_advisor> server # [    1.009033] megasas: 07.727.03.00-rc1388vm-test-run-index_advisor> server # [    1.014920] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]389vm-test-run-index_advisor> server # [    1.017083] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000390vm-test-run-index_advisor> server # [    1.018992] Intel/Sharp Extended Query Table at 0x0031391vm-test-run-index_advisor> server # [    1.020402] Using buffer write method392vm-test-run-index_advisor> server # [    1.021383] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]393vm-test-run-index_advisor> server # [    1.024163] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000394vm-test-run-index_advisor> server # [    1.024479] Intel/Sharp Extended Query Table at 0x0031395vm-test-run-index_advisor> server # [    1.026497] Using buffer write method396vm-test-run-index_advisor> server # [    1.027100] Concatenating MTD devices:397vm-test-run-index_advisor> server # [    1.027187] (0): "0.flash"398vm-test-run-index_advisor> server # [    1.027248] (1): "0.flash"399vm-test-run-index_advisor> server # [    1.027309] into device "0.flash"400vm-test-run-index_advisor> server # [    1.036252] Freeing initrd memory: 10684K401vm-test-run-index_advisor> server # [    1.058790] tun: Universal TUN/TAP device driver, 1.6402vm-test-run-index_advisor> server # [    1.071955] thunder_xcv, ver 1.0403vm-test-run-index_advisor> server # [    1.072231] thunder_bgx, ver 1.0404vm-test-run-index_advisor> server # [    1.072404] nicpf, ver 1.0405vm-test-run-index_advisor> server # [    1.075682] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version406vm-test-run-index_advisor> server # [    1.075850] hns3: Copyright (c) 2017 Huawei Corporation.407vm-test-run-index_advisor> server # [    1.076216] hclge is initializing408vm-test-run-index_advisor> server # [    1.076433] e1000: Intel(R) PRO/1000 Network Driver409vm-test-run-index_advisor> server # [    1.076522] e1000: Copyright (c) 1999-2006 Intel Corporation.410vm-test-run-index_advisor> server # [    1.076725] e1000e: Intel(R) PRO/1000 Network Driver411vm-test-run-index_advisor> server # [    1.076813] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.412vm-test-run-index_advisor> server # [    1.077045] igb: Intel(R) Gigabit Ethernet Network Driver413vm-test-run-index_advisor> server # [    1.077149] igb: Copyright (c) 2007-2014 Intel Corporation.414vm-test-run-index_advisor> server # [    1.077341] igbvf: Intel(R) Gigabit Virtual Function Network Driver415vm-test-run-index_advisor> server # [    1.077444] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.416vm-test-run-index_advisor> server # [    1.079115] sky2: driver version 1.30417vm-test-run-index_advisor> server # [    1.088247] ehci-pci 0000:00:0a.0: EHCI Host Controller418vm-test-run-index_advisor> server # [    1.088809] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1419vm-test-run-index_advisor> server # [    1.090354] usbcore: registered new interface driver usb-storage420vm-test-run-index_advisor> server # [    1.091065] usbcore: registered new interface driver usbserial_generic421vm-test-run-index_advisor> server # [    1.091366] usbserial: USB Serial support registered for generic422vm-test-run-index_advisor> server # [    1.093022] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000423vm-test-run-index_advisor> server # [    1.101902] rtc-pl031 9010000.pl031: registered as rtc0424vm-test-run-index_advisor> server # [    1.102982] rtc-pl031 9010000.pl031: setting system clock to 2026-10-23T15:20:03 UTC (1792768803)425vm-test-run-index_advisor> server # [    1.104960] i2c_dev: i2c /dev entries driver426vm-test-run-index_advisor> server # [    1.106611] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00427vm-test-run-index_advisor> server # [    1.113681] hub 1-0:1.0: USB hub found428vm-test-run-index_advisor> server # [    1.114807] hub 1-0:1.0: 6 ports detected429vm-test-run-index_advisor> server # [    1.122488] sdhci: Secure Digital Host Controller Interface driver430vm-test-run-index_advisor> server # [    1.123052] sdhci: Copyright(c) Pierre Ossman431vm-test-run-index_advisor> server # [    1.124622] Synopsys Designware Multimedia Card Interface Driver432vm-test-run-index_advisor> server # [    1.126863] sdhci-pltfm: SDHCI platform and OF driver helper433vm-test-run-index_advisor> server # [    1.133537] hid: raw HID events driver (C) Jiri Kosina434vm-test-run-index_advisor> server # [    1.135994] usbcore: registered new interface driver usbhid435vm-test-run-index_advisor> server # [    1.136238] usbhid: USB HID core driver436vm-test-run-index_advisor> server # [    1.145212] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available437vm-test-run-index_advisor> server # [    1.160890] drop_monitor: Initializing network drop monitor service438vm-test-run-index_advisor> server # [    1.163049] NET: Registered PF_INET6 protocol family439vm-test-run-index_advisor> server # [    1.179571] Segment Routing with IPv6440vm-test-run-index_advisor> server # [    1.179976] In-situ OAM (IOAM) with IPv6441vm-test-run-index_advisor> server # [    1.180600] NET: Registered PF_PACKET protocol family442vm-test-run-index_advisor> server # [    1.182460] 9pnet: Installing 9P2000 support443vm-test-run-index_advisor> server # [    1.187627] Key type dns_resolver registered444vm-test-run-index_advisor> server # [    1.226351] registered taskstats version 1445vm-test-run-index_advisor> server # [    1.228502] Loading compiled-in X.509 certificates446vm-test-run-index_advisor> server # [    1.285875] Demotion targets for Node 0: null447vm-test-run-index_advisor> server # [    1.288652] Key type .fscrypt registered448vm-test-run-index_advisor> server # [    1.288854] Key type fscrypt-provisioning registered449vm-test-run-index_advisor> server # [    1.299668] input: gpio-keys as /devices/platform/gpio-keys/input/input0450vm-test-run-index_advisor> server # [    1.347740] clk: Disabling unused clocks451vm-test-run-index_advisor> server # [    1.348613] PM: genpd: Disabling unused power domains452vm-test-run-index_advisor> server # [    1.349319] ALSA device list:453vm-test-run-index_advisor> server # [    1.350158]   No soundcards found.454vm-test-run-index_advisor> server # [    1.365956] usb 1-1: new high-speed USB device number 2 using ehci-pci455vm-test-run-index_advisor> server # [    1.439538] Freeing unused kernel memory: 4416K456vm-test-run-index_advisor> server # [    1.441257] Run /init as init process457vm-test-run-index_advisor> server # [    1.552625] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1458vm-test-run-index_advisor> server # [    1.716451] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0459vm-test-run-index_advisor> server # [    1.841782] usb 1-2: new high-speed USB device number 3 using ehci-pci460vm-test-run-index_advisor> server # [    2.024702] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev461vm-test-run-index_advisor> server # [    2.039625] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2462vm-test-run-index_advisor> server # [    2.040809] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0463vm-test-run-index_advisor> server # [    2.155322] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0464vm-test-run-index_advisor> server # [    2.156032] [drm] features: -virgl +edid -resource_blob -host_visible465vm-test-run-index_advisor> server # [    2.156055] [drm] features: -context_init466vm-test-run-index_advisor> server # [    2.159498] [drm] number of scanouts: 1467vm-test-run-index_advisor> server # [    2.159700] [drm] number of cap sets: 0468vm-test-run-index_advisor> server # [    2.168958] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0469vm-test-run-index_advisor> server # [    2.204074] Console: switching to colour frame buffer device 160x50470vm-test-run-index_advisor> server # [    2.216075] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device471vm-test-run-index_advisor> server # [    5.223837] EXT4-fs (vda): mounted filesystem 234ae3b6-d5e7-4de2-98a4-ca8a583314a7 r/w with ordered data mode. Quota mode: none.472vm-test-run-index_advisor> server # [    7.617277] EXT4-fs (vda): re-mounted 234ae3b6-d5e7-4de2-98a4-ca8a583314a7.473vm-test-run-index_advisor> server # [    7.634814] booting system configuration /nix/store/9im41iz4mgfwdf5fyng9jp1364k6mdbn-nixos-system-server-test474vm-test-run-index_advisor> server # [   20.635977] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)475vm-test-run-index_advisor> server # [   20.646648] systemd[1]: Detected virtualization qemu.476vm-test-run-index_advisor> server # [   20.651052] systemd[1]: Detected architecture arm64.477vm-test-run-index_advisor> server # [   20.655140] systemd[1]: Detected first boot.478vm-test-run-index_advisor> server # [   20.685026] systemd[1]: Initializing machine ID from random generator.479vm-test-run-index_advisor> server # [   20.723190] systemd[1]: Hostname set to <server>.480vm-test-run-index_advisor> server # [   21.947079] systemd[1]: bpf-restrict-fs: LSM BPF program attached481vm-test-run-index_advisor> server # [   23.315694] systemd[1]: Applying preset policy.482vm-test-run-index_advisor> server # [   26.880317] systemd[1]: Populated /etc with preset unit settings.483vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...484vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.485vm-test-run-index_advisor> server # [   30.363629] systemd[1]: Queued start job for default target Multi-User System.486vm-test-run-index_advisor> server # [   30.597088] systemd[1]: Created slice Slice /system/getty.487vm-test-run-index_advisor> server # [   30.612116] systemd[1]: Created slice Slice /system/modprobe.488vm-test-run-index_advisor> server # [   30.625534] systemd[1]: Created slice User and Session Slice.489vm-test-run-index_advisor> server # [   30.634995] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.490vm-test-run-index_advisor> server # [   30.641316] systemd[1]: Started Forward Password Requests to Wall Directory Watch.491vm-test-run-index_advisor> server # [   30.647663] systemd[1]: Expecting device /dev/hvc0...492vm-test-run-index_advisor> server # [   30.652847] systemd[1]: Expecting device /dev/ttyAMA0...493vm-test-run-index_advisor> server # [   30.658076] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...494vm-test-run-index_advisor> server # [   30.664039] systemd[1]: Reached target Local Encrypted Volumes.495vm-test-run-index_advisor> server # [   30.669676] systemd[1]: Reached target Virtual Machines and Containers.496vm-test-run-index_advisor> server # [   30.674203] systemd[1]: Reached target Path Units.497vm-test-run-index_advisor> server # [   30.677016] systemd[1]: Reached target Remote File Systems.498vm-test-run-index_advisor> server # [   30.681766] systemd[1]: Reached target Slice Units.499vm-test-run-index_advisor> server # [   30.687240] systemd[1]: Reached target Swaps.500vm-test-run-index_advisor> server # [   30.726252] systemd[1]: Listening on Process Core Dump Socket.501vm-test-run-index_advisor> server # [   30.753451] systemd[1]: Listening on Credential Encryption/Decryption.502vm-test-run-index_advisor> server # [   30.765897] systemd[1]: Listening on Journal Audit Socket.503vm-test-run-index_advisor> server # [   30.774084] systemd[1]: Listening on Journal Socket (/dev/log).504vm-test-run-index_advisor> server # [   30.779953] systemd[1]: Listening on Journal Sockets.505vm-test-run-index_advisor> server # [   30.788781] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.506vm-test-run-index_advisor> server # [   30.794036] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).507vm-test-run-index_advisor> server # [   30.799932] systemd[1]: Listening on udev Control Socket.508vm-test-run-index_advisor> server # [   30.805686] systemd[1]: Listening on udev Kernel Socket.509vm-test-run-index_advisor> server # [   30.857824] systemd[1]: Mounting Huge Pages File System...510vm-test-run-index_advisor> server # [   30.908082] systemd[1]: Mounting POSIX Message Queue File System...511vm-test-run-index_advisor> server # [   30.960721] systemd[1]: Mounting Kernel Debug File System...512vm-test-run-index_advisor> server # [   31.083856] systemd[1]: Mounting Kernel Trace File System...513vm-test-run-index_advisor> server # [   31.207944] systemd[1]: Starting Create List of Static Device Nodes...514vm-test-run-index_advisor> server # [   31.347498] systemd[1]: Starting Load Kernel Module 9pnet_virtio...515vm-test-run-index_advisor> server # [   31.368177] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).516vm-test-run-index_advisor> server # [   31.480894] systemd[1]: Mounting Kernel Configuration File System...517vm-test-run-index_advisor> server # [   31.504183] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).518vm-test-run-index_advisor> server # [   31.517720] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).519vm-test-run-index_advisor> server # [   31.602174] systemd[1]: Starting Load Kernel Module fuse...520vm-test-run-index_advisor> server # [   31.615623] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).521vm-test-run-index_advisor> server # [   31.628035] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).522vm-test-run-index_advisor> server # [   31.720419] systemd[1]: Starting Journal Service...523vm-test-run-index_advisor> server # [   31.824134] systemd[1]: Starting Load Kernel Modules...524vm-test-run-index_advisor> server # [   31.924482] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...525vm-test-run-index_advisor> server # [   31.995136] systemd[1]: Starting Remount Root and Kernel File Systems...526vm-test-run-index_advisor> server # [   32.009159] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).527vm-test-run-index_advisor> server # [   32.068125] systemd[1]: Starting Coldplug All udev Devices...528vm-test-run-index_advisor> server # [   32.160743] systemd[1]: Mounted Huge Pages File System.529vm-test-run-index_advisor> server # [   32.179981] systemd[1]: Mounted POSIX Message Queue File System.530vm-test-run-index_advisor> server # [   32.194951] systemd[1]: Mounted Kernel Debug File System.531vm-test-run-index_advisor> server # [   32.214322] systemd[1]: Mounted Kernel Trace File System.532vm-test-run-index_advisor> server # [   32.240050] systemd[1]: Finished Create List of Static Device Nodes.533vm-test-run-index_advisor> server # [   32.256732] systemd[1]: Mounted Kernel Configuration File System.534vm-test-run-index_advisor> server # [   32.322622] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...535vm-test-run-index_advisor> server # [   32.453239] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.536vm-test-run-index_advisor> server # [   32.468478] systemd[1]: Finished Load Kernel Module 9pnet_virtio.537vm-test-run-index_advisor> server # [   33.085852] systemd[1]: Finished Load Kernel Modules.538vm-test-run-index_advisor> server # [   33.095428] fuse: init (API version 7.41)539vm-test-run-index_advisor> server # [   33.153022] systemd[1]: Starting Firewall...540vm-test-run-index_advisor> server # [   33.187339] EXT4-fs (vda): re-mounted 234ae3b6-d5e7-4de2-98a4-ca8a583314a7.541vm-test-run-index_advisor> server # [   33.209070] systemd[1]: Starting Apply Kernel Variables...542vm-test-run-index_advisor> server # [   33.252324] systemd[1]: modprobe@fuse.service: Deactivated successfully.543vm-test-run-index_advisor> server # [   33.275880] systemd[1]: Finished Load Kernel Module fuse.544vm-test-run-index_advisor> server # [   33.292385] systemd[1]: Finished Remount Root and Kernel File Systems.545vm-test-run-index_advisor> server # [   33.340501] systemd[1]: Mounting FUSE Control File System...546vm-test-run-index_advisor> server # [   33.356254] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).547vm-test-run-index_advisor> server # [   33.416228] systemd-journald[395]: Collecting audit messages is enabled.548vm-test-run-index_advisor> server # [   33.427175] systemd[1]: Starting Load/Save OS Random Seed...549vm-test-run-index_advisor> server # [   33.432481] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).550vm-test-run-index_advisor> server # [   33.645444] systemd[1]: Mounted FUSE Control File System.551vm-test-run-index_advisor> server # [   34.490940] systemd[1]: Finished Load/Save OS Random Seed.552vm-test-run-index_advisor> server # [   34.502752] systemd[1]: Reached target First Boot Complete.553vm-test-run-index_advisor> server # [   34.842827] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.554vm-test-run-index_advisor> server # [   34.889062] systemd[1]: Finished Apply Kernel Variables.555vm-test-run-index_advisor> server # [   34.999745] systemd[1]: Starting Create Static Device Nodes in /dev...556vm-test-run-index_advisor> server # [   35.292851] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.557vm-test-run-index_advisor> server # [   36.060369] systemd[1]: Finished Create Static Device Nodes in /dev.558vm-test-run-index_advisor> server # [   36.081321] systemd[1]: Reached target Preparation for Local File Systems.559vm-test-run-index_advisor> server # [   36.133098] systemd[1]: Mounting /run/wrappers...560vm-test-run-index_advisor> server # [   36.190127] systemd[1]: Starting Rule-based Manager for Device Events and Files...561vm-test-run-index_advisor> server # [   36.396746] systemd[1]: Started Journal Service.562vm-test-run-index_advisor> server # [   36.328929] systemd-modules-load[396]: Module 'atkbd' is built in563vm-test-run-index_advisor> server # [   36.390703] systemd-modules-load[396]: Module 'loop' is built in564vm-test-run-index_advisor> server # [   36.443616] systemd-oomd[397]: No swap; memory pressure usage will be degraded565vm-test-run-index_advisor> server # [   36.509738] systemd[1]: Starting Flush Journal to Persistent Storage...566vm-test-run-index_advisor> server # [   36.670813] systemd[1]: Mounted /run/wrappers.567vm-test-run-index_advisor> server # [   36.810902] systemd[1]: Reached target Local File Systems.568vm-test-run-index_advisor> server # [   36.829764] systemd[1]: Listening on Boot Entries Service Socket.569vm-test-run-index_advisor> server # [   36.873536] systemd[1]: Starting Create SUID/SGID Wrappers...570vm-test-run-index_advisor> server # [   36.883279] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.571vm-test-run-index_advisor> server # [   36.941157] systemd[1]: Starting Save Transient machine-id to Disk...572vm-test-run-index_advisor> server # [   37.457163] systemd-journald[395]: Received client request to flush runtime journal.573vm-test-run-index_advisor> server # [   38.071233] systemd-udevd[425]: Using default interface naming scheme 'v258'.574vm-test-run-index_advisor> server # [   38.102489] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.575vm-test-run-index_advisor> server # [   38.117900] systemd[1]: Finished Save Transient machine-id to Disk.576vm-test-run-index_advisor> server # [   38.127619] systemd[1]: Finished Flush Journal to Persistent Storage.577vm-test-run-index_advisor> server # [   38.162245] systemd[1]: Starting Create System Files and Directories...578vm-test-run-index_advisor> server # [   40.141448] systemd[1]: Finished Create System Files and Directories.579vm-test-run-index_advisor> server # [   40.205351] systemd[1]: Starting Rebuild Journal Catalog...580vm-test-run-index_advisor> server # [   40.267718] systemd[1]: Starting Record System Boot/Shutdown in UTMP...581vm-test-run-index_advisor> server # [   40.540740] systemd[1]: Started Rule-based Manager for Device Events and Files.582vm-test-run-index_advisor> server # [   41.352018] systemd[1]: Finished Record System Boot/Shutdown in UTMP.583vm-test-run-index_advisor> server # [   42.619035] systemd[1]: Finished Rebuild Journal Catalog.584vm-test-run-index_advisor> server # [   42.659764] systemd[1]: Starting Update is Completed...585vm-test-run-index_advisor> server # [   43.478965] systemd[1]: Finished Update is Completed.586vm-test-run-index_advisor> server # [   47.421482] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.587vm-test-run-index_advisor> server # [   47.431644] systemd[1]: Finished Create SUID/SGID Wrappers.588vm-test-run-index_advisor> server # [   47.436609] systemd[1]: suid-sgid-wrappers.service: Consumed 2.869s CPU time, 5.6M memory peak.589vm-test-run-index_advisor> server # [   49.899284] systemd[1]: Finished Firewall.590vm-test-run-index_advisor> server # [   51.414972] systemd[1]: Finished Coldplug All udev Devices.591vm-test-run-index_advisor> server # [   51.434537] systemd[1]: Reached target System Initialization.592vm-test-run-index_advisor> server # [   51.436200] systemd[1]: Started Discard unused filesystem blocks once a week.593vm-test-run-index_advisor> server # [   51.451192] systemd[1]: Started Daily Cleanup of Temporary Directories.594vm-test-run-index_advisor> server # [   51.457228] systemd[1]: Reached target Timer Units.595vm-test-run-index_advisor> server # [   51.458554] systemd[1]: Listening on D-Bus System Message Bus Socket.596vm-test-run-index_advisor> server # [   51.461420] systemd[1]: Listening on Nix Daemon Socket.597vm-test-run-index_advisor> server # [   51.466906] systemd[1]: Listening on Hostname Service Socket.598vm-test-run-index_advisor> server # [   51.468297] systemd[1]: Reached target Socket Units.599vm-test-run-index_advisor> server # [   51.471378] systemd[1]: Reached target Basic System.600vm-test-run-index_advisor> server # [   51.489052] systemd[1]: Starting Import lastlog data into lastlog2 database...601vm-test-run-index_advisor> server # [   51.534692] systemd[1]: Starting Name Service Cache Daemon (nsncd)...602vm-test-run-index_advisor> server # [   51.575269] systemd[1]: Started Reset console on configuration changes.603vm-test-run-index_advisor> server # [   51.621046] systemd[1]: Starting resolvconf update...604vm-test-run-index_advisor> server # [   51.700670] systemd[1]: Starting D-Bus System Message Bus...605vm-test-run-index_advisor> server # [   52.691970] systemd[1]: Finished Import lastlog data into lastlog2 database.606vm-test-run-index_advisor> server # [   52.883873] nsncd[630]: Oct 23 15:20:55.326 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"607vm-test-run-index_advisor> server # [   52.906575] systemd[1]: Started Name Service Cache Daemon (nsncd).608vm-test-run-index_advisor> server # [   52.917771] systemd[1]: Reached target Host and Network Name Lookups.609vm-test-run-index_advisor> server # [   52.926066] systemd[1]: Reached target User and Group Name Lookups.610vm-test-run-index_advisor> server # [   52.964613] systemd[1]: Starting User Login Management...611vm-test-run-index_advisor> server # [   53.126405] dbus-daemon[633]: dbus[633]: Unknown username "systemd-timesync" in message bus configuration file612vm-test-run-index_advisor> server # [   53.511266] systemd[1]: Started D-Bus System Message Bus.613vm-test-run-index_advisor> server # [   54.308073] systemd-logind[649]: New seat seat0.614vm-test-run-index_advisor> server # [   54.375046] systemd[1]: Started User Login Management.615vm-test-run-index_advisor> server # [   54.404195] systemd[1]: Starting linger-users.service...616vm-test-run-index_advisor> server # [   54.587263] systemd[1]: Stopped target Host and Network Name Lookups.617vm-test-run-index_advisor> server # [   54.598537] systemd[1]: Stopping Host and Network Name Lookups...618vm-test-run-index_advisor> server # [   54.610810] systemd[1]: Stopped target User and Group Name Lookups.619vm-test-run-index_advisor> server # [   54.623215] systemd[1]: Stopping User and Group Name Lookups...620vm-test-run-index_advisor> server # [   54.625725] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...621vm-test-run-index_advisor> server # [   54.683624] systemd[1]: nscd.service: Deactivated successfully.622vm-test-run-index_advisor> server # [   54.702446] systemd[1]: Stopped Name Service Cache Daemon (nsncd).623vm-test-run-index_advisor> server # [   54.855881] systemd[1]: Starting Name Service Cache Daemon (nsncd)...624vm-test-run-index_advisor> server # [   54.970208] systemd[1]: linger-users.service: Deactivated successfully.625vm-test-run-index_advisor> server # [   54.985059] systemd[1]: Finished linger-users.service.626vm-test-run-index_advisor> server # [   55.443310] systemd[1]: Started Name Service Cache Daemon (nsncd).627vm-test-run-index_advisor> server # [   55.449426] systemd[1]: Reached target Host and Network Name Lookups.628vm-test-run-index_advisor> server # [   55.467867] nsncd[692]: Oct 23 15:20:57.940 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"629vm-test-run-index_advisor> server # [   55.475492] systemd[1]: Reached target User and Group Name Lookups.630vm-test-run-index_advisor> server # [   55.884118] systemd[1]: Finished resolvconf update.631vm-test-run-index_advisor> server # [   55.896722] systemd[1]: Reached target Preparation for Network.632vm-test-run-index_advisor> server # [   55.966209] systemd[1]: Starting DHCP Client...633vm-test-run-index_advisor> server # [   57.384251] dhcpcd[741]: dhcpcd-10.2.4 starting634vm-test-run-index_advisor> server # [   57.475575] dhcpcd[745]: dev: loaded udev635vm-test-run-index_advisor> server # [   57.831352] 8021q: 802.1Q VLAN Support v1.8636vm-test-run-index_advisor> server # [   58.266824] cfg80211: Loading compiled-in X.509 certificates for regulatory database637vm-test-run-index_advisor> server # [   58.518650] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'638vm-test-run-index_advisor> server # [   58.525348] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'639vm-test-run-index_advisor> server # [   58.544696] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2640vm-test-run-index_advisor> server # [   58.554178] cfg80211: failed to load regulatory.db641vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...642vm-test-run-index_advisor> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.643vm-test-run-index_advisor> server # [   59.399300] 8021q: adding VLAN 0 to HW filter on device eth0644vm-test-run-index_advisor> server # [   59.346813] dhcpcd[745]: eth0: waiting for carrier645vm-test-run-index_advisor> server # [   59.349881] dhcpcd[745]: eth0: carrier acquired646vm-test-run-index_advisor> server # [   59.508956] dhcpcd[745]: DUID 00:01:00:01:32:6e:37:dd:52:54:00:12:34:56647vm-test-run-index_advisor> server # [   59.511992] dhcpcd[745]: eth0: IAID 00:12:34:56648vm-test-run-index_advisor> server # [   59.518072] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456649vm-test-run-index_advisor> server # [   59.646705] systemd[1]: Found device /dev/hvc0.650vm-test-run-index_advisor> server # [   60.029137] systemd[1]: Found device /dev/ttyAMA0.651vm-test-run-index_advisor> server # [   60.066552] systemd[1]: Started backdoor.service.652vm-test-run-index_advisor> server # connecting to host...653vm-test-run-index_advisor> server: Guest shell says: b'Spawning backdoor root shell...\n'654vm-test-run-index_advisor> server: connected to guest root shell655vm-test-run-index_advisor> server: (connecting took 61.77 seconds)656vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 61.77 seconds)657vm-test-run-index_advisor> server # [   61.110199] (udev-worker)[778]: Network interface NamePolicy= disabled on kernel command line.658vm-test-run-index_advisor> server # [   61.174893] (udev-worker)[775]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.659vm-test-run-index_advisor> server # [   61.182216] (udev-worker)[775]: Network interface NamePolicy= disabled on kernel command line.660vm-test-run-index_advisor> server # [   61.470329] dhcpcd[745]: eth0: soliciting an IPv6 router661vm-test-run-index_advisor> server # [   61.486476] dhcpcd[745]: eth0: soliciting a DHCP lease662vm-test-run-index_advisor> server # [   61.522843] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2663vm-test-run-index_advisor> server # [   61.529392] dhcpcd[745]: eth0: Router Advertisement from fe80::2664vm-test-run-index_advisor> server # [   61.551610] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64665vm-test-run-index_advisor> server # [   61.569127] dhcpcd[745]: eth0: adding route to fec0::/64666vm-test-run-index_advisor> server # [   61.571001] dhcpcd[745]: eth0: adding default route via fe80::2667vm-test-run-index_advisor> server # [   61.585225] dhcpcd[745]: eth0: probing address 10.0.2.15/24668vm-test-run-index_advisor> server # [   61.914613] dhcpcd[745]: eth0: IAID 00:12:34:56669vm-test-run-index_advisor> server # [   62.022303] systemd[1]: Found device Virtio network device.670vm-test-run-index_advisor> server # [   62.080238] systemd[1]: Starting Address configuration of eth1...671vm-test-run-index_advisor> server # [   62.161439] dhcpcd[745]: eth0: soliciting a DHCP lease672vm-test-run-index_advisor> server # [   62.169690] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2673vm-test-run-index_advisor> server # [   62.171115] dhcpcd[745]: eth0: probing address 10.0.2.15/24674vm-test-run-index_advisor> server # [   62.439189] dhcpcd[745]: eth0: soliciting an IPv6 router675vm-test-run-index_advisor> server # [   62.927722] 8021q: adding VLAN 0 to HW filter on device eth1676vm-test-run-index_advisor> server # [   63.083775] network-addresses-eth1-start[797]: adding address 192.168.1.1/24... done677vm-test-run-index_advisor> server # [   63.251186] network-addresses-eth1-start[797]: adding address 2001:db8:1::1/64... done678vm-test-run-index_advisor> server # [   63.470889] systemd[1]: Finished Address configuration of eth1.679vm-test-run-index_advisor> server # [   63.550914] systemd[1]: Starting Networking Setup...680vm-test-run-index_advisor> server # [   65.869603] systemd[1]: Finished Networking Setup.681vm-test-run-index_advisor> server # [   65.886625] systemd[1]: Reached target Network.682vm-test-run-index_advisor> server # [   65.929184] systemd[1]: Starting PostgreSQL Database Server...683vm-test-run-index_advisor> server # [   65.978236] systemd[1]: Starting Permit User Sessions...684vm-test-run-index_advisor> server # [   66.674209] systemd[1]: Finished Permit User Sessions.685vm-test-run-index_advisor> server # [   66.758461] systemd[1]: Started Getty on tty1.686vm-test-run-index_advisor> server # [   66.774024] systemd[1]: Reached target Login Prompts.687vm-test-run-index_advisor> server # [   67.499965] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[911]: Initializing database at /var/lib/postgresql/data688vm-test-run-index_advisor> server # [   67.719695] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds689vm-test-run-index_advisor> server # [   67.731731] dhcpcd[745]: eth0: adding route to 10.0.2.0/24690vm-test-run-index_advisor> server # [   67.733233] dhcpcd[745]: eth0: adding default route via 10.0.2.2691vm-test-run-index_advisor> server # [   69.745706] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The files belonging to this database system will be owned by user "postgres".692vm-test-run-index_advisor> server # [   69.762850] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: This user must also own the server process.693vm-test-run-index_advisor> server # [   69.854402] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The database cluster will be initialized with locale "en_US.UTF-8".694vm-test-run-index_advisor> server # [   69.855791] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The default database encoding has accordingly been set to "UTF8".695vm-test-run-index_advisor> server # [   69.868185] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: The default text search configuration will be set to "english".696vm-test-run-index_advisor> server # [   69.877184] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: Data page checksums are enabled.697vm-test-run-index_advisor> server # [   69.877956] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: fixing permissions on existing directory /var/lib/postgresql/data ... ok698vm-test-run-index_advisor> server # [   69.889621] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: creating subdirectories ... ok699vm-test-run-index_advisor> server # [   69.908182] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting dynamic shared memory implementation ... posix700vm-test-run-index_advisor> server # [   70.726200] systemd[1]: Started DHCP Client.701vm-test-run-index_advisor> server # [   71.273088] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting default max_connections ... 100702vm-test-run-index_advisor> server # [   71.836121] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting default shared_buffers ... 128MB703vm-test-run-index_advisor> server # [   74.513908] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys)704vm-test-run-index_advisor> server # [   77.923425] mousedev: PS/2 mouse device common for all mice705vm-test-run-index_advisor> server # [   81.654384] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)706vm-test-run-index_advisor> server # [   82.370264] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: selecting default time zone ... UTC707vm-test-run-index_advisor> server # [   82.404155] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: creating configuration files ... ok708vm-test-run-index_advisor> server # [   87.070515] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: running bootstrap script ... ok709vm-test-run-index_advisor> server # [   98.000017] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: performing post-bootstrap initialization ... ok710vm-test-run-index_advisor> server # [  100.548683] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: syncing data to disk ... ok711vm-test-run-index_advisor> server # [  100.563752] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: initdb: warning: enabling "trust" authentication for local connections712vm-test-run-index_advisor> server # [  100.572352] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.713vm-test-run-index_advisor> server # [  100.575770] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]: Success. You can now start the database server using:714vm-test-run-index_advisor> server # [  100.577331] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[915]:     /nix/store/i45yhjwwac2d5ygyv0s5gbg2cgdjclf9-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start715vm-test-run-index_advisor> server # [  102.489568] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[911]: Validating PostgreSQL configuration...716vm-test-run-index_advisor> server # [  102.880670] ab3kvjrs325pdcgm8ssmc8711l6ypzc8-postgresql-init[1322]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault717vm-test-run-index_advisor> server # [  103.755951] postgres[1325]:  2026-10-23 15:21:46.243 UTC [1325] LOG:  pgaudit extension initialized718vm-test-run-index_advisor> server # [  104.879642] postgres[1325]:  2026-10-23 15:21:47.346 UTC [1325] LOG:  pgsodium primary server secret key loaded719vm-test-run-index_advisor> server # [  105.396858] postgres[1325]:  2026-10-23 15:21:47.890 UTC [1325] LOG:  vault primary server secret key loaded720vm-test-run-index_advisor> server # [  105.721297] postgres[1325]:  2026-10-23 15:21:48.215 UTC [1325] LOG:  starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit721vm-test-run-index_advisor> server # [  105.738011] postgres[1325]:  2026-10-23 15:21:48.231 UTC [1325] LOG:  listening on IPv4 address "0.0.0.0", port 5432722vm-test-run-index_advisor> server # [  105.740308] postgres[1325]:  2026-10-23 15:21:48.232 UTC [1325] LOG:  listening on IPv6 address "::", port 5432723vm-test-run-index_advisor> server # [  105.758771] postgres[1325]:  2026-10-23 15:21:48.253 UTC [1325] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"724vm-test-run-index_advisor> server # [  105.857866] postgres[1359]:  2026-10-23 15:21:48.342 UTC [1359] LOG:  database system was shut down at 2026-10-23 15:21:40 UTC725vm-test-run-index_advisor> server # [  106.004044] postgres[1325]:  2026-10-23 15:21:48.492 UTC [1325] LOG:  database system is ready to accept connections726vm-test-run-index_advisor> server # [  106.048347] postgres[1363]:  2026-10-23 15:21:48.532 UTC [1363] FATAL:  role "postgres" does not exist727vm-test-run-index_advisor> server # [  106.095680] postgres[1362]:  2026-10-23 15:21:48.558 UTC [1362] LOG:  TimescaleDB background worker launcher connected to shared catalogs728vm-test-run-index_advisor> server # [  106.123550] systemd[1]: Started PostgreSQL Database Server.729vm-test-run-index_advisor> server # [  106.169574] systemd[1]: Starting Supabase Database Initialization...730vm-test-run-index_advisor> server # [  106.275504] postgres[1325]:  2026-10-23 15:21:48.769 UTC [1325] LOG:  background worker "pg_net 0.20.4 worker" (PID 1363) exited with exit code 1731vm-test-run-index_advisor> server # [  106.508044] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Waiting for PostgreSQL to be ready...732vm-test-run-index_advisor> server # [  106.621788] postgres[1364]:  2026-10-23 15:21:49.096 UTC [1364] LOG:  pg_cron scheduler started733vm-test-run-index_advisor> server # [  107.086765] postgres[1374]: ::1 2026-10-23 15:21:49.555 UTC [1374] root@root FATAL:  role "root" does not exist734vm-test-run-index_advisor> server # [  107.114265] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: PostgreSQL is ready735vm-test-run-index_advisor> server # [  107.126324] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Creating postgres role...736vm-test-run-index_advisor> server # [  107.342962] postgres[1382]:  2026-10-23 15:21:49.824 UTC [1382] FATAL:  role "postgres" does not exist737vm-test-run-index_advisor> server # [  107.393146] postgres[1325]:  2026-10-23 15:21:49.878 UTC [1325] LOG:  background worker "pg_net 0.20.4 worker" (PID 1382) exited with exit code 1738vm-test-run-index_advisor> server # [  107.538743] postgres[1384]: ::1 2026-10-23 15:21:50.028 UTC [1384] supabase_admin@postgres LOG:  statement: CREATE ROLE postgres SUPERUSER LOGIN;739vm-test-run-index_advisor> server # [  107.587669] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1378]: CREATE ROLE740vm-test-run-index_advisor> server # [  107.924164] postgres[1396]: ::1 2026-10-23 15:21:50.385 UTC [1396] supabase_admin@postgres LOG:  statement: ALTER DATABASE postgres OWNER TO postgres;741vm-test-run-index_advisor> server # [  107.969367] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1391]: ALTER DATABASE742vm-test-run-index_advisor> server # [  108.047170] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000000-initial-schema.sql743vm-test-run-index_advisor> server # [  108.352321] postgres[1405]: ::1 2026-10-23 15:21:50.841 UTC [1405] postgres@postgres LOG:  statement: create publication supabase_realtime;744vm-test-run-index_advisor> server # [  108.385901] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE PUBLICATION745vm-test-run-index_advisor> server # [  108.392554] postgres[1405]: ::1 2026-10-23 15:21:50.882 UTC [1405] postgres@postgres LOG:  statement: alter user  supabase_admin with superuser createdb createrole replication bypassrls;746vm-test-run-index_advisor> server # [  108.407923] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE747vm-test-run-index_advisor> server # [  108.413066] postgres[1405]: ::1 2026-10-23 15:21:50.904 UTC [1405] postgres@postgres LOG:  statement: create user supabase_replication_admin with login replication;748vm-test-run-index_advisor> server # [  108.423526] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE749vm-test-run-index_advisor> server # [  108.425238] postgres[1405]: ::1 2026-10-23 15:21:50.915 UTC [1405] postgres@postgres LOG:  statement: create user supabase_etl_admin with login replication bypassrls;750vm-test-run-index_advisor> server # [  108.437756] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE751vm-test-run-index_advisor> server # [  108.441021] postgres[1405]: ::1 2026-10-23 15:21:50.933 UTC [1405] postgres@postgres LOG:  statement: grant pg_read_all_data to supabase_etl_admin;752vm-test-run-index_advisor> server # [  108.454063] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE753vm-test-run-index_advisor> server # [  108.456950] postgres[1405]: ::1 2026-10-23 15:21:50.949 UTC [1405] postgres@postgres LOG:  statement: grant create on database postgres to supabase_etl_admin;754vm-test-run-index_advisor> server # [  108.466213] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT755vm-test-run-index_advisor> server # [  108.471406] postgres[1405]: ::1 2026-10-23 15:21:50.963 UTC [1405] postgres@postgres LOG:  statement: create role supabase_read_only_user with login bypassrls;756vm-test-run-index_advisor> server # [  108.481163] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE757vm-test-run-index_advisor> server # [  108.486265] postgres[1405]: ::1 2026-10-23 15:21:50.977 UTC [1405] postgres@postgres LOG:  statement: grant pg_read_all_data to supabase_read_only_user;758vm-test-run-index_advisor> server # [  108.500939] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE759vm-test-run-index_advisor> server # [  108.503734] postgres[1405]: ::1 2026-10-23 15:21:50.992 UTC [1405] postgres@postgres LOG:  statement: create schema if not exists extensions;760vm-test-run-index_advisor> server # [  108.526796] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE SCHEMA761vm-test-run-index_advisor> server # [  108.535188] postgres[1405]: ::1 2026-10-23 15:21:51.026 UTC [1405] postgres@postgres LOG:  statement: create extension if not exists "uuid-ossp"      with schema extensions;762vm-test-run-index_advisor> server # [  110.922758] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE EXTENSION763vm-test-run-index_advisor> server # [  110.935834] postgres[1405]: ::1 2026-10-23 15:21:53.413 UTC [1405] postgres@postgres LOG:  statement: create extension if not exists pgcrypto         with schema extensions;764vm-test-run-index_advisor> server # [  111.991099] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE EXTENSION765vm-test-run-index_advisor> server # [  112.007390] postgres[1405]: ::1 2026-10-23 15:21:54.468 UTC [1405] postgres@postgres LOG:  statement: create role anon                nologin noinherit;766vm-test-run-index_advisor> server # [  112.011736] postgres[1405]: ::1 2026-10-23 15:21:54.487 UTC [1405] postgres@postgres LOG:  statement: create role authenticated       nologin noinherit;767vm-test-run-index_advisor> server # [  112.023390] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE768vm-test-run-index_advisor> server # [  112.024437] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE769vm-test-run-index_advisor> server # [  112.026068] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE770vm-test-run-index_advisor> server # [  112.034217] postgres[1405]: ::1 2026-10-23 15:21:54.500 UTC [1405] postgres@postgres LOG:  statement: create role service_role        nologin noinherit bypassrls;771vm-test-run-index_advisor> server # [  112.037680] postgres[1405]: ::1 2026-10-23 15:21:54.518 UTC [1405] postgres@postgres LOG:  statement: create user authenticator noinherit;772vm-test-run-index_advisor> server # [  112.038750] postgres[1405]: ::1 2026-10-23 15:21:54.530 UTC [1405] postgres@postgres LOG:  statement: grant anon              to authenticator;773vm-test-run-index_advisor> server # [  112.043221] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: CREATE ROLE774vm-test-run-index_advisor> server # [  112.051446] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE775vm-test-run-index_advisor> server # [  112.052232] postgres[1405]: ::1 2026-10-23 15:21:54.545 UTC [1405] postgres@postgres LOG:  statement: grant authenticated     to authenticator;776vm-test-run-index_advisor> server # [  112.065370] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE777vm-test-run-index_advisor> server # [  112.073722] postgres[1405]: ::1 2026-10-23 15:21:54.562 UTC [1405] postgres@postgres LOG:  statement: grant service_role      to authenticator;778vm-test-run-index_advisor> server # [  112.077247] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE779vm-test-run-index_advisor> server # [  112.087121] postgres[1405]: ::1 2026-10-23 15:21:54.576 UTC [1405] postgres@postgres LOG:  statement: grant supabase_admin    to authenticator;780vm-test-run-index_advisor> server # [  112.098587] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT ROLE781vm-test-run-index_advisor> server # [  112.108063] postgres[1405]: ::1 2026-10-23 15:21:54.595 UTC [1405] postgres@postgres LOG:  statement: grant usage                     on schema public to postgres, anon, authenticated, service_role;782vm-test-run-index_advisor> server # [  112.121458] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT783vm-test-run-index_advisor> server # [  112.130049] postgres[1405]: ::1 2026-10-23 15:21:54.622 UTC [1405] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;784vm-test-run-index_advisor> server # [  112.154975] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES785vm-test-run-index_advisor> server # [  112.156162] postgres[1405]: ::1 2026-10-23 15:21:54.648 UTC [1405] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;786vm-test-run-index_advisor> server # [  112.170083] postgres[1405]: ::1 2026-10-23 15:21:54.659 UTC [1405] postgres@postgres LOG:  statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;787vm-test-run-index_advisor> server # [  112.171953] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES788vm-test-run-index_advisor> server # [  112.185718] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES789vm-test-run-index_advisor> server # [  112.187237] postgres[1405]: ::1 2026-10-23 15:21:54.673 UTC [1405] postgres@postgres LOG:  statement: grant usage                     on schema extensions to postgres, anon, authenticated, service_role;790vm-test-run-index_advisor> server # [  112.197294] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: GRANT791vm-test-run-index_advisor> server # [  112.198556] postgres[1405]: ::1 2026-10-23 15:21:54.689 UTC [1405] postgres@postgres LOG:  statement: alter user supabase_admin SET search_path TO public, extensions;792vm-test-run-index_advisor> server # [  112.210460] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE793vm-test-run-index_advisor> server # [  112.211487] postgres[1405]: ::1 2026-10-23 15:21:54.702 UTC [1405] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all794vm-test-run-index_advisor> server # [  112.212061] postgres[1405]: 	    on sequences to postgres, anon, authenticated, service_role;795vm-test-run-index_advisor> server # [  112.223668] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES796vm-test-run-index_advisor> server # [  112.230751] postgres[1405]: ::1 2026-10-23 15:21:54.718 UTC [1405] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all797vm-test-run-index_advisor> server # [  112.231721] postgres[1405]: 	    on tables to postgres, anon, authenticated, service_role;798vm-test-run-index_advisor> server # [  112.243145] postgres[1405]: ::1 2026-10-23 15:21:54.735 UTC [1405] postgres@postgres LOG:  statement: alter default privileges for user supabase_admin in schema public grant all799vm-test-run-index_advisor> server # [  112.253543] postgres[1405]: 	    on functions to postgres, anon, authenticated, service_role;800vm-test-run-index_advisor> server # [  112.254858] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES801vm-test-run-index_advisor> server # [  112.255666] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER DEFAULT PRIVILEGES802vm-test-run-index_advisor> server # [  112.262406] postgres[1405]: ::1 2026-10-23 15:21:54.748 UTC [1405] postgres@postgres LOG:  statement: alter role anon set statement_timeout = '3s';803vm-test-run-index_advisor> server # [  112.275212] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE804vm-test-run-index_advisor> server # [  112.279317] postgres[1405]: ::1 2026-10-23 15:21:54.769 UTC [1405] postgres@postgres LOG:  statement: alter role authenticated set statement_timeout = '8s';805vm-test-run-index_advisor> server # [  112.292981] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1403]: ALTER ROLE806vm-test-run-index_advisor> server # [  112.371550] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000001-auth-schema.sql807vm-test-run-index_advisor> server # [  112.677327] postgres[1436]: ::1 2026-10-23 15:21:55.161 UTC [1436] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;808vm-test-run-index_advisor> server # [  112.691551] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE SCHEMA809vm-test-run-index_advisor> server # [  112.694153] postgres[1436]: ::1 2026-10-23 15:21:55.180 UTC [1436] postgres@postgres LOG:  statement: CREATE TABLE auth.users (810vm-test-run-index_advisor> server # [  112.694889] postgres[1436]: 	    instance_id uuid NULL,811vm-test-run-index_advisor> server # [  112.695876] postgres[1436]: 	    id uuid NOT NULL UNIQUE,812vm-test-run-index_advisor> server # [  112.697612] postgres[1436]: 	    aud varchar(255) NULL,813vm-test-run-index_advisor> server # [  112.699654] postgres[1436]: 	    "role" varchar(255) NULL,814vm-test-run-index_advisor> server # [  112.700622] postgres[1436]: 	    email varchar(255) NULL UNIQUE,815vm-test-run-index_advisor> server # [  112.704918] postgres[1436]: 	    encrypted_password varchar(255) NULL,816vm-test-run-index_advisor> server # [  112.705526] postgres[1436]: 	    confirmed_at timestamptz NULL,817vm-test-run-index_advisor> server # [  112.705850] postgres[1436]: 	    invited_at timestamptz NULL,818vm-test-run-index_advisor> server # [  112.706690] postgres[1436]: 	    confirmation_token varchar(255) NULL,819vm-test-run-index_advisor> server # [  112.707152] postgres[1436]: 	    confirmation_sent_at timestamptz NULL,820vm-test-run-index_advisor> server # [  112.707465] postgres[1436]: 	    recovery_token varchar(255) NULL,821vm-test-run-index_advisor> server # [  112.708086] postgres[1436]: 	    recovery_sent_at timestamptz NULL,822vm-test-run-index_advisor> server # [  112.708413] postgres[1436]: 	    email_change_token varchar(255) NULL,823vm-test-run-index_advisor> server # [  112.710737] postgres[1436]: 	    email_change varchar(255) NULL,824vm-test-run-index_advisor> server # [  112.711724] postgres[1436]: 	    email_change_sent_at timestamptz NULL,825vm-test-run-index_advisor> server # [  112.714216] postgres[1436]: 	    last_sign_in_at timestamptz NULL,826vm-test-run-index_advisor> server # [  112.714845] postgres[1436]: 	    raw_app_meta_data jsonb NULL,827vm-test-run-index_advisor> server # [  112.715163] postgres[1436]: 	    raw_user_meta_data jsonb NULL,828vm-test-run-index_advisor> server # [  112.716009] postgres[1436]: 	    is_super_admin bool NULL,829vm-test-run-index_advisor> server # [  112.717503] postgres[1436]: 	    created_at timestamptz NULL,830vm-test-run-index_advisor> server # [  112.718238] postgres[1436]: 	    updated_at timestamptz NULL,831vm-test-run-index_advisor> server # [  112.719019] postgres[1436]: 	    CONSTRAINT users_pkey PRIMARY KEY (id)832vm-test-run-index_advisor> server # [  112.719328] postgres[1436]: 	);833vm-test-run-index_advisor> server # [  112.835767] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE834vm-test-run-index_advisor> server # [  112.840914] postgres[1436]: ::1 2026-10-23 15:21:55.335 UTC [1436] postgres@postgres LOG:  statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);835vm-test-run-index_advisor> server # [  112.878388] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX836vm-test-run-index_advisor> server # [  112.884038] postgres[1436]: ::1 2026-10-23 15:21:55.376 UTC [1436] postgres@postgres LOG:  statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);837vm-test-run-index_advisor> server # [  112.918578] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX838vm-test-run-index_advisor> server # [  112.923034] postgres[1436]: ::1 2026-10-23 15:21:55.416 UTC [1436] postgres@postgres LOG:  statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';839vm-test-run-index_advisor> server # [  112.933182] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT840vm-test-run-index_advisor> server # [  112.934263] postgres[1436]: ::1 2026-10-23 15:21:55.425 UTC [1436] postgres@postgres LOG:  statement: CREATE TABLE auth.refresh_tokens (841vm-test-run-index_advisor> server # [  112.935065] postgres[1436]: 	    instance_id uuid NULL,842vm-test-run-index_advisor> server # [  112.945130] postgres[1436]: 	    id bigserial NOT NULL,843vm-test-run-index_advisor> server # [  112.946922] postgres[1436]: 	    "token" varchar(255) NULL,844vm-test-run-index_advisor> server # [  112.947379] postgres[1436]: 	    user_id varchar(255) NULL,845vm-test-run-index_advisor> server # [  112.947844] postgres[1436]: 	    revoked bool NULL,846vm-test-run-index_advisor> server # [  112.948345] postgres[1436]: 	    created_at timestamptz NULL,847vm-test-run-index_advisor> server # [  112.949539] postgres[1436]: 	    updated_at timestamptz NULL,848vm-test-run-index_advisor> server # [  112.950477] postgres[1436]: 	    CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)849vm-test-run-index_advisor> server # [  112.953825] postgres[1436]: 	);850vm-test-run-index_advisor> server # [  113.030049] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE851vm-test-run-index_advisor> server # [  113.034990] postgres[1436]: ::1 2026-10-23 15:21:55.528 UTC [1436] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);852vm-test-run-index_advisor> server # [  113.058480] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX853vm-test-run-index_advisor> server # [  113.062416] postgres[1436]: ::1 2026-10-23 15:21:55.553 UTC [1436] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);854vm-test-run-index_advisor> server # [  113.093881] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX855vm-test-run-index_advisor> server # [  113.095489] postgres[1436]: ::1 2026-10-23 15:21:55.589 UTC [1436] postgres@postgres LOG:  statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);856vm-test-run-index_advisor> server # [  113.125927] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX857vm-test-run-index_advisor> server # [  113.127765] postgres[1436]: ::1 2026-10-23 15:21:55.621 UTC [1436] postgres@postgres LOG:  statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';858vm-test-run-index_advisor> server # [  113.135876] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT859vm-test-run-index_advisor> server # [  113.141336] postgres[1436]: ::1 2026-10-23 15:21:55.632 UTC [1436] postgres@postgres LOG:  statement: CREATE TABLE auth.instances (860vm-test-run-index_advisor> server # [  113.142159] postgres[1436]: 	    id uuid NOT NULL,861vm-test-run-index_advisor> server # [  113.143006] postgres[1436]: 	    uuid uuid NULL,862vm-test-run-index_advisor> server # [  113.143761] postgres[1436]: 	    raw_base_config text NULL,863vm-test-run-index_advisor> server # [  113.146960] postgres[1436]: 	    created_at timestamptz NULL,864vm-test-run-index_advisor> server # [  113.155641] postgres[1436]: 	    updated_at timestamptz NULL,865vm-test-run-index_advisor> server # [  113.156528] postgres[1436]: 	    CONSTRAINT instances_pkey PRIMARY KEY (id)866vm-test-run-index_advisor> server # [  113.157973] postgres[1436]: 	);867vm-test-run-index_advisor> server # [  113.202526] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE868vm-test-run-index_advisor> server # [  113.203447] postgres[1436]: ::1 2026-10-23 15:21:55.696 UTC [1436] postgres@postgres LOG:  statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';869vm-test-run-index_advisor> server # [  113.211369] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT870vm-test-run-index_advisor> server # [  113.213896] postgres[1436]: ::1 2026-10-23 15:21:55.706 UTC [1436] postgres@postgres LOG:  statement: CREATE TABLE auth.audit_log_entries (871vm-test-run-index_advisor> server # [  113.217748] postgres[1436]: 	    instance_id uuid NULL,872vm-test-run-index_advisor> server # [  113.218247] postgres[1436]: 	    id uuid NOT NULL,873vm-test-run-index_advisor> server # [  113.218554] postgres[1436]: 	    payload json NULL,874vm-test-run-index_advisor> server # [  113.218994] postgres[1436]: 	    created_at timestamptz NULL,875vm-test-run-index_advisor> server # [  113.219281] postgres[1436]: 	    CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)876vm-test-run-index_advisor> server # [  113.220147] postgres[1436]: 	);877vm-test-run-index_advisor> server # [  113.277960] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE878vm-test-run-index_advisor> server # [  113.278980] postgres[1436]: ::1 2026-10-23 15:21:55.772 UTC [1436] postgres@postgres LOG:  statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);879vm-test-run-index_advisor> server # [  113.346940] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE INDEX880vm-test-run-index_advisor> server # [  113.347871] postgres[1436]: ::1 2026-10-23 15:21:55.842 UTC [1436] postgres@postgres LOG:  statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';881vm-test-run-index_advisor> server # [  113.362062] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT882vm-test-run-index_advisor> server # [  113.363830] postgres[1436]: ::1 2026-10-23 15:21:55.857 UTC [1436] postgres@postgres LOG:  statement: CREATE TABLE auth.schema_migrations (883vm-test-run-index_advisor> server # [  113.372204] postgres[1436]: 	    "version" varchar(255) NOT NULL,884vm-test-run-index_advisor> server # [  113.373606] postgres[1436]: 	    CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")885vm-test-run-index_advisor> server # [  113.374175] postgres[1436]: 	);886vm-test-run-index_advisor> server # [  113.448833] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE TABLE887vm-test-run-index_advisor> server # [  113.450617] postgres[1436]: ::1 2026-10-23 15:21:55.944 UTC [1436] postgres@postgres LOG:  statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';888vm-test-run-index_advisor> server # [  113.461471] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: COMMENT889vm-test-run-index_advisor> server # [  113.501683] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: INSERT 0 7890vm-test-run-index_advisor> server # [  113.513716] postgres[1436]: ::1 2026-10-23 15:21:55.999 UTC [1436] postgres@postgres LOG:  statement: create or replace function auth.uid() returns uuid as $$891vm-test-run-index_advisor> server # [  113.525292] postgres[1436]: 	  select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;892vm-test-run-index_advisor> server # [  113.526704] postgres[1436]: 	$$ language sql stable;893vm-test-run-index_advisor> server # [  113.542920] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE FUNCTION894vm-test-run-index_advisor> server # [  113.554818] postgres[1436]: ::1 2026-10-23 15:21:56.041 UTC [1436] postgres@postgres LOG:  statement: create or replace function auth.role() returns text as $$895vm-test-run-index_advisor> server # [  113.559093] postgres[1436]: 	  select nullif(current_setting('request.jwt.claim.role', true), '')::text;896vm-test-run-index_advisor> server # [  113.574187] postgres[1436]: 	$$ language sql stable;897vm-test-run-index_advisor> server # [  113.596981] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE FUNCTION898vm-test-run-index_advisor> server # [  113.613511] postgres[1436]: ::1 2026-10-23 15:21:56.095 UTC [1436] postgres@postgres LOG:  statement: create or replace function auth.email() returns text as $$899vm-test-run-index_advisor> server # [  113.615611] postgres[1436]: 	  select nullif(current_setting('request.jwt.claim.email', true), '')::text;900vm-test-run-index_advisor> server # [  113.623661] postgres[1436]: 	$$ language sql stable;901vm-test-run-index_advisor> server # [  113.634271] postgres[1436]: ::1 2026-10-23 15:21:56.117 UTC [1436] postgres@postgres LOG:  statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;902vm-test-run-index_advisor> server # [  113.645514] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE FUNCTION903vm-test-run-index_advisor> server # [  113.646339] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT904vm-test-run-index_advisor> server # [  113.646785] postgres[1436]: ::1 2026-10-23 15:21:56.127 UTC [1436] postgres@postgres LOG:  statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;905vm-test-run-index_advisor> server # [  113.647257] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: CREATE ROLE906vm-test-run-index_advisor> server # [  113.647609] postgres[1436]: ::1 2026-10-23 15:21:56.141 UTC [1436] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;907vm-test-run-index_advisor> server # [  113.663940] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT908vm-test-run-index_advisor> server # [  113.682752] postgres[1436]: ::1 2026-10-23 15:21:56.157 UTC [1436] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;909vm-test-run-index_advisor> server # [  113.690266] postgres[1436]: ::1 2026-10-23 15:21:56.174 UTC [1436] postgres@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;910vm-test-run-index_advisor> server # [  113.711936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT911vm-test-run-index_advisor> server # [  113.719002] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: GRANT912vm-test-run-index_advisor> server # [  113.719735] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER ROLE913vm-test-run-index_advisor> server # [  113.720196] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE914vm-test-run-index_advisor> server # [  113.730168] postgres[1436]: ::1 2026-10-23 15:21:56.189 UTC [1436] postgres@postgres LOG:  statement: ALTER USER supabase_auth_admin SET search_path = "auth";915vm-test-run-index_advisor> server # [  113.731638] postgres[1436]: ::1 2026-10-23 15:21:56.200 UTC [1436] postgres@postgres LOG:  statement: ALTER table "auth".users OWNER TO supabase_auth_admin;916vm-test-run-index_advisor> server # [  113.748540] postgres[1436]: ::1 2026-10-23 15:21:56.214 UTC [1436] postgres@postgres LOG:  statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;917vm-test-run-index_advisor> server # [  113.749595] postgres[1436]: ::1 2026-10-23 15:21:56.230 UTC [1436] postgres@postgres LOG:  statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;918vm-test-run-index_advisor> server # [  113.751412] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE919vm-test-run-index_advisor> server # [  113.751780] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE920vm-test-run-index_advisor> server # [  113.752126] postgres[1436]: ::1 2026-10-23 15:21:56.246 UTC [1436] postgres@postgres LOG:  statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;921vm-test-run-index_advisor> server # [  113.765765] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE922vm-test-run-index_advisor> server # [  113.767400] postgres[1436]: ::1 2026-10-23 15:21:56.258 UTC [1436] postgres@postgres LOG:  statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;923vm-test-run-index_advisor> server # [  113.801105] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1435]: ALTER TABLE924vm-test-run-index_advisor> server # [  113.883418] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000002-storage-schema.sql925vm-test-run-index_advisor> server # [  114.418811] postgres[1454]: ::1 2026-10-23 15:21:56.896 UTC [1454] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;926vm-test-run-index_advisor> server # [  114.430746] postgres[1454]: ::1 2026-10-23 15:21:56.913 UTC [1454] postgres@postgres LOG:  statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;927vm-test-run-index_advisor> server # [  114.454372] postgres[1454]: ::1 2026-10-23 15:21:56.924 UTC [1454] postgres@postgres LOG:  statement: ALTER USER supabase_storage_admin SET search_path = "storage";928vm-test-run-index_advisor> server # [  114.485905] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: CREATE SCHEMA929vm-test-run-index_advisor> server # [  114.487336] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: CREATE ROLE930vm-test-run-index_advisor> server # [  114.493325] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: ALTER ROLE931vm-test-run-index_advisor> server # [  114.494427] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: GRANT932vm-test-run-index_advisor> server # [  114.494979] postgres[1454]: ::1 2026-10-23 15:21:56.937 UTC [1454] postgres@postgres LOG:  statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;933vm-test-run-index_advisor> server # [  114.522843] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1450]: DO934vm-test-run-index_advisor> server # [  114.569201] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running init-script: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/init-scripts/00000000000003-post-setup.sql935vm-test-run-index_advisor> server # [  115.048028] postgres[1464]: ::1 2026-10-23 15:21:57.524 UTC [1464] postgres@postgres LOG:  statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;936vm-test-run-index_advisor> server # [  115.081971] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: ALTER ROLE937vm-test-run-index_advisor> server # [  115.086310] postgres[1464]: ::1 2026-10-23 15:21:57.561 UTC [1464] postgres@postgres LOG:  statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;938vm-test-run-index_advisor> server # [  115.087950] postgres[1464]: ::1 2026-10-23 15:21:57.573 UTC [1464] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()939vm-test-run-index_advisor> server # [  115.091497] postgres[1464]: 	RETURNS event_trigger940vm-test-run-index_advisor> server # [  115.098183] postgres[1464]: 	LANGUAGE plpgsql941vm-test-run-index_advisor> server # [  115.099605] postgres[1464]: 	AS $$942vm-test-run-index_advisor> server # [  115.099948] postgres[1464]: 	DECLARE943vm-test-run-index_advisor> server # [  115.102457] postgres[1464]: 	  schema_is_cron bool;944vm-test-run-index_advisor> server # [  115.103184] postgres[1464]: 	BEGIN945vm-test-run-index_advisor> server # [  115.104319] postgres[1464]: 	  schema_is_cron = (946vm-test-run-index_advisor> server # [  115.104706] postgres[1464]: 	    SELECT n.nspname = 'cron'947vm-test-run-index_advisor> server # [  115.106382] postgres[1464]: 	    FROM pg_event_trigger_ddl_commands() AS ev948vm-test-run-index_advisor> server # [  115.106797] postgres[1464]: 	    LEFT JOIN pg_catalog.pg_namespace AS n949vm-test-run-index_advisor> server # [  115.107287] postgres[1464]: 	      ON ev.objid = n.oid950vm-test-run-index_advisor> server # [  115.109915] postgres[1464]: 	  );951vm-test-run-index_advisor> server # [  115.111646] postgres[1464]:952vm-test-run-index_advisor> server # [  115.115015] postgres[1464]: 	  IF schema_is_cron953vm-test-run-index_advisor> server # [  115.118509] postgres[1464]: 	  THEN954vm-test-run-index_advisor> server # [  115.118986] postgres[1464]: 	    grant usage on schema cron to postgres with grant option;955vm-test-run-index_advisor> server # [  115.119527] postgres[1464]:956vm-test-run-index_advisor> server # [  115.119847] postgres[1464]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;957vm-test-run-index_advisor> server # [  115.121398] postgres[1464]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;958vm-test-run-index_advisor> server # [  115.121983] postgres[1464]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;959vm-test-run-index_advisor> server # [  115.122547] postgres[1464]:960vm-test-run-index_advisor> server # [  115.123307] postgres[1464]: 	    alter default privileges for user supabase_admin in schema cron grant all961vm-test-run-index_advisor> server # [  115.124074] postgres[1464]: 	        on sequences to postgres with grant option;962vm-test-run-index_advisor> server # [  115.125784] postgres[1464]: 	    alter default privileges for user supabase_admin in schema cron grant all963vm-test-run-index_advisor> server # [  115.126591] postgres[1464]: 	        on tables to postgres with grant option;964vm-test-run-index_advisor> server # [  115.127561] postgres[1464]: 	    alter default privileges for user supabase_admin in schema cron grant all965vm-test-run-index_advisor> server # [  115.130388] postgres[1464]: 	        on functions to postgres with grant option;966vm-test-run-index_advisor> server # [  115.131002] postgres[1464]:967vm-test-run-index_advisor> server # [  115.132174] postgres[1464]: 	    grant all privileges on all tables in schema cron to postgres with grant option;968vm-test-run-index_advisor> server # [  115.133392] postgres[1464]:969vm-test-run-index_advisor> server # [  115.134162] postgres[1464]: 	  END IF;970vm-test-run-index_advisor> server # [  115.135121] postgres[1464]:971vm-test-run-index_advisor> server # [  115.135755] postgres[1464]: 	END;972vm-test-run-index_advisor> server # [  115.138435] postgres[1464]: 	$$;973vm-test-run-index_advisor> server # [  115.139112] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: ALTER ROLE974vm-test-run-index_advisor> server # [  115.139683] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE FUNCTION975vm-test-run-index_advisor> server # [  115.140260] postgres[1464]: ::1 2026-10-23 15:21:57.617 UTC [1464] postgres@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')976vm-test-run-index_advisor> server # [  115.143521] postgres[1464]: 	EXECUTE PROCEDURE extensions.grant_pg_cron_access();977vm-test-run-index_advisor> server # [  115.146930] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE EVENT TRIGGER978vm-test-run-index_advisor> server # [  115.154732] postgres[1464]: ::1 2026-10-23 15:21:57.644 UTC [1464] postgres@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';979vm-test-run-index_advisor> server # [  115.162232] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: COMMENT980vm-test-run-index_advisor> server # [  115.165193] postgres[1464]: ::1 2026-10-23 15:21:57.658 UTC [1464] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()981vm-test-run-index_advisor> server # [  115.167648] postgres[1464]: 	RETURNS event_trigger982vm-test-run-index_advisor> server # [  115.169663] postgres[1464]: 	LANGUAGE plpgsql983vm-test-run-index_advisor> server # [  115.171080] postgres[1464]: 	AS $$984vm-test-run-index_advisor> server # [  115.172950] postgres[1464]: 	BEGIN985vm-test-run-index_advisor> server # [  115.174572] postgres[1464]: 	  IF EXISTS (986vm-test-run-index_advisor> server # [  115.176093] postgres[1464]: 	    SELECT 1987vm-test-run-index_advisor> server # [  115.177596] postgres[1464]: 	    FROM pg_event_trigger_ddl_commands() AS ev988vm-test-run-index_advisor> server # [  115.178054] postgres[1464]: 	    JOIN pg_extension AS ext989vm-test-run-index_advisor> server # [  115.178672] postgres[1464]: 	    ON ev.objid = ext.oid990vm-test-run-index_advisor> server # [  115.179559] postgres[1464]: 	    WHERE ext.extname = 'pg_net'991vm-test-run-index_advisor> server # [  115.179874] postgres[1464]: 	  )992vm-test-run-index_advisor> server # [  115.181641] postgres[1464]: 	  THEN993vm-test-run-index_advisor> server # [  115.182336] postgres[1464]: 	    IF NOT EXISTS (994vm-test-run-index_advisor> server # [  115.183350] postgres[1464]: 	      SELECT 1995vm-test-run-index_advisor> server # [  115.185507] postgres[1464]: 	      FROM pg_roles996vm-test-run-index_advisor> server # [  115.186815] postgres[1464]: 	      WHERE rolname = 'supabase_functions_admin'997vm-test-run-index_advisor> server # [  115.187187] postgres[1464]: 	    )998vm-test-run-index_advisor> server # [  115.188939] postgres[1464]: 	    THEN999vm-test-run-index_advisor> server # [  115.189700] postgres[1464]: 	      CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1000vm-test-run-index_advisor> server # [  115.190091] postgres[1464]: 	    END IF;1001vm-test-run-index_advisor> server # [  115.190354] postgres[1464]:1002vm-test-run-index_advisor> server # [  115.190606] postgres[1464]: 	    GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1003vm-test-run-index_advisor> server # [  115.191415] postgres[1464]:1004vm-test-run-index_advisor> server # [  115.191748] postgres[1464]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1005vm-test-run-index_advisor> server # [  115.192075] postgres[1464]: 	    ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1006vm-test-run-index_advisor> server # [  115.194019] postgres[1464]:1007vm-test-run-index_advisor> server # [  115.195203] postgres[1464]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1008vm-test-run-index_advisor> server # [  115.196988] postgres[1464]: 	    ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1009vm-test-run-index_advisor> server # [  115.198057] postgres[1464]:1010vm-test-run-index_advisor> server # [  115.199156] postgres[1464]: 	    REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1011vm-test-run-index_advisor> server # [  115.199589] postgres[1464]: 	    REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1012vm-test-run-index_advisor> server # [  115.200643] postgres[1464]:1013vm-test-run-index_advisor> server # [  115.201332] postgres[1464]: 	    GRANT EXECUTE ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) TO supabase_functions_admin, postgres, anon, authenticated, service_role;1014vm-test-run-index_advisor> server # [  115.203560] postgres[1464]: 	    GRANT EXECUTE ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) TO supabase_functions_admin, postgres, anon, authenticated, service_role;1015vm-test-run-index_advisor> server # [  115.206439] postgres[1464]: 	  END IF;1016vm-test-run-index_advisor> server # [  115.206992] postgres[1464]: 	END;1017vm-test-run-index_advisor> server # [  115.207318] postgres[1464]: 	$$;1018vm-test-run-index_advisor> server # [  115.207725] postgres[1464]: ::1 2026-10-23 15:21:57.696 UTC [1464] postgres@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1019vm-test-run-index_advisor> server # [  115.209195] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE FUNCTION1020vm-test-run-index_advisor> server # [  115.220967] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: COMMENT1021vm-test-run-index_advisor> server # [  115.244355] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: DO1022vm-test-run-index_advisor> server # [  115.250126] postgres[1464]: ::1 2026-10-23 15:21:57.739 UTC [1464] postgres@postgres LOG:  statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1023vm-test-run-index_advisor> server # [  115.254268] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: CREATE ROLE1024vm-test-run-index_advisor> server # [  115.255194] postgres[1464]: ::1 2026-10-23 15:21:57.749 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1025vm-test-run-index_advisor> server # [  115.263045] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1026vm-test-run-index_advisor> server # [  115.263988] postgres[1464]: ::1 2026-10-23 15:21:57.758 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1027vm-test-run-index_advisor> server # [  115.275122] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1028vm-test-run-index_advisor> server # [  115.276377] postgres[1464]: ::1 2026-10-23 15:21:57.770 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1029vm-test-run-index_advisor> server # [  115.286833] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1030vm-test-run-index_advisor> server # [  115.291665] postgres[1464]: ::1 2026-10-23 15:21:57.781 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1031vm-test-run-index_advisor> server # [  115.302838] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1032vm-test-run-index_advisor> server # [  115.303756] postgres[1464]: ::1 2026-10-23 15:21:57.798 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1033vm-test-run-index_advisor> server # [  115.310037] postgres[1464]: ::1 2026-10-23 15:21:57.801 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1034vm-test-run-index_advisor> server # [  115.315408] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1035vm-test-run-index_advisor> server # [  115.316281] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1036vm-test-run-index_advisor> server # [  115.317413] postgres[1464]: ::1 2026-10-23 15:21:57.811 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1037vm-test-run-index_advisor> server # [  115.322049] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1038vm-test-run-index_advisor> server # [  115.323090] postgres[1464]: ::1 2026-10-23 15:21:57.817 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1039vm-test-run-index_advisor> server # [  115.343841] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1040vm-test-run-index_advisor> server # [  115.349734] postgres[1464]: ::1 2026-10-23 15:21:57.839 UTC [1464] postgres@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1041vm-test-run-index_advisor> server # [  115.376961] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: GRANT1042vm-test-run-index_advisor> server # [  115.392882] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1458]: DO1043vm-test-run-index_advisor> server # [  115.439143] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running pgbouncer auth schema...1044vm-test-run-index_advisor> server # [  115.742493] postgres[1466]: ::1 2026-10-23 15:21:58.216 UTC [1466] postgres@postgres LOG:  statement: CREATE USER pgbouncer;1045vm-test-run-index_advisor> server # [  115.767601] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: CREATE ROLE1046vm-test-run-index_advisor> server # [  115.775370] postgres[1466]: ::1 2026-10-23 15:21:58.242 UTC [1466] postgres@postgres LOG:  statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1047vm-test-run-index_advisor> server # [  115.781924] postgres[1466]: ::1 2026-10-23 15:21:58.273 UTC [1466] postgres@postgres LOG:  statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1048vm-test-run-index_advisor> server # [  115.783162] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: REVOKE1049vm-test-run-index_advisor> server # [  115.836571] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: CREATE SCHEMA1050vm-test-run-index_advisor> server # [  115.842976] postgres[1466]: ::1 2026-10-23 15:21:58.335 UTC [1466] postgres@postgres LOG:  statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1051vm-test-run-index_advisor> server # [  115.853935] postgres[1466]: 	RETURNS TABLE(username TEXT, password TEXT) AS1052vm-test-run-index_advisor> server # [  115.855265] postgres[1466]: 	$$1053vm-test-run-index_advisor> server # [  115.856672] postgres[1466]: 	BEGIN1054vm-test-run-index_advisor> server # [  115.860658] postgres[1466]: 	    RAISE WARNING 'PgBouncer auth request: %', p_usename;1055vm-test-run-index_advisor> server # [  115.865837] postgres[1466]:1056vm-test-run-index_advisor> server # [  115.866597] postgres[1466]: 	    RETURN QUERY1057vm-test-run-index_advisor> server # [  115.867520] postgres[1466]: 	    SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1058vm-test-run-index_advisor> server # [  115.867894] postgres[1466]: 	    WHERE usename = p_usename;1059vm-test-run-index_advisor> server # [  115.878470] postgres[1466]: 	END;1060vm-test-run-index_advisor> server # [  115.879009] postgres[1466]: 	$$ LANGUAGE plpgsql1061vm-test-run-index_advisor> server # [  115.879301] postgres[1466]: 	SET search_path = ''1062vm-test-run-index_advisor> server # [  115.879555] postgres[1466]: 	SECURITY DEFINER;1063vm-test-run-index_advisor> server # [  115.890608] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: CREATE FUNCTION1064vm-test-run-index_advisor> server # [  115.891562] postgres[1466]: ::1 2026-10-23 15:21:58.380 UTC [1466] postgres@postgres LOG:  statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1065vm-test-run-index_advisor> server # [  115.906353] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: REVOKE1066vm-test-run-index_advisor> server # [  115.926297] postgres[1466]: ::1 2026-10-23 15:21:58.398 UTC [1466] postgres@postgres LOG:  statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1067vm-test-run-index_advisor> server # [  115.934383] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1465]: GRANT1068vm-test-run-index_advisor> server # [  115.976904] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running stat extension...1069vm-test-run-index_advisor> server # [  116.380062] postgres[1472]: ::1 2026-10-23 15:21:58.860 UTC [1472] postgres@postgres LOG:  statement: CREATE SCHEMA IF NOT exists extensions;1070vm-test-run-index_advisor> server # [  116.410191] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1471]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE:  schema "extensions" already exists, skipping1071vm-test-run-index_advisor> server # [  116.440087] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1471]: CREATE SCHEMA1072vm-test-run-index_advisor> server # [  116.447221] postgres[1472]: ::1 2026-10-23 15:21:58.939 UTC [1472] postgres@postgres LOG:  statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1073vm-test-run-index_advisor> server # [  118.127984] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1471]: CREATE EXTENSION1074vm-test-run-index_advisor> server # [  118.185812] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/10000000000000_demote-postgres.sql1075vm-test-run-index_advisor> server # [  118.574958] postgres[1498]: ::1 2026-10-23 15:22:01.058 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON DATABASE postgres TO postgres;1076vm-test-run-index_advisor> server # [  118.584275] postgres[1498]: ::1 2026-10-23 15:22:01.077 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON SCHEMA auth TO postgres;1077vm-test-run-index_advisor> server # [  118.605423] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1078vm-test-run-index_advisor> server # [  118.606315] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1079vm-test-run-index_advisor> server # [  118.606936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1080vm-test-run-index_advisor> server # [  118.607485] postgres[1498]: ::1 2026-10-23 15:22:01.092 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON SCHEMA extensions TO postgres;1081vm-test-run-index_advisor> server # [  118.608209] postgres[1498]: ::1 2026-10-23 15:22:01.100 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1082vm-test-run-index_advisor> server # [  118.633274] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1083vm-test-run-index_advisor> server # [  118.634204] postgres[1498]: ::1 2026-10-23 15:22:01.126 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1084vm-test-run-index_advisor> server # [  118.639026] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1085vm-test-run-index_advisor> server # [  118.639960] postgres[1498]: ::1 2026-10-23 15:22:01.133 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1086vm-test-run-index_advisor> server # [  118.651366] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1087vm-test-run-index_advisor> server # [  118.654225] postgres[1498]: ::1 2026-10-23 15:22:01.145 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1088vm-test-run-index_advisor> server # [  118.655239] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1089vm-test-run-index_advisor> server # [  118.665478] postgres[1498]: ::1 2026-10-23 15:22:01.151 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1090vm-test-run-index_advisor> server # [  118.681139] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1091vm-test-run-index_advisor> server # [  118.682172] postgres[1498]: ::1 2026-10-23 15:22:01.173 UTC [1498] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1092vm-test-run-index_advisor> server # [  118.715392] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: GRANT1093vm-test-run-index_advisor> server # [  118.742528] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: DO1094vm-test-run-index_advisor> server # [  118.745642] postgres[1498]: ::1 2026-10-23 15:22:01.238 UTC [1498] supabase_admin@postgres LOG:  statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1095vm-test-run-index_advisor> server # [  118.756966] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1497]: ALTER ROLE1096vm-test-run-index_advisor> server # [  118.793932] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211115181400_update-auth-permissions.sql1097vm-test-run-index_advisor> server # [  119.040743] postgres[1500]: ::1 2026-10-23 15:22:01.529 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1098vm-test-run-index_advisor> server # [  119.057658] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1099vm-test-run-index_advisor> server # [  119.061396] postgres[1500]: ::1 2026-10-23 15:22:01.549 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1100vm-test-run-index_advisor> server # [  119.069741] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1101vm-test-run-index_advisor> server # [  119.070731] postgres[1500]: ::1 2026-10-23 15:22:01.564 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1102vm-test-run-index_advisor> server # [  119.078931] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1103vm-test-run-index_advisor> server # [  119.079810] postgres[1500]: ::1 2026-10-23 15:22:01.574 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1104vm-test-run-index_advisor> server # [  119.089706] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE1105vm-test-run-index_advisor> server # [  119.090902] postgres[1500]: ::1 2026-10-23 15:22:01.583 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1106vm-test-run-index_advisor> server # [  119.101597] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE1107vm-test-run-index_advisor> server # [  119.102560] postgres[1500]: ::1 2026-10-23 15:22:01.593 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1108vm-test-run-index_advisor> server # [  119.107669] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE1109vm-test-run-index_advisor> server # [  119.111755] postgres[1500]: ::1 2026-10-23 15:22:01.603 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1110vm-test-run-index_advisor> server # [  119.120316] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE1111vm-test-run-index_advisor> server # [  119.123580] postgres[1500]: ::1 2026-10-23 15:22:01.612 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1112vm-test-run-index_advisor> server # [  119.127389] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER TABLE1113vm-test-run-index_advisor> server # [  119.133383] postgres[1500]: ::1 2026-10-23 15:22:01.625 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT USAGE ON SCHEMA auth TO postgres;1114vm-test-run-index_advisor> server # [  119.144976] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1115vm-test-run-index_advisor> server # [  119.146273] postgres[1500]: ::1 2026-10-23 15:22:01.637 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1116vm-test-run-index_advisor> server # [  119.156529] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1117vm-test-run-index_advisor> server # [  119.162150] postgres[1500]: ::1 2026-10-23 15:22:01.655 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1118vm-test-run-index_advisor> server # [  119.173619] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1119vm-test-run-index_advisor> server # [  119.175977] postgres[1500]: ::1 2026-10-23 15:22:01.669 UTC [1500] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1120vm-test-run-index_advisor> server # [  119.187239] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: GRANT1121vm-test-run-index_advisor> server # [  119.195220] postgres[1500]: ::1 2026-10-23 15:22:01.684 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1122vm-test-run-index_advisor> server # [  119.201493] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER DEFAULT PRIVILEGES1123vm-test-run-index_advisor> server # [  119.207107] postgres[1500]: ::1 2026-10-23 15:22:01.698 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1124vm-test-run-index_advisor> server # [  119.215892] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER DEFAULT PRIVILEGES1125vm-test-run-index_advisor> server # [  119.217747] postgres[1500]: ::1 2026-10-23 15:22:01.707 UTC [1500] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1126vm-test-run-index_advisor> server # [  119.222983] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1499]: ALTER DEFAULT PRIVILEGES1127vm-test-run-index_advisor> server # [  119.263799] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211118015519_create-realtime-schema.sql1128vm-test-run-index_advisor> server # [  119.547644] postgres[1502]: ::1 2026-10-23 15:22:02.025 UTC [1502] supabase_admin@postgres LOG:  statement: CREATE SCHEMA IF NOT EXISTS realtime;1129vm-test-run-index_advisor> server # [  119.623948] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1501]: CREATE SCHEMA1130vm-test-run-index_advisor> server # [  119.642300] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1131vm-test-run-index_advisor> server # [  120.074793] postgres[1511]: ::1 2026-10-23 15:22:02.551 UTC [1511] supabase_admin@postgres LOG:  statement: GRANT USAGE ON SCHEMA realtime TO postgres;1132vm-test-run-index_advisor> server # [  120.114545] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT1133vm-test-run-index_advisor> server # [  120.127280] postgres[1511]: ::1 2026-10-23 15:22:02.594 UTC [1511] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1134vm-test-run-index_advisor> server # [  120.133461] postgres[1511]: ::1 2026-10-23 15:22:02.612 UTC [1511] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1135vm-test-run-index_advisor> server # [  120.145176] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT1136vm-test-run-index_advisor> server # [  120.147679] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT1137vm-test-run-index_advisor> server # [  120.158240] postgres[1511]: ::1 2026-10-23 15:22:02.624 UTC [1511] supabase_admin@postgres LOG:  statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1138vm-test-run-index_advisor> server # [  120.174990] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1505]: GRANT1139vm-test-run-index_advisor> server # [  120.201270] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211124212715_update-auth-owner.sql1140vm-test-run-index_advisor> server # [  120.719568] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1515]: DO1141vm-test-run-index_advisor> server # [  120.732903] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1515]: DO1142vm-test-run-index_advisor> server # [  120.753263] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1515]: DO1143vm-test-run-index_advisor> server # [  120.817225] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1144vm-test-run-index_advisor> server # [  121.231264] postgres[1531]: ::1 2026-10-23 15:22:03.721 UTC [1531] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1145vm-test-run-index_advisor> server # [  121.275009] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES1146vm-test-run-index_advisor> server # [  121.276235] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES1147vm-test-run-index_advisor> server # [  121.281725] postgres[1531]: ::1 2026-10-23 15:22:03.756 UTC [1531] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1148vm-test-run-index_advisor> server # [  121.283044] postgres[1531]: ::1 2026-10-23 15:22:03.774 UTC [1531] supabase_admin@postgres LOG:  statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1149vm-test-run-index_advisor> server # [  121.293939] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1525]: ALTER DEFAULT PRIVILEGES1150vm-test-run-index_advisor> server # [  121.327266] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1151vm-test-run-index_advisor> server # [  121.651496] postgres[1534]: ::1 2026-10-23 15:22:04.140 UTC [1534] supabase_admin@postgres LOG:  statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1152vm-test-run-index_advisor> server # [  121.700248] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1533]: ALTER ROLE1153vm-test-run-index_advisor> server # [  121.737168] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1154vm-test-run-index_advisor> server # [  122.058111] postgres[1540]: ::1 2026-10-23 15:22:04.544 UTC [1540] supabase_admin@postgres LOG:  statement: drop event trigger if exists api_restart;1155vm-test-run-index_advisor> server # [  122.073366] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE:  event trigger "api_restart" does not exist, skipping1156vm-test-run-index_advisor> server # [  122.075337] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP EVENT TRIGGER1157vm-test-run-index_advisor> server # [  122.079129] postgres[1540]: ::1 2026-10-23 15:22:04.565 UTC [1540] supabase_admin@postgres LOG:  statement: drop function if exists extensions.notify_api_restart();1158vm-test-run-index_advisor> server # [  122.089644] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE:  function extensions.notify_api_restart() does not exist, skipping1159vm-test-run-index_advisor> server # [  122.090807] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP FUNCTION1160vm-test-run-index_advisor> server # [  122.094560] postgres[1540]: ::1 2026-10-23 15:22:04.587 UTC [1540] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1161vm-test-run-index_advisor> server # [  122.100859] postgres[1540]: 	DECLARE1162vm-test-run-index_advisor> server # [  122.102167] postgres[1540]: 	  cmd record;1163vm-test-run-index_advisor> server # [  122.103917] postgres[1540]: 	BEGIN1164vm-test-run-index_advisor> server # [  122.109181] postgres[1540]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1165vm-test-run-index_advisor> server # [  122.110226] postgres[1540]: 	  LOOP1166vm-test-run-index_advisor> server # [  122.111271] postgres[1540]: 	    IF cmd.command_tag IN (1167vm-test-run-index_advisor> server # [  122.111775] postgres[1540]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1168vm-test-run-index_advisor> server # [  122.112324] postgres[1540]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1169vm-test-run-index_advisor> server # [  122.116635] postgres[1540]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1170vm-test-run-index_advisor> server # [  122.117918] postgres[1540]: 	    , 'CREATE VIEW', 'ALTER VIEW'1171vm-test-run-index_advisor> server # [  122.119326] postgres[1540]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1172vm-test-run-index_advisor> server # [  122.125134] postgres[1540]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1173vm-test-run-index_advisor> server # [  122.125932] postgres[1540]: 	    , 'CREATE TRIGGER'1174vm-test-run-index_advisor> server # [  122.126519] postgres[1540]: 	    , 'CREATE TYPE'1175vm-test-run-index_advisor> server # [  122.127422] postgres[1540]: 	    , 'CREATE RULE'1176vm-test-run-index_advisor> server # [  122.127785] postgres[1540]: 	    , 'COMMENT'1177vm-test-run-index_advisor> server # [  122.131813] postgres[1540]: 	    )1178vm-test-run-index_advisor> server # [  122.141323] postgres[1540]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1179vm-test-run-index_advisor> server # [  122.142658] postgres[1540]: 	    AND cmd.schema_name is distinct from 'pg_temp'1180vm-test-run-index_advisor> server # [  122.143589] postgres[1540]: 	    THEN1181vm-test-run-index_advisor> server # [  122.150509] postgres[1540]: 	      NOTIFY pgrst, 'reload schema';1182vm-test-run-index_advisor> server # [  122.162261] postgres[1540]: 	    END IF;1183vm-test-run-index_advisor> server # [  122.163176] postgres[1540]: 	  END LOOP;1184vm-test-run-index_advisor> server # [  122.163967] postgres[1540]: 	END; $$ LANGUAGE plpgsql;1185vm-test-run-index_advisor> server # [  122.170511] postgres[1540]: ::1 2026-10-23 15:22:04.634 UTC [1540] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1186vm-test-run-index_advisor> server # [  122.172282] postgres[1540]: 	DECLARE1187vm-test-run-index_advisor> server # [  122.176420] postgres[1540]: 	  obj record;1188vm-test-run-index_advisor> server # [  122.177073] postgres[1540]: 	BEGIN1189vm-test-run-index_advisor> server # [  122.186514] postgres[1540]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1190vm-test-run-index_advisor> server # [  122.188119] postgres[1540]: 	  LOOP1191vm-test-run-index_advisor> server # [  122.194094] postgres[1540]: 	    IF obj.object_type IN (1192vm-test-run-index_advisor> server # [  122.194462] postgres[1540]: 	      'schema'1193vm-test-run-index_advisor> server # [  122.195057] postgres[1540]: 	    , 'table'1194vm-test-run-index_advisor> server # [  122.195552] postgres[1540]: 	    , 'foreign table'1195vm-test-run-index_advisor> server # [  122.195944] postgres[1540]: 	    , 'view'1196vm-test-run-index_advisor> server # [  122.196197] postgres[1540]: 	    , 'materialized view'1197vm-test-run-index_advisor> server # [  122.202288] postgres[1540]: 	    , 'function'1198vm-test-run-index_advisor> server # [  122.205867] postgres[1540]: 	    , 'trigger'1199vm-test-run-index_advisor> server # [  122.206574] postgres[1540]: 	    , 'type'1200vm-test-run-index_advisor> server # [  122.207110] postgres[1540]: 	    , 'rule'1201vm-test-run-index_advisor> server # [  122.207582] postgres[1540]: 	    )1202vm-test-run-index_advisor> server # [  122.207839] postgres[1540]: 	    AND obj.is_temporary IS false -- no pg_temp objects1203vm-test-run-index_advisor> server # [  122.208995] postgres[1540]: 	    THEN1204vm-test-run-index_advisor> server # [  122.210018] postgres[1540]: 	      NOTIFY pgrst, 'reload schema';1205vm-test-run-index_advisor> server # [  122.210946] postgres[1540]: 	    END IF;1206vm-test-run-index_advisor> server # [  122.213622] postgres[1540]: 	  END LOOP;1207vm-test-run-index_advisor> server # [  122.214837] postgres[1540]: 	END; $$ LANGUAGE plpgsql;1208vm-test-run-index_advisor> server # [  122.215304] postgres[1540]: ::1 2026-10-23 15:22:04.652 UTC [1540] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1209vm-test-run-index_advisor> server # [  122.217396] postgres[1540]: ::1 2026-10-23 15:22:04.661 UTC [1540] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_ddl_watch1210vm-test-run-index_advisor> server # [  122.223199] postgres[1540]: 	  ON ddl_command_end1211vm-test-run-index_advisor> server # [  122.224199] postgres[1540]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1212vm-test-run-index_advisor> server # [  122.230475] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE FUNCTION1213vm-test-run-index_advisor> server # [  122.231770] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE FUNCTION1214vm-test-run-index_advisor> server # [  122.239173] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE:  event trigger "pgrst_ddl_watch" does not exist, skipping1215vm-test-run-index_advisor> server # [  122.246348] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP EVENT TRIGGER1216vm-test-run-index_advisor> server # [  122.247051] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE EVENT TRIGGER1217vm-test-run-index_advisor> server # [  122.247841] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE:  event trigger "pgrst_drop_watch" does not exist, skipping1218vm-test-run-index_advisor> server # [  122.257837] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: DROP EVENT TRIGGER1219vm-test-run-index_advisor> server # [  122.268936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1538]: CREATE EVENT TRIGGER1220vm-test-run-index_advisor> server # [  122.271155] postgres[1540]: ::1 2026-10-23 15:22:04.677 UTC [1540] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1221vm-test-run-index_advisor> server # [  122.285747] postgres[1540]: ::1 2026-10-23 15:22:04.685 UTC [1540] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_drop_watch1222vm-test-run-index_advisor> server # [  122.287434] postgres[1540]: 	  ON sql_drop1223vm-test-run-index_advisor> server # [  122.298319] postgres[1540]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1224vm-test-run-index_advisor> server # [  122.309470] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1225vm-test-run-index_advisor> server # [  123.257603] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1541]: DO1226vm-test-run-index_advisor> server # [  123.318745] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220317095840_pg_graphql.sql1227vm-test-run-index_advisor> server # [  123.786447] postgres[1558]: ::1 2026-10-23 15:22:06.256 UTC [1558] supabase_admin@postgres LOG:  statement: create schema if not exists graphql_public;1228vm-test-run-index_advisor> server # [  123.851912] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE SCHEMA1229vm-test-run-index_advisor> server # [  123.861242] postgres[1558]: ::1 2026-10-23 15:22:06.336 UTC [1558] supabase_admin@postgres LOG:  statement: drop function if exists graphql_public.graphql(text, text, jsonb);1230vm-test-run-index_advisor> server # [  123.862354] postgres[1558]: ::1 2026-10-23 15:22:06.346 UTC [1558] supabase_admin@postgres LOG:  statement: create or replace function graphql_public.graphql(1231vm-test-run-index_advisor> server # [  123.863541] postgres[1558]: 	    "operationName" text default null,1232vm-test-run-index_advisor> server # [  123.869105] postgres[1558]: 	    query text default null,1233vm-test-run-index_advisor> server # [  123.870303] postgres[1558]: 	    variables jsonb default null,1234vm-test-run-index_advisor> server # [  123.871737] postgres[1558]: 	    extensions jsonb default null1235vm-test-run-index_advisor> server # [  123.872391] postgres[1558]: 	)1236vm-test-run-index_advisor> server # [  123.885544] postgres[1558]: 	    returns jsonb1237vm-test-run-index_advisor> server # [  123.886573] postgres[1558]: 	    language plpgsql1238vm-test-run-index_advisor> server # [  123.886933] postgres[1558]: 	as $$1239vm-test-run-index_advisor> server # [  123.887745] postgres[1558]: 	    DECLARE1240vm-test-run-index_advisor> server # [  123.888090] postgres[1558]: 	        server_version float;1241vm-test-run-index_advisor> server # [  123.888610] postgres[1558]: 	    BEGIN1242vm-test-run-index_advisor> server # [  123.898168] postgres[1558]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1243vm-test-run-index_advisor> server # [  123.899091] postgres[1558]:1244vm-test-run-index_advisor> server # [  123.907670] postgres[1558]: 	        IF server_version >= 14 THEN1245vm-test-run-index_advisor> server # [  123.915157] postgres[1558]: 	            RETURN jsonb_build_object(1246vm-test-run-index_advisor> server # [  123.915703] postgres[1558]: 	                'data', null::jsonb,1247vm-test-run-index_advisor> server # [  123.916206] postgres[1558]: 	                'errors', array['pg_graphql extension is not enabled.']1248vm-test-run-index_advisor> server # [  123.919283] postgres[1558]: 	            );1249vm-test-run-index_advisor> server # [  123.927099] postgres[1558]: 	        ELSE1250vm-test-run-index_advisor> server # [  123.932307] postgres[1558]: 	            RETURN jsonb_build_object(1251vm-test-run-index_advisor> server # [  123.933034] postgres[1558]: 	                'data', null::jsonb,1252vm-test-run-index_advisor> server # [  123.941162] postgres[1558]: 	                'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1253vm-test-run-index_advisor> server # [  123.942331] postgres[1558]: 	            );1254vm-test-run-index_advisor> server # [  123.943218] postgres[1558]: 	        END IF;1255vm-test-run-index_advisor> server # [  123.958207] postgres[1558]: 	    END;1256vm-test-run-index_advisor> server # [  123.971535] postgres[1558]: 	$$;1257vm-test-run-index_advisor> server # [  123.978748] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE:  function graphql_public.graphql(text,text,jsonb) does not exist, skipping1258vm-test-run-index_advisor> server # [  123.987805] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: DROP FUNCTION1259vm-test-run-index_advisor> server # [  123.999103] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE FUNCTION1260vm-test-run-index_advisor> server # [  124.013569] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: GRANT1261vm-test-run-index_advisor> server # [  124.014526] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES1262vm-test-run-index_advisor> server # [  124.014916] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES1263vm-test-run-index_advisor> server # [  124.015202] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES1264vm-test-run-index_advisor> server # [  124.015474] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES1265vm-test-run-index_advisor> server # [  124.016501] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES1266vm-test-run-index_advisor> server # [  124.026226] postgres[1558]: ::1 2026-10-23 15:22:06.373 UTC [1558] supabase_admin@postgres LOG:  statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1267vm-test-run-index_advisor> server # [  124.027967] postgres[1558]: ::1 2026-10-23 15:22:06.396 UTC [1558] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1268vm-test-run-index_advisor> server # [  124.046018] postgres[1558]: ::1 2026-10-23 15:22:06.412 UTC [1558] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1269vm-test-run-index_advisor> server # [  124.047328] postgres[1558]: ::1 2026-10-23 15:22:06.425 UTC [1558] supabase_admin@postgres LOG:  statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1270vm-test-run-index_advisor> server # [  124.060930] postgres[1558]: ::1 2026-10-23 15:22:06.438 UTC [1558] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1271vm-test-run-index_advisor> server # [  124.062405] postgres[1558]: 	    on sequences to postgres, anon, authenticated, service_role;1272vm-test-run-index_advisor> server # [  124.062989] postgres[1558]: ::1 2026-10-23 15:22:06.458 UTC [1558] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1273vm-test-run-index_advisor> server # [  124.063412] postgres[1558]: 	    on tables to postgres, anon, authenticated, service_role;1274vm-test-run-index_advisor> server # [  124.064141] postgres[1558]: ::1 2026-10-23 15:22:06.476 UTC [1558] supabase_admin@postgres LOG:  statement: alter default privileges for user supabase_admin in schema graphql_public grant all1275vm-test-run-index_advisor> server # [  124.064550] postgres[1558]: 	    on functions to postgres, anon, authenticated, service_role;1276vm-test-run-index_advisor> server # [  124.074098] postgres[1558]: ::1 2026-10-23 15:22:06.489 UTC [1558] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1277vm-test-run-index_advisor> server # [  124.074762] postgres[1558]: 	RETURNS event_trigger1278vm-test-run-index_advisor> server # [  124.075213] postgres[1558]: 	LANGUAGE plpgsql1279vm-test-run-index_advisor> server # [  124.075507] postgres[1558]: 	AS $func$1280vm-test-run-index_advisor> server # [  124.075775] postgres[1558]: 	    DECLARE1281vm-test-run-index_advisor> server # [  124.076035] postgres[1558]: 	    func_is_graphql_resolve bool;1282vm-test-run-index_advisor> server # [  124.088658] postgres[1558]: 	    BEGIN1283vm-test-run-index_advisor> server # [  124.093898] postgres[1558]: 	    func_is_graphql_resolve = (1284vm-test-run-index_advisor> server # [  124.101349] postgres[1558]: 	        SELECT n.proname = 'resolve'1285vm-test-run-index_advisor> server # [  124.104284] postgres[1558]: 	        FROM pg_event_trigger_ddl_commands() AS ev1286vm-test-run-index_advisor> server # [  124.112948] postgres[1558]: 	        LEFT JOIN pg_catalog.pg_proc AS n1287vm-test-run-index_advisor> server # [  124.119234] postgres[1558]: 	        ON ev.objid = n.oid1288vm-test-run-index_advisor> server # [  124.127092] postgres[1558]: 	    );1289vm-test-run-index_advisor> server # [  124.127624] postgres[1558]:1290vm-test-run-index_advisor> server # [  124.127898] postgres[1558]: 	    IF func_is_graphql_resolve1291vm-test-run-index_advisor> server # [  124.136831] postgres[1558]: 	    THEN1292vm-test-run-index_advisor> server # [  124.139141] postgres[1558]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1293vm-test-run-index_advisor> server # [  124.149210] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: ALTER DEFAULT PRIVILEGES1294vm-test-run-index_advisor> server # [  124.151176] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE FUNCTION1295vm-test-run-index_advisor> server # [  124.171073] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE:  event trigger "issue_pg_graphql_access" does not exist, skipping1296vm-test-run-index_advisor> server # [  124.181980] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: DROP EVENT TRIGGER1297vm-test-run-index_advisor> server # [  124.182730] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE EVENT TRIGGER1298vm-test-run-index_advisor> server # [  124.183387] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: COMMENT1299vm-test-run-index_advisor> server # [  124.183982] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE FUNCTION1300vm-test-run-index_advisor> server # [  124.199379] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE:  event trigger "issue_graphql_placeholder" does not exist, skipping1301vm-test-run-index_advisor> server # [  124.212043] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: DROP EVENT TRIGGER1302vm-test-run-index_advisor> server # [  124.220853] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: CREATE EVENT TRIGGER1303vm-test-run-index_advisor> server # [  124.223695] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1551]: COMMENT1304vm-test-run-index_advisor> server # [  124.239306] postgres[1558]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1305vm-test-run-index_advisor> server # [  124.249403] postgres[1558]:1306vm-test-run-index_advisor> server # [  124.250035] postgres[1558]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1307vm-test-run-index_advisor> server # [  124.250908] postgres[1558]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1308vm-test-run-index_advisor> server # [  124.252006] postgres[1558]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1309vm-test-run-index_advisor> server # [  124.252480] postgres[1558]:1310vm-test-run-index_advisor> server # [  124.263092] postgres[1558]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1311vm-test-run-index_advisor> server # [  124.287373] postgres[1558]: 	        create or replace function graphql_public.graphql(1312vm-test-run-index_advisor> server # [  124.298137] postgres[1558]: 	            "operationName" text default null,1313vm-test-run-index_advisor> server # [  124.299915] postgres[1558]: 	            query text default null,1314vm-test-run-index_advisor> server # [  124.305941] postgres[1558]: 	            variables jsonb default null,1315vm-test-run-index_advisor> server # [  124.308303] postgres[1558]: 	            extensions jsonb default null1316vm-test-run-index_advisor> server # [  124.313305] postgres[1558]: 	        )1317vm-test-run-index_advisor> server # [  124.314418] postgres[1558]: 	            returns jsonb1318vm-test-run-index_advisor> server # [  124.316605] postgres[1558]: 	            language sql1319vm-test-run-index_advisor> server # [  124.321472] postgres[1558]: 	        as $$1320vm-test-run-index_advisor> server # [  124.321852] postgres[1558]: 	            SELECT graphql.resolve(query, coalesce(variables, '{}'));1321vm-test-run-index_advisor> server # [  124.322423] postgres[1558]: 	        $$;1322vm-test-run-index_advisor> server # [  124.322815] postgres[1558]:1323vm-test-run-index_advisor> server # [  124.323061] postgres[1558]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1324vm-test-run-index_advisor> server # [  124.324210] postgres[1558]: 	    END IF;1325vm-test-run-index_advisor> server # [  124.327461] postgres[1558]:1326vm-test-run-index_advisor> server # [  124.329190] postgres[1558]: 	    END;1327vm-test-run-index_advisor> server # [  124.330259] postgres[1558]: 	$func$;1328vm-test-run-index_advisor> server # [  124.332906] postgres[1558]: ::1 2026-10-23 15:22:06.509 UTC [1558] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1329vm-test-run-index_advisor> server # [  124.334181] postgres[1558]: ::1 2026-10-23 15:22:06.518 UTC [1558] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1330vm-test-run-index_advisor> server # [  124.335136] postgres[1558]: 	EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1331vm-test-run-index_advisor> server # [  124.335686] postgres[1558]: ::1 2026-10-23 15:22:06.529 UTC [1558] supabase_admin@postgres LOG:  statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1332vm-test-run-index_advisor> server # [  124.336100] postgres[1558]: ::1 2026-10-23 15:22:06.552 UTC [1558] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1333vm-test-run-index_advisor> server # [  124.336432] postgres[1558]: 	RETURNS event_trigger1334vm-test-run-index_advisor> server # [  124.337303] postgres[1558]: 	LANGUAGE plpgsql1335vm-test-run-index_advisor> server # [  124.337866] postgres[1558]: 	AS $func$1336vm-test-run-index_advisor> server # [  124.338822] postgres[1558]: 	    DECLARE1337vm-test-run-index_advisor> server # [  124.339088] postgres[1558]: 	    graphql_is_dropped bool;1338vm-test-run-index_advisor> server # [  124.342412] postgres[1558]: 	    BEGIN1339vm-test-run-index_advisor> server # [  124.343316] postgres[1558]: 	    graphql_is_dropped = (1340vm-test-run-index_advisor> server # [  124.349288] postgres[1558]: 	        SELECT ev.schema_name = 'graphql_public'1341vm-test-run-index_advisor> server # [  124.349873] postgres[1558]: 	        FROM pg_event_trigger_dropped_objects() AS ev1342vm-test-run-index_advisor> server # [  124.350155] postgres[1558]: 	        WHERE ev.schema_name = 'graphql_public'1343vm-test-run-index_advisor> server # [  124.350600] postgres[1558]: 	    );1344vm-test-run-index_advisor> server # [  124.350834] postgres[1558]:1345vm-test-run-index_advisor> server # [  124.360936] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1346vm-test-run-index_advisor> server # [  124.362308] postgres[1558]: 	    IF graphql_is_dropped1347vm-test-run-index_advisor> server # [  124.363638] postgres[1558]: 	    THEN1348vm-test-run-index_advisor> server # [  124.363956] postgres[1558]: 	        create or replace function graphql_public.graphql(1349vm-test-run-index_advisor> server # [  124.364285] postgres[1558]: 	            "operationName" text default null,1350vm-test-run-index_advisor> server # [  124.364565] postgres[1558]: 	            query text default null,1351vm-test-run-index_advisor> server # [  124.369997] postgres[1558]: 	            variables jsonb default null,1352vm-test-run-index_advisor> server # [  124.370499] postgres[1558]: 	            extensions jsonb default null1353vm-test-run-index_advisor> server # [  124.371526] postgres[1558]: 	        )1354vm-test-run-index_advisor> server # [  124.372072] postgres[1558]: 	            returns jsonb1355vm-test-run-index_advisor> server # [  124.374472] postgres[1558]: 	            language plpgsql1356vm-test-run-index_advisor> server # [  124.382277] postgres[1558]: 	        as $$1357vm-test-run-index_advisor> server # [  124.387693] postgres[1558]: 	            DECLARE1358vm-test-run-index_advisor> server # [  124.389590] postgres[1558]: 	                server_version float;1359vm-test-run-index_advisor> server # [  124.391131] postgres[1558]: 	            BEGIN1360vm-test-run-index_advisor> server # [  124.392308] postgres[1558]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1361vm-test-run-index_advisor> server # [  124.398051] postgres[1558]:1362vm-test-run-index_advisor> server # [  124.398721] postgres[1558]: 	                IF server_version >= 14 THEN1363vm-test-run-index_advisor> server # [  124.399611] postgres[1558]: 	                    RETURN jsonb_build_object(1364vm-test-run-index_advisor> server # [  124.404972] postgres[1558]: 	                        'data', null::jsonb,1365vm-test-run-index_advisor> server # [  124.405855] postgres[1558]: 	                        'errors', array['pg_graphql extension is not enabled.']1366vm-test-run-index_advisor> server # [  124.407018] postgres[1558]: 	                    );1367vm-test-run-index_advisor> server # [  124.407395] postgres[1558]: 	                ELSE1368vm-test-run-index_advisor> server # [  124.413401] postgres[1558]: 	                    RETURN jsonb_build_object(1369vm-test-run-index_advisor> server # [  124.414015] postgres[1558]: 	                        'data', null::jsonb,1370vm-test-run-index_advisor> server # [  124.414558] postgres[1558]: 	                        'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1371vm-test-run-index_advisor> server # [  124.415103] postgres[1558]: 	                    );1372vm-test-run-index_advisor> server # [  124.415491] postgres[1558]: 	                END IF;1373vm-test-run-index_advisor> server # [  124.421247] postgres[1558]: 	            END;1374vm-test-run-index_advisor> server # [  124.422688] postgres[1558]: 	        $$;1375vm-test-run-index_advisor> server # [  124.423261] postgres[1558]: 	    END IF;1376vm-test-run-index_advisor> server # [  124.423637] postgres[1558]:1377vm-test-run-index_advisor> server # [  124.424423] postgres[1558]: 	    END;1378vm-test-run-index_advisor> server # [  124.430124] postgres[1558]: 	$func$;1379vm-test-run-index_advisor> server # [  124.431447] postgres[1558]: ::1 2026-10-23 15:22:06.601 UTC [1558] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1380vm-test-run-index_advisor> server # [  124.432073] postgres[1558]: ::1 2026-10-23 15:22:06.606 UTC [1558] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1381vm-test-run-index_advisor> server # [  124.439523] postgres[1558]: 	EXECUTE PROCEDURE extensions.set_graphql_placeholder();1382vm-test-run-index_advisor> server # [  124.441846] postgres[1558]: ::1 2026-10-23 15:22:06.619 UTC [1558] supabase_admin@postgres LOG:  statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1383vm-test-run-index_advisor> server # [  124.709505] postgres[1568]: ::1 2026-10-23 15:22:07.172 UTC [1568] supabase_admin@postgres LOG:  statement: drop event trigger if exists api_restart;1384vm-test-run-index_advisor> server # [  124.711256] postgres[1568]: ::1 2026-10-23 15:22:07.187 UTC [1568] supabase_admin@postgres LOG:  statement: drop function if exists extensions.notify_api_restart();1385vm-test-run-index_advisor> server # [  124.723580] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE:  event trigger "api_restart" does not exist, skipping1386vm-test-run-index_advisor> server # [  124.729263] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP EVENT TRIGGER1387vm-test-run-index_advisor> server # [  124.729769] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE:  function extensions.notify_api_restart() does not exist, skipping1388vm-test-run-index_advisor> server # [  124.731700] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP FUNCTION1389vm-test-run-index_advisor> server # [  124.732460] postgres[1568]: ::1 2026-10-23 15:22:07.222 UTC [1568] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1390vm-test-run-index_advisor> server # [  124.737945] postgres[1568]: 	DECLARE1391vm-test-run-index_advisor> server # [  124.738644] postgres[1568]: 	  cmd record;1392vm-test-run-index_advisor> server # [  124.739092] postgres[1568]: 	BEGIN1393vm-test-run-index_advisor> server # [  124.739369] postgres[1568]: 	  FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1394vm-test-run-index_advisor> server # [  124.740274] postgres[1568]: 	  LOOP1395vm-test-run-index_advisor> server # [  124.746110] postgres[1568]: 	    IF cmd.command_tag IN (1396vm-test-run-index_advisor> server # [  124.746954] postgres[1568]: 	      'CREATE SCHEMA', 'ALTER SCHEMA'1397vm-test-run-index_advisor> server # [  124.747322] postgres[1568]: 	    , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1398vm-test-run-index_advisor> server # [  124.748101] postgres[1568]: 	    , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1399vm-test-run-index_advisor> server # [  124.748688] postgres[1568]: 	    , 'CREATE VIEW', 'ALTER VIEW'1400vm-test-run-index_advisor> server # [  124.753527] postgres[1568]: 	    , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1401vm-test-run-index_advisor> server # [  124.755197] postgres[1568]: 	    , 'CREATE FUNCTION', 'ALTER FUNCTION'1402vm-test-run-index_advisor> server # [  124.755599] postgres[1568]: 	    , 'CREATE TRIGGER'1403vm-test-run-index_advisor> server # [  124.760957] postgres[1568]: 	    , 'CREATE TYPE', 'ALTER TYPE'1404vm-test-run-index_advisor> server # [  124.762150] postgres[1568]: 	    , 'CREATE RULE'1405vm-test-run-index_advisor> server # [  124.763935] postgres[1568]: 	    , 'COMMENT'1406vm-test-run-index_advisor> server # [  124.769717] postgres[1568]: 	    )1407vm-test-run-index_advisor> server # [  124.770178] postgres[1568]: 	    -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1408vm-test-run-index_advisor> server # [  124.770515] postgres[1568]: 	    AND cmd.schema_name is distinct from 'pg_temp'1409vm-test-run-index_advisor> server # [  124.770766] postgres[1568]: 	    THEN1410vm-test-run-index_advisor> server # [  124.771653] postgres[1568]: 	      NOTIFY pgrst, 'reload schema';1411vm-test-run-index_advisor> server # [  124.773045] postgres[1568]: 	    END IF;1412vm-test-run-index_advisor> server # [  124.773410] postgres[1568]: 	  END LOOP;1413vm-test-run-index_advisor> server # [  124.773675] postgres[1568]: 	END; $$ LANGUAGE plpgsql;1414vm-test-run-index_advisor> server # [  124.811582] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE FUNCTION1415vm-test-run-index_advisor> server # [  124.821226] postgres[1568]: ::1 2026-10-23 15:22:07.311 UTC [1568] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1416vm-test-run-index_advisor> server # [  124.822197] postgres[1568]: 	DECLARE1417vm-test-run-index_advisor> server # [  124.822917] postgres[1568]: 	  obj record;1418vm-test-run-index_advisor> server # [  124.823394] postgres[1568]: 	BEGIN1419vm-test-run-index_advisor> server # [  124.825207] postgres[1568]: 	  FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1420vm-test-run-index_advisor> server # [  124.825950] postgres[1568]: 	  LOOP1421vm-test-run-index_advisor> server # [  124.826366] postgres[1568]: 	    IF obj.object_type IN (1422vm-test-run-index_advisor> server # [  124.827725] postgres[1568]: 	      'schema'1423vm-test-run-index_advisor> server # [  124.833076] postgres[1568]: 	    , 'table'1424vm-test-run-index_advisor> server # [  124.833660] postgres[1568]: 	    , 'foreign table'1425vm-test-run-index_advisor> server # [  124.833966] postgres[1568]: 	    , 'view'1426vm-test-run-index_advisor> server # [  124.834208] postgres[1568]: 	    , 'materialized view'1427vm-test-run-index_advisor> server # [  124.834450] postgres[1568]: 	    , 'function'1428vm-test-run-index_advisor> server # [  124.835089] postgres[1568]: 	    , 'trigger'1429vm-test-run-index_advisor> server # [  124.835365] postgres[1568]: 	    , 'type'1430vm-test-run-index_advisor> server # [  124.835614] postgres[1568]: 	    , 'rule'1431vm-test-run-index_advisor> server # [  124.835832] postgres[1568]: 	    )1432vm-test-run-index_advisor> server # [  124.836054] postgres[1568]: 	    AND obj.is_temporary IS false -- no pg_temp objects1433vm-test-run-index_advisor> server # [  124.842106] postgres[1568]: 	    THEN1434vm-test-run-index_advisor> server # [  124.847170] postgres[1568]: 	      NOTIFY pgrst, 'reload schema';1435vm-test-run-index_advisor> server # [  124.848274] postgres[1568]: 	    END IF;1436vm-test-run-index_advisor> server # [  124.853643] postgres[1568]: 	  END LOOP;1437vm-test-run-index_advisor> server # [  124.854533] postgres[1568]: 	END; $$ LANGUAGE plpgsql;1438vm-test-run-index_advisor> server # [  124.855297] postgres[1568]: ::1 2026-10-23 15:22:07.347 UTC [1568] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1439vm-test-run-index_advisor> server # [  124.861777] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE FUNCTION1440vm-test-run-index_advisor> server # [  124.869103] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP EVENT TRIGGER1441vm-test-run-index_advisor> server # [  124.871440] postgres[1568]: ::1 2026-10-23 15:22:07.365 UTC [1568] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_ddl_watch1442vm-test-run-index_advisor> server # [  124.872138] postgres[1568]: 	  ON ddl_command_end1443vm-test-run-index_advisor> server # [  124.876123] postgres[1568]: 	  EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1444vm-test-run-index_advisor> server # [  124.879130] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE EVENT TRIGGER1445vm-test-run-index_advisor> server # [  124.879826] postgres[1568]: ::1 2026-10-23 15:22:07.374 UTC [1568] supabase_admin@postgres LOG:  statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1446vm-test-run-index_advisor> server # [  124.893173] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: DROP EVENT TRIGGER1447vm-test-run-index_advisor> server # [  124.899024] postgres[1568]: ::1 2026-10-23 15:22:07.388 UTC [1568] supabase_admin@postgres LOG:  statement: CREATE EVENT TRIGGER pgrst_drop_watch1448vm-test-run-index_advisor> server # [  124.901076] postgres[1568]: 	  ON sql_drop1449vm-test-run-index_advisor> server # [  124.901949] postgres[1568]: 	  EXECUTE PROCEDURE extensions.pgrst_drop_watch();1450vm-test-run-index_advisor> server # [  124.915640] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1563]: CREATE EVENT TRIGGER1451vm-test-run-index_advisor> server # [  124.958874] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1452vm-test-run-index_advisor> server # [  125.227326] postgres[1572]: ::1 2026-10-23 15:22:07.717 UTC [1572] supabase_admin@postgres LOG:  statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1453vm-test-run-index_advisor> server # [  125.250856] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1571]: ALTER ROLE1454vm-test-run-index_advisor> server # [  125.284995] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1455vm-test-run-index_advisor> server # [  125.527368] postgres[1574]: ::1 2026-10-23 15:22:08.016 UTC [1574] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1456vm-test-run-index_advisor> server # [  125.533532] postgres[1574]: 	    returns event_trigger1457vm-test-run-index_advisor> server # [  125.534097] postgres[1574]: 	    language plpgsql1458vm-test-run-index_advisor> server # [  125.534412] postgres[1574]: 	AS $func$1459vm-test-run-index_advisor> server # [  125.534641] postgres[1574]: 	DECLARE1460vm-test-run-index_advisor> server # [  125.534857] postgres[1574]: 	    func_is_graphql_resolve bool;1461vm-test-run-index_advisor> server # [  125.535087] postgres[1574]: 	BEGIN1462vm-test-run-index_advisor> server # [  125.535517] postgres[1574]: 	    func_is_graphql_resolve = (1463vm-test-run-index_advisor> server # [  125.535830] postgres[1574]: 	        SELECT n.proname = 'resolve'1464vm-test-run-index_advisor> server # [  125.538463] postgres[1574]: 	        FROM pg_event_trigger_ddl_commands() AS ev1465vm-test-run-index_advisor> server # [  125.541657] postgres[1574]: 	        LEFT JOIN pg_catalog.pg_proc AS n1466vm-test-run-index_advisor> server # [  125.542133] postgres[1574]: 	        ON ev.objid = n.oid1467vm-test-run-index_advisor> server # [  125.543179] postgres[1574]: 	    );1468vm-test-run-index_advisor> server # [  125.543627] postgres[1574]:1469vm-test-run-index_advisor> server # [  125.544280] postgres[1574]: 	    IF func_is_graphql_resolve1470vm-test-run-index_advisor> server # [  125.546197] postgres[1574]: 	    THEN1471vm-test-run-index_advisor> server # [  125.546972] postgres[1574]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1472vm-test-run-index_advisor> server # [  125.547523] postgres[1574]: 	        grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1473vm-test-run-index_advisor> server # [  125.548140] postgres[1574]:1474vm-test-run-index_advisor> server # [  125.549713] postgres[1574]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1475vm-test-run-index_advisor> server # [  125.550177] postgres[1574]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1476vm-test-run-index_advisor> server # [  125.550531] postgres[1574]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1477vm-test-run-index_advisor> server # [  125.551549] postgres[1574]:1478vm-test-run-index_advisor> server # [  125.552221] postgres[1574]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1479vm-test-run-index_advisor> server # [  125.557514] postgres[1574]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1480vm-test-run-index_advisor> server # [  125.558328] postgres[1574]: 	        create or replace function graphql_public.graphql(1481vm-test-run-index_advisor> server # [  125.559100] postgres[1574]: 	            "operationName" text default null,1482vm-test-run-index_advisor> server # [  125.560018] postgres[1574]: 	            query text default null,1483vm-test-run-index_advisor> server # [  125.561720] postgres[1574]: 	            variables jsonb default null,1484vm-test-run-index_advisor> server # [  125.562485] postgres[1574]: 	            extensions jsonb default null1485vm-test-run-index_advisor> server # [  125.562786] postgres[1574]: 	        )1486vm-test-run-index_advisor> server # [  125.563917] postgres[1574]: 	            returns jsonb1487vm-test-run-index_advisor> server # [  125.569190] postgres[1574]: 	            language sql1488vm-test-run-index_advisor> server # [  125.570454] postgres[1574]: 	        as $$1489vm-test-run-index_advisor> server # [  125.573400] postgres[1574]: 	            -- This changed1490vm-test-run-index_advisor> server # [  125.573933] postgres[1574]: 	            select graphql.resolve(1491vm-test-run-index_advisor> server # [  125.574856] postgres[1574]: 	                query := query,1492vm-test-run-index_advisor> server # [  125.575673] postgres[1574]: 	                variables := coalesce(variables, '{}'),1493vm-test-run-index_advisor> server # [  125.576216] postgres[1574]: 	                "operationName" := "operationName",1494vm-test-run-index_advisor> server # [  125.577632] postgres[1574]: 	                extensions := extensions1495vm-test-run-index_advisor> server # [  125.578033] postgres[1574]: 	            );1496vm-test-run-index_advisor> server # [  125.578548] postgres[1574]: 	        $$;1497vm-test-run-index_advisor> server # [  125.578835] postgres[1574]:1498vm-test-run-index_advisor> server # [  125.579240] postgres[1574]: 	        grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1499vm-test-run-index_advisor> server # [  125.580957] postgres[1574]: 	    END IF;1500vm-test-run-index_advisor> server # [  125.581446] postgres[1574]:1501vm-test-run-index_advisor> server # [  125.581728] postgres[1574]: 	END;1502vm-test-run-index_advisor> server # [  125.582372] postgres[1574]: 	$func$;1503vm-test-run-index_advisor> server # [  125.604261] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: CREATE FUNCTION1504vm-test-run-index_advisor> server # [  125.610186] postgres[1574]: ::1 2026-10-23 15:22:08.101 UTC [1574] supabase_admin@postgres LOG:  statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1505vm-test-run-index_advisor> server # [  125.612042] postgres[1574]: 	RETURNS event_trigger1506vm-test-run-index_advisor> server # [  125.615374] postgres[1574]: 	LANGUAGE plpgsql1507vm-test-run-index_advisor> server # [  125.615923] postgres[1574]: 	AS $func$1508vm-test-run-index_advisor> server # [  125.617537] postgres[1574]: 	    DECLARE1509vm-test-run-index_advisor> server # [  125.617962] postgres[1574]: 	    graphql_is_dropped bool;1510vm-test-run-index_advisor> server # [  125.618741] postgres[1574]: 	    BEGIN1511vm-test-run-index_advisor> server # [  125.619032] postgres[1574]: 	    graphql_is_dropped = (1512vm-test-run-index_advisor> server # [  125.619322] postgres[1574]: 	        SELECT ev.schema_name = 'graphql_public'1513vm-test-run-index_advisor> server # [  125.620073] postgres[1574]: 	        FROM pg_event_trigger_dropped_objects() AS ev1514vm-test-run-index_advisor> server # [  125.622742] postgres[1574]: 	        WHERE ev.schema_name = 'graphql_public'1515vm-test-run-index_advisor> server # [  125.623375] postgres[1574]: 	    );1516vm-test-run-index_advisor> server # [  125.623682] postgres[1574]:1517vm-test-run-index_advisor> server # [  125.624069] postgres[1574]: 	    IF graphql_is_dropped1518vm-test-run-index_advisor> server # [  125.624372] postgres[1574]: 	    THEN1519vm-test-run-index_advisor> server # [  125.637265] postgres[1574]: 	        create or replace function graphql_public.graphql(1520vm-test-run-index_advisor> server # [  125.637932] postgres[1574]: 	            "operationName" text default null,1521vm-test-run-index_advisor> server # [  125.638680] postgres[1574]: 	            query text default null,1522vm-test-run-index_advisor> server # [  125.639443] postgres[1574]: 	            variables jsonb default null,1523vm-test-run-index_advisor> server # [  125.646231] postgres[1574]: 	            extensions jsonb default null1524vm-test-run-index_advisor> server # [  125.647127] postgres[1574]: 	        )1525vm-test-run-index_advisor> server # [  125.647445] postgres[1574]: 	            returns jsonb1526vm-test-run-index_advisor> server # [  125.648313] postgres[1574]: 	            language plpgsql1527vm-test-run-index_advisor> server # [  125.657767] postgres[1574]: 	        as $$1528vm-test-run-index_advisor> server # [  125.659141] postgres[1574]: 	            DECLARE1529vm-test-run-index_advisor> server # [  125.659737] postgres[1574]: 	                server_version float;1530vm-test-run-index_advisor> server # [  125.660028] postgres[1574]: 	            BEGIN1531vm-test-run-index_advisor> server # [  125.660257] postgres[1574]: 	                server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1532vm-test-run-index_advisor> server # [  125.669465] postgres[1574]:1533vm-test-run-index_advisor> server # [  125.669941] postgres[1574]: 	                IF server_version >= 14 THEN1534vm-test-run-index_advisor> server # [  125.670247] postgres[1574]: 	                    RETURN jsonb_build_object(1535vm-test-run-index_advisor> server # [  125.670654] postgres[1574]: 	                        'errors', jsonb_build_array(1536vm-test-run-index_advisor> server # [  125.671482] postgres[1574]: 	                            jsonb_build_object(1537vm-test-run-index_advisor> server # [  125.683768] postgres[1574]: 	                                'message', 'pg_graphql extension is not enabled.'1538vm-test-run-index_advisor> server # [  125.693589] postgres[1574]: 	                            )1539vm-test-run-index_advisor> server # [  125.694205] postgres[1574]: 	                        )1540vm-test-run-index_advisor> server # [  125.694525] postgres[1574]: 	                    );1541vm-test-run-index_advisor> server # [  125.695685] postgres[1574]: 	                ELSE1542vm-test-run-index_advisor> server # [  125.696073] postgres[1574]: 	                    RETURN jsonb_build_object(1543vm-test-run-index_advisor> server # [  125.698867] postgres[1574]: 	                        'errors', jsonb_build_array(1544vm-test-run-index_advisor> server # [  125.700249] postgres[1574]: 	                            jsonb_build_object(1545vm-test-run-index_advisor> server # [  125.705135] postgres[1574]: 	                                'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1546vm-test-run-index_advisor> server # [  125.706371] postgres[1574]: 	                            )1547vm-test-run-index_advisor> server # [  125.706891] postgres[1574]: 	                        )1548vm-test-run-index_advisor> server # [  125.707350] postgres[1574]: 	                    );1549vm-test-run-index_advisor> server # [  125.711151] postgres[1574]: 	                END IF;1550vm-test-run-index_advisor> server # [  125.721479] postgres[1574]: 	            END;1551vm-test-run-index_advisor> server # [  125.722478] postgres[1574]: 	        $$;1552vm-test-run-index_advisor> server # [  125.723088] postgres[1574]: 	    END IF;1553vm-test-run-index_advisor> server # [  125.723398] postgres[1574]:1554vm-test-run-index_advisor> server # [  125.733898] postgres[1574]: 	    END;1555vm-test-run-index_advisor> server # [  125.745560] postgres[1574]: 	$func$;1556vm-test-run-index_advisor> server # [  125.746508] postgres[1574]: ::1 2026-10-23 15:22:08.144 UTC [1574] supabase_admin@postgres LOG:  statement: create or replace function graphql_public.graphql(1557vm-test-run-index_advisor> server # [  125.748704] postgres[1574]: 	    "operationName" text default null,1558vm-test-run-index_advisor> server # [  125.754141] postgres[1574]: 	    query text default null,1559vm-test-run-index_advisor> server # [  125.754590] postgres[1574]: 	    variables jsonb default null,1560vm-test-run-index_advisor> server # [  125.756078] postgres[1574]: 	    extensions jsonb default null1561vm-test-run-index_advisor> server # [  125.770263] postgres[1574]: 	)1562vm-test-run-index_advisor> server # [  125.770845] postgres[1574]: 	    returns jsonb1563vm-test-run-index_advisor> server # [  125.771595] postgres[1574]: 	    language plpgsql1564vm-test-run-index_advisor> server # [  125.772160] postgres[1574]: 	as $$1565vm-test-run-index_advisor> server # [  125.773155] postgres[1574]: 	    DECLARE1566vm-test-run-index_advisor> server # [  125.790595] postgres[1574]: 	        server_version float;1567vm-test-run-index_advisor> server # [  125.793531] postgres[1574]: 	    BEGIN1568vm-test-run-index_advisor> server # [  125.795197] postgres[1574]: 	        server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1569vm-test-run-index_advisor> server # [  125.795916] postgres[1574]:1570vm-test-run-index_advisor> server # [  125.796441] postgres[1574]: 	        IF server_version >= 14 THEN1571vm-test-run-index_advisor> server # [  125.807296] postgres[1574]: 	            RETURN jsonb_build_object(1572vm-test-run-index_advisor> server # [  125.816652] postgres[1574]: 	                'errors', jsonb_build_array(1573vm-test-run-index_advisor> server # [  125.818612] postgres[1574]: 	                    jsonb_build_object(1574vm-test-run-index_advisor> server # [  125.819161] postgres[1574]: 	                        'message', 'pg_graphql extension is not enabled.'1575vm-test-run-index_advisor> server # [  125.823402] postgres[1574]: 	                    )1576vm-test-run-index_advisor> server # [  125.835997] postgres[1574]: 	                )1577vm-test-run-index_advisor> server # [  125.842614] postgres[1574]: 	            );1578vm-test-run-index_advisor> server # [  125.843038] postgres[1574]: 	        ELSE1579vm-test-run-index_advisor> server # [  125.843680] postgres[1574]: 	            RETURN jsonb_build_object(1580vm-test-run-index_advisor> server # [  125.844555] postgres[1574]: 	                'errors', jsonb_build_array(1581vm-test-run-index_advisor> server # [  125.847988] postgres[1574]: 	                    jsonb_build_object(1582vm-test-run-index_advisor> server # [  125.850084] postgres[1574]: 	                        'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1583vm-test-run-index_advisor> server # [  125.851382] postgres[1574]: 	                    )1584vm-test-run-index_advisor> server # [  125.852977] postgres[1574]: 	                )1585vm-test-run-index_advisor> server # [  125.853520] postgres[1574]: 	            );1586vm-test-run-index_advisor> server # [  125.853810] postgres[1574]: 	        END IF;1587vm-test-run-index_advisor> server # [  125.854401] postgres[1574]: 	    END;1588vm-test-run-index_advisor> server # [  125.856812] postgres[1574]: 	$$;1589vm-test-run-index_advisor> server # [  125.857335] postgres[1574]: ::1 2026-10-23 15:22:08.190 UTC [1574] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1590vm-test-run-index_advisor> server # [  125.859703] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: CREATE FUNCTION1591vm-test-run-index_advisor> server # [  125.860986] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: CREATE FUNCTION1592vm-test-run-index_advisor> server # [  125.862158] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: psql:/nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE:  extension "pg_graphql" does not exist, skipping1593vm-test-run-index_advisor> server # [  125.864627] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: DROP EXTENSION1594vm-test-run-index_advisor> server # [  127.959671] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1573]: DO1595vm-test-run-index_advisor> server # [  128.015899] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1596vm-test-run-index_advisor> server # [  128.380181] postgres[1604]: ::1 2026-10-23 15:22:10.861 UTC [1604] supabase_admin@postgres LOG:  statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1597vm-test-run-index_advisor> server # [  128.389926] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1603]: GRANT ROLE1598vm-test-run-index_advisor> server # [  128.431326] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1599vm-test-run-index_advisor> server # [  128.757339] postgres[1606]: ::1 2026-10-23 15:22:11.241 UTC [1606] supabase_admin@postgres LOG:  statement: create or replace function extensions.grant_pg_graphql_access()1600vm-test-run-index_advisor> server # [  128.758840] postgres[1606]: 	    returns event_trigger1601vm-test-run-index_advisor> server # [  128.759625] postgres[1606]: 	    language plpgsql1602vm-test-run-index_advisor> server # [  128.760145] postgres[1606]: 	AS $func$1603vm-test-run-index_advisor> server # [  128.765376] postgres[1606]: 	DECLARE1604vm-test-run-index_advisor> server # [  128.765884] postgres[1606]: 	    func_is_graphql_resolve bool;1605vm-test-run-index_advisor> server # [  128.766837] postgres[1606]: 	BEGIN1606vm-test-run-index_advisor> server # [  128.767481] postgres[1606]: 	    func_is_graphql_resolve = (1607vm-test-run-index_advisor> server # [  128.768204] postgres[1606]: 	        SELECT n.proname = 'resolve'1608vm-test-run-index_advisor> server # [  128.775837] postgres[1606]: 	        FROM pg_event_trigger_ddl_commands() AS ev1609vm-test-run-index_advisor> server # [  128.781548] postgres[1606]: 	        LEFT JOIN pg_catalog.pg_proc AS n1610vm-test-run-index_advisor> server # [  128.782868] postgres[1606]: 	        ON ev.objid = n.oid1611vm-test-run-index_advisor> server # [  128.791497] postgres[1606]: 	    );1612vm-test-run-index_advisor> server # [  128.793489] postgres[1606]:1613vm-test-run-index_advisor> server # [  128.794873] postgres[1606]: 	    IF func_is_graphql_resolve1614vm-test-run-index_advisor> server # [  128.795549] postgres[1606]: 	    THEN1615vm-test-run-index_advisor> server # [  128.796898] postgres[1606]: 	        -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1616vm-test-run-index_advisor> server # [  128.797647] postgres[1606]: 	        DROP FUNCTION IF EXISTS graphql_public.graphql;1617vm-test-run-index_advisor> server # [  128.798889] postgres[1606]: 	        create or replace function graphql_public.graphql(1618vm-test-run-index_advisor> server # [  128.800065] postgres[1606]: 	            "operationName" text default null,1619vm-test-run-index_advisor> server # [  128.802091] postgres[1606]: 	            query text default null,1620vm-test-run-index_advisor> server # [  128.802932] postgres[1606]: 	            variables jsonb default null,1621vm-test-run-index_advisor> server # [  128.803867] postgres[1606]: 	            extensions jsonb default null1622vm-test-run-index_advisor> server # [  128.804422] postgres[1606]: 	        )1623vm-test-run-index_advisor> server # [  128.807677] postgres[1606]: 	            returns jsonb1624vm-test-run-index_advisor> server # [  128.809486] postgres[1606]: 	            language sql1625vm-test-run-index_advisor> server # [  128.811391] postgres[1606]: 	        as $$1626vm-test-run-index_advisor> server # [  128.811754] postgres[1606]: 	            select graphql.resolve(1627vm-test-run-index_advisor> server # [  128.814007] postgres[1606]: 	                query := query,1628vm-test-run-index_advisor> server # [  128.814455] postgres[1606]: 	                variables := coalesce(variables, '{}'),1629vm-test-run-index_advisor> server # [  128.816266] postgres[1606]: 	                "operationName" := "operationName",1630vm-test-run-index_advisor> server # [  128.819077] postgres[1606]: 	                extensions := extensions1631vm-test-run-index_advisor> server # [  128.819667] postgres[1606]: 	            );1632vm-test-run-index_advisor> server # [  128.820512] postgres[1606]: 	        $$;1633vm-test-run-index_advisor> server # [  128.821298] postgres[1606]:1634vm-test-run-index_advisor> server # [  128.822343] postgres[1606]: 	        -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1635vm-test-run-index_advisor> server # [  128.823300] postgres[1606]: 	        -- function in the extension so we need to grant permissions on existing entities AND1636vm-test-run-index_advisor> server # [  128.824630] postgres[1606]: 	        -- update default permissions to any others that are created after `graphql.resolve`1637vm-test-run-index_advisor> server # [  128.825960] postgres[1606]: 	        grant usage on schema graphql to postgres, anon, authenticated, service_role;1638vm-test-run-index_advisor> server # [  128.826887] postgres[1606]: 	        grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1639vm-test-run-index_advisor> server # [  128.827273] postgres[1606]: 	        grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1640vm-test-run-index_advisor> server # [  128.828141] postgres[1606]: 	        grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1641vm-test-run-index_advisor> server # [  128.828904] postgres[1606]: 	        alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1642vm-test-run-index_advisor> server # [  128.830410] postgres[1606]: 	        alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1643vm-test-run-index_advisor> server # [  128.831507] postgres[1606]: 	        alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1644vm-test-run-index_advisor> server # [  128.832024] postgres[1606]: 	    END IF;1645vm-test-run-index_advisor> server # [  128.833233] postgres[1606]:1646vm-test-run-index_advisor> server # [  128.833829] postgres[1606]: 	END;1647vm-test-run-index_advisor> server # [  128.834342] postgres[1606]: 	$func$;1648vm-test-run-index_advisor> server # [  128.866997] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1605]: CREATE FUNCTION1649vm-test-run-index_advisor> server # [  128.870294] postgres[1606]: ::1 2026-10-23 15:22:11.359 UTC [1606] supabase_admin@postgres LOG:  statement: drop extension if exists pg_graphql;1650vm-test-run-index_advisor> server # [  128.910444] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1605]: DROP EXTENSION1651vm-test-run-index_advisor> server # [  130.816410] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1605]: DO1652vm-test-run-index_advisor> server # [  130.867830] 0vcx6sgxx3y2ww1d8r7b4326xslwi829-supabase-db-init[1369]: Running migration: /nix/store/siw3h7b96xlh4mr6icndv0gmh0a99w1x-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql