these 24 derivations will be built: /nix/store/r3173ysql3iq906asp3ipzki2i5ydw26-wrappers-0.6.4.drv /nix/store/690n3nhq2aay7b9f938i7nvhrq4hl4j8-wrappers.drv /nix/store/58y86cdy038kap3lvxm5sh563b70abz6-postgresql-and-plugins-15.19.drv /nix/store/j7m26xg8lsg7ngnv0i4y6ykxbp51lnjd-receipt.drv /nix/store/iyxz0zqk0230n28mg4q5s1kcb7l2jq64-postgresql-and-plugins-15.19.drv /nix/store/046iphxnqagbjadqaaxyn2wjg7gywfgp-supabase-db-init.drv /nix/store/chgrs7bpyvydwvgblg3ymfjj9060wvxx-system-path.drv /nix/store/xizvafwprglyj49viiqqlhp79ag1dgc7-dbus-1.drv /nix/store/fvrhrrg3pxzq31qjrjk3ci6v3w1z5anx-X-Restart-Triggers-dbus.drv /nix/store/36hxlmlbs1hb97vj6i8clrs71s191fbc-unit-dbus.service.drv /nix/store/jkr0grhd90clq1id6z711h7w0yh2zr6g-user-units.drv /nix/store/caydhmmdq3cdabb3pjp5qxmmgljky8c9-postgresql-init.drv /nix/store/avkwwxny9lnwa5c1r3nhjiqr972675h3-unit-postgresql.service.drv /nix/store/jfc8v86175jpdxl8mixfq20sh87p8vgk-unit-supabase-db-init.service.drv /nix/store/k8f7cgdz22ir031k3ivhbsigab0bdwsz-unit-dbus.service.drv /nix/store/kzx86y8li04s8691vhksb8qjsk19h2yp-system-units.drv /nix/store/5jpllfdi8zfl74b8biavh5ayld5zm52d-etc.drv /nix/store/svalll6p7qjzn9b1qzbs0g46433gc0fg-activate.drv /nix/store/x1nfl2mf9rjf6y5l48lp6q10r6859bxc-nixos-system-server-test.drv /nix/store/yskb97lxac4kal1kizbp0b0jzxy5vv4h-closure-info.drv /nix/store/ldxlni9qva54g5gr19503rdl3hznny1c-run-nixos-vm.drv /nix/store/ddv34d8lx8abni16w329m2hb8gxq921v-nixos-vm.drv /nix/store/mv3kmirbyy7jklrv02j67zh29qqizxhm-nixos-test-driver-plv8.drv /nix/store/p206hxfd68b9xx4v4s8j2zjf18nzdz7d-vm-test-run-plv8.drv building '/nix/store/mv3kmirbyy7jklrv02j67zh29qqizxhm-nixos-test-driver-plv8.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/p206hxfd68b9xx4v4s8j2zjf18nzdz7d-vm-test-run-plv8.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/p206hxfd68b9xx4v4s8j2zjf18nzdz7d-vm-test-run-plv8.drv' vm-test-run-plv8> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-plv8> start all VLans vm-test-run-plv8> start vlan vm-test-run-plv8> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-plv8> (finished: start all VLans, in 0.00 seconds) vm-test-run-plv8> Test will time out and terminate in 3600 seconds vm-test-run-plv8> run the VM test script vm-test-run-plv8> additionally exposed symbols: vm-test-run-plv8> server, vm-test-run-plv8> vlan1, vm-test-run-plv8> 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, debug vm-test-run-plv8> start all VMs vm-test-run-plv8> server: starting vm vm-test-run-plv8> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-plv8> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-plv8> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-plv8> qemu-system-aarch64: falling back to tcg vm-test-run-plv8> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-plv8> server # Formatting '/build/vm-state-server/tmp.gEMnLUBHb9', fmt=raw size=1073741824 vm-test-run-plv8> server # Discarding device blocks: 0/262144 done vm-test-run-plv8> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-plv8> server # Filesystem UUID: d29be819-e61e-4c27-90c1-7ca00e6bdac8 vm-test-run-plv8> server # Superblock backups stored on blocks: vm-test-run-plv8> server # 32768, 98304, 163840, 229376 vm-test-run-plv8> server # vm-test-run-plv8> server # Allocating group tables: 0/8 done vm-test-run-plv8> server # Writing inode tables: 0/8 done vm-test-run-plv8> server: QEMU running (pid 9) vm-test-run-plv8> server # Creating journal (8192 blocks): done vm-test-run-plv8> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-plv8> server # vm-test-run-plv8> server # Virtualisation disk image created. vm-test-run-plv8> (finished: start all VMs, in 0.17 seconds) vm-test-run-plv8> server: waiting for unit supabase-db-init.service vm-test-run-plv8> server: waiting for the VM to finish booting vm-test-run-plv8> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-plv8> 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 2025 vm-test-run-plv8> server # [ 0.000000] KASLR enabled vm-test-run-plv8> server # [ 0.000000] random: crng init done vm-test-run-plv8> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-plv8> server # [ 0.000000] efi: UEFI not found. vm-test-run-plv8> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-plv8> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f] vm-test-run-plv8> server # [ 0.000000] Zone ranges: vm-test-run-plv8> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-plv8> server # [ 0.000000] DMA32 empty vm-test-run-plv8> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] Device empty vm-test-run-plv8> server # [ 0.000000] Movable zone start for each node vm-test-run-plv8> server # [ 0.000000] Early memory node ranges vm-test-run-plv8> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-plv8> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1 vm-test-run-plv8> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-plv8> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-plv8> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-plv8> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-plv8> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-plv8> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-plv8> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-plv8> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-plv8> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-plv8> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-plv8> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-plv8> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-plv8> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-plv8> 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/4j5zz0sl951csvklrykdj0bjr29m1mjw-nixos-system-server-test/init regInfo=/nix/store/pii2210xdpc10rxq1z85mns0wlqkz6pz-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-plv8> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/pii2210xdpc10rxq1z85mns0wlqkz6pz-closure-info/registration", will be passed to user space. vm-test-run-plv8> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-plv8> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-plv8> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-plv8> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-plv8> server # [ 0.000000] Policy zone: Normal vm-test-run-plv8> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-plv8> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-plv8> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-plv8> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-plv8> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-plv8> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-plv8> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-plv8> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-plv8> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-plv8> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-plv8> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-plv8> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-plv8> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-plv8> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-plv8> server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-plv8> server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-plv8> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-plv8> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-plv8> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-plv8> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-plv8> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-plv8> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-plv8> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-plv8> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-plv8> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ad0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-plv8> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100ae0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-plv8> server # [ 0.000000] GICv3: using LPI property table @0x0000000100af0000 vm-test-run-plv8> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b00000 vm-test-run-plv8> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-plv8> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-plv8> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-plv8> server # [ 0.000035] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-plv8> server # [ 0.003152] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-plv8> server # [ 0.010682] Console: colour dummy device 80x25 vm-test-run-plv8> server # [ 0.014172] printk: legacy console [tty0] enabled vm-test-run-plv8> server # [ 0.019691] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-plv8> server # [ 0.020058] pid_max: default: 32768 minimum: 301 vm-test-run-plv8> server # [ 0.021721] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-plv8> server # [ 0.023008] landlock: Up and running. vm-test-run-plv8> server # [ 0.023047] Yama: becoming mindful. vm-test-run-plv8> server # [ 0.025624] LSM support for eBPF active vm-test-run-plv8> server # [ 0.028251] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.028406] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.055049] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-plv8> server # [ 0.063874] rcu: Hierarchical SRCU implementation. vm-test-run-plv8> server # [ 0.063954] rcu: Max phase no-delay instances is 1000. vm-test-run-plv8> server # [ 0.065131] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-plv8> server # [ 0.070034] fsl-mc MSI: its@8080000 domain created vm-test-run-plv8> server # [ 0.072221] EFI services will not be available. vm-test-run-plv8> server # [ 0.073746] smp: Bringing up secondary CPUs ... vm-test-run-plv8> server # [ 0.077378] Detected PIPT I-cache on CPU1 vm-test-run-plv8> server # [ 0.078492] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-plv8> server # [ 0.079065] GICv3: CPU1: using allocated LPI pending table @0x0000000100b10000 vm-test-run-plv8> server # [ 0.080549] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-plv8> server # [ 0.083430] smp: Brought up 1 node, 2 CPUs vm-test-run-plv8> server # [ 0.083545] SMP: Total of 2 processors activated. vm-test-run-plv8> server # [ 0.083582] CPU: All CPU(s) started at EL1 vm-test-run-plv8> server # [ 0.083722] CPU features: detected: Branch Target Identification vm-test-run-plv8> server # [ 0.083771] CPU features: detected: 32-bit EL0 Support vm-test-run-plv8> server # [ 0.083800] CPU features: detected: 32-bit EL1 Support vm-test-run-plv8> server # [ 0.083823] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-plv8> server # [ 0.083857] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-plv8> server # [ 0.083896] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-plv8> server # [ 0.083956] CPU features: detected: Common not Private translations vm-test-run-plv8> server # [ 0.083981] CPU features: detected: CRC32 instructions vm-test-run-plv8> server # [ 0.084003] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-plv8> server # [ 0.084031] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-plv8> server # [ 0.084053] CPU features: detected: Data independent timing control (DIT) vm-test-run-plv8> server # [ 0.084077] CPU features: detected: E0PD vm-test-run-plv8> server # [ 0.084096] CPU features: detected: Enhanced Counter Virtualization vm-test-run-plv8> server # [ 0.084119] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-plv8> server # [ 0.084143] CPU features: detected: Enhanced Privileged Access Never vm-test-run-plv8> server # [ 0.084166] CPU features: detected: Enhanced Virtualization Traps vm-test-run-plv8> server # [ 0.084188] CPU features: detected: Fine Grained Traps vm-test-run-plv8> server # [ 0.084242] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-plv8> server # [ 0.084320] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-plv8> server # [ 0.084364] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-plv8> server # [ 0.084390] CPU features: detected: LSE atomic instructions vm-test-run-plv8> server # [ 0.084412] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-plv8> server # [ 0.084437] CPU features: detected: Privileged Access Never vm-test-run-plv8> server # [ 0.084459] CPU features: detected: RAS Extension Support vm-test-run-plv8> server # [ 0.084479] CPU features: detected: Random Number Generator vm-test-run-plv8> server # [ 0.084501] CPU features: detected: Speculation barrier (SB) vm-test-run-plv8> server # [ 0.084522] CPU features: detected: Stage-2 Force Write-Back vm-test-run-plv8> server # [ 0.084544] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-plv8> server # [ 0.084571] CPU features: detected: TLB range maintenance instructions vm-test-run-plv8> server # [ 0.084594] CPU features: detected: WFx with timeout vm-test-run-plv8> server # [ 0.084632] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-plv8> server # [ 0.084656] CPU features: detected: Scalable Vector Extension vm-test-run-plv8> server # [ 0.090401] alternatives: applying system-wide alternatives vm-test-run-plv8> server # [ 0.103394] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-plv8> server # [ 0.103960] SVE: maximum available vector length 256 bytes per vector vm-test-run-plv8> server # [ 0.104041] SVE: default vector length 64 bytes per vector vm-test-run-plv8> server # [ 0.114691] Memory: 3922924K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved) vm-test-run-plv8> server # [ 0.134175] devtmpfs: initialized vm-test-run-plv8> server # [ 0.158400] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-plv8> server # [ 0.158848] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-plv8> server # [ 0.160930] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-plv8> server # [ 0.160983] 0 pages in range for non-PLT usage vm-test-run-plv8> server # [ 0.161020] 509248 pages in range for PLT usage vm-test-run-plv8> server # [ 0.163807] pinctrl core: initialized pinctrl subsystem vm-test-run-plv8> server # [ 0.172613] DMI not present or invalid. vm-test-run-plv8> server # [ 0.188312] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-plv8> server # [ 0.198971] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-plv8> server # [ 0.199880] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-plv8> server # [ 0.200870] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-plv8> server # [ 0.201085] audit: initializing netlink subsys (disabled) vm-test-run-plv8> server # [ 0.202814] audit: type=2000 audit(0.072:1): state=initialized audit_enabled=0 res=1 vm-test-run-plv8> server # [ 0.208063] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-plv8> server # [ 0.208102] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-plv8> server # [ 0.208138] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-plv8> server # [ 0.208164] thermal_sys: Registered thermal governor 'user_space' vm-test-run-plv8> server # [ 0.208189] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-plv8> server # [ 0.208735] cpuidle: using governor ladder vm-test-run-plv8> server # [ 0.208979] cpuidle: using governor menu vm-test-run-plv8> server # [ 0.210718] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-plv8> server # [ 0.211415] ASID allocator initialised with 65536 entries vm-test-run-plv8> server # [ 0.217379] Serial: AMBA PL011 UART driver vm-test-run-plv8> server # [ 0.267968] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-plv8> server # [ 0.268995] printk: legacy console [ttyAMA0] enabled vm-test-run-plv8> server # [ 0.305886] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.306051] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-plv8> server # [ 0.306172] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.306387] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-plv8> server # [ 0.306753] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.306850] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-plv8> server # [ 0.306935] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.307031] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-plv8> server # [ 0.317274] fbcon: Taking over console vm-test-run-plv8> server # [ 0.317803] ACPI: Interpreter disabled. vm-test-run-plv8> server # [ 0.324667] iommu: Default domain type: Translated vm-test-run-plv8> server # [ 0.324791] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-plv8> server # [ 0.336866] SCSI subsystem initialized vm-test-run-plv8> server # [ 0.338919] usbcore: registered new interface driver usbfs vm-test-run-plv8> server # [ 0.339929] usbcore: registered new interface driver hub vm-test-run-plv8> server # [ 0.340125] usbcore: registered new device driver usb vm-test-run-plv8> server # [ 0.342389] pps_core: LinuxPPS API ver. 1 registered vm-test-run-plv8> server # [ 0.342479] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-plv8> server # [ 0.342657] PTP clock support registered vm-test-run-plv8> server # [ 0.343762] EDAC MC: Ver: 3.0.0 vm-test-run-plv8> server # [ 0.345439] scmi_core: SCMI protocol bus registered vm-test-run-plv8> server # [ 0.347824] FPGA manager framework vm-test-run-plv8> server # [ 0.349039] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-plv8> server # [ 0.357432] vgaarb: loaded vm-test-run-plv8> server # [ 0.362168] clocksource: Switched to clocksource arch_sys_counter vm-test-run-plv8> server # [ 0.367104] VFS: Disk quotas dquot_6.6.0 vm-test-run-plv8> server # [ 0.367333] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-plv8> server # [ 0.369025] netfs: FS-Cache loaded vm-test-run-plv8> server # [ 0.371241] pnp: PnP ACPI: disabled vm-test-run-plv8> server # [ 0.391037] NET: Registered PF_INET protocol family vm-test-run-plv8> server # [ 0.392415] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-plv8> server # [ 0.490147] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-plv8> server # [ 0.490567] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-plv8> server # [ 0.490986] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-plv8> server # [ 0.492224] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-plv8> server # [ 0.492870] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-plv8> server # [ 0.494257] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-plv8> server # [ 0.494656] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.494942] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.496320] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-plv8> server # [ 0.496762] NET: Registered PF_XDP protocol family vm-test-run-plv8> server # [ 0.496980] PCI: CLS 0 bytes, default 64 vm-test-run-plv8> server # [ 0.498597] kvm [1]: HYP mode not available vm-test-run-plv8> server # [ 0.500680] Trying to unpack rootfs image as initramfs... vm-test-run-plv8> server # [ 0.586865] Initialise system trusted keyrings vm-test-run-plv8> server # [ 0.589045] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-plv8> server # [ 0.597059] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-plv8> server # [ 0.598644] 9p: Installing v9fs 9p2000 file system support vm-test-run-plv8> server # [ 0.667199] Key type asymmetric registered vm-test-run-plv8> server # [ 0.667400] Asymmetric key parser 'x509' registered vm-test-run-plv8> server # [ 0.668250] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-plv8> server # [ 0.669551] io scheduler mq-deadline registered vm-test-run-plv8> server # [ 0.669953] io scheduler kyber registered vm-test-run-plv8> server # [ 0.691013] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-plv8> server # [ 0.693695] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-plv8> server # [ 0.700269] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-plv8> server # [ 0.701535] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-plv8> server # [ 0.703011] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-plv8> server # [ 0.703588] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-plv8> server # [ 0.704558] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-plv8> server # [ 0.705232] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-plv8> server # [ 0.706912] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-plv8> server # [ 0.707247] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-plv8> server # [ 0.707379] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-plv8> server # [ 0.707505] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-plv8> server # [ 0.707605] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-plv8> server # [ 0.708729] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.711850] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.712119] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.712240] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.712704] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.713080] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-plv8> server # [ 0.713666] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-plv8> server # [ 0.713799] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.713887] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.713989] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.714799] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.715206] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-plv8> server # [ 0.715298] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.715392] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.715809] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.715955] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.716037] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.716133] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.716502] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.716632] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.716713] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.716809] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.717196] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.717684] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-plv8> server # [ 0.717784] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.717880] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.718507] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.719147] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.719244] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.719836] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.720076] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-plv8> server # [ 0.720992] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.721132] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.721231] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.721611] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.721747] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.721847] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.722242] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-plv8> server # [ 0.722457] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.723077] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.723727] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-plv8> server # [ 0.723959] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.724057] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.724435] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-plv8> server # [ 0.724923] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.725038] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.725137] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.727373] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-plv8> server # [ 0.728138] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-plv8> server # [ 0.728669] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729166] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729309] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729424] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729548] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729660] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729770] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.729900] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.730015] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.730129] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.730820] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.731174] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-plv8> server # [ 0.732918] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-plv8> server # [ 0.733121] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-plv8> server # [ 0.734243] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-plv8> server # [ 0.735034] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-plv8> server # [ 0.735467] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-plv8> server # [ 0.735567] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-plv8> server # [ 0.735672] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-plv8> server # [ 0.736020] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-plv8> server # [ 0.736512] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-plv8> server # [ 0.737168] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-plv8> server # [ 0.737413] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-plv8> server # [ 0.737687] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-plv8> server # [ 0.737795] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-plv8> server # [ 0.737890] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-plv8> server # [ 0.737979] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-plv8> server # [ 0.739007] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-plv8> server # [ 0.739599] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-plv8> server # [ 0.739779] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-plv8> server # [ 0.740069] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-plv8> server # [ 0.740164] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-plv8> server # [ 0.740421] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-plv8> server # [ 0.740692] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-plv8> server # [ 0.740778] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-plv8> server # [ 0.741637] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.801468] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.805960] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.812430] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.815572] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.817529] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.820971] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.825005] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.827973] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.830217] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.833685] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.837735] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.857863] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-plv8> server # [ 0.876359] msm_serial: driver initialized vm-test-run-plv8> server # [ 0.877421] SuperH (H)SCI(F) driver initialized vm-test-run-plv8> server # [ 0.877792] STM32 USART driver initialized vm-test-run-plv8> server # [ 0.930526] loop: module loaded vm-test-run-plv8> server # [ 0.931078] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-plv8> server # [ 0.936866] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-plv8> server # [ 0.944430] Freeing initrd memory: 10684K vm-test-run-plv8> server # [ 0.949883] megasas: 07.727.03.00-rc1 vm-test-run-plv8> server # [ 0.954165] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-plv8> server # [ 0.958082] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-plv8> server # [ 0.959778] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-plv8> server # [ 0.962951] Using buffer write method vm-test-run-plv8> server # [ 0.963596] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-plv8> server # [ 0.965814] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-plv8> server # [ 0.966032] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-plv8> server # [ 0.968324] Using buffer write method vm-test-run-plv8> server # [ 0.968544] Concatenating MTD devices: vm-test-run-plv8> server # [ 0.968614] (0): "0.flash" vm-test-run-plv8> server # [ 0.968664] (1): "0.flash" vm-test-run-plv8> server # [ 0.968713] into device "0.flash" vm-test-run-plv8> server # [ 0.995123] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-plv8> server # [ 1.006701] thunder_xcv, ver 1.0 vm-test-run-plv8> server # [ 1.007308] thunder_bgx, ver 1.0 vm-test-run-plv8> server # [ 1.007481] nicpf, ver 1.0 vm-test-run-plv8> server # [ 1.009475] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-plv8> server # [ 1.009625] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-plv8> server # [ 1.009965] hclge is initializing vm-test-run-plv8> server # [ 1.010152] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-plv8> server # [ 1.010225] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-plv8> server # [ 1.011320] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-plv8> server # [ 1.011722] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-plv8> server # [ 1.012293] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-plv8> server # [ 1.012525] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-plv8> server # [ 1.012745] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-plv8> server # [ 1.013048] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-plv8> server # [ 1.014134] sky2: driver version 1.30 vm-test-run-plv8> server # [ 1.023461] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-plv8> server # [ 1.023899] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-plv8> server # [ 1.026008] usbcore: registered new interface driver usb-storage vm-test-run-plv8> server # [ 1.027444] usbcore: registered new interface driver usbserial_generic vm-test-run-plv8> server # [ 1.027778] usbserial: USB Serial support registered for generic vm-test-run-plv8> server # [ 1.028508] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-plv8> server # [ 1.036733] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-plv8> server # [ 1.037789] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T12:22:13 UTC (1792671733) vm-test-run-plv8> server # [ 1.039526] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-plv8> server # [ 1.039877] i2c_dev: i2c /dev entries driver vm-test-run-plv8> server # [ 1.048913] hub 1-0:1.0: USB hub found vm-test-run-plv8> server # [ 1.049876] hub 1-0:1.0: 6 ports detected vm-test-run-plv8> server # [ 1.053293] sdhci: Secure Digital Host Controller Interface driver vm-test-run-plv8> server # [ 1.053439] sdhci: Copyright(c) Pierre Ossman vm-test-run-plv8> server # [ 1.054965] Synopsys Designware Multimedia Card Interface Driver vm-test-run-plv8> server # [ 1.056259] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-plv8> server # [ 1.063079] hid: raw HID events driver (C) Jiri Kosina vm-test-run-plv8> server # [ 1.065432] usbcore: registered new interface driver usbhid vm-test-run-plv8> server # [ 1.065561] usbhid: USB HID core driver vm-test-run-plv8> server # [ 1.075439] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-plv8> server # [ 1.087984] drop_monitor: Initializing network drop monitor service vm-test-run-plv8> server # [ 1.089275] NET: Registered PF_INET6 protocol family vm-test-run-plv8> server # [ 1.108371] Segment Routing with IPv6 vm-test-run-plv8> server # [ 1.108944] In-situ OAM (IOAM) with IPv6 vm-test-run-plv8> server # [ 1.109839] NET: Registered PF_PACKET protocol family vm-test-run-plv8> server # [ 1.112323] 9pnet: Installing 9P2000 support vm-test-run-plv8> server # [ 1.118019] Key type dns_resolver registered vm-test-run-plv8> server # [ 1.147787] registered taskstats version 1 vm-test-run-plv8> server # [ 1.150463] Loading compiled-in X.509 certificates vm-test-run-plv8> server # [ 1.198933] Demotion targets for Node 0: null vm-test-run-plv8> server # [ 1.202124] Key type .fscrypt registered vm-test-run-plv8> server # [ 1.202726] Key type fscrypt-provisioning registered vm-test-run-plv8> server # [ 1.210078] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-plv8> server # [ 1.249477] clk: Disabling unused clocks vm-test-run-plv8> server # [ 1.249717] PM: genpd: Disabling unused power domains vm-test-run-plv8> server # [ 1.250150] ALSA device list: vm-test-run-plv8> server # [ 1.251160] No soundcards found. vm-test-run-plv8> server # [ 1.305089] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-plv8> server # [ 1.323760] Freeing unused kernel memory: 4416K vm-test-run-plv8> server # [ 1.325737] Run /init as init process vm-test-run-plv8> server # [ 1.553207] 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/input1 vm-test-run-plv8> server # [ 1.694028] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 vm-test-run-plv8> server # [ 1.823850] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-plv8> server # [ 1.880388] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-plv8> server # [ 1.993381] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-plv8> server # [ 1.995043] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-plv8> server # [ 1.995066] [drm] features: -context_init vm-test-run-plv8> server # [ 1.997739] [drm] number of scanouts: 1 vm-test-run-plv8> server # [ 1.998172] [drm] number of cap sets: 0 vm-test-run-plv8> server # [ 2.011376] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-plv8> server # [ 2.013498] 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/input2 vm-test-run-plv8> server # [ 2.015354] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 vm-test-run-plv8> server # [ 2.040286] Console: switching to colour frame buffer device 160x50 vm-test-run-plv8> server # [ 2.049742] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-plv8> server # [ 4.766030] EXT4-fs (vda): mounted filesystem d29be819-e61e-4c27-90c1-7ca00e6bdac8 r/w with ordered data mode. Quota mode: none. vm-test-run-plv8> server # [ 7.034966] EXT4-fs (vda): re-mounted d29be819-e61e-4c27-90c1-7ca00e6bdac8. vm-test-run-plv8> server # [ 7.052232] booting system configuration /nix/store/4j5zz0sl951csvklrykdj0bjr29m1mjw-nixos-system-server-test vm-test-run-plv8> server # [ 17.492899] 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) vm-test-run-plv8> server # [ 17.503430] systemd[1]: Detected virtualization qemu. vm-test-run-plv8> server # [ 17.507821] systemd[1]: Detected architecture arm64. vm-test-run-plv8> server # [ 17.512638] systemd[1]: Detected first boot. vm-test-run-plv8> server # [ 17.539582] systemd[1]: Initializing machine ID from random generator. vm-test-run-plv8> server # [ 17.569015] systemd[1]: Hostname set to . vm-test-run-plv8> server # [ 18.663580] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-plv8> server # [ 20.077817] systemd[1]: Applying preset policy. vm-test-run-plv8> server # [ 23.127016] systemd[1]: Populated /etc with preset unit settings. vm-test-run-plv8> server # [ 25.949991] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-plv8> server # [ 26.135515] systemd[1]: Created slice Slice /system/getty. vm-test-run-plv8> server # [ 26.149269] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-plv8> server # [ 26.160271] systemd[1]: Created slice User and Session Slice. vm-test-run-plv8> server # [ 26.167588] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-plv8> server # [ 26.172793] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-plv8> server # [ 26.177441] systemd[1]: Expecting device /dev/hvc0... vm-test-run-plv8> server # [ 26.181759] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-plv8> server # [ 26.185796] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-plv8> server # [ 26.189878] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-plv8> server # [ 26.193818] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-plv8> server # [ 26.197845] systemd[1]: Reached target Path Units. vm-test-run-plv8> server # [ 26.202130] systemd[1]: Reached target Remote File Systems. vm-test-run-plv8> server # [ 26.206725] systemd[1]: Reached target Slice Units. vm-test-run-plv8> server # [ 26.210850] systemd[1]: Reached target Swaps. vm-test-run-plv8> server # [ 26.241782] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-plv8> server # [ 26.262233] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-plv8> server # [ 26.273075] systemd[1]: Listening on Journal Audit Socket. vm-test-run-plv8> server # [ 26.279310] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-plv8> server # [ 26.284826] systemd[1]: Listening on Journal Sockets. vm-test-run-plv8> server # [ 26.292807] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-plv8> server # [ 26.297713] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-plv8> server # [ 26.303953] systemd[1]: Listening on udev Control Socket. vm-test-run-plv8> server # [ 26.309718] systemd[1]: Listening on udev Kernel Socket. vm-test-run-plv8> server # [ 26.355874] systemd[1]: Mounting Huge Pages File System... vm-test-run-plv8> server # [ 26.389099] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-plv8> server # [ 26.432078] systemd[1]: Mounting Kernel Debug File System... vm-test-run-plv8> server # [ 26.488418] systemd[1]: Mounting Kernel Trace File System... vm-test-run-plv8> server # [ 26.632786] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-plv8> server # [ 26.777890] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-plv8> server # [ 26.799100] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-plv8> server # [ 26.933779] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-plv8> server # [ 26.951828] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-plv8> server # [ 26.960250] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-plv8> server # [ 27.099908] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-plv8> server # [ 27.111719] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-plv8> server # [ 27.123467] 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). vm-test-run-plv8> server # [ 27.204649] systemd[1]: Starting Journal Service... vm-test-run-plv8> server # [ 27.297835] systemd[1]: Starting Load Kernel Modules... vm-test-run-plv8> server # [ 27.379878] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-plv8> server # [ 27.446172] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-plv8> server # [ 27.459591] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-plv8> server # [ 27.532682] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-plv8> server # [ 27.629034] systemd[1]: Mounted Huge Pages File System. vm-test-run-plv8> server # [ 27.645195] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-plv8> server # [ 27.659820] systemd[1]: Mounted Kernel Debug File System. vm-test-run-plv8> server # [ 27.664613] systemd[1]: Mounted Kernel Trace File System. vm-test-run-plv8> server # [ 27.689293] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-plv8> server # [ 27.701812] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-plv8> server # [ 27.768114] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-plv8> server # [ 28.184797] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-plv8> server # [ 28.203031] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-plv8> server # [ 28.690017] systemd[1]: Finished Load Kernel Modules. vm-test-run-plv8> server # [ 28.728175] EXT4-fs (vda): re-mounted d29be819-e61e-4c27-90c1-7ca00e6bdac8. vm-test-run-plv8> server # [ 28.770925] systemd[1]: Starting Firewall... vm-test-run-plv8> server # [ 28.828201] systemd[1]: Starting Apply Kernel Variables... vm-test-run-plv8> server # [ 28.870374] fuse: init (API version 7.41) vm-test-run-plv8> server # [ 28.872645] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-plv8> server # [ 28.880589] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-plv8> server # [ 28.953309] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-plv8> server # [ 28.963219] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-plv8> server # [ 29.063184] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-plv8> server # [ 29.082400] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-plv8> server # [ 29.152004] systemd[1]: Mounting FUSE Control File System... vm-test-run-plv8> server: Guest root shell did not produce any data yet... vm-test-run-plv8> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-plv8> server # [ 29.470034] systemd[1]: Mounted FUSE Control File System. vm-test-run-plv8> server # [ 29.633301] systemd-journald[397]: Collecting audit messages is enabled. vm-test-run-plv8> server # [ 30.202637] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-plv8> server # [ 30.214200] systemd[1]: Reached target First Boot Complete. vm-test-run-plv8> server # [ 30.581363] systemd[1]: Finished Apply Kernel Variables. vm-test-run-plv8> server # [ 30.637446] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-plv8> server # [ 30.703332] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-plv8> server # [ 30.788430] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-plv8> server # [ 31.775277] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-plv8> server # [ 31.794207] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-plv8> server # [ 31.843568] systemd[1]: Mounting /run/wrappers... vm-test-run-plv8> server # [ 31.900048] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-plv8> server # [ 32.372643] systemd[1]: Mounted /run/wrappers. vm-test-run-plv8> server # [ 32.386785] systemd[1]: Reached target Local File Systems. vm-test-run-plv8> server # [ 32.421964] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-plv8> server # [ 32.491232] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-plv8> server # [ 32.501013] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-plv8> server # [ 32.570343] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-plv8> server # [ 32.600176] systemd[1]: Started Journal Service. vm-test-run-plv8> server # [ 32.446636] systemd-modules-load[398]: Module 'atkbd' is built in vm-test-run-plv8> server # [ 32.466844] systemd-modules-load[398]: Module 'loop' is built in vm-test-run-plv8> server # [ 32.555415] systemd-oomd[399]: No swap; memory pressure usage will be degraded vm-test-run-plv8> server # [ 32.607272] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-plv8> server # [ 33.554868] systemd-udevd[424]: Using default interface naming scheme 'v258'. vm-test-run-plv8> server # [ 33.694871] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-plv8> server # [ 33.909544] systemd-journald[397]: Received client request to flush runtime journal. vm-test-run-plv8> server # [ 34.290602] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-plv8> server # [ 34.372834] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-plv8> server # [ 34.453795] systemd[1]: Starting Create System Files and Directories... vm-test-run-plv8> server # [ 36.092161] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-plv8> server # [ 36.330279] systemd[1]: Finished Create System Files and Directories. vm-test-run-plv8> server # [ 36.409689] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-plv8> server # [ 36.453532] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-plv8> server # [ 37.356267] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-plv8> server # [ 38.905900] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-plv8> server # [ 38.983961] systemd[1]: Starting Update is Completed... vm-test-run-plv8> server # [ 39.740551] systemd[1]: Finished Update is Completed. vm-test-run-plv8> server # [ 43.550223] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-plv8> server # [ 43.558592] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-plv8> server # [ 43.574292] systemd[1]: suid-sgid-wrappers.service: Consumed 3.060s CPU time, 5.6M memory peak. vm-test-run-plv8> server # [ 46.550933] systemd[1]: Finished Firewall. vm-test-run-plv8> server # [ 48.063066] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-plv8> server # [ 48.066687] systemd[1]: Reached target System Initialization. vm-test-run-plv8> server # [ 48.084089] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-plv8> server # [ 48.097058] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-plv8> server # [ 48.120106] systemd[1]: Reached target Timer Units. vm-test-run-plv8> server # [ 48.130161] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-plv8> server # [ 48.135692] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-plv8> server # [ 48.147086] systemd[1]: Listening on Hostname Service Socket. vm-test-run-plv8> server # [ 48.163344] systemd[1]: Reached target Socket Units. vm-test-run-plv8> server # [ 48.177859] systemd[1]: Reached target Basic System. vm-test-run-plv8> server # [ 48.186789] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-plv8> server # [ 48.214153] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 48.291042] systemd[1]: Started Reset console on configuration changes. vm-test-run-plv8> server # [ 48.355275] systemd[1]: Starting resolvconf update... vm-test-run-plv8> server # [ 48.433608] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-plv8> server # [ 49.513917] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-plv8> server # [ 49.950861] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 49.971123] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-plv8> server # [ 50.029496] nsncd[631]: Oct 22 12:23:02.559 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-plv8> server # [ 50.046432] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-plv8> server # [ 50.063318] systemd[1]: Starting User Login Management... vm-test-run-plv8> server # [ 50.339913] dbus-daemon[634]: dbus[634]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-plv8> server # [ 50.757764] systemd[1]: Started D-Bus System Message Bus. vm-test-run-plv8> server # [ 51.714659] systemd-logind[650]: New seat seat0. vm-test-run-plv8> server # [ 51.790806] systemd[1]: Started User Login Management. vm-test-run-plv8> server # [ 51.816159] systemd[1]: Starting linger-users.service... vm-test-run-plv8> server # [ 51.974003] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-plv8> server # [ 51.982230] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-plv8> server # [ 51.997982] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-plv8> server # [ 52.006403] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-plv8> server # [ 52.015637] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 52.083960] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-plv8> server # [ 52.092001] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 52.268151] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 52.569742] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-plv8> server # [ 52.583975] systemd[1]: Finished linger-users.service. vm-test-run-plv8> server # [ 53.097634] nsncd[697]: Oct 22 12:23:05.727 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-plv8> server # [ 53.116976] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 53.119422] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-plv8> server # [ 53.127388] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-plv8> server # [ 53.595992] systemd[1]: Finished resolvconf update. vm-test-run-plv8> server # [ 53.615811] systemd[1]: Reached target Preparation for Network. vm-test-run-plv8> server # [ 53.681810] systemd[1]: Starting DHCP Client... vm-test-run-plv8> server # [ 55.432048] dhcpcd[742]: dhcpcd-10.2.4 starting vm-test-run-plv8> server # [ 55.567881] dhcpcd[766]: dev: loaded udev vm-test-run-plv8> server # [ 56.068311] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-plv8> server # [ 56.540464] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-plv8> server # [ 56.807482] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-plv8> server # [ 56.817978] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-plv8> server # [ 56.856020] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-plv8> server # [ 56.860709] cfg80211: failed to load regulatory.db vm-test-run-plv8> server # [ 57.661124] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-plv8> server # [ 57.494706] dhcpcd[766]: eth0: waiting for carrier vm-test-run-plv8> server # [ 57.496144] dhcpcd[766]: eth0: carrier acquired vm-test-run-plv8> server # [ 57.636431] dhcpcd[766]: DUID 00:01:00:01:32:6c:bc:ae:52:54:00:12:34:56 vm-test-run-plv8> server # [ 57.639762] dhcpcd[766]: eth0: IAID 00:12:34:56 vm-test-run-plv8> server # [ 57.646917] dhcpcd[766]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-plv8> server # [ 58.418274] systemd[1]: Found device /dev/hvc0. vm-test-run-plv8> server # [ 58.786402] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-plv8> server # [ 58.830867] systemd[1]: Started backdoor.service. vm-test-run-plv8> server: Guest root shell did not produce any data yet... vm-test-run-plv8> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-plv8> server # [ 59.275652] (udev-worker)[780]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-plv8> server # [ 59.346319] (udev-worker)[780]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server # [ 59.421970] (udev-worker)[788]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server # [ 59.446134] dhcpcd[766]: eth0: soliciting a DHCP lease vm-test-run-plv8> server # [ 59.449965] dhcpcd[766]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-plv8> server # connecting to host... vm-test-run-plv8> server # [ 59.480635] dhcpcd[766]: eth0: probing address 10.0.2.15/24 vm-test-run-plv8> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-plv8> server: connected to guest root shell vm-test-run-plv8> server: (connecting took 60.70 seconds) vm-test-run-plv8> server: (finished: waiting for the VM to finish booting, in 60.70 seconds) vm-test-run-plv8> server # [ 60.301486] dhcpcd[766]: eth0: soliciting an IPv6 router vm-test-run-plv8> server # [ 60.342923] dhcpcd[766]: eth0: Router Advertisement from fe80::2 vm-test-run-plv8> server # [ 60.350582] dhcpcd[766]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-plv8> server # [ 60.363221] dhcpcd[766]: eth0: adding route to fec0::/64 vm-test-run-plv8> server # [ 60.367456] dhcpcd[766]: eth0: adding default route via fe80::2 vm-test-run-plv8> server # [ 60.579642] systemd[1]: Found device Virtio network device. vm-test-run-plv8> server # [ 60.625791] systemd[1]: Starting Address configuration of eth1... vm-test-run-plv8> server # [ 60.649102] dhcpcd[766]: eth0: IAID 00:12:34:56 vm-test-run-plv8> server # [ 60.707913] dhcpcd[766]: eth0: soliciting an IPv6 router vm-test-run-plv8> server # [ 60.773518] dhcpcd[766]: eth0: soliciting a DHCP lease vm-test-run-plv8> server # [ 60.783710] dhcpcd[766]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-plv8> server # [ 60.793414] dhcpcd[766]: eth0: probing address 10.0.2.15/24 vm-test-run-plv8> server # [ 61.624669] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-plv8> server # [ 61.756389] network-addresses-eth1-start[814]: adding address 192.168.1.1/24... done vm-test-run-plv8> server # [ 61.988886] network-addresses-eth1-start[814]: adding address 2001:db8:1::1/64... done vm-test-run-plv8> server # [ 62.306732] systemd[1]: Finished Address configuration of eth1. vm-test-run-plv8> server # [ 62.347695] systemd[1]: Starting Networking Setup... vm-test-run-plv8> server # [ 64.990215] systemd[1]: Finished Networking Setup. vm-test-run-plv8> server # [ 65.006932] systemd[1]: Reached target Network. vm-test-run-plv8> server # [ 65.062155] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-plv8> server # [ 65.140113] systemd[1]: Starting Permit User Sessions... vm-test-run-plv8> server # [ 65.804233] systemd[1]: Finished Permit User Sessions. vm-test-run-plv8> server # [ 65.893640] systemd[1]: Started Getty on tty1. vm-test-run-plv8> server # [ 65.910799] systemd[1]: Reached target Login Prompts. vm-test-run-plv8> server # [ 66.279718] dhcpcd[766]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-plv8> server # [ 66.287690] dhcpcd[766]: eth0: adding route to 10.0.2.0/24 vm-test-run-plv8> server # [ 66.295498] dhcpcd[766]: eth0: adding default route via 10.0.2.2 vm-test-run-plv8> server # [ 66.794496] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[930]: Initializing database at /var/lib/postgresql/data vm-test-run-plv8> server # [ 69.008878] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: The files belonging to this database system will be owned by user "postgres". vm-test-run-plv8> server # [ 69.012748] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: This user must also own the server process. vm-test-run-plv8> server # [ 69.149455] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-plv8> server # [ 69.150793] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: The default database encoding has accordingly been set to "UTF8". vm-test-run-plv8> server # [ 69.158159] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: The default text search configuration will be set to "english". vm-test-run-plv8> server # [ 69.160103] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: Data page checksums are enabled. vm-test-run-plv8> server # [ 69.166818] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-plv8> server # [ 69.185299] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: creating subdirectories ... ok vm-test-run-plv8> server # [ 69.201237] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: selecting dynamic shared memory implementation ... posix vm-test-run-plv8> server # [ 69.965652] systemd[1]: Started DHCP Client. vm-test-run-plv8> server # [ 70.623908] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: selecting default max_connections ... 100 vm-test-run-plv8> server # [ 71.589392] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: selecting default shared_buffers ... 128MB vm-test-run-plv8> server # [ 80.115410] systemd-logind[650]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-plv8> server # [ 83.230853] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: selecting default time zone ... UTC vm-test-run-plv8> server # [ 83.282945] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: creating configuration files ... ok vm-test-run-plv8> server # [ 87.147100] mousedev: PS/2 mouse device common for all mice vm-test-run-plv8> server # [ 88.233916] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: running bootstrap script ... ok vm-test-run-plv8> server # [ 90.758070] systemd-logind[650]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-plv8> server # [ 102.724076] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: performing post-bootstrap initialization ... ok vm-test-run-plv8> server # [ 105.959180] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: syncing data to disk ... ok vm-test-run-plv8> server # [ 105.963399] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-plv8> server # [ 105.969887] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: 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. vm-test-run-plv8> server # [ 105.972021] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: Success. You can now start the database server using: vm-test-run-plv8> server # [ 105.978148] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[937]: /nix/store/jk4km6vsq6dg3cx4c21nfva5s1dr9w1r-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-plv8> server # [ 107.758069] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[930]: Validating PostgreSQL configuration... vm-test-run-plv8> server # [ 108.435734] 19ny9nls5xy27z699czszs2xwcs2wfhs-postgresql-init[1352]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault vm-test-run-plv8> server # [ 109.938395] postgres[1366]: 2026-10-22 12:24:02.579 UTC [1366] LOG: pgaudit extension initialized vm-test-run-plv8> server # [ 110.733913] postgres[1366]: 2026-10-22 12:24:03.380 UTC [1366] LOG: pgsodium primary server secret key loaded vm-test-run-plv8> server # [ 111.386104] postgres[1366]: 2026-10-22 12:24:04.018 UTC [1366] LOG: vault primary server secret key loaded vm-test-run-plv8> server # [ 112.220179] postgres[1366]: 2026-10-22 12:24:04.867 UTC [1366] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-plv8> server # [ 112.243514] postgres[1366]: 2026-10-22 12:24:04.890 UTC [1366] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-plv8> server # [ 112.255160] postgres[1366]: 2026-10-22 12:24:04.892 UTC [1366] LOG: listening on IPv6 address "::", port 5432 vm-test-run-plv8> server # [ 112.269241] postgres[1366]: 2026-10-22 12:24:04.912 UTC [1366] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-plv8> server # [ 112.430431] postgres[1410]: 2026-10-22 12:24:05.056 UTC [1410] LOG: database system was shut down at 2026-10-22 12:23:55 UTC vm-test-run-plv8> server # [ 112.779672] postgres[1366]: 2026-10-22 12:24:05.411 UTC [1366] LOG: database system is ready to accept connections vm-test-run-plv8> server # [ 112.803974] postgres[1420]: 2026-10-22 12:24:05.426 UTC [1420] FATAL: role "postgres" does not exist vm-test-run-plv8> server # [ 112.897585] systemd[1]: Started PostgreSQL Database Server. vm-test-run-plv8> server # [ 112.922035] systemd[1]: Starting Supabase Database Initialization... vm-test-run-plv8> server # [ 112.942727] postgres[1418]: 2026-10-22 12:24:05.580 UTC [1418] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-plv8> server # [ 113.079256] postgres[1366]: 2026-10-22 12:24:05.723 UTC [1366] LOG: background worker "pg_net 0.20.4 worker" (PID 1420) exited with exit code 1 vm-test-run-plv8> server # [ 113.262209] postgres[1421]: 2026-10-22 12:24:05.896 UTC [1421] LOG: pg_cron scheduler started vm-test-run-plv8> server # [ 113.346778] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Waiting for PostgreSQL to be ready... vm-test-run-plv8> server # [ 114.055592] postgres[1432]: ::1 2026-10-22 12:24:06.656 UTC [1432] root@root FATAL: role "root" does not exist vm-test-run-plv8> server # [ 114.089323] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: PostgreSQL is ready vm-test-run-plv8> server # [ 114.096266] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Creating postgres role... vm-test-run-plv8> server # [ 114.198353] postgres[1434]: 2026-10-22 12:24:06.833 UTC [1434] FATAL: role "postgres" does not exist vm-test-run-plv8> server # [ 114.270169] postgres[1366]: 2026-10-22 12:24:06.908 UTC [1366] LOG: background worker "pg_net 0.20.4 worker" (PID 1434) exited with exit code 1 vm-test-run-plv8> server # [ 114.518876] postgres[1435]: ::1 2026-10-22 12:24:07.159 UTC [1435] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-plv8> server # [ 114.552020] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1433]: CREATE ROLE vm-test-run-plv8> server # [ 114.825348] postgres[1437]: ::1 2026-10-22 12:24:07.468 UTC [1437] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-plv8> server # [ 114.855673] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1436]: ALTER DATABASE vm-test-run-plv8> server # [ 114.925793] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-plv8> server # [ 115.297425] postgres[1443]: ::1 2026-10-22 12:24:07.930 UTC [1443] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-plv8> server # [ 115.350982] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE PUBLICATION vm-test-run-plv8> server # [ 115.357384] postgres[1443]: ::1 2026-10-22 12:24:07.994 UTC [1443] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-plv8> server # [ 115.362506] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER ROLE vm-test-run-plv8> server # [ 115.366000] postgres[1443]: ::1 2026-10-22 12:24:08.013 UTC [1443] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-plv8> server # [ 115.385558] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 115.389966] postgres[1443]: ::1 2026-10-22 12:24:08.037 UTC [1443] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-plv8> server # [ 115.403068] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 115.408593] postgres[1443]: ::1 2026-10-22 12:24:08.053 UTC [1443] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-plv8> server # [ 115.429338] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT ROLE vm-test-run-plv8> server # [ 115.437877] postgres[1443]: ::1 2026-10-22 12:24:08.075 UTC [1443] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-plv8> server # [ 115.456269] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT vm-test-run-plv8> server # [ 115.465781] postgres[1443]: ::1 2026-10-22 12:24:08.109 UTC [1443] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-plv8> server # [ 115.485923] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 115.494529] postgres[1443]: ::1 2026-10-22 12:24:08.138 UTC [1443] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-plv8> server # [ 115.518123] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT ROLE vm-test-run-plv8> server # [ 115.526122] postgres[1443]: ::1 2026-10-22 12:24:08.161 UTC [1443] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-plv8> server # [ 115.551202] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE SCHEMA vm-test-run-plv8> server # [ 115.561986] postgres[1443]: ::1 2026-10-22 12:24:08.192 UTC [1443] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-plv8> server # [ 118.545973] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE EXTENSION vm-test-run-plv8> server # [ 118.552209] postgres[1443]: ::1 2026-10-22 12:24:11.183 UTC [1443] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-plv8> server # [ 119.886071] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE EXTENSION vm-test-run-plv8> server # [ 119.901047] postgres[1443]: ::1 2026-10-22 12:24:12.491 UTC [1443] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-plv8> server # [ 119.903811] postgres[1443]: ::1 2026-10-22 12:24:12.501 UTC [1443] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-plv8> server # [ 119.922564] postgres[1443]: ::1 2026-10-22 12:24:12.520 UTC [1443] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-plv8> server # [ 119.927975] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 119.930141] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 119.931683] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 119.954201] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: CREATE ROLE vm-test-run-plv8> server # [ 119.955942] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT ROLE vm-test-run-plv8> server # [ 119.956419] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT ROLE vm-test-run-plv8> server # [ 119.978130] postgres[1443]: ::1 2026-10-22 12:24:12.538 UTC [1443] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-plv8> server # [ 119.979402] postgres[1443]: ::1 2026-10-22 12:24:12.547 UTC [1443] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-plv8> server # [ 119.989976] postgres[1443]: ::1 2026-10-22 12:24:12.559 UTC [1443] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-plv8> server # [ 119.991363] postgres[1443]: ::1 2026-10-22 12:24:12.576 UTC [1443] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-plv8> server # [ 120.010098] postgres[1443]: ::1 2026-10-22 12:24:12.586 UTC [1443] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-plv8> server # [ 120.011335] postgres[1443]: ::1 2026-10-22 12:24:12.599 UTC [1443] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.017704] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT ROLE vm-test-run-plv8> server # [ 120.029643] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT ROLE vm-test-run-plv8> server # [ 120.035078] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT vm-test-run-plv8> server # [ 120.036017] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 120.052199] postgres[1443]: ::1 2026-10-22 12:24:12.611 UTC [1443] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.053898] postgres[1443]: ::1 2026-10-22 12:24:12.651 UTC [1443] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.056159] postgres[1443]: ::1 2026-10-22 12:24:12.682 UTC [1443] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.066491] postgres[1443]: ::1 2026-10-22 12:24:12.695 UTC [1443] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.067663] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 120.075442] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 120.085673] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: GRANT vm-test-run-plv8> server # [ 120.093994] postgres[1443]: ::1 2026-10-22 12:24:12.720 UTC [1443] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-plv8> server # [ 120.094541] postgres[1443]: ::1 2026-10-22 12:24:12.738 UTC [1443] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 120.095032] postgres[1443]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.106637] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER ROLE vm-test-run-plv8> server # [ 120.107938] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 120.121767] postgres[1443]: ::1 2026-10-22 12:24:12.752 UTC [1443] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 120.122535] postgres[1443]: on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.123814] postgres[1443]: ::1 2026-10-22 12:24:12.767 UTC [1443] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 120.141820] postgres[1443]: on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 120.143064] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 120.143980] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 120.155240] postgres[1443]: ::1 2026-10-22 12:24:12.784 UTC [1443] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-plv8> server # [ 120.159311] postgres[1443]: ::1 2026-10-22 12:24:12.800 UTC [1443] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-plv8> server # [ 120.171932] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER ROLE vm-test-run-plv8> server # [ 120.181838] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1438]: ALTER ROLE vm-test-run-plv8> server # [ 120.256895] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-plv8> server # [ 120.610339] postgres[1494]: ::1 2026-10-22 12:24:13.244 UTC [1494] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-plv8> server # [ 120.624050] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE SCHEMA vm-test-run-plv8> server # [ 120.625785] postgres[1494]: ::1 2026-10-22 12:24:13.264 UTC [1494] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-plv8> server # [ 120.627388] postgres[1494]: instance_id uuid NULL, vm-test-run-plv8> server # [ 120.627816] postgres[1494]: id uuid NOT NULL UNIQUE, vm-test-run-plv8> server # [ 120.633476] postgres[1494]: aud varchar(255) NULL, vm-test-run-plv8> server # [ 120.635198] postgres[1494]: "role" varchar(255) NULL, vm-test-run-plv8> server # [ 120.635539] postgres[1494]: email varchar(255) NULL UNIQUE, vm-test-run-plv8> server # [ 120.635959] postgres[1494]: encrypted_password varchar(255) NULL, vm-test-run-plv8> server # [ 120.638174] postgres[1494]: confirmed_at timestamptz NULL, vm-test-run-plv8> server # [ 120.639636] postgres[1494]: invited_at timestamptz NULL, vm-test-run-plv8> server # [ 120.640121] postgres[1494]: confirmation_token varchar(255) NULL, vm-test-run-plv8> server # [ 120.641140] postgres[1494]: confirmation_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 120.642869] postgres[1494]: recovery_token varchar(255) NULL, vm-test-run-plv8> server # [ 120.645662] postgres[1494]: recovery_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 120.646434] postgres[1494]: email_change_token varchar(255) NULL, vm-test-run-plv8> server # [ 120.647068] postgres[1494]: email_change varchar(255) NULL, vm-test-run-plv8> server # [ 120.647415] postgres[1494]: email_change_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 120.648594] postgres[1494]: last_sign_in_at timestamptz NULL, vm-test-run-plv8> server # [ 120.650571] postgres[1494]: raw_app_meta_data jsonb NULL, vm-test-run-plv8> server # [ 120.651972] postgres[1494]: raw_user_meta_data jsonb NULL, vm-test-run-plv8> server # [ 120.652290] postgres[1494]: is_super_admin bool NULL, vm-test-run-plv8> server # [ 120.653526] postgres[1494]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 120.654727] postgres[1494]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 120.656221] postgres[1494]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 120.658743] postgres[1494]: ); vm-test-run-plv8> server # [ 120.755848] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE TABLE vm-test-run-plv8> server # [ 120.761134] postgres[1494]: ::1 2026-10-22 12:24:13.408 UTC [1494] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-plv8> server # [ 120.789112] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE INDEX vm-test-run-plv8> server # [ 120.793664] postgres[1494]: ::1 2026-10-22 12:24:13.439 UTC [1494] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-plv8> server # [ 120.823303] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE INDEX vm-test-run-plv8> server # [ 120.825395] postgres[1494]: ::1 2026-10-22 12:24:13.471 UTC [1494] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-plv8> server # [ 120.837738] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: COMMENT vm-test-run-plv8> server # [ 120.838615] postgres[1494]: ::1 2026-10-22 12:24:13.486 UTC [1494] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-plv8> server # [ 120.841295] postgres[1494]: instance_id uuid NULL, vm-test-run-plv8> server # [ 120.845358] postgres[1494]: id bigserial NOT NULL, vm-test-run-plv8> server # [ 120.845905] postgres[1494]: "token" varchar(255) NULL, vm-test-run-plv8> server # [ 120.846461] postgres[1494]: user_id varchar(255) NULL, vm-test-run-plv8> server # [ 120.847355] postgres[1494]: revoked bool NULL, vm-test-run-plv8> server # [ 120.848024] postgres[1494]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 120.854793] postgres[1494]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 120.855304] postgres[1494]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 120.855658] postgres[1494]: ); vm-test-run-plv8> server # [ 120.914335] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE TABLE vm-test-run-plv8> server # [ 120.922117] postgres[1494]: ::1 2026-10-22 12:24:13.566 UTC [1494] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-plv8> server # [ 120.955118] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE INDEX vm-test-run-plv8> server # [ 120.958866] postgres[1494]: ::1 2026-10-22 12:24:13.602 UTC [1494] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id); vm-test-run-plv8> server # [ 120.993686] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE INDEX vm-test-run-plv8> server # [ 120.994582] postgres[1494]: ::1 2026-10-22 12:24:13.640 UTC [1494] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-plv8> server # [ 121.022873] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE INDEX vm-test-run-plv8> server # [ 121.023992] postgres[1494]: ::1 2026-10-22 12:24:13.672 UTC [1494] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.'; vm-test-run-plv8> server # [ 121.034112] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: COMMENT vm-test-run-plv8> server # [ 121.037121] postgres[1494]: ::1 2026-10-22 12:24:13.684 UTC [1494] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-plv8> server # [ 121.038070] postgres[1494]: id uuid NOT NULL, vm-test-run-plv8> server # [ 121.038715] postgres[1494]: uuid uuid NULL, vm-test-run-plv8> server # [ 121.039392] postgres[1494]: raw_base_config text NULL, vm-test-run-plv8> server # [ 121.039920] postgres[1494]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 121.040247] postgres[1494]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 121.045624] postgres[1494]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 121.047101] postgres[1494]: ); vm-test-run-plv8> server # [ 121.097726] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE TABLE vm-test-run-plv8> server # [ 121.099179] postgres[1494]: ::1 2026-10-22 12:24:13.745 UTC [1494] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-plv8> server # [ 121.106196] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: COMMENT vm-test-run-plv8> server # [ 121.110970] postgres[1494]: ::1 2026-10-22 12:24:13.756 UTC [1494] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-plv8> server # [ 121.111893] postgres[1494]: instance_id uuid NULL, vm-test-run-plv8> server # [ 121.117569] postgres[1494]: id uuid NOT NULL, vm-test-run-plv8> server # [ 121.118164] postgres[1494]: payload json NULL, vm-test-run-plv8> server # [ 121.119079] postgres[1494]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 121.119430] postgres[1494]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 121.119946] postgres[1494]: ); vm-test-run-plv8> server # [ 121.174018] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE TABLE vm-test-run-plv8> server # [ 121.176892] postgres[1494]: ::1 2026-10-22 12:24:13.823 UTC [1494] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id); vm-test-run-plv8> server # [ 121.208801] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE INDEX vm-test-run-plv8> server # [ 121.212210] postgres[1494]: ::1 2026-10-22 12:24:13.859 UTC [1494] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-plv8> server # [ 121.219267] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: COMMENT vm-test-run-plv8> server # [ 121.221698] postgres[1494]: ::1 2026-10-22 12:24:13.869 UTC [1494] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-plv8> server # [ 121.223160] postgres[1494]: "version" varchar(255) NOT NULL, vm-test-run-plv8> server # [ 121.225756] postgres[1494]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-plv8> server # [ 121.226526] postgres[1494]: ); vm-test-run-plv8> server # [ 121.255655] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE TABLE vm-test-run-plv8> server # [ 121.257196] postgres[1494]: ::1 2026-10-22 12:24:13.905 UTC [1494] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-plv8> server # [ 121.265431] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: COMMENT vm-test-run-plv8> server # [ 121.286623] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: INSERT 0 7 vm-test-run-plv8> server # [ 121.290661] postgres[1494]: ::1 2026-10-22 12:24:13.935 UTC [1494] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-plv8> server # [ 121.294177] postgres[1494]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-plv8> server # [ 121.295701] postgres[1494]: $$ language sql stable; vm-test-run-plv8> server # [ 121.308377] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE FUNCTION vm-test-run-plv8> server # [ 121.310886] postgres[1494]: ::1 2026-10-22 12:24:13.959 UTC [1494] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-plv8> server # [ 121.311560] postgres[1494]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-plv8> server # [ 121.311938] postgres[1494]: $$ language sql stable; vm-test-run-plv8> server # [ 121.321855] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE FUNCTION vm-test-run-plv8> server # [ 121.325332] postgres[1494]: ::1 2026-10-22 12:24:13.973 UTC [1494] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-plv8> server # [ 121.326285] postgres[1494]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-plv8> server # [ 121.326688] postgres[1494]: $$ language sql stable; vm-test-run-plv8> server # [ 121.341470] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE FUNCTION vm-test-run-plv8> server # [ 121.347194] postgres[1494]: ::1 2026-10-22 12:24:13.994 UTC [1494] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-plv8> server # [ 121.357639] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: GRANT vm-test-run-plv8> server # [ 121.362435] postgres[1494]: ::1 2026-10-22 12:24:14.007 UTC [1494] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 121.373575] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: CREATE ROLE vm-test-run-plv8> server # [ 121.375701] postgres[1494]: ::1 2026-10-22 12:24:14.019 UTC [1494] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.385499] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: GRANT vm-test-run-plv8> server # [ 121.386424] postgres[1494]: ::1 2026-10-22 12:24:14.033 UTC [1494] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.399963] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: GRANT vm-test-run-plv8> server # [ 121.400863] postgres[1494]: ::1 2026-10-22 12:24:14.049 UTC [1494] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.415705] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: GRANT vm-test-run-plv8> server # [ 121.418823] postgres[1494]: ::1 2026-10-22 12:24:14.063 UTC [1494] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-plv8> server # [ 121.426072] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: ALTER ROLE vm-test-run-plv8> server # [ 121.427114] postgres[1494]: ::1 2026-10-22 12:24:14.074 UTC [1494] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.445477] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: ALTER TABLE vm-test-run-plv8> server # [ 121.446553] postgres[1494]: ::1 2026-10-22 12:24:14.094 UTC [1494] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.463152] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: ALTER TABLE vm-test-run-plv8> server # [ 121.463999] postgres[1494]: ::1 2026-10-22 12:24:14.112 UTC [1494] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.476983] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: ALTER TABLE vm-test-run-plv8> server # [ 121.478224] postgres[1494]: ::1 2026-10-22 12:24:14.125 UTC [1494] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.486640] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: ALTER TABLE vm-test-run-plv8> server # [ 121.491735] postgres[1494]: ::1 2026-10-22 12:24:14.139 UTC [1494] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 121.506117] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1493]: ALTER TABLE vm-test-run-plv8> server # [ 121.610999] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-plv8> server # [ 122.043584] postgres[1505]: ::1 2026-10-22 12:24:14.672 UTC [1505] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-plv8> server # [ 122.098711] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1498]: CREATE SCHEMA vm-test-run-plv8> server # [ 122.107605] postgres[1505]: ::1 2026-10-22 12:24:14.716 UTC [1505] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 122.116282] postgres[1505]: ::1 2026-10-22 12:24:14.734 UTC [1505] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-plv8> server # [ 122.118935] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1498]: CREATE ROLE vm-test-run-plv8> server # [ 122.119619] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1498]: ALTER ROLE vm-test-run-plv8> server # [ 122.121202] postgres[1505]: ::1 2026-10-22 12:24:14.758 UTC [1505] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-plv8> server # [ 122.173905] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1498]: GRANT vm-test-run-plv8> server # [ 122.263801] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1498]: DO vm-test-run-plv8> server # [ 122.341471] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-plv8> server # [ 122.911692] postgres[1519]: ::1 2026-10-22 12:24:15.542 UTC [1519] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-plv8> server # [ 122.972163] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: ALTER ROLE vm-test-run-plv8> server # [ 122.980042] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: ALTER ROLE vm-test-run-plv8> server # [ 122.989067] postgres[1519]: ::1 2026-10-22 12:24:15.582 UTC [1519] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-plv8> server # [ 122.989915] postgres[1519]: ::1 2026-10-22 12:24:15.623 UTC [1519] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-plv8> server # [ 122.991250] postgres[1519]: RETURNS event_trigger vm-test-run-plv8> server # [ 122.992000] postgres[1519]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 122.995318] postgres[1519]: AS $$ vm-test-run-plv8> server # [ 122.995837] postgres[1519]: DECLARE vm-test-run-plv8> server # [ 123.001106] postgres[1519]: schema_is_cron bool; vm-test-run-plv8> server # [ 123.002206] postgres[1519]: BEGIN vm-test-run-plv8> server # [ 123.003792] postgres[1519]: schema_is_cron = ( vm-test-run-plv8> server # [ 123.006795] postgres[1519]: SELECT n.nspname = 'cron' vm-test-run-plv8> server # [ 123.007746] postgres[1519]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 123.009338] postgres[1519]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-plv8> server # [ 123.011566] postgres[1519]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 123.012929] postgres[1519]: ); vm-test-run-plv8> server # [ 123.013438] postgres[1519]: vm-test-run-plv8> server # [ 123.014875] postgres[1519]: IF schema_is_cron vm-test-run-plv8> server # [ 123.015462] postgres[1519]: THEN vm-test-run-plv8> server # [ 123.018519] postgres[1519]: grant usage on schema cron to postgres with grant option; vm-test-run-plv8> server # [ 123.019604] postgres[1519]: vm-test-run-plv8> server # [ 123.021888] postgres[1519]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 123.023170] postgres[1519]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-plv8> server # [ 123.029859] postgres[1519]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 123.031801] postgres[1519]: vm-test-run-plv8> server # [ 123.038114] postgres[1519]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 123.038927] postgres[1519]: on sequences to postgres with grant option; vm-test-run-plv8> server # [ 123.039423] postgres[1519]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 123.039991] postgres[1519]: on tables to postgres with grant option; vm-test-run-plv8> server # [ 123.041419] postgres[1519]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 123.043004] postgres[1519]: on functions to postgres with grant option; vm-test-run-plv8> server # [ 123.046813] postgres[1519]: vm-test-run-plv8> server # [ 123.048068] postgres[1519]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-plv8> server # [ 123.049663] postgres[1519]: vm-test-run-plv8> server # [ 123.050548] postgres[1519]: END IF; vm-test-run-plv8> server # [ 123.051564] postgres[1519]: vm-test-run-plv8> server # [ 123.059344] postgres[1519]: END; vm-test-run-plv8> server # [ 123.059818] postgres[1519]: $$; vm-test-run-plv8> server # [ 123.060725] postgres[1519]: ::1 2026-10-22 12:24:15.685 UTC [1519] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA') vm-test-run-plv8> server # [ 123.062143] postgres[1519]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-plv8> server # [ 123.063268] postgres[1519]: ::1 2026-10-22 12:24:15.707 UTC [1519] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-plv8> server # [ 123.069945] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: CREATE FUNCTION vm-test-run-plv8> server # [ 123.073278] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 123.075009] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: COMMENT vm-test-run-plv8> server # [ 123.076056] postgres[1519]: ::1 2026-10-22 12:24:15.722 UTC [1519] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-plv8> server # [ 123.081624] postgres[1519]: RETURNS event_trigger vm-test-run-plv8> server # [ 123.082596] postgres[1519]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 123.083558] postgres[1519]: AS $$ vm-test-run-plv8> server # [ 123.084492] postgres[1519]: BEGIN vm-test-run-plv8> server # [ 123.086414] postgres[1519]: IF EXISTS ( vm-test-run-plv8> server # [ 123.087534] postgres[1519]: SELECT 1 vm-test-run-plv8> server # [ 123.088049] postgres[1519]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 123.091000] postgres[1519]: JOIN pg_extension AS ext vm-test-run-plv8> server # [ 123.092058] postgres[1519]: ON ev.objid = ext.oid vm-test-run-plv8> server # [ 123.094984] postgres[1519]: WHERE ext.extname = 'pg_net' vm-test-run-plv8> server # [ 123.095890] postgres[1519]: ) vm-test-run-plv8> server # [ 123.097444] postgres[1519]: THEN vm-test-run-plv8> server # [ 123.098421] postgres[1519]: IF NOT EXISTS ( vm-test-run-plv8> server # [ 123.098977] postgres[1519]: SELECT 1 vm-test-run-plv8> server # [ 123.099289] postgres[1519]: FROM pg_roles vm-test-run-plv8> server # [ 123.102153] postgres[1519]: WHERE rolname = 'supabase_functions_admin' vm-test-run-plv8> server # [ 123.107257] postgres[1519]: ) vm-test-run-plv8> server # [ 123.108249] postgres[1519]: THEN vm-test-run-plv8> server # [ 123.110733] postgres[1519]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 123.111318] postgres[1519]: END IF; vm-test-run-plv8> server # [ 123.117628] postgres[1519]: vm-test-run-plv8> server # [ 123.118551] postgres[1519]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 123.119092] postgres[1519]: vm-test-run-plv8> server # [ 123.119371] postgres[1519]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-plv8> server # [ 123.120016] postgres[1519]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-plv8> server # [ 123.126297] postgres[1519]: vm-test-run-plv8> server # [ 123.127982] postgres[1519]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-plv8> server # [ 123.128657] postgres[1519]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-plv8> server # [ 123.134583] postgres[1519]: vm-test-run-plv8> server # [ 123.134990] postgres[1519]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-plv8> server # [ 123.140926] postgres[1519]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-plv8> server # [ 123.142694] postgres[1519]: vm-test-run-plv8> server # [ 123.143621] postgres[1519]: 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; vm-test-run-plv8> server # [ 123.146076] postgres[1519]: 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; vm-test-run-plv8> server # [ 123.150232] postgres[1519]: END IF; vm-test-run-plv8> server # [ 123.152002] postgres[1519]: END; vm-test-run-plv8> server # [ 123.160079] postgres[1519]: $$; vm-test-run-plv8> server # [ 123.167460] postgres[1519]: ::1 2026-10-22 12:24:15.739 UTC [1519] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-plv8> server # [ 123.173561] postgres[1519]: ::1 2026-10-22 12:24:15.783 UTC [1519] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-plv8> server # [ 123.175083] postgres[1519]: ::1 2026-10-22 12:24:15.796 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-plv8> server # [ 123.176188] postgres[1519]: ::1 2026-10-22 12:24:15.806 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 123.179211] postgres[1519]: ::1 2026-10-22 12:24:15.815 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 123.180130] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: CREATE FUNCTION vm-test-run-plv8> server # [ 123.186525] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: COMMENT vm-test-run-plv8> server # [ 123.186991] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: DO vm-test-run-plv8> server # [ 123.187276] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: CREATE ROLE vm-test-run-plv8> server # [ 123.187532] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.187779] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.189556] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.190439] postgres[1519]: ::1 2026-10-22 12:24:15.827 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 123.200941] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.202346] postgres[1519]: ::1 2026-10-22 12:24:15.847 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 123.208367] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.213771] postgres[1519]: ::1 2026-10-22 12:24:15.854 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 123.221853] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.222823] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.223520] postgres[1519]: ::1 2026-10-22 12:24:15.867 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 123.229006] postgres[1519]: ::1 2026-10-22 12:24:15.873 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 123.281508] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.282348] postgres[1519]: ::1 2026-10-22 12:24:15.927 UTC [1519] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 123.354650] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: GRANT vm-test-run-plv8> server # [ 123.371527] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1510]: DO vm-test-run-plv8> server # [ 123.427254] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running pgbouncer auth schema... vm-test-run-plv8> server # [ 123.705358] postgres[1524]: ::1 2026-10-22 12:24:16.344 UTC [1524] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-plv8> server # [ 123.718341] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1523]: CREATE ROLE vm-test-run-plv8> server # [ 123.720050] postgres[1524]: ::1 2026-10-22 12:24:16.364 UTC [1524] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-plv8> server # [ 123.730785] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1523]: REVOKE vm-test-run-plv8> server # [ 123.731905] postgres[1524]: ::1 2026-10-22 12:24:16.379 UTC [1524] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-plv8> server # [ 123.769208] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1523]: CREATE SCHEMA vm-test-run-plv8> server # [ 123.770267] postgres[1524]: ::1 2026-10-22 12:24:16.416 UTC [1524] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-plv8> server # [ 123.771675] postgres[1524]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-plv8> server # [ 123.774879] postgres[1524]: $$ vm-test-run-plv8> server # [ 123.776092] postgres[1524]: BEGIN vm-test-run-plv8> server # [ 123.783220] postgres[1524]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-plv8> server # [ 123.784569] postgres[1524]: vm-test-run-plv8> server # [ 123.789822] postgres[1524]: RETURN QUERY vm-test-run-plv8> server # [ 123.790399] postgres[1524]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-plv8> server # [ 123.790714] postgres[1524]: WHERE usename = p_usename; vm-test-run-plv8> server # [ 123.791559] postgres[1524]: END; vm-test-run-plv8> server # [ 123.791854] postgres[1524]: $$ LANGUAGE plpgsql vm-test-run-plv8> server # [ 123.792114] postgres[1524]: SET search_path = '' vm-test-run-plv8> server # [ 123.794521] postgres[1524]: SECURITY DEFINER; vm-test-run-plv8> server # [ 123.802209] postgres[1524]: ::1 2026-10-22 12:24:16.436 UTC [1524] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-plv8> server # [ 123.805673] postgres[1524]: ::1 2026-10-22 12:24:16.448 UTC [1524] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-plv8> server # [ 123.811978] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1523]: CREATE FUNCTION vm-test-run-plv8> server # [ 123.814170] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1523]: REVOKE vm-test-run-plv8> server # [ 123.815695] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1523]: GRANT vm-test-run-plv8> server # [ 123.865677] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running stat extension... vm-test-run-plv8> server # [ 124.315924] postgres[1530]: ::1 2026-10-22 12:24:16.954 UTC [1530] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-plv8> server # [ 124.328068] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1526]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-plv8> server # [ 124.365474] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1526]: CREATE SCHEMA vm-test-run-plv8> server # [ 124.370612] postgres[1530]: ::1 2026-10-22 12:24:17.018 UTC [1530] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-plv8> server # [ 126.067083] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1526]: CREATE EXTENSION vm-test-run-plv8> server # [ 126.167059] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-plv8> server # [ 126.538558] postgres[1556]: ::1 2026-10-22 12:24:19.162 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-plv8> server # [ 126.540001] postgres[1556]: ::1 2026-10-22 12:24:19.180 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 126.564845] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.566432] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.566979] postgres[1556]: ::1 2026-10-22 12:24:19.205 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 126.581972] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.582894] postgres[1556]: ::1 2026-10-22 12:24:19.223 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 126.589644] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.597775] postgres[1556]: ::1 2026-10-22 12:24:19.241 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 126.619135] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.626474] postgres[1556]: ::1 2026-10-22 12:24:19.272 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 126.645393] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.655667] postgres[1556]: ::1 2026-10-22 12:24:19.297 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 126.657261] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.658701] postgres[1556]: ::1 2026-10-22 12:24:19.305 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 126.681529] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.683414] postgres[1556]: ::1 2026-10-22 12:24:19.324 UTC [1556] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 126.719170] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: GRANT vm-test-run-plv8> server # [ 126.745460] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: DO vm-test-run-plv8> server # [ 126.747354] postgres[1556]: ::1 2026-10-22 12:24:19.391 UTC [1556] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-plv8> server # [ 126.757408] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1555]: ALTER ROLE vm-test-run-plv8> server # [ 126.801345] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-plv8> server # [ 127.094037] postgres[1558]: ::1 2026-10-22 12:24:19.735 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.122571] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.128095] postgres[1558]: ::1 2026-10-22 12:24:19.754 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.131128] postgres[1558]: ::1 2026-10-22 12:24:19.774 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.131963] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.139329] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.143688] postgres[1558]: ::1 2026-10-22 12:24:19.789 UTC [1558] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.149963] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER TABLE vm-test-run-plv8> server # [ 127.153319] postgres[1558]: ::1 2026-10-22 12:24:19.800 UTC [1558] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.164008] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER TABLE vm-test-run-plv8> server # [ 127.169076] postgres[1558]: ::1 2026-10-22 12:24:19.815 UTC [1558] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.177072] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER TABLE vm-test-run-plv8> server # [ 127.179641] postgres[1558]: ::1 2026-10-22 12:24:19.827 UTC [1558] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.191137] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER TABLE vm-test-run-plv8> server # [ 127.194109] postgres[1558]: ::1 2026-10-22 12:24:19.842 UTC [1558] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 127.205692] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER TABLE vm-test-run-plv8> server # [ 127.206600] postgres[1558]: ::1 2026-10-22 12:24:19.854 UTC [1558] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 127.211518] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.216853] postgres[1558]: ::1 2026-10-22 12:24:19.863 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 127.229529] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.233313] postgres[1558]: ::1 2026-10-22 12:24:19.880 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 127.242374] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.250309] postgres[1558]: ::1 2026-10-22 12:24:19.892 UTC [1558] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 127.254766] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: GRANT vm-test-run-plv8> server # [ 127.260926] postgres[1558]: ::1 2026-10-22 12:24:19.906 UTC [1558] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user; vm-test-run-plv8> server # [ 127.271884] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 127.274729] postgres[1558]: ::1 2026-10-22 12:24:19.920 UTC [1558] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user; vm-test-run-plv8> server # [ 127.282895] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 127.284929] postgres[1558]: ::1 2026-10-22 12:24:19.933 UTC [1558] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user; vm-test-run-plv8> server # [ 127.295985] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1557]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 127.365826] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-plv8> server # [ 127.607115] postgres[1560]: ::1 2026-10-22 12:24:20.246 UTC [1560] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-plv8> server # [ 127.623156] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1559]: CREATE SCHEMA vm-test-run-plv8> server # [ 127.663028] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-plv8> server # [ 128.098443] postgres[1565]: ::1 2026-10-22 12:24:20.717 UTC [1565] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-plv8> server # [ 128.113334] postgres[1565]: ::1 2026-10-22 12:24:20.750 UTC [1565] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 128.115904] postgres[1565]: ::1 2026-10-22 12:24:20.758 UTC [1565] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 128.133551] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1561]: GRANT vm-test-run-plv8> server # [ 128.134697] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1561]: GRANT vm-test-run-plv8> server # [ 128.143334] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1561]: GRANT vm-test-run-plv8> server # [ 128.150943] postgres[1565]: ::1 2026-10-22 12:24:20.765 UTC [1565] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 128.162346] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1561]: GRANT vm-test-run-plv8> server # [ 128.211119] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-plv8> server # [ 128.679737] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1568]: DO vm-test-run-plv8> server # [ 128.686632] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1568]: DO vm-test-run-plv8> server # [ 128.713714] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1568]: DO vm-test-run-plv8> server # [ 128.823292] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-plv8> server # [ 129.239168] postgres[1584]: ::1 2026-10-22 12:24:21.875 UTC [1584] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user; vm-test-run-plv8> server # [ 129.261489] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.262648] postgres[1584]: ::1 2026-10-22 12:24:21.902 UTC [1584] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user; vm-test-run-plv8> server # [ 129.265178] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.271168] postgres[1584]: ::1 2026-10-22 12:24:21.919 UTC [1584] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user; vm-test-run-plv8> server # [ 129.283357] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 129.331264] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-plv8> server # [ 129.617232] postgres[1592]: ::1 2026-10-22 12:24:22.260 UTC [1592] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-plv8> server # [ 129.633834] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1589]: ALTER ROLE vm-test-run-plv8> server # [ 129.681285] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-plv8> server # [ 130.111088] postgres[1598]: ::1 2026-10-22 12:24:22.743 UTC [1598] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-plv8> server # [ 130.121108] postgres[1598]: ::1 2026-10-22 12:24:22.766 UTC [1598] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-plv8> server # [ 130.130130] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-plv8> server # [ 130.135739] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.136677] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-plv8> server # [ 130.138344] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: DROP FUNCTION vm-test-run-plv8> server # [ 130.139126] postgres[1598]: ::1 2026-10-22 12:24:22.783 UTC [1598] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 130.140157] postgres[1598]: DECLARE vm-test-run-plv8> server # [ 130.148186] postgres[1598]: cmd record; vm-test-run-plv8> server # [ 130.149695] postgres[1598]: BEGIN vm-test-run-plv8> server # [ 130.153552] postgres[1598]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-plv8> server # [ 130.155998] postgres[1598]: LOOP vm-test-run-plv8> server # [ 130.159778] postgres[1598]: IF cmd.command_tag IN ( vm-test-run-plv8> server # [ 130.160135] postgres[1598]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-plv8> server # [ 130.160508] postgres[1598]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-plv8> server # [ 130.162557] postgres[1598]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-plv8> server # [ 130.163367] postgres[1598]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-plv8> server # [ 130.163696] postgres[1598]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-plv8> server # [ 130.163988] postgres[1598]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-plv8> server # [ 130.171091] postgres[1598]: , 'CREATE TRIGGER' vm-test-run-plv8> server # [ 130.173343] postgres[1598]: , 'CREATE TYPE' vm-test-run-plv8> server # [ 130.174004] postgres[1598]: , 'CREATE RULE' vm-test-run-plv8> server # [ 130.174299] postgres[1598]: , 'COMMENT' vm-test-run-plv8> server # [ 130.174762] postgres[1598]: ) vm-test-run-plv8> server # [ 130.176123] postgres[1598]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-plv8> server # [ 130.176678] postgres[1598]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-plv8> server # [ 130.186639] postgres[1598]: THEN vm-test-run-plv8> server # [ 130.187992] postgres[1598]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 130.197506] postgres[1598]: END IF; vm-test-run-plv8> server # [ 130.199030] postgres[1598]: END LOOP; vm-test-run-plv8> server # [ 130.199511] postgres[1598]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 130.215328] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: CREATE FUNCTION vm-test-run-plv8> server # [ 130.221904] postgres[1598]: ::1 2026-10-22 12:24:22.865 UTC [1598] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 130.223650] postgres[1598]: DECLARE vm-test-run-plv8> server # [ 130.232011] postgres[1598]: obj record; vm-test-run-plv8> server # [ 130.238072] postgres[1598]: BEGIN vm-test-run-plv8> server # [ 130.238960] postgres[1598]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-plv8> server # [ 130.239338] postgres[1598]: LOOP vm-test-run-plv8> server # [ 130.239604] postgres[1598]: IF obj.object_type IN ( vm-test-run-plv8> server # [ 130.239853] postgres[1598]: 'schema' vm-test-run-plv8> server # [ 130.240096] postgres[1598]: , 'table' vm-test-run-plv8> server # [ 130.251194] postgres[1598]: , 'foreign table' vm-test-run-plv8> server # [ 130.252053] postgres[1598]: , 'view' vm-test-run-plv8> server # [ 130.257947] postgres[1598]: , 'materialized view' vm-test-run-plv8> server # [ 130.259561] postgres[1598]: , 'function' vm-test-run-plv8> server # [ 130.266343] postgres[1598]: , 'trigger' vm-test-run-plv8> server # [ 130.270529] postgres[1598]: , 'type' vm-test-run-plv8> server # [ 130.271797] postgres[1598]: , 'rule' vm-test-run-plv8> server # [ 130.274562] postgres[1598]: ) vm-test-run-plv8> server # [ 130.275589] postgres[1598]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-plv8> server # [ 130.276365] postgres[1598]: THEN vm-test-run-plv8> server # [ 130.283244] postgres[1598]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 130.293374] postgres[1598]: END IF; vm-test-run-plv8> server # [ 130.294599] postgres[1598]: END LOOP; vm-test-run-plv8> server # [ 130.296726] postgres[1598]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 130.306333] postgres[1598]: ::1 2026-10-22 12:24:22.888 UTC [1598] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-plv8> server # [ 130.313816] postgres[1598]: ::1 2026-10-22 12:24:22.911 UTC [1598] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-plv8> server # [ 130.319048] postgres[1598]: ON ddl_command_end vm-test-run-plv8> server # [ 130.319771] postgres[1598]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-plv8> server # [ 130.323684] postgres[1598]: ::1 2026-10-22 12:24:22.934 UTC [1598] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-plv8> server # [ 130.324214] postgres[1598]: ::1 2026-10-22 12:24:22.944 UTC [1598] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-plv8> server # [ 130.324820] postgres[1598]: ON sql_drop vm-test-run-plv8> server # [ 130.334048] postgres[1598]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-plv8> server # [ 130.349493] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: CREATE FUNCTION vm-test-run-plv8> server # [ 130.367230] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-plv8> server # [ 130.374114] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.375216] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 130.382038] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-plv8> server # [ 130.382721] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 130.383195] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1593]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 130.399799] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-plv8> server # [ 131.397315] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1599]: DO vm-test-run-plv8> server # [ 131.450839] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-plv8> server # [ 132.002064] postgres[1614]: ::1 2026-10-22 12:24:24.620 UTC [1614] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-plv8> server # [ 132.125646] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: CREATE SCHEMA vm-test-run-plv8> server # [ 132.126769] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping vm-test-run-plv8> server # [ 132.127753] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: DROP FUNCTION vm-test-run-plv8> server # [ 132.128198] postgres[1614]: ::1 2026-10-22 12:24:24.752 UTC [1614] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-plv8> server # [ 132.137456] postgres[1614]: ::1 2026-10-22 12:24:24.778 UTC [1614] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 132.144172] postgres[1614]: "operationName" text default null, vm-test-run-plv8> server # [ 132.145755] postgres[1614]: query text default null, vm-test-run-plv8> server # [ 132.147890] postgres[1614]: variables jsonb default null, vm-test-run-plv8> server # [ 132.162446] postgres[1614]: extensions jsonb default null vm-test-run-plv8> server # [ 132.165848] postgres[1614]: ) vm-test-run-plv8> server # [ 132.170448] postgres[1614]: returns jsonb vm-test-run-plv8> server # [ 132.174423] postgres[1614]: language plpgsql vm-test-run-plv8> server # [ 132.181716] postgres[1614]: as $$ vm-test-run-plv8> server # [ 132.190399] postgres[1614]: DECLARE vm-test-run-plv8> server # [ 132.193753] postgres[1614]: server_version float; vm-test-run-plv8> server # [ 132.199173] postgres[1614]: BEGIN vm-test-run-plv8> server # [ 132.200778] postgres[1614]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 132.203235] postgres[1614]: vm-test-run-plv8> server # [ 132.203592] postgres[1614]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 132.210203] postgres[1614]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 132.211225] postgres[1614]: 'data', null::jsonb, vm-test-run-plv8> server # [ 132.212066] postgres[1614]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-plv8> server # [ 132.220451] postgres[1614]: ); vm-test-run-plv8> server # [ 132.229617] postgres[1614]: ELSE vm-test-run-plv8> server # [ 132.230255] postgres[1614]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 132.231379] postgres[1614]: 'data', null::jsonb, vm-test-run-plv8> server # [ 132.231741] postgres[1614]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-plv8> server # [ 132.241812] postgres[1614]: ); vm-test-run-plv8> server # [ 132.242381] postgres[1614]: END IF; vm-test-run-plv8> server # [ 132.243901] postgres[1614]: END; vm-test-run-plv8> server # [ 132.250688] postgres[1614]: $$; vm-test-run-plv8> server # [ 132.260065] postgres[1614]: ::1 2026-10-22 12:24:24.824 UTC [1614] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.274635] postgres[1614]: ::1 2026-10-22 12:24:24.854 UTC [1614] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.286018] postgres[1614]: ::1 2026-10-22 12:24:24.876 UTC [1614] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.286642] postgres[1614]: ::1 2026-10-22 12:24:24.900 UTC [1614] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.287289] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: CREATE FUNCTION vm-test-run-plv8> server # [ 132.287751] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: GRANT vm-test-run-plv8> server # [ 132.288514] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 132.317899] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 132.318691] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 132.322079] postgres[1614]: ::1 2026-10-22 12:24:24.934 UTC [1614] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 132.323202] postgres[1614]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.323920] postgres[1614]: ::1 2026-10-22 12:24:24.950 UTC [1614] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 132.331610] postgres[1614]: on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.346054] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 132.365103] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 132.365973] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 132.368350] postgres[1614]: ::1 2026-10-22 12:24:24.971 UTC [1614] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 132.381830] postgres[1614]: on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.382545] postgres[1614]: ::1 2026-10-22 12:24:25.011 UTC [1614] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 132.392045] postgres[1614]: RETURNS event_trigger vm-test-run-plv8> server # [ 132.399844] postgres[1614]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 132.410219] postgres[1614]: AS $func$ vm-test-run-plv8> server # [ 132.418622] postgres[1614]: DECLARE vm-test-run-plv8> server # [ 132.426162] postgres[1614]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 132.426972] postgres[1614]: BEGIN vm-test-run-plv8> server # [ 132.428291] postgres[1614]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 132.437260] postgres[1614]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 132.445079] postgres[1614]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 132.453165] postgres[1614]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 132.453981] postgres[1614]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 132.455069] postgres[1614]: ); vm-test-run-plv8> server # [ 132.455598] postgres[1614]: vm-test-run-plv8> server # [ 132.455896] postgres[1614]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 132.456194] postgres[1614]: THEN vm-test-run-plv8> server # [ 132.461684] postgres[1614]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.463603] postgres[1614]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.469347] postgres[1614]: vm-test-run-plv8> server # [ 132.470007] postgres[1614]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.470628] postgres[1614]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.471617] postgres[1614]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.472005] postgres[1614]: vm-test-run-plv8> server # [ 132.477280] postgres[1614]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 132.478670] postgres[1614]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 132.485442] postgres[1614]: "operationName" text default null, vm-test-run-plv8> server # [ 132.486564] postgres[1614]: query text default null, vm-test-run-plv8> server # [ 132.487328] postgres[1614]: variables jsonb default null, vm-test-run-plv8> server # [ 132.494942] postgres[1614]: extensions jsonb default null vm-test-run-plv8> server # [ 132.521069] postgres[1614]: ) vm-test-run-plv8> server # [ 132.521917] postgres[1614]: returns jsonb vm-test-run-plv8> server # [ 132.522893] postgres[1614]: language sql vm-test-run-plv8> server # [ 132.523547] postgres[1614]: as $$ vm-test-run-plv8> server # [ 132.523993] postgres[1614]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-plv8> server # [ 132.537618] postgres[1614]: $$; vm-test-run-plv8> server # [ 132.538145] postgres[1614]: vm-test-run-plv8> server # [ 132.538939] postgres[1614]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 132.539972] postgres[1614]: END IF; vm-test-run-plv8> server # [ 132.565954] postgres[1614]: vm-test-run-plv8> server # [ 132.566492] postgres[1614]: END; vm-test-run-plv8> server # [ 132.566757] postgres[1614]: $func$; vm-test-run-plv8> server # [ 132.567463] postgres[1614]: ::1 2026-10-22 12:24:25.071 UTC [1614] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-plv8> server # [ 132.578419] postgres[1614]: ::1 2026-10-22 12:24:25.091 UTC [1614] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION') vm-test-run-plv8> server # [ 132.579759] postgres[1614]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-plv8> server # [ 132.611484] postgres[1614]: ::1 2026-10-22 12:24:25.118 UTC [1614] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql'; vm-test-run-plv8> server # [ 132.642928] postgres[1614]: ::1 2026-10-22 12:24:25.138 UTC [1614] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-plv8> server # [ 132.655983] postgres[1614]: RETURNS event_trigger vm-test-run-plv8> server # [ 132.681291] postgres[1614]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 132.685596] postgres[1614]: AS $func$ vm-test-run-plv8> server # [ 132.686185] postgres[1614]: DECLARE vm-test-run-plv8> server # [ 132.686589] postgres[1614]: graphql_is_dropped bool; vm-test-run-plv8> server # [ 132.698579] postgres[1614]: BEGIN vm-test-run-plv8> server # [ 132.699479] postgres[1614]: graphql_is_dropped = ( vm-test-run-plv8> server # [ 132.700008] postgres[1614]: SELECT ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 132.706009] postgres[1614]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-plv8> server # [ 132.707833] postgres[1614]: WHERE ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 132.715377] postgres[1614]: ); vm-test-run-plv8> server # [ 132.715903] postgres[1614]: vm-test-run-plv8> server # [ 132.716259] postgres[1614]: IF graphql_is_dropped vm-test-run-plv8> server # [ 132.725258] postgres[1614]: THEN vm-test-run-plv8> server # [ 132.725683] postgres[1614]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 132.727254] postgres[1614]: "operationName" text default null, vm-test-run-plv8> server # [ 132.727605] postgres[1614]: query text default null, vm-test-run-plv8> server # [ 132.727869] postgres[1614]: variables jsonb default null, vm-test-run-plv8> server # [ 132.752218] postgres[1614]: extensions jsonb default null vm-test-run-plv8> server # [ 132.758356] postgres[1614]: ) vm-test-run-plv8> server # [ 132.760361] postgres[1614]: returns jsonb vm-test-run-plv8> server # [ 132.766221] postgres[1614]: language plpgsql vm-test-run-plv8> server # [ 132.767629] postgres[1614]: as $$ vm-test-run-plv8> server # [ 132.770880] postgres[1614]: DECLARE vm-test-run-plv8> server # [ 132.777631] postgres[1614]: server_version float; vm-test-run-plv8> server # [ 132.788803] postgres[1614]: BEGIN vm-test-run-plv8> server # [ 132.798917] postgres[1614]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 132.802741] postgres[1614]: vm-test-run-plv8> server # [ 132.803827] postgres[1614]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 132.805367] postgres[1614]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 132.809259] postgres[1614]: 'data', null::jsonb, vm-test-run-plv8> server # [ 132.810783] postgres[1614]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-plv8> server # [ 132.814362] postgres[1614]: ); vm-test-run-plv8> server # [ 132.815502] postgres[1614]: ELSE vm-test-run-plv8> server # [ 132.816694] postgres[1614]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 132.818454] postgres[1614]: 'data', null::jsonb, vm-test-run-plv8> server # [ 132.819293] postgres[1614]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-plv8> server # [ 132.819680] postgres[1614]: ); vm-test-run-plv8> server # [ 132.822051] postgres[1614]: END IF; vm-test-run-plv8> server # [ 132.822631] postgres[1614]: END; vm-test-run-plv8> server # [ 132.823782] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: CREATE FUNCTION vm-test-run-plv8> server # [ 132.825478] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-plv8> server # [ 132.829880] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 132.831428] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 132.831971] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: COMMENT vm-test-run-plv8> server # [ 132.835848] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: CREATE FUNCTION vm-test-run-plv8> server # [ 132.837106] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-plv8> server # [ 132.839682] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 132.841864] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 132.845605] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1607]: COMMENT vm-test-run-plv8> server # [ 132.850396] postgres[1614]: $$; vm-test-run-plv8> server # [ 132.851522] postgres[1614]: END IF; vm-test-run-plv8> server # [ 132.857580] postgres[1614]: vm-test-run-plv8> server # [ 132.859899] postgres[1614]: END; vm-test-run-plv8> server # [ 132.866572] postgres[1614]: $func$; vm-test-run-plv8> server # [ 132.866924] postgres[1614]: ::1 2026-10-22 12:24:25.204 UTC [1614] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-plv8> server # [ 132.870480] postgres[1614]: ::1 2026-10-22 12:24:25.214 UTC [1614] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION') vm-test-run-plv8> server # [ 132.871836] postgres[1614]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-plv8> server # [ 132.873518] postgres[1614]: ::1 2026-10-22 12:24:25.222 UTC [1614] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql'; vm-test-run-plv8> server # [ 132.885280] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-plv8> server # [ 133.151290] postgres[1628]: ::1 2026-10-22 12:24:25.790 UTC [1628] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-plv8> server # [ 133.154340] postgres[1628]: ::1 2026-10-22 12:24:25.798 UTC [1628] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-plv8> server # [ 133.163209] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-plv8> server # [ 133.165692] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 133.167350] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-plv8> server # [ 133.178095] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: DROP FUNCTION vm-test-run-plv8> server # [ 133.179529] postgres[1628]: ::1 2026-10-22 12:24:25.824 UTC [1628] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 133.180045] postgres[1628]: DECLARE vm-test-run-plv8> server # [ 133.181497] postgres[1628]: cmd record; vm-test-run-plv8> server # [ 133.181924] postgres[1628]: BEGIN vm-test-run-plv8> server # [ 133.182363] postgres[1628]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-plv8> server # [ 133.183811] postgres[1628]: LOOP vm-test-run-plv8> server # [ 133.189678] postgres[1628]: IF cmd.command_tag IN ( vm-test-run-plv8> server # [ 133.190183] postgres[1628]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-plv8> server # [ 133.190937] postgres[1628]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-plv8> server # [ 133.191605] postgres[1628]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-plv8> server # [ 133.191909] postgres[1628]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-plv8> server # [ 133.197888] postgres[1628]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-plv8> server # [ 133.198716] postgres[1628]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-plv8> server # [ 133.199044] postgres[1628]: , 'CREATE TRIGGER' vm-test-run-plv8> server # [ 133.199279] postgres[1628]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-plv8> server # [ 133.199534] postgres[1628]: , 'CREATE RULE' vm-test-run-plv8> server # [ 133.200009] postgres[1628]: , 'COMMENT' vm-test-run-plv8> server # [ 133.206345] postgres[1628]: ) vm-test-run-plv8> server # [ 133.206972] postgres[1628]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-plv8> server # [ 133.207301] postgres[1628]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-plv8> server # [ 133.207566] postgres[1628]: THEN vm-test-run-plv8> server # [ 133.207806] postgres[1628]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 133.208489] postgres[1628]: END IF; vm-test-run-plv8> server # [ 133.208732] postgres[1628]: END LOOP; vm-test-run-plv8> server # [ 133.217263] postgres[1628]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 133.223356] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: CREATE FUNCTION vm-test-run-plv8> server # [ 133.233168] postgres[1628]: ::1 2026-10-22 12:24:25.872 UTC [1628] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 133.234087] postgres[1628]: DECLARE vm-test-run-plv8> server # [ 133.234550] postgres[1628]: obj record; vm-test-run-plv8> server # [ 133.235522] postgres[1628]: BEGIN vm-test-run-plv8> server # [ 133.235992] postgres[1628]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-plv8> server # [ 133.251570] postgres[1628]: LOOP vm-test-run-plv8> server # [ 133.263362] postgres[1628]: IF obj.object_type IN ( vm-test-run-plv8> server # [ 133.264176] postgres[1628]: 'schema' vm-test-run-plv8> server # [ 133.270240] postgres[1628]: , 'table' vm-test-run-plv8> server # [ 133.271060] postgres[1628]: , 'foreign table' vm-test-run-plv8> server # [ 133.272601] postgres[1628]: , 'view' vm-test-run-plv8> server # [ 133.273156] postgres[1628]: , 'materialized view' vm-test-run-plv8> server # [ 133.273451] postgres[1628]: , 'function' vm-test-run-plv8> server # [ 133.273929] postgres[1628]: , 'trigger' vm-test-run-plv8> server # [ 133.274201] postgres[1628]: , 'type' vm-test-run-plv8> server # [ 133.274420] postgres[1628]: , 'rule' vm-test-run-plv8> server # [ 133.274629] postgres[1628]: ) vm-test-run-plv8> server # [ 133.274856] postgres[1628]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-plv8> server # [ 133.287385] postgres[1628]: THEN vm-test-run-plv8> server # [ 133.289924] postgres[1628]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 133.290386] postgres[1628]: END IF; vm-test-run-plv8> server # [ 133.290768] postgres[1628]: END LOOP; vm-test-run-plv8> server # [ 133.292750] postgres[1628]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 133.299383] postgres[1628]: ::1 2026-10-22 12:24:25.910 UTC [1628] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-plv8> server # [ 133.301001] postgres[1628]: ::1 2026-10-22 12:24:25.930 UTC [1628] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-plv8> server # [ 133.301426] postgres[1628]: ON ddl_command_end vm-test-run-plv8> server # [ 133.321325] postgres[1628]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-plv8> server # [ 133.322587] postgres[1628]: ::1 2026-10-22 12:24:25.941 UTC [1628] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-plv8> server # [ 133.331031] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: CREATE FUNCTION vm-test-run-plv8> server # [ 133.339433] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 133.348024] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 133.379553] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 133.389967] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1626]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 133.391304] postgres[1628]: ::1 2026-10-22 12:24:25.948 UTC [1628] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-plv8> server # [ 133.413991] postgres[1628]: ON sql_drop vm-test-run-plv8> server # [ 133.416082] postgres[1628]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-plv8> server # [ 133.430770] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-plv8> server # [ 133.646600] postgres[1630]: ::1 2026-10-22 12:24:26.288 UTC [1630] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-plv8> server # [ 133.663635] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1629]: ALTER ROLE vm-test-run-plv8> server # [ 133.701487] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-plv8> server # [ 134.055866] postgres[1634]: ::1 2026-10-22 12:24:26.684 UTC [1634] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 134.070416] postgres[1634]: returns event_trigger vm-test-run-plv8> server # [ 134.071300] postgres[1634]: language plpgsql vm-test-run-plv8> server # [ 134.071838] postgres[1634]: AS $func$ vm-test-run-plv8> server # [ 134.080096] postgres[1634]: DECLARE vm-test-run-plv8> server # [ 134.081660] postgres[1634]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 134.082342] postgres[1634]: BEGIN vm-test-run-plv8> server # [ 134.082623] postgres[1634]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 134.082934] postgres[1634]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 134.083795] postgres[1634]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 134.085679] postgres[1634]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 134.086487] postgres[1634]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 134.086778] postgres[1634]: ); vm-test-run-plv8> server # [ 134.087222] postgres[1634]: vm-test-run-plv8> server # [ 134.087676] postgres[1634]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 134.091855] postgres[1634]: THEN vm-test-run-plv8> server # [ 134.094961] postgres[1634]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.105431] postgres[1634]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.106752] postgres[1634]: vm-test-run-plv8> server # [ 134.107134] postgres[1634]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.113083] postgres[1634]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.114064] postgres[1634]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.114776] postgres[1634]: vm-test-run-plv8> server # [ 134.115177] postgres[1634]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 134.115711] postgres[1634]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 134.116586] postgres[1634]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 134.116906] postgres[1634]: "operationName" text default null, vm-test-run-plv8> server # [ 134.122774] postgres[1634]: query text default null, vm-test-run-plv8> server # [ 134.123396] postgres[1634]: variables jsonb default null, vm-test-run-plv8> server # [ 134.123716] postgres[1634]: extensions jsonb default null vm-test-run-plv8> server # [ 134.124413] postgres[1634]: ) vm-test-run-plv8> server # [ 134.130562] postgres[1634]: returns jsonb vm-test-run-plv8> server # [ 134.135998] postgres[1634]: language sql vm-test-run-plv8> server # [ 134.145373] postgres[1634]: as $$ vm-test-run-plv8> server # [ 134.146163] postgres[1634]: -- This changed vm-test-run-plv8> server # [ 134.147976] postgres[1634]: select graphql.resolve( vm-test-run-plv8> server # [ 134.154555] postgres[1634]: query := query, vm-test-run-plv8> server # [ 134.161640] postgres[1634]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 134.163939] postgres[1634]: "operationName" := "operationName", vm-test-run-plv8> server # [ 134.171633] postgres[1634]: extensions := extensions vm-test-run-plv8> server # [ 134.177252] postgres[1634]: ); vm-test-run-plv8> server # [ 134.178678] postgres[1634]: $$; vm-test-run-plv8> server # [ 134.179083] postgres[1634]: vm-test-run-plv8> server # [ 134.179352] postgres[1634]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 134.180089] postgres[1634]: END IF; vm-test-run-plv8> server # [ 134.180465] postgres[1634]: vm-test-run-plv8> server # [ 134.185991] postgres[1634]: END; vm-test-run-plv8> server # [ 134.187207] postgres[1634]: $func$; vm-test-run-plv8> server # [ 134.187762] postgres[1634]: ::1 2026-10-22 12:24:26.819 UTC [1634] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-plv8> server # [ 134.189953] postgres[1634]: RETURNS event_trigger vm-test-run-plv8> server # [ 134.191084] postgres[1634]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 134.191563] postgres[1634]: AS $func$ vm-test-run-plv8> server # [ 134.191944] postgres[1634]: DECLARE vm-test-run-plv8> server # [ 134.197439] postgres[1634]: graphql_is_dropped bool; vm-test-run-plv8> server # [ 134.198311] postgres[1634]: BEGIN vm-test-run-plv8> server # [ 134.199169] postgres[1634]: graphql_is_dropped = ( vm-test-run-plv8> server # [ 134.205436] postgres[1634]: SELECT ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 134.215324] postgres[1634]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-plv8> server # [ 134.225519] postgres[1634]: WHERE ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 134.226780] postgres[1634]: ); vm-test-run-plv8> server # [ 134.227098] postgres[1634]: vm-test-run-plv8> server # [ 134.227343] postgres[1634]: IF graphql_is_dropped vm-test-run-plv8> server # [ 134.227704] postgres[1634]: THEN vm-test-run-plv8> server # [ 134.228067] postgres[1634]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 134.235405] postgres[1634]: "operationName" text default null, vm-test-run-plv8> server # [ 134.236648] postgres[1634]: query text default null, vm-test-run-plv8> server # [ 134.237490] postgres[1634]: variables jsonb default null, vm-test-run-plv8> server # [ 134.238501] postgres[1634]: extensions jsonb default null vm-test-run-plv8> server # [ 134.239093] postgres[1634]: ) vm-test-run-plv8> server # [ 134.239346] postgres[1634]: returns jsonb vm-test-run-plv8> server # [ 134.239608] postgres[1634]: language plpgsql vm-test-run-plv8> server # [ 134.240111] postgres[1634]: as $$ vm-test-run-plv8> server # [ 134.243241] postgres[1634]: DECLARE vm-test-run-plv8> server # [ 134.250050] postgres[1634]: server_version float; vm-test-run-plv8> server # [ 134.250874] postgres[1634]: BEGIN vm-test-run-plv8> server # [ 134.251186] postgres[1634]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 134.251490] postgres[1634]: vm-test-run-plv8> server # [ 134.252090] postgres[1634]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 134.256236] postgres[1634]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 134.267065] postgres[1634]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 134.267765] postgres[1634]: jsonb_build_object( vm-test-run-plv8> server # [ 134.273044] postgres[1634]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-plv8> server # [ 134.278745] postgres[1634]: ) vm-test-run-plv8> server # [ 134.279743] postgres[1634]: ) vm-test-run-plv8> server # [ 134.286715] postgres[1634]: ); vm-test-run-plv8> server # [ 134.288079] postgres[1634]: ELSE vm-test-run-plv8> server # [ 134.291812] postgres[1634]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 134.292798] postgres[1634]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 134.293602] postgres[1634]: jsonb_build_object( vm-test-run-plv8> server # [ 134.301718] postgres[1634]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-plv8> server # [ 134.309957] postgres[1634]: ) vm-test-run-plv8> server # [ 134.322087] postgres[1634]: ) vm-test-run-plv8> server # [ 134.323279] postgres[1634]: ); vm-test-run-plv8> server # [ 134.324068] postgres[1634]: END IF; vm-test-run-plv8> server # [ 134.331005] postgres[1634]: END; vm-test-run-plv8> server # [ 134.334096] postgres[1634]: $$; vm-test-run-plv8> server # [ 134.335676] postgres[1634]: END IF; vm-test-run-plv8> server # [ 134.337986] postgres[1634]: vm-test-run-plv8> server # [ 134.338933] postgres[1634]: END; vm-test-run-plv8> server # [ 134.339677] postgres[1634]: $func$; vm-test-run-plv8> server # [ 134.350590] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1631]: CREATE FUNCTION vm-test-run-plv8> server # [ 134.354759] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1631]: CREATE FUNCTION vm-test-run-plv8> server # [ 134.356791] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1631]: CREATE FUNCTION vm-test-run-plv8> server # [ 134.359031] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1631]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-plv8> server # [ 134.360510] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1631]: DROP EXTENSION vm-test-run-plv8> server # [ 134.363647] postgres[1634]: ::1 2026-10-22 12:24:26.878 UTC [1634] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 134.367783] postgres[1634]: "operationName" text default null, vm-test-run-plv8> server # [ 134.369689] postgres[1634]: query text default null, vm-test-run-plv8> server # [ 134.370429] postgres[1634]: variables jsonb default null, vm-test-run-plv8> server # [ 134.370869] postgres[1634]: extensions jsonb default null vm-test-run-plv8> server # [ 134.371133] postgres[1634]: ) vm-test-run-plv8> server # [ 134.371679] postgres[1634]: returns jsonb vm-test-run-plv8> server # [ 134.371934] postgres[1634]: language plpgsql vm-test-run-plv8> server # [ 134.372155] postgres[1634]: as $$ vm-test-run-plv8> server # [ 134.373650] postgres[1634]: DECLARE vm-test-run-plv8> server # [ 134.374932] postgres[1634]: server_version float; vm-test-run-plv8> server # [ 134.377246] postgres[1634]: BEGIN vm-test-run-plv8> server # [ 134.378970] postgres[1634]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 134.381081] postgres[1634]: vm-test-run-plv8> server # [ 134.381479] postgres[1634]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 134.381793] postgres[1634]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 134.382040] postgres[1634]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 134.382288] postgres[1634]: jsonb_build_object( vm-test-run-plv8> server # [ 134.384926] postgres[1634]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-plv8> server # [ 134.386497] postgres[1634]: ) vm-test-run-plv8> server # [ 134.387471] postgres[1634]: ) vm-test-run-plv8> server # [ 134.388521] postgres[1634]: ); vm-test-run-plv8> server # [ 134.390867] postgres[1634]: ELSE vm-test-run-plv8> server # [ 134.394851] postgres[1634]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 134.397849] postgres[1634]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 134.399480] postgres[1634]: jsonb_build_object( vm-test-run-plv8> server # [ 134.402460] postgres[1634]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-plv8> server # [ 134.403721] postgres[1634]: ) vm-test-run-plv8> server # [ 134.405396] postgres[1634]: ) vm-test-run-plv8> server # [ 134.405794] postgres[1634]: ); vm-test-run-plv8> server # [ 134.407053] postgres[1634]: END IF; vm-test-run-plv8> server # [ 134.409644] postgres[1634]: END; vm-test-run-plv8> server # [ 134.411684] postgres[1634]: $$; vm-test-run-plv8> server # [ 134.413454] postgres[1634]: ::1 2026-10-22 12:24:26.912 UTC [1634] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 136.578236] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1631]: DO vm-test-run-plv8> server # [ 136.627096] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-plv8> server # [ 136.947315] postgres[1662]: ::1 2026-10-22 12:24:29.585 UTC [1662] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-plv8> server # [ 136.967574] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1661]: GRANT ROLE vm-test-run-plv8> server # [ 136.997497] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-plv8> server # [ 137.261681] postgres[1664]: ::1 2026-10-22 12:24:29.891 UTC [1664] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 137.263931] postgres[1664]: returns event_trigger vm-test-run-plv8> server # [ 137.265228] postgres[1664]: language plpgsql vm-test-run-plv8> server # [ 137.265953] postgres[1664]: AS $func$ vm-test-run-plv8> server # [ 137.266319] postgres[1664]: DECLARE vm-test-run-plv8> server # [ 137.267093] postgres[1664]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 137.267914] postgres[1664]: BEGIN vm-test-run-plv8> server # [ 137.269577] postgres[1664]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 137.270496] postgres[1664]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 137.271185] postgres[1664]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 137.271490] postgres[1664]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 137.271870] postgres[1664]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 137.272250] postgres[1664]: ); vm-test-run-plv8> server # [ 137.272821] postgres[1664]: vm-test-run-plv8> server # [ 137.273050] postgres[1664]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 137.273280] postgres[1664]: THEN vm-test-run-plv8> server # [ 137.274634] postgres[1664]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 137.275236] postgres[1664]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 137.275929] postgres[1664]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 137.277967] postgres[1664]: "operationName" text default null, vm-test-run-plv8> server # [ 137.278514] postgres[1664]: query text default null, vm-test-run-plv8> server # [ 137.278784] postgres[1664]: variables jsonb default null, vm-test-run-plv8> server # [ 137.279030] postgres[1664]: extensions jsonb default null vm-test-run-plv8> server # [ 137.279272] postgres[1664]: ) vm-test-run-plv8> server # [ 137.281368] postgres[1664]: returns jsonb vm-test-run-plv8> server # [ 137.281922] postgres[1664]: language sql vm-test-run-plv8> server # [ 137.282216] postgres[1664]: as $$ vm-test-run-plv8> server # [ 137.282450] postgres[1664]: select graphql.resolve( vm-test-run-plv8> server # [ 137.286336] postgres[1664]: query := query, vm-test-run-plv8> server # [ 137.287459] postgres[1664]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 137.293727] postgres[1664]: "operationName" := "operationName", vm-test-run-plv8> server # [ 137.295081] postgres[1664]: extensions := extensions vm-test-run-plv8> server # [ 137.295895] postgres[1664]: ); vm-test-run-plv8> server # [ 137.300263] postgres[1664]: $$; vm-test-run-plv8> server # [ 137.302435] postgres[1664]: vm-test-run-plv8> server # [ 137.303066] postgres[1664]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-plv8> server # [ 137.304156] postgres[1664]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-plv8> server # [ 137.307994] postgres[1664]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-plv8> server # [ 137.310968] postgres[1664]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.311590] postgres[1664]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.314716] postgres[1664]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.315561] postgres[1664]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.316254] postgres[1664]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.318373] postgres[1664]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.319926] postgres[1664]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 137.321660] postgres[1664]: END IF; vm-test-run-plv8> server # [ 137.321982] postgres[1664]: vm-test-run-plv8> server # [ 137.322731] postgres[1664]: END; vm-test-run-plv8> server # [ 137.323195] postgres[1664]: $func$; vm-test-run-plv8> server # [ 137.347696] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1663]: CREATE FUNCTION vm-test-run-plv8> server # [ 137.349707] postgres[1664]: ::1 2026-10-22 12:24:29.990 UTC [1664] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 137.396939] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1663]: DROP EXTENSION vm-test-run-plv8> server # [ 139.565575] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1663]: DO vm-test-run-plv8> server # [ 139.615380] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-plv8> server # [ 140.685626] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1689]: DO vm-test-run-plv8> server # [ 141.385022] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1689]: DO vm-test-run-plv8> server # [ 141.451572] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-plv8> server # [ 141.883357] postgres[1708]: ::1 2026-10-22 12:24:34.517 UTC [1708] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-plv8> server # [ 141.935296] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1703]: ALTER ROLE vm-test-run-plv8> server # [ 141.989154] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-plv8> server # [ 142.511936] postgres[1719]: ::1 2026-10-22 12:24:35.142 UTC [1719] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-plv8> server # [ 142.550096] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1715]: REVOKE ROLE vm-test-run-plv8> server # [ 142.611624] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-plv8> server # [ 145.283530] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1722]: DO vm-test-run-plv8> server # [ 145.322645] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-plv8> server # [ 145.658191] postgres[1750]: ::1 2026-10-22 12:24:38.296 UTC [1750] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-plv8> server # [ 145.666990] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1747]: GRANT ROLE vm-test-run-plv8> server # [ 145.719111] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-plv8> server # [ 146.027103] postgres[1752]: ::1 2026-10-22 12:24:38.660 UTC [1752] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 146.135716] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1751]: GRANT vm-test-run-plv8> server # [ 146.143542] postgres[1752]: ::1 2026-10-22 12:24:38.779 UTC [1752] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 146.183111] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1751]: GRANT vm-test-run-plv8> server # [ 146.190834] postgres[1752]: ::1 2026-10-22 12:24:38.838 UTC [1752] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 146.194860] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1751]: GRANT vm-test-run-plv8> server # [ 146.201461] postgres[1752]: ::1 2026-10-22 12:24:38.846 UTC [1752] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 146.221836] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1751]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 146.223767] postgres[1752]: ::1 2026-10-22 12:24:38.870 UTC [1752] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option; vm-test-run-plv8> server # [ 146.243911] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1751]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 146.246230] postgres[1752]: ::1 2026-10-22 12:24:38.894 UTC [1752] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 146.261358] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1751]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 146.336537] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-plv8> server # [ 146.697696] postgres[1758]: ::1 2026-10-22 12:24:39.335 UTC [1758] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-plv8> server # [ 146.711029] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1757]: GRANT ROLE vm-test-run-plv8> server # [ 146.761358] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-plv8> server # [ 147.037414] postgres[1760]: ::1 2026-10-22 12:24:39.675 UTC [1760] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-plv8> server # [ 147.051051] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1759]: GRANT ROLE vm-test-run-plv8> server # [ 147.098881] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-plv8> server # [ 147.365143] postgres[1762]: ::1 2026-10-22 12:24:40.008 UTC [1762] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-plv8> server # [ 147.380955] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1761]: ALTER ROLE vm-test-run-plv8> server # [ 147.382315] postgres[1762]: ::1 2026-10-22 12:24:40.030 UTC [1762] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-plv8> server # [ 147.390068] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1761]: ALTER ROLE vm-test-run-plv8> server # [ 147.393466] postgres[1762]: ::1 2026-10-22 12:24:40.040 UTC [1762] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-plv8> server # [ 147.402838] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1761]: ALTER ROLE vm-test-run-plv8> server # [ 147.430788] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1761]: DO vm-test-run-plv8> server # [ 147.485440] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-plv8> server # [ 147.908053] postgres[1771]: ::1 2026-10-22 12:24:40.530 UTC [1771] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-plv8> server # [ 147.953199] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1763]: GRANT ROLE vm-test-run-plv8> server # [ 147.956993] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1763]: REVOKE ROLE vm-test-run-plv8> server # [ 147.957969] postgres[1771]: ::1 2026-10-22 12:24:40.582 UTC [1771] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-plv8> server # [ 148.025299] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-plv8> server # [ 148.566607] postgres[1783]: ::1 2026-10-22 12:24:41.206 UTC [1783] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 148.578377] postgres[1783]: returns event_trigger vm-test-run-plv8> server # [ 148.585512] postgres[1783]: language plpgsql vm-test-run-plv8> server # [ 148.586228] postgres[1783]: AS $func$ vm-test-run-plv8> server # [ 148.586595] postgres[1783]: DECLARE vm-test-run-plv8> server # [ 148.586846] postgres[1783]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 148.587092] postgres[1783]: BEGIN vm-test-run-plv8> server # [ 148.587469] postgres[1783]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 148.597536] postgres[1783]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 148.604912] postgres[1783]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 148.607540] postgres[1783]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 148.607978] postgres[1783]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 148.618495] postgres[1783]: ); vm-test-run-plv8> server # [ 148.619092] postgres[1783]: vm-test-run-plv8> server # [ 148.620106] postgres[1783]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 148.625543] postgres[1783]: THEN vm-test-run-plv8> server # [ 148.626252] postgres[1783]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 148.626797] postgres[1783]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 148.627379] postgres[1783]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 148.635085] postgres[1783]: "operationName" text default null, vm-test-run-plv8> server # [ 148.635610] postgres[1783]: query text default null, vm-test-run-plv8> server # [ 148.635888] postgres[1783]: variables jsonb default null, vm-test-run-plv8> server # [ 148.636159] postgres[1783]: extensions jsonb default null vm-test-run-plv8> server # [ 148.637608] postgres[1783]: ) vm-test-run-plv8> server # [ 148.637925] postgres[1783]: returns jsonb vm-test-run-plv8> server # [ 148.638186] postgres[1783]: language sql vm-test-run-plv8> server # [ 148.638408] postgres[1783]: as $$ vm-test-run-plv8> server # [ 148.638623] postgres[1783]: select graphql.resolve( vm-test-run-plv8> server # [ 148.638845] postgres[1783]: query := query, vm-test-run-plv8> server # [ 148.639073] postgres[1783]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 148.639305] postgres[1783]: "operationName" := "operationName", vm-test-run-plv8> server # [ 148.639528] postgres[1783]: extensions := extensions vm-test-run-plv8> server # [ 148.639762] postgres[1783]: ); vm-test-run-plv8> server # [ 148.639971] postgres[1783]: $$; vm-test-run-plv8> server # [ 148.649230] postgres[1783]: vm-test-run-plv8> server # [ 148.651572] postgres[1783]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-plv8> server # [ 148.665794] postgres[1783]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-plv8> server # [ 148.666815] postgres[1783]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-plv8> server # [ 148.667589] postgres[1783]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.668063] postgres[1783]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.673940] postgres[1783]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.674554] postgres[1783]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.675643] postgres[1783]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.681601] postgres[1783]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.683130] postgres[1783]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 148.693421] postgres[1783]: vm-test-run-plv8> server # [ 148.694026] postgres[1783]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-plv8> server # [ 148.705087] postgres[1783]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-plv8> server # [ 148.731645] postgres[1783]: grant usage on schema graphql to postgres with grant option; vm-test-run-plv8> server # [ 148.738402] postgres[1783]: END IF; vm-test-run-plv8> server # [ 148.739010] postgres[1783]: vm-test-run-plv8> server # [ 148.739671] postgres[1783]: END; vm-test-run-plv8> server # [ 148.744849] postgres[1783]: $func$; vm-test-run-plv8> server # [ 148.799027] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1779]: CREATE FUNCTION vm-test-run-plv8> server # [ 148.817513] postgres[1783]: ::1 2026-10-22 12:24:41.422 UTC [1783] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 148.941086] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1779]: DROP EXTENSION vm-test-run-plv8> server # [ 150.731733] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1779]: DO vm-test-run-plv8> server # [ 150.751629] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-plv8> server # [ 151.178787] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1797]: DO vm-test-run-plv8> server # [ 151.215630] postgres[1803]: ::1 2026-10-22 12:24:43.815 UTC [1803] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-plv8> server # [ 151.222758] postgres[1803]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 151.223961] postgres[1803]: AS $$ vm-test-run-plv8> server # [ 151.239195] postgres[1803]: BEGIN vm-test-run-plv8> server # [ 151.242452] postgres[1803]: IF EXISTS ( vm-test-run-plv8> server # [ 151.243265] postgres[1803]: SELECT vm-test-run-plv8> server # [ 151.244093] postgres[1803]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 151.255330] postgres[1803]: JOIN pg_extension AS ext vm-test-run-plv8> server # [ 151.257064] postgres[1803]: ON ev.objid = ext.oid vm-test-run-plv8> server # [ 151.261894] postgres[1803]: WHERE ext.extname = 'pg_cron' vm-test-run-plv8> server # [ 151.263644] postgres[1803]: ) vm-test-run-plv8> server # [ 151.275085] postgres[1803]: THEN vm-test-run-plv8> server # [ 151.276034] postgres[1803]: grant usage on schema cron to postgres with grant option; vm-test-run-plv8> server # [ 151.281913] postgres[1803]: vm-test-run-plv8> server # [ 151.282475] postgres[1803]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 151.283936] postgres[1803]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-plv8> server # [ 151.289820] postgres[1803]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 151.290825] postgres[1803]: vm-test-run-plv8> server # [ 151.291125] postgres[1803]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 151.291880] postgres[1803]: on sequences to postgres with grant option; vm-test-run-plv8> server # [ 151.293240] postgres[1803]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 151.302765] postgres[1803]: on tables to postgres with grant option; vm-test-run-plv8> server # [ 151.303998] postgres[1803]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 151.318148] postgres[1803]: on functions to postgres with grant option; vm-test-run-plv8> server # [ 151.318680] postgres[1803]: vm-test-run-plv8> server # [ 151.318959] postgres[1803]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-plv8> server # [ 151.319835] postgres[1803]: revoke all on table cron.job from postgres; vm-test-run-plv8> server # [ 151.325408] postgres[1803]: grant select on table cron.job to postgres with grant option; vm-test-run-plv8> server # [ 151.327016] postgres[1803]: END IF; vm-test-run-plv8> server # [ 151.327701] postgres[1803]: END; vm-test-run-plv8> server # [ 151.333408] postgres[1803]: $$; vm-test-run-plv8> server # [ 151.334069] postgres[1803]: ::1 2026-10-22 12:24:43.965 UTC [1803] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-plv8> server # [ 151.335450] postgres[1803]: ::1 2026-10-22 12:24:43.981 UTC [1803] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-plv8> server # [ 151.341426] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1797]: CREATE FUNCTION vm-test-run-plv8> server # [ 151.343759] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1797]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 151.353006] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1797]: ALTER FUNCTION vm-test-run-plv8> server # [ 151.360878] postgres[1803]: ::1 2026-10-22 12:24:44.007 UTC [1803] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-plv8> server # [ 151.362265] postgres[1803]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-plv8> server # [ 151.362630] postgres[1803]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-plv8> server # [ 151.372130] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1797]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 151.453715] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-plv8> server # [ 152.008862] postgres[1820]: ::1 2026-10-22 12:24:44.642 UTC [1820] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-plv8> server # [ 152.055247] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1811]: ALTER ROLE vm-test-run-plv8> server # [ 152.123580] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-plv8> server # [ 152.515298] postgres[1824]: ::1 2026-10-22 12:24:45.155 UTC [1824] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-plv8> server # [ 152.564017] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1823]: ALTER FUNCTION vm-test-run-plv8> server # [ 152.587581] postgres[1824]: ::1 2026-10-22 12:24:45.192 UTC [1824] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-plv8> server # [ 152.595965] postgres[1824]: ::1 2026-10-22 12:24:45.218 UTC [1824] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-plv8> server # [ 152.613205] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1823]: ALTER FUNCTION vm-test-run-plv8> server # [ 152.620700] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1823]: ALTER FUNCTION vm-test-run-plv8> server # [ 152.633362] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-plv8> server # [ 152.939054] postgres[1826]: ::1 2026-10-22 12:24:45.580 UTC [1826] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-plv8> server # [ 152.959534] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1825]: GRANT ROLE vm-test-run-plv8> server # [ 152.993036] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-plv8> server # [ 153.992156] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1827]: DO vm-test-run-plv8> server # [ 154.064971] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-plv8> server # [ 154.675803] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1837]: DO vm-test-run-plv8> server # [ 154.689659] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1837]: DO vm-test-run-plv8> server # [ 154.744122] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-plv8> server # [ 155.186704] postgres[1852]: ::1 2026-10-22 12:24:47.806 UTC [1852] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-plv8> server # [ 155.227170] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1847]: ALTER ROLE vm-test-run-plv8> server # [ 155.250567] postgres[1852]: ::1 2026-10-22 12:24:47.843 UTC [1852] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-plv8> server # [ 155.263396] postgres[1852]: ::1 2026-10-22 12:24:47.870 UTC [1852] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-plv8> server # [ 155.282075] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1847]: ALTER ROLE vm-test-run-plv8> server # [ 155.293519] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1847]: ALTER ROLE vm-test-run-plv8> server # [ 155.318920] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-plv8> server # [ 155.765130] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1857]: DO vm-test-run-plv8> server # [ 155.786755] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-plv8> server # [ 156.242969] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1862]: DO vm-test-run-plv8> server # [ 156.246997] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-plv8> server # [ 156.578441] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1865]: CREATE FUNCTION vm-test-run-plv8> server # [ 156.589290] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1865]: DO vm-test-run-plv8> server # [ 156.656003] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-plv8> server # [ 156.943399] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1867]: ALTER FUNCTION vm-test-run-plv8> server # [ 156.954710] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1867]: GRANT vm-test-run-plv8> server # [ 157.003794] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-plv8> server # [ 157.427598] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1869]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 157.465256] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1869]: ALTER FUNCTION vm-test-run-plv8> server # [ 157.488779] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1869]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 157.557691] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-plv8> server # [ 158.092292] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1883]: DO vm-test-run-plv8> server # [ 158.151875] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-plv8> server # [ 158.767342] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1892]: CREATE FUNCTION vm-test-run-plv8> server # [ 158.783443] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1892]: GRANT vm-test-run-plv8> server # [ 158.809738] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-plv8> server # [ 159.114663] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1899]: REVOKE ROLE vm-test-run-plv8> server # [ 159.138137] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1899]: DO vm-test-run-plv8> server # [ 159.155458] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1899]: DO vm-test-run-plv8> server # [ 159.169412] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1899]: REVOKE ROLE vm-test-run-plv8> server # [ 159.181709] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1899]: REVOKE vm-test-run-plv8> server # [ 159.209795] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1899]: DO vm-test-run-plv8> server # [ 159.255916] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-plv8> server # [ 159.563028] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1901]: DO vm-test-run-plv8> server # [ 159.606306] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-plv8> server # [ 159.995117] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1906]: DO vm-test-run-plv8> server # [ 160.054741] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-plv8> server # [ 160.619550] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1909]: DO vm-test-run-plv8> server # [ 160.674730] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-plv8> server # [ 161.137781] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1918]: ALTER ROLE vm-test-run-plv8> server # [ 161.178208] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-plv8> server # [ 161.638464] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1928]: GRANT ROLE vm-test-run-plv8> server # [ 161.662325] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1928]: DO vm-test-run-plv8> server # [ 161.729212] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-plv8> server # [ 162.236968] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1939]: GRANT vm-test-run-plv8> server # [ 162.238213] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-plv8> server # [ 162.578125] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1941]: CREATE FUNCTION vm-test-run-plv8> server # [ 162.583423] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1941]: REVOKE vm-test-run-plv8> server # [ 162.597512] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1941]: REVOKE vm-test-run-plv8> server # [ 162.609938] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1941]: GRANT vm-test-run-plv8> server # [ 162.647205] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-plv8> server # [ 162.937049] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1943]: DO vm-test-run-plv8> server # [ 162.976907] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-plv8> server # [ 163.258969] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1945]: ALTER ROLE vm-test-run-plv8> server # [ 163.310315] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-plv8> server # [ 163.981413] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1947]: DROP EXTENSION vm-test-run-plv8> server # [ 164.014449] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-plv8> server # [ 164.528233] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1960]: CREATE FUNCTION vm-test-run-plv8> server # [ 164.546328] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1960]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 164.565571] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1960]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 164.605164] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-plv8> server # [ 165.099254] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1970]: DO vm-test-run-plv8> server # [ 165.126818] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1970]: CREATE FUNCTION vm-test-run-plv8> server # [ 165.180845] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql vm-test-run-plv8> server # [ 165.630764] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1977]: CREATE FUNCTION vm-test-run-plv8> server # [ 165.664008] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1977]: CREATE FUNCTION vm-test-run-plv8> server # [ 165.699418] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20261016000001_search_path_for_supa_functions.sql vm-test-run-plv8> server # [ 166.109077] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1983]: CREATE FUNCTION vm-test-run-plv8> server # [ 166.126555] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1983]: CREATE FUNCTION vm-test-run-plv8> server # [ 166.157186] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1983]: CREATE FUNCTION vm-test-run-plv8> server # [ 166.187926] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1983]: CREATE FUNCTION vm-test-run-plv8> server # [ 166.207908] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1983]: CREATE FUNCTION vm-test-run-plv8> server # [ 166.219981] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1983]: CREATE FUNCTION vm-test-run-plv8> server # [ 166.275498] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Running postgresql schema... vm-test-run-plv8> server # [ 166.534781] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER DATABASE vm-test-run-plv8> server # [ 166.538072] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER DATABASE vm-test-run-plv8> server # [ 166.599746] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 166.652774] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 166.746297] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 166.859604] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.019140] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.134105] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.257928] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.481337] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.601452] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.602990] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1985]: ALTER ROLE vm-test-run-plv8> server # [ 167.671423] pdqmmassqysz2zf3lbajaw7wsyyz0mk1-supabase-db-init[1424]: Database initialization complete vm-test-run-plv8> server # [ 167.726131] systemd[1]: Finished Supabase Database Initialization. vm-test-run-plv8> server # [ 167.743585] systemd[1]: Reached target Multi-User System. vm-test-run-plv8> server # [ 167.750704] systemd[1]: Startup finished in 16.726s (kernel) + 2min 31.005s (userspace) = 2min 47.732s. vm-test-run-plv8> server: (finished: waiting for unit supabase-db-init.service, in 169.16 seconds) vm-test-run-plv8> subtest: Check upgrade path with postgresql 15 vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server # NOTICE: extension "plv8" does not exist, skipping vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.54 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.4' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.4' CASCADE;" , in 7.06 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.89 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.78 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.5' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.5' CASCADE;" , in 7.23 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.77 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.92 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" , in 5.87 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.50 seconds) vm-test-run-plv8> (finished: subtest: Check upgrade path with postgresql 15, in 24.56 seconds) vm-test-run-plv8> subtest: Check the install of the last version of the extension vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS plv8;" , in 0.51 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION plv8 WITH VERSION '3.1.10' CASCADE;" , in 1.42 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'plv8';" , in 0.37 seconds) vm-test-run-plv8> (finished: subtest: Check the install of the last version of the extension, in 2.30 seconds) vm-test-run-plv8> (finished: run the VM test script, in 196.20 seconds) vm-test-run-plv8> test script finished in 196.61s vm-test-run-plv8> cleanup vm-test-run-plv8> kill machine (pid 9) vm-test-run-plv8> qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/y88crn50yx35fiiwcjx6xqwi6ahk7mlq-python3-3.13.11/bin/python3.13) vm-test-run-plv8> vde_switch: EOF data port: Interrupted system call vm-test-run-plv8> kill vlan (pid 7) vm-test-run-plv8> (finished: cleanup, in 0.01 seconds) vm-test-run-plv8> vde_switch: Caught signal 15, cleaning up and exiting