1these 19 derivations will be built:2 /nix/store/c2fy76sb4l1a9bircmk53qy752ymmh9h-postgresql-migrate.drv3 /nix/store/q2cyar3rvwil73pryzpxyd3ni38795rl-unit-postgresql-migrate.service.drv4 /nix/store/xbhbrbm7jn3fsknqsm7v2gdgvy4v9bvh-unit-postgresql.service.drv5 /nix/store/ifghd86p4zmd9373zwpyr5vn9dxiirjk-system-units.drv6 /nix/store/mrrr9xz4b4hd28yjc7qdjx6x7f37zy2a-etc.drv7 /nix/store/kn21khmc00g1xh07msy7svv2jm7zkzhk-activate.drv8 /nix/store/vzz9c6myxag79wjl0lv40d2369gzkvrb-nixos-system-server-test.drv9 /nix/store/v7355r3wlapz2cqnq2y0rp67b09883yy-postgresql-orioledb-init.drv10 /nix/store/k74xnf9pjsknj1nhi1icfbn6a1924wkg-unit-postgresql.service.drv11 /nix/store/kacqda995i4iyn5h06wvi51fbgdajdnx-system-units.drv12 /nix/store/pymwaf1wg64nna92jdd21wcdaknpyxqf-etc.drv13 /nix/store/vlc1xh4g0qvm2s8svqnlil5pmhm5sy69-activate.drv14 /nix/store/xrg85rzkkgahwvw7pqda40wq458jk5sa-nixos-system-server-test.drv15 /nix/store/lppj8qr2wflzclj8m0lqr870nn64c27z-nixos-system-server-test.drv16 /nix/store/rnpw7k68mn82a1hhfb6rh8mzicn1fydl-closure-info.drv17 /nix/store/ngavxv8s29rqf926pkg1q754lgzdjxp2-run-nixos-vm.drv18 /nix/store/dv7npnf9vd58iz3zb1dwics1ksap7bk9-nixos-vm.drv19 /nix/store/lz9qchw7ylzbc7qbcxbc39iac0m8bcdc-nixos-test-driver-index_advisor.drv20 /nix/store/7wc3pfmpzi1q1wxb0vdrn69fbaixi61j-vm-test-run-index_advisor.drv21these 2 paths will be fetched (29.8 KiB download, 122.1 KiB unpacked):22 /nix/store/3kiik8dkh8ish7bwxc1zs3lmwcbdl2vb-processed-postgresql-config-1723 /nix/store/3qadyyycv5n7nmai0rq9kb7grqxad4qz-processed-postgresql-config-orioledb-1724building '/nix/store/c2fy76sb4l1a9bircmk53qy752ymmh9h-postgresql-migrate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'25building '/nix/store/c2fy76sb4l1a9bircmk53qy752ymmh9h-postgresql-migrate.drv'26building '/nix/store/q2cyar3rvwil73pryzpxyd3ni38795rl-unit-postgresql-migrate.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'27building '/nix/store/q2cyar3rvwil73pryzpxyd3ni38795rl-unit-postgresql-migrate.service.drv'28building '/nix/store/ifghd86p4zmd9373zwpyr5vn9dxiirjk-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'29building '/nix/store/ifghd86p4zmd9373zwpyr5vn9dxiirjk-system-units.drv'30building '/nix/store/mrrr9xz4b4hd28yjc7qdjx6x7f37zy2a-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'31building '/nix/store/mrrr9xz4b4hd28yjc7qdjx6x7f37zy2a-etc.drv'32building '/nix/store/kn21khmc00g1xh07msy7svv2jm7zkzhk-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'33building '/nix/store/vzz9c6myxag79wjl0lv40d2369gzkvrb-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'34building '/nix/store/vzz9c6myxag79wjl0lv40d2369gzkvrb-nixos-system-server-test.drv'35building '/nix/store/lz9qchw7ylzbc7qbcxbc39iac0m8bcdc-nixos-test-driver-index_advisor.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'36building '/nix/store/lz9qchw7ylzbc7qbcxbc39iac0m8bcdc-nixos-test-driver-index_advisor.drv'37nixos-test-driver-index_advisor> Running type check (enable/disable: config.skipTypeCheck)38nixos-test-driver-index_advisor> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck39nixos-test-driver-index_advisor> Success: no issues found in 1 source file40nixos-test-driver-index_advisor> additionally exposed symbols:41nixos-test-driver-index_advisor> ,42nixos-test-driver-index_advisor> ,43nixos-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, debug44nixos-test-driver-index_advisor> Linting test script (enable/disable: config.skipLint)45nixos-test-driver-index_advisor> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint46building '/nix/store/7wc3pfmpzi1q1wxb0vdrn69fbaixi61j-vm-test-run-index_advisor.drv' on 'ssh-ng://builder@mac-linux-builder'47building '/nix/store/7wc3pfmpzi1q1wxb0vdrn69fbaixi61j-vm-test-run-index_advisor.drv'48vm-test-run-index_advisor> Machine state will be reset. To keep it, pass --keep-vm-state49vm-test-run-index_advisor> start all VLans50vm-test-run-index_advisor> start vlan51vm-test-run-index_advisor> running vlan (pid 7; ctl /build/vde1.ctl)52vm-test-run-index_advisor> (finished: start all VLans, in 0.00 seconds)53vm-test-run-index_advisor> Test will time out and terminate in 3600 seconds54vm-test-run-index_advisor> run the VM test script55vm-test-run-index_advisor> additionally exposed symbols:56vm-test-run-index_advisor> server,57vm-test-run-index_advisor> vlan1,58vm-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, debug59vm-test-run-index_advisor> start all VMs60vm-test-run-index_advisor> server: starting vm61vm-test-run-index_advisor> mke2fs 1.47.3 (8-Jul-2025)62vm-test-run-index_advisor> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory63vm-test-run-index_advisor> qemu-system-aarch64: failed to initialize kvm: No such file or directory64vm-test-run-index_advisor> qemu-system-aarch64: falling back to tcg65vm-test-run-index_advisor> server # Disk image does not exist, creating the virtualisation disk image...66vm-test-run-index_advisor> server # Formatting '/build/vm-state-server/tmp.wzcyUAIlQB', fmt=raw size=107374182467vm-test-run-index_advisor> server # Discarding device blocks: 0/262144 done68vm-test-run-index_advisor> server # Creating filesystem with 262144 4k blocks and 65536 inodes69vm-test-run-index_advisor> server # Filesystem UUID: 824ef44d-3250-4846-9fb4-c5c0c400af7470vm-test-run-index_advisor> server # Superblock backups stored on blocks:71vm-test-run-index_advisor> server # 32768, 98304, 163840, 22937672vm-test-run-index_advisor> server # 73vm-test-run-index_advisor> server # Allocating group tables: 0/8 done74vm-test-run-index_advisor> server # Writing inode tables: 0/8 done75vm-test-run-index_advisor> server # Creating journal (8192 blocks): done76vm-test-run-index_advisor> server # Writing superblocks and filesystem accounting information: 0/8 done77vm-test-run-index_advisor> server # 78vm-test-run-index_advisor> server # Virtualisation disk image created.79vm-test-run-index_advisor> server: QEMU running (pid 9)80vm-test-run-index_advisor> (finished: start all VMs, in 0.20 seconds)81vm-test-run-index_advisor> server: waiting for unit supabase-db-init.service82vm-test-run-index_advisor> server: waiting for the VM to finish booting83vm-test-run-index_advisor> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]84vm-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 202585vm-test-run-index_advisor> server # [ 0.000000] KASLR enabled86vm-test-run-index_advisor> server # [ 0.000000] random: crng init done87vm-test-run-index_advisor> server # [ 0.000000] Machine model: linux,dummy-virt88vm-test-run-index_advisor> server # [ 0.000000] efi: UEFI not found.89vm-test-run-index_advisor> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT90vm-test-run-index_advisor> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]91vm-test-run-index_advisor> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]92vm-test-run-index_advisor> server # [ 0.000000] Zone ranges:93vm-test-run-index_advisor> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff]94vm-test-run-index_advisor> server # [ 0.000000] DMA32 empty95vm-test-run-index_advisor> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff]96vm-test-run-index_advisor> server # [ 0.000000] Device empty97vm-test-run-index_advisor> server # [ 0.000000] Movable zone start for each node98vm-test-run-index_advisor> server # [ 0.000000] Early memory node ranges99vm-test-run-index_advisor> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff]100vm-test-run-index_advisor> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]101vm-test-run-index_advisor> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1102vm-test-run-index_advisor> server # [ 0.000000] psci: probing for conduit method from DT.103vm-test-run-index_advisor> server # [ 0.000000] psci: PSCIv1.1 detected in firmware.104vm-test-run-index_advisor> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs105vm-test-run-index_advisor> server # [ 0.000000] psci: Trusted OS migration not required106vm-test-run-index_advisor> server # [ 0.000000] psci: SMC Calling Convention v1.0107vm-test-run-index_advisor> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448108vm-test-run-index_advisor> server # [ 0.000000] Detected PIPT I-cache on CPU0109vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)110vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: GIC system register CPU interface111vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: HCRX_EL2 register112vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)113vm-test-run-index_advisor> server # [ 0.000000] CPU features: detected: Spectre-v4114vm-test-run-index_advisor> server # [ 0.000000] alternatives: applying boot alternatives115vm-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/x62hqmx3ldqjyxqdifjh19n95m6gd3ls-nixos-system-server-test/init regInfo=/nix/store/v0hgk9bky38s5kn96qj1wqfj8yfk88jm-closure-info/registration console=ttyAMA0,115200n8 console=tty0116vm-test-run-index_advisor> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/v0hgk9bky38s5kn96qj1wqfj8yfk88jm-closure-info/registration", will be passed to user space.117vm-test-run-index_advisor> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)118vm-test-run-index_advisor> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)119vm-test-run-index_advisor> server # [ 0.000000] Fallback order for Node 0: 0120vm-test-run-index_advisor> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576121vm-test-run-index_advisor> server # [ 0.000000] Policy zone: Normal122vm-test-run-index_advisor> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off123vm-test-run-index_advisor> server # [ 0.000000] software IO TLB: area num 2.124vm-test-run-index_advisor> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)125vm-test-run-index_advisor> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1126vm-test-run-index_advisor> server # [ 0.000000] allocated 8388608 bytes of page_ext127vm-test-run-index_advisor> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages128vm-test-run-index_advisor> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups129vm-test-run-index_advisor> server # [ 0.000000] rcu: Hierarchical RCU implementation.130vm-test-run-index_advisor> server # [ 0.000000] rcu: RCU event tracing is enabled.131vm-test-run-index_advisor> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.132vm-test-run-index_advisor> server # [ 0.000000] Rude variant of Tasks RCU enabled.133vm-test-run-index_advisor> server # [ 0.000000] Tracing variant of Tasks RCU enabled.134vm-test-run-index_advisor> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.135vm-test-run-index_advisor> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2136vm-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.137vm-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.138vm-test-run-index_advisor> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0139vm-test-run-index_advisor> server # [ 0.000000] GICv3: 256 SPIs implemented140vm-test-run-index_advisor> server # [ 0.000000] GICv3: 0 Extended SPIs implemented141vm-test-run-index_advisor> server # [ 0.000000] Root IRQ handler: gic_handle_irq142vm-test-run-index_advisor> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs143vm-test-run-index_advisor> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0144vm-test-run-index_advisor> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000145vm-test-run-index_advisor> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]146vm-test-run-index_advisor> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1)147vm-test-run-index_advisor> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1)148vm-test-run-index_advisor> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b00000149vm-test-run-index_advisor> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000150vm-test-run-index_advisor> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.151vm-test-run-index_advisor> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).152vm-test-run-index_advisor> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns153vm-test-run-index_advisor> server # [ 0.000039] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns154vm-test-run-index_advisor> server # [ 0.003409] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)155vm-test-run-index_advisor> server # [ 0.014484] Console: colour dummy device 80x25156vm-test-run-index_advisor> server # [ 0.016351] printk: legacy console [tty0] enabled157vm-test-run-index_advisor> server # [ 0.022539] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)158vm-test-run-index_advisor> server # [ 0.022964] pid_max: default: 32768 minimum: 301159vm-test-run-index_advisor> server # [ 0.025169] LSM: initializing lsm=capability,landlock,yama,bpf160vm-test-run-index_advisor> server # [ 0.026635] landlock: Up and running.161vm-test-run-index_advisor> server # [ 0.026692] Yama: becoming mindful.162vm-test-run-index_advisor> server # [ 0.030190] LSM support for eBPF active163vm-test-run-index_advisor> server # [ 0.033492] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)164vm-test-run-index_advisor> server # [ 0.033693] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)165vm-test-run-index_advisor> server # [ 0.065060] cacheinfo: Unable to detect cache hierarchy for CPU 0166vm-test-run-index_advisor> server # [ 0.076325] rcu: Hierarchical SRCU implementation.167vm-test-run-index_advisor> server # [ 0.076425] rcu: Max phase no-delay instances is 1000.168vm-test-run-index_advisor> server # [ 0.077792] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level169vm-test-run-index_advisor> server # [ 0.084762] fsl-mc MSI: its@8080000 domain created170vm-test-run-index_advisor> server # [ 0.086734] EFI services will not be available.171vm-test-run-index_advisor> server # [ 0.089895] smp: Bringing up secondary CPUs ...172vm-test-run-index_advisor> server # [ 0.094320] Detected PIPT I-cache on CPU1173vm-test-run-index_advisor> server # [ 0.095625] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000174vm-test-run-index_advisor> server # [ 0.096470] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000175vm-test-run-index_advisor> server # [ 0.098112] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]176vm-test-run-index_advisor> server # [ 0.102102] smp: Brought up 1 node, 2 CPUs177vm-test-run-index_advisor> server # [ 0.102260] SMP: Total of 2 processors activated.178vm-test-run-index_advisor> server # [ 0.102306] CPU: All CPU(s) started at EL1179vm-test-run-index_advisor> server # [ 0.102476] CPU features: detected: Branch Target Identification180vm-test-run-index_advisor> server # [ 0.102528] CPU features: detected: 32-bit EL0 Support181vm-test-run-index_advisor> server # [ 0.102560] CPU features: detected: 32-bit EL1 Support182vm-test-run-index_advisor> server # [ 0.102582] CPU features: detected: ARMv8.4 Translation Table Level183vm-test-run-index_advisor> server # [ 0.102618] CPU features: detected: Instruction cache invalidation not required for I/D coherence184vm-test-run-index_advisor> server # [ 0.102662] CPU features: detected: Data cache clean to the PoU not required for I/D coherence185vm-test-run-index_advisor> server # [ 0.102726] CPU features: detected: Common not Private translations186vm-test-run-index_advisor> server # [ 0.102750] CPU features: detected: CRC32 instructions187vm-test-run-index_advisor> server # [ 0.102770] CPU features: detected: Data cache clean to Point of Deep Persistence188vm-test-run-index_advisor> server # [ 0.102800] CPU features: detected: Data cache clean to Point of Persistence189vm-test-run-index_advisor> server # [ 0.102828] CPU features: detected: Data independent timing control (DIT)190vm-test-run-index_advisor> server # [ 0.102855] CPU features: detected: E0PD191vm-test-run-index_advisor> server # [ 0.102877] CPU features: detected: Enhanced Counter Virtualization192vm-test-run-index_advisor> server # [ 0.102900] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)193vm-test-run-index_advisor> server # [ 0.102929] CPU features: detected: Enhanced Privileged Access Never194vm-test-run-index_advisor> server # [ 0.102956] CPU features: detected: Enhanced Virtualization Traps195vm-test-run-index_advisor> server # [ 0.102984] CPU features: detected: Fine Grained Traps196vm-test-run-index_advisor> server # [ 0.103045] CPU features: detected: Generic authentication (IMP DEF algorithm)197vm-test-run-index_advisor> server # [ 0.103131] CPU features: detected: RCpc load-acquire (LDAPR)198vm-test-run-index_advisor> server # [ 0.103181] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)199vm-test-run-index_advisor> server # [ 0.103209] CPU features: detected: LSE atomic instructions200vm-test-run-index_advisor> server # [ 0.103235] CPU features: detected: Memory Copy and Memory Set instructions201vm-test-run-index_advisor> server # [ 0.103263] CPU features: detected: Privileged Access Never202vm-test-run-index_advisor> server # [ 0.103289] CPU features: detected: RAS Extension Support203vm-test-run-index_advisor> server # [ 0.103313] CPU features: detected: Random Number Generator204vm-test-run-index_advisor> server # [ 0.103338] CPU features: detected: Speculation barrier (SB)205vm-test-run-index_advisor> server # [ 0.103363] CPU features: detected: Stage-2 Force Write-Back206vm-test-run-index_advisor> server # [ 0.103388] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality207vm-test-run-index_advisor> server # [ 0.103415] CPU features: detected: TLB range maintenance instructions208vm-test-run-index_advisor> server # [ 0.103437] CPU features: detected: WFx with timeout209vm-test-run-index_advisor> server # [ 0.103479] CPU features: detected: Speculative Store Bypassing Safe (SSBS)210vm-test-run-index_advisor> server # [ 0.103506] CPU features: detected: Scalable Vector Extension211vm-test-run-index_advisor> server # [ 0.112860] alternatives: applying system-wide alternatives212vm-test-run-index_advisor> server # [ 0.127484] CPU features: detected: Hardware dirty bit management on CPU0-1213vm-test-run-index_advisor> server # [ 0.128186] SVE: maximum available vector length 256 bytes per vector214vm-test-run-index_advisor> server # [ 0.128277] SVE: default vector length 64 bytes per vector215vm-test-run-index_advisor> server # [ 0.140937] Memory: 3922804K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved)216vm-test-run-index_advisor> server # [ 0.163609] devtmpfs: initialized217vm-test-run-index_advisor> server # [ 0.191336] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns218vm-test-run-index_advisor> server # [ 0.191842] futex hash table entries: 512 (order: 3, 32768 bytes, linear)219vm-test-run-index_advisor> server # [ 0.194599] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL220vm-test-run-index_advisor> server # [ 0.194672] 0 pages in range for non-PLT usage221vm-test-run-index_advisor> server # [ 0.194716] 509248 pages in range for PLT usage222vm-test-run-index_advisor> server # [ 0.197572] pinctrl core: initialized pinctrl subsystem223vm-test-run-index_advisor> server # [ 0.208338] DMI not present or invalid.224vm-test-run-index_advisor> server # [ 0.226910] NET: Registered PF_NETLINK/PF_ROUTE protocol family225vm-test-run-index_advisor> server # [ 0.238177] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations226vm-test-run-index_advisor> server # [ 0.239245] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations227vm-test-run-index_advisor> server # [ 0.240796] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations228vm-test-run-index_advisor> server # [ 0.241122] audit: initializing netlink subsys (disabled)229vm-test-run-index_advisor> server # [ 0.243188] audit: type=2000 audit(0.140:1): state=initialized audit_enabled=0 res=1230vm-test-run-index_advisor> server # [ 0.250449] thermal_sys: Registered thermal governor 'fair_share'231vm-test-run-index_advisor> server # [ 0.250503] thermal_sys: Registered thermal governor 'bang_bang'232vm-test-run-index_advisor> server # [ 0.250561] thermal_sys: Registered thermal governor 'step_wise'233vm-test-run-index_advisor> server # [ 0.250592] thermal_sys: Registered thermal governor 'user_space'234vm-test-run-index_advisor> server # [ 0.250620] thermal_sys: Registered thermal governor 'power_allocator'235vm-test-run-index_advisor> server # [ 0.251254] cpuidle: using governor ladder236vm-test-run-index_advisor> server # [ 0.251645] cpuidle: using governor menu237vm-test-run-index_advisor> server # [ 0.253534] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.238vm-test-run-index_advisor> server # [ 0.254190] ASID allocator initialised with 65536 entries239vm-test-run-index_advisor> server # [ 0.261870] Serial: AMBA PL011 UART driver240vm-test-run-index_advisor> server # [ 0.310651] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1241vm-test-run-index_advisor> server # [ 0.311697] printk: legacy console [ttyAMA0] enabled242vm-test-run-index_advisor> server # [ 0.346536] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages243vm-test-run-index_advisor> server # [ 0.346743] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page244vm-test-run-index_advisor> server # [ 0.346866] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages245vm-test-run-index_advisor> server # [ 0.347109] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page246vm-test-run-index_advisor> server # [ 0.347229] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages247vm-test-run-index_advisor> server # [ 0.347325] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page248vm-test-run-index_advisor> server # [ 0.347418] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages249vm-test-run-index_advisor> server # [ 0.347511] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page250vm-test-run-index_advisor> server # [ 0.359538] fbcon: Taking over console251vm-test-run-index_advisor> server # [ 0.360438] ACPI: Interpreter disabled.252vm-test-run-index_advisor> server # [ 0.367223] iommu: Default domain type: Translated253vm-test-run-index_advisor> server # [ 0.367821] iommu: DMA domain TLB invalidation policy: strict mode254vm-test-run-index_advisor> server # [ 0.370547] SCSI subsystem initialized255vm-test-run-index_advisor> server # [ 0.372627] usbcore: registered new interface driver usbfs256vm-test-run-index_advisor> server # [ 0.373053] usbcore: registered new interface driver hub257vm-test-run-index_advisor> server # [ 0.373291] usbcore: registered new device driver usb258vm-test-run-index_advisor> server # [ 0.376533] pps_core: LinuxPPS API ver. 1 registered259vm-test-run-index_advisor> server # [ 0.376659] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>260vm-test-run-index_advisor> server # [ 0.376838] PTP clock support registered261vm-test-run-index_advisor> server # [ 0.377347] EDAC MC: Ver: 3.0.0262vm-test-run-index_advisor> server # [ 0.379218] scmi_core: SCMI protocol bus registered263vm-test-run-index_advisor> server # [ 0.382271] FPGA manager framework264vm-test-run-index_advisor> server # [ 0.384852] Advanced Linux Sound Architecture Driver Initialized.265vm-test-run-index_advisor> server # [ 0.393101] vgaarb: loaded266vm-test-run-index_advisor> server # [ 0.397986] clocksource: Switched to clocksource arch_sys_counter267vm-test-run-index_advisor> server # [ 0.402128] VFS: Disk quotas dquot_6.6.0268vm-test-run-index_advisor> server # [ 0.402400] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)269vm-test-run-index_advisor> server # [ 0.405629] netfs: FS-Cache loaded270vm-test-run-index_advisor> server # [ 0.407809] pnp: PnP ACPI: disabled271vm-test-run-index_advisor> server # [ 0.430962] NET: Registered PF_INET protocol family272vm-test-run-index_advisor> server # [ 0.432624] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)273vm-test-run-index_advisor> server # [ 0.549500] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)274vm-test-run-index_advisor> server # [ 0.550678] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)275vm-test-run-index_advisor> server # [ 0.551871] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)276vm-test-run-index_advisor> server # [ 0.552865] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)277vm-test-run-index_advisor> server # [ 0.554143] TCP: Hash tables configured (established 32768 bind 32768)278vm-test-run-index_advisor> server # [ 0.555987] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)279vm-test-run-index_advisor> server # [ 0.556749] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)280vm-test-run-index_advisor> server # [ 0.557422] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)281vm-test-run-index_advisor> server # [ 0.559382] NET: Registered PF_UNIX/PF_LOCAL protocol family282vm-test-run-index_advisor> server # [ 0.560213] NET: Registered PF_XDP protocol family283vm-test-run-index_advisor> server # [ 0.560439] PCI: CLS 0 bytes, default 64284vm-test-run-index_advisor> server # [ 0.564326] kvm [1]: HYP mode not available285vm-test-run-index_advisor> server # [ 0.564497] Trying to unpack rootfs image as initramfs...286vm-test-run-index_advisor> server # [ 0.671394] Initialise system trusted keyrings287vm-test-run-index_advisor> server # [ 0.673484] workingset: timestamp_bits=42 max_order=20 bucket_order=0288vm-test-run-index_advisor> server # [ 0.677370] squashfs: version 4.0 (2009/01/31) Phillip Lougher289vm-test-run-index_advisor> server # [ 0.678916] 9p: Installing v9fs 9p2000 file system support290vm-test-run-index_advisor> server # [ 0.737197] Key type asymmetric registered291vm-test-run-index_advisor> server # [ 0.737439] Asymmetric key parser 'x509' registered292vm-test-run-index_advisor> server # [ 0.738794] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)293vm-test-run-index_advisor> server # [ 0.740082] io scheduler mq-deadline registered294vm-test-run-index_advisor> server # [ 0.740251] io scheduler kyber registered295vm-test-run-index_advisor> server # [ 0.766497] pl061_gpio 9030000.pl061: PL061 GPIO chip registered296vm-test-run-index_advisor> server # [ 0.772587] ledtrig-cpu: registered to indicate activity on CPUs297vm-test-run-index_advisor> server # [ 0.776646] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:298vm-test-run-index_advisor> server # [ 0.777276] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000299vm-test-run-index_advisor> server # [ 0.778402] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000300vm-test-run-index_advisor> server # [ 0.779309] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000301vm-test-run-index_advisor> server # [ 0.780462] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits302vm-test-run-index_advisor> server # [ 0.781548] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]303vm-test-run-index_advisor> server # [ 0.783422] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00304vm-test-run-index_advisor> server # [ 0.784403] pci_bus 0000:00: root bus resource [bus 00-ff]305vm-test-run-index_advisor> server # [ 0.784593] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]306vm-test-run-index_advisor> server # [ 0.784951] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]307vm-test-run-index_advisor> server # [ 0.785269] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]308vm-test-run-index_advisor> server # [ 0.786802] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint309vm-test-run-index_advisor> server # [ 0.789718] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint310vm-test-run-index_advisor> server # [ 0.790752] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]311vm-test-run-index_advisor> server # [ 0.791264] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]312vm-test-run-index_advisor> server # [ 0.791478] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]313vm-test-run-index_advisor> server # [ 0.791995] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]314vm-test-run-index_advisor> server # [ 0.793037] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint315vm-test-run-index_advisor> server # [ 0.793440] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]316vm-test-run-index_advisor> server # [ 0.793826] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]317vm-test-run-index_advisor> server # [ 0.793949] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]318vm-test-run-index_advisor> server # [ 0.794680] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint319vm-test-run-index_advisor> server # [ 0.795058] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]320vm-test-run-index_advisor> server # [ 0.795178] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]321vm-test-run-index_advisor> server # [ 0.795713] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]322vm-test-run-index_advisor> server # [ 0.796615] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint323vm-test-run-index_advisor> server # [ 0.796997] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]324vm-test-run-index_advisor> server # [ 0.797219] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]325vm-test-run-index_advisor> server # [ 0.797552] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]326vm-test-run-index_advisor> server # [ 0.799389] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint327vm-test-run-index_advisor> server # [ 0.799593] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]328vm-test-run-index_advisor> server # [ 0.799852] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]329vm-test-run-index_advisor> server # [ 0.800216] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]330vm-test-run-index_advisor> server # [ 0.800840] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint331vm-test-run-index_advisor> server # [ 0.801589] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]332vm-test-run-index_advisor> server # [ 0.801701] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]333vm-test-run-index_advisor> server # [ 0.801820] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]334vm-test-run-index_advisor> server # [ 0.802889] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint335vm-test-run-index_advisor> server # [ 0.803231] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]336vm-test-run-index_advisor> server # [ 0.803343] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]337vm-test-run-index_advisor> server # [ 0.803677] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]338vm-test-run-index_advisor> server # [ 0.803810] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]339vm-test-run-index_advisor> server # [ 0.804641] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint340vm-test-run-index_advisor> server # [ 0.805162] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]341vm-test-run-index_advisor> server # [ 0.805285] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]342vm-test-run-index_advisor> server # [ 0.805766] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint343vm-test-run-index_advisor> server # [ 0.806268] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]344vm-test-run-index_advisor> server # [ 0.806607] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]345vm-test-run-index_advisor> server # [ 0.807244] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint346vm-test-run-index_advisor> server # [ 0.807390] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]347vm-test-run-index_advisor> server # [ 0.807785] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint348vm-test-run-index_advisor> server # [ 0.807985] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]349vm-test-run-index_advisor> server # [ 0.808788] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]350vm-test-run-index_advisor> server # [ 0.808912] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]351vm-test-run-index_advisor> server # [ 0.809431] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint352vm-test-run-index_advisor> server # [ 0.809810] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]353vm-test-run-index_advisor> server # [ 0.809912] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]354vm-test-run-index_advisor> server # [ 0.810029] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]355vm-test-run-index_advisor> server # [ 0.813019] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned356vm-test-run-index_advisor> server # [ 0.813306] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned357vm-test-run-index_advisor> server # [ 0.813506] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned358vm-test-run-index_advisor> server # [ 0.813843] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned359vm-test-run-index_advisor> server # [ 0.814034] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned360vm-test-run-index_advisor> server # [ 0.814187] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned361vm-test-run-index_advisor> server # [ 0.814924] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned362vm-test-run-index_advisor> server # [ 0.815579] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned363vm-test-run-index_advisor> server # [ 0.815954] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned364vm-test-run-index_advisor> server # [ 0.816604] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned365vm-test-run-index_advisor> server # [ 0.816778] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned366vm-test-run-index_advisor> server # [ 0.817043] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned367vm-test-run-index_advisor> server # [ 0.817220] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned368vm-test-run-index_advisor> server # [ 0.817356] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned369vm-test-run-index_advisor> server # [ 0.817650] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned370vm-test-run-index_advisor> server # [ 0.817782] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned371vm-test-run-index_advisor> server # [ 0.817892] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned372vm-test-run-index_advisor> server # [ 0.818242] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned373vm-test-run-index_advisor> server # [ 0.819066] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned374vm-test-run-index_advisor> server # [ 0.819180] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned375vm-test-run-index_advisor> server # [ 0.819771] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned376vm-test-run-index_advisor> server # [ 0.819891] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned377vm-test-run-index_advisor> server # [ 0.820094] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned378vm-test-run-index_advisor> server # [ 0.820384] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned379vm-test-run-index_advisor> server # [ 0.820781] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned380vm-test-run-index_advisor> server # [ 0.821075] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned381vm-test-run-index_advisor> server # [ 0.821219] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned382vm-test-run-index_advisor> server # [ 0.821332] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned383vm-test-run-index_advisor> server # [ 0.821439] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned384vm-test-run-index_advisor> server # [ 0.821539] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned385vm-test-run-index_advisor> server # [ 0.821645] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned386vm-test-run-index_advisor> server # [ 0.821746] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned387vm-test-run-index_advisor> server # [ 0.821852] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned388vm-test-run-index_advisor> server # [ 0.821955] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned389vm-test-run-index_advisor> server # [ 0.822266] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]390vm-test-run-index_advisor> server # [ 0.822377] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]391vm-test-run-index_advisor> server # [ 0.823243] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]392vm-test-run-index_advisor> server # [ 0.824517] pci 0000:00:0a.0: enabling device (0000 -> 0002)393vm-test-run-index_advisor> server # [ 0.880919] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)394vm-test-run-index_advisor> server # [ 0.885226] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)395vm-test-run-index_advisor> server # [ 0.888345] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)396vm-test-run-index_advisor> server # [ 0.891429] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)397vm-test-run-index_advisor> server # [ 0.893826] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)398vm-test-run-index_advisor> server # [ 0.896606] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)399vm-test-run-index_advisor> server # [ 0.898410] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)400vm-test-run-index_advisor> server # [ 0.900483] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)401vm-test-run-index_advisor> server # [ 0.902992] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)402vm-test-run-index_advisor> server # [ 0.905292] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)403vm-test-run-index_advisor> server # [ 0.907294] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)404vm-test-run-index_advisor> server # [ 0.923224] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled405vm-test-run-index_advisor> server # [ 0.933005] msm_serial: driver initialized406vm-test-run-index_advisor> server # [ 0.935477] SuperH (H)SCI(F) driver initialized407vm-test-run-index_advisor> server # [ 0.935919] STM32 USART driver initialized408vm-test-run-index_advisor> server # [ 0.980629] loop: module loaded409vm-test-run-index_advisor> server # [ 0.981925] virtio_blk virtio5: 2/0/0 default/read/poll queues410vm-test-run-index_advisor> server # [ 0.986212] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)411vm-test-run-index_advisor> server # [ 0.997673] megasas: 07.727.03.00-rc1412vm-test-run-index_advisor> server # [ 1.002793] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]413vm-test-run-index_advisor> server # [ 1.005719] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000414vm-test-run-index_advisor> server # [ 1.007296] Intel/Sharp Extended Query Table at 0x0031415vm-test-run-index_advisor> server # [ 1.009112] Using buffer write method416vm-test-run-index_advisor> server # [ 1.009686] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]417vm-test-run-index_advisor> server # [ 1.012008] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000418vm-test-run-index_advisor> server # [ 1.012810] Intel/Sharp Extended Query Table at 0x0031419vm-test-run-index_advisor> server # [ 1.015825] Using buffer write method420vm-test-run-index_advisor> server # [ 1.016360] Concatenating MTD devices:421vm-test-run-index_advisor> server # [ 1.016455] (0): "0.flash"422vm-test-run-index_advisor> server # [ 1.016514] (1): "0.flash"423vm-test-run-index_advisor> server # [ 1.016574] into device "0.flash"424vm-test-run-index_advisor> server # [ 1.025440] Freeing initrd memory: 10684K425vm-test-run-index_advisor> server # [ 1.050761] tun: Universal TUN/TAP device driver, 1.6426vm-test-run-index_advisor> server # [ 1.063522] thunder_xcv, ver 1.0427vm-test-run-index_advisor> server # [ 1.063771] thunder_bgx, ver 1.0428vm-test-run-index_advisor> server # [ 1.064537] nicpf, ver 1.0429vm-test-run-index_advisor> server # [ 1.067410] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version430vm-test-run-index_advisor> server # [ 1.067596] hns3: Copyright (c) 2017 Huawei Corporation.431vm-test-run-index_advisor> server # [ 1.068085] hclge is initializing432vm-test-run-index_advisor> server # [ 1.068317] e1000: Intel(R) PRO/1000 Network Driver433vm-test-run-index_advisor> server # [ 1.068398] e1000: Copyright (c) 1999-2006 Intel Corporation.434vm-test-run-index_advisor> server # [ 1.068615] e1000e: Intel(R) PRO/1000 Network Driver435vm-test-run-index_advisor> server # [ 1.068870] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.436vm-test-run-index_advisor> server # [ 1.069213] igb: Intel(R) Gigabit Ethernet Network Driver437vm-test-run-index_advisor> server # [ 1.069470] igb: Copyright (c) 2007-2014 Intel Corporation.438vm-test-run-index_advisor> server # [ 1.069728] igbvf: Intel(R) Gigabit Virtual Function Network Driver439vm-test-run-index_advisor> server # [ 1.070625] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.440vm-test-run-index_advisor> server # [ 1.071424] sky2: driver version 1.30441vm-test-run-index_advisor> server # [ 1.079156] ehci-pci 0000:00:0a.0: EHCI Host Controller442vm-test-run-index_advisor> server # [ 1.081142] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1443vm-test-run-index_advisor> server # [ 1.082738] usbcore: registered new interface driver usb-storage444vm-test-run-index_advisor> server # [ 1.084249] usbcore: registered new interface driver usbserial_generic445vm-test-run-index_advisor> server # [ 1.085105] usbserial: USB Serial support registered for generic446vm-test-run-index_advisor> server # [ 1.085605] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000447vm-test-run-index_advisor> server # [ 1.096499] rtc-pl031 9010000.pl031: registered as rtc0448vm-test-run-index_advisor> server # [ 1.097161] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T20:37:03 UTC (1791405423)449vm-test-run-index_advisor> server # [ 1.100950] i2c_dev: i2c /dev entries driver450vm-test-run-index_advisor> server # [ 1.110574] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00451vm-test-run-index_advisor> server # [ 1.115539] sdhci: Secure Digital Host Controller Interface driver452vm-test-run-index_advisor> server # [ 1.115835] sdhci: Copyright(c) Pierre Ossman453vm-test-run-index_advisor> server # [ 1.117757] hub 1-0:1.0: USB hub found454vm-test-run-index_advisor> server # [ 1.118872] Synopsys Designware Multimedia Card Interface Driver455vm-test-run-index_advisor> server # [ 1.119956] hub 1-0:1.0: 6 ports detected456vm-test-run-index_advisor> server # [ 1.121849] sdhci-pltfm: SDHCI platform and OF driver helper457vm-test-run-index_advisor> server # [ 1.128795] hid: raw HID events driver (C) Jiri Kosina458vm-test-run-index_advisor> server # [ 1.131238] usbcore: registered new interface driver usbhid459vm-test-run-index_advisor> server # [ 1.131411] usbhid: USB HID core driver460vm-test-run-index_advisor> server # [ 1.140178] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available461vm-test-run-index_advisor> server # [ 1.153883] drop_monitor: Initializing network drop monitor service462vm-test-run-index_advisor> server # [ 1.155819] NET: Registered PF_INET6 protocol family463vm-test-run-index_advisor> server # [ 1.171407] Segment Routing with IPv6464vm-test-run-index_advisor> server # [ 1.172730] In-situ OAM (IOAM) with IPv6465vm-test-run-index_advisor> server # [ 1.173600] NET: Registered PF_PACKET protocol family466vm-test-run-index_advisor> server # [ 1.175709] 9pnet: Installing 9P2000 support467vm-test-run-index_advisor> server # [ 1.180430] Key type dns_resolver registered468vm-test-run-index_advisor> server # [ 1.215372] registered taskstats version 1469vm-test-run-index_advisor> server # [ 1.217959] Loading compiled-in X.509 certificates470vm-test-run-index_advisor> server # [ 1.276092] Demotion targets for Node 0: null471vm-test-run-index_advisor> server # [ 1.278673] Key type .fscrypt registered472vm-test-run-index_advisor> server # [ 1.278882] Key type fscrypt-provisioning registered473vm-test-run-index_advisor> server # [ 1.287879] input: gpio-keys as /devices/platform/gpio-keys/input/input0474vm-test-run-index_advisor> server # [ 1.330661] clk: Disabling unused clocks475vm-test-run-index_advisor> server # [ 1.330959] PM: genpd: Disabling unused power domains476vm-test-run-index_advisor> server # [ 1.332624] ALSA device list:477vm-test-run-index_advisor> server # [ 1.332773] No soundcards found.478vm-test-run-index_advisor> server # [ 1.415235] Freeing unused kernel memory: 4416K479vm-test-run-index_advisor> server # [ 1.417317] usb 1-1: new high-speed USB device number 2 using ehci-pci480vm-test-run-index_advisor> server # [ 1.418826] Run /init as init process481vm-test-run-index_advisor> server # [ 1.647441] 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/input1482vm-test-run-index_advisor> server # [ 1.808303] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0483vm-test-run-index_advisor> server # [ 1.938466] usb 1-2: new high-speed USB device number 3 using ehci-pci484vm-test-run-index_advisor> server # [ 2.006750] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev485vm-test-run-index_advisor> server # [ 2.144190] 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/input2486vm-test-run-index_advisor> server # [ 2.146416] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0487vm-test-run-index_advisor> server # [ 2.160878] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0488vm-test-run-index_advisor> server # [ 2.161705] [drm] features: -virgl +edid -resource_blob -host_visible489vm-test-run-index_advisor> server # [ 2.161730] [drm] features: -context_init490vm-test-run-index_advisor> server # [ 2.164328] [drm] number of scanouts: 1491vm-test-run-index_advisor> server # [ 2.164469] [drm] number of cap sets: 0492vm-test-run-index_advisor> server # [ 2.178724] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0493vm-test-run-index_advisor> server # [ 2.214645] Console: switching to colour frame buffer device 160x50494vm-test-run-index_advisor> server # [ 2.228209] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device495vm-test-run-index_advisor> server # [ 5.480926] EXT4-fs (vda): mounted filesystem 824ef44d-3250-4846-9fb4-c5c0c400af74 r/w with ordered data mode. Quota mode: none.496vm-test-run-index_advisor> server # [ 8.283930] EXT4-fs (vda): re-mounted 824ef44d-3250-4846-9fb4-c5c0c400af74.497vm-test-run-index_advisor> server # [ 8.306943] booting system configuration /nix/store/x62hqmx3ldqjyxqdifjh19n95m6gd3ls-nixos-system-server-test498vm-test-run-index_advisor> server # [ 19.680735] 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)499vm-test-run-index_advisor> server # [ 19.691705] systemd[1]: Detected virtualization qemu.500vm-test-run-index_advisor> server # [ 19.696464] systemd[1]: Detected architecture arm64.501vm-test-run-index_advisor> server # [ 19.700532] systemd[1]: Detected first boot.502vm-test-run-index_advisor> server # [ 19.728836] systemd[1]: Initializing machine ID from random generator.503vm-test-run-index_advisor> server # [ 19.756966] systemd[1]: Hostname set to <server>.504vm-test-run-index_advisor> server # [ 21.076240] systemd[1]: bpf-restrict-fs: LSM BPF program attached505vm-test-run-index_advisor> server # [ 22.458103] systemd[1]: Applying preset policy.506vm-test-run-index_advisor> server # [ 26.170899] systemd[1]: Populated /etc with preset unit settings.507vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...508vm-test-run-index_advisor> server: To debug, enter the VM and run 'systemctl status backdoor.service'.509vm-test-run-index_advisor> server # [ 29.147649] systemd[1]: Queued start job for default target Multi-User System.510vm-test-run-index_advisor> server # [ 29.370749] systemd[1]: Created slice Slice /system/getty.511vm-test-run-index_advisor> server # [ 29.385132] systemd[1]: Created slice Slice /system/modprobe.512vm-test-run-index_advisor> server # [ 29.397405] systemd[1]: Created slice User and Session Slice.513vm-test-run-index_advisor> server # [ 29.405264] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.514vm-test-run-index_advisor> server # [ 29.410340] systemd[1]: Started Forward Password Requests to Wall Directory Watch.515vm-test-run-index_advisor> server # [ 29.415537] systemd[1]: Expecting device /dev/hvc0...516vm-test-run-index_advisor> server # [ 29.419409] systemd[1]: Expecting device /dev/ttyAMA0...517vm-test-run-index_advisor> server # [ 29.423573] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...518vm-test-run-index_advisor> server # [ 29.426518] systemd[1]: Reached target Local Encrypted Volumes.519vm-test-run-index_advisor> server # [ 29.431377] systemd[1]: Reached target Virtual Machines and Containers.520vm-test-run-index_advisor> server # [ 29.435975] systemd[1]: Reached target Path Units.521vm-test-run-index_advisor> server # [ 29.440852] systemd[1]: Reached target Remote File Systems.522vm-test-run-index_advisor> server # [ 29.445668] systemd[1]: Reached target Slice Units.523vm-test-run-index_advisor> server # [ 29.450470] systemd[1]: Reached target Swaps.524vm-test-run-index_advisor> server # [ 29.487101] systemd[1]: Listening on Process Core Dump Socket.525vm-test-run-index_advisor> server # [ 29.507531] systemd[1]: Listening on Credential Encryption/Decryption.526vm-test-run-index_advisor> server # [ 29.517713] systemd[1]: Listening on Journal Audit Socket.527vm-test-run-index_advisor> server # [ 29.524153] systemd[1]: Listening on Journal Socket (/dev/log).528vm-test-run-index_advisor> server # [ 29.530810] systemd[1]: Listening on Journal Sockets.529vm-test-run-index_advisor> server # [ 29.539551] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.530vm-test-run-index_advisor> server # [ 29.543667] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).531vm-test-run-index_advisor> server # [ 29.549590] systemd[1]: Listening on udev Control Socket.532vm-test-run-index_advisor> server # [ 29.554547] systemd[1]: Listening on udev Kernel Socket.533vm-test-run-index_advisor> server # [ 29.597956] systemd[1]: Mounting Huge Pages File System...534vm-test-run-index_advisor> server # [ 29.639953] systemd[1]: Mounting POSIX Message Queue File System...535vm-test-run-index_advisor> server # [ 29.685215] systemd[1]: Mounting Kernel Debug File System...536vm-test-run-index_advisor> server # [ 29.739226] systemd[1]: Mounting Kernel Trace File System...537vm-test-run-index_advisor> server # [ 29.835660] systemd[1]: Starting Create List of Static Device Nodes...538vm-test-run-index_advisor> server # [ 29.985406] systemd[1]: Starting Load Kernel Module 9pnet_virtio...539vm-test-run-index_advisor> server # [ 30.009130] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).540vm-test-run-index_advisor> server # [ 30.126972] systemd[1]: Mounting Kernel Configuration File System...541vm-test-run-index_advisor> server # [ 30.145126] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).542vm-test-run-index_advisor> server # [ 30.164784] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).543vm-test-run-index_advisor> server # [ 30.212690] systemd[1]: Starting Load Kernel Module fuse...544vm-test-run-index_advisor> server # [ 30.219603] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).545vm-test-run-index_advisor> server # [ 30.225568] 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).546vm-test-run-index_advisor> server # [ 30.308083] systemd[1]: Starting Journal Service...547vm-test-run-index_advisor> server # [ 30.371630] systemd[1]: Starting Load Kernel Modules...548vm-test-run-index_advisor> server # [ 30.465785] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...549vm-test-run-index_advisor> server # [ 30.522705] systemd[1]: Starting Remount Root and Kernel File Systems...550vm-test-run-index_advisor> server # [ 30.531207] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).551vm-test-run-index_advisor> server # [ 30.583056] systemd[1]: Starting Coldplug All udev Devices...552vm-test-run-index_advisor> server # [ 30.690293] systemd[1]: Mounted Huge Pages File System.553vm-test-run-index_advisor> server # [ 30.699123] systemd[1]: Mounted POSIX Message Queue File System.554vm-test-run-index_advisor> server # [ 30.709705] systemd[1]: Mounted Kernel Debug File System.555vm-test-run-index_advisor> server # [ 30.728142] systemd[1]: Mounted Kernel Trace File System.556vm-test-run-index_advisor> server # [ 30.748048] systemd[1]: Finished Create List of Static Device Nodes.557vm-test-run-index_advisor> server # [ 30.772794] systemd[1]: Mounted Kernel Configuration File System.558vm-test-run-index_advisor> server # [ 30.829200] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...559vm-test-run-index_advisor> server # [ 31.336754] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.560vm-test-run-index_advisor> server # [ 31.353266] systemd[1]: Finished Load Kernel Module 9pnet_virtio.561vm-test-run-index_advisor> server # [ 31.554622] EXT4-fs (vda): re-mounted 824ef44d-3250-4846-9fb4-c5c0c400af74.562vm-test-run-index_advisor> server # [ 31.613077] systemd[1]: Finished Load Kernel Modules.563vm-test-run-index_advisor> server # [ 31.661716] fuse: init (API version 7.41)564vm-test-run-index_advisor> server # [ 31.675922] systemd[1]: Starting Firewall...565vm-test-run-index_advisor> server # [ 31.739554] systemd[1]: Starting Apply Kernel Variables...566vm-test-run-index_advisor> server # [ 31.754041] systemd[1]: modprobe@fuse.service: Deactivated successfully.567vm-test-run-index_advisor> server # [ 31.767252] systemd[1]: Finished Load Kernel Module fuse.568vm-test-run-index_advisor> server # [ 31.784019] systemd[1]: Finished Remount Root and Kernel File Systems.569vm-test-run-index_advisor> server # [ 31.822792] systemd[1]: Mounting FUSE Control File System...570vm-test-run-index_advisor> server # [ 31.829234] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).571vm-test-run-index_advisor> server # [ 31.887566] systemd[1]: Starting Load/Save OS Random Seed...572vm-test-run-index_advisor> server # [ 31.893346] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).573vm-test-run-index_advisor> server # [ 32.061520] systemd[1]: Mounted FUSE Control File System.574vm-test-run-index_advisor> server # [ 32.125079] systemd-journald[398]: Collecting audit messages is enabled.575vm-test-run-index_advisor> server # [ 32.824398] systemd[1]: Finished Load/Save OS Random Seed.576vm-test-run-index_advisor> server # [ 32.835301] systemd[1]: Reached target First Boot Complete.577vm-test-run-index_advisor> server # [ 33.037587] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.578vm-test-run-index_advisor> server # [ 33.145404] systemd[1]: Starting Create Static Device Nodes in /dev...579vm-test-run-index_advisor> server # [ 33.197405] systemd[1]: Finished Apply Kernel Variables.580vm-test-run-index_advisor> server # [ 33.424188] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.581vm-test-run-index_advisor> server # [ 34.287050] systemd[1]: Finished Create Static Device Nodes in /dev.582vm-test-run-index_advisor> server # [ 34.312366] systemd[1]: Reached target Preparation for Local File Systems.583vm-test-run-index_advisor> server # [ 34.365887] systemd[1]: Mounting /run/wrappers...584vm-test-run-index_advisor> server # [ 34.425654] systemd[1]: Starting Rule-based Manager for Device Events and Files...585vm-test-run-index_advisor> server # [ 34.627232] systemd[1]: Started Journal Service.586vm-test-run-index_advisor> server # [ 34.501913] systemd-modules-load[399]: Module 'atkbd' is built in587vm-test-run-index_advisor> server # [ 34.511368] systemd-modules-load[399]: Module 'loop' is built in588vm-test-run-index_advisor> server # [ 34.579541] systemd-oomd[400]: No swap; memory pressure usage will be degraded589vm-test-run-index_advisor> server # [ 34.621905] systemd[1]: Starting Flush Journal to Persistent Storage...590vm-test-run-index_advisor> server # [ 34.855381] systemd[1]: Mounted /run/wrappers.591vm-test-run-index_advisor> server # [ 34.905429] systemd[1]: Reached target Local File Systems.592vm-test-run-index_advisor> server # [ 34.937156] systemd[1]: Listening on Boot Entries Service Socket.593vm-test-run-index_advisor> server # [ 34.995199] systemd[1]: Starting Create SUID/SGID Wrappers...594vm-test-run-index_advisor> server # [ 35.013956] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.595vm-test-run-index_advisor> server # [ 35.055526] systemd[1]: Starting Save Transient machine-id to Disk...596vm-test-run-index_advisor> server # [ 35.591793] systemd-journald[398]: Received client request to flush runtime journal.597vm-test-run-index_advisor> server # [ 36.278551] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.598vm-test-run-index_advisor> server # [ 36.314845] systemd[1]: Finished Save Transient machine-id to Disk.599vm-test-run-index_advisor> server # [ 36.371610] systemd-udevd[428]: Using default interface naming scheme 'v258'.600vm-test-run-index_advisor> server # [ 36.381578] systemd[1]: Finished Flush Journal to Persistent Storage.601vm-test-run-index_advisor> server # [ 36.407221] systemd[1]: Starting Create System Files and Directories...602vm-test-run-index_advisor> server # [ 38.520136] systemd[1]: Finished Create System Files and Directories.603vm-test-run-index_advisor> server # [ 38.610133] systemd[1]: Starting Rebuild Journal Catalog...604vm-test-run-index_advisor> server # [ 38.649592] systemd[1]: Starting Record System Boot/Shutdown in UTMP...605vm-test-run-index_advisor> server # [ 38.697162] systemd[1]: Started Rule-based Manager for Device Events and Files.606vm-test-run-index_advisor> server # [ 39.779747] systemd[1]: Finished Record System Boot/Shutdown in UTMP.607vm-test-run-index_advisor> server # [ 40.796449] systemd[1]: Finished Rebuild Journal Catalog.608vm-test-run-index_advisor> server # [ 40.838466] systemd[1]: Starting Update is Completed...609vm-test-run-index_advisor> server # [ 41.683510] systemd[1]: Finished Update is Completed.610vm-test-run-index_advisor> server # [ 45.526634] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.611vm-test-run-index_advisor> server # [ 45.546978] systemd[1]: Finished Create SUID/SGID Wrappers.612vm-test-run-index_advisor> server # [ 45.550707] systemd[1]: suid-sgid-wrappers.service: Consumed 2.909s CPU time, 5.5M memory peak.613vm-test-run-index_advisor> server # [ 47.791739] systemd[1]: Finished Firewall.614vm-test-run-index_advisor> server # [ 49.261372] systemd[1]: Finished Coldplug All udev Devices.615vm-test-run-index_advisor> server # [ 49.270792] systemd[1]: Reached target System Initialization.616vm-test-run-index_advisor> server # [ 49.284943] systemd[1]: Started Discard unused filesystem blocks once a week.617vm-test-run-index_advisor> server # [ 49.289593] systemd[1]: Started Daily Cleanup of Temporary Directories.618vm-test-run-index_advisor> server # [ 49.297601] systemd[1]: Reached target Timer Units.619vm-test-run-index_advisor> server # [ 49.301890] systemd[1]: Listening on D-Bus System Message Bus Socket.620vm-test-run-index_advisor> server # [ 49.313771] systemd[1]: Listening on Nix Daemon Socket.621vm-test-run-index_advisor> server # [ 49.321850] systemd[1]: Listening on Hostname Service Socket.622vm-test-run-index_advisor> server # [ 49.323212] systemd[1]: Reached target Socket Units.623vm-test-run-index_advisor> server # [ 49.330696] systemd[1]: Reached target Basic System.624vm-test-run-index_advisor> server # [ 49.343090] systemd[1]: Starting Import lastlog data into lastlog2 database...625vm-test-run-index_advisor> server # [ 49.391225] systemd[1]: Starting Name Service Cache Daemon (nsncd)...626vm-test-run-index_advisor> server # [ 49.446887] systemd[1]: Started Reset console on configuration changes.627vm-test-run-index_advisor> server # [ 49.497123] systemd[1]: Starting resolvconf update...628vm-test-run-index_advisor> server # [ 49.563545] systemd[1]: Starting D-Bus System Message Bus...629vm-test-run-index_advisor> server # [ 50.526609] systemd[1]: Finished Import lastlog data into lastlog2 database.630vm-test-run-index_advisor> server # [ 50.829059] systemd[1]: Started Name Service Cache Daemon (nsncd).631vm-test-run-index_advisor> server # [ 50.902179] nsncd[631]: Oct 07 20:37:53.352 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"632vm-test-run-index_advisor> server # [ 50.922929] systemd[1]: Reached target Host and Network Name Lookups.633vm-test-run-index_advisor> server # [ 50.938290] systemd[1]: Reached target User and Group Name Lookups.634vm-test-run-index_advisor> server # [ 50.945348] systemd[1]: Starting User Login Management...635vm-test-run-index_advisor> server # [ 50.967400] dbus-daemon[634]: dbus[634]: Unknown username "systemd-timesync" in message bus configuration file636vm-test-run-index_advisor> server # [ 51.598488] systemd[1]: Started D-Bus System Message Bus.637vm-test-run-index_advisor> server # [ 52.642971] systemd-logind[652]: New seat seat0.638vm-test-run-index_advisor> server # [ 52.691700] systemd[1]: Started User Login Management.639vm-test-run-index_advisor> server # [ 52.735324] systemd[1]: Starting linger-users.service...640vm-test-run-index_advisor> server # [ 52.891580] systemd[1]: Stopped target Host and Network Name Lookups.641vm-test-run-index_advisor> server # [ 52.911133] systemd[1]: Stopping Host and Network Name Lookups...642vm-test-run-index_advisor> server # [ 52.927347] systemd[1]: Stopped target User and Group Name Lookups.643vm-test-run-index_advisor> server # [ 52.935389] systemd[1]: Stopping User and Group Name Lookups...644vm-test-run-index_advisor> server # [ 52.957146] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...645vm-test-run-index_advisor> server # [ 53.002824] systemd[1]: nscd.service: Deactivated successfully.646vm-test-run-index_advisor> server # [ 53.017063] systemd[1]: Stopped Name Service Cache Daemon (nsncd).647vm-test-run-index_advisor> server # [ 53.217021] systemd[1]: Starting Name Service Cache Daemon (nsncd)...648vm-test-run-index_advisor> server # [ 53.612014] systemd[1]: linger-users.service: Deactivated successfully.649vm-test-run-index_advisor> server # [ 53.635783] systemd[1]: Finished linger-users.service.650vm-test-run-index_advisor> server # [ 54.170626] systemd[1]: Started Name Service Cache Daemon (nsncd).651vm-test-run-index_advisor> server # [ 54.180143] systemd[1]: Reached target Host and Network Name Lookups.652vm-test-run-index_advisor> server # [ 54.191732] nsncd[698]: Oct 07 20:37:56.736 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"653vm-test-run-index_advisor> server # [ 54.198086] systemd[1]: Reached target User and Group Name Lookups.654vm-test-run-index_advisor> server # [ 54.559510] systemd[1]: Finished resolvconf update.655vm-test-run-index_advisor> server # [ 54.582233] systemd[1]: Reached target Preparation for Network.656vm-test-run-index_advisor> server # [ 54.634808] systemd[1]: Starting DHCP Client...657vm-test-run-index_advisor> server # [ 56.467567] dhcpcd[740]: dhcpcd-10.2.4 starting658vm-test-run-index_advisor> server # [ 56.604221] dhcpcd[764]: dev: loaded udev659vm-test-run-index_advisor> server # [ 57.131948] 8021q: 802.1Q VLAN Support v1.8660vm-test-run-index_advisor> server # [ 57.808205] cfg80211: Loading compiled-in X.509 certificates for regulatory database661vm-test-run-index_advisor> server # [ 58.116480] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'662vm-test-run-index_advisor> server # [ 58.121617] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'663vm-test-run-index_advisor> server # [ 58.149031] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2664vm-test-run-index_advisor> server # [ 58.164150] cfg80211: failed to load regulatory.db665vm-test-run-index_advisor> server: Guest root shell did not produce any data yet...666vm-test-run-index_advisor> server: To debug, enter the VM and run 'systemctl status backdoor.service'.667vm-test-run-index_advisor> server # [ 59.155758] 8021q: adding VLAN 0 to HW filter on device eth0668vm-test-run-index_advisor> server # [ 59.010322] dhcpcd[764]: eth0: waiting for carrier669vm-test-run-index_advisor> server # [ 59.023652] dhcpcd[764]: libudev: received NULL device670vm-test-run-index_advisor> server # [ 59.033053] dhcpcd[764]: libudev: received NULL device671vm-test-run-index_advisor> server # [ 59.037548] dhcpcd[764]: eth0: carrier acquired672vm-test-run-index_advisor> server # [ 59.198147] dhcpcd[764]: DUID 00:01:00:01:32:59:6a:29:52:54:00:12:34:56673vm-test-run-index_advisor> server # [ 59.201585] dhcpcd[764]: eth0: IAID 00:12:34:56674vm-test-run-index_advisor> server # [ 59.204758] dhcpcd[764]: eth0: adding address fe80::5054:ff:fe12:3456675vm-test-run-index_advisor> server # [ 59.523192] dhcpcd[764]: eth0: soliciting a DHCP lease676vm-test-run-index_advisor> server # [ 59.544244] systemd[1]: Found device /dev/hvc0.677vm-test-run-index_advisor> server # [ 59.589541] dhcpcd[764]: eth0: offered 10.0.2.15 from 10.0.2.2678vm-test-run-index_advisor> server # [ 59.629266] dhcpcd[764]: eth0: probing address 10.0.2.15/24679vm-test-run-index_advisor> server # [ 60.341888] systemd[1]: Found device /dev/ttyAMA0.680vm-test-run-index_advisor> server # [ 60.383448] systemd[1]: Started backdoor.service.681vm-test-run-index_advisor> server # [ 60.642910] dhcpcd[764]: eth0: soliciting an IPv6 router682vm-test-run-index_advisor> server # [ 60.656498] dhcpcd[764]: eth0: Router Advertisement from fe80::2683vm-test-run-index_advisor> server # [ 60.668010] dhcpcd[764]: eth0: adding address fec0::5054:ff:fe12:3456/64684vm-test-run-index_advisor> server # [ 60.671049] dhcpcd[764]: eth0: adding route to fec0::/64685vm-test-run-index_advisor> server # [ 60.675777] dhcpcd[764]: eth0: adding default route via fe80::2686vm-test-run-index_advisor> server # connecting to host...687vm-test-run-index_advisor> server: Guest shell says: b'Spawning backdoor root shell...\n'688vm-test-run-index_advisor> server: connected to guest root shell689vm-test-run-index_advisor> server: (connecting took 62.37 seconds)690vm-test-run-index_advisor> server: (finished: waiting for the VM to finish booting, in 62.37 seconds)691vm-test-run-index_advisor> server # [ 63.635804] (udev-worker)[810]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.692vm-test-run-index_advisor> server # [ 63.646886] (udev-worker)[809]: Network interface NamePolicy= disabled on kernel command line.693vm-test-run-index_advisor> server # [ 63.651428] (udev-worker)[810]: Network interface NamePolicy= disabled on kernel command line.694vm-test-run-index_advisor> server # [ 64.199825] systemd[1]: Found device Virtio network device.695vm-test-run-index_advisor> server # [ 64.225416] systemd[1]: Starting Address configuration of eth1...696vm-test-run-index_advisor> server # [ 64.291150] dhcpcd[764]: eth0: IAID 00:12:34:56697vm-test-run-index_advisor> server # [ 64.587730] dhcpcd[764]: eth0: soliciting a DHCP lease698vm-test-run-index_advisor> server # [ 64.596718] dhcpcd[764]: eth0: offered 10.0.2.15 from 10.0.2.2699vm-test-run-index_advisor> server # [ 64.597753] dhcpcd[764]: eth0: probing address 10.0.2.15/24700vm-test-run-index_advisor> server # [ 65.037508] dhcpcd[764]: eth0: soliciting an IPv6 router701vm-test-run-index_advisor> server # [ 65.387054] 8021q: adding VLAN 0 to HW filter on device eth1702vm-test-run-index_advisor> server # [ 65.627360] network-addresses-eth1-start[842]: adding address 192.168.1.1/24... done703vm-test-run-index_advisor> server # [ 65.936803] network-addresses-eth1-start[842]: adding address 2001:db8:1::1/64... done704vm-test-run-index_advisor> server # [ 66.271815] systemd[1]: Finished Address configuration of eth1.705vm-test-run-index_advisor> server # [ 66.334994] systemd[1]: Starting Networking Setup...706vm-test-run-index_advisor> server # [ 66.391181] dhcpcd[764]: eth0: leased 10.0.2.15 for 86400 seconds707vm-test-run-index_advisor> server # [ 66.397671] dhcpcd[764]: eth0: adding route to 10.0.2.0/24708vm-test-run-index_advisor> server # [ 66.403823] dhcpcd[764]: eth0: adding default route via 10.0.2.2709vm-test-run-index_advisor> server # [ 69.859546] systemd[1]: Started DHCP Client.710vm-test-run-index_advisor> server # [ 70.014972] systemd-logind[652]: Watching system buttons on /dev/input/event0 (gpio-keys)711vm-test-run-index_advisor> server # [ 72.033218] systemd[1]: Finished Networking Setup.712vm-test-run-index_advisor> server # [ 72.057458] systemd[1]: Reached target Network.713vm-test-run-index_advisor> server # [ 72.097617] systemd[1]: Starting PostgreSQL Database Server...714vm-test-run-index_advisor> server # [ 72.151325] systemd[1]: Starting Permit User Sessions...715vm-test-run-index_advisor> server # [ 72.790410] systemd[1]: Finished Permit User Sessions.716vm-test-run-index_advisor> server # [ 72.863756] systemd[1]: Started Getty on tty1.717vm-test-run-index_advisor> server # [ 72.871501] systemd[1]: Reached target Login Prompts.718vm-test-run-index_advisor> server # [ 73.594381] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1023]: Initializing database at /var/lib/postgresql/data719vm-test-run-index_advisor> server # [ 75.779673] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The files belonging to this database system will be owned by user "postgres".720vm-test-run-index_advisor> server # [ 75.791588] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: This user must also own the server process.721vm-test-run-index_advisor> server # [ 75.897593] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The database cluster will be initialized with locale "en_US.UTF-8".722vm-test-run-index_advisor> server # [ 75.899788] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The default database encoding has accordingly been set to "UTF8".723vm-test-run-index_advisor> server # [ 75.906725] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: The default text search configuration will be set to "english".724vm-test-run-index_advisor> server # [ 75.913061] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: Data page checksums are enabled.725vm-test-run-index_advisor> server # [ 75.914925] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: fixing permissions on existing directory /var/lib/postgresql/data ... ok726vm-test-run-index_advisor> server # [ 75.915638] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: creating subdirectories ... ok727vm-test-run-index_advisor> server # [ 75.923677] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting dynamic shared memory implementation ... posix728vm-test-run-index_advisor> server # [ 77.178843] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting default max_connections ... 100729vm-test-run-index_advisor> server # [ 78.198802] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting default shared_buffers ... 128MB730vm-test-run-index_advisor> server # [ 82.312731] mousedev: PS/2 mouse device common for all mice731vm-test-run-index_advisor> server # [ 87.127487] systemd-logind[652]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)732vm-test-run-index_advisor> server # [ 90.117478] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: selecting default time zone ... UTC733vm-test-run-index_advisor> server # [ 90.163642] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: creating configuration files ... ok734vm-test-run-index_advisor> server # [ 95.791906] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: running bootstrap script ... ok735vm-test-run-index_advisor> server # [ 110.206867] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: performing post-bootstrap initialization ... ok736vm-test-run-index_advisor> server # [ 112.956018] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: syncing data to disk ... ok737vm-test-run-index_advisor> server # [ 112.966717] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: initdb: warning: enabling "trust" authentication for local connections738vm-test-run-index_advisor> server # [ 112.968749] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: 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.739vm-test-run-index_advisor> server # [ 112.978241] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: Success. You can now start the database server using:740vm-test-run-index_advisor> server # [ 112.989736] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1037]: /nix/store/2bhdbvpk81xy3mkfgqrpc719ip588cba-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start741vm-test-run-index_advisor> server # [ 115.231905] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1023]: Validating PostgreSQL configuration...742vm-test-run-index_advisor> server # [ 115.747120] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1420]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault743vm-test-run-index_advisor> server # [ 116.757130] postgres[1423]: 2026-10-07 20:38:59.292 UTC [1423] LOG: pgaudit extension initialized744vm-test-run-index_advisor> server # [ 118.087691] postgres[1423]: 2026-10-07 20:39:00.602 UTC [1423] LOG: pgsodium primary server secret key loaded745vm-test-run-index_advisor> server # [ 118.820067] postgres[1423]: 2026-10-07 20:39:01.392 UTC [1423] LOG: vault primary server secret key loaded746vm-test-run-index_advisor> server # [ 119.203269] postgres[1423]: 2026-10-07 20:39:01.771 UTC [1423] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit747vm-test-run-index_advisor> server # [ 119.216519] postgres[1423]: 2026-10-07 20:39:01.789 UTC [1423] LOG: listening on IPv4 address "0.0.0.0", port 5432748vm-test-run-index_advisor> server # [ 119.222646] postgres[1423]: 2026-10-07 20:39:01.792 UTC [1423] LOG: listening on IPv6 address "::", port 5432749vm-test-run-index_advisor> server # [ 119.240017] postgres[1423]: 2026-10-07 20:39:01.811 UTC [1423] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"750vm-test-run-index_advisor> server # [ 119.357220] postgres[1457]: 2026-10-07 20:39:01.914 UTC [1457] LOG: database system was shut down at 2026-10-07 20:38:52 UTC751vm-test-run-index_advisor> server # [ 119.591737] postgres[1423]: 2026-10-07 20:39:02.162 UTC [1423] LOG: database system is ready to accept connections752vm-test-run-index_advisor> server # [ 119.677977] postgres[1460]: 2026-10-07 20:39:02.181 UTC [1460] LOG: TimescaleDB background worker launcher connected to shared catalogs753vm-test-run-index_advisor> server # [ 119.682484] postgres[1461]: 2026-10-07 20:39:02.181 UTC [1461] FATAL: role "postgres" does not exist754vm-test-run-index_advisor> server # [ 119.699097] systemd[1]: Started PostgreSQL Database Server.755vm-test-run-index_advisor> server # [ 119.716788] systemd[1]: Starting Supabase Database Initialization...756vm-test-run-index_advisor> server # [ 119.817476] postgres[1423]: 2026-10-07 20:39:02.384 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1461) exited with exit code 1757vm-test-run-index_advisor> server # [ 120.131047] postgres[1462]: 2026-10-07 20:39:02.660 UTC [1462] LOG: pg_cron scheduler started758vm-test-run-index_advisor> server # [ 120.362618] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Waiting for PostgreSQL to be ready...759vm-test-run-index_advisor> server # [ 121.009056] postgres[1475]: 2026-10-07 20:39:03.545 UTC [1475] FATAL: role "postgres" does not exist760vm-test-run-index_advisor> server # [ 121.188176] postgres[1423]: 2026-10-07 20:39:03.703 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1475) exited with exit code 1761vm-test-run-index_advisor> server # [ 121.563874] postgres[1479]: ::1 2026-10-07 20:39:04.098 UTC [1479] root@root FATAL: role "root" does not exist762vm-test-run-index_advisor> server # [ 121.646933] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: PostgreSQL is ready763vm-test-run-index_advisor> server # [ 121.655287] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Creating postgres role...764vm-test-run-index_advisor> server # [ 122.261532] postgres[1494]: 2026-10-07 20:39:04.787 UTC [1494] FATAL: role "postgres" does not exist765vm-test-run-index_advisor> server # [ 122.339387] postgres[1423]: 2026-10-07 20:39:04.898 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1494) exited with exit code 1766vm-test-run-index_advisor> server # [ 122.439201] postgres[1493]: ::1 2026-10-07 20:39:04.981 UTC [1493] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN;767vm-test-run-index_advisor> server # [ 122.499744] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1484]: CREATE ROLE768vm-test-run-index_advisor> server # [ 122.931776] postgres[1502]: ::1 2026-10-07 20:39:05.487 UTC [1502] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres;769vm-test-run-index_advisor> server # [ 123.000878] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1500]: ALTER DATABASE770vm-test-run-index_advisor> server # [ 123.054764] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000000-initial-schema.sql771vm-test-run-index_advisor> server # [ 123.402067] postgres[1504]: ::1 2026-10-07 20:39:05.960 UTC [1504] postgres@postgres LOG: statement: create publication supabase_realtime;772vm-test-run-index_advisor> server # [ 123.423333] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE PUBLICATION773vm-test-run-index_advisor> server # [ 123.429191] postgres[1504]: ::1 2026-10-07 20:39:05.992 UTC [1504] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls;774vm-test-run-index_advisor> server # [ 123.441778] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE775vm-test-run-index_advisor> server # [ 123.445886] postgres[1504]: ::1 2026-10-07 20:39:06.015 UTC [1504] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication;776vm-test-run-index_advisor> server # [ 123.456482] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE777vm-test-run-index_advisor> server # [ 123.460357] postgres[1504]: ::1 2026-10-07 20:39:06.032 UTC [1504] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls;778vm-test-run-index_advisor> server # [ 123.471081] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE779vm-test-run-index_advisor> server # [ 123.477665] postgres[1504]: ::1 2026-10-07 20:39:06.046 UTC [1504] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin;780vm-test-run-index_advisor> server # [ 123.484510] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE781vm-test-run-index_advisor> server # [ 123.488651] postgres[1504]: ::1 2026-10-07 20:39:06.060 UTC [1504] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin;782vm-test-run-index_advisor> server # [ 123.499574] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT783vm-test-run-index_advisor> server # [ 123.502853] postgres[1504]: ::1 2026-10-07 20:39:06.075 UTC [1504] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls;784vm-test-run-index_advisor> server # [ 123.513453] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE785vm-test-run-index_advisor> server # [ 123.517175] postgres[1504]: ::1 2026-10-07 20:39:06.087 UTC [1504] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user;786vm-test-run-index_advisor> server # [ 123.526311] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE787vm-test-run-index_advisor> server # [ 123.530931] postgres[1504]: ::1 2026-10-07 20:39:06.100 UTC [1504] postgres@postgres LOG: statement: create schema if not exists extensions;788vm-test-run-index_advisor> server # [ 123.541314] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE SCHEMA789vm-test-run-index_advisor> server # [ 123.542297] postgres[1504]: ::1 2026-10-07 20:39:06.112 UTC [1504] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions;790vm-test-run-index_advisor> server # [ 126.790228] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE EXTENSION791vm-test-run-index_advisor> server # [ 126.795646] postgres[1504]: ::1 2026-10-07 20:39:09.346 UTC [1504] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions;792vm-test-run-index_advisor> server # [ 127.818664] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE EXTENSION793vm-test-run-index_advisor> server # [ 127.829649] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE794vm-test-run-index_advisor> server # [ 127.839685] postgres[1504]: ::1 2026-10-07 20:39:10.392 UTC [1504] postgres@postgres LOG: statement: create role anon nologin noinherit;795vm-test-run-index_advisor> server # [ 127.845654] postgres[1504]: ::1 2026-10-07 20:39:10.402 UTC [1504] postgres@postgres LOG: statement: create role authenticated nologin noinherit;796vm-test-run-index_advisor> server # [ 127.847061] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE797vm-test-run-index_advisor> server # [ 127.851305] postgres[1504]: ::1 2026-10-07 20:39:10.422 UTC [1504] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls;798vm-test-run-index_advisor> server # [ 127.860034] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE799vm-test-run-index_advisor> server # [ 127.861893] postgres[1504]: ::1 2026-10-07 20:39:10.432 UTC [1504] postgres@postgres LOG: statement: create user authenticator noinherit;800vm-test-run-index_advisor> server # [ 127.871455] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: CREATE ROLE801vm-test-run-index_advisor> server # [ 127.874804] postgres[1504]: ::1 2026-10-07 20:39:10.446 UTC [1504] postgres@postgres LOG: statement: grant anon to authenticator;802vm-test-run-index_advisor> server # [ 127.886277] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE803vm-test-run-index_advisor> server # [ 127.889488] postgres[1504]: ::1 2026-10-07 20:39:10.461 UTC [1504] postgres@postgres LOG: statement: grant authenticated to authenticator;804vm-test-run-index_advisor> server # [ 127.900096] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE805vm-test-run-index_advisor> server # [ 127.902784] postgres[1504]: ::1 2026-10-07 20:39:10.474 UTC [1504] postgres@postgres LOG: statement: grant service_role to authenticator;806vm-test-run-index_advisor> server # [ 127.913784] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE807vm-test-run-index_advisor> server # [ 127.915698] postgres[1504]: ::1 2026-10-07 20:39:10.488 UTC [1504] postgres@postgres LOG: statement: grant supabase_admin to authenticator;808vm-test-run-index_advisor> server # [ 127.927970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT ROLE809vm-test-run-index_advisor> server # [ 127.934141] postgres[1504]: ::1 2026-10-07 20:39:10.503 UTC [1504] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role;810vm-test-run-index_advisor> server # [ 127.942536] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT811vm-test-run-index_advisor> server # [ 127.945951] postgres[1504]: ::1 2026-10-07 20:39:10.517 UTC [1504] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;812vm-test-run-index_advisor> server # [ 127.967230] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES813vm-test-run-index_advisor> server # [ 127.974012] postgres[1504]: ::1 2026-10-07 20:39:10.544 UTC [1504] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;814vm-test-run-index_advisor> server # [ 127.985735] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES815vm-test-run-index_advisor> server # [ 127.987214] postgres[1504]: ::1 2026-10-07 20:39:10.559 UTC [1504] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;816vm-test-run-index_advisor> server # [ 128.001170] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES817vm-test-run-index_advisor> server # [ 128.002227] postgres[1504]: ::1 2026-10-07 20:39:10.572 UTC [1504] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role;818vm-test-run-index_advisor> server # [ 128.017542] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: GRANT819vm-test-run-index_advisor> server # [ 128.019765] postgres[1504]: ::1 2026-10-07 20:39:10.585 UTC [1504] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions;820vm-test-run-index_advisor> server # [ 128.024011] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE821vm-test-run-index_advisor> server # [ 128.030949] postgres[1504]: ::1 2026-10-07 20:39:10.600 UTC [1504] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all822vm-test-run-index_advisor> server # [ 128.033188] postgres[1504]: on sequences to postgres, anon, authenticated, service_role;823vm-test-run-index_advisor> server # [ 128.040068] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES824vm-test-run-index_advisor> server # [ 128.042045] postgres[1504]: ::1 2026-10-07 20:39:10.612 UTC [1504] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all825vm-test-run-index_advisor> server # [ 128.044005] postgres[1504]: on tables to postgres, anon, authenticated, service_role;826vm-test-run-index_advisor> server # [ 128.056484] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES827vm-test-run-index_advisor> server # [ 128.057347] postgres[1504]: ::1 2026-10-07 20:39:10.629 UTC [1504] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all828vm-test-run-index_advisor> server # [ 128.057815] postgres[1504]: on functions to postgres, anon, authenticated, service_role;829vm-test-run-index_advisor> server # [ 128.069554] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER DEFAULT PRIVILEGES830vm-test-run-index_advisor> server # [ 128.072479] postgres[1504]: ::1 2026-10-07 20:39:10.640 UTC [1504] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s';831vm-test-run-index_advisor> server # [ 128.080183] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE832vm-test-run-index_advisor> server # [ 128.083186] postgres[1504]: ::1 2026-10-07 20:39:10.655 UTC [1504] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s';833vm-test-run-index_advisor> server # [ 128.092674] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1503]: ALTER ROLE834vm-test-run-index_advisor> server # [ 128.150314] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000001-auth-schema.sql835vm-test-run-index_advisor> server # [ 128.760757] postgres[1542]: ::1 2026-10-07 20:39:11.309 UTC [1542] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;836vm-test-run-index_advisor> server # [ 128.763614] postgres[1542]: ::1 2026-10-07 20:39:11.330 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.users (837vm-test-run-index_advisor> server # [ 128.773531] postgres[1542]: instance_id uuid NULL,838vm-test-run-index_advisor> server # [ 128.774238] postgres[1542]: id uuid NOT NULL UNIQUE,839vm-test-run-index_advisor> server # [ 128.774624] postgres[1542]: aud varchar(255) NULL,840vm-test-run-index_advisor> server # [ 128.774969] postgres[1542]: "role" varchar(255) NULL,841vm-test-run-index_advisor> server # [ 128.775239] postgres[1542]: email varchar(255) NULL UNIQUE,842vm-test-run-index_advisor> server # [ 128.775497] postgres[1542]: encrypted_password varchar(255) NULL,843vm-test-run-index_advisor> server # [ 128.775767] postgres[1542]: confirmed_at timestamptz NULL,844vm-test-run-index_advisor> server # [ 128.785986] postgres[1542]: invited_at timestamptz NULL,845vm-test-run-index_advisor> server # [ 128.787315] postgres[1542]: confirmation_token varchar(255) NULL,846vm-test-run-index_advisor> server # [ 128.794992] postgres[1542]: confirmation_sent_at timestamptz NULL,847vm-test-run-index_advisor> server # [ 128.795788] postgres[1542]: recovery_token varchar(255) NULL,848vm-test-run-index_advisor> server # [ 128.796122] postgres[1542]: recovery_sent_at timestamptz NULL,849vm-test-run-index_advisor> server # [ 128.796406] postgres[1542]: email_change_token varchar(255) NULL,850vm-test-run-index_advisor> server # [ 128.803096] postgres[1542]: email_change varchar(255) NULL,851vm-test-run-index_advisor> server # [ 128.803713] postgres[1542]: email_change_sent_at timestamptz NULL,852vm-test-run-index_advisor> server # [ 128.810158] postgres[1542]: last_sign_in_at timestamptz NULL,853vm-test-run-index_advisor> server # [ 128.811099] postgres[1542]: raw_app_meta_data jsonb NULL,854vm-test-run-index_advisor> server # [ 128.811670] postgres[1542]: raw_user_meta_data jsonb NULL,855vm-test-run-index_advisor> server # [ 128.818197] postgres[1542]: is_super_admin bool NULL,856vm-test-run-index_advisor> server # [ 128.819781] postgres[1542]: created_at timestamptz NULL,857vm-test-run-index_advisor> server # [ 128.826939] postgres[1542]: updated_at timestamptz NULL,858vm-test-run-index_advisor> server # [ 128.828018] postgres[1542]: CONSTRAINT users_pkey PRIMARY KEY (id)859vm-test-run-index_advisor> server # [ 128.835516] postgres[1542]: );860vm-test-run-index_advisor> server # [ 128.858857] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE SCHEMA861vm-test-run-index_advisor> server # [ 128.999659] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE862vm-test-run-index_advisor> server # [ 129.005955] postgres[1542]: ::1 2026-10-07 20:39:11.575 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);863vm-test-run-index_advisor> server # [ 129.107430] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX864vm-test-run-index_advisor> server # [ 129.138078] postgres[1542]: ::1 2026-10-07 20:39:11.698 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);865vm-test-run-index_advisor> server # [ 129.202715] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX866vm-test-run-index_advisor> server # [ 129.210425] postgres[1542]: ::1 2026-10-07 20:39:11.778 UTC [1542] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';867vm-test-run-index_advisor> server # [ 129.227419] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT868vm-test-run-index_advisor> server # [ 129.236447] postgres[1542]: ::1 2026-10-07 20:39:11.806 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens (869vm-test-run-index_advisor> server # [ 129.238653] postgres[1542]: instance_id uuid NULL,870vm-test-run-index_advisor> server # [ 129.249149] postgres[1542]: id bigserial NOT NULL,871vm-test-run-index_advisor> server # [ 129.250642] postgres[1542]: "token" varchar(255) NULL,872vm-test-run-index_advisor> server # [ 129.251469] postgres[1542]: user_id varchar(255) NULL,873vm-test-run-index_advisor> server # [ 129.263983] postgres[1542]: revoked bool NULL,874vm-test-run-index_advisor> server # [ 129.270586] postgres[1542]: created_at timestamptz NULL,875vm-test-run-index_advisor> server # [ 129.271654] postgres[1542]: updated_at timestamptz NULL,876vm-test-run-index_advisor> server # [ 129.279910] postgres[1542]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)877vm-test-run-index_advisor> server # [ 129.289682] postgres[1542]: );878vm-test-run-index_advisor> server # [ 129.430505] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE879vm-test-run-index_advisor> server # [ 129.450515] postgres[1542]: ::1 2026-10-07 20:39:12.018 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);880vm-test-run-index_advisor> server # [ 129.506732] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX881vm-test-run-index_advisor> server # [ 129.515066] postgres[1542]: ::1 2026-10-07 20:39:12.081 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);882vm-test-run-index_advisor> server # [ 129.587049] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX883vm-test-run-index_advisor> server # [ 129.601498] postgres[1542]: ::1 2026-10-07 20:39:12.154 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);884vm-test-run-index_advisor> server # [ 129.640012] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX885vm-test-run-index_advisor> server # [ 129.649569] postgres[1542]: ::1 2026-10-07 20:39:12.210 UTC [1542] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';886vm-test-run-index_advisor> server # [ 129.651621] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT887vm-test-run-index_advisor> server # [ 129.661376] postgres[1542]: ::1 2026-10-07 20:39:12.220 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.instances (888vm-test-run-index_advisor> server # [ 129.671567] postgres[1542]: id uuid NOT NULL,889vm-test-run-index_advisor> server # [ 129.690683] postgres[1542]: uuid uuid NULL,890vm-test-run-index_advisor> server # [ 129.697377] postgres[1542]: raw_base_config text NULL,891vm-test-run-index_advisor> server # [ 129.709096] postgres[1542]: created_at timestamptz NULL,892vm-test-run-index_advisor> server # [ 129.710658] postgres[1542]: updated_at timestamptz NULL,893vm-test-run-index_advisor> server # [ 129.712022] postgres[1542]: CONSTRAINT instances_pkey PRIMARY KEY (id)894vm-test-run-index_advisor> server # [ 129.712456] postgres[1542]: );895vm-test-run-index_advisor> server # [ 129.828203] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE896vm-test-run-index_advisor> server # [ 129.879701] postgres[1542]: ::1 2026-10-07 20:39:12.396 UTC [1542] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';897vm-test-run-index_advisor> server # [ 129.889014] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT898vm-test-run-index_advisor> server # [ 129.890520] postgres[1542]: ::1 2026-10-07 20:39:12.414 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries (899vm-test-run-index_advisor> server # [ 129.892144] postgres[1542]: instance_id uuid NULL,900vm-test-run-index_advisor> server # [ 129.900497] postgres[1542]: id uuid NOT NULL,901vm-test-run-index_advisor> server # [ 129.903984] postgres[1542]: payload json NULL,902vm-test-run-index_advisor> server # [ 129.911844] postgres[1542]: created_at timestamptz NULL,903vm-test-run-index_advisor> server # [ 129.914285] postgres[1542]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)904vm-test-run-index_advisor> server # [ 129.925021] postgres[1542]: );905vm-test-run-index_advisor> server # [ 130.000284] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE906vm-test-run-index_advisor> server # [ 130.018024] postgres[1542]: ::1 2026-10-07 20:39:12.584 UTC [1542] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);907vm-test-run-index_advisor> server # [ 130.051146] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE INDEX908vm-test-run-index_advisor> server # [ 130.057358] postgres[1542]: ::1 2026-10-07 20:39:12.625 UTC [1542] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';909vm-test-run-index_advisor> server # [ 130.062266] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT910vm-test-run-index_advisor> server # [ 130.063306] postgres[1542]: ::1 2026-10-07 20:39:12.635 UTC [1542] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations (911vm-test-run-index_advisor> server # [ 130.064114] postgres[1542]: "version" varchar(255) NOT NULL,912vm-test-run-index_advisor> server # [ 130.065437] postgres[1542]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")913vm-test-run-index_advisor> server # [ 130.066149] postgres[1542]: );914vm-test-run-index_advisor> server # [ 130.101110] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE TABLE915vm-test-run-index_advisor> server # [ 130.102984] postgres[1542]: ::1 2026-10-07 20:39:12.674 UTC [1542] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';916vm-test-run-index_advisor> server # [ 130.111675] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: COMMENT917vm-test-run-index_advisor> server # [ 130.131777] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: INSERT 0 7918vm-test-run-index_advisor> server # [ 130.135400] postgres[1542]: ::1 2026-10-07 20:39:12.706 UTC [1542] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$919vm-test-run-index_advisor> server # [ 130.137771] postgres[1542]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;920vm-test-run-index_advisor> server # [ 130.138496] postgres[1542]: $$ language sql stable;921vm-test-run-index_advisor> server # [ 130.149701] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE FUNCTION922vm-test-run-index_advisor> server # [ 130.158219] postgres[1542]: ::1 2026-10-07 20:39:12.728 UTC [1542] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$923vm-test-run-index_advisor> server # [ 130.159726] postgres[1542]: select nullif(current_setting('request.jwt.claim.role', true), '')::text;924vm-test-run-index_advisor> server # [ 130.166276] postgres[1542]: $$ language sql stable;925vm-test-run-index_advisor> server # [ 130.174896] postgres[1542]: ::1 2026-10-07 20:39:12.743 UTC [1542] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$926vm-test-run-index_advisor> server # [ 130.175874] postgres[1542]: select nullif(current_setting('request.jwt.claim.email', true), '')::text;927vm-test-run-index_advisor> server # [ 130.179352] postgres[1542]: $$ language sql stable;928vm-test-run-index_advisor> server # [ 130.190031] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE FUNCTION929vm-test-run-index_advisor> server # [ 130.190918] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE FUNCTION930vm-test-run-index_advisor> server # [ 130.191537] postgres[1542]: ::1 2026-10-07 20:39:12.757 UTC [1542] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;931vm-test-run-index_advisor> server # [ 130.193059] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT932vm-test-run-index_advisor> server # [ 130.198883] postgres[1542]: ::1 2026-10-07 20:39:12.767 UTC [1542] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;933vm-test-run-index_advisor> server # [ 130.209577] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: CREATE ROLE934vm-test-run-index_advisor> server # [ 130.210567] postgres[1542]: ::1 2026-10-07 20:39:12.778 UTC [1542] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;935vm-test-run-index_advisor> server # [ 130.219928] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT936vm-test-run-index_advisor> server # [ 130.229661] postgres[1542]: ::1 2026-10-07 20:39:12.794 UTC [1542] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;937vm-test-run-index_advisor> server # [ 130.237260] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT938vm-test-run-index_advisor> server # [ 130.238107] postgres[1542]: ::1 2026-10-07 20:39:12.810 UTC [1542] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;939vm-test-run-index_advisor> server # [ 130.250808] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: GRANT940vm-test-run-index_advisor> server # [ 130.253625] postgres[1542]: ::1 2026-10-07 20:39:12.826 UTC [1542] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth";941vm-test-run-index_advisor> server # [ 130.259949] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER ROLE942vm-test-run-index_advisor> server # [ 130.264275] postgres[1542]: ::1 2026-10-07 20:39:12.835 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin;943vm-test-run-index_advisor> server # [ 130.285602] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE944vm-test-run-index_advisor> server # [ 130.290196] postgres[1542]: ::1 2026-10-07 20:39:12.855 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;945vm-test-run-index_advisor> server # [ 130.303126] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE946vm-test-run-index_advisor> server # [ 130.303996] postgres[1542]: ::1 2026-10-07 20:39:12.876 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;947vm-test-run-index_advisor> server # [ 130.319124] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE948vm-test-run-index_advisor> server # [ 130.320001] postgres[1542]: ::1 2026-10-07 20:39:12.892 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;949vm-test-run-index_advisor> server # [ 130.327886] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE950vm-test-run-index_advisor> server # [ 130.331985] postgres[1542]: ::1 2026-10-07 20:39:12.904 UTC [1542] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;951vm-test-run-index_advisor> server # [ 130.346113] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1534]: ALTER TABLE952vm-test-run-index_advisor> server # [ 130.391590] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000002-storage-schema.sql953vm-test-run-index_advisor> server # [ 130.767182] postgres[1564]: ::1 2026-10-07 20:39:13.325 UTC [1564] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;954vm-test-run-index_advisor> server # [ 130.798316] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: CREATE SCHEMA955vm-test-run-index_advisor> server # [ 130.803637] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: CREATE ROLE956vm-test-run-index_advisor> server # [ 130.810782] postgres[1564]: ::1 2026-10-07 20:39:13.358 UTC [1564] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;957vm-test-run-index_advisor> server # [ 130.811394] postgres[1564]: ::1 2026-10-07 20:39:13.371 UTC [1564] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage";958vm-test-run-index_advisor> server # [ 130.819078] postgres[1564]: ::1 2026-10-07 20:39:13.386 UTC [1564] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;959vm-test-run-index_advisor> server # [ 130.829589] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: ALTER ROLE960vm-test-run-index_advisor> server # [ 130.833772] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: GRANT961vm-test-run-index_advisor> server # [ 130.884861] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1560]: DO962vm-test-run-index_advisor> server # [ 130.941404] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000003-post-setup.sql963vm-test-run-index_advisor> server # [ 131.331917] postgres[1567]: ::1 2026-10-07 20:39:13.886 UTC [1567] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;964vm-test-run-index_advisor> server # [ 131.344256] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: ALTER ROLE965vm-test-run-index_advisor> server # [ 131.350944] postgres[1567]: ::1 2026-10-07 20:39:13.923 UTC [1567] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;966vm-test-run-index_advisor> server # [ 131.364107] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: ALTER ROLE967vm-test-run-index_advisor> server # [ 131.366319] postgres[1567]: ::1 2026-10-07 20:39:13.937 UTC [1567] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()968vm-test-run-index_advisor> server # [ 131.367831] postgres[1567]: RETURNS event_trigger969vm-test-run-index_advisor> server # [ 131.368849] postgres[1567]: LANGUAGE plpgsql970vm-test-run-index_advisor> server # [ 131.372901] postgres[1567]: AS $$971vm-test-run-index_advisor> server # [ 131.382258] postgres[1567]: DECLARE972vm-test-run-index_advisor> server # [ 131.398306] postgres[1567]: schema_is_cron bool;973vm-test-run-index_advisor> server # [ 131.399997] postgres[1567]: BEGIN974vm-test-run-index_advisor> server # [ 131.401763] postgres[1567]: schema_is_cron = (975vm-test-run-index_advisor> server # [ 131.402900] postgres[1567]: SELECT n.nspname = 'cron'976vm-test-run-index_advisor> server # [ 131.403654] postgres[1567]: FROM pg_event_trigger_ddl_commands() AS ev977vm-test-run-index_advisor> server # [ 131.408021] postgres[1567]: LEFT JOIN pg_catalog.pg_namespace AS n978vm-test-run-index_advisor> server # [ 131.413535] postgres[1567]: ON ev.objid = n.oid979vm-test-run-index_advisor> server # [ 131.418901] postgres[1567]: );980vm-test-run-index_advisor> server # [ 131.419786] postgres[1567]:981vm-test-run-index_advisor> server # [ 131.427011] postgres[1567]: IF schema_is_cron982vm-test-run-index_advisor> server # [ 131.429888] postgres[1567]: THEN983vm-test-run-index_advisor> server # [ 131.430530] postgres[1567]: grant usage on schema cron to postgres with grant option;984vm-test-run-index_advisor> server # [ 131.431081] postgres[1567]:985vm-test-run-index_advisor> server # [ 131.431616] postgres[1567]: alter default privileges in schema cron grant all on tables to postgres with grant option;986vm-test-run-index_advisor> server # [ 131.431941] postgres[1567]: alter default privileges in schema cron grant all on functions to postgres with grant option;987vm-test-run-index_advisor> server # [ 131.433214] postgres[1567]: alter default privileges in schema cron grant all on sequences to postgres with grant option;988vm-test-run-index_advisor> server # [ 131.434210] postgres[1567]:989vm-test-run-index_advisor> server # [ 131.435120] postgres[1567]: alter default privileges for user supabase_admin in schema cron grant all990vm-test-run-index_advisor> server # [ 131.435716] postgres[1567]: on sequences to postgres with grant option;991vm-test-run-index_advisor> server # [ 131.436041] postgres[1567]: alter default privileges for user supabase_admin in schema cron grant all992vm-test-run-index_advisor> server # [ 131.437145] postgres[1567]: on tables to postgres with grant option;993vm-test-run-index_advisor> server # [ 131.438296] postgres[1567]: alter default privileges for user supabase_admin in schema cron grant all994vm-test-run-index_advisor> server # [ 131.438823] postgres[1567]: on functions to postgres with grant option;995vm-test-run-index_advisor> server # [ 131.439224] postgres[1567]:996vm-test-run-index_advisor> server # [ 131.440159] postgres[1567]: grant all privileges on all tables in schema cron to postgres with grant option;997vm-test-run-index_advisor> server # [ 131.441819] postgres[1567]:998vm-test-run-index_advisor> server # [ 131.443464] postgres[1567]: END IF;999vm-test-run-index_advisor> server # [ 131.443910] postgres[1567]:1000vm-test-run-index_advisor> server # [ 131.447775] postgres[1567]: END;1001vm-test-run-index_advisor> server # [ 131.453248] postgres[1567]: $$;1002vm-test-run-index_advisor> server # [ 131.461636] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE FUNCTION1003vm-test-run-index_advisor> server # [ 131.467284] postgres[1567]: ::1 2026-10-07 20:39:14.036 UTC [1567] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')1004vm-test-run-index_advisor> server # [ 131.468087] postgres[1567]: EXECUTE PROCEDURE extensions.grant_pg_cron_access();1005vm-test-run-index_advisor> server # [ 131.479016] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE EVENT TRIGGER1006vm-test-run-index_advisor> server # [ 131.489506] postgres[1567]: ::1 2026-10-07 20:39:14.052 UTC [1567] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';1007vm-test-run-index_advisor> server # [ 131.495175] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: COMMENT1008vm-test-run-index_advisor> server # [ 131.499706] postgres[1567]: ::1 2026-10-07 20:39:14.069 UTC [1567] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()1009vm-test-run-index_advisor> server # [ 131.505045] postgres[1567]: RETURNS event_trigger1010vm-test-run-index_advisor> server # [ 131.506488] postgres[1567]: LANGUAGE plpgsql1011vm-test-run-index_advisor> server # [ 131.506840] postgres[1567]: AS $$1012vm-test-run-index_advisor> server # [ 131.507099] postgres[1567]: BEGIN1013vm-test-run-index_advisor> server # [ 131.507409] postgres[1567]: IF EXISTS (1014vm-test-run-index_advisor> server # [ 131.507702] postgres[1567]: SELECT 11015vm-test-run-index_advisor> server # [ 131.509771] postgres[1567]: FROM pg_event_trigger_ddl_commands() AS ev1016vm-test-run-index_advisor> server # [ 131.510587] postgres[1567]: JOIN pg_extension AS ext1017vm-test-run-index_advisor> server # [ 131.511417] postgres[1567]: ON ev.objid = ext.oid1018vm-test-run-index_advisor> server # [ 131.511730] postgres[1567]: WHERE ext.extname = 'pg_net'1019vm-test-run-index_advisor> server # [ 131.512451] postgres[1567]: )1020vm-test-run-index_advisor> server # [ 131.513143] postgres[1567]: THEN1021vm-test-run-index_advisor> server # [ 131.515558] postgres[1567]: IF NOT EXISTS (1022vm-test-run-index_advisor> server # [ 131.516045] postgres[1567]: SELECT 11023vm-test-run-index_advisor> server # [ 131.516730] postgres[1567]: FROM pg_roles1024vm-test-run-index_advisor> server # [ 131.522652] postgres[1567]: WHERE rolname = 'supabase_functions_admin'1025vm-test-run-index_advisor> server # [ 131.523202] postgres[1567]: )1026vm-test-run-index_advisor> server # [ 131.523478] postgres[1567]: THEN1027vm-test-run-index_advisor> server # [ 131.524773] postgres[1567]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1028vm-test-run-index_advisor> server # [ 131.526619] postgres[1567]: END IF;1029vm-test-run-index_advisor> server # [ 131.527450] postgres[1567]:1030vm-test-run-index_advisor> server # [ 131.529938] postgres[1567]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1031vm-test-run-index_advisor> server # [ 131.531898] postgres[1567]:1032vm-test-run-index_advisor> server # [ 131.532461] postgres[1567]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1033vm-test-run-index_advisor> server # [ 131.534845] postgres[1567]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1034vm-test-run-index_advisor> server # [ 131.539260] postgres[1567]:1035vm-test-run-index_advisor> server # [ 131.539731] postgres[1567]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1036vm-test-run-index_advisor> server # [ 131.541684] postgres[1567]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1037vm-test-run-index_advisor> server # [ 131.543605] postgres[1567]:1038vm-test-run-index_advisor> server # [ 131.545564] postgres[1567]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1039vm-test-run-index_advisor> server # [ 131.546523] postgres[1567]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1040vm-test-run-index_advisor> server # [ 131.550737] postgres[1567]:1041vm-test-run-index_advisor> server # [ 131.551299] postgres[1567]: 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;1042vm-test-run-index_advisor> server # [ 131.552606] postgres[1567]: 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;1043vm-test-run-index_advisor> server # [ 131.553442] postgres[1567]: END IF;1044vm-test-run-index_advisor> server # [ 131.559152] postgres[1567]: END;1045vm-test-run-index_advisor> server # [ 131.569438] postgres[1567]: $$;1046vm-test-run-index_advisor> server # [ 131.578183] postgres[1567]: ::1 2026-10-07 20:39:14.092 UTC [1567] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1047vm-test-run-index_advisor> server # [ 131.579750] postgres[1567]: ::1 2026-10-07 20:39:14.145 UTC [1567] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1048vm-test-run-index_advisor> server # [ 131.582824] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE FUNCTION1049vm-test-run-index_advisor> server # [ 131.585978] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: COMMENT1050vm-test-run-index_advisor> server # [ 131.595306] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: DO1051vm-test-run-index_advisor> server # [ 131.598086] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: CREATE ROLE1052vm-test-run-index_advisor> server # [ 131.599194] postgres[1567]: ::1 2026-10-07 20:39:14.161 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1053vm-test-run-index_advisor> server # [ 131.601558] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1054vm-test-run-index_advisor> server # [ 131.603677] postgres[1567]: ::1 2026-10-07 20:39:14.176 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1055vm-test-run-index_advisor> server # [ 131.614293] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1056vm-test-run-index_advisor> server # [ 131.615537] postgres[1567]: ::1 2026-10-07 20:39:14.187 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1057vm-test-run-index_advisor> server # [ 131.624404] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1058vm-test-run-index_advisor> server # [ 131.629011] postgres[1567]: ::1 2026-10-07 20:39:14.199 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1059vm-test-run-index_advisor> server # [ 131.647626] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1060vm-test-run-index_advisor> server # [ 131.650733] postgres[1567]: ::1 2026-10-07 20:39:14.219 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1061vm-test-run-index_advisor> server # [ 131.655747] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1062vm-test-run-index_advisor> server # [ 131.662141] postgres[1567]: ::1 2026-10-07 20:39:14.228 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1063vm-test-run-index_advisor> server # [ 131.676730] postgres[1567]: ::1 2026-10-07 20:39:14.248 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1064vm-test-run-index_advisor> server # [ 131.679223] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1065vm-test-run-index_advisor> server # [ 131.680457] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1066vm-test-run-index_advisor> server # [ 131.692118] postgres[1567]: ::1 2026-10-07 20:39:14.258 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1067vm-test-run-index_advisor> server # [ 131.712614] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1068vm-test-run-index_advisor> server # [ 131.714651] postgres[1567]: ::1 2026-10-07 20:39:14.285 UTC [1567] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1069vm-test-run-index_advisor> server # [ 131.769092] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: GRANT1070vm-test-run-index_advisor> server # [ 131.793616] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1566]: DO1071vm-test-run-index_advisor> server # [ 131.862604] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running pgbouncer auth schema...1072vm-test-run-index_advisor> server # [ 132.383468] postgres[1574]: ::1 2026-10-07 20:39:14.938 UTC [1574] postgres@postgres LOG: statement: CREATE USER pgbouncer;1073vm-test-run-index_advisor> server # [ 132.408335] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: CREATE ROLE1074vm-test-run-index_advisor> server # [ 132.429933] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: REVOKE1075vm-test-run-index_advisor> server # [ 132.430864] postgres[1574]: ::1 2026-10-07 20:39:14.976 UTC [1574] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1076vm-test-run-index_advisor> server # [ 132.431997] postgres[1574]: ::1 2026-10-07 20:39:14.987 UTC [1574] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1077vm-test-run-index_advisor> server # [ 132.585525] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: CREATE SCHEMA1078vm-test-run-index_advisor> server # [ 132.589768] postgres[1574]: ::1 2026-10-07 20:39:15.160 UTC [1574] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1079vm-test-run-index_advisor> server # [ 132.591498] postgres[1574]: RETURNS TABLE(username TEXT, password TEXT) AS1080vm-test-run-index_advisor> server # [ 132.606434] postgres[1574]: $$1081vm-test-run-index_advisor> server # [ 132.615382] postgres[1574]: BEGIN1082vm-test-run-index_advisor> server # [ 132.620932] postgres[1574]: RAISE WARNING 'PgBouncer auth request: %', p_usename;1083vm-test-run-index_advisor> server # [ 132.624095] postgres[1574]:1084vm-test-run-index_advisor> server # [ 132.653201] postgres[1574]: RETURN QUERY1085vm-test-run-index_advisor> server # [ 132.659106] postgres[1574]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1086vm-test-run-index_advisor> server # [ 132.674638] postgres[1574]: WHERE usename = p_usename;1087vm-test-run-index_advisor> server # [ 132.675479] postgres[1574]: END;1088vm-test-run-index_advisor> server # [ 132.675974] postgres[1574]: $$ LANGUAGE plpgsql1089vm-test-run-index_advisor> server # [ 132.676305] postgres[1574]: SET search_path = ''1090vm-test-run-index_advisor> server # [ 132.676577] postgres[1574]: SECURITY DEFINER;1091vm-test-run-index_advisor> server # [ 132.690254] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: CREATE FUNCTION1092vm-test-run-index_advisor> server # [ 132.691847] postgres[1574]: ::1 2026-10-07 20:39:15.263 UTC [1574] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1093vm-test-run-index_advisor> server # [ 132.709759] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: REVOKE1094vm-test-run-index_advisor> server # [ 132.725808] postgres[1574]: ::1 2026-10-07 20:39:15.286 UTC [1574] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1095vm-test-run-index_advisor> server # [ 132.761570] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1568]: GRANT1096vm-test-run-index_advisor> server # [ 132.829970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running stat extension...1097vm-test-run-index_advisor> server # [ 133.264536] postgres[1591]: ::1 2026-10-07 20:39:15.819 UTC [1591] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions;1098vm-test-run-index_advisor> server # [ 133.292906] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1588]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping1099vm-test-run-index_advisor> server # [ 133.301516] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1588]: CREATE SCHEMA1100vm-test-run-index_advisor> server # [ 133.302735] postgres[1591]: ::1 2026-10-07 20:39:15.870 UTC [1591] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1101vm-test-run-index_advisor> server # [ 134.451653] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1588]: CREATE EXTENSION1102vm-test-run-index_advisor> server # [ 134.523501] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/10000000000000_demote-postgres.sql1103vm-test-run-index_advisor> server # [ 134.975743] postgres[1600]: ::1 2026-10-07 20:39:17.528 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres;1104vm-test-run-index_advisor> server # [ 135.011051] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1105vm-test-run-index_advisor> server # [ 135.017108] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1106vm-test-run-index_advisor> server # [ 135.020768] postgres[1600]: ::1 2026-10-07 20:39:17.562 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres;1107vm-test-run-index_advisor> server # [ 135.034008] postgres[1600]: ::1 2026-10-07 20:39:17.580 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres;1108vm-test-run-index_advisor> server # [ 135.035681] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1109vm-test-run-index_advisor> server # [ 135.038720] postgres[1600]: ::1 2026-10-07 20:39:17.595 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1110vm-test-run-index_advisor> server # [ 135.045005] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1111vm-test-run-index_advisor> server # [ 135.056329] postgres[1600]: ::1 2026-10-07 20:39:17.620 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1112vm-test-run-index_advisor> server # [ 135.070307] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1113vm-test-run-index_advisor> server # [ 135.074778] postgres[1600]: ::1 2026-10-07 20:39:17.646 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1114vm-test-run-index_advisor> server # [ 135.098177] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1115vm-test-run-index_advisor> server # [ 135.113341] postgres[1600]: ::1 2026-10-07 20:39:17.678 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1116vm-test-run-index_advisor> server # [ 135.115894] postgres[1600]: ::1 2026-10-07 20:39:17.683 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1117vm-test-run-index_advisor> server # [ 135.116741] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1118vm-test-run-index_advisor> server # [ 135.132380] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1119vm-test-run-index_advisor> server # [ 135.148910] postgres[1600]: ::1 2026-10-07 20:39:17.705 UTC [1600] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1120vm-test-run-index_advisor> server # [ 135.198494] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: GRANT1121vm-test-run-index_advisor> server # [ 135.329918] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: DO1122vm-test-run-index_advisor> server # [ 135.337838] postgres[1600]: ::1 2026-10-07 20:39:17.900 UTC [1600] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1123vm-test-run-index_advisor> server # [ 135.340274] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1596]: ALTER ROLE1124vm-test-run-index_advisor> server # [ 135.410608] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211115181400_update-auth-permissions.sql1125vm-test-run-index_advisor> server # [ 135.850671] postgres[1609]: ::1 2026-10-07 20:39:18.410 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1126vm-test-run-index_advisor> server # [ 135.874580] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1127vm-test-run-index_advisor> server # [ 135.881034] postgres[1609]: ::1 2026-10-07 20:39:18.432 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1128vm-test-run-index_advisor> server # [ 135.891562] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1129vm-test-run-index_advisor> server # [ 135.899980] postgres[1609]: ::1 2026-10-07 20:39:18.467 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1130vm-test-run-index_advisor> server # [ 135.911715] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1131vm-test-run-index_advisor> server # [ 135.913922] postgres[1609]: ::1 2026-10-07 20:39:18.484 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1132vm-test-run-index_advisor> server # [ 135.928180] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE1133vm-test-run-index_advisor> server # [ 135.934776] postgres[1609]: ::1 2026-10-07 20:39:18.501 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1134vm-test-run-index_advisor> server # [ 135.945783] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE1135vm-test-run-index_advisor> server # [ 135.947344] postgres[1609]: ::1 2026-10-07 20:39:18.519 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1136vm-test-run-index_advisor> server # [ 135.957856] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE1137vm-test-run-index_advisor> server # [ 135.958848] postgres[1609]: ::1 2026-10-07 20:39:18.530 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1138vm-test-run-index_advisor> server # [ 135.970430] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE1139vm-test-run-index_advisor> server # [ 135.981897] postgres[1609]: ::1 2026-10-07 20:39:18.549 UTC [1609] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1140vm-test-run-index_advisor> server # [ 135.999536] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER TABLE1141vm-test-run-index_advisor> server # [ 136.011017] postgres[1609]: ::1 2026-10-07 20:39:18.580 UTC [1609] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres;1142vm-test-run-index_advisor> server # [ 136.050125] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1143vm-test-run-index_advisor> server # [ 136.058038] postgres[1609]: ::1 2026-10-07 20:39:18.626 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1144vm-test-run-index_advisor> server # [ 136.084058] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1145vm-test-run-index_advisor> server # [ 136.093660] postgres[1609]: ::1 2026-10-07 20:39:18.661 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1146vm-test-run-index_advisor> server # [ 136.101072] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1147vm-test-run-index_advisor> server # [ 136.102058] postgres[1609]: ::1 2026-10-07 20:39:18.674 UTC [1609] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1148vm-test-run-index_advisor> server # [ 136.111220] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: GRANT1149vm-test-run-index_advisor> server # [ 136.112059] postgres[1609]: ::1 2026-10-07 20:39:18.684 UTC [1609] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1150vm-test-run-index_advisor> server # [ 136.134382] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1151vm-test-run-index_advisor> server # [ 136.135275] postgres[1609]: ::1 2026-10-07 20:39:18.707 UTC [1609] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1152vm-test-run-index_advisor> server # [ 136.144850] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1153vm-test-run-index_advisor> server # [ 136.163545] postgres[1609]: ::1 2026-10-07 20:39:18.729 UTC [1609] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1154vm-test-run-index_advisor> server # [ 136.174557] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1606]: ALTER DEFAULT PRIVILEGES1155vm-test-run-index_advisor> server # [ 136.277024] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211118015519_create-realtime-schema.sql1156vm-test-run-index_advisor> server # [ 136.695571] postgres[1624]: ::1 2026-10-07 20:39:19.253 UTC [1624] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime;1157vm-test-run-index_advisor> server # [ 136.763240] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1617]: CREATE SCHEMA1158vm-test-run-index_advisor> server # [ 136.808722] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1159vm-test-run-index_advisor> server # [ 137.313855] postgres[1631]: ::1 2026-10-07 20:39:19.863 UTC [1631] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres;1160vm-test-run-index_advisor> server # [ 137.350564] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT1161vm-test-run-index_advisor> server # [ 137.357403] postgres[1631]: ::1 2026-10-07 20:39:19.904 UTC [1631] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1162vm-test-run-index_advisor> server # [ 137.359661] postgres[1631]: ::1 2026-10-07 20:39:19.919 UTC [1631] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1163vm-test-run-index_advisor> server # [ 137.360887] postgres[1631]: ::1 2026-10-07 20:39:19.926 UTC [1631] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1164vm-test-run-index_advisor> server # [ 137.367790] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT1165vm-test-run-index_advisor> server # [ 137.374692] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT1166vm-test-run-index_advisor> server # [ 137.375238] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1628]: GRANT1167vm-test-run-index_advisor> server # [ 137.447333] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211124212715_update-auth-owner.sql1168vm-test-run-index_advisor> server # [ 137.799794] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1632]: DO1169vm-test-run-index_advisor> server # [ 137.811891] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1632]: DO1170vm-test-run-index_advisor> server # [ 137.825277] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1632]: DO1171vm-test-run-index_advisor> server # [ 137.875411] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1172vm-test-run-index_advisor> server # [ 138.151935] postgres[1635]: ::1 2026-10-07 20:39:20.714 UTC [1635] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1173vm-test-run-index_advisor> server # [ 138.174302] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1634]: ALTER DEFAULT PRIVILEGES1174vm-test-run-index_advisor> server # [ 138.175619] postgres[1635]: ::1 2026-10-07 20:39:20.736 UTC [1635] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1175vm-test-run-index_advisor> server # [ 138.184056] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1634]: ALTER DEFAULT PRIVILEGES1176vm-test-run-index_advisor> server # [ 138.187616] postgres[1635]: ::1 2026-10-07 20:39:20.750 UTC [1635] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1177vm-test-run-index_advisor> server # [ 138.196692] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1634]: ALTER DEFAULT PRIVILEGES1178vm-test-run-index_advisor> server # [ 138.240568] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1179vm-test-run-index_advisor> server # [ 138.507465] postgres[1637]: ::1 2026-10-07 20:39:21.071 UTC [1637] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1180vm-test-run-index_advisor> server # [ 138.559495] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1636]: ALTER ROLE1181vm-test-run-index_advisor> server # [ 138.579829] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1182vm-test-run-index_advisor> server # [ 139.075321] postgres[1643]: ::1 2026-10-07 20:39:21.629 UTC [1643] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1183vm-test-run-index_advisor> server # [ 139.101184] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1184vm-test-run-index_advisor> server # [ 139.102777] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP EVENT TRIGGER1185vm-test-run-index_advisor> server # [ 139.103313] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1186vm-test-run-index_advisor> server # [ 139.110084] postgres[1643]: ::1 2026-10-07 20:39:21.659 UTC [1643] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1187vm-test-run-index_advisor> server # [ 139.122613] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP FUNCTION1188vm-test-run-index_advisor> server # [ 139.123555] postgres[1643]: ::1 2026-10-07 20:39:21.687 UTC [1643] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1189vm-test-run-index_advisor> server # [ 139.137324] postgres[1643]: DECLARE1190vm-test-run-index_advisor> server # [ 139.138959] postgres[1643]: cmd record;1191vm-test-run-index_advisor> server # [ 139.149038] postgres[1643]: BEGIN1192vm-test-run-index_advisor> server # [ 139.149652] postgres[1643]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1193vm-test-run-index_advisor> server # [ 139.150730] postgres[1643]: LOOP1194vm-test-run-index_advisor> server # [ 139.151104] postgres[1643]: IF cmd.command_tag IN (1195vm-test-run-index_advisor> server # [ 139.151798] postgres[1643]: 'CREATE SCHEMA', 'ALTER SCHEMA'1196vm-test-run-index_advisor> server # [ 139.152107] postgres[1643]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1197vm-test-run-index_advisor> server # [ 139.159370] postgres[1643]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1198vm-test-run-index_advisor> server # [ 139.165094] postgres[1643]: , 'CREATE VIEW', 'ALTER VIEW'1199vm-test-run-index_advisor> server # [ 139.176498] postgres[1643]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1200vm-test-run-index_advisor> server # [ 139.179143] postgres[1643]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1201vm-test-run-index_advisor> server # [ 139.182574] postgres[1643]: , 'CREATE TRIGGER'1202vm-test-run-index_advisor> server # [ 139.189802] postgres[1643]: , 'CREATE TYPE'1203vm-test-run-index_advisor> server # [ 139.190268] postgres[1643]: , 'CREATE RULE'1204vm-test-run-index_advisor> server # [ 139.190579] postgres[1643]: , 'COMMENT'1205vm-test-run-index_advisor> server # [ 139.190812] postgres[1643]: )1206vm-test-run-index_advisor> server # [ 139.191134] postgres[1643]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1207vm-test-run-index_advisor> server # [ 139.196736] postgres[1643]: AND cmd.schema_name is distinct from 'pg_temp'1208vm-test-run-index_advisor> server # [ 139.197973] postgres[1643]: THEN1209vm-test-run-index_advisor> server # [ 139.198419] postgres[1643]: NOTIFY pgrst, 'reload schema';1210vm-test-run-index_advisor> server # [ 139.200275] postgres[1643]: END IF;1211vm-test-run-index_advisor> server # [ 139.209625] postgres[1643]: END LOOP;1212vm-test-run-index_advisor> server # [ 139.210277] postgres[1643]: END; $$ LANGUAGE plpgsql;1213vm-test-run-index_advisor> server # [ 139.211014] postgres[1643]: ::1 2026-10-07 20:39:21.733 UTC [1643] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1214vm-test-run-index_advisor> server # [ 139.220330] postgres[1643]: DECLARE1215vm-test-run-index_advisor> server # [ 139.225678] postgres[1643]: obj record;1216vm-test-run-index_advisor> server # [ 139.226626] postgres[1643]: BEGIN1217vm-test-run-index_advisor> server # [ 139.239580] postgres[1643]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1218vm-test-run-index_advisor> server # [ 139.291818] postgres[1643]: LOOP1219vm-test-run-index_advisor> server # [ 139.297175] postgres[1643]: IF obj.object_type IN (1220vm-test-run-index_advisor> server # [ 139.299482] postgres[1643]: 'schema'1221vm-test-run-index_advisor> server # [ 139.321705] postgres[1643]: , 'table'1222vm-test-run-index_advisor> server # [ 139.329765] postgres[1643]: , 'foreign table'1223vm-test-run-index_advisor> server # [ 139.347037] postgres[1643]: , 'view'1224vm-test-run-index_advisor> server # [ 139.351009] postgres[1643]: , 'materialized view'1225vm-test-run-index_advisor> server # [ 139.354946] postgres[1643]: , 'function'1226vm-test-run-index_advisor> server # [ 139.357063] postgres[1643]: , 'trigger'1227vm-test-run-index_advisor> server # [ 139.358298] postgres[1643]: , 'type'1228vm-test-run-index_advisor> server # [ 139.363030] postgres[1643]: , 'rule'1229vm-test-run-index_advisor> server # [ 139.363874] postgres[1643]: )1230vm-test-run-index_advisor> server # [ 139.369177] postgres[1643]: AND obj.is_temporary IS false -- no pg_temp objects1231vm-test-run-index_advisor> server # [ 139.372203] postgres[1643]: THEN1232vm-test-run-index_advisor> server # [ 139.373739] postgres[1643]: NOTIFY pgrst, 'reload schema';1233vm-test-run-index_advisor> server # [ 139.381555] postgres[1643]: END IF;1234vm-test-run-index_advisor> server # [ 139.382903] postgres[1643]: END LOOP;1235vm-test-run-index_advisor> server # [ 139.383556] postgres[1643]: END; $$ LANGUAGE plpgsql;1236vm-test-run-index_advisor> server # [ 139.393899] postgres[1643]: ::1 2026-10-07 20:39:21.767 UTC [1643] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1237vm-test-run-index_advisor> server # [ 139.409177] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE FUNCTION1238vm-test-run-index_advisor> server # [ 139.411174] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE FUNCTION1239vm-test-run-index_advisor> server # [ 139.411573] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping1240vm-test-run-index_advisor> server # [ 139.412336] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP EVENT TRIGGER1241vm-test-run-index_advisor> server # [ 139.413870] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE EVENT TRIGGER1242vm-test-run-index_advisor> server # [ 139.414754] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping1243vm-test-run-index_advisor> server # [ 139.421541] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: DROP EVENT TRIGGER1244vm-test-run-index_advisor> server # [ 139.422749] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1638]: CREATE EVENT TRIGGER1245vm-test-run-index_advisor> server # [ 139.424212] postgres[1643]: ::1 2026-10-07 20:39:21.787 UTC [1643] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1246vm-test-run-index_advisor> server # [ 139.431663] postgres[1643]: ON ddl_command_end1247vm-test-run-index_advisor> server # [ 139.437976] postgres[1643]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1248vm-test-run-index_advisor> server # [ 139.438636] postgres[1643]: ::1 2026-10-07 20:39:21.801 UTC [1643] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1249vm-test-run-index_advisor> server # [ 139.439734] postgres[1643]: ::1 2026-10-07 20:39:21.839 UTC [1643] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1250vm-test-run-index_advisor> server # [ 139.440152] postgres[1643]: ON sql_drop1251vm-test-run-index_advisor> server # [ 139.440668] postgres[1643]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1252vm-test-run-index_advisor> server # [ 139.441448] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1253vm-test-run-index_advisor> server # [ 140.661721] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1649]: DO1254vm-test-run-index_advisor> server # [ 140.727707] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql1255vm-test-run-index_advisor> server # [ 141.168641] postgres[1671]: ::1 2026-10-07 20:39:23.733 UTC [1671] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public;1256vm-test-run-index_advisor> server # [ 141.265259] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE SCHEMA1257vm-test-run-index_advisor> server # [ 141.282532] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-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 # [ 141.283833] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: DROP FUNCTION1259vm-test-run-index_advisor> server # [ 141.290221] postgres[1671]: ::1 2026-10-07 20:39:23.814 UTC [1671] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb);1260vm-test-run-index_advisor> server # [ 141.305234] postgres[1671]: ::1 2026-10-07 20:39:23.837 UTC [1671] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1261vm-test-run-index_advisor> server # [ 141.307573] postgres[1671]: "operationName" text default null,1262vm-test-run-index_advisor> server # [ 141.309272] postgres[1671]: query text default null,1263vm-test-run-index_advisor> server # [ 141.325001] postgres[1671]: variables jsonb default null,1264vm-test-run-index_advisor> server # [ 141.326078] postgres[1671]: extensions jsonb default null1265vm-test-run-index_advisor> server # [ 141.340009] postgres[1671]: )1266vm-test-run-index_advisor> server # [ 141.342875] postgres[1671]: returns jsonb1267vm-test-run-index_advisor> server # [ 141.346193] postgres[1671]: language plpgsql1268vm-test-run-index_advisor> server # [ 141.354566] postgres[1671]: as $$1269vm-test-run-index_advisor> server # [ 141.355263] postgres[1671]: DECLARE1270vm-test-run-index_advisor> server # [ 141.356098] postgres[1671]: server_version float;1271vm-test-run-index_advisor> server # [ 141.370918] postgres[1671]: BEGIN1272vm-test-run-index_advisor> server # [ 141.378324] postgres[1671]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1273vm-test-run-index_advisor> server # [ 141.381239] postgres[1671]:1274vm-test-run-index_advisor> server # [ 141.383334] postgres[1671]: IF server_version >= 14 THEN1275vm-test-run-index_advisor> server # [ 141.383958] postgres[1671]: RETURN jsonb_build_object(1276vm-test-run-index_advisor> server # [ 141.385485] postgres[1671]: 'data', null::jsonb,1277vm-test-run-index_advisor> server # [ 141.387590] postgres[1671]: 'errors', array['pg_graphql extension is not enabled.']1278vm-test-run-index_advisor> server # [ 141.389823] postgres[1671]: );1279vm-test-run-index_advisor> server # [ 141.398001] postgres[1671]: ELSE1280vm-test-run-index_advisor> server # [ 141.399912] postgres[1671]: RETURN jsonb_build_object(1281vm-test-run-index_advisor> server # [ 141.401350] postgres[1671]: 'data', null::jsonb,1282vm-test-run-index_advisor> server # [ 141.402068] postgres[1671]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1283vm-test-run-index_advisor> server # [ 141.403922] postgres[1671]: );1284vm-test-run-index_advisor> server # [ 141.405817] postgres[1671]: END IF;1285vm-test-run-index_advisor> server # [ 141.410077] postgres[1671]: END;1286vm-test-run-index_advisor> server # [ 141.411934] postgres[1671]: $$;1287vm-test-run-index_advisor> server # [ 141.417571] postgres[1671]: ::1 2026-10-07 20:39:23.904 UTC [1671] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1288vm-test-run-index_advisor> server # [ 141.418853] postgres[1671]: ::1 2026-10-07 20:39:23.922 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1289vm-test-run-index_advisor> server # [ 141.419800] postgres[1671]: ::1 2026-10-07 20:39:23.938 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1290vm-test-run-index_advisor> server # [ 141.427360] postgres[1671]: ::1 2026-10-07 20:39:23.966 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1291vm-test-run-index_advisor> server # [ 141.438814] postgres[1671]: ::1 2026-10-07 20:39:23.986 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1292vm-test-run-index_advisor> server # [ 141.439865] postgres[1671]: on sequences to postgres, anon, authenticated, service_role;1293vm-test-run-index_advisor> server # [ 141.443293] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE FUNCTION1294vm-test-run-index_advisor> server # [ 141.450852] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: GRANT1295vm-test-run-index_advisor> server # [ 141.451390] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES1296vm-test-run-index_advisor> server # [ 141.459176] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES1297vm-test-run-index_advisor> server # [ 141.461561] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES1298vm-test-run-index_advisor> server # [ 141.462571] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES1299vm-test-run-index_advisor> server # [ 141.463708] postgres[1671]: ::1 2026-10-07 20:39:24.005 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1300vm-test-run-index_advisor> server # [ 141.470689] postgres[1671]: on tables to postgres, anon, authenticated, service_role;1301vm-test-run-index_advisor> server # [ 141.475181] postgres[1671]: ::1 2026-10-07 20:39:24.019 UTC [1671] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1302vm-test-run-index_advisor> server # [ 141.483857] postgres[1671]: on functions to postgres, anon, authenticated, service_role;1303vm-test-run-index_advisor> server # [ 141.487433] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES1304vm-test-run-index_advisor> server # [ 141.487914] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: ALTER DEFAULT PRIVILEGES1305vm-test-run-index_advisor> server # [ 141.488233] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE FUNCTION1306vm-test-run-index_advisor> server # [ 141.491111] postgres[1671]: ::1 2026-10-07 20:39:24.046 UTC [1671] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1307vm-test-run-index_advisor> server # [ 141.492464] postgres[1671]: RETURNS event_trigger1308vm-test-run-index_advisor> server # [ 141.494147] postgres[1671]: LANGUAGE plpgsql1309vm-test-run-index_advisor> server # [ 141.494993] postgres[1671]: AS $func$1310vm-test-run-index_advisor> server # [ 141.497249] postgres[1671]: DECLARE1311vm-test-run-index_advisor> server # [ 141.498084] postgres[1671]: func_is_graphql_resolve bool;1312vm-test-run-index_advisor> server # [ 141.501942] postgres[1671]: BEGIN1313vm-test-run-index_advisor> server # [ 141.503034] postgres[1671]: func_is_graphql_resolve = (1314vm-test-run-index_advisor> server # [ 141.505257] postgres[1671]: SELECT n.proname = 'resolve'1315vm-test-run-index_advisor> server # [ 141.506702] postgres[1671]: FROM pg_event_trigger_ddl_commands() AS ev1316vm-test-run-index_advisor> server # [ 141.507094] postgres[1671]: LEFT JOIN pg_catalog.pg_proc AS n1317vm-test-run-index_advisor> server # [ 141.507434] postgres[1671]: ON ev.objid = n.oid1318vm-test-run-index_advisor> server # [ 141.508189] postgres[1671]: );1319vm-test-run-index_advisor> server # [ 141.509838] postgres[1671]:1320vm-test-run-index_advisor> server # [ 141.510174] postgres[1671]: IF func_is_graphql_resolve1321vm-test-run-index_advisor> server # [ 141.510631] postgres[1671]: THEN1322vm-test-run-index_advisor> server # [ 141.511174] postgres[1671]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1323vm-test-run-index_advisor> server # [ 141.514116] postgres[1671]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1324vm-test-run-index_advisor> server # [ 141.515415] postgres[1671]:1325vm-test-run-index_advisor> server # [ 141.518121] postgres[1671]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1326vm-test-run-index_advisor> server # [ 141.520013] postgres[1671]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1327vm-test-run-index_advisor> server # [ 141.522485] postgres[1671]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1328vm-test-run-index_advisor> server # [ 141.523604] postgres[1671]:1329vm-test-run-index_advisor> server # [ 141.524423] postgres[1671]: DROP FUNCTION IF EXISTS graphql_public.graphql;1330vm-test-run-index_advisor> server # [ 141.528965] postgres[1671]: create or replace function graphql_public.graphql(1331vm-test-run-index_advisor> server # [ 141.530590] postgres[1671]: "operationName" text default null,1332vm-test-run-index_advisor> server # [ 141.539228] postgres[1671]: query text default null,1333vm-test-run-index_advisor> server # [ 141.539846] postgres[1671]: variables jsonb default null,1334vm-test-run-index_advisor> server # [ 141.540529] postgres[1671]: extensions jsonb default null1335vm-test-run-index_advisor> server # [ 141.540978] postgres[1671]: )1336vm-test-run-index_advisor> server # [ 141.541557] postgres[1671]: returns jsonb1337vm-test-run-index_advisor> server # [ 141.542823] postgres[1671]: language sql1338vm-test-run-index_advisor> server # [ 141.553680] postgres[1671]: as $$1339vm-test-run-index_advisor> server # [ 141.555974] postgres[1671]: SELECT graphql.resolve(query, coalesce(variables, '{}'));1340vm-test-run-index_advisor> server # [ 141.557814] postgres[1671]: $$;1341vm-test-run-index_advisor> server # [ 141.558676] postgres[1671]:1342vm-test-run-index_advisor> server # [ 141.558996] postgres[1671]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1343vm-test-run-index_advisor> server # [ 141.559325] postgres[1671]: END IF;1344vm-test-run-index_advisor> server # [ 141.559732] postgres[1671]:1345vm-test-run-index_advisor> server # [ 141.559975] postgres[1671]: END;1346vm-test-run-index_advisor> server # [ 141.560322] postgres[1671]: $func$;1347vm-test-run-index_advisor> server # [ 141.565327] postgres[1671]: ::1 2026-10-07 20:39:24.060 UTC [1671] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1348vm-test-run-index_advisor> server # [ 141.566045] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping1349vm-test-run-index_advisor> server # [ 141.567665] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: DROP EVENT TRIGGER1350vm-test-run-index_advisor> server # [ 141.574181] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE EVENT TRIGGER1351vm-test-run-index_advisor> server # [ 141.578911] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: COMMENT1352vm-test-run-index_advisor> server # [ 141.591007] postgres[1671]: ::1 2026-10-07 20:39:24.078 UTC [1671] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1353vm-test-run-index_advisor> server # [ 141.601795] postgres[1671]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1354vm-test-run-index_advisor> server # [ 141.613080] postgres[1671]: ::1 2026-10-07 20:39:24.106 UTC [1671] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1355vm-test-run-index_advisor> server # [ 141.614820] postgres[1671]: ::1 2026-10-07 20:39:24.120 UTC [1671] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1356vm-test-run-index_advisor> server # [ 141.624060] postgres[1671]: RETURNS event_trigger1357vm-test-run-index_advisor> server # [ 141.630582] postgres[1671]: LANGUAGE plpgsql1358vm-test-run-index_advisor> server # [ 141.631289] postgres[1671]: AS $func$1359vm-test-run-index_advisor> server # [ 141.631566] postgres[1671]: DECLARE1360vm-test-run-index_advisor> server # [ 141.632006] postgres[1671]: graphql_is_dropped bool;1361vm-test-run-index_advisor> server # [ 141.634152] postgres[1671]: BEGIN1362vm-test-run-index_advisor> server # [ 141.635123] postgres[1671]: graphql_is_dropped = (1363vm-test-run-index_advisor> server # [ 141.637247] postgres[1671]: SELECT ev.schema_name = 'graphql_public'1364vm-test-run-index_advisor> server # [ 141.639601] postgres[1671]: FROM pg_event_trigger_dropped_objects() AS ev1365vm-test-run-index_advisor> server # [ 141.642697] postgres[1671]: WHERE ev.schema_name = 'graphql_public'1366vm-test-run-index_advisor> server # [ 141.647579] postgres[1671]: );1367vm-test-run-index_advisor> server # [ 141.649322] postgres[1671]:1368vm-test-run-index_advisor> server # [ 141.650861] postgres[1671]: IF graphql_is_dropped1369vm-test-run-index_advisor> server # [ 141.654216] postgres[1671]: THEN1370vm-test-run-index_advisor> server # [ 141.657001] postgres[1671]: create or replace function graphql_public.graphql(1371vm-test-run-index_advisor> server # [ 141.658234] postgres[1671]: "operationName" text default null,1372vm-test-run-index_advisor> server # [ 141.663327] postgres[1671]: query text default null,1373vm-test-run-index_advisor> server # [ 141.666843] postgres[1671]: variables jsonb default null,1374vm-test-run-index_advisor> server # [ 141.667520] postgres[1671]: extensions jsonb default null1375vm-test-run-index_advisor> server # [ 141.668475] postgres[1671]: )1376vm-test-run-index_advisor> server # [ 141.669674] postgres[1671]: returns jsonb1377vm-test-run-index_advisor> server # [ 141.669979] postgres[1671]: language plpgsql1378vm-test-run-index_advisor> server # [ 141.670869] postgres[1671]: as $$1379vm-test-run-index_advisor> server # [ 141.671655] postgres[1671]: DECLARE1380vm-test-run-index_advisor> server # [ 141.672427] postgres[1671]: server_version float;1381vm-test-run-index_advisor> server # [ 141.673459] postgres[1671]: BEGIN1382vm-test-run-index_advisor> server # [ 141.674931] postgres[1671]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1383vm-test-run-index_advisor> server # [ 141.675835] postgres[1671]:1384vm-test-run-index_advisor> server # [ 141.678550] postgres[1671]: IF server_version >= 14 THEN1385vm-test-run-index_advisor> server # [ 141.679146] postgres[1671]: RETURN jsonb_build_object(1386vm-test-run-index_advisor> server # [ 141.679785] postgres[1671]: 'data', null::jsonb,1387vm-test-run-index_advisor> server # [ 141.680888] postgres[1671]: 'errors', array['pg_graphql extension is not enabled.']1388vm-test-run-index_advisor> server # [ 141.682250] postgres[1671]: );1389vm-test-run-index_advisor> server # [ 141.689460] postgres[1671]: ELSE1390vm-test-run-index_advisor> server # [ 141.690840] postgres[1671]: RETURN jsonb_build_object(1391vm-test-run-index_advisor> server # [ 141.691295] postgres[1671]: 'data', null::jsonb,1392vm-test-run-index_advisor> server # [ 141.691623] postgres[1671]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1393vm-test-run-index_advisor> server # [ 141.693422] postgres[1671]: );1394vm-test-run-index_advisor> server # [ 141.693861] postgres[1671]: END IF;1395vm-test-run-index_advisor> server # [ 141.694297] postgres[1671]: END;1396vm-test-run-index_advisor> server # [ 141.694963] postgres[1671]: $$;1397vm-test-run-index_advisor> server # [ 141.695500] postgres[1671]: END IF;1398vm-test-run-index_advisor> server # [ 141.695766] postgres[1671]:1399vm-test-run-index_advisor> server # [ 141.697932] postgres[1671]: END;1400vm-test-run-index_advisor> server # [ 141.699746] postgres[1671]: $func$;1401vm-test-run-index_advisor> server # [ 141.701354] postgres[1671]: ::1 2026-10-07 20:39:24.251 UTC [1671] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1402vm-test-run-index_advisor> server # [ 141.701883] postgres[1671]: ::1 2026-10-07 20:39:24.269 UTC [1671] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1403vm-test-run-index_advisor> server # [ 141.702751] postgres[1671]: EXECUTE PROCEDURE extensions.set_graphql_placeholder();1404vm-test-run-index_advisor> server # [ 141.703353] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE FUNCTION1405vm-test-run-index_advisor> server # [ 141.705383] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping1406vm-test-run-index_advisor> server # [ 141.707576] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: DROP EVENT TRIGGER1407vm-test-run-index_advisor> server # [ 141.712029] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: CREATE EVENT TRIGGER1408vm-test-run-index_advisor> server # [ 141.713908] postgres[1671]: ::1 2026-10-07 20:39:24.283 UTC [1671] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1409vm-test-run-index_advisor> server # [ 141.723644] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1666]: COMMENT1410vm-test-run-index_advisor> server # [ 141.779914] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1411vm-test-run-index_advisor> server # [ 142.047213] postgres[1673]: ::1 2026-10-07 20:39:24.616 UTC [1673] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1412vm-test-run-index_advisor> server # [ 142.059530] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1413vm-test-run-index_advisor> server # [ 142.070208] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP EVENT TRIGGER1414vm-test-run-index_advisor> server # [ 142.071146] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1415vm-test-run-index_advisor> server # [ 142.077657] postgres[1673]: ::1 2026-10-07 20:39:24.630 UTC [1673] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1416vm-test-run-index_advisor> server # [ 142.081212] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP FUNCTION1417vm-test-run-index_advisor> server # [ 142.083056] postgres[1673]: ::1 2026-10-07 20:39:24.655 UTC [1673] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1418vm-test-run-index_advisor> server # [ 142.085709] postgres[1673]: DECLARE1419vm-test-run-index_advisor> server # [ 142.087564] postgres[1673]: cmd record;1420vm-test-run-index_advisor> server # [ 142.088880] postgres[1673]: BEGIN1421vm-test-run-index_advisor> server # [ 142.090824] postgres[1673]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1422vm-test-run-index_advisor> server # [ 142.091641] postgres[1673]: LOOP1423vm-test-run-index_advisor> server # [ 142.093450] postgres[1673]: IF cmd.command_tag IN (1424vm-test-run-index_advisor> server # [ 142.094174] postgres[1673]: 'CREATE SCHEMA', 'ALTER SCHEMA'1425vm-test-run-index_advisor> server # [ 142.094774] postgres[1673]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1426vm-test-run-index_advisor> server # [ 142.095103] postgres[1673]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1427vm-test-run-index_advisor> server # [ 142.097329] postgres[1673]: , 'CREATE VIEW', 'ALTER VIEW'1428vm-test-run-index_advisor> server # [ 142.097722] postgres[1673]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1429vm-test-run-index_advisor> server # [ 142.098178] postgres[1673]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1430vm-test-run-index_advisor> server # [ 142.099058] postgres[1673]: , 'CREATE TRIGGER'1431vm-test-run-index_advisor> server # [ 142.100467] postgres[1673]: , 'CREATE TYPE', 'ALTER TYPE'1432vm-test-run-index_advisor> server # [ 142.102130] postgres[1673]: , 'CREATE RULE'1433vm-test-run-index_advisor> server # [ 142.102747] postgres[1673]: , 'COMMENT'1434vm-test-run-index_advisor> server # [ 142.103487] postgres[1673]: )1435vm-test-run-index_advisor> server # [ 142.103743] postgres[1673]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1436vm-test-run-index_advisor> server # [ 142.104834] postgres[1673]: AND cmd.schema_name is distinct from 'pg_temp'1437vm-test-run-index_advisor> server # [ 142.106119] postgres[1673]: THEN1438vm-test-run-index_advisor> server # [ 142.106930] postgres[1673]: NOTIFY pgrst, 'reload schema';1439vm-test-run-index_advisor> server # [ 142.107857] postgres[1673]: END IF;1440vm-test-run-index_advisor> server # [ 142.108207] postgres[1673]: END LOOP;1441vm-test-run-index_advisor> server # [ 142.108443] postgres[1673]: END; $$ LANGUAGE plpgsql;1442vm-test-run-index_advisor> server # [ 142.140895] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE FUNCTION1443vm-test-run-index_advisor> server # [ 142.143232] postgres[1673]: ::1 2026-10-07 20:39:24.713 UTC [1673] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1444vm-test-run-index_advisor> server # [ 142.144058] postgres[1673]: DECLARE1445vm-test-run-index_advisor> server # [ 142.148059] postgres[1673]: obj record;1446vm-test-run-index_advisor> server # [ 142.149314] postgres[1673]: BEGIN1447vm-test-run-index_advisor> server # [ 142.149653] postgres[1673]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1448vm-test-run-index_advisor> server # [ 142.150204] postgres[1673]: LOOP1449vm-test-run-index_advisor> server # [ 142.150572] postgres[1673]: IF obj.object_type IN (1450vm-test-run-index_advisor> server # [ 142.151329] postgres[1673]: 'schema'1451vm-test-run-index_advisor> server # [ 142.151608] postgres[1673]: , 'table'1452vm-test-run-index_advisor> server # [ 142.152082] postgres[1673]: , 'foreign table'1453vm-test-run-index_advisor> server # [ 142.153760] postgres[1673]: , 'view'1454vm-test-run-index_advisor> server # [ 142.155177] postgres[1673]: , 'materialized view'1455vm-test-run-index_advisor> server # [ 142.155514] postgres[1673]: , 'function'1456vm-test-run-index_advisor> server # [ 142.155813] postgres[1673]: , 'trigger'1457vm-test-run-index_advisor> server # [ 142.156028] postgres[1673]: , 'type'1458vm-test-run-index_advisor> server # [ 142.156576] postgres[1673]: , 'rule'1459vm-test-run-index_advisor> server # [ 142.156796] postgres[1673]: )1460vm-test-run-index_advisor> server # [ 142.156996] postgres[1673]: AND obj.is_temporary IS false -- no pg_temp objects1461vm-test-run-index_advisor> server # [ 142.157959] postgres[1673]: THEN1462vm-test-run-index_advisor> server # [ 142.158982] postgres[1673]: NOTIFY pgrst, 'reload schema';1463vm-test-run-index_advisor> server # [ 142.159302] postgres[1673]: END IF;1464vm-test-run-index_advisor> server # [ 142.159565] postgres[1673]: END LOOP;1465vm-test-run-index_advisor> server # [ 142.160086] postgres[1673]: END; $$ LANGUAGE plpgsql;1466vm-test-run-index_advisor> server # [ 142.180200] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE FUNCTION1467vm-test-run-index_advisor> server # [ 142.185146] postgres[1673]: ::1 2026-10-07 20:39:24.754 UTC [1673] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1468vm-test-run-index_advisor> server # [ 142.198006] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP EVENT TRIGGER1469vm-test-run-index_advisor> server # [ 142.199800] postgres[1673]: ::1 2026-10-07 20:39:24.772 UTC [1673] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1470vm-test-run-index_advisor> server # [ 142.202536] postgres[1673]: ON ddl_command_end1471vm-test-run-index_advisor> server # [ 142.205089] postgres[1673]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1472vm-test-run-index_advisor> server # [ 142.227628] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE EVENT TRIGGER1473vm-test-run-index_advisor> server # [ 142.238272] postgres[1673]: ::1 2026-10-07 20:39:24.802 UTC [1673] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1474vm-test-run-index_advisor> server # [ 142.242584] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: DROP EVENT TRIGGER1475vm-test-run-index_advisor> server # [ 142.248961] postgres[1673]: ::1 2026-10-07 20:39:24.815 UTC [1673] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1476vm-test-run-index_advisor> server # [ 142.250215] postgres[1673]: ON sql_drop1477vm-test-run-index_advisor> server # [ 142.251680] postgres[1673]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1478vm-test-run-index_advisor> server # [ 142.256033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1672]: CREATE EVENT TRIGGER1479vm-test-run-index_advisor> server # [ 142.308932] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1480vm-test-run-index_advisor> server # [ 142.649774] postgres[1675]: ::1 2026-10-07 20:39:25.196 UTC [1675] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1481vm-test-run-index_advisor> server # [ 142.694308] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1674]: ALTER ROLE1482vm-test-run-index_advisor> server # [ 142.727622] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1483vm-test-run-index_advisor> server # [ 143.199713] postgres[1686]: ::1 2026-10-07 20:39:25.754 UTC [1686] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1484vm-test-run-index_advisor> server # [ 143.212430] postgres[1686]: returns event_trigger1485vm-test-run-index_advisor> server # [ 143.216878] postgres[1686]: language plpgsql1486vm-test-run-index_advisor> server # [ 143.217393] postgres[1686]: AS $func$1487vm-test-run-index_advisor> server # [ 143.217679] postgres[1686]: DECLARE1488vm-test-run-index_advisor> server # [ 143.218112] postgres[1686]: func_is_graphql_resolve bool;1489vm-test-run-index_advisor> server # [ 143.218543] postgres[1686]: BEGIN1490vm-test-run-index_advisor> server # [ 143.218906] postgres[1686]: func_is_graphql_resolve = (1491vm-test-run-index_advisor> server # [ 143.219172] postgres[1686]: SELECT n.proname = 'resolve'1492vm-test-run-index_advisor> server # [ 143.219423] postgres[1686]: FROM pg_event_trigger_ddl_commands() AS ev1493vm-test-run-index_advisor> server # [ 143.219674] postgres[1686]: LEFT JOIN pg_catalog.pg_proc AS n1494vm-test-run-index_advisor> server # [ 143.219913] postgres[1686]: ON ev.objid = n.oid1495vm-test-run-index_advisor> server # [ 143.227723] postgres[1686]: );1496vm-test-run-index_advisor> server # [ 143.228466] postgres[1686]:1497vm-test-run-index_advisor> server # [ 143.235375] postgres[1686]: IF func_is_graphql_resolve1498vm-test-run-index_advisor> server # [ 143.235861] postgres[1686]: THEN1499vm-test-run-index_advisor> server # [ 143.236115] postgres[1686]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1500vm-test-run-index_advisor> server # [ 143.238755] postgres[1686]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1501vm-test-run-index_advisor> server # [ 143.249788] postgres[1686]:1502vm-test-run-index_advisor> server # [ 143.250755] postgres[1686]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1503vm-test-run-index_advisor> server # [ 143.251548] postgres[1686]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1504vm-test-run-index_advisor> server # [ 143.251934] postgres[1686]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1505vm-test-run-index_advisor> server # [ 143.259904] postgres[1686]:1506vm-test-run-index_advisor> server # [ 143.273681] postgres[1686]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1507vm-test-run-index_advisor> server # [ 143.276404] postgres[1686]: DROP FUNCTION IF EXISTS graphql_public.graphql;1508vm-test-run-index_advisor> server # [ 143.279436] postgres[1686]: create or replace function graphql_public.graphql(1509vm-test-run-index_advisor> server # [ 143.293235] postgres[1686]: "operationName" text default null,1510vm-test-run-index_advisor> server # [ 143.294101] postgres[1686]: query text default null,1511vm-test-run-index_advisor> server # [ 143.294596] postgres[1686]: variables jsonb default null,1512vm-test-run-index_advisor> server # [ 143.294918] postgres[1686]: extensions jsonb default null1513vm-test-run-index_advisor> server # [ 143.295200] postgres[1686]: )1514vm-test-run-index_advisor> server # [ 143.295446] postgres[1686]: returns jsonb1515vm-test-run-index_advisor> server # [ 143.295664] postgres[1686]: language sql1516vm-test-run-index_advisor> server # [ 143.295897] postgres[1686]: as $$1517vm-test-run-index_advisor> server # [ 143.308169] postgres[1686]: -- This changed1518vm-test-run-index_advisor> server # [ 143.310426] postgres[1686]: select graphql.resolve(1519vm-test-run-index_advisor> server # [ 143.311103] postgres[1686]: query := query,1520vm-test-run-index_advisor> server # [ 143.311622] postgres[1686]: variables := coalesce(variables, '{}'),1521vm-test-run-index_advisor> server # [ 143.311954] postgres[1686]: "operationName" := "operationName",1522vm-test-run-index_advisor> server # [ 143.314090] postgres[1686]: extensions := extensions1523vm-test-run-index_advisor> server # [ 143.318892] postgres[1686]: );1524vm-test-run-index_advisor> server # [ 143.319552] postgres[1686]: $$;1525vm-test-run-index_advisor> server # [ 143.320007] postgres[1686]:1526vm-test-run-index_advisor> server # [ 143.320304] postgres[1686]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1527vm-test-run-index_advisor> server # [ 143.334014] postgres[1686]: END IF;1528vm-test-run-index_advisor> server # [ 143.335028] postgres[1686]:1529vm-test-run-index_advisor> server # [ 143.335330] postgres[1686]: END;1530vm-test-run-index_advisor> server # [ 143.335577] postgres[1686]: $func$;1531vm-test-run-index_advisor> server # [ 143.429591] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: CREATE FUNCTION1532vm-test-run-index_advisor> server # [ 143.431030] postgres[1686]: ::1 2026-10-07 20:39:25.983 UTC [1686] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1533vm-test-run-index_advisor> server # [ 143.432104] postgres[1686]: RETURNS event_trigger1534vm-test-run-index_advisor> server # [ 143.432623] postgres[1686]: LANGUAGE plpgsql1535vm-test-run-index_advisor> server # [ 143.432931] postgres[1686]: AS $func$1536vm-test-run-index_advisor> server # [ 143.447009] postgres[1686]: DECLARE1537vm-test-run-index_advisor> server # [ 143.455172] postgres[1686]: graphql_is_dropped bool;1538vm-test-run-index_advisor> server # [ 143.466765] postgres[1686]: BEGIN1539vm-test-run-index_advisor> server # [ 143.476921] postgres[1686]: graphql_is_dropped = (1540vm-test-run-index_advisor> server # [ 143.477761] postgres[1686]: SELECT ev.schema_name = 'graphql_public'1541vm-test-run-index_advisor> server # [ 143.478133] postgres[1686]: FROM pg_event_trigger_dropped_objects() AS ev1542vm-test-run-index_advisor> server # [ 143.478562] postgres[1686]: WHERE ev.schema_name = 'graphql_public'1543vm-test-run-index_advisor> server # [ 143.478826] postgres[1686]: );1544vm-test-run-index_advisor> server # [ 143.479042] postgres[1686]:1545vm-test-run-index_advisor> server # [ 143.479843] postgres[1686]: IF graphql_is_dropped1546vm-test-run-index_advisor> server # [ 143.484993] postgres[1686]: THEN1547vm-test-run-index_advisor> server # [ 143.486009] postgres[1686]: create or replace function graphql_public.graphql(1548vm-test-run-index_advisor> server # [ 143.486713] postgres[1686]: "operationName" text default null,1549vm-test-run-index_advisor> server # [ 143.487518] postgres[1686]: query text default null,1550vm-test-run-index_advisor> server # [ 143.488010] postgres[1686]: variables jsonb default null,1551vm-test-run-index_advisor> server # [ 143.493383] postgres[1686]: extensions jsonb default null1552vm-test-run-index_advisor> server # [ 143.494258] postgres[1686]: )1553vm-test-run-index_advisor> server # [ 143.494895] postgres[1686]: returns jsonb1554vm-test-run-index_advisor> server # [ 143.495170] postgres[1686]: language plpgsql1555vm-test-run-index_advisor> server # [ 143.495644] postgres[1686]: as $$1556vm-test-run-index_advisor> server # [ 143.505400] postgres[1686]: DECLARE1557vm-test-run-index_advisor> server # [ 143.506226] postgres[1686]: server_version float;1558vm-test-run-index_advisor> server # [ 143.507099] postgres[1686]: BEGIN1559vm-test-run-index_advisor> server # [ 143.507386] postgres[1686]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1560vm-test-run-index_advisor> server # [ 143.508305] postgres[1686]:1561vm-test-run-index_advisor> server # [ 143.508557] postgres[1686]: IF server_version >= 14 THEN1562vm-test-run-index_advisor> server # [ 143.508807] postgres[1686]: RETURN jsonb_build_object(1563vm-test-run-index_advisor> server # [ 143.509256] postgres[1686]: 'errors', jsonb_build_array(1564vm-test-run-index_advisor> server # [ 143.509554] postgres[1686]: jsonb_build_object(1565vm-test-run-index_advisor> server # [ 143.509801] postgres[1686]: 'message', 'pg_graphql extension is not enabled.'1566vm-test-run-index_advisor> server # [ 143.513886] postgres[1686]: )1567vm-test-run-index_advisor> server # [ 143.521410] postgres[1686]: )1568vm-test-run-index_advisor> server # [ 143.522184] postgres[1686]: );1569vm-test-run-index_advisor> server # [ 143.522861] postgres[1686]: ELSE1570vm-test-run-index_advisor> server # [ 143.523724] postgres[1686]: RETURN jsonb_build_object(1571vm-test-run-index_advisor> server # [ 143.524188] postgres[1686]: 'errors', jsonb_build_array(1572vm-test-run-index_advisor> server # [ 143.533463] postgres[1686]: jsonb_build_object(1573vm-test-run-index_advisor> server # [ 143.534795] postgres[1686]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1574vm-test-run-index_advisor> server # [ 143.535387] postgres[1686]: )1575vm-test-run-index_advisor> server # [ 143.535732] postgres[1686]: )1576vm-test-run-index_advisor> server # [ 143.550499] postgres[1686]: );1577vm-test-run-index_advisor> server # [ 143.551791] postgres[1686]: END IF;1578vm-test-run-index_advisor> server # [ 143.559020] postgres[1686]: END;1579vm-test-run-index_advisor> server # [ 143.559976] postgres[1686]: $$;1580vm-test-run-index_advisor> server # [ 143.573191] postgres[1686]: END IF;1581vm-test-run-index_advisor> server # [ 143.574743] postgres[1686]:1582vm-test-run-index_advisor> server # [ 143.575785] postgres[1686]: END;1583vm-test-run-index_advisor> server # [ 143.576081] postgres[1686]: $func$;1584vm-test-run-index_advisor> server # [ 143.603865] postgres[1686]: ::1 2026-10-07 20:39:26.044 UTC [1686] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1585vm-test-run-index_advisor> server # [ 143.611579] postgres[1686]: "operationName" text default null,1586vm-test-run-index_advisor> server # [ 143.612185] postgres[1686]: query text default null,1587vm-test-run-index_advisor> server # [ 143.618945] postgres[1686]: variables jsonb default null,1588vm-test-run-index_advisor> server # [ 143.619457] postgres[1686]: extensions jsonb default null1589vm-test-run-index_advisor> server # [ 143.623935] postgres[1686]: )1590vm-test-run-index_advisor> server # [ 143.625220] postgres[1686]: returns jsonb1591vm-test-run-index_advisor> server # [ 143.625574] postgres[1686]: language plpgsql1592vm-test-run-index_advisor> server # [ 143.625868] postgres[1686]: as $$1593vm-test-run-index_advisor> server # [ 143.629550] postgres[1686]: DECLARE1594vm-test-run-index_advisor> server # [ 143.630563] postgres[1686]: server_version float;1595vm-test-run-index_advisor> server # [ 143.631707] postgres[1686]: BEGIN1596vm-test-run-index_advisor> server # [ 143.631998] postgres[1686]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1597vm-test-run-index_advisor> server # [ 143.632274] postgres[1686]:1598vm-test-run-index_advisor> server # [ 143.639793] postgres[1686]: IF server_version >= 14 THEN1599vm-test-run-index_advisor> server # [ 143.653236] postgres[1686]: RETURN jsonb_build_object(1600vm-test-run-index_advisor> server # [ 143.663616] postgres[1686]: 'errors', jsonb_build_array(1601vm-test-run-index_advisor> server # [ 143.670077] postgres[1686]: jsonb_build_object(1602vm-test-run-index_advisor> server # [ 143.682366] postgres[1686]: 'message', 'pg_graphql extension is not enabled.'1603vm-test-run-index_advisor> server # [ 143.684332] postgres[1686]: )1604vm-test-run-index_advisor> server # [ 143.694613] postgres[1686]: )1605vm-test-run-index_advisor> server # [ 143.695770] postgres[1686]: );1606vm-test-run-index_advisor> server # [ 143.699678] postgres[1686]: ELSE1607vm-test-run-index_advisor> server # [ 143.709401] postgres[1686]: RETURN jsonb_build_object(1608vm-test-run-index_advisor> server # [ 143.710106] postgres[1686]: 'errors', jsonb_build_array(1609vm-test-run-index_advisor> server # [ 143.710721] postgres[1686]: jsonb_build_object(1610vm-test-run-index_advisor> server # [ 143.711524] postgres[1686]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1611vm-test-run-index_advisor> server # [ 143.712377] postgres[1686]: )1612vm-test-run-index_advisor> server # [ 143.727279] postgres[1686]: )1613vm-test-run-index_advisor> server # [ 143.741003] postgres[1686]: );1614vm-test-run-index_advisor> server # [ 143.742142] postgres[1686]: END IF;1615vm-test-run-index_advisor> server # [ 143.742981] postgres[1686]: END;1616vm-test-run-index_advisor> server # [ 143.743278] postgres[1686]: $$;1617vm-test-run-index_advisor> server # [ 143.743501] postgres[1686]: ::1 2026-10-07 20:39:26.088 UTC [1686] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1618vm-test-run-index_advisor> server # [ 143.760682] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: CREATE FUNCTION1619vm-test-run-index_advisor> server # [ 143.763007] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: CREATE FUNCTION1620vm-test-run-index_advisor> server # [ 143.763426] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping1621vm-test-run-index_advisor> server # [ 143.773456] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: DROP EXTENSION1622vm-test-run-index_advisor> server # [ 145.552253] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1681]: DO1623vm-test-run-index_advisor> server # [ 145.570013] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1624vm-test-run-index_advisor> server # [ 145.881978] postgres[1707]: ::1 2026-10-07 20:39:28.436 UTC [1707] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1625vm-test-run-index_advisor> server # [ 145.908527] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1706]: GRANT ROLE1626vm-test-run-index_advisor> server # [ 145.928839] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1627vm-test-run-index_advisor> server # [ 146.234310] postgres[1709]: ::1 2026-10-07 20:39:28.801 UTC [1709] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1628vm-test-run-index_advisor> server # [ 146.238653] postgres[1709]: returns event_trigger1629vm-test-run-index_advisor> server # [ 146.239443] postgres[1709]: language plpgsql1630vm-test-run-index_advisor> server # [ 146.241741] postgres[1709]: AS $func$1631vm-test-run-index_advisor> server # [ 146.246029] postgres[1709]: DECLARE1632vm-test-run-index_advisor> server # [ 146.247137] postgres[1709]: func_is_graphql_resolve bool;1633vm-test-run-index_advisor> server # [ 146.249363] postgres[1709]: BEGIN1634vm-test-run-index_advisor> server # [ 146.250080] postgres[1709]: func_is_graphql_resolve = (1635vm-test-run-index_advisor> server # [ 146.251410] postgres[1709]: SELECT n.proname = 'resolve'1636vm-test-run-index_advisor> server # [ 146.252613] postgres[1709]: FROM pg_event_trigger_ddl_commands() AS ev1637vm-test-run-index_advisor> server # [ 146.255873] postgres[1709]: LEFT JOIN pg_catalog.pg_proc AS n1638vm-test-run-index_advisor> server # [ 146.257676] postgres[1709]: ON ev.objid = n.oid1639vm-test-run-index_advisor> server # [ 146.258120] postgres[1709]: );1640vm-test-run-index_advisor> server # [ 146.259149] postgres[1709]:1641vm-test-run-index_advisor> server # [ 146.259612] postgres[1709]: IF func_is_graphql_resolve1642vm-test-run-index_advisor> server # [ 146.261710] postgres[1709]: THEN1643vm-test-run-index_advisor> server # [ 146.262605] postgres[1709]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1644vm-test-run-index_advisor> server # [ 146.265697] postgres[1709]: DROP FUNCTION IF EXISTS graphql_public.graphql;1645vm-test-run-index_advisor> server # [ 146.267436] postgres[1709]: create or replace function graphql_public.graphql(1646vm-test-run-index_advisor> server # [ 146.269346] postgres[1709]: "operationName" text default null,1647vm-test-run-index_advisor> server # [ 146.270217] postgres[1709]: query text default null,1648vm-test-run-index_advisor> server # [ 146.270770] postgres[1709]: variables jsonb default null,1649vm-test-run-index_advisor> server # [ 146.271208] postgres[1709]: extensions jsonb default null1650vm-test-run-index_advisor> server # [ 146.273906] postgres[1709]: )1651vm-test-run-index_advisor> server # [ 146.274563] postgres[1709]: returns jsonb1652vm-test-run-index_advisor> server # [ 146.275348] postgres[1709]: language sql1653vm-test-run-index_advisor> server # [ 146.275627] postgres[1709]: as $$1654vm-test-run-index_advisor> server # [ 146.277108] postgres[1709]: select graphql.resolve(1655vm-test-run-index_advisor> server # [ 146.278583] postgres[1709]: query := query,1656vm-test-run-index_advisor> server # [ 146.278933] postgres[1709]: variables := coalesce(variables, '{}'),1657vm-test-run-index_advisor> server # [ 146.281517] postgres[1709]: "operationName" := "operationName",1658vm-test-run-index_advisor> server # [ 146.282185] postgres[1709]: extensions := extensions1659vm-test-run-index_advisor> server # [ 146.283356] postgres[1709]: );1660vm-test-run-index_advisor> server # [ 146.285394] postgres[1709]: $$;1661vm-test-run-index_advisor> server # [ 146.286268] postgres[1709]:1662vm-test-run-index_advisor> server # [ 146.287097] postgres[1709]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1663vm-test-run-index_advisor> server # [ 146.288775] postgres[1709]: -- function in the extension so we need to grant permissions on existing entities AND1664vm-test-run-index_advisor> server # [ 146.290746] postgres[1709]: -- update default permissions to any others that are created after `graphql.resolve`1665vm-test-run-index_advisor> server # [ 146.291250] postgres[1709]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1666vm-test-run-index_advisor> server # [ 146.291626] postgres[1709]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1667vm-test-run-index_advisor> server # [ 146.292139] postgres[1709]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1668vm-test-run-index_advisor> server # [ 146.293792] postgres[1709]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1669vm-test-run-index_advisor> server # [ 146.294260] postgres[1709]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1670vm-test-run-index_advisor> server # [ 146.296623] postgres[1709]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1671vm-test-run-index_advisor> server # [ 146.298681] postgres[1709]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1672vm-test-run-index_advisor> server # [ 146.299733] postgres[1709]: END IF;1673vm-test-run-index_advisor> server # [ 146.300990] postgres[1709]:1674vm-test-run-index_advisor> server # [ 146.302174] postgres[1709]: END;1675vm-test-run-index_advisor> server # [ 146.303451] postgres[1709]: $func$;1676vm-test-run-index_advisor> server # [ 146.426321] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1708]: CREATE FUNCTION1677vm-test-run-index_advisor> server # [ 146.427635] postgres[1709]: ::1 2026-10-07 20:39:28.975 UTC [1709] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1678vm-test-run-index_advisor> server # [ 146.521896] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1708]: DROP EXTENSION1679vm-test-run-index_advisor> server # [ 148.451136] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1708]: DO1680vm-test-run-index_advisor> server # [ 148.504688] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1681vm-test-run-index_advisor> server # [ 149.642035] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1734]: DO1682vm-test-run-index_advisor> server # [ 150.559556] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1734]: DO1683vm-test-run-index_advisor> server # [ 150.679613] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1684vm-test-run-index_advisor> server # [ 151.178121] postgres[1761]: ::1 2026-10-07 20:39:33.718 UTC [1761] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s';1685vm-test-run-index_advisor> server # [ 151.189221] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1754]: ALTER ROLE1686vm-test-run-index_advisor> server # [ 151.225776] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221103090837_revoke_admin.sql1687vm-test-run-index_advisor> server # [ 151.747867] postgres[1767]: ::1 2026-10-07 20:39:34.305 UTC [1767] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator;1688vm-test-run-index_advisor> server # [ 151.778041] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1765]: REVOKE ROLE1689vm-test-run-index_advisor> server # [ 151.837131] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1690vm-test-run-index_advisor> server # [ 154.394796] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1768]: DO1691vm-test-run-index_advisor> server # [ 154.439303] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1692vm-test-run-index_advisor> server # [ 154.804031] postgres[1795]: ::1 2026-10-07 20:39:37.365 UTC [1795] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres;1693vm-test-run-index_advisor> server # [ 154.823291] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1792]: GRANT ROLE1694vm-test-run-index_advisor> server # [ 154.885295] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1695vm-test-run-index_advisor> server # [ 155.253335] postgres[1801]: ::1 2026-10-07 20:39:37.813 UTC [1801] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option;1696vm-test-run-index_advisor> server # [ 155.300012] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: GRANT1697vm-test-run-index_advisor> server # [ 155.311839] postgres[1801]: ::1 2026-10-07 20:39:37.873 UTC [1801] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option;1698vm-test-run-index_advisor> server # [ 155.349558] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: GRANT1699vm-test-run-index_advisor> server # [ 155.355708] postgres[1801]: ::1 2026-10-07 20:39:37.926 UTC [1801] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1700vm-test-run-index_advisor> server # [ 155.365583] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: GRANT1701vm-test-run-index_advisor> server # [ 155.370221] postgres[1801]: ::1 2026-10-07 20:39:37.942 UTC [1801] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1702vm-test-run-index_advisor> server # [ 155.394734] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: ALTER DEFAULT PRIVILEGES1703vm-test-run-index_advisor> server # [ 155.398947] postgres[1801]: ::1 2026-10-07 20:39:37.969 UTC [1801] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1704vm-test-run-index_advisor> server # [ 155.411440] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: ALTER DEFAULT PRIVILEGES1705vm-test-run-index_advisor> server # [ 155.420858] postgres[1801]: ::1 2026-10-07 20:39:37.988 UTC [1801] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1706vm-test-run-index_advisor> server # [ 155.439714] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1797]: ALTER DEFAULT PRIVILEGES1707vm-test-run-index_advisor> server # [ 155.508472] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1708vm-test-run-index_advisor> server # [ 155.841311] postgres[1803]: ::1 2026-10-07 20:39:38.410 UTC [1803] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres;1709vm-test-run-index_advisor> server # [ 155.865749] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1802]: GRANT ROLE1710vm-test-run-index_advisor> server # [ 155.890660] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1711vm-test-run-index_advisor> server # [ 156.183919] postgres[1805]: ::1 2026-10-07 20:39:38.748 UTC [1805] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin;1712vm-test-run-index_advisor> server # [ 156.201065] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1804]: GRANT ROLE1713vm-test-run-index_advisor> server # [ 156.231545] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1714vm-test-run-index_advisor> server # [ 156.606721] postgres[1807]: ::1 2026-10-07 20:39:39.152 UTC [1807] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit;1715vm-test-run-index_advisor> server # [ 156.613143] postgres[1807]: ::1 2026-10-07 20:39:39.182 UTC [1807] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit;1716vm-test-run-index_advisor> server # [ 156.638214] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: ALTER ROLE1717vm-test-run-index_advisor> server # [ 156.640880] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: ALTER ROLE1718vm-test-run-index_advisor> server # [ 156.641847] postgres[1807]: ::1 2026-10-07 20:39:39.203 UTC [1807] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit;1719vm-test-run-index_advisor> server # [ 156.643236] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: ALTER ROLE1720vm-test-run-index_advisor> server # [ 156.715713] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1806]: DO1721vm-test-run-index_advisor> server # [ 156.794055] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1722vm-test-run-index_advisor> server # [ 157.384752] postgres[1824]: ::1 2026-10-07 20:39:39.938 UTC [1824] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin;1723vm-test-run-index_advisor> server # [ 157.417000] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1814]: GRANT ROLE1724vm-test-run-index_advisor> server # [ 157.423228] postgres[1824]: ::1 2026-10-07 20:39:39.983 UTC [1824] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin;1725vm-test-run-index_advisor> server # [ 157.433023] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1814]: REVOKE ROLE1726vm-test-run-index_advisor> server # [ 157.483846] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1727vm-test-run-index_advisor> server # [ 158.038052] postgres[1831]: ::1 2026-10-07 20:39:40.599 UTC [1831] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1728vm-test-run-index_advisor> server # [ 158.042641] postgres[1831]: returns event_trigger1729vm-test-run-index_advisor> server # [ 158.049078] postgres[1831]: language plpgsql1730vm-test-run-index_advisor> server # [ 158.050272] postgres[1831]: AS $func$1731vm-test-run-index_advisor> server # [ 158.051533] postgres[1831]: DECLARE1732vm-test-run-index_advisor> server # [ 158.051906] postgres[1831]: func_is_graphql_resolve bool;1733vm-test-run-index_advisor> server # [ 158.059526] postgres[1831]: BEGIN1734vm-test-run-index_advisor> server # [ 158.060746] postgres[1831]: func_is_graphql_resolve = (1735vm-test-run-index_advisor> server # [ 158.061644] postgres[1831]: SELECT n.proname = 'resolve'1736vm-test-run-index_advisor> server # [ 158.061956] postgres[1831]: FROM pg_event_trigger_ddl_commands() AS ev1737vm-test-run-index_advisor> server # [ 158.062559] postgres[1831]: LEFT JOIN pg_catalog.pg_proc AS n1738vm-test-run-index_advisor> server # [ 158.063070] postgres[1831]: ON ev.objid = n.oid1739vm-test-run-index_advisor> server # [ 158.063746] postgres[1831]: );1740vm-test-run-index_advisor> server # [ 158.063974] postgres[1831]:1741vm-test-run-index_advisor> server # [ 158.069940] postgres[1831]: IF func_is_graphql_resolve1742vm-test-run-index_advisor> server # [ 158.071458] postgres[1831]: THEN1743vm-test-run-index_advisor> server # [ 158.073465] postgres[1831]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1744vm-test-run-index_advisor> server # [ 158.074173] postgres[1831]: DROP FUNCTION IF EXISTS graphql_public.graphql;1745vm-test-run-index_advisor> server # [ 158.074781] postgres[1831]: create or replace function graphql_public.graphql(1746vm-test-run-index_advisor> server # [ 158.075768] postgres[1831]: "operationName" text default null,1747vm-test-run-index_advisor> server # [ 158.077644] postgres[1831]: query text default null,1748vm-test-run-index_advisor> server # [ 158.078312] postgres[1831]: variables jsonb default null,1749vm-test-run-index_advisor> server # [ 158.078808] postgres[1831]: extensions jsonb default null1750vm-test-run-index_advisor> server # [ 158.079414] postgres[1831]: )1751vm-test-run-index_advisor> server # [ 158.079692] postgres[1831]: returns jsonb1752vm-test-run-index_advisor> server # [ 158.085659] postgres[1831]: language sql1753vm-test-run-index_advisor> server # [ 158.086123] postgres[1831]: as $$1754vm-test-run-index_advisor> server # [ 158.086459] postgres[1831]: select graphql.resolve(1755vm-test-run-index_advisor> server # [ 158.086728] postgres[1831]: query := query,1756vm-test-run-index_advisor> server # [ 158.086974] postgres[1831]: variables := coalesce(variables, '{}'),1757vm-test-run-index_advisor> server # [ 158.087839] postgres[1831]: "operationName" := "operationName",1758vm-test-run-index_advisor> server # [ 158.093316] postgres[1831]: extensions := extensions1759vm-test-run-index_advisor> server # [ 158.094063] postgres[1831]: );1760vm-test-run-index_advisor> server # [ 158.095001] postgres[1831]: $$;1761vm-test-run-index_advisor> server # [ 158.095272] postgres[1831]:1762vm-test-run-index_advisor> server # [ 158.095841] postgres[1831]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1763vm-test-run-index_advisor> server # [ 158.097608] postgres[1831]: -- function in the extension so we need to grant permissions on existing entities AND1764vm-test-run-index_advisor> server # [ 158.098039] postgres[1831]: -- update default permissions to any others that are created after `graphql.resolve`1765vm-test-run-index_advisor> server # [ 158.098625] postgres[1831]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1766vm-test-run-index_advisor> server # [ 158.099267] postgres[1831]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1767vm-test-run-index_advisor> server # [ 158.105057] postgres[1831]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1768vm-test-run-index_advisor> server # [ 158.106930] postgres[1831]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1769vm-test-run-index_advisor> server # [ 158.109536] postgres[1831]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1770vm-test-run-index_advisor> server # [ 158.110538] postgres[1831]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1771vm-test-run-index_advisor> server # [ 158.111193] postgres[1831]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1772vm-test-run-index_advisor> server # [ 158.111542] postgres[1831]:1773vm-test-run-index_advisor> server # [ 158.111780] postgres[1831]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1774vm-test-run-index_advisor> server # [ 158.112077] postgres[1831]: grant usage on schema graphql_public to postgres with grant option;1775vm-test-run-index_advisor> server # [ 158.112359] postgres[1831]: grant usage on schema graphql to postgres with grant option;1776vm-test-run-index_advisor> server # [ 158.118234] postgres[1831]: END IF;1777vm-test-run-index_advisor> server # [ 158.118828] postgres[1831]:1778vm-test-run-index_advisor> server # [ 158.119097] postgres[1831]: END;1779vm-test-run-index_advisor> server # [ 158.119345] postgres[1831]: $func$;1780vm-test-run-index_advisor> server # [ 158.167214] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1828]: CREATE FUNCTION1781vm-test-run-index_advisor> server # [ 158.168034] postgres[1831]: ::1 2026-10-07 20:39:40.736 UTC [1831] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1782vm-test-run-index_advisor> server # [ 158.257572] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1828]: DROP EXTENSION1783vm-test-run-index_advisor> server # [ 160.092834] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1828]: DO1784vm-test-run-index_advisor> server # [ 160.136009] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1785vm-test-run-index_advisor> server # [ 160.635139] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: DO1786vm-test-run-index_advisor> server # [ 160.646134] postgres[1851]: ::1 2026-10-07 20:39:43.201 UTC [1851] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1787vm-test-run-index_advisor> server # [ 160.647922] postgres[1851]: LANGUAGE plpgsql1788vm-test-run-index_advisor> server # [ 160.655832] postgres[1851]: AS $$1789vm-test-run-index_advisor> server # [ 160.657620] postgres[1851]: BEGIN1790vm-test-run-index_advisor> server # [ 160.657982] postgres[1851]: IF EXISTS (1791vm-test-run-index_advisor> server # [ 160.658244] postgres[1851]: SELECT1792vm-test-run-index_advisor> server # [ 160.658625] postgres[1851]: FROM pg_event_trigger_ddl_commands() AS ev1793vm-test-run-index_advisor> server # [ 160.658932] postgres[1851]: JOIN pg_extension AS ext1794vm-test-run-index_advisor> server # [ 160.659330] postgres[1851]: ON ev.objid = ext.oid1795vm-test-run-index_advisor> server # [ 160.659597] postgres[1851]: WHERE ext.extname = 'pg_cron'1796vm-test-run-index_advisor> server # [ 160.666078] postgres[1851]: )1797vm-test-run-index_advisor> server # [ 160.669227] postgres[1851]: THEN1798vm-test-run-index_advisor> server # [ 160.670291] postgres[1851]: grant usage on schema cron to postgres with grant option;1799vm-test-run-index_advisor> server # [ 160.671766] postgres[1851]:1800vm-test-run-index_advisor> server # [ 160.678012] postgres[1851]: alter default privileges in schema cron grant all on tables to postgres with grant option;1801vm-test-run-index_advisor> server # [ 160.681978] postgres[1851]: alter default privileges in schema cron grant all on functions to postgres with grant option;1802vm-test-run-index_advisor> server # [ 160.682663] postgres[1851]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1803vm-test-run-index_advisor> server # [ 160.683022] postgres[1851]:1804vm-test-run-index_advisor> server # [ 160.683239] postgres[1851]: alter default privileges for user supabase_admin in schema cron grant all1805vm-test-run-index_advisor> server # [ 160.683514] postgres[1851]: on sequences to postgres with grant option;1806vm-test-run-index_advisor> server # [ 160.683766] postgres[1851]: alter default privileges for user supabase_admin in schema cron grant all1807vm-test-run-index_advisor> server # [ 160.684781] postgres[1851]: on tables to postgres with grant option;1808vm-test-run-index_advisor> server # [ 160.685065] postgres[1851]: alter default privileges for user supabase_admin in schema cron grant all1809vm-test-run-index_advisor> server # [ 160.689119] postgres[1851]: on functions to postgres with grant option;1810vm-test-run-index_advisor> server # [ 160.689926] postgres[1851]:1811vm-test-run-index_advisor> server # [ 160.690720] postgres[1851]: grant all privileges on all tables in schema cron to postgres with grant option;1812vm-test-run-index_advisor> server # [ 160.691284] postgres[1851]: revoke all on table cron.job from postgres;1813vm-test-run-index_advisor> server # [ 160.691864] postgres[1851]: grant select on table cron.job to postgres with grant option;1814vm-test-run-index_advisor> server # [ 160.692225] postgres[1851]: END IF;1815vm-test-run-index_advisor> server # [ 160.692448] postgres[1851]: END;1816vm-test-run-index_advisor> server # [ 160.708870] postgres[1851]: $$;1817vm-test-run-index_advisor> server # [ 160.718302] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: CREATE FUNCTION1818vm-test-run-index_advisor> server # [ 160.719492] postgres[1851]: ::1 2026-10-07 20:39:43.291 UTC [1851] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access;1819vm-test-run-index_advisor> server # [ 160.739446] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: DROP EVENT TRIGGER1820vm-test-run-index_advisor> server # [ 160.741227] postgres[1851]: ::1 2026-10-07 20:39:43.313 UTC [1851] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1821vm-test-run-index_advisor> server # [ 160.761988] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: ALTER FUNCTION1822vm-test-run-index_advisor> server # [ 160.763555] postgres[1851]: ::1 2026-10-07 20:39:43.336 UTC [1851] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1823vm-test-run-index_advisor> server # [ 160.767842] postgres[1851]: WHEN TAG IN ('CREATE EXTENSION')1824vm-test-run-index_advisor> server # [ 160.770532] postgres[1851]: EXECUTE FUNCTION extensions.grant_pg_cron_access();1825vm-test-run-index_advisor> server # [ 160.771588] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1846]: CREATE EVENT TRIGGER1826vm-test-run-index_advisor> server # [ 160.848994] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1827vm-test-run-index_advisor> server # [ 161.384000] postgres[1866]: ::1 2026-10-07 20:39:43.936 UTC [1866] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s';1828vm-test-run-index_advisor> server # [ 161.423010] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1860]: ALTER ROLE1829vm-test-run-index_advisor> server # [ 161.464110] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1830vm-test-run-index_advisor> server # [ 161.905295] postgres[1869]: ::1 2026-10-07 20:39:44.472 UTC [1869] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin;1831vm-test-run-index_advisor> server # [ 161.932970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1868]: ALTER FUNCTION1832vm-test-run-index_advisor> server # [ 161.935611] postgres[1869]: ::1 2026-10-07 20:39:44.507 UTC [1869] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1833vm-test-run-index_advisor> server # [ 161.943702] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1868]: ALTER FUNCTION1834vm-test-run-index_advisor> server # [ 161.947356] postgres[1869]: ::1 2026-10-07 20:39:44.519 UTC [1869] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1835vm-test-run-index_advisor> server # [ 161.955470] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1868]: ALTER FUNCTION1836vm-test-run-index_advisor> server # [ 162.014180] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1837vm-test-run-index_advisor> server # [ 162.291816] postgres[1871]: ::1 2026-10-07 20:39:44.854 UTC [1871] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres;1838vm-test-run-index_advisor> server # [ 162.303751] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1870]: GRANT ROLE1839vm-test-run-index_advisor> server # [ 162.337097] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241031003909_create_orioledb.sql1840vm-test-run-index_advisor> server # [ 163.603363] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1872]: DO1841vm-test-run-index_advisor> server # [ 163.635812] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1842vm-test-run-index_advisor> server # [ 164.268898] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1887]: DO1843vm-test-run-index_advisor> server # [ 164.270038] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1887]: DO1844vm-test-run-index_advisor> server # [ 164.314794] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1845vm-test-run-index_advisor> server # [ 164.753475] postgres[1901]: ::1 2026-10-07 20:39:47.318 UTC [1901] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none;1846vm-test-run-index_advisor> server # [ 164.774997] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1897]: ALTER ROLE1847vm-test-run-index_advisor> server # [ 164.778220] postgres[1901]: ::1 2026-10-07 20:39:47.340 UTC [1901] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none;1848vm-test-run-index_advisor> server # [ 164.779696] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1897]: ALTER ROLE1849vm-test-run-index_advisor> server # [ 164.782297] postgres[1901]: ::1 2026-10-07 20:39:47.351 UTC [1901] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none;1850vm-test-run-index_advisor> server # [ 164.790965] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1897]: ALTER ROLE1851vm-test-run-index_advisor> server # [ 164.831351] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1852vm-test-run-index_advisor> server # [ 165.240126] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1902]: DO1853vm-test-run-index_advisor> server # [ 165.283140] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1854vm-test-run-index_advisor> server # [ 165.730758] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1908]: DO1855vm-test-run-index_advisor> server # [ 165.751630] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1856vm-test-run-index_advisor> server # [ 166.136064] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1910]: CREATE FUNCTION1857vm-test-run-index_advisor> server # [ 166.143556] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1910]: DO1858vm-test-run-index_advisor> server # [ 166.214612] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1859vm-test-run-index_advisor> server # [ 166.658714] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1912]: ALTER FUNCTION1860vm-test-run-index_advisor> server # [ 166.668943] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1912]: GRANT1861vm-test-run-index_advisor> server # [ 166.708651] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1862vm-test-run-index_advisor> server # [ 167.357442] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1922]: DROP EVENT TRIGGER1863vm-test-run-index_advisor> server # [ 167.384903] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1922]: ALTER FUNCTION1864vm-test-run-index_advisor> server # [ 167.398929] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1922]: CREATE EVENT TRIGGER1865vm-test-run-index_advisor> server # [ 167.495920] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1866vm-test-run-index_advisor> server # [ 167.947192] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1935]: DO1867vm-test-run-index_advisor> server # [ 167.977313] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql1868vm-test-run-index_advisor> server # [ 168.387806] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1940]: CREATE FUNCTION1869vm-test-run-index_advisor> server # [ 168.390687] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1940]: GRANT1870vm-test-run-index_advisor> server # [ 168.436999] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql1871vm-test-run-index_advisor> server # [ 168.719189] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: REVOKE ROLE1872vm-test-run-index_advisor> server # [ 168.751664] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: DO1873vm-test-run-index_advisor> server # [ 168.759488] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: DO1874vm-test-run-index_advisor> server # [ 168.770391] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: REVOKE ROLE1875vm-test-run-index_advisor> server # [ 168.782524] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: REVOKE1876vm-test-run-index_advisor> server # [ 168.805125] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1944]: DO1877vm-test-run-index_advisor> server # [ 168.864743] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql1878vm-test-run-index_advisor> server # [ 169.360687] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1946]: DO1879vm-test-run-index_advisor> server # [ 169.401281] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql1880vm-test-run-index_advisor> server # [ 169.861371] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1952]: DO1881vm-test-run-index_advisor> server # [ 169.910043] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql1882vm-test-run-index_advisor> server # [ 170.463607] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1954]: DO1883vm-test-run-index_advisor> server # [ 170.498545] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql1884vm-test-run-index_advisor> server # [ 171.029324] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1966]: ALTER ROLE1885vm-test-run-index_advisor> server # [ 171.099121] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251001204436_predefined_role_grants.sql1886vm-test-run-index_advisor> server # [ 171.566118] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1978]: GRANT ROLE1887vm-test-run-index_advisor> server # [ 171.587760] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1978]: DO1888vm-test-run-index_advisor> server # [ 171.629088] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql1889vm-test-run-index_advisor> server # [ 172.074973] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1984]: GRANT1890vm-test-run-index_advisor> server # [ 172.125470] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql1891vm-test-run-index_advisor> server # [ 172.423048] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: CREATE FUNCTION1892vm-test-run-index_advisor> server # [ 172.432842] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: REVOKE1893vm-test-run-index_advisor> server # [ 172.445472] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: REVOKE1894vm-test-run-index_advisor> server # [ 172.469578] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1986]: GRANT1895vm-test-run-index_advisor> server # [ 172.499408] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260211120934_supabase_privileged_role.sql1896vm-test-run-index_advisor> server # [ 172.783780] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1988]: DO1897vm-test-run-index_advisor> server # [ 172.841710] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql1898vm-test-run-index_advisor> server # [ 173.232146] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1990]: ALTER ROLE1899vm-test-run-index_advisor> server # [ 173.297928] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql1900vm-test-run-index_advisor> server # [ 173.944633] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1996]: DROP EXTENSION1901vm-test-run-index_advisor> server # [ 173.974659] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql1902vm-test-run-index_advisor> server # [ 174.547524] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2008]: CREATE FUNCTION1903vm-test-run-index_advisor> server # [ 174.549353] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2008]: DROP EVENT TRIGGER1904vm-test-run-index_advisor> server # [ 174.577671] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2008]: CREATE EVENT TRIGGER1905vm-test-run-index_advisor> server # [ 174.669865] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql1906vm-test-run-index_advisor> server # [ 175.146046] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2020]: DO1907vm-test-run-index_advisor> server # [ 175.210190] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2020]: CREATE FUNCTION1908vm-test-run-index_advisor> server # [ 175.261086] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql1909vm-test-run-index_advisor> server # [ 175.699461] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2026]: CREATE FUNCTION1910vm-test-run-index_advisor> server # [ 175.706497] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2026]: CREATE FUNCTION1911vm-test-run-index_advisor> server # [ 175.760668] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Running postgresql schema...1912vm-test-run-index_advisor> server # [ 176.023965] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER DATABASE1913vm-test-run-index_advisor> server # [ 176.032767] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER DATABASE1914vm-test-run-index_advisor> server # [ 176.101063] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1915vm-test-run-index_advisor> server # [ 176.166791] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1916vm-test-run-index_advisor> server # [ 176.222296] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1917vm-test-run-index_advisor> server # [ 176.279650] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1918vm-test-run-index_advisor> server # [ 176.342843] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1919vm-test-run-index_advisor> server # [ 176.440463] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1920vm-test-run-index_advisor> server # [ 176.605766] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1921vm-test-run-index_advisor> server # [ 176.737762] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1922vm-test-run-index_advisor> server # [ 176.901315] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1923vm-test-run-index_advisor> server # [ 176.918866] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2028]: ALTER ROLE1924vm-test-run-index_advisor> server # [ 176.983397] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1464]: Database initialization complete1925vm-test-run-index_advisor> server # [ 177.088464] systemd[1]: Finished Supabase Database Initialization.1926vm-test-run-index_advisor> server # [ 177.108597] systemd[1]: Reached target Multi-User System.1927vm-test-run-index_advisor> server # [ 177.143898] systemd[1]: Startup finished in 18.926s (kernel) + 2min 38.127s (userspace) = 2min 57.053s.1928vm-test-run-index_advisor> server: (finished: waiting for unit supabase-db-init.service, in 178.88 seconds)1929vm-test-run-index_advisor> subtest: Verify PostgreSQL 15 is our custom build1930vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();"1931vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.61 seconds)1932vm-test-run-index_advisor> server: must succeed: readlink -f $(which postgres)1933vm-test-run-index_advisor> server: (finished: must succeed: readlink -f $(which postgres), in 0.48 seconds)1934vm-test-run-index_advisor> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 1.10 seconds)1935vm-test-run-index_advisor> subtest: Verify ansible config loaded1936vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;"1937vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.59 seconds)1938vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;"1939vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.85 seconds)1940vm-test-run-index_advisor> (finished: subtest: Verify ansible config loaded, in 1.43 seconds)1941vm-test-run-index_advisor> subtest: Verify init scripts and migrations ran1942vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;"1943vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;", in 0.82 seconds)1944vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;"1945vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;", in 0.58 seconds)1946vm-test-run-index_advisor> (finished: subtest: Verify init scripts and migrations ran, in 1.39 seconds)1947vm-test-run-index_advisor> subtest: Check upgrade path with postgresql 151948vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1949vm-test-run-index_advisor> server # NOTICE: extension "index_advisor" does not exist, skipping1950vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" , in 0.61 seconds)1951vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" 1952vm-test-run-index_advisor> server # NOTICE: installing required extension "hypopg"1953vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" , in 2.11 seconds)1954vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" 1955vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" , in 0.55 seconds)1956vm-test-run-index_advisor> (finished: subtest: Check upgrade path with postgresql 15, in 3.28 seconds)1957vm-test-run-index_advisor> subtest: Check the install of the last version of the extension1958vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" 1959vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS index_advisor;" , in 0.68 seconds)1960vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" 1961vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION index_advisor WITH VERSION '0.2.0' CASCADE;" , in 1.66 seconds)1962vm-test-run-index_advisor> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" 1963vm-test-run-index_advisor> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'index_advisor';" , in 0.57 seconds)1964vm-test-run-index_advisor> (finished: subtest: Check the install of the last version of the extension, in 2.91 seconds)1965vm-test-run-index_advisor> subtest: switch to postgresql 171966vm-test-run-index_advisor> server # Checking switch inhibitors... done1967vm-test-run-index_advisor> server # stopping the following units: postgresql.service, supabase-db-init.service1968vm-test-run-index_advisor> server # [ 198.026196] nixos[2168]: switching to system configuration /nix/store/p6hpg7fs71y8djdag53jwb1wiq2rsacw-nixos-system-server-test1969vm-test-run-index_advisor> server # [ 198.098046] systemd[1]: Stopped target Remote File Systems.1970vm-test-run-index_advisor> server # [ 198.146682] systemd[1]: supabase-db-init.service: Deactivated successfully.1971vm-test-run-index_advisor> server # [ 198.162136] systemd[1]: Stopped Supabase Database Initialization.1972vm-test-run-index_advisor> server # [ 198.174502] systemd[1]: supabase-db-init.service: Consumed 8.696s CPU time, 3.5M memory peak, 89.6K incoming IP traffic, 120.7K outgoing IP traffic.1973vm-test-run-index_advisor> server # [ 198.231788] postgres[1423]: 2026-10-07 20:40:20.798 UTC [1423] LOG: received fast shutdown request1974vm-test-run-index_advisor> server # [ 198.239708] systemd[1]: Stopping PostgreSQL Database Server...1975vm-test-run-index_advisor> server # [ 198.254332] postgres[1423]: 2026-10-07 20:40:20.826 UTC [1423] LOG: aborting any active transactions1976vm-test-run-index_advisor> server # [ 198.317557] postgres[1460]: 2026-10-07 20:40:20.880 UTC [1460] FATAL: terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command1977vm-test-run-index_advisor> server # [ 198.318625] systemd[1]: Stopped target Local File Systems.1978vm-test-run-index_advisor> server # [ 198.336036] postgres[1462]: 2026-10-07 20:40:20.867 UTC [1462] LOG: pg_cron scheduler shutting down1979vm-test-run-index_advisor> server # [ 198.469270] postgres[1423]: 2026-10-07 20:40:21.041 UTC [1423] LOG: background worker "TimescaleDB Background Worker Launcher" (PID 1460) exited with exit code 11980vm-test-run-index_advisor> server # [ 198.516987] postgres[1423]: 2026-10-07 20:40:21.087 UTC [1423] LOG: background worker "logical replication launcher" (PID 1463) exited with exit code 11981vm-test-run-index_advisor> server # [ 198.581347] postgres[1423]: 2026-10-07 20:40:21.150 UTC [1423] LOG: background worker "pg_cron launcher" (PID 1462) exited with exit code 11982vm-test-run-index_advisor> server # [ 198.601850] postgres[1423]: 2026-10-07 20:40:21.172 UTC [1423] LOG: background worker "pg_net 0.20.4 worker" (PID 1505) exited with exit code 11983vm-test-run-index_advisor> server # [ 198.641419] postgres[1455]: 2026-10-07 20:40:21.186 UTC [1455] LOG: shutting down1984vm-test-run-index_advisor> server # [ 198.647747] postgres[1455]: 2026-10-07 20:40:21.206 UTC [1455] LOG: checkpoint starting: shutdown immediate1985vm-test-run-index_advisor> server # [ 199.197218] postgres[1455]: 2026-10-07 20:40:21.766 UTC [1455] LOG: checkpoint complete: wrote 278 buffers (1.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.225 s, sync=0.229 s, total=0.579 s; sync files=140, longest=0.018 s, average=0.002 s; distance=2046 kB, estimate=2046 kB1986vm-test-run-index_advisor> server # [ 199.364229] postgres[1423]: 2026-10-07 20:40:21.934 UTC [1423] LOG: database system is shut down1987vm-test-run-index_advisor> server # [ 199.462645] systemd[1]: postgresql.service: Deactivated successfully.1988vm-test-run-index_advisor> server # [ 199.471447] systemd[1]: Stopped PostgreSQL Database Server.1989vm-test-run-index_advisor> server # [ 199.481508] systemd[1]: postgresql.service: Consumed 42.535s CPU time, 124.2M memory peak, 54.9M written to disk, 124.9K incoming IP traffic, 93.8K outgoing IP traffic.1990vm-test-run-index_advisor> server # activating the configuration...1991vm-test-run-index_advisor> server # setting up /etc...1992vm-test-run-index_advisor> server # [ 207.750250] systemd[1]: Reload requested from client PID 2168 ('.switch-to-conf') (unit backdoor.service)...1993vm-test-run-index_advisor> server # [ 207.757919] systemd[1]: Reloading...