these 6 derivations will be built: /nix/store/iwpwsm3bfp0233blbxizd8473zi476kr-run-nixos-vm.drv /nix/store/1g7fsfjii1ia54rabnwszi0wxwfjszry-nixos-vm.drv /nix/store/38p56xm3y31ck7bmxrxy8ka9d0ngj85s-test-script.drv /nix/store/hk0bl6vh1lviqhv21mx1k16jbw70q7pw-driverConfiguration.json.drv /nix/store/p9kii5d7xkf6fkpr5isblawn84s9h6qm-nixos-test-driver-plv8.drv /nix/store/vyc7hlnispmwf1x3gd4qr125fra1pcrw-vm-test-run-plv8.drv building '/nix/store/38p56xm3y31ck7bmxrxy8ka9d0ngj85s-test-script.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/38p56xm3y31ck7bmxrxy8ka9d0ngj85s-test-script.drv' building '/nix/store/hk0bl6vh1lviqhv21mx1k16jbw70q7pw-driverConfiguration.json.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/hk0bl6vh1lviqhv21mx1k16jbw70q7pw-driverConfiguration.json.drv' driverConfiguration.json> structuredAttrs is enabled building '/nix/store/p9kii5d7xkf6fkpr5isblawn84s9h6qm-nixos-test-driver-plv8.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/p9kii5d7xkf6fkpr5isblawn84s9h6qm-nixos-test-driver-plv8.drv' nixos-test-driver-plv8> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-plv8> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-plv8> All checks passed! nixos-test-driver-plv8> Linting test script (enable/disable: config.skipLint) nixos-test-driver-plv8> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint nixos-test-driver-plv8> All checks passed! building '/nix/store/vyc7hlnispmwf1x3gd4qr125fra1pcrw-vm-test-run-plv8.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/vyc7hlnispmwf1x3gd4qr125fra1pcrw-vm-test-run-plv8.drv' vm-test-run-plv8> Machine state will be reset. To keep it, pass --keep-machine-state vm-test-run-plv8> start all VLans vm-test-run-plv8> (finished: start all VLans, in 0.01 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, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh vm-test-run-plv8> start all VMs vm-test-run-plv8> Exception in thread Thread-3 (_log_stream): vm-test-run-plv8> server: starting vm vm-test-run-plv8> Traceback (most recent call last): vm-test-run-plv8> File "/nix/store/8immk1bh2f5bf0zwa8hlbfqj9gbi09q4-python3-3.13.15/lib/python3.13/threading.py", line 1044, in _bootstrap_inner vm-test-run-plv8> self.run() vm-test-run-plv8> ~~~~~~~~^^ vm-test-run-plv8> File "/nix/store/8immk1bh2f5bf0zwa8hlbfqj9gbi09q4-python3-3.13.15/lib/python3.13/threading.py", line 995, in run vm-test-run-plv8> self._target(*self._args, **self._kwargs) vm-test-run-plv8> ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ vm-test-run-plv8> File "/nix/store/03bhcqpzjarrkb19clc0l615094a6kk5-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/vlan.py", line 63, in _log_stream vm-test-run-plv8> for line in stream: vm-test-run-plv8> ^^^^^^ vm-test-run-plv8> File "", line 325, in decode vm-test-run-plv8> UnicodeDecodeError: 'utf-8' codec can't decode byte 0xf4 in position 42: invalid continuation byte vm-test-run-plv8> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-plv8> server # Formatting '/nix/var/nix/builds/nix-92964-1419680764/vm-state-server/tmp.9wszJ5emyj', fmt=raw size=1073741824 vm-test-run-plv8> server # mke2fs 1.47.4 (6-Mar-2025) vm-test-run-plv8> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-plv8> server # Filesystem UUID: 8dde9023-05a4-439c-8eae-b2c0d5ff9d2f 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 # 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> server: QEMU running (pid 93004) vm-test-run-plv8> (finished: start all VMs, in 2.33 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 [0x610f0000] vm-test-run-plv8> server # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 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 0x13f604280-0x13f6079ff] 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 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 76 pages/cpu s186520 r8192 d116584 u311296 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: Spectre-v4 vm-test-run-plv8> server # [ 0.000000] CPU features: detected: Spectre-BHB 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 root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/qi36bjlm9rlhx5jnmfx1jvgl31956sf7-nixos-system-server-test/init regInfo=/nix/store/927finx0ydymp8y1l5z4gdnp4lx7wqcy-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-plv8> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/927finx0ydymp8y1l5z4gdnp4lx7wqcy-closure-info/registration", will be passed to user space. vm-test-run-plv8> server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes 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] 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] 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] 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 74832 entries in 294 pages vm-test-run-plv8> server # [ 0.000000] ftrace: allocated 294 pages with 4 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] Trampoline variant of Tasks RCU enabled. 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: 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 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] Root IRQ handler: gic_handle_irq vm-test-run-plv8> server # [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] vm-test-run-plv8> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-plv8> server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-plv8> server # [ 0.000000] arch_timer: cp15 timer running at 24.00MHz (virt). vm-test-run-plv8> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns vm-test-run-plv8> server # [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns vm-test-run-plv8> server # [ 0.000189] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-plv8> server # [ 0.000239] Console: colour dummy device 80x25 vm-test-run-plv8> server # [ 0.000241] printk: legacy console [tty0] enabled vm-test-run-plv8> server # [ 0.000300] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) vm-test-run-plv8> server # [ 0.000303] pid_max: default: 32768 minimum: 301 vm-test-run-plv8> server # [ 0.000331] LSM: initializing lsm=capability,landlock,yama,bpf,ima vm-test-run-plv8> server # [ 0.000391] landlock: Up and running. vm-test-run-plv8> server # [ 0.000392] Yama: becoming mindful. vm-test-run-plv8> server # [ 0.000591] LSM support for eBPF active vm-test-run-plv8> server # [ 0.000638] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.000649] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-plv8> server # [ 0.001129] rcu: Hierarchical SRCU implementation. vm-test-run-plv8> server # [ 0.001131] rcu: Max phase no-delay instances is 1000. vm-test-run-plv8> server # [ 0.001164] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-plv8> server # [ 0.001520] EFI services will not be available. vm-test-run-plv8> server # [ 0.001556] smp: Bringing up secondary CPUs ... vm-test-run-plv8> server # [ 0.001807] Detected PIPT I-cache on CPU1 vm-test-run-plv8> server # [ 0.001912] CPU1: Booted secondary processor 0x0000000001 [0x610f0000] vm-test-run-plv8> server # [ 0.002069] smp: Brought up 1 node, 2 CPUs vm-test-run-plv8> server # [ 0.002075] SMP: Total of 2 processors activated. vm-test-run-plv8> server # [ 0.002077] CPU: All CPU(s) started at EL1 vm-test-run-plv8> server # [ 0.002080] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-plv8> server # [ 0.002082] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-plv8> server # [ 0.002084] CPU features: detected: Common not Private translations vm-test-run-plv8> server # [ 0.002085] CPU features: detected: CRC32 instructions vm-test-run-plv8> server # [ 0.002087] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-plv8> server # [ 0.002088] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-plv8> server # [ 0.002090] CPU features: detected: Data independent timing control (DIT) vm-test-run-plv8> server # [ 0.002091] CPU features: detected: E0PD vm-test-run-plv8> server # [ 0.002092] CPU features: detected: Enhanced Privileged Access Never vm-test-run-plv8> server # [ 0.002094] CPU features: detected: Fine Grained Traps vm-test-run-plv8> server # [ 0.002095] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-plv8> server # [ 0.002097] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-plv8> server # [ 0.002098] CPU features: detected: LSE atomic instructions vm-test-run-plv8> server # [ 0.002100] CPU features: detected: Privileged Access Never vm-test-run-plv8> server # [ 0.002101] CPU features: detected: RAS Extension Support vm-test-run-plv8> server # [ 0.002102] CPU features: detected: Speculation barrier (SB) vm-test-run-plv8> server # [ 0.002103] CPU features: detected: TLB range maintenance instructions vm-test-run-plv8> server # [ 0.002148] alternatives: applying system-wide alternatives vm-test-run-plv8> server # [ 0.003307] Memory: 3906368K/4194304K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 239844K reserved, 32768K cma-reserved) vm-test-run-plv8> server # [ 0.003780] devtmpfs: initialized vm-test-run-plv8> server # [ 0.005316] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear) vm-test-run-plv8> server # [ 0.005332] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear). vm-test-run-plv8> server # [ 0.005425] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-plv8> server # [ 0.005427] 0 pages in range for non-PLT usage vm-test-run-plv8> server # [ 0.005428] 508272 pages in range for PLT usage vm-test-run-plv8> server # [ 0.005524] pinctrl core: initialized pinctrl subsystem vm-test-run-plv8> server # [ 0.005901] DMI not present or invalid. vm-test-run-plv8> server # [ 0.007126] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-plv8> server # [ 0.007686] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-plv8> server # [ 0.007723] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-plv8> server # [ 0.007759] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-plv8> server # [ 0.007789] audit: initializing netlink subsys (disabled) vm-test-run-plv8> server # [ 0.007984] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1 vm-test-run-plv8> server # [ 0.008150] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-plv8> server # [ 0.008150] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-plv8> server # [ 0.008152] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-plv8> server # [ 0.008153] thermal_sys: Registered thermal governor 'user_space' vm-test-run-plv8> server # [ 0.008153] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-plv8> server # [ 0.008161] cpuidle: using governor ladder vm-test-run-plv8> server # [ 0.008164] cpuidle: using governor menu vm-test-run-plv8> server # [ 0.008220] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-plv8> server # [ 0.008318] ASID allocator initialised with 65536 entries vm-test-run-plv8> server # [ 0.008761] Serial: AMBA PL011 UART driver vm-test-run-plv8> server # [ 0.010853] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-plv8> server # [ 0.011083] printk: console [ttyAMA0] enabled vm-test-run-plv8> server # [ 0.030840] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.030847] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-plv8> server # [ 0.030849] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.030851] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-plv8> server # [ 0.030853] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.030855] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-plv8> server # [ 0.030857] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-plv8> server # [ 0.030858] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-plv8> server # [ 0.035671] fbcon: Taking over console vm-test-run-plv8> server # [ 0.035679] ACPI: Interpreter disabled. vm-test-run-plv8> server # [ 0.051689] iommu: Default domain type: Translated vm-test-run-plv8> server # [ 0.051705] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-plv8> server # [ 0.052001] SCSI subsystem initialized vm-test-run-plv8> server # [ 0.052252] usbcore: registered new interface driver usbfs vm-test-run-plv8> server # [ 0.052268] usbcore: registered new interface driver hub vm-test-run-plv8> server # [ 0.052274] usbcore: registered new device driver usb vm-test-run-plv8> server # [ 0.052457] pps_core: LinuxPPS API ver. 1 registered vm-test-run-plv8> server # [ 0.052460] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-plv8> server # [ 0.052463] PTP clock support registered vm-test-run-plv8> server # [ 0.052481] EDAC MC: Ver: 3.0.0 vm-test-run-plv8> server # [ 0.052706] scmi_core: SCMI protocol bus registered vm-test-run-plv8> server # [ 0.053074] FPGA manager framework vm-test-run-plv8> server # [ 0.053540] vgaarb: loaded vm-test-run-plv8> server # [ 0.053783] clocksource: Switched to clocksource arch_sys_counter vm-test-run-plv8> server # [ 0.054012] VFS: Disk quotas dquot_6.6.0 vm-test-run-plv8> server # [ 0.054035] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-plv8> server # [ 0.054733] netfs: FS-Cache loaded vm-test-run-plv8> server # [ 0.054785] pnp: PnP ACPI: disabled vm-test-run-plv8> server # [ 0.056378] NET: Registered PF_INET protocol family vm-test-run-plv8> server # [ 0.056476] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-plv8> server # [ 0.069346] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-plv8> server # [ 0.069358] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-plv8> server # [ 0.069367] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-plv8> server # [ 0.069392] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-plv8> server # [ 0.069426] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-plv8> server # [ 0.069463] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-plv8> server # [ 0.069480] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) vm-test-run-plv8> server # [ 0.069490] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) vm-test-run-plv8> server # [ 0.069520] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-plv8> server # [ 0.069529] NET: Registered PF_XDP protocol family vm-test-run-plv8> server # [ 0.069536] PCI: CLS 0 bytes, default 64 vm-test-run-plv8> server # [ 0.069610] Trying to unpack rootfs image as initramfs... vm-test-run-plv8> server # [ 0.077856] kvm [1]: HYP mode not available vm-test-run-plv8> server # [ 0.098295] Initialise system trusted keyrings vm-test-run-plv8> server # [ 0.101958] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-plv8> server # [ 0.106216] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-plv8> server # [ 0.106242] 9p: Installing v9fs 9p2000 file system support vm-test-run-plv8> server # [ 0.114255] Key type asymmetric registered vm-test-run-plv8> server # [ 0.114260] Asymmetric key parser 'x509' registered vm-test-run-plv8> server # [ 0.114283] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) vm-test-run-plv8> server # [ 0.117951] io scheduler mq-deadline registered vm-test-run-plv8> server # [ 0.117958] io scheduler kyber registered vm-test-run-plv8> server # [ 0.126025] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-plv8> server # [ 0.130227] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-plv8> server # [ 0.130541] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-plv8> server # [ 0.130553] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-plv8> server # [ 0.130559] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-plv8> server # [ 0.130562] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-plv8> server # [ 0.130574] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-plv8> server # [ 0.130589] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-plv8> server # [ 0.130643] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-plv8> server # [ 0.130654] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-plv8> server # [ 0.130657] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-plv8> server # [ 0.130659] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-plv8> server # [ 0.130660] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-plv8> server # [ 0.130686] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.130842] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.130917] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.130921] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.130929] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.130932] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-plv8> server # [ 0.131139] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-plv8> server # [ 0.131214] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.131222] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.131232] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.131412] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.131469] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-plv8> server # [ 0.131473] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.131481] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.131634] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.131691] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.131694] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.131702] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.131890] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-plv8> server # [ 0.131947] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.131951] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.131958] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.132073] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.132117] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-plv8> server # [ 0.132119] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.132124] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.132238] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.132280] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.132282] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.132287] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.132289] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-plv8> server # [ 0.132394] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.132437] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.132443] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.132538] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.132582] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.132587] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.132683] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-plv8> server # [ 0.132730] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.132791] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-plv8> server # [ 0.132944] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-plv8> server # [ 0.132952] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.132960] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.133137] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-plv8> server # [ 0.133194] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-plv8> server # [ 0.133198] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-plv8> server # [ 0.133205] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-plv8> server # [ 0.133469] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-plv8> server # [ 0.133477] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-plv8> server # [ 0.133482] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133507] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133521] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133534] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133547] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133560] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133574] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133587] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133600] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133614] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133664] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-plv8> server # [ 0.133701] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-plv8> server # [ 0.133710] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-plv8> server # [ 0.133716] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-plv8> server # [ 0.133741] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-plv8> server # [ 0.133746] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-plv8> server # [ 0.133750] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-plv8> server # [ 0.133754] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-plv8> server # [ 0.133758] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-plv8> server # [ 0.133763] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-plv8> server # [ 0.133767] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-plv8> server # [ 0.133771] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-plv8> server # [ 0.133775] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-plv8> server # [ 0.163985] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-plv8> server # [ 0.164006] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-plv8> server # [ 0.164010] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-plv8> server # [ 0.164014] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-plv8> server # [ 0.164019] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-plv8> server # [ 0.164022] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-plv8> server # [ 0.164026] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-plv8> server # [ 0.164030] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-plv8> server # [ 0.164033] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-plv8> server # [ 0.164046] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-plv8> server # [ 0.164048] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-plv8> server # [ 0.164049] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-plv8> server # [ 0.164319] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.173614] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.174881] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.175693] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.176220] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.176675] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.177180] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.177733] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.180322] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.180806] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-plv8> server # [ 0.183192] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.184866] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-plv8> server # [ 0.187186] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-plv8> server # [ 0.187685] msm_serial: driver initialized vm-test-run-plv8> server # [ 0.187738] SuperH (H)SCI(F) driver initialized vm-test-run-plv8> server # [ 0.187764] STM32 USART driver initialized vm-test-run-plv8> server # [ 0.198598] loop: module loaded vm-test-run-plv8> server # [ 0.198653] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-plv8> server # [ 0.198908] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-plv8> server # [ 0.199752] megasas: 07.734.00.00-rc1 vm-test-run-plv8> server # [ 0.200005] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-plv8> server # [ 0.200501] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-plv8> server # [ 0.200506] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-plv8> server # [ 0.201041] Using buffer write method vm-test-run-plv8> server # [ 0.201053] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-plv8> server # [ 0.201593] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-plv8> server # [ 0.201602] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-plv8> server # [ 0.202246] Freeing initrd memory: 25488K vm-test-run-plv8> server # [ 0.205377] Using buffer write method vm-test-run-plv8> server # [ 0.205389] Concatenating MTD devices: vm-test-run-plv8> server # [ 0.205391] (0): "0.flash" vm-test-run-plv8> server # [ 0.205392] (1): "0.flash" vm-test-run-plv8> server # [ 0.205394] into device "0.flash" vm-test-run-plv8> server # [ 0.208801] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-plv8> server # [ 0.210824] thunder_xcv, ver 1.0 vm-test-run-plv8> server # [ 0.210862] thunder_bgx, ver 1.0 vm-test-run-plv8> server # [ 0.210867] nicpf, ver 1.0 vm-test-run-plv8> server # [ 0.211111] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-plv8> server # [ 0.211113] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-plv8> server # [ 0.211119] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-plv8> server # [ 0.211120] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-plv8> server # [ 0.211127] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-plv8> server # [ 0.211128] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-plv8> server # [ 0.211133] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-plv8> server # [ 0.211134] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-plv8> server # [ 0.211180] sky2: driver version 1.30 vm-test-run-plv8> server # [ 0.211769] usbcore: registered new interface driver usb-storage vm-test-run-plv8> server # [ 0.211790] usbcore: registered new interface driver usbserial_generic vm-test-run-plv8> server # [ 0.211793] usbserial: USB Serial support registered for generic vm-test-run-plv8> server # [ 0.211983] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-plv8> server # [ 0.211990] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-plv8> server # [ 0.212086] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-plv8> server # [ 0.212170] hv_vmbus: registering driver hyperv_keyboard vm-test-run-plv8> server # [ 0.212534] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-plv8> server # [ 0.212543] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T11:10:40 UTC (1791198640) vm-test-run-plv8> server # [ 0.212702] i2c_dev: i2c /dev entries driver vm-test-run-plv8> server # [ 0.213604] sdhci: Secure Digital Host Controller Interface driver vm-test-run-plv8> server # [ 0.213606] sdhci: Copyright(c) Pierre Ossman vm-test-run-plv8> server # [ 0.213702] Synopsys Designware Multimedia Card Interface Driver vm-test-run-plv8> server # [ 0.213988] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-plv8> server # [ 0.214722] hid: raw HID events driver (C) Jiri Kosina vm-test-run-plv8> server # [ 0.214879] usbcore: registered new interface driver usbhid vm-test-run-plv8> server # [ 0.214882] usbhid: USB HID core driver vm-test-run-plv8> server # [ 0.215508] armv8-pmu pmu: hw perfevents: failed to probe PMU! vm-test-run-plv8> server # [ 0.216189] drop_monitor: Initializing network drop monitor service vm-test-run-plv8> server # [ 0.216267] NET: Registered PF_INET6 protocol family vm-test-run-plv8> server # [ 0.217750] Segment Routing with IPv6 vm-test-run-plv8> server # [ 0.217758] In-situ OAM (IOAM) with IPv6 vm-test-run-plv8> server # [ 0.217777] NET: Registered PF_PACKET protocol family vm-test-run-plv8> server # [ 0.218095] 9pnet: Installing 9P2000 support vm-test-run-plv8> server # [ 0.220799] Key type dns_resolver registered vm-test-run-plv8> server # [ 0.222135] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-plv8> server # [ 0.223432] registered taskstats version 1 vm-test-run-plv8> server # [ 0.223524] Loading compiled-in X.509 certificates vm-test-run-plv8> server # [ 0.226654] hub 1-0:1.0: USB hub found vm-test-run-plv8> server # [ 0.226671] hub 1-0:1.0: 6 ports detected vm-test-run-plv8> server # [ 0.227155] Demotion targets for Node 0: null vm-test-run-plv8> server # [ 0.227249] Key type .fscrypt registered vm-test-run-plv8> server # [ 0.227252] Key type fscrypt-provisioning registered vm-test-run-plv8> server # [ 0.227307] ima: No TPM chip found, activating TPM-bypass! vm-test-run-plv8> server # [ 0.227315] ima: Allocated hash algorithm: sha1 vm-test-run-plv8> server # [ 0.227324] ima: No architecture policies found vm-test-run-plv8> server # [ 0.227815] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-plv8> server # [ 0.232674] clk: Disabling unused clocks vm-test-run-plv8> server # [ 0.232681] PM: genpd: Disabling unused power domains vm-test-run-plv8> server # [ 0.233594] Freeing unused kernel memory: 4736K vm-test-run-plv8> server # [ 0.233624] Run /init as init process vm-test-run-plv8> server # [ 0.258004] systemd[1]: Successfully made /usr/ read-only. vm-test-run-plv8> server # [ 0.489351] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-plv8> server # [ 0.593272] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) vm-test-run-plv8> server # [ 0.593286] systemd[1]: Detected virtualization qemu. vm-test-run-plv8> server # [ 0.593349] systemd[1]: Detected architecture arm64. vm-test-run-plv8> server # [ 0.593354] systemd[1]: Running in initrd. vm-test-run-plv8> server # [ 0.593901] systemd[1]: Initializing machine ID from random generator. vm-test-run-plv8> server # [ 0.593996] systemd[1]: Hostname set to . vm-test-run-plv8> server # [ 0.658755] systemd[1]: Queued start job for default target Initrd Default Target. vm-test-run-plv8> server # [ 0.668172] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-plv8> server # [ 0.668243] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-plv8> server # [ 0.668255] systemd[1]: Expecting device /dev/disk/by-label/nixos... vm-test-run-plv8> server # [ 0.668266] systemd[1]: Reached target Path Units. vm-test-run-plv8> server # [ 0.668272] systemd[1]: Reached target Slice Units. vm-test-run-plv8> server # [ 0.668277] systemd[1]: Reached target Swaps. vm-test-run-plv8> server # [ 0.668282] systemd[1]: Reached target Timer Units. vm-test-run-plv8> server # [ 0.668325] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-plv8> server # [ 0.668359] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-plv8> server # [ 0.668381] systemd[1]: Listening on Journal Sockets. vm-test-run-plv8> server # [ 0.668406] systemd[1]: Listening on udev Control Socket. vm-test-run-plv8> server # [ 0.668436] systemd[1]: Listening on udev Kernel Socket. vm-test-run-plv8> server # [ 0.668442] systemd[1]: Reached target Socket Units. vm-test-run-plv8> server # [ 0.669004] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-plv8> server # [ 0.669766] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-plv8> server # [ 0.669864] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-plv8> server # [ 0.675924] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-plv8> server # [ 0.676775] systemd[1]: Starting Journal Service... vm-test-run-plv8> server # [ 0.677571] systemd[1]: Starting Load Kernel Modules... vm-test-run-plv8> server # [ 0.677589] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-plv8> server # [ 0.680613] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-plv8> server # [ 0.681025] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-plv8> server # [ 0.681374] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-plv8> server # [ 0.681454] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-plv8> server # [ 0.684296] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-plv8> server # [ 0.689374] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-plv8> server # [ 0.695548] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. vm-test-run-plv8> server # [ 0.701896] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev vm-test-run-plv8> server # [ 0.702711] systemd-journald[79]: Collecting audit messages is disabled. vm-test-run-plv8> server # [ 0.703951] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-plv8> server # [ 0.704007] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-plv8> server # [ 0.704008] [drm] features: -context_init vm-test-run-plv8> server # [ 0.704283] [drm] number of scanouts: 1 vm-test-run-plv8> server # [ 0.704287] [drm] number of cap sets: 0 vm-test-run-plv8> server # [ 0.710259] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-plv8> server # [ 0.710891] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic vm-test-run-plv8> server # [ 0.710895] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-plv8> server # [ 0.712361] Console: switching to colour frame buffer device 160x50 vm-test-run-plv8> server # [ 0.715446] 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 # [ 0.715985] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-plv8> server # [ 0.718598] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-plv8> server # [ 0.711411] systemd-modules-load[80]: Using 2 probe threads vm-test-run-plv8> server # [ 0.728338] systemd[1]: Started Journal Service. vm-test-run-plv8> server # [ 0.713507] systemd-modules-load[80]: Module 'virtio_balloon' is built in vm-test-run-plv8> server # [ 0.716201] systemd-modules-load[80]: Module 'virtio_console' is built in vm-test-run-plv8> server # [ 0.716487] systemd-modules-load[80]: Inserted module 'dm_mod' vm-test-run-plv8> server # [ 0.717180] systemd-modules-load[80]: Module 'virtio_rng' is built in vm-test-run-plv8> server # [ 0.719336] systemd-modules-load[80]: Inserted module 'virtio_gpu' vm-test-run-plv8> server # [ 0.719869] systemd[1]: Finished Load Kernel Modules. vm-test-run-plv8> server # [ 0.720941] systemd[1]: Starting Apply Kernel Variables... vm-test-run-plv8> server # [ 0.723860] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-plv8> server # [ 0.724478] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-plv8> server # [ 0.724877] systemd[1]: Reached target Local File Systems. vm-test-run-plv8> server # [ 0.725457] systemd[1]: Starting Create System Files and Directories... vm-test-run-plv8> server # [ 0.726718] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-plv8> server # [ 0.731126] systemd[1]: Finished Apply Kernel Variables. vm-test-run-plv8> server # [ 0.752696] systemd[1]: Finished Create System Files and Directories. vm-test-run-plv8> server # [ 0.755485] systemd-udevd[95]: Using default interface naming scheme 'v260'. vm-test-run-plv8> server # [ 0.761975] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-plv8> server # [ 0.777745] systemd[1]: Starting Virtual Console Setup... vm-test-run-plv8> server # [ 0.820051] 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 # [ 0.819147] systemd-vconsole-setup[116]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-plv8> server # [ 0.820722] systemd[1]: Finished Virtual Console Setup. vm-test-run-plv8> server # [ 0.864521] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-plv8> server # [ 0.864948] systemd[1]: Reached target System Initialization. vm-test-run-plv8> server # [ 0.865268] systemd[1]: Reached target Basic System. vm-test-run-plv8> server # [ 0.915533] (udev-worker)[106]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-plv8> server # [ 0.917983] (udev-worker)[106]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server # [ 0.923908] (udev-worker)[112]: Network interface NamePolicy= disabled on kernel command line.[ 0.937892] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-plv8> server # vm-test-run-plv8> server # [ 0.945157] systemd[1]: Found device /dev/disk/by-label/nixos. vm-test-run-plv8> server # [ 0.946169] systemd[1]: Reached target Initrd Root Device. vm-test-run-plv8> server # [ 0.946393] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... vm-test-run-plv8> server # [ 0.985535] systemd-fsck[134]: nixos: clean, 12/65536 files, 13019/262144 blocks vm-test-run-plv8> server # [ 0.989525] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. vm-test-run-plv8> server # [ 1.166379] 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 # [ 1.166724] 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 # [ 1.190351] (udev-worker)[114]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory vm-test-run-plv8> server # [ 1.191434] (udev-worker)[114]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory vm-test-run-plv8> server # [ 1.196391] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-plv8> server # [ 1.198716] systemd[1]: Stopped Virtual Console Setup. vm-test-run-plv8> server # [ 1.199529] systemd[1]: Stopping Virtual Console Setup... vm-test-run-plv8> server # [ 1.200077] systemd[1]: Starting Virtual Console Setup... vm-test-run-plv8> server # [ 1.239795] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-plv8> server # [ 1.240981] systemd[1]: Finished Virtual Console Setup. vm-test-run-plv8> server # [ 1.720814] systemd[1]: Mounting /sysroot... vm-test-run-plv8> server # [ 1.772744] EXT4-fs (vda): mounted filesystem 8dde9023-05a4-439c-8eae-b2c0d5ff9d2f r/w with ordered data mode. Quota mode: none. vm-test-run-plv8> server # [ 1.756965] systemd[1]: Mounted /sysroot. vm-test-run-plv8> server # [ 1.757437] systemd[1]: Reached target Initrd Root File System. vm-test-run-plv8> server # [ 1.759692] systemd[1]: Mounting /sysroot/nix/.ro-store... vm-test-run-plv8> server # [ 1.760609] systemd[1]: Mounting /sysroot/nix/.rw-store... vm-test-run-plv8> server # [ 1.762704] systemd[1]: Mounting /sysroot/run... vm-test-run-plv8> server # [ 1.796174] systemd[1]: Mounting /sysroot/tmp/shared... vm-test-run-plv8> server # [ 1.797392] systemd[1]: Mounting /sysroot/tmp/xchg... vm-test-run-plv8> server # [ 1.801738] systemd[1]: Starting Mountpoints Configured in the Real Root... vm-test-run-plv8> server # [ 1.806750] systemd[1]: Mounted /sysroot/nix/.ro-store. vm-test-run-plv8> server # [ 1.807076] systemd[1]: Mounted /sysroot/nix/.rw-store. vm-test-run-plv8> server # [ 1.807741] systemd[1]: Mounted /sysroot/run. vm-test-run-plv8> server # [ 1.808387] systemd-sysroot-fstab-check[153]: /sysroot should be mounted in the initrd, will request daemon-reload. vm-test-run-plv8> server # [ 1.809381] systemd[1]: Mounted /sysroot/tmp/xchg. vm-test-run-plv8> server # [ 1.810535] systemd[1]: Mounted /sysroot/tmp/shared. vm-test-run-plv8> server # [ 1.813626] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-plv8> server # [ 1.813937] systemd[1]: Reload requested from client PID 153 ('systemd-sysroot') (unit initrd-parse-etc.service)... vm-test-run-plv8> server # [ 1.814384] systemd[1]: Reloading... vm-test-run-plv8> server # [ 1.857809] systemd[1]: Reloading finished in 44 ms. vm-test-run-plv8> server # [ 1.889988] systemd-sysroot-fstab-check[153]: Requesting initrd-fs.target/start/replace... vm-test-run-plv8> server # [ 1.890597] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-plv8> server # [ 1.891276] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-plv8> server # [ 1.891653] systemd-sysroot-fstab-check[153]: Requesting swap.target/start/replace... vm-test-run-plv8> server # [ 1.892254] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-plv8> server # [ 1.894229] systemd[1]: initrd-parse-etc.service: Deactivated successfully. vm-test-run-plv8> server # [ 1.894736] systemd[1]: Finished Mountpoints Configured in the Real Root. vm-test-run-plv8> server # [ 1.895511] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. vm-test-run-plv8> server # [ 1.914620] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-plv8> server # [ 1.915044] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-plv8> server # [ 2.726375] systemd[1]: Mounting /sysroot/nix/store... vm-test-run-plv8> server # [ 2.746761] systemd[1]: Mounted /sysroot/nix/store. vm-test-run-plv8> server # [ 2.747158] systemd[1]: Reached target Initrd File Systems. vm-test-run-plv8> server # [ 2.750810] systemd[1]: Starting Find NixOS closure... vm-test-run-plv8> server # [ 2.751676] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... vm-test-run-plv8> server # [ 2.758972] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. vm-test-run-plv8> server # [ 2.760458] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. vm-test-run-plv8> server # [ 2.761708] systemd[1]: Finished Find NixOS closure. vm-test-run-plv8> server # [ 2.762526] systemd[1]: Reached target Initrd Default Target. vm-test-run-plv8> server # [ 2.762959] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... vm-test-run-plv8> server # [ 2.770381] systemd[1]: Stopped target Initrd Default Target. vm-test-run-plv8> server # [ 2.770632] systemd[1]: Stopped target Basic System. vm-test-run-plv8> server # [ 2.770974] systemd[1]: Stopped target Initrd Root Device. vm-test-run-plv8> server # [ 2.771258] systemd[1]: Stopped target Path Units. vm-test-run-plv8> server # [ 2.771608] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. vm-test-run-plv8> server # [ 2.772962] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. vm-test-run-plv8> server # [ 2.773473] systemd[1]: Stopped target Slice Units. vm-test-run-plv8> server # [ 2.775617] systemd[1]: Stopped target Socket Units. vm-test-run-plv8> server # [ 2.775955] systemd[1]: Stopped target System Initialization. vm-test-run-plv8> server # [ 2.776642] systemd[1]: Stopped target Swaps. vm-test-run-plv8> server # [ 2.777018] systemd[1]: Stopped target Timer Units. vm-test-run-plv8> server # [ 2.777426] systemd[1]: dbus.socket: Deactivated successfully. vm-test-run-plv8> server # [ 2.777819] systemd[1]: Closed D-Bus System Message Bus Socket. vm-test-run-plv8> server # [ 2.778288] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.778852] systemd[1]: Stopped Find NixOS closure. vm-test-run-plv8> server # [ 2.779280] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-plv8> server # [ 2.779717] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-plv8> server # [ 2.783223] systemd[1]: systemd-sysctl.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.783470] systemd[1]: Stopped Apply Kernel Variables. vm-test-run-plv8> server # [ 2.784735] systemd[1]: systemd-modules-load.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.785327] systemd[1]: Stopped Load Kernel Modules. vm-test-run-plv8> server # [ 2.785712] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.786059] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. vm-test-run-plv8> server # [ 2.786376] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.786816] systemd[1]: Stopped Create System Files and Directories. vm-test-run-plv8> server # [ 2.787206] systemd[1]: Stopped target Local File Systems. vm-test-run-plv8> server # [ 2.787388] systemd[1]: Stopped target Preparation for Local File Systems. vm-test-run-plv8> server # [ 2.787620] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.788148] systemd[1]: Stopped Coldplug All udev Devices. vm-test-run-plv8> server # [ 2.788671] systemd[1]: Stopping Rule-based Manager for Device Events and Files... vm-test-run-plv8> server # [ 2.789139] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.789528] systemd[1]: Stopped Virtual Console Setup. vm-test-run-plv8> server # [ 2.789717] systemd[1]: initrd-cleanup.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.789958] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. vm-test-run-plv8> server # [ 2.790279] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.790767] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-plv8> server # [ 2.791300] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.791826] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-plv8> server # [ 2.794401] systemd[1]: systemd-udevd.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.794890] systemd[1]: Stopped Rule-based Manager for Device Events and Files. vm-test-run-plv8> server # [ 2.795278] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. vm-test-run-plv8> server # [ 2.796625] systemd[1]: Closed udev Control Socket. vm-test-run-plv8> server # [ 2.796978] systemd[1]: Starting Cleanup udev Database... vm-test-run-plv8> server # [ 2.797344] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.797792] systemd[1]: Stopped Create Static Device Nodes in /dev. vm-test-run-plv8> server # [ 2.798214] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.798561] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. vm-test-run-plv8> server # [ 2.799157] systemd[1]: kmod-static-nodes.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.799788] systemd[1]: Stopped Create List of Static Device Nodes. vm-test-run-plv8> server # [ 2.826896] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. vm-test-run-plv8> server # [ 2.827396] systemd[1]: Finished Cleanup udev Database. vm-test-run-plv8> server # [ 2.827573] systemd[1]: Reached target Switch Root. vm-test-run-plv8> server # [ 2.828250] systemd[1]: Starting NixOS Activation... vm-test-run-plv8> server # [ 3.152507] initrd-nixos-activation-start[200]: booting system configuration /nix/store/qi36bjlm9rlhx5jnmfx1jvgl31956sf7-nixos-system-server-test vm-test-run-plv8> server # [ 3.277275] initrd-nixos-activation-start[200]: running activation script... vm-test-run-plv8> server # [ 4.078281] initrd-nixos-activation-start[223]: setting up /etc... vm-test-run-plv8> server # [ 4.602359] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. vm-test-run-plv8> server # [ 4.602711] systemd[1]: Finished NixOS Activation. vm-test-run-plv8> server # [ 4.602979] systemd[1]: Starting Switch Root... vm-test-run-plv8> server # [ 4.640129] systemd[1]: Switching root. vm-test-run-plv8> server # [ 4.767141] systemd-journald[79]: Received SIGTERM from PID 1 (systemd). vm-test-run-plv8> server # [ 5.555804] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) vm-test-run-plv8> server # [ 5.555836] systemd[1]: Detected virtualization qemu. vm-test-run-plv8> server # [ 5.555879] systemd[1]: Detected architecture arm64. vm-test-run-plv8> server # [ 5.555975] systemd[1]: Detected first boot. vm-test-run-plv8> server # [ 5.565985] systemd[1]: Initializing machine ID from random generator. vm-test-run-plv8> server # [ 5.865158] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-plv8> server # [ 6.005942] systemd[1]: Applying preset policy. vm-test-run-plv8> server # [ 6.845069] systemd[1]: Populated /etc with preset unit settings. vm-test-run-plv8> server # [ 7.804142] systemd[1]: initrd-switch-root.service: Deactivated successfully. vm-test-run-plv8> server # [ 7.804719] systemd[1]: Stopped initrd-switch-root.service. vm-test-run-plv8> server # [ 7.805634] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. vm-test-run-plv8> server # [ 7.807694] systemd[1]: Created slice Slice /system/getty. vm-test-run-plv8> server # [ 7.808166] systemd[1]: Created slice User and Session Slice. vm-test-run-plv8> server # [ 7.808481] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-plv8> server # [ 7.808988] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-plv8> server # [ 7.809676] systemd[1]: Expecting device /dev/hvc0... vm-test-run-plv8> server # [ 7.810430] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-plv8> server # [ 7.811231] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-plv8> server # [ 7.811764] systemd[1]: Stopped target initrd-fs.target. vm-test-run-plv8> server # [ 7.812426] systemd[1]: Stopped target initrd-root-fs.target. vm-test-run-plv8> server # [ 7.813183] systemd[1]: Stopped target initrd-switch-root.target. vm-test-run-plv8> server # [ 7.813843] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-plv8> server # [ 7.814073] systemd[1]: Reached target Path Units. vm-test-run-plv8> server # [ 7.814331] systemd[1]: Reached target Remote File Systems. vm-test-run-plv8> server # [ 7.815211] systemd[1]: Reached target Slice Units. vm-test-run-plv8> server # [ 7.815593] systemd[1]: Reached target Swaps. vm-test-run-plv8> server # [ 7.822468] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-plv8> server # [ 7.827754] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-plv8> server # [ 7.832762] systemd[1]: Starting Journal Log Access Socket... vm-test-run-plv8> server # [ 7.833144] systemd[1]: Listening on Journal Audit Socket. vm-test-run-plv8> server # [ 7.834132] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-plv8> server # [ 7.834556] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-plv8> server # [ 7.835269] systemd[1]: Listening on udev Control Socket. vm-test-run-plv8> server # [ 7.868165] systemd[1]: Mounting Huge Pages File System... vm-test-run-plv8> server # [ 7.872512] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-plv8> server # [ 7.877825] systemd[1]: Mounting Kernel Debug File System... vm-test-run-plv8> server # [ 7.884438] systemd[1]: Mounting Kernel Trace File System... vm-test-run-plv8> server # [ 7.894290] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-plv8> server # [ 7.928621] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-plv8> server # [ 7.929608] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-plv8> server # [ 7.940059] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-plv8> server # [ 7.940511] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm vm-test-run-plv8> server # [ 7.941002] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-plv8> server # [ 7.949905] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-plv8> server # [ 7.951386] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-plv8> server # [ 7.964276] systemd[1]: Starting Journal Service... vm-test-run-plv8> server # [ 7.969977] systemd[1]: Starting Load Kernel Modules... vm-test-run-plv8> server # [ 7.977690] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-plv8> server # [ 7.991523] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-plv8> server # [ 7.992038] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-plv8> server # [ 8.001182] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-plv8> server # [ 8.003459] systemd[1]: Listening on Journal Log Access Socket. vm-test-run-plv8> server # [ 8.066812] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-plv8> server # [ 8.067458] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-plv8> server # [ 8.067800] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-plv8> server # [ 8.081553] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-plv8> server # [ 8.104823] systemd-journald[293]: Collecting audit messages is enabled. vm-test-run-plv8> server # [ 8.111637] systemd[1]: Started Journal Service. vm-test-run-plv8> server # [ 8.093614] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-plv8> server # [ 8.101843] systemd[1]: systemd-journald.service: Deactivated successfully. vm-test-run-plv8> server # [ 8.102567] systemd-modules-load[294]: Using 2 probe threads vm-test-run-plv8> server # [ 8.107265] systemd-oomd[295]: No swap; memory pressure usage will be degraded vm-test-run-plv8> server # [ 8.137702] fuse: init (API version 7.45) vm-test-run-plv8> server # [ 8.122546] systemd[1]: Mounted Kernel Debug File System. vm-test-run-plv8> server # [ 8.130668] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-plv8> server # [ 8.132800] systemd[1]: Mounted Huge Pages File System. vm-test-run-plv8> server # [ 8.133403] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-plv8> server # [ 8.135182] systemd[1]: Mounted Kernel Trace File System. vm-test-run-plv8> server # [ 8.137160] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-plv8> server # [ 8.137867] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-plv8> server # [ 8.143986] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-plv8> server # [ 8.157939] EXT4-fs (vda): re-mounted 8dde9023-05a4-439c-8eae-b2c0d5ff9d2f. vm-test-run-plv8> server # [ 8.146983] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-plv8> server # [ 8.148403] systemd-modules-load[294]: Module 'atkbd' is built in vm-test-run-plv8> server # [ 8.149387] systemd-modules-load[294]: Module 'loop' is built in vm-test-run-plv8> server # [ 8.152623] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-plv8> server # [ 8.155055] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-plv8> server # [ 8.158163] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-plv8> server # [ 8.159753] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-plv8> server # [ 8.161416] systemd[1]: Finished Load Kernel Modules. vm-test-run-plv8> server # [ 8.166170] systemd[1]: Starting Firewall... vm-test-run-plv8> server # [ 8.170991] systemd[1]: Starting Apply Kernel Variables... vm-test-run-plv8> server # [ 8.279977] systemd-journald[293]: Received client request to flush runtime journal. vm-test-run-plv8> server # [ 8.287808] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-plv8> server # [ 8.291032] systemd[1]: Reached target First Boot Complete. vm-test-run-plv8> server # [ 8.292903] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-plv8> server # [ 8.323483] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-plv8> server # [ 8.329342] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-plv8> server # [ 8.333822] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-plv8> server # [ 8.352764] systemd[1]: Finished Apply Kernel Variables. vm-test-run-plv8> server # [ 8.427668] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-plv8> server # [ 8.428376] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-plv8> server # [ 8.430759] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-plv8> server # [ 8.530006] systemd-udevd[331]: Using default interface naming scheme 'v260'. vm-test-run-plv8> server # [ 8.735848] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-plv8> server # [ 8.790476] systemd[1]: Mounting /run/wrappers... vm-test-run-plv8> server # [ 8.794294] systemd[1]: Mounting FUSE Control File System... vm-test-run-plv8> server # [ 8.815290] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-plv8> server # [ 8.819649] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse vm-test-run-plv8> server # [ 8.850323] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. vm-test-run-plv8> server # [ 8.864101] systemd[1]: Mounted /run/wrappers. vm-test-run-plv8> server # [ 8.864944] systemd[1]: Mounted FUSE Control File System. vm-test-run-plv8> server # [ 8.865459] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. vm-test-run-plv8> server # [ 8.866290] systemd[1]: Reached target Local File Systems. vm-test-run-plv8> server # [ 8.871673] systemd[1]: Listening on Boot Loader Control Service Socket. vm-test-run-plv8> server # [ 8.875937] systemd[1]: Starting register-nix-paths.service... vm-test-run-plv8> server # [ 8.881714] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-plv8> server # [ 8.882736] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-plv8> server # [ 8.891213] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-plv8> server # [ 8.897080] systemd[1]: Starting Create System Files and Directories... vm-test-run-plv8> server # [ 9.002808] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-plv8> server # [ 9.007711] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-plv8> server # [ 9.038235] (udev-worker)[377]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-plv8> server # [ 9.042088] (udev-worker)[377]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server # [ 9.047700] (udev-worker)[370]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-plv8> server # [ 9.087867] systemd[1]: Finished Create System Files and Directories. vm-test-run-plv8> server # [ 9.094009] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-plv8> server # [ 9.098670] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-plv8> server # [ 9.139817] systemd[1]: Condition check resulted in Virtio network device being skipped. vm-test-run-plv8> server # [ 9.146462] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-plv8> server # [ 9.151308] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-plv8> server # [ 9.153048] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-plv8> server # [ 9.158406] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-plv8> server # [ 9.161145] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-plv8> server # [ 9.164244] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-plv8> server # [ 9.187814] mousedev: PS/2 mouse device common for all mice vm-test-run-plv8> server # [ 9.199154] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-plv8> server # [ 9.283004] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-plv8> server # [ 9.286766] systemd[1]: Starting Update is Completed... vm-test-run-plv8> server # [ 9.360695] systemd[1]: Finished Update is Completed. vm-test-run-plv8> server # [ 9.426123] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 vm-test-run-plv8> server # [ 9.581121] systemd[1]: Starting Virtual Console Setup... vm-test-run-plv8> server # [ 9.941261] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-plv8> server # [ 9.943411] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-plv8> server # [ 10.166842] systemd[1]: Finished Firewall. vm-test-run-plv8> server # [ 10.335645] systemd[1]: Finished register-nix-paths.service. vm-test-run-plv8> server # [ 10.578548] systemd-vconsole-setup[475]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-plv8> server # [ 10.581150] systemd[1]: Finished Virtual Console Setup. vm-test-run-plv8> server # [ 10.581632] systemd[1]: Reached target System Initialization. vm-test-run-plv8> server # [ 10.582068] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-plv8> server # [ 10.582440] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-plv8> server # [ 10.583212] systemd[1]: Reached target Timer Units. vm-test-run-plv8> server # [ 10.583886] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-plv8> server # [ 10.584863] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-plv8> server # [ 10.585421] systemd[1]: Listening on Hostname Service Socket. vm-test-run-plv8> server # [ 10.586025] systemd[1]: Reached target Socket Units. vm-test-run-plv8> server # [ 10.587271] systemd[1]: Reached target Basic System. vm-test-run-plv8> server # [ 10.588365] systemd[1]: Started backdoor.service. vm-test-run-plv8> server # [ 10.588980] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-plv8> server # [ 10.596195] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 10.621282] systemd[1]: Starting Post-Boot Actions... vm-test-run-plv8> server # [ 10.625892] systemd[1]: Started Reset console on configuration changes. vm-test-run-plv8> server # [ 10.633556] systemd[1]: Starting resolvconf update... vm-test-run-plv8> server # [ 10.641078] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-plv8> server # [ 10.715264] systemd[1]: Finished Post-Boot Actions. vm-test-run-plv8> server # connecting to host... vm-test-run-plv8> server # [ 10.740950] nsncd[545]: Oct 05 11:10:51.045 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-plv8> server # [ 10.742622] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 10.743110] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-plv8> server # [ 10.743731] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-plv8> server # [ 10.747151] systemd[1]: Starting User Login Management... vm-test-run-plv8> server # [ 10.750316] systemd[1]: Finished Import lastlog data into lastlog2 database. 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 10.93 seconds) vm-test-run-plv8> server: (finished: waiting for the VM to finish booting, in 10.93 seconds) vm-test-run-plv8> server # [ 10.838704] systemd-logind[570]: New seat seat0. vm-test-run-plv8> server # [ 10.842423] systemd-logind[570]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) vm-test-run-plv8> server # [ 10.843084] systemd-logind[570]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-plv8> server # [ 10.844365] systemd-logind[570]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-plv8> server # [ 10.844991] systemd[1]: Started User Login Management. vm-test-run-plv8> server # [ 10.847804] systemd[1]: Starting linger-users.service... vm-test-run-plv8> server # [ 10.850491] dbus-broker-launch[550]: Looking up NSS user entry for 'systemd-timesync'... vm-test-run-plv8> server # [ 10.923372] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-plv8> server # [ 10.924499] systemd[1]: Finished linger-users.service. vm-test-run-plv8> server # [ 10.927612] dbus-broker-launch[550]: NSS returned no entry for 'systemd-timesync' vm-test-run-plv8> server # [ 10.928501] dbus-broker-launch[550]: Invalid user-name in /nix/store/pdfq1nwq546h83cw8irgpibxz7flmccw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" vm-test-run-plv8> server # [ 10.931980] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-plv8> server # [ 10.932849] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-plv8> server # [ 10.933308] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-plv8> server # [ 10.935318] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-plv8> server # [ 10.936266] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 10.938448] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-plv8> server # [ 10.939825] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 10.944660] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-plv8> server # [ 11.000443] systemd[1]: Started D-Bus System Message Bus. vm-test-run-plv8> server # [ 11.014252] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-plv8> server # [ 11.016136] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-plv8> server # [ 11.016882] nsncd[624]: Oct 05 11:10:51.321 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-plv8> server # [ 11.017950] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-plv8> server # [ 11.028322] dbus-broker-launch[550]: Ready vm-test-run-plv8> server # [ 11.053886] systemd[1]: Finished resolvconf update. vm-test-run-plv8> server # [ 11.054448] systemd[1]: Reached target Preparation for Network. vm-test-run-plv8> server # [ 11.057888] systemd[1]: Starting DHCP Client... vm-test-run-plv8> server # [ 11.061707] systemd[1]: Starting Address configuration of eth1... vm-test-run-plv8> server # [ 11.065039] systemd[1]: Starting Extra networking commands.... vm-test-run-plv8> server # [ 11.228483] network-addresses-eth1-start[654]: adding address 192.168.1.1/24... done vm-test-run-plv8> server # [ 11.230313] dhcpcd[661]: dhcpcd-10.3.1 starting vm-test-run-plv8> server # [ 11.246823] network-addresses-eth1-start[654]: adding address 2001:db8:1::1/64... done vm-test-run-plv8> server # [ 11.252309] dhcpcd[697]: dev: loaded udev vm-test-run-plv8> server # [ 11.271448] systemd[1]: Finished Address configuration of eth1. vm-test-run-plv8> server # [ 11.287576] systemd[1]: Finished Extra networking commands.. vm-test-run-plv8> server # [ 11.288545] systemd[1]: Reached target Network. vm-test-run-plv8> server # [ 11.292657] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-plv8> server # [ 11.295410] systemd[1]: Starting Permit User Sessions... vm-test-run-plv8> server # [ 11.329232] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-plv8> server # [ 11.330901] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-plv8> server # [ 11.382694] systemd[1]: Finished Permit User Sessions. vm-test-run-plv8> server # [ 11.383054] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. vm-test-run-plv8> server # [ 11.386867] systemd[1]: Started Getty on tty1. vm-test-run-plv8> server # [ 11.388241] systemd[1]: Reached target Login Prompts. vm-test-run-plv8> server # [ 11.433739] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-plv8> server # [ 11.478730] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-plv8> server # [ 11.479082] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-plv8> server # [ 11.466656] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[729]: Initializing database at /var/lib/postgresql/data vm-test-run-plv8> server # [ 11.483776] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 vm-test-run-plv8> server # [ 11.484074] cfg80211: failed to load regulatory.db vm-test-run-plv8> server # [ 11.558506] dhcpcd[697]: eth0: waiting for carrier vm-test-run-plv8> server # [ 11.559043] dhcpcd[697]: eth0: carrier acquired vm-test-run-plv8> server # [ 11.574937] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-plv8> server # [ 11.577115] dhcpcd[697]: DUID 00:01:00:01:32:56:42:3b:52:54:00:12:34:56 vm-test-run-plv8> server # [ 11.577674] dhcpcd[697]: eth0: IAID 00:12:34:56 vm-test-run-plv8> server # [ 11.578135] dhcpcd[697]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-plv8> server # [ 11.946824] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: The files belonging to this database system will be owned by user "postgres". vm-test-run-plv8> server # [ 11.948420] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: This user must also own the server process. vm-test-run-plv8> server # [ 11.970746] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-plv8> server # [ 11.971857] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: The default database encoding has accordingly been set to "UTF8". vm-test-run-plv8> server # [ 11.972996] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: The default text search configuration will be set to "english". vm-test-run-plv8> server # [ 11.973620] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: Data page checksums are enabled. vm-test-run-plv8> server # [ 11.974319] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-plv8> server # [ 11.975785] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: creating subdirectories ... ok vm-test-run-plv8> server # [ 11.976954] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: selecting dynamic shared memory implementation ... posix vm-test-run-plv8> server # [ 12.194792] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: selecting default max_connections ... 100 vm-test-run-plv8> server # [ 12.271057] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: selecting default shared_buffers ... 128MB vm-test-run-plv8> server # [ 12.776932] dhcpcd[697]: eth0: soliciting a DHCP lease vm-test-run-plv8> server # [ 12.788626] dhcpcd[697]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-plv8> server # [ 12.804409] dhcpcd[697]: eth0: probing address 10.0.2.15/24 vm-test-run-plv8> server # [ 13.456169] dhcpcd[697]: eth0: soliciting an IPv6 router vm-test-run-plv8> server # [ 13.457225] dhcpcd[697]: eth0: Router Advertisement from fe80::2 vm-test-run-plv8> server # [ 13.457809] dhcpcd[697]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-plv8> server # [ 13.458418] dhcpcd[697]: eth0: adding route to fec0::/64 vm-test-run-plv8> server # [ 13.459192] dhcpcd[697]: eth0: adding default route via fe80::2 vm-test-run-plv8> server # [ 14.262909] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: selecting default time zone ... UTC vm-test-run-plv8> server # [ 14.271594] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: creating configuration files ... ok vm-test-run-plv8> server # [ 14.490437] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: running bootstrap script ... ok vm-test-run-plv8> server # [ 15.007147] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: performing post-bootstrap initialization ... ok vm-test-run-plv8> server # [ 15.181031] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: syncing data to disk ... ok vm-test-run-plv8> server # [ 15.181837] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-plv8> server # [ 15.182947] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: 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 # [ 15.185313] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: Success. You can now start the database server using: vm-test-run-plv8> server # [ 15.185974] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[734]: /nix/store/2bhdbvpk81xy3mkfgqrpc719ip588cba-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-plv8> server # [ 15.397761] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[729]: Validating PostgreSQL configuration... vm-test-run-plv8> server # [ 15.463863] d40506md7j2s63w6zdx4m1ajlgkwzw1l-postgresql-init[795]: 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 # [ 15.657251] postgres[797]: 2026-10-05 11:10:55.962 UTC [797] LOG: pgaudit extension initialized vm-test-run-plv8> server # [ 15.802291] postgres[797]: 2026-10-05 11:10:56.107 UTC [797] LOG: pgsodium primary server secret key loaded vm-test-run-plv8> server # [ 15.876518] postgres[797]: 2026-10-05 11:10:56.181 UTC [797] LOG: vault primary server secret key loaded vm-test-run-plv8> server # [ 15.887778] postgres[797]: 2026-10-05 11:10:56.192 UTC [797] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-plv8> server # [ 15.889587] postgres[797]: 2026-10-05 11:10:56.194 UTC [797] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-plv8> server # [ 15.890268] postgres[797]: 2026-10-05 11:10:56.194 UTC [797] LOG: listening on IPv6 address "::", port 5432 vm-test-run-plv8> server # [ 15.892194] postgres[797]: 2026-10-05 11:10:56.197 UTC [797] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-plv8> server # [ 15.896859] postgres[811]: 2026-10-05 11:10:56.201 UTC [811] LOG: database system was shut down at 2026-10-05 11:10:55 UTC vm-test-run-plv8> server # [ 15.909699] postgres[797]: 2026-10-05 11:10:56.214 UTC [797] LOG: database system is ready to accept connections vm-test-run-plv8> server # [ 15.913895] postgres[815]: 2026-10-05 11:10:56.217 UTC [815] FATAL: role "postgres" does not exist vm-test-run-plv8> server # [ 15.914755] postgres[816]: 2026-10-05 11:10:56.218 UTC [816] LOG: pg_cron scheduler started vm-test-run-plv8> server # [ 15.915974] postgres[814]: 2026-10-05 11:10:56.219 UTC [814] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-plv8> server # [ 15.922247] systemd[1]: Started PostgreSQL Database Server. vm-test-run-plv8> server # [ 15.924271] postgres[797]: 2026-10-05 11:10:56.229 UTC [797] LOG: background worker "pg_net 0.20.4 worker" (PID 815) exited with exit code 1 vm-test-run-plv8> server # [ 15.926413] systemd[1]: Starting Supabase Database Initialization... vm-test-run-plv8> server # [ 15.980159] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Waiting for PostgreSQL to be ready... vm-test-run-plv8> server # [ 16.038757] postgres[822]: ::1 2026-10-05 11:10:56.343 UTC [822] root@root FATAL: role "root" does not exist vm-test-run-plv8> server # [ 16.041601] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: PostgreSQL is ready vm-test-run-plv8> server # [ 16.042721] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Creating postgres role... vm-test-run-plv8> server # [ 16.108582] postgres[824]: ::1 2026-10-05 11:10:56.413 UTC [824] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-plv8> server # [ 16.110084] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[823]: CREATE ROLE vm-test-run-plv8> server # [ 16.146272] postgres[826]: ::1 2026-10-05 11:10:56.451 UTC [826] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-plv8> server # [ 16.147932] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[825]: ALTER DATABASE vm-test-run-plv8> server # [ 16.154672] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-plv8> server # [ 16.186837] postgres[828]: ::1 2026-10-05 11:10:56.491 UTC [828] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-plv8> server # [ 16.188378] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE PUBLICATION vm-test-run-plv8> server # [ 16.189232] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER ROLE vm-test-run-plv8> server # [ 16.189644] postgres[828]: ::1 2026-10-05 11:10:56.493 UTC [828] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-plv8> server # [ 16.190891] postgres[828]: ::1 2026-10-05 11:10:56.494 UTC [828] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-plv8> server # [ 16.192146] postgres[828]: ::1 2026-10-05 11:10:56.494 UTC [828] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-plv8> server # [ 16.193439] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 16.193864] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 16.194719] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT ROLE vm-test-run-plv8> server # [ 16.195204] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT vm-test-run-plv8> server # [ 16.195627] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 16.195947] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT ROLE vm-test-run-plv8> server # [ 16.196766] postgres[828]: ::1 2026-10-05 11:10:56.495 UTC [828] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-plv8> server # [ 16.197640] postgres[828]: ::1 2026-10-05 11:10:56.496 UTC [828] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-plv8> server # [ 16.198505] postgres[828]: ::1 2026-10-05 11:10:56.496 UTC [828] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-plv8> server # [ 16.199900] postgres[828]: ::1 2026-10-05 11:10:56.497 UTC [828] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-plv8> server # [ 16.200662] postgres[828]: ::1 2026-10-05 11:10:56.497 UTC [828] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-plv8> server # [ 16.201421] postgres[828]: ::1 2026-10-05 11:10:56.498 UTC [828] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-plv8> server # [ 16.202202] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE SCHEMA vm-test-run-plv8> server # [ 16.843945] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE EXTENSION vm-test-run-plv8> server # [ 16.846013] postgres[828]: ::1 2026-10-05 11:10:57.149 UTC [828] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-plv8> server # [ 17.099630] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE EXTENSION vm-test-run-plv8> server # [ 17.100737] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 17.101642] postgres[828]: ::1 2026-10-05 11:10:57.405 UTC [828] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-plv8> server # [ 17.103210] postgres[828]: ::1 2026-10-05 11:10:57.405 UTC [828] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-plv8> server # [ 17.104472] postgres[828]: ::1 2026-10-05 11:10:57.406 UTC [828] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-plv8> server # [ 17.105885] postgres[828]: ::1 2026-10-05 11:10:57.407 UTC [828] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-plv8> server # [ 17.106885] postgres[828]: ::1 2026-10-05 11:10:57.408 UTC [828] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-plv8> server # [ 17.107860] postgres[828]: ::1 2026-10-05 11:10:57.409 UTC [828] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-plv8> server # [ 17.109557] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 17.110382] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 17.111230] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: CREATE ROLE vm-test-run-plv8> server # [ 17.111796] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT ROLE vm-test-run-plv8> server # [ 17.112687] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT ROLE vm-test-run-plv8> server # [ 17.113411] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT ROLE vm-test-run-plv8> server # [ 17.113711] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT ROLE vm-test-run-plv8> server # [ 17.114151] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT vm-test-run-plv8> server # [ 17.114754] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 17.115808] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 17.116756] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 17.117565] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: GRANT vm-test-run-plv8> server # [ 17.118086] postgres[828]: ::1 2026-10-05 11:10:57.409 UTC [828] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-plv8> server # [ 17.119220] postgres[828]: ::1 2026-10-05 11:10:57.410 UTC [828] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-plv8> server # [ 17.120532] postgres[828]: ::1 2026-10-05 11:10:57.411 UTC [828] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.121566] postgres[828]: ::1 2026-10-05 11:10:57.411 UTC [828] 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 # [ 17.122581] postgres[828]: ::1 2026-10-05 11:10:57.412 UTC [828] 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 # [ 17.124650] postgres[828]: ::1 2026-10-05 11:10:57.413 UTC [828] 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 # [ 17.125510] postgres[828]: ::1 2026-10-05 11:10:57.414 UTC [828] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.126357] postgres[828]: ::1 2026-10-05 11:10:57.414 UTC [828] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-plv8> server # [ 17.128206] postgres[828]: ::1 2026-10-05 11:10:57.415 UTC [828] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 17.128996] postgres[828]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.129819] postgres[828]: ::1 2026-10-05 11:10:57.415 UTC [828] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 17.131193] postgres[828]: on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.132382] postgres[828]: ::1 2026-10-05 11:10:57.416 UTC [828] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-plv8> server # [ 17.133131] postgres[828]: on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.133905] postgres[828]: ::1 2026-10-05 11:10:57.417 UTC [828] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-plv8> server # [ 17.135096] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER ROLE vm-test-run-plv8> server # [ 17.136140] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 17.136847] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 17.137448] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 17.138048] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER ROLE vm-test-run-plv8> server # [ 17.139016] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[827]: ALTER ROLE vm-test-run-plv8> server # [ 17.140096] postgres[828]: ::1 2026-10-05 11:10:57.417 UTC [828] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-plv8> server # [ 17.141367] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-plv8> server # [ 17.170181] postgres[835]: ::1 2026-10-05 11:10:57.475 UTC [835] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-plv8> server # [ 17.172414] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE SCHEMA vm-test-run-plv8> server # [ 17.172692] postgres[835]: ::1 2026-10-05 11:10:57.477 UTC [835] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-plv8> server # [ 17.173083] postgres[835]: instance_id uuid NULL, vm-test-run-plv8> server # [ 17.173576] postgres[835]: id uuid NOT NULL UNIQUE, vm-test-run-plv8> server # [ 17.173958] postgres[835]: aud varchar(255) NULL, vm-test-run-plv8> server # [ 17.174242] postgres[835]: "role" varchar(255) NULL, vm-test-run-plv8> server # [ 17.174442] postgres[835]: email varchar(255) NULL UNIQUE, vm-test-run-plv8> server # [ 17.174671] postgres[835]: encrypted_password varchar(255) NULL, vm-test-run-plv8> server # [ 17.175084] postgres[835]: confirmed_at timestamptz NULL, vm-test-run-plv8> server # [ 17.175301] postgres[835]: invited_at timestamptz NULL, vm-test-run-plv8> server # [ 17.175484] postgres[835]: confirmation_token varchar(255) NULL, vm-test-run-plv8> server # [ 17.175704] postgres[835]: confirmation_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 17.176148] postgres[835]: recovery_token varchar(255) NULL, vm-test-run-plv8> server # [ 17.176937] postgres[835]: recovery_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 17.177640] postgres[835]: email_change_token varchar(255) NULL, vm-test-run-plv8> server # [ 17.178144] postgres[835]: email_change varchar(255) NULL, vm-test-run-plv8> server # [ 17.178679] postgres[835]: email_change_sent_at timestamptz NULL, vm-test-run-plv8> server # [ 17.179127] postgres[835]: last_sign_in_at timestamptz NULL, vm-test-run-plv8> server # [ 17.179526] postgres[835]: raw_app_meta_data jsonb NULL, vm-test-run-plv8> server # [ 17.180425] postgres[835]: raw_user_meta_data jsonb NULL, vm-test-run-plv8> server # [ 17.180879] postgres[835]: is_super_admin bool NULL, vm-test-run-plv8> server # [ 17.181385] postgres[835]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 17.181802] postgres[835]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 17.182251] postgres[835]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 17.182659] postgres[835]: ); vm-test-run-plv8> server # [ 17.185171] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE TABLE vm-test-run-plv8> server # [ 17.185616] postgres[835]: ::1 2026-10-05 11:10:57.490 UTC [835] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-plv8> server # [ 17.189064] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE INDEX vm-test-run-plv8> server # [ 17.189538] postgres[835]: ::1 2026-10-05 11:10:57.494 UTC [835] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-plv8> server # [ 17.192523] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE INDEX vm-test-run-plv8> server # [ 17.193245] postgres[835]: ::1 2026-10-05 11:10:57.498 UTC [835] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-plv8> server # [ 17.195480] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: COMMENT vm-test-run-plv8> server # [ 17.196393] postgres[835]: ::1 2026-10-05 11:10:57.499 UTC [835] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-plv8> server # [ 17.197872] postgres[835]: instance_id uuid NULL, vm-test-run-plv8> server # [ 17.198178] postgres[835]: id bigserial NOT NULL, vm-test-run-plv8> server # [ 17.198359] postgres[835]: "token" varchar(255) NULL, vm-test-run-plv8> server # [ 17.199460] postgres[835]: user_id varchar(255) NULL, vm-test-run-plv8> server # [ 17.199698] postgres[835]: revoked bool NULL, vm-test-run-plv8> server # [ 17.199882] postgres[835]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 17.200600] postgres[835]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 17.201098] postgres[835]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 17.201928] postgres[835]: ); vm-test-run-plv8> server # [ 17.202432] postgres[835]: ::1 2026-10-05 11:10:57.505 UTC [835] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-plv8> server # [ 17.203924] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE TABLE vm-test-run-plv8> server # [ 17.205189] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE INDEX vm-test-run-plv8> server # [ 17.205993] postgres[835]: ::1 2026-10-05 11:10:57.508 UTC [835] 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 # [ 17.207986] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE INDEX vm-test-run-plv8> server # [ 17.208743] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE INDEX vm-test-run-plv8> server # [ 17.209025] postgres[835]: ::1 2026-10-05 11:10:57.511 UTC [835] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-plv8> server # [ 17.210619] postgres[835]: ::1 2026-10-05 11:10:57.513 UTC [835] 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 # [ 17.212293] postgres[835]: ::1 2026-10-05 11:10:57.514 UTC [835] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-plv8> server # [ 17.213559] postgres[835]: id uuid NOT NULL, vm-test-run-plv8> server # [ 17.213868] postgres[835]: uuid uuid NULL, vm-test-run-plv8> server # [ 17.214469] postgres[835]: raw_base_config text NULL, vm-test-run-plv8> server # [ 17.214806] postgres[835]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 17.215620] postgres[835]: updated_at timestamptz NULL, vm-test-run-plv8> server # [ 17.216462] postgres[835]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 17.216858] postgres[835]: ); vm-test-run-plv8> server # [ 17.217097] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: COMMENT vm-test-run-plv8> server # [ 17.217836] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE TABLE vm-test-run-plv8> server # [ 17.218696] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: COMMENT vm-test-run-plv8> server # [ 17.219158] postgres[835]: ::1 2026-10-05 11:10:57.518 UTC [835] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-plv8> server # [ 17.221133] postgres[835]: ::1 2026-10-05 11:10:57.519 UTC [835] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-plv8> server # [ 17.222462] postgres[835]: instance_id uuid NULL, vm-test-run-plv8> server # [ 17.223141] postgres[835]: id uuid NOT NULL, vm-test-run-plv8> server # [ 17.223910] postgres[835]: payload json NULL, vm-test-run-plv8> server # [ 17.224674] postgres[835]: created_at timestamptz NULL, vm-test-run-plv8> server # [ 17.225308] postgres[835]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-plv8> server # [ 17.225560] postgres[835]: ); vm-test-run-plv8> server # [ 17.225652] postgres[835]: ::1 2026-10-05 11:10:57.524 UTC [835] 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 # [ 17.226886] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE TABLE vm-test-run-plv8> server # [ 17.227686] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE INDEX vm-test-run-plv8> server # [ 17.228737] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: COMMENT vm-test-run-plv8> server # [ 17.229436] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE TABLE vm-test-run-plv8> server # [ 17.230040] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: COMMENT vm-test-run-plv8> server # [ 17.230629] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: INSERT 0 7 vm-test-run-plv8> server # [ 17.231151] postgres[835]: ::1 2026-10-05 11:10:57.526 UTC [835] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-plv8> server # [ 17.232942] postgres[835]: ::1 2026-10-05 11:10:57.527 UTC [835] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-plv8> server # [ 17.233543] postgres[835]: "version" varchar(255) NOT NULL, vm-test-run-plv8> server # [ 17.234239] postgres[835]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-plv8> server # [ 17.234938] postgres[835]: ); vm-test-run-plv8> server # [ 17.235168] postgres[835]: ::1 2026-10-05 11:10:57.529 UTC [835] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-plv8> server # [ 17.236855] postgres[835]: ::1 2026-10-05 11:10:57.531 UTC [835] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-plv8> server # [ 17.238100] postgres[835]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-plv8> server # [ 17.238506] postgres[835]: $$ language sql stable; vm-test-run-plv8> server # [ 17.238967] postgres[835]: ::1 2026-10-05 11:10:57.532 UTC [835] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-plv8> server # [ 17.240253] postgres[835]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-plv8> server # [ 17.241298] postgres[835]: $$ language sql stable; vm-test-run-plv8> server # [ 17.241907] postgres[835]: ::1 2026-10-05 11:10:57.533 UTC [835] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-plv8> server # [ 17.242778] postgres[835]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-plv8> server # [ 17.244186] postgres[835]: $$ language sql stable; vm-test-run-plv8> server # [ 17.245017] postgres[835]: ::1 2026-10-05 11:10:57.534 UTC [835] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.245892] postgres[835]: ::1 2026-10-05 11:10:57.534 UTC [835] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 17.246640] postgres[835]: ::1 2026-10-05 11:10:57.535 UTC [835] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.248192] postgres[835]: ::1 2026-10-05 11:10:57.536 UTC [835] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.249315] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE FUNCTION vm-test-run-plv8> server # [ 17.250029] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE FUNCTION vm-test-run-plv8> server # [ 17.250977] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE FUNCTION vm-test-run-plv8> server # [ 17.251837] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: GRANT vm-test-run-plv8> server # [ 17.252652] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: CREATE ROLE vm-test-run-plv8> server # [ 17.253316] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: GRANT vm-test-run-plv8> server # [ 17.253830] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: GRANT vm-test-run-plv8> server # [ 17.254509] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: GRANT vm-test-run-plv8> server # [ 17.255120] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: ALTER ROLE vm-test-run-plv8> server # [ 17.256256] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: ALTER TABLE vm-test-run-plv8> server # [ 17.256875] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: ALTER TABLE vm-test-run-plv8> server # [ 17.257497] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: ALTER TABLE vm-test-run-plv8> server # [ 17.258171] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: ALTER TABLE vm-test-run-plv8> server # [ 17.259139] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[834]: ALTER TABLE vm-test-run-plv8> server # [ 17.260134] postgres[835]: ::1 2026-10-05 11:10:57.536 UTC [835] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.261358] postgres[835]: ::1 2026-10-05 11:10:57.537 UTC [835] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-plv8> server # [ 17.262181] postgres[835]: ::1 2026-10-05 11:10:57.538 UTC [835] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.263809] postgres[835]: ::1 2026-10-05 11:10:57.539 UTC [835] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.264818] postgres[835]: ::1 2026-10-05 11:10:57.540 UTC [835] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.265968] postgres[835]: ::1 2026-10-05 11:10:57.540 UTC [835] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.267978] postgres[835]: ::1 2026-10-05 11:10:57.541 UTC [835] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 17.269279] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-plv8> server # [ 17.293428] postgres[837]: ::1 2026-10-05 11:10:57.598 UTC [837] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-plv8> server # [ 17.294828] postgres[837]: ::1 2026-10-05 11:10:57.599 UTC [837] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 17.296838] postgres[837]: ::1 2026-10-05 11:10:57.599 UTC [837] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-plv8> server # [ 17.298335] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[836]: CREATE SCHEMA vm-test-run-plv8> server # [ 17.298798] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[836]: CREATE ROLE vm-test-run-plv8> server # [ 17.299341] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[836]: ALTER ROLE vm-test-run-plv8> server # [ 17.300269] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[836]: GRANT vm-test-run-plv8> server # [ 17.300839] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[836]: DO vm-test-run-plv8> server # [ 17.301439] postgres[837]: ::1 2026-10-05 11:10:57.600 UTC [837] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-plv8> server # [ 17.304695] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-plv8> server # [ 17.333434] postgres[839]: ::1 2026-10-05 11:10:57.638 UTC [839] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-plv8> server # [ 17.335406] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: ALTER ROLE vm-test-run-plv8> server # [ 17.336427] postgres[839]: ::1 2026-10-05 11:10:57.641 UTC [839] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-plv8> server # [ 17.337561] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: ALTER ROLE vm-test-run-plv8> server # [ 17.338006] postgres[839]: ::1 2026-10-05 11:10:57.642 UTC [839] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-plv8> server # [ 17.338988] postgres[839]: RETURNS event_trigger vm-test-run-plv8> server # [ 17.339639] postgres[839]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 17.339909] postgres[839]: AS $$ vm-test-run-plv8> server # [ 17.340572] postgres[839]: DECLARE vm-test-run-plv8> server # [ 17.340901] postgres[839]: schema_is_cron bool; vm-test-run-plv8> server # [ 17.341459] postgres[839]: BEGIN vm-test-run-plv8> server # [ 17.341827] postgres[839]: schema_is_cron = ( vm-test-run-plv8> server # [ 17.342375] postgres[839]: SELECT n.nspname = 'cron' vm-test-run-plv8> server # [ 17.343094] postgres[839]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 17.343993] postgres[839]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-plv8> server # [ 17.344530] postgres[839]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 17.344695] postgres[839]: ); vm-test-run-plv8> server # [ 17.345138] postgres[839]: vm-test-run-plv8> server # [ 17.345518] postgres[839]: IF schema_is_cron vm-test-run-plv8> server # [ 17.345888] postgres[839]: THEN vm-test-run-plv8> server # [ 17.346361] postgres[839]: grant usage on schema cron to postgres with grant option; vm-test-run-plv8> server # [ 17.347117] postgres[839]: vm-test-run-plv8> server # [ 17.347339] postgres[839]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 17.348459] postgres[839]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-plv8> server # [ 17.349567] postgres[839]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 17.350608] postgres[839]: vm-test-run-plv8> server # [ 17.350874] postgres[839]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 17.352155] postgres[839]: on sequences to postgres with grant option; vm-test-run-plv8> server # [ 17.352839] postgres[839]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 17.353415] postgres[839]: on tables to postgres with grant option; vm-test-run-plv8> server # [ 17.354031] postgres[839]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 17.354457] postgres[839]: on functions to postgres with grant option; vm-test-run-plv8> server # [ 17.354702] postgres[839]: vm-test-run-plv8> server # [ 17.354796] postgres[839]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-plv8> server # [ 17.355132] postgres[839]: vm-test-run-plv8> server # [ 17.355231] postgres[839]: END IF; vm-test-run-plv8> server # [ 17.355890] postgres[839]: vm-test-run-plv8> server # [ 17.356214] postgres[839]: END; vm-test-run-plv8> server # [ 17.356443] postgres[839]: $$; vm-test-run-plv8> server # [ 17.356728] postgres[839]: ::1 2026-10-05 11:10:57.644 UTC [839] 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 # [ 17.358093] postgres[839]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-plv8> server # [ 17.358421] postgres[839]: ::1 2026-10-05 11:10:57.645 UTC [839] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-plv8> server # [ 17.360592] postgres[839]: ::1 2026-10-05 11:10:57.646 UTC [839] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-plv8> server # [ 17.362467] postgres[839]: RETURNS event_trigger vm-test-run-plv8> server # [ 17.363245] postgres[839]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 17.363796] postgres[839]: AS $$ vm-test-run-plv8> server # [ 17.364580] postgres[839]: BEGIN vm-test-run-plv8> server # [ 17.364853] postgres[839]: IF EXISTS ( vm-test-run-plv8> server # [ 17.365267] postgres[839]: SELECT 1 vm-test-run-plv8> server # [ 17.365881] postgres[839]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 17.366910] postgres[839]: JOIN pg_extension AS ext vm-test-run-plv8> server # [ 17.367448] postgres[839]: ON ev.objid = ext.oid vm-test-run-plv8> server # [ 17.368421] postgres[839]: WHERE ext.extname = 'pg_net' vm-test-run-plv8> server # [ 17.369157] postgres[839]: ) vm-test-run-plv8> server # [ 17.369495] postgres[839]: THEN vm-test-run-plv8> server # [ 17.369973] postgres[839]: IF NOT EXISTS ( vm-test-run-plv8> server # [ 17.370901] postgres[839]: SELECT 1 vm-test-run-plv8> server # [ 17.371732] postgres[839]: FROM pg_roles vm-test-run-plv8> server # [ 17.372739] postgres[839]: WHERE rolname = 'supabase_functions_admin' vm-test-run-plv8> server # [ 17.373604] postgres[839]: ) vm-test-run-plv8> server # [ 17.374037] postgres[839]: THEN vm-test-run-plv8> server # [ 17.374741] postgres[839]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-plv8> server # [ 17.376122] postgres[839]: END IF; vm-test-run-plv8> server # [ 17.376694] postgres[839]: vm-test-run-plv8> server # [ 17.377129] postgres[839]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 17.378192] postgres[839]: vm-test-run-plv8> server # [ 17.378381] postgres[839]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-plv8> server # [ 17.380279] postgres[839]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-plv8> server # [ 17.381776] postgres[839]: vm-test-run-plv8> server # [ 17.382248] postgres[839]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-plv8> server # [ 17.383360] postgres[839]: 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 # [ 17.384802] postgres[839]: vm-test-run-plv8> server # [ 17.385159] postgres[839]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-plv8> server # [ 17.386623] postgres[839]: 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 # [ 17.389368] postgres[839]: vm-test-run-plv8> server # [ 17.389675] postgres[839]: 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 # [ 17.390650] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: CREATE FUNCTION vm-test-run-plv8> server # [ 17.391478] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 17.391943] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: COMMENT vm-test-run-plv8> server # [ 17.393259] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: CREATE FUNCTION vm-test-run-plv8> server # [ 17.393747] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: COMMENT vm-test-run-plv8> server # [ 17.394064] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: DO vm-test-run-plv8> server # [ 17.394292] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: CREATE ROLE vm-test-run-plv8> server # [ 17.394952] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.395971] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.397289] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.397982] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.398287] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.398991] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.399573] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.400364] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.400777] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: GRANT vm-test-run-plv8> server # [ 17.401340] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[838]: DO vm-test-run-plv8> server # [ 17.401786] postgres[839]: 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 # [ 17.403902] postgres[839]: END IF; vm-test-run-plv8> server # [ 17.404395] postgres[839]: END; vm-test-run-plv8> server # [ 17.404601] postgres[839]: $$; vm-test-run-plv8> server # [ 17.404993] postgres[839]: ::1 2026-10-05 11:10:57.648 UTC [839] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-plv8> server # [ 17.406311] postgres[839]: ::1 2026-10-05 11:10:57.650 UTC [839] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-plv8> server # [ 17.407093] postgres[839]: ::1 2026-10-05 11:10:57.651 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-plv8> server # [ 17.407491] postgres[839]: ::1 2026-10-05 11:10:57.652 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 17.407832] postgres[839]: ::1 2026-10-05 11:10:57.653 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 17.408541] postgres[839]: ::1 2026-10-05 11:10:57.654 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 17.410979] postgres[839]: ::1 2026-10-05 11:10:57.655 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 17.412927] postgres[839]: ::1 2026-10-05 11:10:57.657 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 17.414717] postgres[839]: ::1 2026-10-05 11:10:57.658 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 17.417136] postgres[839]: ::1 2026-10-05 11:10:57.658 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-plv8> server # [ 17.418435] postgres[839]: ::1 2026-10-05 11:10:57.659 UTC [839] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-plv8> server # [ 17.420782] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running pgbouncer auth schema... vm-test-run-plv8> server # [ 17.421856] postgres[841]: ::1 2026-10-05 11:10:57.703 UTC [841] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-plv8> server # [ 17.423097] postgres[841]: ::1 2026-10-05 11:10:57.704 UTC [841] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-plv8> server # [ 17.425134] postgres[841]: ::1 2026-10-05 11:10:57.705 UTC [841] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-plv8> server # [ 17.426495] postgres[841]: ::1 2026-10-05 11:10:57.708 UTC [841] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-plv8> server # [ 17.429123] postgres[841]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-plv8> server # [ 17.430065] postgres[841]: $$ vm-test-run-plv8> server # [ 17.430483] postgres[841]: BEGIN vm-test-run-plv8> server # [ 17.431076] postgres[841]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-plv8> server # [ 17.432266] postgres[841]: vm-test-run-plv8> server # [ 17.432703] postgres[841]: RETURN QUERY vm-test-run-plv8> server # [ 17.433119] postgres[841]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-plv8> server # [ 17.433606] postgres[841]: WHERE usename = p_usename; vm-test-run-plv8> server # [ 17.433947] postgres[841]: END; vm-test-run-plv8> server # [ 17.434153] postgres[841]: $$ LANGUAGE plpgsql vm-test-run-plv8> server # [ 17.434317] postgres[841]: SET search_path = '' vm-test-run-plv8> server # [ 17.434465] postgres[841]: SECURITY DEFINER; vm-test-run-plv8> server # [ 17.434744] postgres[841]: ::1 2026-10-05 11:10:57.709 UTC [841] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-plv8> server # [ 17.436826] postgres[841]: ::1 2026-10-05 11:10:57.710 UTC [841] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-plv8> server # [ 17.437797] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[840]: CREATE ROLE vm-test-run-plv8> server # [ 17.438218] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[840]: REVOKE vm-test-run-plv8> server # [ 17.438619] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[840]: CREATE SCHEMA vm-test-run-plv8> server # [ 17.439143] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[840]: CREATE FUNCTION vm-test-run-plv8> server # [ 17.439566] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[840]: REVOKE vm-test-run-plv8> server # [ 17.439788] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[840]: GRANT vm-test-run-plv8> server # [ 17.440319] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running stat extension... vm-test-run-plv8> server # [ 17.451275] postgres[843]: ::1 2026-10-05 11:10:57.756 UTC [843] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-plv8> server # [ 17.453036] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[842]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-plv8> server # [ 17.454227] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[842]: CREATE SCHEMA vm-test-run-plv8> server # [ 17.454485] postgres[843]: ::1 2026-10-05 11:10:57.758 UTC [843] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-plv8> server # [ 17.649510] dhcpcd[697]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-plv8> server # [ 17.650110] dhcpcd[697]: eth0: adding route to 10.0.2.0/24 vm-test-run-plv8> server # [ 17.651192] dhcpcd[697]: eth0: adding default route via 10.0.2.2 vm-test-run-plv8> server # [ 17.807267] systemd[1]: Started DHCP Client. vm-test-run-plv8> server # [ 17.903429] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[842]: CREATE EXTENSION vm-test-run-plv8> server # [ 17.912649] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-plv8> server # [ 17.945743] postgres[900]: ::1 2026-10-05 11:10:58.250 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-plv8> server # [ 17.946681] postgres[900]: ::1 2026-10-05 11:10:58.251 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 17.947878] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.948602] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.949338] postgres[900]: ::1 2026-10-05 11:10:58.252 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 17.950263] postgres[900]: ::1 2026-10-05 11:10:58.253 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 17.951316] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.952112] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.952600] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.953106] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.953776] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.954306] postgres[900]: ::1 2026-10-05 11:10:58.254 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 17.956043] postgres[900]: ::1 2026-10-05 11:10:58.255 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 17.956834] postgres[900]: ::1 2026-10-05 11:10:58.255 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 17.957805] postgres[900]: ::1 2026-10-05 11:10:58.256 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 17.959321] postgres[900]: ::1 2026-10-05 11:10:58.257 UTC [900] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-plv8> server # [ 17.960450] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.960689] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: GRANT vm-test-run-plv8> server # [ 17.961065] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: DO vm-test-run-plv8> server # [ 17.961312] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[899]: ALTER ROLE vm-test-run-plv8> server # [ 17.961619] postgres[900]: ::1 2026-10-05 11:10:58.263 UTC [900] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-plv8> server # [ 17.965459] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-plv8> server # [ 18.002347] postgres[902]: ::1 2026-10-05 11:10:58.307 UTC [902] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.003701] postgres[902]: ::1 2026-10-05 11:10:58.308 UTC [902] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.006001] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.006687] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.007538] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.008563] postgres[902]: ::1 2026-10-05 11:10:58.309 UTC [902] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.010056] postgres[902]: ::1 2026-10-05 11:10:58.310 UTC [902] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.011658] postgres[902]: ::1 2026-10-05 11:10:58.311 UTC [902] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.012754] postgres[902]: ::1 2026-10-05 11:10:58.311 UTC [902] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.014271] postgres[902]: ::1 2026-10-05 11:10:58.312 UTC [902] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.014984] postgres[902]: ::1 2026-10-05 11:10:58.313 UTC [902] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-plv8> server # [ 18.015918] postgres[902]: ::1 2026-10-05 11:10:58.313 UTC [902] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-plv8> server # [ 18.019311] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER TABLE vm-test-run-plv8> server # [ 18.020872] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER TABLE vm-test-run-plv8> server # [ 18.022401] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER TABLE vm-test-run-plv8> server # [ 18.024162] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER TABLE vm-test-run-plv8> server # [ 18.025210] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER TABLE vm-test-run-plv8> server # [ 18.026161] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.027139] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.028863] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.029652] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: GRANT vm-test-run-plv8> server # [ 18.030947] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.032495] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.033929] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[901]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.035701] postgres[902]: ::1 2026-10-05 11:10:58.314 UTC [902] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 18.038017] postgres[902]: ::1 2026-10-05 11:10:58.315 UTC [902] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 18.038944] postgres[902]: ::1 2026-10-05 11:10:58.315 UTC [902] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-plv8> server # [ 18.042354] postgres[902]: ::1 2026-10-05 11:10:58.316 UTC [902] 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 # [ 18.045541] postgres[902]: ::1 2026-10-05 11:10:58.318 UTC [902] 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 # [ 18.046708] postgres[902]: ::1 2026-10-05 11:10:58.318 UTC [902] 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 # [ 18.049059] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-plv8> server # [ 18.060855] postgres[908]: ::1 2026-10-05 11:10:58.365 UTC [908] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-plv8> server # [ 18.063364] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[903]: CREATE SCHEMA vm-test-run-plv8> server # [ 18.067581] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-plv8> server # [ 18.104638] postgres[910]: ::1 2026-10-05 11:10:58.409 UTC [910] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-plv8> server # [ 18.105619] postgres[910]: ::1 2026-10-05 11:10:58.410 UTC [910] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 18.106825] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[909]: GRANT vm-test-run-plv8> server # [ 18.107230] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[909]: GRANT vm-test-run-plv8> server # [ 18.107598] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[909]: GRANT vm-test-run-plv8> server # [ 18.109038] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[909]: GRANT vm-test-run-plv8> server # [ 18.110437] postgres[910]: ::1 2026-10-05 11:10:58.411 UTC [910] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 18.112244] postgres[910]: ::1 2026-10-05 11:10:58.411 UTC [910] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-plv8> server # [ 18.114063] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-plv8> server # [ 18.144759] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[911]: DO vm-test-run-plv8> server # [ 18.146299] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[911]: DO vm-test-run-plv8> server # [ 18.147584] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[911]: DO vm-test-run-plv8> server # [ 18.152539] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-plv8> server # [ 18.190259] postgres[914]: ::1 2026-10-05 11:10:58.495 UTC [914] 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 # [ 18.191798] postgres[914]: ::1 2026-10-05 11:10:58.496 UTC [914] 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 # [ 18.194030] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[913]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.194843] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[913]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.195720] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[913]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.196723] postgres[914]: ::1 2026-10-05 11:10:58.497 UTC [914] 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 # [ 18.201097] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-plv8> server # [ 18.235859] postgres[916]: ::1 2026-10-05 11:10:58.540 UTC [916] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-plv8> server # [ 18.237691] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[915]: ALTER ROLE vm-test-run-plv8> server # [ 18.243870] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-plv8> server # [ 18.279378] postgres[919]: ::1 2026-10-05 11:10:58.584 UTC [919] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-plv8> server # [ 18.280458] postgres[919]: ::1 2026-10-05 11:10:58.584 UTC [919] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-plv8> server # [ 18.281385] postgres[919]: ::1 2026-10-05 11:10:58.584 UTC [919] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 18.282371] postgres[919]: DECLARE vm-test-run-plv8> server # [ 18.282865] postgres[919]: cmd record; vm-test-run-plv8> server # [ 18.283359] postgres[919]: BEGIN vm-test-run-plv8> server # [ 18.283721] postgres[919]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-plv8> server # [ 18.284289] postgres[919]: LOOP vm-test-run-plv8> server # [ 18.284391] postgres[919]: IF cmd.command_tag IN ( vm-test-run-plv8> server # [ 18.284537] postgres[919]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-plv8> server # [ 18.284689] postgres[919]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-plv8> server # [ 18.284966] postgres[919]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-plv8> server # [ 18.285610] postgres[919]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-plv8> server # [ 18.286175] postgres[919]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-plv8> server # [ 18.287452] postgres[919]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-plv8> server # [ 18.288442] postgres[919]: , 'CREATE TRIGGER' vm-test-run-plv8> server # [ 18.288931] postgres[919]: , 'CREATE TYPE' vm-test-run-plv8> server # [ 18.289415] postgres[919]: , 'CREATE RULE' vm-test-run-plv8> server # [ 18.289859] postgres[919]: , 'COMMENT' vm-test-run-plv8> server # [ 18.290135] postgres[919]: ) vm-test-run-plv8> server # [ 18.290763] postgres[919]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-plv8> server # [ 18.291937] postgres[919]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-plv8> server # [ 18.292752] postgres[919]: THEN vm-test-run-plv8> server # [ 18.292925] postgres[919]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 18.293118] postgres[919]: END IF; vm-test-run-plv8> server # [ 18.293281] postgres[919]: END LOOP; vm-test-run-plv8> server # [ 18.293405] postgres[919]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 18.293955] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-plv8> server # [ 18.294801] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.295028] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-plv8> server # [ 18.295923] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: DROP FUNCTION vm-test-run-plv8> server # [ 18.296602] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.297056] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.297704] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-plv8> server # [ 18.298819] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.299713] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 18.300633] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-plv8> server # [ 18.301589] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.302213] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[917]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 18.303296] postgres[919]: ::1 2026-10-05 11:10:58.586 UTC [919] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 18.304665] postgres[919]: DECLARE vm-test-run-plv8> server # [ 18.304885] postgres[919]: obj record; vm-test-run-plv8> server # [ 18.305372] postgres[919]: BEGIN vm-test-run-plv8> server # [ 18.305813] postgres[919]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-plv8> server # [ 18.306652] postgres[919]: LOOP vm-test-run-plv8> server # [ 18.307320] postgres[919]: IF obj.object_type IN ( vm-test-run-plv8> server # [ 18.307983] postgres[919]: 'schema' vm-test-run-plv8> server # [ 18.308720] postgres[919]: , 'table' vm-test-run-plv8> server # [ 18.309228] postgres[919]: , 'foreign table' vm-test-run-plv8> server # [ 18.309659] postgres[919]: , 'view' vm-test-run-plv8> server # [ 18.310003] postgres[919]: , 'materialized view' vm-test-run-plv8> server # [ 18.310905] postgres[919]: , 'function' vm-test-run-plv8> server # [ 18.311463] postgres[919]: , 'trigger' vm-test-run-plv8> server # [ 18.312384] postgres[919]: , 'type' vm-test-run-plv8> server # [ 18.313064] postgres[919]: , 'rule' vm-test-run-plv8> server # [ 18.313440] postgres[919]: ) vm-test-run-plv8> server # [ 18.313836] postgres[919]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-plv8> server # [ 18.314375] postgres[919]: THEN vm-test-run-plv8> server # [ 18.314558] postgres[919]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 18.314919] postgres[919]: END IF; vm-test-run-plv8> server # [ 18.315559] postgres[919]: END LOOP; vm-test-run-plv8> server # [ 18.316083] postgres[919]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 18.316815] postgres[919]: ::1 2026-10-05 11:10:58.587 UTC [919] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-plv8> server # [ 18.317773] postgres[919]: ::1 2026-10-05 11:10:58.587 UTC [919] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-plv8> server # [ 18.318609] postgres[919]: ON ddl_command_end vm-test-run-plv8> server # [ 18.319769] postgres[919]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-plv8> server # [ 18.320412] postgres[919]: ::1 2026-10-05 11:10:58.588 UTC [919] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-plv8> server # [ 18.321089] postgres[919]: ::1 2026-10-05 11:10:58.588 UTC [919] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-plv8> server # [ 18.322031] postgres[919]: ON sql_drop vm-test-run-plv8> server # [ 18.322725] postgres[919]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-plv8> server # [ 18.323716] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-plv8> server # [ 18.585965] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[920]: DO vm-test-run-plv8> server # [ 18.590465] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-plv8> server # [ 18.620526] postgres[923]: ::1 2026-10-05 11:10:58.925 UTC [923] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-plv8> server # [ 18.624373] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: CREATE SCHEMA vm-test-run-plv8> server # [ 18.624806] postgres[923]: ::1 2026-10-05 11:10:58.929 UTC [923] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-plv8> server # [ 18.625960] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-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 # [ 18.629377] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: DROP FUNCTION vm-test-run-plv8> server # [ 18.630507] postgres[923]: ::1 2026-10-05 11:10:58.930 UTC [923] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 18.631949] postgres[923]: "operationName" text default null, vm-test-run-plv8> server # [ 18.633905] postgres[923]: query text default null, vm-test-run-plv8> server # [ 18.634882] postgres[923]: variables jsonb default null, vm-test-run-plv8> server # [ 18.635390] postgres[923]: extensions jsonb default null vm-test-run-plv8> server # [ 18.637075] postgres[923]: ) vm-test-run-plv8> server # [ 18.637894] postgres[923]: returns jsonb vm-test-run-plv8> server # [ 18.639014] postgres[923]: language plpgsql vm-test-run-plv8> server # [ 18.639997] postgres[923]: as $$ vm-test-run-plv8> server # [ 18.641231] postgres[923]: DECLARE vm-test-run-plv8> server # [ 18.641959] postgres[923]: server_version float; vm-test-run-plv8> server # [ 18.642190] postgres[923]: BEGIN vm-test-run-plv8> server # [ 18.642397] postgres[923]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 18.644880] postgres[923]: vm-test-run-plv8> server # [ 18.645226] postgres[923]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 18.645398] postgres[923]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.645566] postgres[923]: 'data', null::jsonb, vm-test-run-plv8> server # [ 18.645710] postgres[923]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-plv8> server # [ 18.645933] postgres[923]: ); vm-test-run-plv8> server # [ 18.646042] postgres[923]: ELSE vm-test-run-plv8> server # [ 18.646150] postgres[923]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.646330] postgres[923]: 'data', null::jsonb, vm-test-run-plv8> server # [ 18.646489] postgres[923]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-plv8> server # [ 18.646793] postgres[923]: ); vm-test-run-plv8> server # [ 18.648269] postgres[923]: END IF; vm-test-run-plv8> server # [ 18.649680] postgres[923]: END; vm-test-run-plv8> server # [ 18.650816] postgres[923]: $$; vm-test-run-plv8> server # [ 18.651436] postgres[923]: ::1 2026-10-05 11:10:58.933 UTC [923] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.654186] postgres[923]: ::1 2026-10-05 11:10:58.935 UTC [923] 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 # [ 18.656533] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.657055] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: GRANT vm-test-run-plv8> server # [ 18.658154] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.658838] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.660739] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.662035] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.663709] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.664580] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 18.665113] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.665712] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-plv8> server # [ 18.667436] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.668581] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 18.669376] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: COMMENT vm-test-run-plv8> server # [ 18.670101] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.671228] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-plv8> server # [ 18.674889] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.676181] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 18.676825] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[922]: COMMENT vm-test-run-plv8> server # [ 18.677685] postgres[923]: ::1 2026-10-05 11:10:58.937 UTC [923] 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 # [ 18.680994] postgres[923]: ::1 2026-10-05 11:10:58.938 UTC [923] 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 # [ 18.682956] postgres[923]: ::1 2026-10-05 11:10:58.940 UTC [923] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 18.685152] postgres[923]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.686039] postgres[923]: ::1 2026-10-05 11:10:58.942 UTC [923] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 18.686943] postgres[923]: on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.687350] postgres[923]: ::1 2026-10-05 11:10:58.944 UTC [923] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-plv8> server # [ 18.689519] postgres[923]: on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.690725] postgres[923]: ::1 2026-10-05 11:10:58.945 UTC [923] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 18.692481] postgres[923]: RETURNS event_trigger vm-test-run-plv8> server # [ 18.692961] postgres[923]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 18.693385] postgres[923]: AS $func$ vm-test-run-plv8> server # [ 18.693822] postgres[923]: DECLARE vm-test-run-plv8> server # [ 18.694517] postgres[923]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 18.694931] postgres[923]: BEGIN vm-test-run-plv8> server # [ 18.695161] postgres[923]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 18.695493] postgres[923]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 18.695869] postgres[923]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 18.696632] postgres[923]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 18.697091] postgres[923]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 18.697549] postgres[923]: ); vm-test-run-plv8> server # [ 18.697843] postgres[923]: vm-test-run-plv8> server # [ 18.698050] postgres[923]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 18.698275] postgres[923]: THEN vm-test-run-plv8> server # [ 18.698494] postgres[923]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.699452] postgres[923]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.700774] postgres[923]: vm-test-run-plv8> server # [ 18.701226] postgres[923]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.701879] postgres[923]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.702378] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-plv8> server # [ 18.703734] postgres[923]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.704637] postgres[923]: vm-test-run-plv8> server # [ 18.704732] postgres[923]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 18.705284] postgres[923]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 18.705830] postgres[923]: "operationName" text default null, vm-test-run-plv8> server # [ 18.706182] postgres[923]: query text default null, vm-test-run-plv8> server # [ 18.706695] postgres[923]: variables jsonb default null, vm-test-run-plv8> server # [ 18.707149] postgres[923]: extensions jsonb default null vm-test-run-plv8> server # [ 18.707718] postgres[923]: ) vm-test-run-plv8> server # [ 18.707827] postgres[923]: returns jsonb vm-test-run-plv8> server # [ 18.708268] postgres[923]: language sql vm-test-run-plv8> server # [ 18.708677] postgres[923]: as $$ vm-test-run-plv8> server # [ 18.709044] postgres[923]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-plv8> server # [ 18.709313] postgres[923]: $$; vm-test-run-plv8> server # [ 18.709853] postgres[923]: vm-test-run-plv8> server # [ 18.710177] postgres[923]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.710787] postgres[923]: END IF; vm-test-run-plv8> server # [ 18.711009] postgres[923]: vm-test-run-plv8> server # [ 18.711232] postgres[923]: END; vm-test-run-plv8> server # [ 18.711413] postgres[923]: $func$; vm-test-run-plv8> server # [ 18.711576] postgres[923]: ::1 2026-10-05 11:10:58.946 UTC [923] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-plv8> server # [ 18.713859] postgres[923]: ::1 2026-10-05 11:10:58.946 UTC [923] 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 # [ 18.716284] postgres[923]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-plv8> server # [ 18.717119] postgres[923]: ::1 2026-10-05 11:10:58.947 UTC [923] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql'; vm-test-run-plv8> server # [ 18.718482] postgres[923]: ::1 2026-10-05 11:10:58.950 UTC [923] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-plv8> server # [ 18.719997] postgres[923]: RETURNS event_trigger vm-test-run-plv8> server # [ 18.720813] postgres[923]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 18.721314] postgres[923]: AS $func$ vm-test-run-plv8> server # [ 18.721813] postgres[923]: DECLARE vm-test-run-plv8> server # [ 18.722394] postgres[923]: graphql_is_dropped bool; vm-test-run-plv8> server # [ 18.723309] postgres[923]: BEGIN vm-test-run-plv8> server # [ 18.723928] postgres[923]: graphql_is_dropped = ( vm-test-run-plv8> server # [ 18.724757] postgres[923]: SELECT ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 18.725547] postgres[923]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-plv8> server # [ 18.726087] postgres[923]: WHERE ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 18.727045] postgres[923]: ); vm-test-run-plv8> server # [ 18.727458] postgres[923]: vm-test-run-plv8> server # [ 18.727698] postgres[923]: IF graphql_is_dropped vm-test-run-plv8> server # [ 18.728507] postgres[923]: THEN vm-test-run-plv8> server # [ 18.729162] postgres[923]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 18.730037] postgres[923]: "operationName" text default null, vm-test-run-plv8> server # [ 18.730909] postgres[923]: query text default null, vm-test-run-plv8> server # [ 18.731832] postgres[923]: variables jsonb default null, vm-test-run-plv8> server # [ 18.732670] postgres[923]: extensions jsonb default null vm-test-run-plv8> server # [ 18.733297] postgres[923]: ) vm-test-run-plv8> server # [ 18.733731] postgres[923]: returns jsonb vm-test-run-plv8> server # [ 18.734258] postgres[923]: language plpgsql vm-test-run-plv8> server # [ 18.735076] postgres[923]: as $$ vm-test-run-plv8> server # [ 18.735528] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-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 # [ 18.737826] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.738084] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-plv8> server # [ 18.739068] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: DROP FUNCTION vm-test-run-plv8> server # [ 18.739653] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.740664] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.741379] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.741883] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 18.742277] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 18.742814] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[924]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 18.743923] postgres[923]: DECLARE vm-test-run-plv8> server # [ 18.744278] postgres[923]: server_version float; vm-test-run-plv8> server # [ 18.744646] postgres[923]: BEGIN vm-test-run-plv8> server # [ 18.744877] postgres[923]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 18.745736] postgres[923]: vm-test-run-plv8> server # [ 18.746181] postgres[923]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 18.746814] postgres[923]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.747850] postgres[923]: 'data', null::jsonb, vm-test-run-plv8> server # [ 18.748278] postgres[923]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-plv8> server # [ 18.748972] postgres[923]: ); vm-test-run-plv8> server # [ 18.749221] postgres[923]: ELSE vm-test-run-plv8> server # [ 18.749512] postgres[923]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.749920] postgres[923]: 'data', null::jsonb, vm-test-run-plv8> server # [ 18.750130] postgres[923]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-plv8> server # [ 18.751215] postgres[923]: ); vm-test-run-plv8> server # [ 18.751463] postgres[923]: END IF; vm-test-run-plv8> server # [ 18.751691] postgres[923]: END; vm-test-run-plv8> server # [ 18.751997] postgres[923]: $$; vm-test-run-plv8> server # [ 18.752199] postgres[923]: END IF; vm-test-run-plv8> server # [ 18.752308] postgres[923]: vm-test-run-plv8> server # [ 18.752390] postgres[923]: END; vm-test-run-plv8> server # [ 18.753052] postgres[923]: $func$; vm-test-run-plv8> server # [ 18.753371] postgres[923]: ::1 2026-10-05 11:10:58.954 UTC [923] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-plv8> server # [ 18.755118] postgres[923]: ::1 2026-10-05 11:10:58.954 UTC [923] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION') vm-test-run-plv8> server # [ 18.755836] postgres[923]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-plv8> server # [ 18.756825] postgres[923]: ::1 2026-10-05 11:10:58.956 UTC [923] 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 # [ 18.758964] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-plv8> server # [ 18.760260] postgres[925]: ::1 2026-10-05 11:10:59.007 UTC [925] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-plv8> server # [ 18.761227] postgres[925]: ::1 2026-10-05 11:10:59.007 UTC [925] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-plv8> server # [ 18.761966] postgres[925]: ::1 2026-10-05 11:10:59.008 UTC [925] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 18.763964] postgres[925]: DECLARE vm-test-run-plv8> server # [ 18.764601] postgres[925]: cmd record; vm-test-run-plv8> server # [ 18.765173] postgres[925]: BEGIN vm-test-run-plv8> server # [ 18.765615] postgres[925]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-plv8> server # [ 18.766228] postgres[925]: LOOP vm-test-run-plv8> server # [ 18.766338] postgres[925]: IF cmd.command_tag IN ( vm-test-run-plv8> server # [ 18.766676] postgres[925]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-plv8> server # [ 18.767656] postgres[925]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-plv8> server # [ 18.768814] postgres[925]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-plv8> server # [ 18.769544] postgres[925]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-plv8> server # [ 18.770260] postgres[925]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-plv8> server # [ 18.771491] postgres[925]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-plv8> server # [ 18.772483] postgres[925]: , 'CREATE TRIGGER' vm-test-run-plv8> server # [ 18.773261] postgres[925]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-plv8> server # [ 18.773993] postgres[925]: , 'CREATE RULE' vm-test-run-plv8> server # [ 18.774558] postgres[925]: , 'COMMENT' vm-test-run-plv8> server # [ 18.775071] postgres[925]: ) vm-test-run-plv8> server # [ 18.775486] postgres[925]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-plv8> server # [ 18.776742] postgres[925]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-plv8> server # [ 18.777091] postgres[925]: THEN vm-test-run-plv8> server # [ 18.777707] postgres[925]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 18.778216] postgres[925]: END IF; vm-test-run-plv8> server # [ 18.778726] postgres[925]: END LOOP; vm-test-run-plv8> server # [ 18.779157] postgres[925]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 18.779846] postgres[925]: ::1 2026-10-05 11:10:59.010 UTC [925] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-plv8> server # [ 18.781898] postgres[925]: DECLARE vm-test-run-plv8> server # [ 18.782608] postgres[925]: obj record; vm-test-run-plv8> server # [ 18.783259] postgres[925]: BEGIN vm-test-run-plv8> server # [ 18.783744] postgres[925]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-plv8> server # [ 18.784644] postgres[925]: LOOP vm-test-run-plv8> server # [ 18.784946] postgres[925]: IF obj.object_type IN ( vm-test-run-plv8> server # [ 18.785719] postgres[925]: 'schema' vm-test-run-plv8> server # [ 18.785915] postgres[925]: , 'table' vm-test-run-plv8> server # [ 18.786536] postgres[925]: , 'foreign table' vm-test-run-plv8> server # [ 18.787012] postgres[925]: , 'view' vm-test-run-plv8> server # [ 18.787550] postgres[925]: , 'materialized view' vm-test-run-plv8> server # [ 18.787803] postgres[925]: , 'function' vm-test-run-plv8> server # [ 18.788127] postgres[925]: , 'trigger' vm-test-run-plv8> server # [ 18.788275] postgres[925]: , 'type' vm-test-run-plv8> server # [ 18.788467] postgres[925]: , 'rule' vm-test-run-plv8> server # [ 18.788758] postgres[925]: ) vm-test-run-plv8> server # [ 18.789008] postgres[925]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-plv8> server # [ 18.789356] postgres[925]: THEN vm-test-run-plv8> server # [ 18.789524] postgres[925]: NOTIFY pgrst, 'reload schema'; vm-test-run-plv8> server # [ 18.790158] postgres[925]: END IF; vm-test-run-plv8> server # [ 18.790718] postgres[925]: END LOOP; vm-test-run-plv8> server # [ 18.791325] postgres[925]: END; $$ LANGUAGE plpgsql; vm-test-run-plv8> server # [ 18.791958] postgres[925]: ::1 2026-10-05 11:10:59.012 UTC [925] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-plv8> server # [ 18.792884] postgres[925]: ::1 2026-10-05 11:10:59.013 UTC [925] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-plv8> server # [ 18.794022] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[926]: ALTER ROLE vm-test-run-plv8> server # [ 18.794581] postgres[925]: ON ddl_command_end vm-test-run-plv8> server # [ 18.795355] postgres[925]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-plv8> server # [ 18.795895] postgres[925]: ::1 2026-10-05 11:10:59.013 UTC [925] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-plv8> server # [ 18.797401] postgres[925]: ::1 2026-10-05 11:10:59.014 UTC [925] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-plv8> server # [ 18.798234] postgres[925]: ON sql_drop vm-test-run-plv8> server # [ 18.798665] postgres[925]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-plv8> server # [ 18.799462] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-plv8> server # [ 18.800683] postgres[927]: ::1 2026-10-05 11:10:59.064 UTC [927] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-plv8> server # [ 18.804761] postgres[929]: ::1 2026-10-05 11:10:59.109 UTC [929] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 18.805900] postgres[929]: returns event_trigger vm-test-run-plv8> server # [ 18.806136] postgres[929]: language plpgsql vm-test-run-plv8> server # [ 18.806427] postgres[929]: AS $func$ vm-test-run-plv8> server # [ 18.806525] postgres[929]: DECLARE vm-test-run-plv8> server # [ 18.806620] postgres[929]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 18.806795] postgres[929]: BEGIN vm-test-run-plv8> server # [ 18.806890] postgres[929]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 18.807549] postgres[929]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 18.807863] postgres[929]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 18.808501] postgres[929]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 18.808977] postgres[929]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 18.809328] postgres[929]: ); vm-test-run-plv8> server # [ 18.809540] postgres[929]: vm-test-run-plv8> server # [ 18.809647] postgres[929]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 18.809913] postgres[929]: THEN vm-test-run-plv8> server # [ 18.810348] postgres[929]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.811048] postgres[929]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.812181] postgres[929]: vm-test-run-plv8> server # [ 18.812627] postgres[929]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.813413] postgres[929]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.814170] postgres[929]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.814861] postgres[929]: vm-test-run-plv8> server # [ 18.815059] postgres[929]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 18.815544] postgres[929]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 18.815978] postgres[929]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 18.816516] postgres[929]: "operationName" text default null, vm-test-run-plv8> server # [ 18.816940] postgres[929]: query text default null, vm-test-run-plv8> server # [ 18.817257] postgres[929]: variables jsonb default null, vm-test-run-plv8> server # [ 18.817481] postgres[929]: extensions jsonb default null vm-test-run-plv8> server # [ 18.817666] postgres[929]: ) vm-test-run-plv8> server # [ 18.817764] postgres[929]: returns jsonb vm-test-run-plv8> server # [ 18.817894] postgres[929]: language sql vm-test-run-plv8> server # [ 18.818040] postgres[929]: as $$ vm-test-run-plv8> server # [ 18.818255] postgres[929]: -- This changed vm-test-run-plv8> server # [ 18.818524] postgres[929]: select graphql.resolve( vm-test-run-plv8> server # [ 18.818868] postgres[929]: query := query, vm-test-run-plv8> server # [ 18.819201] postgres[929]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 18.819528] postgres[929]: "operationName" := "operationName", vm-test-run-plv8> server # [ 18.819982] postgres[929]: extensions := extensions vm-test-run-plv8> server # [ 18.820241] postgres[929]: ); vm-test-run-plv8> server # [ 18.820454] postgres[929]: $$; vm-test-run-plv8> server # [ 18.820568] postgres[929]: vm-test-run-plv8> server # [ 18.820648] postgres[929]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 18.821138] postgres[929]: END IF; vm-test-run-plv8> server # [ 18.821355] postgres[929]: vm-test-run-plv8> server # [ 18.821540] postgres[929]: END; vm-test-run-plv8> server # [ 18.821757] postgres[929]: $func$; vm-test-run-plv8> server # [ 18.821985] postgres[929]: ::1 2026-10-05 11:10:59.112 UTC [929] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-plv8> server # [ 18.822630] postgres[929]: RETURNS event_trigger vm-test-run-plv8> server # [ 18.822942] postgres[929]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 18.823160] postgres[929]: AS $func$ vm-test-run-plv8> server # [ 18.823344] postgres[929]: DECLARE vm-test-run-plv8> server # [ 18.823529] postgres[929]: graphql_is_dropped bool; vm-test-run-plv8> server # [ 18.823793] postgres[929]: BEGIN vm-test-run-plv8> server # [ 18.823962] postgres[929]: graphql_is_dropped = ( vm-test-run-plv8> server # [ 18.824173] postgres[929]: SELECT ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 18.824712] postgres[929]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-plv8> server # [ 18.824978] postgres[929]: WHERE ev.schema_name = 'graphql_public' vm-test-run-plv8> server # [ 18.825354] postgres[929]: ); vm-test-run-plv8> server # [ 18.825562] postgres[929]: vm-test-run-plv8> server # [ 18.825794] postgres[929]: IF graphql_is_dropped vm-test-run-plv8> server # [ 18.826056] postgres[929]: THEN vm-test-run-plv8> server # [ 18.826230] postgres[929]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 18.826671] postgres[929]: "operationName" text default null, vm-test-run-plv8> server # [ 18.827039] postgres[929]: query text default null, vm-test-run-plv8> server # [ 18.827385] postgres[929]: variables jsonb default null, vm-test-run-plv8> server # [ 18.827757] postgres[929]: extensions jsonb default null vm-test-run-plv8> server # [ 18.828201] postgres[929]: ) vm-test-run-plv8> server # [ 18.828395] postgres[929]: returns jsonb vm-test-run-plv8> server # [ 18.828807] postgres[929]: language plpgsql vm-test-run-plv8> server # [ 18.829358] postgres[929]: as $$ vm-test-run-plv8> server # [ 18.829479] postgres[929]: DECLARE vm-test-run-plv8> server # [ 18.829759] postgres[929]: server_version float; vm-test-run-plv8> server # [ 18.830115] postgres[929]: BEGIN vm-test-run-plv8> server # [ 18.830366] postgres[929]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 18.830876] postgres[929]: vm-test-run-plv8> server # [ 18.831039] postgres[929]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 18.831407] postgres[929]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.831771] postgres[929]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 18.832080] postgres[929]: jsonb_build_object( vm-test-run-plv8> server # [ 18.832479] postgres[929]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-plv8> server # [ 18.832957] postgres[929]: ) vm-test-run-plv8> server # [ 18.833120] postgres[929]: ) vm-test-run-plv8> server # [ 18.833318] postgres[929]: ); vm-test-run-plv8> server # [ 18.833454] postgres[929]: ELSE vm-test-run-plv8> server # [ 18.833586] postgres[929]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.833772] postgres[929]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 18.833964] postgres[929]: jsonb_build_object( vm-test-run-plv8> server # [ 18.834144] postgres[929]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-plv8> server # [ 18.834604] postgres[929]: ) vm-test-run-plv8> server # [ 18.834772] postgres[929]: ) vm-test-run-plv8> server # [ 18.834922] postgres[929]: ); vm-test-run-plv8> server # [ 18.835047] postgres[929]: END IF; vm-test-run-plv8> server # [ 18.835187] postgres[929]: END; vm-test-run-plv8> server # [ 18.835461] postgres[929]: $$; vm-test-run-plv8> server # [ 18.835674] postgres[929]: END IF; vm-test-run-plv8> server # [ 18.835896] postgres[929]: vm-test-run-plv8> server # [ 18.836072] postgres[929]: END; vm-test-run-plv8> server # [ 18.836316] postgres[929]: $func$; vm-test-run-plv8> server # [ 18.836445] postgres[929]: ::1 2026-10-05 11:10:59.114 UTC [929] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 18.837094] postgres[929]: "operationName" text default null, vm-test-run-plv8> server # [ 18.837463] postgres[929]: query text default null, vm-test-run-plv8> server # [ 18.837726] postgres[929]: variables jsonb default null, vm-test-run-plv8> server # [ 18.838281] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[928]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.838529] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[928]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.838882] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[928]: CREATE FUNCTION vm-test-run-plv8> server # [ 18.839292] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[928]: psql:/nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-plv8> server # [ 18.840309] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[928]: DROP EXTENSION vm-test-run-plv8> server # [ 18.840860] postgres[929]: extensions jsonb default null vm-test-run-plv8> server # [ 18.841213] postgres[929]: ) vm-test-run-plv8> server # [ 18.841309] postgres[929]: returns jsonb vm-test-run-plv8> server # [ 18.841420] postgres[929]: language plpgsql vm-test-run-plv8> server # [ 18.841545] postgres[929]: as $$ vm-test-run-plv8> server # [ 18.841628] postgres[929]: DECLARE vm-test-run-plv8> server # [ 18.841830] postgres[929]: server_version float; vm-test-run-plv8> server # [ 18.842045] postgres[929]: BEGIN vm-test-run-plv8> server # [ 18.842176] postgres[929]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-plv8> server # [ 18.842683] postgres[929]: vm-test-run-plv8> server # [ 18.842852] postgres[929]: IF server_version >= 14 THEN vm-test-run-plv8> server # [ 18.843168] postgres[929]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.843492] postgres[929]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 18.843748] postgres[929]: jsonb_build_object( vm-test-run-plv8> server # [ 18.844070] postgres[929]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-plv8> server # [ 18.844800] postgres[929]: ) vm-test-run-plv8> server # [ 18.845037] postgres[929]: ) vm-test-run-plv8> server # [ 18.845207] postgres[929]: ); vm-test-run-plv8> server # [ 18.845421] postgres[929]: ELSE vm-test-run-plv8> server # [ 18.845645] postgres[929]: RETURN jsonb_build_object( vm-test-run-plv8> server # [ 18.846011] postgres[929]: 'errors', jsonb_build_array( vm-test-run-plv8> server # [ 18.846304] postgres[929]: jsonb_build_object( vm-test-run-plv8> server # [ 18.846519] postgres[929]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-plv8> server # [ 18.847046] postgres[929]: ) vm-test-run-plv8> server # [ 18.847278] postgres[929]: ) vm-test-run-plv8> server # [ 18.847494] postgres[929]: ); vm-test-run-plv8> server # [ 18.847694] postgres[929]: END IF; vm-test-run-plv8> server # [ 18.847893] postgres[929]: END; vm-test-run-plv8> server # [ 18.848126] postgres[929]: $$; vm-test-run-plv8> server # [ 18.848277] postgres[929]: ::1 2026-10-05 11:10:59.116 UTC [929] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 19.356558] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[928]: DO vm-test-run-plv8> server # [ 19.360473] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-plv8> server # [ 19.390694] postgres[935]: ::1 2026-10-05 11:10:59.695 UTC [935] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-plv8> server # [ 19.392719] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[934]: GRANT ROLE vm-test-run-plv8> server # [ 19.397605] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-plv8> server # [ 19.431217] postgres[937]: ::1 2026-10-05 11:10:59.736 UTC [937] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 19.431805] postgres[937]: returns event_trigger vm-test-run-plv8> server # [ 19.431948] postgres[937]: language plpgsql vm-test-run-plv8> server # [ 19.432397] postgres[937]: AS $func$ vm-test-run-plv8> server # [ 19.432612] postgres[937]: DECLARE vm-test-run-plv8> server # [ 19.432951] postgres[937]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 19.433237] postgres[937]: BEGIN vm-test-run-plv8> server # [ 19.433407] postgres[937]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 19.433705] postgres[937]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 19.434030] postgres[937]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 19.434375] postgres[937]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 19.435144] postgres[937]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 19.435423] postgres[937]: ); vm-test-run-plv8> server # [ 19.435695] postgres[937]: vm-test-run-plv8> server # [ 19.435891] postgres[937]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 19.436268] postgres[937]: THEN vm-test-run-plv8> server # [ 19.436472] postgres[937]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 19.436772] postgres[937]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 19.437128] postgres[937]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 19.437523] postgres[937]: "operationName" text default null, vm-test-run-plv8> server # [ 19.438358] postgres[937]: query text default null, vm-test-run-plv8> server # [ 19.438869] postgres[937]: variables jsonb default null, vm-test-run-plv8> server # [ 19.439188] postgres[937]: extensions jsonb default null vm-test-run-plv8> server # [ 19.439385] postgres[937]: ) vm-test-run-plv8> server # [ 19.439479] postgres[937]: returns jsonb vm-test-run-plv8> server # [ 19.439927] postgres[937]: language sql vm-test-run-plv8> server # [ 19.440531] postgres[937]: as $$ vm-test-run-plv8> server # [ 19.440790] postgres[937]: select graphql.resolve( vm-test-run-plv8> server # [ 19.441120] postgres[937]: query := query, vm-test-run-plv8> server # [ 19.441329] postgres[937]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 19.441708] postgres[937]: "operationName" := "operationName", vm-test-run-plv8> server # [ 19.442175] postgres[937]: extensions := extensions vm-test-run-plv8> server # [ 19.442532] postgres[937]: ); vm-test-run-plv8> server # [ 19.442779] postgres[937]: $$; vm-test-run-plv8> server # [ 19.443016] postgres[937]: vm-test-run-plv8> server # [ 19.443207] postgres[937]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-plv8> server # [ 19.443819] postgres[937]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-plv8> server # [ 19.444564] postgres[937]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-plv8> server # [ 19.444924] postgres[937]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.445479] postgres[937]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.446043] postgres[937]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.446490] postgres[937]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.446997] postgres[937]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.447479] postgres[937]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.448303] postgres[937]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 19.449002] postgres[937]: END IF; vm-test-run-plv8> server # [ 19.449203] postgres[937]: vm-test-run-plv8> server # [ 19.449293] postgres[937]: END; vm-test-run-plv8> server # [ 19.449560] postgres[937]: $func$; vm-test-run-plv8> server # [ 19.449737] postgres[937]: ::1 2026-10-05 11:10:59.740 UTC [937] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 19.450418] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[936]: CREATE FUNCTION vm-test-run-plv8> server # [ 19.450676] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[936]: DROP EXTENSION vm-test-run-plv8> server # [ 19.969939] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[936]: DO vm-test-run-plv8> server # [ 19.975580] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-plv8> server # [ 20.272586] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[938]: DO vm-test-run-plv8> server # [ 20.496809] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[938]: DO vm-test-run-plv8> server # [ 20.501586] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-plv8> server # [ 20.533720] postgres[945]: ::1 2026-10-05 11:11:00.838 UTC [945] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-plv8> server # [ 20.535525] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[944]: ALTER ROLE vm-test-run-plv8> server # [ 20.541033] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-plv8> server # [ 20.574399] postgres[947]: ::1 2026-10-05 11:11:00.879 UTC [947] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-plv8> server # [ 20.575862] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[946]: REVOKE ROLE vm-test-run-plv8> server # [ 20.579874] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-plv8> server # [ 21.302452] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[948]: DO vm-test-run-plv8> server # [ 21.307553] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-plv8> server # [ 21.384959] postgres[955]: ::1 2026-10-05 11:11:01.687 UTC [955] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-plv8> server # [ 21.397878] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[953]: GRANT ROLE vm-test-run-plv8> server # [ 21.410000] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-plv8> server # [ 21.443834] postgres[957]: ::1 2026-10-05 11:11:01.748 UTC [957] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 21.446419] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[956]: GRANT vm-test-run-plv8> server # [ 21.446949] postgres[957]: ::1 2026-10-05 11:11:01.752 UTC [957] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 21.448296] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[956]: GRANT vm-test-run-plv8> server # [ 21.448758] postgres[957]: ::1 2026-10-05 11:11:01.753 UTC [957] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-plv8> server # [ 21.450018] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[956]: GRANT vm-test-run-plv8> server # [ 21.450550] postgres[957]: ::1 2026-10-05 11:11:01.754 UTC [957] 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 # [ 21.452268] postgres[957]: ::1 2026-10-05 11:11:01.756 UTC [957] 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 # [ 21.453747] postgres[957]: ::1 2026-10-05 11:11:01.757 UTC [957] 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 # [ 21.454397] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[956]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 21.454871] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[956]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 21.455875] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[956]: ALTER DEFAULT PRIVILEGES vm-test-run-plv8> server # [ 21.458594] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-plv8> server # [ 21.497752] postgres[959]: ::1 2026-10-05 11:11:01.802 UTC [959] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-plv8> server # [ 21.499450] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[958]: GRANT ROLE vm-test-run-plv8> server # [ 21.504683] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-plv8> server # [ 21.541592] postgres[961]: ::1 2026-10-05 11:11:01.846 UTC [961] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-plv8> server # [ 21.542891] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[960]: GRANT ROLE vm-test-run-plv8> server # [ 21.547430] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-plv8> server # [ 21.584216] postgres[963]: ::1 2026-10-05 11:11:01.889 UTC [963] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-plv8> server # [ 21.584977] postgres[963]: ::1 2026-10-05 11:11:01.890 UTC [963] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-plv8> server # [ 21.586002] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[962]: ALTER ROLE vm-test-run-plv8> server # [ 21.586581] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[962]: ALTER ROLE vm-test-run-plv8> server # [ 21.587773] postgres[963]: ::1 2026-10-05 11:11:01.890 UTC [963] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-plv8> server # [ 21.588492] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[962]: ALTER ROLE vm-test-run-plv8> server # [ 21.589432] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[962]: DO vm-test-run-plv8> server # [ 21.593758] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-plv8> server # [ 21.627403] postgres[966]: ::1 2026-10-05 11:11:01.932 UTC [966] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-plv8> server # [ 21.628904] postgres[966]: ::1 2026-10-05 11:11:01.934 UTC [966] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-plv8> server # [ 21.630220] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[965]: GRANT ROLE vm-test-run-plv8> server # [ 21.630635] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[965]: REVOKE ROLE vm-test-run-plv8> server # [ 21.635389] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-plv8> server # [ 21.708669] postgres[968]: ::1 2026-10-05 11:11:02.013 UTC [968] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-plv8> server # [ 21.709523] postgres[968]: returns event_trigger vm-test-run-plv8> server # [ 21.709983] postgres[968]: language plpgsql vm-test-run-plv8> server # [ 21.710328] postgres[968]: AS $func$ vm-test-run-plv8> server # [ 21.710467] postgres[968]: DECLARE vm-test-run-plv8> server # [ 21.710565] postgres[968]: func_is_graphql_resolve bool; vm-test-run-plv8> server # [ 21.710714] postgres[968]: BEGIN vm-test-run-plv8> server # [ 21.710803] postgres[968]: func_is_graphql_resolve = ( vm-test-run-plv8> server # [ 21.710986] postgres[968]: SELECT n.proname = 'resolve' vm-test-run-plv8> server # [ 21.711178] postgres[968]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 21.711474] postgres[968]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-plv8> server # [ 21.711849] postgres[968]: ON ev.objid = n.oid vm-test-run-plv8> server # [ 21.712302] postgres[968]: ); vm-test-run-plv8> server # [ 21.712503] postgres[968]: vm-test-run-plv8> server # [ 21.712939] postgres[968]: IF func_is_graphql_resolve vm-test-run-plv8> server # [ 21.713394] postgres[968]: THEN vm-test-run-plv8> server # [ 21.713500] postgres[968]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-plv8> server # [ 21.713809] postgres[968]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-plv8> server # [ 21.714038] postgres[968]: create or replace function graphql_public.graphql( vm-test-run-plv8> server # [ 21.714482] postgres[968]: "operationName" text default null, vm-test-run-plv8> server # [ 21.714946] postgres[968]: query text default null, vm-test-run-plv8> server # [ 21.715325] postgres[968]: variables jsonb default null, vm-test-run-plv8> server # [ 21.715655] postgres[968]: extensions jsonb default null vm-test-run-plv8> server # [ 21.715981] postgres[968]: ) vm-test-run-plv8> server # [ 21.716401] postgres[968]: returns jsonb vm-test-run-plv8> server # [ 21.716635] postgres[968]: language sql vm-test-run-plv8> server # [ 21.716893] postgres[968]: as $$ vm-test-run-plv8> server # [ 21.717137] postgres[968]: select graphql.resolve( vm-test-run-plv8> server # [ 21.717412] postgres[968]: query := query, vm-test-run-plv8> server # [ 21.717732] postgres[968]: variables := coalesce(variables, '{}'), vm-test-run-plv8> server # [ 21.718171] postgres[968]: "operationName" := "operationName", vm-test-run-plv8> server # [ 21.719325] postgres[968]: extensions := extensions vm-test-run-plv8> server # [ 21.719554] postgres[968]: ); vm-test-run-plv8> server # [ 21.719727] postgres[968]: $$; vm-test-run-plv8> server # [ 21.720065] postgres[968]: vm-test-run-plv8> server # [ 21.720161] postgres[968]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-plv8> server # [ 21.720425] postgres[968]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-plv8> server # [ 21.720991] postgres[968]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-plv8> server # [ 21.721816] postgres[968]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.722446] postgres[968]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.723024] postgres[968]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.723557] postgres[968]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.724249] postgres[968]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.724952] postgres[968]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.725496] postgres[968]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-plv8> server # [ 21.726113] postgres[968]: vm-test-run-plv8> server # [ 21.726308] postgres[968]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-plv8> server # [ 21.726720] postgres[968]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-plv8> server # [ 21.727136] postgres[968]: grant usage on schema graphql to postgres with grant option; vm-test-run-plv8> server # [ 21.727456] postgres[968]: END IF; vm-test-run-plv8> server # [ 21.727714] postgres[968]: vm-test-run-plv8> server # [ 21.727893] postgres[968]: END; vm-test-run-plv8> server # [ 21.728153] postgres[968]: $func$; vm-test-run-plv8> server # [ 21.728352] postgres[968]: ::1 2026-10-05 11:11:02.021 UTC [968] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-plv8> server # [ 21.729261] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[967]: CREATE FUNCTION vm-test-run-plv8> server # [ 21.729736] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[967]: DROP EXTENSION vm-test-run-plv8> server # [ 22.347711] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[967]: DO vm-test-run-plv8> server # [ 22.352370] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-plv8> server # [ 22.384383] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[969]: DO vm-test-run-plv8> server # [ 22.385056] postgres[970]: ::1 2026-10-05 11:11:02.690 UTC [970] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-plv8> server # [ 22.386212] postgres[970]: LANGUAGE plpgsql vm-test-run-plv8> server # [ 22.386420] postgres[970]: AS $$ vm-test-run-plv8> server # [ 22.386528] postgres[970]: BEGIN vm-test-run-plv8> server # [ 22.386758] postgres[970]: IF EXISTS ( vm-test-run-plv8> server # [ 22.386939] postgres[970]: SELECT vm-test-run-plv8> server # [ 22.387058] postgres[970]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-plv8> server # [ 22.387652] postgres[970]: JOIN pg_extension AS ext vm-test-run-plv8> server # [ 22.388378] postgres[970]: ON ev.objid = ext.oid vm-test-run-plv8> server # [ 22.388888] postgres[970]: WHERE ext.extname = 'pg_cron' vm-test-run-plv8> server # [ 22.389435] postgres[970]: ) vm-test-run-plv8> server # [ 22.389609] postgres[970]: THEN vm-test-run-plv8> server # [ 22.389930] postgres[970]: grant usage on schema cron to postgres with grant option; vm-test-run-plv8> server # [ 22.390431] postgres[970]: vm-test-run-plv8> server # [ 22.390665] postgres[970]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-plv8> server # [ 22.391721] postgres[970]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-plv8> server # [ 22.392590] postgres[970]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-plv8> server # [ 22.393084] postgres[970]: vm-test-run-plv8> server # [ 22.393208] postgres[970]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 22.393513] postgres[970]: on sequences to postgres with grant option; vm-test-run-plv8> server # [ 22.393722] postgres[970]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 22.394056] postgres[970]: on tables to postgres with grant option; vm-test-run-plv8> server # [ 22.395296] postgres[970]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-plv8> server # [ 22.396965] postgres[970]: on functions to postgres with grant option; vm-test-run-plv8> server # [ 22.398075] postgres[970]: vm-test-run-plv8> server # [ 22.398496] postgres[970]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-plv8> server # [ 22.398982] postgres[970]: revoke all on table cron.job from postgres; vm-test-run-plv8> server # [ 22.399991] postgres[970]: grant select on table cron.job to postgres with grant option; vm-test-run-plv8> server # [ 22.400906] postgres[970]: END IF; vm-test-run-plv8> server # [ 22.401143] postgres[970]: END; vm-test-run-plv8> server # [ 22.401265] postgres[970]: $$; vm-test-run-plv8> server # [ 22.401763] postgres[970]: ::1 2026-10-05 11:11:02.693 UTC [970] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-plv8> server # [ 22.403340] postgres[970]: ::1 2026-10-05 11:11:02.694 UTC [970] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-plv8> server # [ 22.405542] postgres[970]: ::1 2026-10-05 11:11:02.695 UTC [970] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-plv8> server # [ 22.407309] postgres[970]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-plv8> server # [ 22.408616] postgres[970]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-plv8> server # [ 22.409342] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[969]: CREATE FUNCTION vm-test-run-plv8> server # [ 22.410045] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[969]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 22.411065] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[969]: ALTER FUNCTION vm-test-run-plv8> server # [ 22.412224] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[969]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 22.412970] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-plv8> server # [ 22.432321] postgres[972]: ::1 2026-10-05 11:11:02.737 UTC [972] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-plv8> server # [ 22.433608] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[971]: ALTER ROLE vm-test-run-plv8> server # [ 22.437893] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-plv8> server # [ 22.469436] postgres[974]: ::1 2026-10-05 11:11:02.774 UTC [974] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-plv8> server # [ 22.471580] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[973]: ALTER FUNCTION vm-test-run-plv8> server # [ 22.472257] postgres[974]: ::1 2026-10-05 11:11:02.777 UTC [974] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-plv8> server # [ 22.473258] postgres[974]: ::1 2026-10-05 11:11:02.777 UTC [974] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-plv8> server # [ 22.474920] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[973]: ALTER FUNCTION vm-test-run-plv8> server # [ 22.475158] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[973]: ALTER FUNCTION vm-test-run-plv8> server # [ 22.477327] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-plv8> server # [ 22.554871] postgres[976]: ::1 2026-10-05 11:11:02.859 UTC [976] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-plv8> server # [ 22.556959] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[975]: GRANT ROLE vm-test-run-plv8> server # [ 22.562641] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-plv8> server # [ 22.965897] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[980]: DO vm-test-run-plv8> server # [ 22.971761] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-plv8> server # [ 23.007615] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[983]: DO vm-test-run-plv8> server # [ 23.008324] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[983]: DO vm-test-run-plv8> server # [ 23.013732] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-plv8> server # [ 23.048331] postgres[986]: ::1 2026-10-05 11:11:03.353 UTC [986] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-plv8> server # [ 23.049968] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[985]: ALTER ROLE vm-test-run-plv8> server # [ 23.050922] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[985]: ALTER ROLE vm-test-run-plv8> server # [ 23.051478] postgres[986]: ::1 2026-10-05 11:11:03.355 UTC [986] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-plv8> server # [ 23.052755] postgres[986]: ::1 2026-10-05 11:11:03.355 UTC [986] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-plv8> server # [ 23.053671] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[985]: ALTER ROLE vm-test-run-plv8> server # [ 23.057011] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-plv8> server # [ 23.094762] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[987]: DO vm-test-run-plv8> server # [ 23.098310] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-plv8> server # [ 23.132805] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[989]: DO vm-test-run-plv8> server # [ 23.137309] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-plv8> server # [ 23.172818] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[991]: CREATE FUNCTION vm-test-run-plv8> server # [ 23.173749] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[991]: DO vm-test-run-plv8> server # [ 23.178295] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-plv8> server # [ 23.250754] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[993]: ALTER FUNCTION vm-test-run-plv8> server # [ 23.251475] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[993]: GRANT vm-test-run-plv8> server # [ 23.255634] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-plv8> server # [ 23.291326] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[995]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 23.293411] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[995]: ALTER FUNCTION vm-test-run-plv8> server # [ 23.294353] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[995]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 23.299501] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-plv8> server # [ 23.333226] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[997]: DO vm-test-run-plv8> server # [ 23.337242] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-plv8> server # [ 23.374788] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[999]: CREATE FUNCTION vm-test-run-plv8> server # [ 23.376509] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[999]: GRANT vm-test-run-plv8> server # [ 23.380777] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-plv8> server # [ 23.414745] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1001]: REVOKE ROLE vm-test-run-plv8> server # [ 23.418034] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1001]: DO vm-test-run-plv8> server # [ 23.418404] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1001]: DO vm-test-run-plv8> server # [ 23.419290] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1001]: REVOKE ROLE vm-test-run-plv8> server # [ 23.420174] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1001]: REVOKE vm-test-run-plv8> server # [ 23.421585] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1001]: DO vm-test-run-plv8> server # [ 23.426181] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-plv8> server # [ 23.460899] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1003]: DO vm-test-run-plv8> server # [ 23.464355] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-plv8> server # [ 23.542940] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1005]: DO vm-test-run-plv8> server # [ 23.547858] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-plv8> server # [ 23.583549] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1007]: DO vm-test-run-plv8> server # [ 23.587929] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-plv8> server # [ 23.622542] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1009]: ALTER ROLE vm-test-run-plv8> server # [ 23.625840] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-plv8> server # [ 23.662701] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1011]: GRANT ROLE vm-test-run-plv8> server # [ 23.664440] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1011]: DO vm-test-run-plv8> server # [ 23.671188] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-plv8> server # [ 23.711797] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1017]: GRANT vm-test-run-plv8> server # [ 23.717792] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-plv8> server # [ 23.754557] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1019]: CREATE FUNCTION vm-test-run-plv8> server # [ 23.756391] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1019]: REVOKE vm-test-run-plv8> server # [ 23.757386] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1019]: REVOKE vm-test-run-plv8> server # [ 23.758604] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1019]: GRANT vm-test-run-plv8> server # [ 23.765381] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-plv8> server # [ 23.848266] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1021]: DO vm-test-run-plv8> server # [ 23.854347] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-plv8> server # [ 23.892352] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1023]: ALTER ROLE vm-test-run-plv8> server # [ 23.897403] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-plv8> server # [ 23.974570] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1025]: DROP EXTENSION vm-test-run-plv8> server # [ 23.978287] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-plv8> server # [ 24.014303] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1027]: CREATE FUNCTION vm-test-run-plv8> server # [ 24.015310] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1027]: DROP EVENT TRIGGER vm-test-run-plv8> server # [ 24.016341] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1027]: CREATE EVENT TRIGGER vm-test-run-plv8> server # [ 24.048531] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-plv8> server # [ 24.095726] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1029]: DO vm-test-run-plv8> server # [ 24.097670] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1029]: CREATE FUNCTION vm-test-run-plv8> server # [ 24.102548] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Running postgresql schema... vm-test-run-plv8> server # [ 24.138207] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER DATABASE vm-test-run-plv8> server # [ 24.139167] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER DATABASE vm-test-run-plv8> server # [ 24.144458] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.147333] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.149948] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.153920] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.156547] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.159429] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.162347] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.165829] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.168379] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.169393] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[1031]: ALTER ROLE vm-test-run-plv8> server # [ 24.174730] qpfm80dqm4lh55d6fwvy3k00jiy2lr5s-supabase-db-init[818]: Database initialization complete vm-test-run-plv8> server # [ 24.177023] systemd[1]: Finished Supabase Database Initialization. vm-test-run-plv8> server # [ 24.177495] systemd[1]: Reached target Multi-User System. vm-test-run-plv8> server # [ 24.177878] systemd[1]: Startup finished in 218ms (kernel) + 4.628s (initrd) + 19.330s (userspace) = 24.177s. vm-test-run-plv8> server: (finished: waiting for unit supabase-db-init.service, in 24.93 seconds) vm-test-run-plv8> subtest: Verify PostgreSQL 15 is our custom build vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.07 seconds) vm-test-run-plv8> server: must succeed: readlink -f $(which postgres) vm-test-run-plv8> server: (finished: must succeed: readlink -f $(which postgres), in 0.11 seconds) vm-test-run-plv8> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 0.18 seconds) vm-test-run-plv8> subtest: Verify ansible config loaded vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.09 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.07 seconds) vm-test-run-plv8> (finished: subtest: Verify ansible config loaded, in 0.16 seconds) vm-test-run-plv8> subtest: Verify init scripts and migrations ran vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;", in 0.12 seconds) vm-test-run-plv8> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;" vm-test-run-plv8> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;", in 0.07 seconds) vm-test-run-plv8> (finished: subtest: Verify init scripts and migrations ran, in 0.19 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.11 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 1.14 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.06 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.06 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 1.07 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.06 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.06 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.02 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.13 seconds) vm-test-run-plv8> (finished: subtest: Check upgrade path with postgresql 15, in 3.71 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.07 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 0.48 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.12 seconds) vm-test-run-plv8> (finished: subtest: Check the install of the last version of the extension, in 0.67 seconds) vm-test-run-plv8> (finished: run the VM test script, in 32.17 seconds) vm-test-run-plv8> test script finished in 32.25s vm-test-run-plv8> cleanup vm-test-run-plv8> kill QemuMachine (pid 93004) vm-test-run-plv8> server # qemu-system-aarch64: terminating on signal 15 vm-test-run-plv8> (finished: cleanup, in 0.01 seconds)