these 16 derivations will be built: /nix/store/i1fxkgmzfykhd1k9mkfgiciicpajl1g8-system-path.drv /nix/store/zb08r0ybz6gsszcba25l49mfw5rv74vp-dbus-1.drv /nix/store/y3b76rgfidgb1awwdlbfsljfg251x4pc-X-Restart-Triggers-dbus.drv /nix/store/1p3fs4gpcn940ndz1yvva356ggwnwkpa-unit-dbus.service.drv /nix/store/85h5qah6i369q4ajdsw14wj51qlg1214-user-units.drv /nix/store/0l23byrhsriywgnkqrs5v3f7cc29wa94-unit-dbus.service.drv /nix/store/jvj1fsapd1sgipj4mj8xh682zzf033jb-system-units.drv /nix/store/vyi06sbwn2hw2rdrgc8zccvnwd6c5rjk-etc.drv /nix/store/0hfcyam7amyh30z1rjlk0pjvnapkgyw4-activate.drv /nix/store/vi0zpn8dl53wmp5394r7xckc72f1583n-nixos-system-server-test.drv /nix/store/00lmwp9hmc5b41p3m4z100hr7z7vc7l4-nixos-system-server-test.drv /nix/store/9pir3xpy8qxmmya4c9pj6q91bn15rbv4-closure-info.drv /nix/store/psyhwc0bj54f9mhwvcc69kcb6m1kablh-run-nixos-vm.drv /nix/store/lyrq3icz1l4sgp5x7740815z6pfa3p0k-nixos-vm.drv /nix/store/d6d0slprdl3hpl7b2jz75w5zzvx5x182-nixos-test-driver-pgsodium.drv /nix/store/l3iv0n967fd4fm6w2fh7waxhp0p2h5c2-vm-test-run-pgsodium.drv building '/nix/store/d6d0slprdl3hpl7b2jz75w5zzvx5x182-nixos-test-driver-pgsodium.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/d6d0slprdl3hpl7b2jz75w5zzvx5x182-nixos-test-driver-pgsodium.drv' nixos-test-driver-pgsodium> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-pgsodium> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-pgsodium> Success: no issues found in 1 source file nixos-test-driver-pgsodium> additionally exposed symbols: nixos-test-driver-pgsodium> , nixos-test-driver-pgsodium> , nixos-test-driver-pgsodium> start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug nixos-test-driver-pgsodium> Linting test script (enable/disable: config.skipLint) nixos-test-driver-pgsodium> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint building '/nix/store/l3iv0n967fd4fm6w2fh7waxhp0p2h5c2-vm-test-run-pgsodium.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/l3iv0n967fd4fm6w2fh7waxhp0p2h5c2-vm-test-run-pgsodium.drv' vm-test-run-pgsodium> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-pgsodium> start all VLans vm-test-run-pgsodium> start vlan vm-test-run-pgsodium> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-pgsodium> (finished: start all VLans, in 0.00 seconds) vm-test-run-pgsodium> Test will time out and terminate in 3600 seconds vm-test-run-pgsodium> run the VM test script vm-test-run-pgsodium> additionally exposed symbols: vm-test-run-pgsodium> server, vm-test-run-pgsodium> vlan1, vm-test-run-pgsodium> start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug vm-test-run-pgsodium> start all VMs vm-test-run-pgsodium> server: starting vm vm-test-run-pgsodium> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-pgsodium> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-pgsodium> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-pgsodium> qemu-system-aarch64: falling back to tcg vm-test-run-pgsodium> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-pgsodium> server: QEMU running (pid 9) vm-test-run-pgsodium> server # Formatting '/build/vm-state-server/tmp.mIWPfSAREH', fmt=raw size=1073741824 vm-test-run-pgsodium> server # Discarding device blocks: 0/262144 done vm-test-run-pgsodium> (finished: start all VMs, in 0.13 seconds) vm-test-run-pgsodium> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-pgsodium> server # Filesystem UUID: ff832013-fadc-4fa4-8d3b-2845bb187e78 vm-test-run-pgsodium> server # Superblock backups stored on blocks: vm-test-run-pgsodium> server # 32768, 98304, 163840, 229376 vm-test-run-pgsodium> server # vm-test-run-pgsodium> server # Allocating group tables: 0/8 done vm-test-run-pgsodium> server # Writing inode tables: 0/8 done vm-test-run-pgsodium> server # Creating journal (8192 blocks): done vm-test-run-pgsodium> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-pgsodium> server # vm-test-run-pgsodium> server # Virtualisation disk image created. vm-test-run-pgsodium> server: waiting for unit supabase-db-init.service vm-test-run-pgsodium> server: waiting for the VM to finish booting vm-test-run-pgsodium> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-pgsodium> server # [ 0.000000] Linux version 6.12.63 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP Thu Dec 18 12:55:23 UTC 2025 vm-test-run-pgsodium> server # [ 0.000000] KASLR enabled vm-test-run-pgsodium> server # [ 0.000000] random: crng init done vm-test-run-pgsodium> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-pgsodium> server # [ 0.000000] efi: UEFI not found. vm-test-run-pgsodium> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-pgsodium> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgsodium> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f] vm-test-run-pgsodium> server # [ 0.000000] Zone ranges: vm-test-run-pgsodium> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-pgsodium> server # [ 0.000000] DMA32 empty vm-test-run-pgsodium> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-pgsodium> server # [ 0.000000] Device empty vm-test-run-pgsodium> server # [ 0.000000] Movable zone start for each node vm-test-run-pgsodium> server # [ 0.000000] Early memory node ranges vm-test-run-pgsodium> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgsodium> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgsodium> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1 vm-test-run-pgsodium> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-pgsodium> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-pgsodium> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-pgsodium> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-pgsodium> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-pgsodium> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-pgsodium> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-pgsodium> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-pgsodium> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-pgsodium> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-pgsodium> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-pgsodium> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-pgsodium> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-pgsodium> server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/h6wy4m62pihbkb0bvw3lxlhndi980nlk-nixos-system-server-test/init regInfo=/nix/store/l8lfhnylxpf0mipvh8m5ijfl8q018clb-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-pgsodium> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/l8lfhnylxpf0mipvh8m5ijfl8q018clb-closure-info/registration", will be passed to user space. vm-test-run-pgsodium> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-pgsodium> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-pgsodium> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-pgsodium> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-pgsodium> server # [ 0.000000] Policy zone: Normal vm-test-run-pgsodium> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-pgsodium> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-pgsodium> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-pgsodium> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-pgsodium> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-pgsodium> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-pgsodium> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-pgsodium> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-pgsodium> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-pgsodium> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-pgsodium> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-pgsodium> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-pgsodium> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-pgsodium> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-pgsodium> 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-pgsodium> 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-pgsodium> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-pgsodium> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-pgsodium> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-pgsodium> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-pgsodium> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-pgsodium> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-pgsodium> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-pgsodium> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-pgsodium> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-pgsodium> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-pgsodium> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b00000 vm-test-run-pgsodium> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000 vm-test-run-pgsodium> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-pgsodium> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-pgsodium> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-pgsodium> server # [ 0.000036] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-pgsodium> server # [ 0.004344] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-pgsodium> server # [ 0.014643] Console: colour dummy device 80x25 vm-test-run-pgsodium> server # [ 0.016252] printk: legacy console [tty0] enabled vm-test-run-pgsodium> server # [ 0.022476] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-pgsodium> server # [ 0.023000] pid_max: default: 32768 minimum: 301 vm-test-run-pgsodium> server # [ 0.024928] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-pgsodium> server # [ 0.027186] landlock: Up and running. vm-test-run-pgsodium> server # [ 0.027258] Yama: becoming mindful. vm-test-run-pgsodium> server # [ 0.031892] LSM support for eBPF active vm-test-run-pgsodium> server # [ 0.035222] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-pgsodium> server # [ 0.035358] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-pgsodium> server # [ 0.067429] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-pgsodium> server # [ 0.079998] rcu: Hierarchical SRCU implementation. vm-test-run-pgsodium> server # [ 0.080099] rcu: Max phase no-delay instances is 1000. vm-test-run-pgsodium> server # [ 0.081548] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-pgsodium> server # [ 0.089566] fsl-mc MSI: its@8080000 domain created vm-test-run-pgsodium> server # [ 0.091803] EFI services will not be available. vm-test-run-pgsodium> server # [ 0.094109] smp: Bringing up secondary CPUs ... vm-test-run-pgsodium> server # [ 0.098517] Detected PIPT I-cache on CPU1 vm-test-run-pgsodium> server # [ 0.099867] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-pgsodium> server # [ 0.100606] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000 vm-test-run-pgsodium> server # [ 0.102217] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-pgsodium> server # [ 0.106474] smp: Brought up 1 node, 2 CPUs vm-test-run-pgsodium> server # [ 0.106641] SMP: Total of 2 processors activated. vm-test-run-pgsodium> server # [ 0.106687] CPU: All CPU(s) started at EL1 vm-test-run-pgsodium> server # [ 0.106852] CPU features: detected: Branch Target Identification vm-test-run-pgsodium> server # [ 0.107040] CPU features: detected: 32-bit EL0 Support vm-test-run-pgsodium> server # [ 0.107116] CPU features: detected: 32-bit EL1 Support vm-test-run-pgsodium> server # [ 0.107161] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-pgsodium> server # [ 0.107220] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-pgsodium> server # [ 0.107287] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-pgsodium> server # [ 0.107417] CPU features: detected: Common not Private translations vm-test-run-pgsodium> server # [ 0.107459] CPU features: detected: CRC32 instructions vm-test-run-pgsodium> server # [ 0.107483] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-pgsodium> server # [ 0.107512] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-pgsodium> server # [ 0.107541] CPU features: detected: Data independent timing control (DIT) vm-test-run-pgsodium> server # [ 0.107570] CPU features: detected: E0PD vm-test-run-pgsodium> server # [ 0.107593] CPU features: detected: Enhanced Counter Virtualization vm-test-run-pgsodium> server # [ 0.107619] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-pgsodium> server # [ 0.107644] CPU features: detected: Enhanced Privileged Access Never vm-test-run-pgsodium> server # [ 0.107667] CPU features: detected: Enhanced Virtualization Traps vm-test-run-pgsodium> server # [ 0.107690] CPU features: detected: Fine Grained Traps vm-test-run-pgsodium> server # [ 0.107751] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-pgsodium> server # [ 0.107840] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-pgsodium> server # [ 0.107886] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-pgsodium> server # [ 0.107911] CPU features: detected: LSE atomic instructions vm-test-run-pgsodium> server # [ 0.107933] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-pgsodium> server # [ 0.107958] CPU features: detected: Privileged Access Never vm-test-run-pgsodium> server # [ 0.108017] CPU features: detected: RAS Extension Support vm-test-run-pgsodium> server # [ 0.108039] CPU features: detected: Random Number Generator vm-test-run-pgsodium> server # [ 0.108065] CPU features: detected: Speculation barrier (SB) vm-test-run-pgsodium> server # [ 0.108088] CPU features: detected: Stage-2 Force Write-Back vm-test-run-pgsodium> server # [ 0.108111] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-pgsodium> server # [ 0.108138] CPU features: detected: TLB range maintenance instructions vm-test-run-pgsodium> server # [ 0.108161] CPU features: detected: WFx with timeout vm-test-run-pgsodium> server # [ 0.108202] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-pgsodium> server # [ 0.108227] CPU features: detected: Scalable Vector Extension vm-test-run-pgsodium> server # [ 0.117798] alternatives: applying system-wide alternatives vm-test-run-pgsodium> server # [ 0.134021] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-pgsodium> server # [ 0.134795] SVE: maximum available vector length 256 bytes per vector vm-test-run-pgsodium> server # [ 0.135022] SVE: default vector length 64 bytes per vector vm-test-run-pgsodium> server # [ 0.147972] Memory: 3922864K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved) vm-test-run-pgsodium> server # [ 0.171659] devtmpfs: initialized vm-test-run-pgsodium> server # [ 0.200483] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-pgsodium> server # [ 0.201341] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-pgsodium> server # [ 0.203969] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-pgsodium> server # [ 0.204046] 0 pages in range for non-PLT usage vm-test-run-pgsodium> server # [ 0.204537] 509248 pages in range for PLT usage vm-test-run-pgsodium> server # [ 0.207080] pinctrl core: initialized pinctrl subsystem vm-test-run-pgsodium> server # [ 0.219294] DMI not present or invalid. vm-test-run-pgsodium> server # [ 0.240743] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-pgsodium> server # [ 0.252925] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-pgsodium> server # [ 0.254410] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-pgsodium> server # [ 0.255632] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-pgsodium> server # [ 0.255898] audit: initializing netlink subsys (disabled) vm-test-run-pgsodium> server # [ 0.258559] audit: type=2000 audit(0.192:1): state=initialized audit_enabled=0 res=1 vm-test-run-pgsodium> server # [ 0.265027] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-pgsodium> server # [ 0.265087] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-pgsodium> server # [ 0.265150] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-pgsodium> server # [ 0.265178] thermal_sys: Registered thermal governor 'user_space' vm-test-run-pgsodium> server # [ 0.265207] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-pgsodium> server # [ 0.266088] cpuidle: using governor ladder vm-test-run-pgsodium> server # [ 0.266512] cpuidle: using governor menu vm-test-run-pgsodium> server # [ 0.268614] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-pgsodium> server # [ 0.269534] ASID allocator initialised with 65536 entries vm-test-run-pgsodium> server # [ 0.278210] Serial: AMBA PL011 UART driver vm-test-run-pgsodium> server # [ 0.330030] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-pgsodium> server # [ 0.331871] printk: legacy console [ttyAMA0] enabled vm-test-run-pgsodium> server # [ 0.369017] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-pgsodium> server # [ 0.369207] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-pgsodium> server # [ 0.369313] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-pgsodium> server # [ 0.369412] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-pgsodium> server # [ 0.369506] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-pgsodium> server # [ 0.369600] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-pgsodium> server # [ 0.369695] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-pgsodium> server # [ 0.370392] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-pgsodium> server # [ 0.384293] fbcon: Taking over console vm-test-run-pgsodium> server # [ 0.385103] ACPI: Interpreter disabled. vm-test-run-pgsodium> server # [ 0.393150] iommu: Default domain type: Translated vm-test-run-pgsodium> server # [ 0.393362] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-pgsodium> server # [ 0.400844] SCSI subsystem initialized vm-test-run-pgsodium> server # [ 0.403310] usbcore: registered new interface driver usbfs vm-test-run-pgsodium> server # [ 0.404094] usbcore: registered new interface driver hub vm-test-run-pgsodium> server # [ 0.404370] usbcore: registered new device driver usb vm-test-run-pgsodium> server # [ 0.406739] pps_core: LinuxPPS API ver. 1 registered vm-test-run-pgsodium> server # [ 0.406899] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-pgsodium> server # [ 0.407098] PTP clock support registered vm-test-run-pgsodium> server # [ 0.407473] EDAC MC: Ver: 3.0.0 vm-test-run-pgsodium> server # [ 0.409253] scmi_core: SCMI protocol bus registered vm-test-run-pgsodium> server # [ 0.412511] FPGA manager framework vm-test-run-pgsodium> server # [ 0.413609] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-pgsodium> server # [ 0.423564] vgaarb: loaded vm-test-run-pgsodium> server # [ 0.429064] clocksource: Switched to clocksource arch_sys_counter vm-test-run-pgsodium> server # [ 0.434371] VFS: Disk quotas dquot_6.6.0 vm-test-run-pgsodium> server # [ 0.434687] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-pgsodium> server # [ 0.438680] netfs: FS-Cache loaded vm-test-run-pgsodium> server # [ 0.439934] pnp: PnP ACPI: disabled vm-test-run-pgsodium> server # [ 0.467189] NET: Registered PF_INET protocol family vm-test-run-pgsodium> server # [ 0.468333] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-pgsodium> server # [ 0.586303] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-pgsodium> server # [ 0.586699] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-pgsodium> server # [ 0.587096] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-pgsodium> server # [ 0.588698] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-pgsodium> server # [ 0.589936] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-pgsodium> server # [ 0.591876] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-pgsodium> server # [ 0.592330] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-pgsodium> server # [ 0.593217] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-pgsodium> server # [ 0.595632] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-pgsodium> server # [ 0.596219] NET: Registered PF_XDP protocol family vm-test-run-pgsodium> server # [ 0.596631] PCI: CLS 0 bytes, default 64 vm-test-run-pgsodium> server # [ 0.600281] kvm [1]: HYP mode not available vm-test-run-pgsodium> server # [ 0.600944] Trying to unpack rootfs image as initramfs... vm-test-run-pgsodium> server # [ 0.715915] Initialise system trusted keyrings vm-test-run-pgsodium> server # [ 0.717427] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-pgsodium> server # [ 0.722621] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-pgsodium> server # [ 0.723507] 9p: Installing v9fs 9p2000 file system support vm-test-run-pgsodium> server # [ 0.785237] Key type asymmetric registered vm-test-run-pgsodium> server # [ 0.785775] Asymmetric key parser 'x509' registered vm-test-run-pgsodium> server # [ 0.786379] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-pgsodium> server # [ 0.788331] io scheduler mq-deadline registered vm-test-run-pgsodium> server # [ 0.788498] io scheduler kyber registered vm-test-run-pgsodium> server # [ 0.817667] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-pgsodium> server # [ 0.823591] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-pgsodium> server # [ 0.827498] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-pgsodium> server # [ 0.828165] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-pgsodium> server # [ 0.828839] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-pgsodium> server # [ 0.829127] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-pgsodium> server # [ 0.829908] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-pgsodium> server # [ 0.830800] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-pgsodium> server # [ 0.832665] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-pgsodium> server # [ 0.833226] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-pgsodium> server # [ 0.833467] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-pgsodium> server # [ 0.833966] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-pgsodium> server # [ 0.834106] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-pgsodium> server # [ 0.836006] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.839396] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.839730] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgsodium> server # [ 0.839855] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.839989] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.840146] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pgsodium> server # [ 0.840664] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.840807] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgsodium> server # [ 0.841124] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.841263] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.841763] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.841907] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pgsodium> server # [ 0.842000] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.842110] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.842537] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.842679] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgsodium> server # [ 0.843170] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.843294] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.843758] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.843905] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgsodium> server # [ 0.843997] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.844106] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.844573] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.844714] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-pgsodium> server # [ 0.845110] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.846333] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.847318] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.847553] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgsodium> server # [ 0.847655] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.847764] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.847870] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pgsodium> server # [ 0.848335] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.848792] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.850116] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.851235] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.851811] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.852398] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.854257] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.854664] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.855476] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.855721] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pgsodium> server # [ 0.855818] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.855931] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.856411] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pgsodium> server # [ 0.856635] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgsodium> server # [ 0.856736] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgsodium> server # [ 0.858093] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgsodium> server # [ 0.860246] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-pgsodium> server # [ 0.860508] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-pgsodium> server # [ 0.860687] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.861447] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.862311] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.862541] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.862673] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.862804] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.863460] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.863601] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.863807] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.863953] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.864118] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-pgsodium> server # [ 0.864730] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-pgsodium> server # [ 0.864849] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-pgsodium> server # [ 0.864970] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-pgsodium> server # [ 0.865083] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-pgsodium> server # [ 0.865188] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-pgsodium> server # [ 0.865294] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-pgsodium> server # [ 0.866340] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-pgsodium> server # [ 0.866571] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-pgsodium> server # [ 0.866822] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-pgsodium> server # [ 0.867286] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-pgsodium> server # [ 0.867733] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-pgsodium> server # [ 0.868056] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-pgsodium> server # [ 0.868176] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-pgsodium> server # [ 0.868295] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-pgsodium> server # [ 0.868394] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-pgsodium> server # [ 0.868497] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-pgsodium> server # [ 0.868712] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-pgsodium> server # [ 0.868827] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-pgsodium> server # [ 0.869018] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-pgsodium> server # [ 0.869125] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-pgsodium> server # [ 0.869228] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-pgsodium> server # [ 0.869548] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-pgsodium> server # [ 0.869782] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-pgsodium> server # [ 0.870137] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-pgsodium> server # [ 0.871817] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-pgsodium> server # [ 0.937078] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.940570] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.943341] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.944897] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.948469] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.952455] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.954521] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.956427] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-pgsodium> server # [ 0.958587] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-pgsodium> server # [ 0.960273] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.963149] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-pgsodium> server # [ 0.981691] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-pgsodium> server # [ 0.992505] msm_serial: driver initialized vm-test-run-pgsodium> server # [ 0.993594] SuperH (H)SCI(F) driver initialized vm-test-run-pgsodium> server # [ 0.994088] STM32 USART driver initialized vm-test-run-pgsodium> server # [ 1.048911] loop: module loaded vm-test-run-pgsodium> server # [ 1.050008] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-pgsodium> server # [ 1.055070] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-pgsodium> server # [ 1.068854] megasas: 07.727.03.00-rc1 vm-test-run-pgsodium> server # [ 1.074494] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-pgsodium> server # [ 1.076632] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pgsodium> server # [ 1.077182] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pgsodium> server # [ 1.078953] Using buffer write method vm-test-run-pgsodium> server # [ 1.080426] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-pgsodium> server # [ 1.081442] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pgsodium> server # [ 1.082319] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pgsodium> server # [ 1.084005] Using buffer write method vm-test-run-pgsodium> server # [ 1.084232] Concatenating MTD devices: vm-test-run-pgsodium> server # [ 1.084315] (0): "0.flash" vm-test-run-pgsodium> server # [ 1.084376] (1): "0.flash" vm-test-run-pgsodium> server # [ 1.084433] into device "0.flash" vm-test-run-pgsodium> server # [ 1.094705] Freeing initrd memory: 10684K vm-test-run-pgsodium> server # [ 1.117429] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-pgsodium> server # [ 1.130733] thunder_xcv, ver 1.0 vm-test-run-pgsodium> server # [ 1.131054] thunder_bgx, ver 1.0 vm-test-run-pgsodium> server # [ 1.131234] nicpf, ver 1.0 vm-test-run-pgsodium> server # [ 1.134077] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-pgsodium> server # [ 1.134216] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-pgsodium> server # [ 1.134709] hclge is initializing vm-test-run-pgsodium> server # [ 1.135207] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-pgsodium> server # [ 1.135293] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-pgsodium> server # [ 1.135519] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-pgsodium> server # [ 1.135599] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-pgsodium> server # [ 1.135813] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-pgsodium> server # [ 1.135913] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-pgsodium> server # [ 1.136109] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-pgsodium> server # [ 1.136363] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-pgsodium> server # [ 1.137676] sky2: driver version 1.30 vm-test-run-pgsodium> server # [ 1.147366] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-pgsodium> server # [ 1.147895] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-pgsodium> server # [ 1.148211] usbcore: registered new interface driver usb-storage vm-test-run-pgsodium> server # [ 1.148788] usbcore: registered new interface driver usbserial_generic vm-test-run-pgsodium> server # [ 1.149759] usbserial: USB Serial support registered for generic vm-test-run-pgsodium> server # [ 1.151717] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-pgsodium> server # [ 1.160592] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-pgsodium> server # [ 1.162241] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-pgsodium> server # [ 1.162235] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T04:45:29 UTC (1791434729) vm-test-run-pgsodium> server # [ 1.165025] i2c_dev: i2c /dev entries driver vm-test-run-pgsodium> server # [ 1.169165] hub 1-0:1.0: USB hub found vm-test-run-pgsodium> server # [ 1.169769] hub 1-0:1.0: 6 ports detected vm-test-run-pgsodium> server # [ 1.182178] sdhci: Secure Digital Host Controller Interface driver vm-test-run-pgsodium> server # [ 1.182346] sdhci: Copyright(c) Pierre Ossman vm-test-run-pgsodium> server # [ 1.184310] Synopsys Designware Multimedia Card Interface Driver vm-test-run-pgsodium> server # [ 1.187143] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-pgsodium> server # [ 1.194725] hid: raw HID events driver (C) Jiri Kosina vm-test-run-pgsodium> server # [ 1.196674] usbcore: registered new interface driver usbhid vm-test-run-pgsodium> server # [ 1.197334] usbhid: USB HID core driver vm-test-run-pgsodium> server # [ 1.207467] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-pgsodium> server # [ 1.223320] drop_monitor: Initializing network drop monitor service vm-test-run-pgsodium> server # [ 1.224732] NET: Registered PF_INET6 protocol family vm-test-run-pgsodium> server # [ 1.244673] Segment Routing with IPv6 vm-test-run-pgsodium> server # [ 1.245219] In-situ OAM (IOAM) with IPv6 vm-test-run-pgsodium> server # [ 1.246483] NET: Registered PF_PACKET protocol family vm-test-run-pgsodium> server # [ 1.248381] 9pnet: Installing 9P2000 support vm-test-run-pgsodium> server # [ 1.253959] Key type dns_resolver registered vm-test-run-pgsodium> server # [ 1.293540] registered taskstats version 1 vm-test-run-pgsodium> server # [ 1.295656] Loading compiled-in X.509 certificates vm-test-run-pgsodium> server # [ 1.356528] Demotion targets for Node 0: null vm-test-run-pgsodium> server # [ 1.359796] Key type .fscrypt registered vm-test-run-pgsodium> server # [ 1.359949] Key type fscrypt-provisioning registered vm-test-run-pgsodium> server # [ 1.370371] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-pgsodium> server # [ 1.418303] clk: Disabling unused clocks vm-test-run-pgsodium> server # [ 1.418616] PM: genpd: Disabling unused power domains vm-test-run-pgsodium> server # [ 1.419018] ALSA device list: vm-test-run-pgsodium> server # [ 1.419154] No soundcards found. vm-test-run-pgsodium> server # [ 1.422384] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-pgsodium> server # [ 1.510446] Freeing unused kernel memory: 4416K vm-test-run-pgsodium> server # [ 1.512224] Run /init as init process vm-test-run-pgsodium> server # [ 1.621503] 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-pgsodium> server # [ 1.748104] 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-pgsodium> server # [ 1.869401] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-pgsodium> server # [ 2.040064] 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-pgsodium> server # [ 2.044360] 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-pgsodium> server # [ 2.148445] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-pgsodium> server # [ 2.290344] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-pgsodium> server # [ 2.291111] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-pgsodium> server # [ 2.291133] [drm] features: -context_init vm-test-run-pgsodium> server # [ 2.293124] [drm] number of scanouts: 1 vm-test-run-pgsodium> server # [ 2.293248] [drm] number of cap sets: 0 vm-test-run-pgsodium> server # [ 2.307150] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-pgsodium> server # [ 2.342590] Console: switching to colour frame buffer device 160x50 vm-test-run-pgsodium> server # [ 2.355918] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-pgsodium> server # [ 5.321981] EXT4-fs (vda): mounted filesystem ff832013-fadc-4fa4-8d3b-2845bb187e78 r/w with ordered data mode. Quota mode: none. vm-test-run-pgsodium> server # [ 7.814208] EXT4-fs (vda): re-mounted ff832013-fadc-4fa4-8d3b-2845bb187e78. vm-test-run-pgsodium> server # [ 7.832713] booting system configuration /nix/store/h6wy4m62pihbkb0bvw3lxlhndi980nlk-nixos-system-server-test vm-test-run-pgsodium> server # [ 20.499505] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) vm-test-run-pgsodium> server # [ 20.509625] systemd[1]: Detected virtualization qemu. vm-test-run-pgsodium> server # [ 20.514051] systemd[1]: Detected architecture arm64. vm-test-run-pgsodium> server # [ 20.519001] systemd[1]: Detected first boot. vm-test-run-pgsodium> server # [ 20.546758] systemd[1]: Initializing machine ID from random generator. vm-test-run-pgsodium> server # [ 20.576593] systemd[1]: Hostname set to . vm-test-run-pgsodium> server # [ 21.807007] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-pgsodium> server # [ 23.170773] systemd[1]: Applying preset policy. vm-test-run-pgsodium> server # [ 26.912559] systemd[1]: Populated /etc with preset unit settings. vm-test-run-pgsodium> server: Guest root shell did not produce any data yet... vm-test-run-pgsodium> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-pgsodium> server # [ 30.411171] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-pgsodium> server # [ 30.649663] systemd[1]: Created slice Slice /system/getty. vm-test-run-pgsodium> server # [ 30.666042] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-pgsodium> server # [ 30.679556] systemd[1]: Created slice User and Session Slice. vm-test-run-pgsodium> server # [ 30.687719] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-pgsodium> server # [ 30.693583] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-pgsodium> server # [ 30.698949] systemd[1]: Expecting device /dev/hvc0... vm-test-run-pgsodium> server # [ 30.703568] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-pgsodium> server # [ 30.708673] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-pgsodium> server # [ 30.713558] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-pgsodium> server # [ 30.718450] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-pgsodium> server # [ 30.723281] systemd[1]: Reached target Path Units. vm-test-run-pgsodium> server # [ 30.727901] systemd[1]: Reached target Remote File Systems. vm-test-run-pgsodium> server # [ 30.732467] systemd[1]: Reached target Slice Units. vm-test-run-pgsodium> server # [ 30.736388] systemd[1]: Reached target Swaps. vm-test-run-pgsodium> server # [ 30.772466] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-pgsodium> server # [ 30.797348] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-pgsodium> server # [ 30.808247] systemd[1]: Listening on Journal Audit Socket. vm-test-run-pgsodium> server # [ 30.815749] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-pgsodium> server # [ 30.821942] systemd[1]: Listening on Journal Sockets. vm-test-run-pgsodium> server # [ 30.831065] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-pgsodium> server # [ 30.835849] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgsodium> server # [ 30.842087] systemd[1]: Listening on udev Control Socket. vm-test-run-pgsodium> server # [ 30.847877] systemd[1]: Listening on udev Kernel Socket. vm-test-run-pgsodium> server # [ 30.900597] systemd[1]: Mounting Huge Pages File System... vm-test-run-pgsodium> server # [ 30.946417] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-pgsodium> server # [ 30.995460] systemd[1]: Mounting Kernel Debug File System... vm-test-run-pgsodium> server # [ 31.107172] systemd[1]: Mounting Kernel Trace File System... vm-test-run-pgsodium> server # [ 31.241486] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-pgsodium> server # [ 31.364065] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-pgsodium> server # [ 31.376731] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-pgsodium> server # [ 31.496020] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-pgsodium> server # [ 31.518797] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-pgsodium> server # [ 31.536910] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-pgsodium> server # [ 31.615595] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-pgsodium> server # [ 31.630450] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-pgsodium> server # [ 31.649754] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). vm-test-run-pgsodium> server # [ 31.758815] systemd[1]: Starting Journal Service... vm-test-run-pgsodium> server # [ 31.838140] systemd[1]: Starting Load Kernel Modules... vm-test-run-pgsodium> server # [ 31.926974] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-pgsodium> server # [ 31.982905] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-pgsodium> server # [ 31.988202] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgsodium> server # [ 32.050761] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-pgsodium> server # [ 32.126565] systemd[1]: Mounted Huge Pages File System. vm-test-run-pgsodium> server # [ 32.136412] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-pgsodium> server # [ 32.150281] systemd[1]: Mounted Kernel Debug File System. vm-test-run-pgsodium> server # [ 32.160186] systemd[1]: Mounted Kernel Trace File System. vm-test-run-pgsodium> server # [ 32.184279] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-pgsodium> server # [ 32.208318] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-pgsodium> server # [ 32.267036] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-pgsodium> server # [ 32.651011] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-pgsodium> server # [ 32.672845] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-pgsodium> server # [ 33.019216] fuse: init (API version 7.41) vm-test-run-pgsodium> server # [ 33.082979] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-pgsodium> server # [ 33.099926] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-pgsodium> server # [ 33.160593] systemd[1]: Mounting FUSE Control File System... vm-test-run-pgsodium> server # [ 33.188700] systemd[1]: Finished Load Kernel Modules. vm-test-run-pgsodium> server # [ 33.278058] systemd[1]: Starting Firewall... vm-test-run-pgsodium> server # [ 33.335345] systemd[1]: Starting Apply Kernel Variables... vm-test-run-pgsodium> server # [ 33.349560] EXT4-fs (vda): re-mounted ff832013-fadc-4fa4-8d3b-2845bb187e78. vm-test-run-pgsodium> server # [ 33.399737] systemd-journald[398]: Collecting audit messages is enabled. vm-test-run-pgsodium> server # [ 33.465807] systemd[1]: Mounted FUSE Control File System. vm-test-run-pgsodium> server # [ 33.498749] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-pgsodium> server # [ 33.511524] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-pgsodium> server # [ 33.568336] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-pgsodium> server # [ 33.578500] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgsodium> server # [ 34.483280] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-pgsodium> server # [ 34.506632] systemd[1]: Reached target First Boot Complete. vm-test-run-pgsodium> server # [ 34.674904] systemd[1]: Finished Apply Kernel Variables. vm-test-run-pgsodium> server # [ 34.764474] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-pgsodium> server # [ 34.848989] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-pgsodium> server # [ 34.864625] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-pgsodium> server # [ 35.783128] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-pgsodium> server # [ 35.808628] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-pgsodium> server # [ 35.850701] systemd[1]: Mounting /run/wrappers... vm-test-run-pgsodium> server # [ 35.902113] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-pgsodium> server # [ 36.359580] systemd[1]: Mounted /run/wrappers. vm-test-run-pgsodium> server # [ 36.368548] systemd[1]: Reached target Local File Systems. vm-test-run-pgsodium> server # [ 36.406756] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-pgsodium> server # [ 36.351938] systemd-modules-load[399]: Module 'atkbd' is built in vm-test-run-pgsodium> server # [ 36.358762] systemd-modules-load[399]: Module 'loop' is built in vm-test-run-pgsodium> server # [ 36.370457] systemd-oomd[400]: No swap; memory pressure usage will be degraded vm-test-run-pgsodium> server # [ 36.472318] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pgsodium> server # [ 36.478599] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-pgsodium> server # [ 36.529029] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-pgsodium> server # [ 36.550259] systemd[1]: Started Journal Service. vm-test-run-pgsodium> server # [ 36.867455] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-pgsodium> server # [ 37.521790] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-pgsodium> server # [ 37.611958] systemd-udevd[425]: Using default interface naming scheme 'v258'. vm-test-run-pgsodium> server # [ 37.623300] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-pgsodium> server # [ 37.877396] systemd-journald[398]: Received client request to flush runtime journal. vm-test-run-pgsodium> server # [ 38.411045] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-pgsodium> server # [ 38.450718] systemd[1]: Starting Create System Files and Directories... vm-test-run-pgsodium> server # [ 40.331411] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-pgsodium> server # [ 40.741475] systemd[1]: Finished Create System Files and Directories. vm-test-run-pgsodium> server # [ 40.829911] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-pgsodium> server # [ 40.880710] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-pgsodium> server # [ 41.861480] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-pgsodium> server # [ 43.003496] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-pgsodium> server # [ 43.093794] systemd[1]: Starting Update is Completed... vm-test-run-pgsodium> server # [ 43.812272] systemd[1]: Finished Update is Completed. vm-test-run-pgsodium> server # [ 46.889784] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pgsodium> server # [ 46.898846] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pgsodium> server # [ 46.902710] systemd[1]: suid-sgid-wrappers.service: Consumed 2.901s CPU time, 5.6M memory peak. vm-test-run-pgsodium> server # [ 49.119494] systemd[1]: Finished Firewall. vm-test-run-pgsodium> server # [ 50.867752] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-pgsodium> server # [ 50.879539] systemd[1]: Reached target System Initialization. vm-test-run-pgsodium> server # [ 50.894690] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-pgsodium> server # [ 50.903144] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-pgsodium> server # [ 50.914274] systemd[1]: Reached target Timer Units. vm-test-run-pgsodium> server # [ 50.928969] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-pgsodium> server # [ 50.930868] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-pgsodium> server # [ 50.931863] systemd[1]: Listening on Hostname Service Socket. vm-test-run-pgsodium> server # [ 50.937996] systemd[1]: Reached target Socket Units. vm-test-run-pgsodium> server # [ 50.939497] systemd[1]: Reached target Basic System. vm-test-run-pgsodium> server # [ 50.952773] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-pgsodium> server # [ 51.000989] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pgsodium> server # [ 51.076098] systemd[1]: Started Reset console on configuration changes. vm-test-run-pgsodium> server # [ 51.103080] systemd[1]: Starting resolvconf update... vm-test-run-pgsodium> server # [ 51.150779] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-pgsodium> server # [ 52.162505] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-pgsodium> server # [ 52.445523] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pgsodium> server # [ 52.458008] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pgsodium> server # [ 52.487274] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pgsodium> server # [ 52.538983] nsncd[630]: Oct 08 04:46:20.846 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pgsodium> server # [ 52.553556] systemd[1]: Starting User Login Management... vm-test-run-pgsodium> server # [ 52.613311] dbus-daemon[633]: dbus[633]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-pgsodium> server # [ 53.117280] systemd[1]: Started D-Bus System Message Bus. vm-test-run-pgsodium> server # [ 53.890559] systemd-logind[649]: New seat seat0. vm-test-run-pgsodium> server # [ 53.943960] systemd[1]: Started User Login Management. vm-test-run-pgsodium> server # [ 53.994444] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-pgsodium> server # [ 54.001918] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-pgsodium> server # [ 54.005903] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-pgsodium> server # [ 54.009603] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-pgsodium> server # [ 54.035714] systemd[1]: Starting linger-users.service... vm-test-run-pgsodium> server # [ 54.066815] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-pgsodium> server # [ 54.130262] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-pgsodium> server # [ 54.145352] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-pgsodium> server # [ 54.289874] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pgsodium> server # [ 54.649794] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pgsodium> server # [ 54.660647] systemd[1]: Finished linger-users.service. vm-test-run-pgsodium> server # [ 54.955025] nsncd[692]: Oct 08 04:46:23.373 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pgsodium> server # [ 54.998979] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pgsodium> server # [ 55.010916] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pgsodium> server # [ 55.029921] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pgsodium> server # [ 55.479070] systemd[1]: Finished resolvconf update. vm-test-run-pgsodium> server # [ 55.497313] systemd[1]: Reached target Preparation for Network. vm-test-run-pgsodium> server # [ 55.542342] systemd[1]: Starting DHCP Client... vm-test-run-pgsodium> server # [ 56.733836] dhcpcd[741]: dhcpcd-10.2.4 starting vm-test-run-pgsodium> server # [ 56.813771] dhcpcd[745]: dev: loaded udev vm-test-run-pgsodium> server # [ 57.214830] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-pgsodium> server # [ 57.802444] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-pgsodium> server # [ 58.060300] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-pgsodium> server # [ 58.071006] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-pgsodium> server # [ 58.091247] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-pgsodium> server # [ 58.103015] cfg80211: failed to load regulatory.db vm-test-run-pgsodium> server # [ 58.260490] systemd[1]: Found device /dev/hvc0. vm-test-run-pgsodium> server # [ 58.872082] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-pgsodium> server # [ 58.809309] dhcpcd[745]: eth0: waiting for carrier vm-test-run-pgsodium> server # [ 58.814079] dhcpcd[745]: eth0: carrier acquired vm-test-run-pgsodium> server # [ 58.927190] dhcpcd[745]: DUID 00:01:00:01:32:59:dc:a3:52:54:00:12:34:56 vm-test-run-pgsodium> server # [ 58.929933] dhcpcd[745]: eth0: IAID 00:12:34:56 vm-test-run-pgsodium> server # [ 58.933676] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-pgsodium> server: Guest root shell did not produce any data yet... vm-test-run-pgsodium> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-pgsodium> server # [ 59.060621] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-pgsodium> server # [ 59.105778] systemd[1]: Started backdoor.service. vm-test-run-pgsodium> server # connecting to host... vm-test-run-pgsodium> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-pgsodium> server: connected to guest root shell vm-test-run-pgsodium> server: (connecting took 60.92 seconds) vm-test-run-pgsodium> server: (finished: waiting for the VM to finish booting, in 60.92 seconds) vm-test-run-pgsodium> server # [ 60.004221] dhcpcd[745]: eth0: soliciting an IPv6 router vm-test-run-pgsodium> server # [ 60.007991] dhcpcd[745]: eth0: Router Advertisement from fe80::2 vm-test-run-pgsodium> server # [ 60.016042] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-pgsodium> server # [ 60.023682] dhcpcd[745]: eth0: adding route to fec0::/64 vm-test-run-pgsodium> server # [ 60.034203] dhcpcd[745]: eth0: adding default route via fe80::2 vm-test-run-pgsodium> server # [ 60.728977] dhcpcd[745]: eth0: soliciting a DHCP lease vm-test-run-pgsodium> server # [ 60.782253] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-pgsodium> server # [ 60.799034] dhcpcd[745]: eth0: probing address 10.0.2.15/24 vm-test-run-pgsodium> server # [ 61.926286] (udev-worker)[791]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-pgsodium> server # [ 61.932506] (udev-worker)[791]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgsodium> server # [ 61.955886] (udev-worker)[790]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgsodium> server # [ 62.714401] dhcpcd[745]: eth0: IAID 00:12:34:56 vm-test-run-pgsodium> server # [ 62.928861] systemd[1]: Found device Virtio network device. vm-test-run-pgsodium> server # [ 62.949238] systemd[1]: Starting Address configuration of eth1... vm-test-run-pgsodium> server # [ 63.203291] dhcpcd[745]: eth0: soliciting an IPv6 router vm-test-run-pgsodium> server # [ 63.959026] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-pgsodium> server # [ 64.182741] network-addresses-eth1-start[828]: adding address 192.168.1.1/24... done vm-test-run-pgsodium> server # [ 64.194944] dhcpcd[745]: eth0: soliciting a DHCP lease vm-test-run-pgsodium> server # [ 64.195786] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-pgsodium> server # [ 64.202866] dhcpcd[745]: eth0: probing address 10.0.2.15/24 vm-test-run-pgsodium> server # [ 64.324478] network-addresses-eth1-start[828]: adding address 2001:db8:1::1/64... done vm-test-run-pgsodium> server # [ 64.486515] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-pgsodium> server # [ 64.559746] systemd[1]: Finished Address configuration of eth1. vm-test-run-pgsodium> server # [ 64.600137] systemd[1]: Starting Networking Setup... vm-test-run-pgsodium> server # [ 66.136140] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-pgsodium> server # [ 66.149181] dhcpcd[745]: eth0: adding route to 10.0.2.0/24 vm-test-run-pgsodium> server # [ 66.155227] dhcpcd[745]: eth0: adding default route via 10.0.2.2 vm-test-run-pgsodium> server # [ 66.818034] dhcpcd[906]: /nix/store/hsj50yr7hr62ql9xaynbihkhfhbgprcn-openresolv-3.17.0/sbin/.resolvconf-wrapped: line 1221: kill: (867) - Operation not permitted vm-test-run-pgsodium> server # [ 66.827266] dhcpcd[906]: clearing stale lock pid 867 vm-test-run-pgsodium> server # [ 67.236172] systemd[1]: Finished Networking Setup. vm-test-run-pgsodium> server # [ 67.270968] systemd[1]: Reached target Network. vm-test-run-pgsodium> server # [ 67.301876] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-pgsodium> server # [ 67.361344] systemd[1]: Starting Permit User Sessions... vm-test-run-pgsodium> server # [ 68.075255] systemd[1]: Finished Permit User Sessions. vm-test-run-pgsodium> server # [ 68.161829] systemd[1]: Started Getty on tty1. vm-test-run-pgsodium> server # [ 68.169990] systemd[1]: Reached target Login Prompts. vm-test-run-pgsodium> server # [ 69.211003] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[972]: Initializing database at /var/lib/postgresql/data vm-test-run-pgsodium> server # [ 69.954928] systemd[1]: Started DHCP Client. vm-test-run-pgsodium> server # [ 71.150739] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pgsodium> server # [ 71.155764] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: This user must also own the server process. vm-test-run-pgsodium> server # [ 71.257372] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-pgsodium> server # [ 71.258670] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The default database encoding has accordingly been set to "UTF8". vm-test-run-pgsodium> server # [ 71.259128] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The default text search configuration will be set to "english". vm-test-run-pgsodium> server # [ 71.259867] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: Data page checksums are enabled. vm-test-run-pgsodium> server # [ 71.269692] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-pgsodium> server # [ 71.280044] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: creating subdirectories ... ok vm-test-run-pgsodium> server # [ 71.298784] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting dynamic shared memory implementation ... posix vm-test-run-pgsodium> server # [ 72.610681] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting default max_connections ... 100 vm-test-run-pgsodium> server # [ 72.956968] mousedev: PS/2 mouse device common for all mice vm-test-run-pgsodium> server # [ 73.308177] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting default shared_buffers ... 128MB vm-test-run-pgsodium> server # [ 74.558682] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-pgsodium> server # [ 83.675446] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting default time zone ... UTC vm-test-run-pgsodium> server # [ 83.711655] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: creating configuration files ... ok vm-test-run-pgsodium> server # [ 87.527199] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: running bootstrap script ... ok vm-test-run-pgsodium> server # [ 98.011200] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: performing post-bootstrap initialization ... ok vm-test-run-pgsodium> server # [ 100.745222] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: syncing data to disk ... ok vm-test-run-pgsodium> server # [ 100.749156] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pgsodium> server # [ 100.751192] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: 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-pgsodium> server # [ 100.753145] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: Success. You can now start the database server using: vm-test-run-pgsodium> server # [ 100.754151] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: /nix/store/bk54g9x4hp9fnfqyvvkdl7p156ryl73n-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-pgsodium> server # [ 102.551224] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[972]: Validating PostgreSQL configuration... vm-test-run-pgsodium> server # [ 103.003359] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[1342]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault vm-test-run-pgsodium> server # [ 103.735455] postgres[1345]: 2026-10-08 04:47:12.155 UTC [1345] LOG: pgaudit extension initialized vm-test-run-pgsodium> server # [ 104.775148] postgres[1345]: 2026-10-08 04:47:13.189 UTC [1345] LOG: pgsodium primary server secret key loaded vm-test-run-pgsodium> server # [ 105.340847] postgres[1345]: 2026-10-08 04:47:13.771 UTC [1345] LOG: vault primary server secret key loaded vm-test-run-pgsodium> server # [ 105.674264] postgres[1345]: 2026-10-08 04:47:14.104 UTC [1345] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pgsodium> server # [ 105.685092] postgres[1345]: 2026-10-08 04:47:14.116 UTC [1345] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-pgsodium> server # [ 105.688652] postgres[1345]: 2026-10-08 04:47:14.119 UTC [1345] LOG: listening on IPv6 address "::", port 5432 vm-test-run-pgsodium> server # [ 105.704868] postgres[1345]: 2026-10-08 04:47:14.134 UTC [1345] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pgsodium> server # [ 105.785235] postgres[1379]: 2026-10-08 04:47:14.213 UTC [1379] LOG: database system was shut down at 2026-10-08 04:47:06 UTC vm-test-run-pgsodium> server # [ 105.963659] postgres[1345]: 2026-10-08 04:47:14.380 UTC [1345] LOG: database system is ready to accept connections vm-test-run-pgsodium> server # [ 105.988002] postgres[1383]: 2026-10-08 04:47:14.414 UTC [1383] FATAL: role "postgres" does not exist vm-test-run-pgsodium> server # [ 105.999314] systemd[1]: Started PostgreSQL Database Server. vm-test-run-pgsodium> server # [ 106.003856] postgres[1382]: 2026-10-08 04:47:14.425 UTC [1382] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-pgsodium> server # [ 106.053590] systemd[1]: Starting Supabase Database Initialization... vm-test-run-pgsodium> server # [ 106.121012] postgres[1345]: 2026-10-08 04:47:14.551 UTC [1345] LOG: background worker "pg_net 0.20.4 worker" (PID 1383) exited with exit code 1 vm-test-run-pgsodium> server # [ 106.224608] postgres[1384]: 2026-10-08 04:47:14.649 UTC [1384] LOG: pg_cron scheduler started vm-test-run-pgsodium> server # [ 106.396986] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Waiting for PostgreSQL to be ready... vm-test-run-pgsodium> server # [ 106.842563] postgres[1405]: ::1 2026-10-08 04:47:15.268 UTC [1405] root@root FATAL: role "root" does not exist vm-test-run-pgsodium> server # [ 106.867464] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: PostgreSQL is ready vm-test-run-pgsodium> server # [ 106.878432] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Creating postgres role... vm-test-run-pgsodium> server # [ 107.170321] postgres[1417]: 2026-10-08 04:47:15.594 UTC [1417] FATAL: role "postgres" does not exist vm-test-run-pgsodium> server # [ 107.196413] postgres[1416]: ::1 2026-10-08 04:47:15.623 UTC [1416] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-pgsodium> server # [ 107.217347] postgres[1345]: 2026-10-08 04:47:15.632 UTC [1345] LOG: background worker "pg_net 0.20.4 worker" (PID 1417) exited with exit code 1 vm-test-run-pgsodium> server # [ 107.229631] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1410]: CREATE ROLE vm-test-run-pgsodium> server # [ 107.395288] postgres[1419]: ::1 2026-10-08 04:47:15.823 UTC [1419] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-pgsodium> server # [ 107.405274] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1418]: ALTER DATABASE vm-test-run-pgsodium> server # [ 107.435156] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-pgsodium> server # [ 107.601159] postgres[1421]: ::1 2026-10-08 04:47:16.023 UTC [1421] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-pgsodium> server # [ 107.614871] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE PUBLICATION vm-test-run-pgsodium> server # [ 107.616450] postgres[1421]: ::1 2026-10-08 04:47:16.042 UTC [1421] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-pgsodium> server # [ 107.622243] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE vm-test-run-pgsodium> server # [ 107.622998] postgres[1421]: ::1 2026-10-08 04:47:16.054 UTC [1421] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-pgsodium> server # [ 107.628546] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 107.631751] postgres[1421]: ::1 2026-10-08 04:47:16.060 UTC [1421] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-pgsodium> server # [ 107.633731] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 107.636615] postgres[1421]: ::1 2026-10-08 04:47:16.067 UTC [1421] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-pgsodium> server # [ 107.645675] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE vm-test-run-pgsodium> server # [ 107.651279] postgres[1421]: ::1 2026-10-08 04:47:16.079 UTC [1421] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-pgsodium> server # [ 107.657682] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT vm-test-run-pgsodium> server # [ 107.658922] postgres[1421]: ::1 2026-10-08 04:47:16.087 UTC [1421] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-pgsodium> server # [ 107.664270] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 107.667364] postgres[1421]: ::1 2026-10-08 04:47:16.097 UTC [1421] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-pgsodium> server # [ 107.671837] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE vm-test-run-pgsodium> server # [ 107.673359] postgres[1421]: ::1 2026-10-08 04:47:16.104 UTC [1421] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-pgsodium> server # [ 107.681182] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE SCHEMA vm-test-run-pgsodium> server # [ 107.682516] postgres[1421]: ::1 2026-10-08 04:47:16.113 UTC [1421] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-pgsodium> server # [ 109.389826] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE EXTENSION vm-test-run-pgsodium> server # [ 109.404344] postgres[1421]: ::1 2026-10-08 04:47:17.812 UTC [1421] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-pgsodium> server # [ 110.357774] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE EXTENSION vm-test-run-pgsodium> server # [ 110.359184] postgres[1421]: ::1 2026-10-08 04:47:18.786 UTC [1421] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-pgsodium> server # [ 110.373567] postgres[1421]: ::1 2026-10-08 04:47:18.801 UTC [1421] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-pgsodium> server # [ 110.374632] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 110.398550] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 110.416023] postgres[1421]: ::1 2026-10-08 04:47:18.818 UTC [1421] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-pgsodium> server # [ 110.418451] postgres[1421]: ::1 2026-10-08 04:47:18.827 UTC [1421] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-pgsodium> server # [ 110.419102] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 110.419455] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE vm-test-run-pgsodium> server # [ 110.419771] postgres[1421]: ::1 2026-10-08 04:47:18.840 UTC [1421] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-pgsodium> server # [ 110.426371] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE vm-test-run-pgsodium> server # [ 110.427188] postgres[1421]: ::1 2026-10-08 04:47:18.855 UTC [1421] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-pgsodium> server # [ 110.438967] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE vm-test-run-pgsodium> server # [ 110.447977] postgres[1421]: ::1 2026-10-08 04:47:18.871 UTC [1421] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-pgsodium> server # [ 110.458568] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE vm-test-run-pgsodium> server # [ 110.476136] postgres[1421]: ::1 2026-10-08 04:47:18.895 UTC [1421] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-pgsodium> server # [ 110.496968] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE vm-test-run-pgsodium> server # [ 110.509731] postgres[1421]: ::1 2026-10-08 04:47:18.919 UTC [1421] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.532885] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT vm-test-run-pgsodium> server # [ 110.535473] postgres[1421]: ::1 2026-10-08 04:47:18.963 UTC [1421] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.561605] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 110.562968] postgres[1421]: ::1 2026-10-08 04:47:18.993 UTC [1421] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.569735] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 110.573630] postgres[1421]: ::1 2026-10-08 04:47:19.004 UTC [1421] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.582875] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 110.587600] postgres[1421]: ::1 2026-10-08 04:47:19.018 UTC [1421] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.598823] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT vm-test-run-pgsodium> server # [ 110.599745] postgres[1421]: ::1 2026-10-08 04:47:19.031 UTC [1421] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-pgsodium> server # [ 110.606272] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE vm-test-run-pgsodium> server # [ 110.607340] postgres[1421]: ::1 2026-10-08 04:47:19.039 UTC [1421] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-pgsodium> server # [ 110.607809] postgres[1421]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.620101] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 110.626052] postgres[1421]: ::1 2026-10-08 04:47:19.051 UTC [1421] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-pgsodium> server # [ 110.626949] postgres[1421]: on tables to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.627379] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 110.631217] postgres[1421]: ::1 2026-10-08 04:47:19.062 UTC [1421] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-pgsodium> server # [ 110.636615] postgres[1421]: on functions to postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 110.642231] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 110.646612] postgres[1421]: ::1 2026-10-08 04:47:19.077 UTC [1421] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-pgsodium> server # [ 110.658929] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE vm-test-run-pgsodium> server # [ 110.659796] postgres[1421]: ::1 2026-10-08 04:47:19.091 UTC [1421] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-pgsodium> server # [ 110.666165] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE vm-test-run-pgsodium> server # [ 110.749780] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-pgsodium> server # [ 111.033127] postgres[1452]: ::1 2026-10-08 04:47:19.448 UTC [1452] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-pgsodium> server # [ 111.045018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE SCHEMA vm-test-run-pgsodium> server # [ 111.046924] postgres[1452]: ::1 2026-10-08 04:47:19.475 UTC [1452] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-pgsodium> server # [ 111.047605] postgres[1452]: instance_id uuid NULL, vm-test-run-pgsodium> server # [ 111.052685] postgres[1452]: id uuid NOT NULL UNIQUE, vm-test-run-pgsodium> server # [ 111.053597] postgres[1452]: aud varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.054271] postgres[1452]: "role" varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.055706] postgres[1452]: email varchar(255) NULL UNIQUE, vm-test-run-pgsodium> server # [ 111.060128] postgres[1452]: encrypted_password varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.065718] postgres[1452]: confirmed_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.066762] postgres[1452]: invited_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.067717] postgres[1452]: confirmation_token varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.071723] postgres[1452]: confirmation_sent_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.073731] postgres[1452]: recovery_token varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.074780] postgres[1452]: recovery_sent_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.075195] postgres[1452]: email_change_token varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.076303] postgres[1452]: email_change varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.076697] postgres[1452]: email_change_sent_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.082737] postgres[1452]: last_sign_in_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.083765] postgres[1452]: raw_app_meta_data jsonb NULL, vm-test-run-pgsodium> server # [ 111.091796] postgres[1452]: raw_user_meta_data jsonb NULL, vm-test-run-pgsodium> server # [ 111.093110] postgres[1452]: is_super_admin bool NULL, vm-test-run-pgsodium> server # [ 111.093995] postgres[1452]: created_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.096054] postgres[1452]: updated_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.103942] postgres[1452]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-pgsodium> server # [ 111.108291] postgres[1452]: ); vm-test-run-pgsodium> server # [ 111.159957] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE vm-test-run-pgsodium> server # [ 111.162903] postgres[1452]: ::1 2026-10-08 04:47:19.593 UTC [1452] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-pgsodium> server # [ 111.187692] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX vm-test-run-pgsodium> server # [ 111.192328] postgres[1452]: ::1 2026-10-08 04:47:19.618 UTC [1452] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-pgsodium> server # [ 111.216165] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX vm-test-run-pgsodium> server # [ 111.218391] postgres[1452]: ::1 2026-10-08 04:47:19.644 UTC [1452] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-pgsodium> server # [ 111.223497] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT vm-test-run-pgsodium> server # [ 111.231600] postgres[1452]: ::1 2026-10-08 04:47:19.658 UTC [1452] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-pgsodium> server # [ 111.237366] postgres[1452]: instance_id uuid NULL, vm-test-run-pgsodium> server # [ 111.243086] postgres[1452]: id bigserial NOT NULL, vm-test-run-pgsodium> server # [ 111.243940] postgres[1452]: "token" varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.249551] postgres[1452]: user_id varchar(255) NULL, vm-test-run-pgsodium> server # [ 111.250476] postgres[1452]: revoked bool NULL, vm-test-run-pgsodium> server # [ 111.251596] postgres[1452]: created_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.251931] postgres[1452]: updated_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.253100] postgres[1452]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-pgsodium> server # [ 111.253954] postgres[1452]: ); vm-test-run-pgsodium> server # [ 111.314264] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE vm-test-run-pgsodium> server # [ 111.320977] postgres[1452]: ::1 2026-10-08 04:47:19.748 UTC [1452] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-pgsodium> server # [ 111.351215] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX vm-test-run-pgsodium> server # [ 111.356685] postgres[1452]: ::1 2026-10-08 04:47:19.784 UTC [1452] 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-pgsodium> server # [ 111.377816] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX vm-test-run-pgsodium> server # [ 111.380096] postgres[1452]: ::1 2026-10-08 04:47:19.811 UTC [1452] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-pgsodium> server # [ 111.409192] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX vm-test-run-pgsodium> server # [ 111.411011] postgres[1452]: ::1 2026-10-08 04:47:19.839 UTC [1452] 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-pgsodium> server # [ 111.419657] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT vm-test-run-pgsodium> server # [ 111.425239] postgres[1452]: ::1 2026-10-08 04:47:19.853 UTC [1452] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-pgsodium> server # [ 111.426093] postgres[1452]: id uuid NOT NULL, vm-test-run-pgsodium> server # [ 111.427477] postgres[1452]: uuid uuid NULL, vm-test-run-pgsodium> server # [ 111.434601] postgres[1452]: raw_base_config text NULL, vm-test-run-pgsodium> server # [ 111.435344] postgres[1452]: created_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.436099] postgres[1452]: updated_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.442880] postgres[1452]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-pgsodium> server # [ 111.456021] postgres[1452]: ); vm-test-run-pgsodium> server # [ 111.491928] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE vm-test-run-pgsodium> server # [ 111.498033] postgres[1452]: ::1 2026-10-08 04:47:19.926 UTC [1452] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-pgsodium> server # [ 111.507018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT vm-test-run-pgsodium> server # [ 111.507905] postgres[1452]: ::1 2026-10-08 04:47:19.937 UTC [1452] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-pgsodium> server # [ 111.517700] postgres[1452]: instance_id uuid NULL, vm-test-run-pgsodium> server # [ 111.518598] postgres[1452]: id uuid NOT NULL, vm-test-run-pgsodium> server # [ 111.519518] postgres[1452]: payload json NULL, vm-test-run-pgsodium> server # [ 111.520114] postgres[1452]: created_at timestamptz NULL, vm-test-run-pgsodium> server # [ 111.521629] postgres[1452]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-pgsodium> server # [ 111.522604] postgres[1452]: ); vm-test-run-pgsodium> server # [ 111.609796] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE vm-test-run-pgsodium> server # [ 111.611135] postgres[1452]: ::1 2026-10-08 04:47:20.039 UTC [1452] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id); vm-test-run-pgsodium> server # [ 111.655451] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX vm-test-run-pgsodium> server # [ 111.657164] postgres[1452]: ::1 2026-10-08 04:47:20.087 UTC [1452] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-pgsodium> server # [ 111.667220] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT vm-test-run-pgsodium> server # [ 111.671445] postgres[1452]: ::1 2026-10-08 04:47:20.099 UTC [1452] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-pgsodium> server # [ 111.672307] postgres[1452]: "version" varchar(255) NOT NULL, vm-test-run-pgsodium> server # [ 111.687349] postgres[1452]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-pgsodium> server # [ 111.688635] postgres[1452]: ); vm-test-run-pgsodium> server # [ 111.699189] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE vm-test-run-pgsodium> server # [ 111.710391] postgres[1452]: ::1 2026-10-08 04:47:20.139 UTC [1452] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-pgsodium> server # [ 111.721365] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT vm-test-run-pgsodium> server # [ 111.745416] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: INSERT 0 7 vm-test-run-pgsodium> server # [ 111.754909] postgres[1452]: ::1 2026-10-08 04:47:20.182 UTC [1452] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-pgsodium> server # [ 111.761010] postgres[1452]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-pgsodium> server # [ 111.763044] postgres[1452]: $$ language sql stable; vm-test-run-pgsodium> server # [ 111.766948] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE FUNCTION vm-test-run-pgsodium> server # [ 111.770479] postgres[1452]: ::1 2026-10-08 04:47:20.198 UTC [1452] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-pgsodium> server # [ 111.771202] postgres[1452]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-pgsodium> server # [ 111.771577] postgres[1452]: $$ language sql stable; vm-test-run-pgsodium> server # [ 111.783770] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE FUNCTION vm-test-run-pgsodium> server # [ 111.786068] postgres[1452]: ::1 2026-10-08 04:47:20.217 UTC [1452] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-pgsodium> server # [ 111.787680] postgres[1452]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-pgsodium> server # [ 111.788573] postgres[1452]: $$ language sql stable; vm-test-run-pgsodium> server # [ 111.805957] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE FUNCTION vm-test-run-pgsodium> server # [ 111.807775] postgres[1452]: ::1 2026-10-08 04:47:20.233 UTC [1452] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 111.815263] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT vm-test-run-pgsodium> server # [ 111.820105] postgres[1452]: ::1 2026-10-08 04:47:20.248 UTC [1452] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-pgsodium> server # [ 111.837618] postgres[1452]: ::1 2026-10-08 04:47:20.265 UTC [1452] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.838678] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE ROLE vm-test-run-pgsodium> server # [ 111.847121] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT vm-test-run-pgsodium> server # [ 111.853704] postgres[1452]: ::1 2026-10-08 04:47:20.274 UTC [1452] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.859382] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT vm-test-run-pgsodium> server # [ 111.864091] postgres[1452]: ::1 2026-10-08 04:47:20.293 UTC [1452] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.871037] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT vm-test-run-pgsodium> server # [ 111.877044] postgres[1452]: ::1 2026-10-08 04:47:20.305 UTC [1452] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-pgsodium> server # [ 111.879263] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER ROLE vm-test-run-pgsodium> server # [ 111.895825] postgres[1452]: ::1 2026-10-08 04:47:20.316 UTC [1452] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.903759] postgres[1452]: ::1 2026-10-08 04:47:20.330 UTC [1452] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.913052] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE vm-test-run-pgsodium> server # [ 111.919199] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE vm-test-run-pgsodium> server # [ 111.925576] postgres[1452]: ::1 2026-10-08 04:47:20.356 UTC [1452] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.941821] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE vm-test-run-pgsodium> server # [ 111.946542] postgres[1452]: ::1 2026-10-08 04:47:20.375 UTC [1452] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.959199] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE vm-test-run-pgsodium> server # [ 111.964630] postgres[1452]: ::1 2026-10-08 04:47:20.394 UTC [1452] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 111.980210] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE vm-test-run-pgsodium> server # [ 112.044020] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-pgsodium> server # [ 112.305075] postgres[1458]: ::1 2026-10-08 04:47:20.725 UTC [1458] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-pgsodium> server # [ 112.327778] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: CREATE SCHEMA vm-test-run-pgsodium> server # [ 112.333470] postgres[1458]: ::1 2026-10-08 04:47:20.742 UTC [1458] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-pgsodium> server # [ 112.334367] postgres[1458]: ::1 2026-10-08 04:47:20.759 UTC [1458] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-pgsodium> server # [ 112.342019] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: CREATE ROLE vm-test-run-pgsodium> server # [ 112.347231] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: ALTER ROLE vm-test-run-pgsodium> server # [ 112.350411] postgres[1458]: ::1 2026-10-08 04:47:20.775 UTC [1458] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-pgsodium> server # [ 112.363214] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: GRANT vm-test-run-pgsodium> server # [ 112.390877] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: DO vm-test-run-pgsodium> server # [ 112.418466] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-pgsodium> server # [ 112.657376] postgres[1460]: ::1 2026-10-08 04:47:21.083 UTC [1460] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-pgsodium> server # [ 112.674601] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: ALTER ROLE vm-test-run-pgsodium> server # [ 112.677638] postgres[1460]: ::1 2026-10-08 04:47:21.101 UTC [1460] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-pgsodium> server # [ 112.680927] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: ALTER ROLE vm-test-run-pgsodium> server # [ 112.681990] postgres[1460]: ::1 2026-10-08 04:47:21.113 UTC [1460] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-pgsodium> server # [ 112.682910] postgres[1460]: RETURNS event_trigger vm-test-run-pgsodium> server # [ 112.684337] postgres[1460]: LANGUAGE plpgsql vm-test-run-pgsodium> server # [ 112.686449] postgres[1460]: AS $$ vm-test-run-pgsodium> server # [ 112.687451] postgres[1460]: DECLARE vm-test-run-pgsodium> server # [ 112.690354] postgres[1460]: schema_is_cron bool; vm-test-run-pgsodium> server # [ 112.691450] postgres[1460]: BEGIN vm-test-run-pgsodium> server # [ 112.692211] postgres[1460]: schema_is_cron = ( vm-test-run-pgsodium> server # [ 112.693614] postgres[1460]: SELECT n.nspname = 'cron' vm-test-run-pgsodium> server # [ 112.695716] postgres[1460]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgsodium> server # [ 112.697561] postgres[1460]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-pgsodium> server # [ 112.699414] postgres[1460]: ON ev.objid = n.oid vm-test-run-pgsodium> server # [ 112.701206] postgres[1460]: ); vm-test-run-pgsodium> server # [ 112.702330] postgres[1460]: vm-test-run-pgsodium> server # [ 112.705052] postgres[1460]: IF schema_is_cron vm-test-run-pgsodium> server # [ 112.705680] postgres[1460]: THEN vm-test-run-pgsodium> server # [ 112.706220] postgres[1460]: grant usage on schema cron to postgres with grant option; vm-test-run-pgsodium> server # [ 112.706788] postgres[1460]: vm-test-run-pgsodium> server # [ 112.707300] postgres[1460]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-pgsodium> server # [ 112.709399] postgres[1460]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-pgsodium> server # [ 112.710945] postgres[1460]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-pgsodium> server # [ 112.713581] postgres[1460]: vm-test-run-pgsodium> server # [ 112.713988] postgres[1460]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgsodium> server # [ 112.714600] postgres[1460]: on sequences to postgres with grant option; vm-test-run-pgsodium> server # [ 112.714907] postgres[1460]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgsodium> server # [ 112.716719] postgres[1460]: on tables to postgres with grant option; vm-test-run-pgsodium> server # [ 112.717166] postgres[1460]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgsodium> server # [ 112.717740] postgres[1460]: on functions to postgres with grant option; vm-test-run-pgsodium> server # [ 112.718770] postgres[1460]: vm-test-run-pgsodium> server # [ 112.720101] postgres[1460]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-pgsodium> server # [ 112.723085] postgres[1460]: vm-test-run-pgsodium> server # [ 112.723466] postgres[1460]: END IF; vm-test-run-pgsodium> server # [ 112.726640] postgres[1460]: vm-test-run-pgsodium> server # [ 112.729205] postgres[1460]: END; vm-test-run-pgsodium> server # [ 112.729573] postgres[1460]: $$; vm-test-run-pgsodium> server # [ 112.731386] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE FUNCTION vm-test-run-pgsodium> server # [ 112.732127] postgres[1460]: ::1 2026-10-08 04:47:21.163 UTC [1460] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA') vm-test-run-pgsodium> server # [ 112.733784] postgres[1460]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-pgsodium> server # [ 112.748724] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE EVENT TRIGGER vm-test-run-pgsodium> server # [ 112.749947] postgres[1460]: ::1 2026-10-08 04:47:21.180 UTC [1460] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-pgsodium> server # [ 112.759272] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: COMMENT vm-test-run-pgsodium> server # [ 112.763632] postgres[1460]: ::1 2026-10-08 04:47:21.194 UTC [1460] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-pgsodium> server # [ 112.769974] postgres[1460]: RETURNS event_trigger vm-test-run-pgsodium> server # [ 112.770690] postgres[1460]: LANGUAGE plpgsql vm-test-run-pgsodium> server # [ 112.771965] postgres[1460]: AS $$ vm-test-run-pgsodium> server # [ 112.773363] postgres[1460]: BEGIN vm-test-run-pgsodium> server # [ 112.774219] postgres[1460]: IF EXISTS ( vm-test-run-pgsodium> server # [ 112.775132] postgres[1460]: SELECT 1 vm-test-run-pgsodium> server # [ 112.777892] postgres[1460]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgsodium> server # [ 112.778632] postgres[1460]: JOIN pg_extension AS ext vm-test-run-pgsodium> server # [ 112.779523] postgres[1460]: ON ev.objid = ext.oid vm-test-run-pgsodium> server # [ 112.780885] postgres[1460]: WHERE ext.extname = 'pg_net' vm-test-run-pgsodium> server # [ 112.781848] postgres[1460]: ) vm-test-run-pgsodium> server # [ 112.782715] postgres[1460]: THEN vm-test-run-pgsodium> server # [ 112.783711] postgres[1460]: IF NOT EXISTS ( vm-test-run-pgsodium> server # [ 112.785017] postgres[1460]: SELECT 1 vm-test-run-pgsodium> server # [ 112.785389] postgres[1460]: FROM pg_roles vm-test-run-pgsodium> server # [ 112.786025] postgres[1460]: WHERE rolname = 'supabase_functions_admin' vm-test-run-pgsodium> server # [ 112.787856] postgres[1460]: ) vm-test-run-pgsodium> server # [ 112.789744] postgres[1460]: THEN vm-test-run-pgsodium> server # [ 112.790429] postgres[1460]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-pgsodium> server # [ 112.792145] postgres[1460]: END IF; vm-test-run-pgsodium> server # [ 112.793936] postgres[1460]: vm-test-run-pgsodium> server # [ 112.795109] postgres[1460]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-pgsodium> server # [ 112.797096] postgres[1460]: vm-test-run-pgsodium> server # [ 112.798725] postgres[1460]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-pgsodium> server # [ 112.801832] postgres[1460]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-pgsodium> server # [ 112.803829] postgres[1460]: vm-test-run-pgsodium> server # [ 112.804869] postgres[1460]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-pgsodium> server # [ 112.807222] postgres[1460]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-pgsodium> server # [ 112.807939] postgres[1460]: vm-test-run-pgsodium> server # [ 112.809211] postgres[1460]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-pgsodium> server # [ 112.810561] postgres[1460]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-pgsodium> server # [ 112.811082] postgres[1460]: vm-test-run-pgsodium> server # [ 112.811388] postgres[1460]: 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-pgsodium> server # [ 112.813698] postgres[1460]: 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-pgsodium> server # [ 112.814841] postgres[1460]: END IF; vm-test-run-pgsodium> server # [ 112.816174] postgres[1460]: END; vm-test-run-pgsodium> server # [ 112.816787] postgres[1460]: $$; vm-test-run-pgsodium> server # [ 112.817867] postgres[1460]: ::1 2026-10-08 04:47:21.215 UTC [1460] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-pgsodium> server # [ 112.819427] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE FUNCTION vm-test-run-pgsodium> server # [ 112.819989] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: COMMENT vm-test-run-pgsodium> server # [ 112.838628] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: DO vm-test-run-pgsodium> server # [ 112.839466] postgres[1460]: ::1 2026-10-08 04:47:21.270 UTC [1460] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-pgsodium> server # [ 112.846993] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE ROLE vm-test-run-pgsodium> server # [ 112.848999] postgres[1460]: ::1 2026-10-08 04:47:21.280 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-pgsodium> server # [ 112.857974] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.859895] postgres[1460]: ::1 2026-10-08 04:47:21.286 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-pgsodium> server # [ 112.866061] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.867804] postgres[1460]: ::1 2026-10-08 04:47:21.298 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-pgsodium> server # [ 112.882316] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.883471] postgres[1460]: ::1 2026-10-08 04:47:21.310 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-pgsodium> server # [ 112.895745] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.906498] postgres[1460]: ::1 2026-10-08 04:47:21.322 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-pgsodium> server # [ 112.913660] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.916567] postgres[1460]: ::1 2026-10-08 04:47:21.340 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-pgsodium> server # [ 112.927139] postgres[1460]: ::1 2026-10-08 04:47:21.355 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-pgsodium> server # [ 112.930755] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.931286] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.931784] postgres[1460]: ::1 2026-10-08 04:47:21.363 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-pgsodium> server # [ 112.963285] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 112.968360] postgres[1460]: ::1 2026-10-08 04:47:21.395 UTC [1460] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-pgsodium> server # [ 113.033033] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT vm-test-run-pgsodium> server # [ 113.057198] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: DO vm-test-run-pgsodium> server # [ 113.121766] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running pgbouncer auth schema... vm-test-run-pgsodium> server # [ 113.536547] postgres[1474]: ::1 2026-10-08 04:47:21.947 UTC [1474] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-pgsodium> server # [ 113.586376] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: CREATE ROLE vm-test-run-pgsodium> server # [ 113.588781] postgres[1474]: ::1 2026-10-08 04:47:21.998 UTC [1474] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-pgsodium> server # [ 113.605182] postgres[1474]: ::1 2026-10-08 04:47:22.029 UTC [1474] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-pgsodium> server # [ 113.605972] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: REVOKE vm-test-run-pgsodium> server # [ 113.690285] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: CREATE SCHEMA vm-test-run-pgsodium> server # [ 113.692135] postgres[1474]: ::1 2026-10-08 04:47:22.117 UTC [1474] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-pgsodium> server # [ 113.696880] postgres[1474]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-pgsodium> server # [ 113.699352] postgres[1474]: $$ vm-test-run-pgsodium> server # [ 113.705506] postgres[1474]: BEGIN vm-test-run-pgsodium> server # [ 113.706232] postgres[1474]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-pgsodium> server # [ 113.707836] postgres[1474]: vm-test-run-pgsodium> server # [ 113.721298] postgres[1474]: RETURN QUERY vm-test-run-pgsodium> server # [ 113.722672] postgres[1474]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-pgsodium> server # [ 113.723261] postgres[1474]: WHERE usename = p_usename; vm-test-run-pgsodium> server # [ 113.723691] postgres[1474]: END; vm-test-run-pgsodium> server # [ 113.724000] postgres[1474]: $$ LANGUAGE plpgsql vm-test-run-pgsodium> server # [ 113.727845] postgres[1474]: SET search_path = '' vm-test-run-pgsodium> server # [ 113.740035] postgres[1474]: SECURITY DEFINER; vm-test-run-pgsodium> server # [ 113.742391] postgres[1474]: ::1 2026-10-08 04:47:22.147 UTC [1474] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-pgsodium> server # [ 113.770712] postgres[1474]: ::1 2026-10-08 04:47:22.155 UTC [1474] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-pgsodium> server # [ 113.786961] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: CREATE FUNCTION vm-test-run-pgsodium> server # [ 113.802763] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: REVOKE vm-test-run-pgsodium> server # [ 113.814195] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: GRANT vm-test-run-pgsodium> server # [ 113.816060] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running stat extension... vm-test-run-pgsodium> server # [ 114.235490] postgres[1485]: ::1 2026-10-08 04:47:22.653 UTC [1485] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-pgsodium> server # [ 114.261018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1482]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-pgsodium> server # [ 114.328102] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1482]: CREATE SCHEMA vm-test-run-pgsodium> server # [ 114.329509] postgres[1485]: ::1 2026-10-08 04:47:22.759 UTC [1485] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-pgsodium> server # [ 115.193815] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1482]: CREATE EXTENSION vm-test-run-pgsodium> server # [ 115.255724] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-pgsodium> server # [ 115.568452] postgres[1494]: ::1 2026-10-08 04:47:23.992 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-pgsodium> server # [ 115.587498] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.593963] postgres[1494]: ::1 2026-10-08 04:47:24.016 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-pgsodium> server # [ 115.602916] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.603766] postgres[1494]: ::1 2026-10-08 04:47:24.031 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-pgsodium> server # [ 115.617476] postgres[1494]: ::1 2026-10-08 04:47:24.046 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-pgsodium> server # [ 115.619096] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.639821] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.645193] postgres[1494]: ::1 2026-10-08 04:47:24.069 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-pgsodium> server # [ 115.652742] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.654758] postgres[1494]: ::1 2026-10-08 04:47:24.084 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-pgsodium> server # [ 115.663917] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.674426] postgres[1494]: ::1 2026-10-08 04:47:24.101 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-pgsodium> server # [ 115.675514] postgres[1494]: ::1 2026-10-08 04:47:24.106 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-pgsodium> server # [ 115.676173] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.708025] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.723525] postgres[1494]: ::1 2026-10-08 04:47:24.141 UTC [1494] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-pgsodium> server # [ 115.755018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT vm-test-run-pgsodium> server # [ 115.784612] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: DO vm-test-run-pgsodium> server # [ 115.793176] postgres[1494]: ::1 2026-10-08 04:47:24.220 UTC [1494] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-pgsodium> server # [ 115.802701] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: ALTER ROLE vm-test-run-pgsodium> server # [ 115.849791] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-pgsodium> server # [ 116.212597] postgres[1498]: ::1 2026-10-08 04:47:24.624 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.244392] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.246702] postgres[1498]: ::1 2026-10-08 04:47:24.659 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.247944] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.254222] postgres[1498]: ::1 2026-10-08 04:47:24.681 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.262641] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.263785] postgres[1498]: ::1 2026-10-08 04:47:24.694 UTC [1498] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.271297] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE vm-test-run-pgsodium> server # [ 116.278822] postgres[1498]: ::1 2026-10-08 04:47:24.703 UTC [1498] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.282737] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE vm-test-run-pgsodium> server # [ 116.285100] postgres[1498]: ::1 2026-10-08 04:47:24.715 UTC [1498] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.294412] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE vm-test-run-pgsodium> server # [ 116.295253] postgres[1498]: ::1 2026-10-08 04:47:24.726 UTC [1498] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.302201] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE vm-test-run-pgsodium> server # [ 116.303140] postgres[1498]: ::1 2026-10-08 04:47:24.734 UTC [1498] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-pgsodium> server # [ 116.313069] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE vm-test-run-pgsodium> server # [ 116.313974] postgres[1498]: ::1 2026-10-08 04:47:24.745 UTC [1498] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-pgsodium> server # [ 116.319540] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.331407] postgres[1498]: ::1 2026-10-08 04:47:24.757 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-pgsodium> server # [ 116.335375] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.337141] postgres[1498]: ::1 2026-10-08 04:47:24.768 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-pgsodium> server # [ 116.342881] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.343969] postgres[1498]: ::1 2026-10-08 04:47:24.775 UTC [1498] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-pgsodium> server # [ 116.374496] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT vm-test-run-pgsodium> server # [ 116.383970] postgres[1498]: ::1 2026-10-08 04:47:24.812 UTC [1498] 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-pgsodium> server # [ 116.407405] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 116.421286] postgres[1498]: ::1 2026-10-08 04:47:24.835 UTC [1498] 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-pgsodium> server # [ 116.423385] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 116.425330] postgres[1498]: ::1 2026-10-08 04:47:24.849 UTC [1498] 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-pgsodium> server # [ 116.433440] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 116.478374] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-pgsodium> server # [ 116.820377] postgres[1515]: ::1 2026-10-08 04:47:25.245 UTC [1515] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-pgsodium> server # [ 116.864696] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1509]: CREATE SCHEMA vm-test-run-pgsodium> server # [ 116.892117] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-pgsodium> server # [ 117.172054] postgres[1520]: ::1 2026-10-08 04:47:25.581 UTC [1520] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-pgsodium> server # [ 117.212641] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT vm-test-run-pgsodium> server # [ 117.214525] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT vm-test-run-pgsodium> server # [ 117.215094] postgres[1520]: ::1 2026-10-08 04:47:25.633 UTC [1520] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-pgsodium> server # [ 117.219360] postgres[1520]: ::1 2026-10-08 04:47:25.650 UTC [1520] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-pgsodium> server # [ 117.224880] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT vm-test-run-pgsodium> server # [ 117.233819] postgres[1520]: ::1 2026-10-08 04:47:25.662 UTC [1520] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-pgsodium> server # [ 117.250279] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT vm-test-run-pgsodium> server # [ 117.283947] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-pgsodium> server # [ 117.610894] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1524]: DO vm-test-run-pgsodium> server # [ 117.619585] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1524]: DO vm-test-run-pgsodium> server # [ 117.634945] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1524]: DO vm-test-run-pgsodium> server # [ 117.679734] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-pgsodium> server # [ 117.943921] postgres[1528]: ::1 2026-10-08 04:47:26.371 UTC [1528] 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-pgsodium> server # [ 117.962997] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1527]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 117.964176] postgres[1528]: ::1 2026-10-08 04:47:26.388 UTC [1528] 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-pgsodium> server # [ 117.969719] postgres[1528]: ::1 2026-10-08 04:47:26.399 UTC [1528] 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-pgsodium> server # [ 117.971297] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1527]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 117.978348] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1527]: ALTER DEFAULT PRIVILEGES vm-test-run-pgsodium> server # [ 118.023142] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql