nixbot

builds

cancelled checks.aarch64-linux.ext-pgsodium build #199 · raw · eval 37.9 s · 2.8 GiB allocated · ·

1these 16 derivations will be built:2  /nix/store/i1fxkgmzfykhd1k9mkfgiciicpajl1g8-system-path.drv3  /nix/store/zb08r0ybz6gsszcba25l49mfw5rv74vp-dbus-1.drv4  /nix/store/y3b76rgfidgb1awwdlbfsljfg251x4pc-X-Restart-Triggers-dbus.drv5  /nix/store/1p3fs4gpcn940ndz1yvva356ggwnwkpa-unit-dbus.service.drv6  /nix/store/85h5qah6i369q4ajdsw14wj51qlg1214-user-units.drv7  /nix/store/0l23byrhsriywgnkqrs5v3f7cc29wa94-unit-dbus.service.drv8  /nix/store/jvj1fsapd1sgipj4mj8xh682zzf033jb-system-units.drv9  /nix/store/vyi06sbwn2hw2rdrgc8zccvnwd6c5rjk-etc.drv10  /nix/store/0hfcyam7amyh30z1rjlk0pjvnapkgyw4-activate.drv11  /nix/store/vi0zpn8dl53wmp5394r7xckc72f1583n-nixos-system-server-test.drv12  /nix/store/00lmwp9hmc5b41p3m4z100hr7z7vc7l4-nixos-system-server-test.drv13  /nix/store/9pir3xpy8qxmmya4c9pj6q91bn15rbv4-closure-info.drv14  /nix/store/psyhwc0bj54f9mhwvcc69kcb6m1kablh-run-nixos-vm.drv15  /nix/store/lyrq3icz1l4sgp5x7740815z6pfa3p0k-nixos-vm.drv16  /nix/store/d6d0slprdl3hpl7b2jz75w5zzvx5x182-nixos-test-driver-pgsodium.drv17  /nix/store/l3iv0n967fd4fm6w2fh7waxhp0p2h5c2-vm-test-run-pgsodium.drv18building '/nix/store/d6d0slprdl3hpl7b2jz75w5zzvx5x182-nixos-test-driver-pgsodium.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'19building '/nix/store/d6d0slprdl3hpl7b2jz75w5zzvx5x182-nixos-test-driver-pgsodium.drv'20nixos-test-driver-pgsodium> Running type check (enable/disable: config.skipTypeCheck)21nixos-test-driver-pgsodium> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck22nixos-test-driver-pgsodium> Success: no issues found in 1 source file23nixos-test-driver-pgsodium> additionally exposed symbols:24nixos-test-driver-pgsodium>     ,25nixos-test-driver-pgsodium>     ,26nixos-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, debug27nixos-test-driver-pgsodium> Linting test script (enable/disable: config.skipLint)28nixos-test-driver-pgsodium> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint29building '/nix/store/l3iv0n967fd4fm6w2fh7waxhp0p2h5c2-vm-test-run-pgsodium.drv' on 'ssh-ng://builder@mac-linux-builder'30building '/nix/store/l3iv0n967fd4fm6w2fh7waxhp0p2h5c2-vm-test-run-pgsodium.drv'31vm-test-run-pgsodium> Machine state will be reset. To keep it, pass --keep-vm-state32vm-test-run-pgsodium> start all VLans33vm-test-run-pgsodium> start vlan34vm-test-run-pgsodium> running vlan (pid 7; ctl /build/vde1.ctl)35vm-test-run-pgsodium> (finished: start all VLans, in 0.00 seconds)36vm-test-run-pgsodium> Test will time out and terminate in 3600 seconds37vm-test-run-pgsodium> run the VM test script38vm-test-run-pgsodium> additionally exposed symbols:39vm-test-run-pgsodium>     server,40vm-test-run-pgsodium>     vlan1,41vm-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, debug42vm-test-run-pgsodium> start all VMs43vm-test-run-pgsodium> server: starting vm44vm-test-run-pgsodium> mke2fs 1.47.3 (8-Jul-2025)45vm-test-run-pgsodium> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory46vm-test-run-pgsodium> qemu-system-aarch64: failed to initialize kvm: No such file or directory47vm-test-run-pgsodium> qemu-system-aarch64: falling back to tcg48vm-test-run-pgsodium> server # Disk image does not exist, creating the virtualisation disk image...49vm-test-run-pgsodium> server: QEMU running (pid 9)50vm-test-run-pgsodium> server # Formatting '/build/vm-state-server/tmp.mIWPfSAREH', fmt=raw size=107374182451vm-test-run-pgsodium> server # Discarding device blocks:      0/262144             done52vm-test-run-pgsodium> (finished: start all VMs, in 0.13 seconds)53vm-test-run-pgsodium> server # Creating filesystem with 262144 4k blocks and 65536 inodes54vm-test-run-pgsodium> server # Filesystem UUID: ff832013-fadc-4fa4-8d3b-2845bb187e7855vm-test-run-pgsodium> server # Superblock backups stored on blocks:56vm-test-run-pgsodium> server # 	32768, 98304, 163840, 22937657vm-test-run-pgsodium> server # 58vm-test-run-pgsodium> server # Allocating group tables: 0/8   done59vm-test-run-pgsodium> server # Writing inode tables: 0/8   done60vm-test-run-pgsodium> server # Creating journal (8192 blocks): done61vm-test-run-pgsodium> server # Writing superblocks and filesystem accounting information: 0/8   done62vm-test-run-pgsodium> server # 63vm-test-run-pgsodium> server # Virtualisation disk image created.64vm-test-run-pgsodium> server: waiting for unit supabase-db-init.service65vm-test-run-pgsodium> server: waiting for the VM to finish booting66vm-test-run-pgsodium> server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]67vm-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 202568vm-test-run-pgsodium> server # [    0.000000] KASLR enabled69vm-test-run-pgsodium> server # [    0.000000] random: crng init done70vm-test-run-pgsodium> server # [    0.000000] Machine model: linux,dummy-virt71vm-test-run-pgsodium> server # [    0.000000] efi: UEFI not found.72vm-test-run-pgsodium> server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT73vm-test-run-pgsodium> server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]74vm-test-run-pgsodium> server # [    0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]75vm-test-run-pgsodium> server # [    0.000000] Zone ranges:76vm-test-run-pgsodium> server # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]77vm-test-run-pgsodium> server # [    0.000000]   DMA32    empty78vm-test-run-pgsodium> server # [    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]79vm-test-run-pgsodium> server # [    0.000000]   Device   empty80vm-test-run-pgsodium> server # [    0.000000] Movable zone start for each node81vm-test-run-pgsodium> server # [    0.000000] Early memory node ranges82vm-test-run-pgsodium> server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]83vm-test-run-pgsodium> server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]84vm-test-run-pgsodium> server # [    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -185vm-test-run-pgsodium> server # [    0.000000] psci: probing for conduit method from DT.86vm-test-run-pgsodium> server # [    0.000000] psci: PSCIv1.1 detected in firmware.87vm-test-run-pgsodium> server # [    0.000000] psci: Using standard PSCI v0.2 function IDs88vm-test-run-pgsodium> server # [    0.000000] psci: Trusted OS migration not required89vm-test-run-pgsodium> server # [    0.000000] psci: SMC Calling Convention v1.090vm-test-run-pgsodium> server # [    0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u36044891vm-test-run-pgsodium> server # [    0.000000] Detected PIPT I-cache on CPU092vm-test-run-pgsodium> server # [    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)93vm-test-run-pgsodium> server # [    0.000000] CPU features: detected: GIC system register CPU interface94vm-test-run-pgsodium> server # [    0.000000] CPU features: detected: HCRX_EL2 register95vm-test-run-pgsodium> server # [    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)96vm-test-run-pgsodium> server # [    0.000000] CPU features: detected: Spectre-v497vm-test-run-pgsodium> server # [    0.000000] alternatives: applying boot alternatives98vm-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=tty099vm-test-run-pgsodium> server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/l8lfhnylxpf0mipvh8m5ijfl8q018clb-closure-info/registration", will be passed to user space.100vm-test-run-pgsodium> server # [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)101vm-test-run-pgsodium> server # [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)102vm-test-run-pgsodium> server # [    0.000000] Fallback order for Node 0: 0103vm-test-run-pgsodium> server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576104vm-test-run-pgsodium> server # [    0.000000] Policy zone: Normal105vm-test-run-pgsodium> server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off106vm-test-run-pgsodium> server # [    0.000000] software IO TLB: area num 2.107vm-test-run-pgsodium> server # [    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)108vm-test-run-pgsodium> server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1109vm-test-run-pgsodium> server # [    0.000000] allocated 8388608 bytes of page_ext110vm-test-run-pgsodium> server # [    0.000000] ftrace: allocating 72257 entries in 283 pages111vm-test-run-pgsodium> server # [    0.000000] ftrace: allocated 283 pages with 5 groups112vm-test-run-pgsodium> server # [    0.000000] rcu: Hierarchical RCU implementation.113vm-test-run-pgsodium> server # [    0.000000] rcu: 	RCU event tracing is enabled.114vm-test-run-pgsodium> server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.115vm-test-run-pgsodium> server # [    0.000000] 	Rude variant of Tasks RCU enabled.116vm-test-run-pgsodium> server # [    0.000000] 	Tracing variant of Tasks RCU enabled.117vm-test-run-pgsodium> server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.118vm-test-run-pgsodium> server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2119vm-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.120vm-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.121vm-test-run-pgsodium> server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0122vm-test-run-pgsodium> server # [    0.000000] GICv3: 256 SPIs implemented123vm-test-run-pgsodium> server # [    0.000000] GICv3: 0 Extended SPIs implemented124vm-test-run-pgsodium> server # [    0.000000] Root IRQ handler: gic_handle_irq125vm-test-run-pgsodium> server # [    0.000000] GICv3: GICv3 features: 16 PPIs126vm-test-run-pgsodium> server # [    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0127vm-test-run-pgsodium> server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000128vm-test-run-pgsodium> server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]129vm-test-run-pgsodium> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1)130vm-test-run-pgsodium> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1)131vm-test-run-pgsodium> server # [    0.000000] GICv3: using LPI property table @0x0000000100b00000132vm-test-run-pgsodium> server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000133vm-test-run-pgsodium> server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.134vm-test-run-pgsodium> server # [    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).135vm-test-run-pgsodium> server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns136vm-test-run-pgsodium> server # [    0.000036] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns137vm-test-run-pgsodium> server # [    0.004344] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)138vm-test-run-pgsodium> server # [    0.014643] Console: colour dummy device 80x25139vm-test-run-pgsodium> server # [    0.016252] printk: legacy console [tty0] enabled140vm-test-run-pgsodium> server # [    0.022476] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)141vm-test-run-pgsodium> server # [    0.023000] pid_max: default: 32768 minimum: 301142vm-test-run-pgsodium> server # [    0.024928] LSM: initializing lsm=capability,landlock,yama,bpf143vm-test-run-pgsodium> server # [    0.027186] landlock: Up and running.144vm-test-run-pgsodium> server # [    0.027258] Yama: becoming mindful.145vm-test-run-pgsodium> server # [    0.031892] LSM support for eBPF active146vm-test-run-pgsodium> server # [    0.035222] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)147vm-test-run-pgsodium> server # [    0.035358] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)148vm-test-run-pgsodium> server # [    0.067429] cacheinfo: Unable to detect cache hierarchy for CPU 0149vm-test-run-pgsodium> server # [    0.079998] rcu: Hierarchical SRCU implementation.150vm-test-run-pgsodium> server # [    0.080099] rcu: 	Max phase no-delay instances is 1000.151vm-test-run-pgsodium> server # [    0.081548] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level152vm-test-run-pgsodium> server # [    0.089566] fsl-mc MSI: its@8080000 domain created153vm-test-run-pgsodium> server # [    0.091803] EFI services will not be available.154vm-test-run-pgsodium> server # [    0.094109] smp: Bringing up secondary CPUs ...155vm-test-run-pgsodium> server # [    0.098517] Detected PIPT I-cache on CPU1156vm-test-run-pgsodium> server # [    0.099867] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000157vm-test-run-pgsodium> server # [    0.100606] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000158vm-test-run-pgsodium> server # [    0.102217] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]159vm-test-run-pgsodium> server # [    0.106474] smp: Brought up 1 node, 2 CPUs160vm-test-run-pgsodium> server # [    0.106641] SMP: Total of 2 processors activated.161vm-test-run-pgsodium> server # [    0.106687] CPU: All CPU(s) started at EL1162vm-test-run-pgsodium> server # [    0.106852] CPU features: detected: Branch Target Identification163vm-test-run-pgsodium> server # [    0.107040] CPU features: detected: 32-bit EL0 Support164vm-test-run-pgsodium> server # [    0.107116] CPU features: detected: 32-bit EL1 Support165vm-test-run-pgsodium> server # [    0.107161] CPU features: detected: ARMv8.4 Translation Table Level166vm-test-run-pgsodium> server # [    0.107220] CPU features: detected: Instruction cache invalidation not required for I/D coherence167vm-test-run-pgsodium> server # [    0.107287] CPU features: detected: Data cache clean to the PoU not required for I/D coherence168vm-test-run-pgsodium> server # [    0.107417] CPU features: detected: Common not Private translations169vm-test-run-pgsodium> server # [    0.107459] CPU features: detected: CRC32 instructions170vm-test-run-pgsodium> server # [    0.107483] CPU features: detected: Data cache clean to Point of Deep Persistence171vm-test-run-pgsodium> server # [    0.107512] CPU features: detected: Data cache clean to Point of Persistence172vm-test-run-pgsodium> server # [    0.107541] CPU features: detected: Data independent timing control (DIT)173vm-test-run-pgsodium> server # [    0.107570] CPU features: detected: E0PD174vm-test-run-pgsodium> server # [    0.107593] CPU features: detected: Enhanced Counter Virtualization175vm-test-run-pgsodium> server # [    0.107619] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)176vm-test-run-pgsodium> server # [    0.107644] CPU features: detected: Enhanced Privileged Access Never177vm-test-run-pgsodium> server # [    0.107667] CPU features: detected: Enhanced Virtualization Traps178vm-test-run-pgsodium> server # [    0.107690] CPU features: detected: Fine Grained Traps179vm-test-run-pgsodium> server # [    0.107751] CPU features: detected: Generic authentication (IMP DEF algorithm)180vm-test-run-pgsodium> server # [    0.107840] CPU features: detected: RCpc load-acquire (LDAPR)181vm-test-run-pgsodium> server # [    0.107886] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)182vm-test-run-pgsodium> server # [    0.107911] CPU features: detected: LSE atomic instructions183vm-test-run-pgsodium> server # [    0.107933] CPU features: detected: Memory Copy and Memory Set instructions184vm-test-run-pgsodium> server # [    0.107958] CPU features: detected: Privileged Access Never185vm-test-run-pgsodium> server # [    0.108017] CPU features: detected: RAS Extension Support186vm-test-run-pgsodium> server # [    0.108039] CPU features: detected: Random Number Generator187vm-test-run-pgsodium> server # [    0.108065] CPU features: detected: Speculation barrier (SB)188vm-test-run-pgsodium> server # [    0.108088] CPU features: detected: Stage-2 Force Write-Back189vm-test-run-pgsodium> server # [    0.108111] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality190vm-test-run-pgsodium> server # [    0.108138] CPU features: detected: TLB range maintenance instructions191vm-test-run-pgsodium> server # [    0.108161] CPU features: detected: WFx with timeout192vm-test-run-pgsodium> server # [    0.108202] CPU features: detected: Speculative Store Bypassing Safe (SSBS)193vm-test-run-pgsodium> server # [    0.108227] CPU features: detected: Scalable Vector Extension194vm-test-run-pgsodium> server # [    0.117798] alternatives: applying system-wide alternatives195vm-test-run-pgsodium> server # [    0.134021] CPU features: detected: Hardware dirty bit management on CPU0-1196vm-test-run-pgsodium> server # [    0.134795] SVE: maximum available vector length 256 bytes per vector197vm-test-run-pgsodium> server # [    0.135022] SVE: default vector length 64 bytes per vector198vm-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)199vm-test-run-pgsodium> server # [    0.171659] devtmpfs: initialized200vm-test-run-pgsodium> server # [    0.200483] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns201vm-test-run-pgsodium> server # [    0.201341] futex hash table entries: 512 (order: 3, 32768 bytes, linear)202vm-test-run-pgsodium> server # [    0.203969] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL203vm-test-run-pgsodium> server # [    0.204046] 0 pages in range for non-PLT usage204vm-test-run-pgsodium> server # [    0.204537] 509248 pages in range for PLT usage205vm-test-run-pgsodium> server # [    0.207080] pinctrl core: initialized pinctrl subsystem206vm-test-run-pgsodium> server # [    0.219294] DMI not present or invalid.207vm-test-run-pgsodium> server # [    0.240743] NET: Registered PF_NETLINK/PF_ROUTE protocol family208vm-test-run-pgsodium> server # [    0.252925] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations209vm-test-run-pgsodium> server # [    0.254410] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations210vm-test-run-pgsodium> server # [    0.255632] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations211vm-test-run-pgsodium> server # [    0.255898] audit: initializing netlink subsys (disabled)212vm-test-run-pgsodium> server # [    0.258559] audit: type=2000 audit(0.192:1): state=initialized audit_enabled=0 res=1213vm-test-run-pgsodium> server # [    0.265027] thermal_sys: Registered thermal governor 'fair_share'214vm-test-run-pgsodium> server # [    0.265087] thermal_sys: Registered thermal governor 'bang_bang'215vm-test-run-pgsodium> server # [    0.265150] thermal_sys: Registered thermal governor 'step_wise'216vm-test-run-pgsodium> server # [    0.265178] thermal_sys: Registered thermal governor 'user_space'217vm-test-run-pgsodium> server # [    0.265207] thermal_sys: Registered thermal governor 'power_allocator'218vm-test-run-pgsodium> server # [    0.266088] cpuidle: using governor ladder219vm-test-run-pgsodium> server # [    0.266512] cpuidle: using governor menu220vm-test-run-pgsodium> server # [    0.268614] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.221vm-test-run-pgsodium> server # [    0.269534] ASID allocator initialised with 65536 entries222vm-test-run-pgsodium> server # [    0.278210] Serial: AMBA PL011 UART driver223vm-test-run-pgsodium> server # [    0.330030] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1224vm-test-run-pgsodium> server # [    0.331871] printk: legacy console [ttyAMA0] enabled225vm-test-run-pgsodium> server # [    0.369017] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages226vm-test-run-pgsodium> server # [    0.369207] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page227vm-test-run-pgsodium> server # [    0.369313] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages228vm-test-run-pgsodium> server # [    0.369412] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page229vm-test-run-pgsodium> server # [    0.369506] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages230vm-test-run-pgsodium> server # [    0.369600] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page231vm-test-run-pgsodium> server # [    0.369695] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages232vm-test-run-pgsodium> server # [    0.370392] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page233vm-test-run-pgsodium> server # [    0.384293] fbcon: Taking over console234vm-test-run-pgsodium> server # [    0.385103] ACPI: Interpreter disabled.235vm-test-run-pgsodium> server # [    0.393150] iommu: Default domain type: Translated236vm-test-run-pgsodium> server # [    0.393362] iommu: DMA domain TLB invalidation policy: strict mode237vm-test-run-pgsodium> server # [    0.400844] SCSI subsystem initialized238vm-test-run-pgsodium> server # [    0.403310] usbcore: registered new interface driver usbfs239vm-test-run-pgsodium> server # [    0.404094] usbcore: registered new interface driver hub240vm-test-run-pgsodium> server # [    0.404370] usbcore: registered new device driver usb241vm-test-run-pgsodium> server # [    0.406739] pps_core: LinuxPPS API ver. 1 registered242vm-test-run-pgsodium> server # [    0.406899] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>243vm-test-run-pgsodium> server # [    0.407098] PTP clock support registered244vm-test-run-pgsodium> server # [    0.407473] EDAC MC: Ver: 3.0.0245vm-test-run-pgsodium> server # [    0.409253] scmi_core: SCMI protocol bus registered246vm-test-run-pgsodium> server # [    0.412511] FPGA manager framework247vm-test-run-pgsodium> server # [    0.413609] Advanced Linux Sound Architecture Driver Initialized.248vm-test-run-pgsodium> server # [    0.423564] vgaarb: loaded249vm-test-run-pgsodium> server # [    0.429064] clocksource: Switched to clocksource arch_sys_counter250vm-test-run-pgsodium> server # [    0.434371] VFS: Disk quotas dquot_6.6.0251vm-test-run-pgsodium> server # [    0.434687] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)252vm-test-run-pgsodium> server # [    0.438680] netfs: FS-Cache loaded253vm-test-run-pgsodium> server # [    0.439934] pnp: PnP ACPI: disabled254vm-test-run-pgsodium> server # [    0.467189] NET: Registered PF_INET protocol family255vm-test-run-pgsodium> server # [    0.468333] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)256vm-test-run-pgsodium> server # [    0.586303] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)257vm-test-run-pgsodium> server # [    0.586699] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)258vm-test-run-pgsodium> server # [    0.587096] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)259vm-test-run-pgsodium> server # [    0.588698] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)260vm-test-run-pgsodium> server # [    0.589936] TCP: Hash tables configured (established 32768 bind 32768)261vm-test-run-pgsodium> server # [    0.591876] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)262vm-test-run-pgsodium> server # [    0.592330] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)263vm-test-run-pgsodium> server # [    0.593217] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)264vm-test-run-pgsodium> server # [    0.595632] NET: Registered PF_UNIX/PF_LOCAL protocol family265vm-test-run-pgsodium> server # [    0.596219] NET: Registered PF_XDP protocol family266vm-test-run-pgsodium> server # [    0.596631] PCI: CLS 0 bytes, default 64267vm-test-run-pgsodium> server # [    0.600281] kvm [1]: HYP mode not available268vm-test-run-pgsodium> server # [    0.600944] Trying to unpack rootfs image as initramfs...269vm-test-run-pgsodium> server # [    0.715915] Initialise system trusted keyrings270vm-test-run-pgsodium> server # [    0.717427] workingset: timestamp_bits=42 max_order=20 bucket_order=0271vm-test-run-pgsodium> server # [    0.722621] squashfs: version 4.0 (2009/01/31) Phillip Lougher272vm-test-run-pgsodium> server # [    0.723507] 9p: Installing v9fs 9p2000 file system support273vm-test-run-pgsodium> server # [    0.785237] Key type asymmetric registered274vm-test-run-pgsodium> server # [    0.785775] Asymmetric key parser 'x509' registered275vm-test-run-pgsodium> server # [    0.786379] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)276vm-test-run-pgsodium> server # [    0.788331] io scheduler mq-deadline registered277vm-test-run-pgsodium> server # [    0.788498] io scheduler kyber registered278vm-test-run-pgsodium> server # [    0.817667] pl061_gpio 9030000.pl061: PL061 GPIO chip registered279vm-test-run-pgsodium> server # [    0.823591] ledtrig-cpu: registered to indicate activity on CPUs280vm-test-run-pgsodium> server # [    0.827498] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:281vm-test-run-pgsodium> server # [    0.828165] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000282vm-test-run-pgsodium> server # [    0.828839] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000283vm-test-run-pgsodium> server # [    0.829127] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000284vm-test-run-pgsodium> server # [    0.829908] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits285vm-test-run-pgsodium> server # [    0.830800] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]286vm-test-run-pgsodium> server # [    0.832665] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00287vm-test-run-pgsodium> server # [    0.833226] pci_bus 0000:00: root bus resource [bus 00-ff]288vm-test-run-pgsodium> server # [    0.833467] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]289vm-test-run-pgsodium> server # [    0.833966] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]290vm-test-run-pgsodium> server # [    0.834106] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]291vm-test-run-pgsodium> server # [    0.836006] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint292vm-test-run-pgsodium> server # [    0.839396] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint293vm-test-run-pgsodium> server # [    0.839730] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]294vm-test-run-pgsodium> server # [    0.839855] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]295vm-test-run-pgsodium> server # [    0.839989] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]296vm-test-run-pgsodium> server # [    0.840146] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]297vm-test-run-pgsodium> server # [    0.840664] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298vm-test-run-pgsodium> server # [    0.840807] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]299vm-test-run-pgsodium> server # [    0.841124] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]300vm-test-run-pgsodium> server # [    0.841263] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301vm-test-run-pgsodium> server # [    0.841763] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint302vm-test-run-pgsodium> server # [    0.841907] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]303vm-test-run-pgsodium> server # [    0.842000] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]304vm-test-run-pgsodium> server # [    0.842110] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]305vm-test-run-pgsodium> server # [    0.842537] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint306vm-test-run-pgsodium> server # [    0.842679] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]307vm-test-run-pgsodium> server # [    0.843170] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]308vm-test-run-pgsodium> server # [    0.843294] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]309vm-test-run-pgsodium> server # [    0.843758] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint310vm-test-run-pgsodium> server # [    0.843905] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]311vm-test-run-pgsodium> server # [    0.843997] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]312vm-test-run-pgsodium> server # [    0.844106] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]313vm-test-run-pgsodium> server # [    0.844573] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint314vm-test-run-pgsodium> server # [    0.844714] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]315vm-test-run-pgsodium> server # [    0.845110] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]316vm-test-run-pgsodium> server # [    0.846333] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]317vm-test-run-pgsodium> server # [    0.847318] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint318vm-test-run-pgsodium> server # [    0.847553] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]319vm-test-run-pgsodium> server # [    0.847655] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]320vm-test-run-pgsodium> server # [    0.847764] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]321vm-test-run-pgsodium> server # [    0.847870] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]322vm-test-run-pgsodium> server # [    0.848335] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint323vm-test-run-pgsodium> server # [    0.848792] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]324vm-test-run-pgsodium> server # [    0.850116] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]325vm-test-run-pgsodium> server # [    0.851235] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint326vm-test-run-pgsodium> server # [    0.851811] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]327vm-test-run-pgsodium> server # [    0.852398] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]328vm-test-run-pgsodium> server # [    0.854257] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint329vm-test-run-pgsodium> server # [    0.854664] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]330vm-test-run-pgsodium> server # [    0.855476] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint331vm-test-run-pgsodium> server # [    0.855721] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]332vm-test-run-pgsodium> server # [    0.855818] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]333vm-test-run-pgsodium> server # [    0.855931] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]334vm-test-run-pgsodium> server # [    0.856411] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint335vm-test-run-pgsodium> server # [    0.856635] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]336vm-test-run-pgsodium> server # [    0.856736] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]337vm-test-run-pgsodium> server # [    0.858093] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]338vm-test-run-pgsodium> server # [    0.860246] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned339vm-test-run-pgsodium> server # [    0.860508] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned340vm-test-run-pgsodium> server # [    0.860687] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned341vm-test-run-pgsodium> server # [    0.861447] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned342vm-test-run-pgsodium> server # [    0.862311] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned343vm-test-run-pgsodium> server # [    0.862541] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned344vm-test-run-pgsodium> server # [    0.862673] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned345vm-test-run-pgsodium> server # [    0.862804] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned346vm-test-run-pgsodium> server # [    0.863460] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned347vm-test-run-pgsodium> server # [    0.863601] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned348vm-test-run-pgsodium> server # [    0.863807] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned349vm-test-run-pgsodium> server # [    0.863953] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned350vm-test-run-pgsodium> server # [    0.864118] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned351vm-test-run-pgsodium> server # [    0.864730] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned352vm-test-run-pgsodium> server # [    0.864849] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned353vm-test-run-pgsodium> server # [    0.864970] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned354vm-test-run-pgsodium> server # [    0.865083] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned355vm-test-run-pgsodium> server # [    0.865188] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned356vm-test-run-pgsodium> server # [    0.865294] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned357vm-test-run-pgsodium> server # [    0.866340] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned358vm-test-run-pgsodium> server # [    0.866571] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned359vm-test-run-pgsodium> server # [    0.866822] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned360vm-test-run-pgsodium> server # [    0.867286] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned361vm-test-run-pgsodium> server # [    0.867733] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned362vm-test-run-pgsodium> server # [    0.868056] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned363vm-test-run-pgsodium> server # [    0.868176] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned364vm-test-run-pgsodium> server # [    0.868295] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned365vm-test-run-pgsodium> server # [    0.868394] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned366vm-test-run-pgsodium> server # [    0.868497] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned367vm-test-run-pgsodium> server # [    0.868712] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned368vm-test-run-pgsodium> server # [    0.868827] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned369vm-test-run-pgsodium> server # [    0.869018] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned370vm-test-run-pgsodium> server # [    0.869125] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned371vm-test-run-pgsodium> server # [    0.869228] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned372vm-test-run-pgsodium> server # [    0.869548] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]373vm-test-run-pgsodium> server # [    0.869782] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]374vm-test-run-pgsodium> server # [    0.870137] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]375vm-test-run-pgsodium> server # [    0.871817] pci 0000:00:0a.0: enabling device (0000 -> 0002)376vm-test-run-pgsodium> server # [    0.937078] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)377vm-test-run-pgsodium> server # [    0.940570] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)378vm-test-run-pgsodium> server # [    0.943341] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)379vm-test-run-pgsodium> server # [    0.944897] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)380vm-test-run-pgsodium> server # [    0.948469] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)381vm-test-run-pgsodium> server # [    0.952455] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)382vm-test-run-pgsodium> server # [    0.954521] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)383vm-test-run-pgsodium> server # [    0.956427] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)384vm-test-run-pgsodium> server # [    0.958587] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)385vm-test-run-pgsodium> server # [    0.960273] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)386vm-test-run-pgsodium> server # [    0.963149] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)387vm-test-run-pgsodium> server # [    0.981691] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled388vm-test-run-pgsodium> server # [    0.992505] msm_serial: driver initialized389vm-test-run-pgsodium> server # [    0.993594] SuperH (H)SCI(F) driver initialized390vm-test-run-pgsodium> server # [    0.994088] STM32 USART driver initialized391vm-test-run-pgsodium> server # [    1.048911] loop: module loaded392vm-test-run-pgsodium> server # [    1.050008] virtio_blk virtio5: 2/0/0 default/read/poll queues393vm-test-run-pgsodium> server # [    1.055070] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)394vm-test-run-pgsodium> server # [    1.068854] megasas: 07.727.03.00-rc1395vm-test-run-pgsodium> server # [    1.074494] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]396vm-test-run-pgsodium> server # [    1.076632] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000397vm-test-run-pgsodium> server # [    1.077182] Intel/Sharp Extended Query Table at 0x0031398vm-test-run-pgsodium> server # [    1.078953] Using buffer write method399vm-test-run-pgsodium> server # [    1.080426] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]400vm-test-run-pgsodium> server # [    1.081442] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000401vm-test-run-pgsodium> server # [    1.082319] Intel/Sharp Extended Query Table at 0x0031402vm-test-run-pgsodium> server # [    1.084005] Using buffer write method403vm-test-run-pgsodium> server # [    1.084232] Concatenating MTD devices:404vm-test-run-pgsodium> server # [    1.084315] (0): "0.flash"405vm-test-run-pgsodium> server # [    1.084376] (1): "0.flash"406vm-test-run-pgsodium> server # [    1.084433] into device "0.flash"407vm-test-run-pgsodium> server # [    1.094705] Freeing initrd memory: 10684K408vm-test-run-pgsodium> server # [    1.117429] tun: Universal TUN/TAP device driver, 1.6409vm-test-run-pgsodium> server # [    1.130733] thunder_xcv, ver 1.0410vm-test-run-pgsodium> server # [    1.131054] thunder_bgx, ver 1.0411vm-test-run-pgsodium> server # [    1.131234] nicpf, ver 1.0412vm-test-run-pgsodium> server # [    1.134077] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version413vm-test-run-pgsodium> server # [    1.134216] hns3: Copyright (c) 2017 Huawei Corporation.414vm-test-run-pgsodium> server # [    1.134709] hclge is initializing415vm-test-run-pgsodium> server # [    1.135207] e1000: Intel(R) PRO/1000 Network Driver416vm-test-run-pgsodium> server # [    1.135293] e1000: Copyright (c) 1999-2006 Intel Corporation.417vm-test-run-pgsodium> server # [    1.135519] e1000e: Intel(R) PRO/1000 Network Driver418vm-test-run-pgsodium> server # [    1.135599] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.419vm-test-run-pgsodium> server # [    1.135813] igb: Intel(R) Gigabit Ethernet Network Driver420vm-test-run-pgsodium> server # [    1.135913] igb: Copyright (c) 2007-2014 Intel Corporation.421vm-test-run-pgsodium> server # [    1.136109] igbvf: Intel(R) Gigabit Virtual Function Network Driver422vm-test-run-pgsodium> server # [    1.136363] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.423vm-test-run-pgsodium> server # [    1.137676] sky2: driver version 1.30424vm-test-run-pgsodium> server # [    1.147366] ehci-pci 0000:00:0a.0: EHCI Host Controller425vm-test-run-pgsodium> server # [    1.147895] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1426vm-test-run-pgsodium> server # [    1.148211] usbcore: registered new interface driver usb-storage427vm-test-run-pgsodium> server # [    1.148788] usbcore: registered new interface driver usbserial_generic428vm-test-run-pgsodium> server # [    1.149759] usbserial: USB Serial support registered for generic429vm-test-run-pgsodium> server # [    1.151717] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000430vm-test-run-pgsodium> server # [    1.160592] rtc-pl031 9010000.pl031: registered as rtc0431vm-test-run-pgsodium> server # [    1.162241] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00432vm-test-run-pgsodium> server # [    1.162235] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T04:45:29 UTC (1791434729)433vm-test-run-pgsodium> server # [    1.165025] i2c_dev: i2c /dev entries driver434vm-test-run-pgsodium> server # [    1.169165] hub 1-0:1.0: USB hub found435vm-test-run-pgsodium> server # [    1.169769] hub 1-0:1.0: 6 ports detected436vm-test-run-pgsodium> server # [    1.182178] sdhci: Secure Digital Host Controller Interface driver437vm-test-run-pgsodium> server # [    1.182346] sdhci: Copyright(c) Pierre Ossman438vm-test-run-pgsodium> server # [    1.184310] Synopsys Designware Multimedia Card Interface Driver439vm-test-run-pgsodium> server # [    1.187143] sdhci-pltfm: SDHCI platform and OF driver helper440vm-test-run-pgsodium> server # [    1.194725] hid: raw HID events driver (C) Jiri Kosina441vm-test-run-pgsodium> server # [    1.196674] usbcore: registered new interface driver usbhid442vm-test-run-pgsodium> server # [    1.197334] usbhid: USB HID core driver443vm-test-run-pgsodium> server # [    1.207467] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available444vm-test-run-pgsodium> server # [    1.223320] drop_monitor: Initializing network drop monitor service445vm-test-run-pgsodium> server # [    1.224732] NET: Registered PF_INET6 protocol family446vm-test-run-pgsodium> server # [    1.244673] Segment Routing with IPv6447vm-test-run-pgsodium> server # [    1.245219] In-situ OAM (IOAM) with IPv6448vm-test-run-pgsodium> server # [    1.246483] NET: Registered PF_PACKET protocol family449vm-test-run-pgsodium> server # [    1.248381] 9pnet: Installing 9P2000 support450vm-test-run-pgsodium> server # [    1.253959] Key type dns_resolver registered451vm-test-run-pgsodium> server # [    1.293540] registered taskstats version 1452vm-test-run-pgsodium> server # [    1.295656] Loading compiled-in X.509 certificates453vm-test-run-pgsodium> server # [    1.356528] Demotion targets for Node 0: null454vm-test-run-pgsodium> server # [    1.359796] Key type .fscrypt registered455vm-test-run-pgsodium> server # [    1.359949] Key type fscrypt-provisioning registered456vm-test-run-pgsodium> server # [    1.370371] input: gpio-keys as /devices/platform/gpio-keys/input/input0457vm-test-run-pgsodium> server # [    1.418303] clk: Disabling unused clocks458vm-test-run-pgsodium> server # [    1.418616] PM: genpd: Disabling unused power domains459vm-test-run-pgsodium> server # [    1.419018] ALSA device list:460vm-test-run-pgsodium> server # [    1.419154]   No soundcards found.461vm-test-run-pgsodium> server # [    1.422384] usb 1-1: new high-speed USB device number 2 using ehci-pci462vm-test-run-pgsodium> server # [    1.510446] Freeing unused kernel memory: 4416K463vm-test-run-pgsodium> server # [    1.512224] Run /init as init process464vm-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/input1465vm-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/input0466vm-test-run-pgsodium> server # [    1.869401] usb 1-2: new high-speed USB device number 3 using ehci-pci467vm-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/input2468vm-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/input0469vm-test-run-pgsodium> server # [    2.148445] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev470vm-test-run-pgsodium> server # [    2.290344] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0471vm-test-run-pgsodium> server # [    2.291111] [drm] features: -virgl +edid -resource_blob -host_visible472vm-test-run-pgsodium> server # [    2.291133] [drm] features: -context_init473vm-test-run-pgsodium> server # [    2.293124] [drm] number of scanouts: 1474vm-test-run-pgsodium> server # [    2.293248] [drm] number of cap sets: 0475vm-test-run-pgsodium> server # [    2.307150] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0476vm-test-run-pgsodium> server # [    2.342590] Console: switching to colour frame buffer device 160x50477vm-test-run-pgsodium> server # [    2.355918] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device478vm-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.479vm-test-run-pgsodium> server # [    7.814208] EXT4-fs (vda): re-mounted ff832013-fadc-4fa4-8d3b-2845bb187e78.480vm-test-run-pgsodium> server # [    7.832713] booting system configuration /nix/store/h6wy4m62pihbkb0bvw3lxlhndi980nlk-nixos-system-server-test481vm-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)482vm-test-run-pgsodium> server # [   20.509625] systemd[1]: Detected virtualization qemu.483vm-test-run-pgsodium> server # [   20.514051] systemd[1]: Detected architecture arm64.484vm-test-run-pgsodium> server # [   20.519001] systemd[1]: Detected first boot.485vm-test-run-pgsodium> server # [   20.546758] systemd[1]: Initializing machine ID from random generator.486vm-test-run-pgsodium> server # [   20.576593] systemd[1]: Hostname set to <server>.487vm-test-run-pgsodium> server # [   21.807007] systemd[1]: bpf-restrict-fs: LSM BPF program attached488vm-test-run-pgsodium> server # [   23.170773] systemd[1]: Applying preset policy.489vm-test-run-pgsodium> server # [   26.912559] systemd[1]: Populated /etc with preset unit settings.490vm-test-run-pgsodium> server: Guest root shell did not produce any data yet...491vm-test-run-pgsodium> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.492vm-test-run-pgsodium> server # [   30.411171] systemd[1]: Queued start job for default target Multi-User System.493vm-test-run-pgsodium> server # [   30.649663] systemd[1]: Created slice Slice /system/getty.494vm-test-run-pgsodium> server # [   30.666042] systemd[1]: Created slice Slice /system/modprobe.495vm-test-run-pgsodium> server # [   30.679556] systemd[1]: Created slice User and Session Slice.496vm-test-run-pgsodium> server # [   30.687719] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.497vm-test-run-pgsodium> server # [   30.693583] systemd[1]: Started Forward Password Requests to Wall Directory Watch.498vm-test-run-pgsodium> server # [   30.698949] systemd[1]: Expecting device /dev/hvc0...499vm-test-run-pgsodium> server # [   30.703568] systemd[1]: Expecting device /dev/ttyAMA0...500vm-test-run-pgsodium> server # [   30.708673] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...501vm-test-run-pgsodium> server # [   30.713558] systemd[1]: Reached target Local Encrypted Volumes.502vm-test-run-pgsodium> server # [   30.718450] systemd[1]: Reached target Virtual Machines and Containers.503vm-test-run-pgsodium> server # [   30.723281] systemd[1]: Reached target Path Units.504vm-test-run-pgsodium> server # [   30.727901] systemd[1]: Reached target Remote File Systems.505vm-test-run-pgsodium> server # [   30.732467] systemd[1]: Reached target Slice Units.506vm-test-run-pgsodium> server # [   30.736388] systemd[1]: Reached target Swaps.507vm-test-run-pgsodium> server # [   30.772466] systemd[1]: Listening on Process Core Dump Socket.508vm-test-run-pgsodium> server # [   30.797348] systemd[1]: Listening on Credential Encryption/Decryption.509vm-test-run-pgsodium> server # [   30.808247] systemd[1]: Listening on Journal Audit Socket.510vm-test-run-pgsodium> server # [   30.815749] systemd[1]: Listening on Journal Socket (/dev/log).511vm-test-run-pgsodium> server # [   30.821942] systemd[1]: Listening on Journal Sockets.512vm-test-run-pgsodium> server # [   30.831065] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.513vm-test-run-pgsodium> server # [   30.835849] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).514vm-test-run-pgsodium> server # [   30.842087] systemd[1]: Listening on udev Control Socket.515vm-test-run-pgsodium> server # [   30.847877] systemd[1]: Listening on udev Kernel Socket.516vm-test-run-pgsodium> server # [   30.900597] systemd[1]: Mounting Huge Pages File System...517vm-test-run-pgsodium> server # [   30.946417] systemd[1]: Mounting POSIX Message Queue File System...518vm-test-run-pgsodium> server # [   30.995460] systemd[1]: Mounting Kernel Debug File System...519vm-test-run-pgsodium> server # [   31.107172] systemd[1]: Mounting Kernel Trace File System...520vm-test-run-pgsodium> server # [   31.241486] systemd[1]: Starting Create List of Static Device Nodes...521vm-test-run-pgsodium> server # [   31.364065] systemd[1]: Starting Load Kernel Module 9pnet_virtio...522vm-test-run-pgsodium> server # [   31.376731] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).523vm-test-run-pgsodium> server # [   31.496020] systemd[1]: Mounting Kernel Configuration File System...524vm-test-run-pgsodium> server # [   31.518797] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).525vm-test-run-pgsodium> server # [   31.536910] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).526vm-test-run-pgsodium> server # [   31.615595] systemd[1]: Starting Load Kernel Module fuse...527vm-test-run-pgsodium> server # [   31.630450] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).528vm-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).529vm-test-run-pgsodium> server # [   31.758815] systemd[1]: Starting Journal Service...530vm-test-run-pgsodium> server # [   31.838140] systemd[1]: Starting Load Kernel Modules...531vm-test-run-pgsodium> server # [   31.926974] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...532vm-test-run-pgsodium> server # [   31.982905] systemd[1]: Starting Remount Root and Kernel File Systems...533vm-test-run-pgsodium> server # [   31.988202] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).534vm-test-run-pgsodium> server # [   32.050761] systemd[1]: Starting Coldplug All udev Devices...535vm-test-run-pgsodium> server # [   32.126565] systemd[1]: Mounted Huge Pages File System.536vm-test-run-pgsodium> server # [   32.136412] systemd[1]: Mounted POSIX Message Queue File System.537vm-test-run-pgsodium> server # [   32.150281] systemd[1]: Mounted Kernel Debug File System.538vm-test-run-pgsodium> server # [   32.160186] systemd[1]: Mounted Kernel Trace File System.539vm-test-run-pgsodium> server # [   32.184279] systemd[1]: Finished Create List of Static Device Nodes.540vm-test-run-pgsodium> server # [   32.208318] systemd[1]: Mounted Kernel Configuration File System.541vm-test-run-pgsodium> server # [   32.267036] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...542vm-test-run-pgsodium> server # [   32.651011] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.543vm-test-run-pgsodium> server # [   32.672845] systemd[1]: Finished Load Kernel Module 9pnet_virtio.544vm-test-run-pgsodium> server # [   33.019216] fuse: init (API version 7.41)545vm-test-run-pgsodium> server # [   33.082979] systemd[1]: modprobe@fuse.service: Deactivated successfully.546vm-test-run-pgsodium> server # [   33.099926] systemd[1]: Finished Load Kernel Module fuse.547vm-test-run-pgsodium> server # [   33.160593] systemd[1]: Mounting FUSE Control File System...548vm-test-run-pgsodium> server # [   33.188700] systemd[1]: Finished Load Kernel Modules.549vm-test-run-pgsodium> server # [   33.278058] systemd[1]: Starting Firewall...550vm-test-run-pgsodium> server # [   33.335345] systemd[1]: Starting Apply Kernel Variables...551vm-test-run-pgsodium> server # [   33.349560] EXT4-fs (vda): re-mounted ff832013-fadc-4fa4-8d3b-2845bb187e78.552vm-test-run-pgsodium> server # [   33.399737] systemd-journald[398]: Collecting audit messages is enabled.553vm-test-run-pgsodium> server # [   33.465807] systemd[1]: Mounted FUSE Control File System.554vm-test-run-pgsodium> server # [   33.498749] systemd[1]: Finished Remount Root and Kernel File Systems.555vm-test-run-pgsodium> server # [   33.511524] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).556vm-test-run-pgsodium> server # [   33.568336] systemd[1]: Starting Load/Save OS Random Seed...557vm-test-run-pgsodium> server # [   33.578500] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).558vm-test-run-pgsodium> server # [   34.483280] systemd[1]: Finished Load/Save OS Random Seed.559vm-test-run-pgsodium> server # [   34.506632] systemd[1]: Reached target First Boot Complete.560vm-test-run-pgsodium> server # [   34.674904] systemd[1]: Finished Apply Kernel Variables.561vm-test-run-pgsodium> server # [   34.764474] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.562vm-test-run-pgsodium> server # [   34.848989] systemd[1]: Starting Create Static Device Nodes in /dev...563vm-test-run-pgsodium> server # [   34.864625] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.564vm-test-run-pgsodium> server # [   35.783128] systemd[1]: Finished Create Static Device Nodes in /dev.565vm-test-run-pgsodium> server # [   35.808628] systemd[1]: Reached target Preparation for Local File Systems.566vm-test-run-pgsodium> server # [   35.850701] systemd[1]: Mounting /run/wrappers...567vm-test-run-pgsodium> server # [   35.902113] systemd[1]: Starting Rule-based Manager for Device Events and Files...568vm-test-run-pgsodium> server # [   36.359580] systemd[1]: Mounted /run/wrappers.569vm-test-run-pgsodium> server # [   36.368548] systemd[1]: Reached target Local File Systems.570vm-test-run-pgsodium> server # [   36.406756] systemd[1]: Listening on Boot Entries Service Socket.571vm-test-run-pgsodium> server # [   36.351938] systemd-modules-load[399]: Module 'atkbd' is built in572vm-test-run-pgsodium> server # [   36.358762] systemd-modules-load[399]: Module 'loop' is built in573vm-test-run-pgsodium> server # [   36.370457] systemd-oomd[400]: No swap; memory pressure usage will be degraded574vm-test-run-pgsodium> server # [   36.472318] systemd[1]: Starting Create SUID/SGID Wrappers...575vm-test-run-pgsodium> server # [   36.478599] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.576vm-test-run-pgsodium> server # [   36.529029] systemd[1]: Starting Save Transient machine-id to Disk...577vm-test-run-pgsodium> server # [   36.550259] systemd[1]: Started Journal Service.578vm-test-run-pgsodium> server # [   36.867455] systemd[1]: Starting Flush Journal to Persistent Storage...579vm-test-run-pgsodium> server # [   37.521790] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.580vm-test-run-pgsodium> server # [   37.611958] systemd-udevd[425]: Using default interface naming scheme 'v258'.581vm-test-run-pgsodium> server # [   37.623300] systemd[1]: Finished Save Transient machine-id to Disk.582vm-test-run-pgsodium> server # [   37.877396] systemd-journald[398]: Received client request to flush runtime journal.583vm-test-run-pgsodium> server # [   38.411045] systemd[1]: Finished Flush Journal to Persistent Storage.584vm-test-run-pgsodium> server # [   38.450718] systemd[1]: Starting Create System Files and Directories...585vm-test-run-pgsodium> server # [   40.331411] systemd[1]: Started Rule-based Manager for Device Events and Files.586vm-test-run-pgsodium> server # [   40.741475] systemd[1]: Finished Create System Files and Directories.587vm-test-run-pgsodium> server # [   40.829911] systemd[1]: Starting Rebuild Journal Catalog...588vm-test-run-pgsodium> server # [   40.880710] systemd[1]: Starting Record System Boot/Shutdown in UTMP...589vm-test-run-pgsodium> server # [   41.861480] systemd[1]: Finished Record System Boot/Shutdown in UTMP.590vm-test-run-pgsodium> server # [   43.003496] systemd[1]: Finished Rebuild Journal Catalog.591vm-test-run-pgsodium> server # [   43.093794] systemd[1]: Starting Update is Completed...592vm-test-run-pgsodium> server # [   43.812272] systemd[1]: Finished Update is Completed.593vm-test-run-pgsodium> server # [   46.889784] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.594vm-test-run-pgsodium> server # [   46.898846] systemd[1]: Finished Create SUID/SGID Wrappers.595vm-test-run-pgsodium> server # [   46.902710] systemd[1]: suid-sgid-wrappers.service: Consumed 2.901s CPU time, 5.6M memory peak.596vm-test-run-pgsodium> server # [   49.119494] systemd[1]: Finished Firewall.597vm-test-run-pgsodium> server # [   50.867752] systemd[1]: Finished Coldplug All udev Devices.598vm-test-run-pgsodium> server # [   50.879539] systemd[1]: Reached target System Initialization.599vm-test-run-pgsodium> server # [   50.894690] systemd[1]: Started Discard unused filesystem blocks once a week.600vm-test-run-pgsodium> server # [   50.903144] systemd[1]: Started Daily Cleanup of Temporary Directories.601vm-test-run-pgsodium> server # [   50.914274] systemd[1]: Reached target Timer Units.602vm-test-run-pgsodium> server # [   50.928969] systemd[1]: Listening on D-Bus System Message Bus Socket.603vm-test-run-pgsodium> server # [   50.930868] systemd[1]: Listening on Nix Daemon Socket.604vm-test-run-pgsodium> server # [   50.931863] systemd[1]: Listening on Hostname Service Socket.605vm-test-run-pgsodium> server # [   50.937996] systemd[1]: Reached target Socket Units.606vm-test-run-pgsodium> server # [   50.939497] systemd[1]: Reached target Basic System.607vm-test-run-pgsodium> server # [   50.952773] systemd[1]: Starting Import lastlog data into lastlog2 database...608vm-test-run-pgsodium> server # [   51.000989] systemd[1]: Starting Name Service Cache Daemon (nsncd)...609vm-test-run-pgsodium> server # [   51.076098] systemd[1]: Started Reset console on configuration changes.610vm-test-run-pgsodium> server # [   51.103080] systemd[1]: Starting resolvconf update...611vm-test-run-pgsodium> server # [   51.150779] systemd[1]: Starting D-Bus System Message Bus...612vm-test-run-pgsodium> server # [   52.162505] systemd[1]: Finished Import lastlog data into lastlog2 database.613vm-test-run-pgsodium> server # [   52.445523] systemd[1]: Started Name Service Cache Daemon (nsncd).614vm-test-run-pgsodium> server # [   52.458008] systemd[1]: Reached target Host and Network Name Lookups.615vm-test-run-pgsodium> server # [   52.487274] systemd[1]: Reached target User and Group Name Lookups.616vm-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"617vm-test-run-pgsodium> server # [   52.553556] systemd[1]: Starting User Login Management...618vm-test-run-pgsodium> server # [   52.613311] dbus-daemon[633]: dbus[633]: Unknown username "systemd-timesync" in message bus configuration file619vm-test-run-pgsodium> server # [   53.117280] systemd[1]: Started D-Bus System Message Bus.620vm-test-run-pgsodium> server # [   53.890559] systemd-logind[649]: New seat seat0.621vm-test-run-pgsodium> server # [   53.943960] systemd[1]: Started User Login Management.622vm-test-run-pgsodium> server # [   53.994444] systemd[1]: Stopped target Host and Network Name Lookups.623vm-test-run-pgsodium> server # [   54.001918] systemd[1]: Stopping Host and Network Name Lookups...624vm-test-run-pgsodium> server # [   54.005903] systemd[1]: Stopped target User and Group Name Lookups.625vm-test-run-pgsodium> server # [   54.009603] systemd[1]: Stopping User and Group Name Lookups...626vm-test-run-pgsodium> server # [   54.035714] systemd[1]: Starting linger-users.service...627vm-test-run-pgsodium> server # [   54.066815] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...628vm-test-run-pgsodium> server # [   54.130262] systemd[1]: nscd.service: Deactivated successfully.629vm-test-run-pgsodium> server # [   54.145352] systemd[1]: Stopped Name Service Cache Daemon (nsncd).630vm-test-run-pgsodium> server # [   54.289874] systemd[1]: Starting Name Service Cache Daemon (nsncd)...631vm-test-run-pgsodium> server # [   54.649794] systemd[1]: linger-users.service: Deactivated successfully.632vm-test-run-pgsodium> server # [   54.660647] systemd[1]: Finished linger-users.service.633vm-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"634vm-test-run-pgsodium> server # [   54.998979] systemd[1]: Started Name Service Cache Daemon (nsncd).635vm-test-run-pgsodium> server # [   55.010916] systemd[1]: Reached target Host and Network Name Lookups.636vm-test-run-pgsodium> server # [   55.029921] systemd[1]: Reached target User and Group Name Lookups.637vm-test-run-pgsodium> server # [   55.479070] systemd[1]: Finished resolvconf update.638vm-test-run-pgsodium> server # [   55.497313] systemd[1]: Reached target Preparation for Network.639vm-test-run-pgsodium> server # [   55.542342] systemd[1]: Starting DHCP Client...640vm-test-run-pgsodium> server # [   56.733836] dhcpcd[741]: dhcpcd-10.2.4 starting641vm-test-run-pgsodium> server # [   56.813771] dhcpcd[745]: dev: loaded udev642vm-test-run-pgsodium> server # [   57.214830] 8021q: 802.1Q VLAN Support v1.8643vm-test-run-pgsodium> server # [   57.802444] cfg80211: Loading compiled-in X.509 certificates for regulatory database644vm-test-run-pgsodium> server # [   58.060300] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'645vm-test-run-pgsodium> server # [   58.071006] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'646vm-test-run-pgsodium> server # [   58.091247] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2647vm-test-run-pgsodium> server # [   58.103015] cfg80211: failed to load regulatory.db648vm-test-run-pgsodium> server # [   58.260490] systemd[1]: Found device /dev/hvc0.649vm-test-run-pgsodium> server # [   58.872082] 8021q: adding VLAN 0 to HW filter on device eth0650vm-test-run-pgsodium> server # [   58.809309] dhcpcd[745]: eth0: waiting for carrier651vm-test-run-pgsodium> server # [   58.814079] dhcpcd[745]: eth0: carrier acquired652vm-test-run-pgsodium> server # [   58.927190] dhcpcd[745]: DUID 00:01:00:01:32:59:dc:a3:52:54:00:12:34:56653vm-test-run-pgsodium> server # [   58.929933] dhcpcd[745]: eth0: IAID 00:12:34:56654vm-test-run-pgsodium> server # [   58.933676] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456655vm-test-run-pgsodium> server: Guest root shell did not produce any data yet...656vm-test-run-pgsodium> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.657vm-test-run-pgsodium> server # [   59.060621] systemd[1]: Found device /dev/ttyAMA0.658vm-test-run-pgsodium> server # [   59.105778] systemd[1]: Started backdoor.service.659vm-test-run-pgsodium> server # connecting to host...660vm-test-run-pgsodium> server: Guest shell says: b'Spawning backdoor root shell...\n'661vm-test-run-pgsodium> server: connected to guest root shell662vm-test-run-pgsodium> server: (connecting took 60.92 seconds)663vm-test-run-pgsodium> server: (finished: waiting for the VM to finish booting, in 60.92 seconds)664vm-test-run-pgsodium> server # [   60.004221] dhcpcd[745]: eth0: soliciting an IPv6 router665vm-test-run-pgsodium> server # [   60.007991] dhcpcd[745]: eth0: Router Advertisement from fe80::2666vm-test-run-pgsodium> server # [   60.016042] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64667vm-test-run-pgsodium> server # [   60.023682] dhcpcd[745]: eth0: adding route to fec0::/64668vm-test-run-pgsodium> server # [   60.034203] dhcpcd[745]: eth0: adding default route via fe80::2669vm-test-run-pgsodium> server # [   60.728977] dhcpcd[745]: eth0: soliciting a DHCP lease670vm-test-run-pgsodium> server # [   60.782253] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2671vm-test-run-pgsodium> server # [   60.799034] dhcpcd[745]: eth0: probing address 10.0.2.15/24672vm-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.673vm-test-run-pgsodium> server # [   61.932506] (udev-worker)[791]: Network interface NamePolicy= disabled on kernel command line.674vm-test-run-pgsodium> server # [   61.955886] (udev-worker)[790]: Network interface NamePolicy= disabled on kernel command line.675vm-test-run-pgsodium> server # [   62.714401] dhcpcd[745]: eth0: IAID 00:12:34:56676vm-test-run-pgsodium> server # [   62.928861] systemd[1]: Found device Virtio network device.677vm-test-run-pgsodium> server # [   62.949238] systemd[1]: Starting Address configuration of eth1...678vm-test-run-pgsodium> server # [   63.203291] dhcpcd[745]: eth0: soliciting an IPv6 router679vm-test-run-pgsodium> server # [   63.959026] 8021q: adding VLAN 0 to HW filter on device eth1680vm-test-run-pgsodium> server # [   64.182741] network-addresses-eth1-start[828]: adding address 192.168.1.1/24... done681vm-test-run-pgsodium> server # [   64.194944] dhcpcd[745]: eth0: soliciting a DHCP lease682vm-test-run-pgsodium> server # [   64.195786] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2683vm-test-run-pgsodium> server # [   64.202866] dhcpcd[745]: eth0: probing address 10.0.2.15/24684vm-test-run-pgsodium> server # [   64.324478] network-addresses-eth1-start[828]: adding address 2001:db8:1::1/64... done685vm-test-run-pgsodium> server # [   64.486515] systemd-logind[649]: Watching system buttons on /dev/input/event0 (gpio-keys)686vm-test-run-pgsodium> server # [   64.559746] systemd[1]: Finished Address configuration of eth1.687vm-test-run-pgsodium> server # [   64.600137] systemd[1]: Starting Networking Setup...688vm-test-run-pgsodium> server # [   66.136140] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds689vm-test-run-pgsodium> server # [   66.149181] dhcpcd[745]: eth0: adding route to 10.0.2.0/24690vm-test-run-pgsodium> server # [   66.155227] dhcpcd[745]: eth0: adding default route via 10.0.2.2691vm-test-run-pgsodium> server # [   66.818034] dhcpcd[906]: /nix/store/hsj50yr7hr62ql9xaynbihkhfhbgprcn-openresolv-3.17.0/sbin/.resolvconf-wrapped: line 1221: kill: (867) - Operation not permitted692vm-test-run-pgsodium> server # [   66.827266] dhcpcd[906]: clearing stale lock pid 867693vm-test-run-pgsodium> server # [   67.236172] systemd[1]: Finished Networking Setup.694vm-test-run-pgsodium> server # [   67.270968] systemd[1]: Reached target Network.695vm-test-run-pgsodium> server # [   67.301876] systemd[1]: Starting PostgreSQL Database Server...696vm-test-run-pgsodium> server # [   67.361344] systemd[1]: Starting Permit User Sessions...697vm-test-run-pgsodium> server # [   68.075255] systemd[1]: Finished Permit User Sessions.698vm-test-run-pgsodium> server # [   68.161829] systemd[1]: Started Getty on tty1.699vm-test-run-pgsodium> server # [   68.169990] systemd[1]: Reached target Login Prompts.700vm-test-run-pgsodium> server # [   69.211003] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[972]: Initializing database at /var/lib/postgresql/data701vm-test-run-pgsodium> server # [   69.954928] systemd[1]: Started DHCP Client.702vm-test-run-pgsodium> server # [   71.150739] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The files belonging to this database system will be owned by user "postgres".703vm-test-run-pgsodium> server # [   71.155764] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: This user must also own the server process.704vm-test-run-pgsodium> server # [   71.257372] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The database cluster will be initialized with locale "en_US.UTF-8".705vm-test-run-pgsodium> server # [   71.258670] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The default database encoding has accordingly been set to "UTF8".706vm-test-run-pgsodium> server # [   71.259128] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: The default text search configuration will be set to "english".707vm-test-run-pgsodium> server # [   71.259867] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: Data page checksums are enabled.708vm-test-run-pgsodium> server # [   71.269692] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: fixing permissions on existing directory /var/lib/postgresql/data ... ok709vm-test-run-pgsodium> server # [   71.280044] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: creating subdirectories ... ok710vm-test-run-pgsodium> server # [   71.298784] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting dynamic shared memory implementation ... posix711vm-test-run-pgsodium> server # [   72.610681] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting default max_connections ... 100712vm-test-run-pgsodium> server # [   72.956968] mousedev: PS/2 mouse device common for all mice713vm-test-run-pgsodium> server # [   73.308177] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting default shared_buffers ... 128MB714vm-test-run-pgsodium> server # [   74.558682] systemd-logind[649]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)715vm-test-run-pgsodium> server # [   83.675446] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: selecting default time zone ... UTC716vm-test-run-pgsodium> server # [   83.711655] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: creating configuration files ... ok717vm-test-run-pgsodium> server # [   87.527199] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: running bootstrap script ... ok718vm-test-run-pgsodium> server # [   98.011200] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: performing post-bootstrap initialization ... ok719vm-test-run-pgsodium> server # [  100.745222] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: syncing data to disk ... ok720vm-test-run-pgsodium> server # [  100.749156] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: initdb: warning: enabling "trust" authentication for local connections721vm-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.722vm-test-run-pgsodium> server # [  100.753145] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[983]: Success. You can now start the database server using:723vm-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 start724vm-test-run-pgsodium> server # [  102.551224] 3vzfzwprw5k5g3c2xh0dcr5sm4fn33dq-postgresql-init[972]: Validating PostgreSQL configuration...725vm-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_vault726vm-test-run-pgsodium> server # [  103.735455] postgres[1345]:  2026-10-08 04:47:12.155 UTC [1345] LOG:  pgaudit extension initialized727vm-test-run-pgsodium> server # [  104.775148] postgres[1345]:  2026-10-08 04:47:13.189 UTC [1345] LOG:  pgsodium primary server secret key loaded728vm-test-run-pgsodium> server # [  105.340847] postgres[1345]:  2026-10-08 04:47:13.771 UTC [1345] LOG:  vault primary server secret key loaded729vm-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-bit730vm-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 5432731vm-test-run-pgsodium> server # [  105.688652] postgres[1345]:  2026-10-08 04:47:14.119 UTC [1345] LOG:  listening on IPv6 address "::", port 5432732vm-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"733vm-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 UTC734vm-test-run-pgsodium> server # [  105.963659] postgres[1345]:  2026-10-08 04:47:14.380 UTC [1345] LOG:  database system is ready to accept connections735vm-test-run-pgsodium> server # [  105.988002] postgres[1383]:  2026-10-08 04:47:14.414 UTC [1383] FATAL:  role "postgres" does not exist736vm-test-run-pgsodium> server # [  105.999314] systemd[1]: Started PostgreSQL Database Server.737vm-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 catalogs738vm-test-run-pgsodium> server # [  106.053590] systemd[1]: Starting Supabase Database Initialization...739vm-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 1740vm-test-run-pgsodium> server # [  106.224608] postgres[1384]:  2026-10-08 04:47:14.649 UTC [1384] LOG:  pg_cron scheduler started741vm-test-run-pgsodium> server # [  106.396986] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Waiting for PostgreSQL to be ready...742vm-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 exist743vm-test-run-pgsodium> server # [  106.867464] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: PostgreSQL is ready744vm-test-run-pgsodium> server # [  106.878432] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Creating postgres role...745vm-test-run-pgsodium> server # [  107.170321] postgres[1417]:  2026-10-08 04:47:15.594 UTC [1417] FATAL:  role "postgres" does not exist746vm-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;747vm-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 1748vm-test-run-pgsodium> server # [  107.229631] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1410]: CREATE ROLE749vm-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;750vm-test-run-pgsodium> server # [  107.405274] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1418]: ALTER DATABASE751vm-test-run-pgsodium> server # [  107.435156] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000000-initial-schema.sql752vm-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;753vm-test-run-pgsodium> server # [  107.614871] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE PUBLICATION754vm-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;755vm-test-run-pgsodium> server # [  107.622243] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE756vm-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;757vm-test-run-pgsodium> server # [  107.628546] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE758vm-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;759vm-test-run-pgsodium> server # [  107.633731] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE760vm-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;761vm-test-run-pgsodium> server # [  107.645675] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE762vm-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;763vm-test-run-pgsodium> server # [  107.657682] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT764vm-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;765vm-test-run-pgsodium> server # [  107.664270] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE766vm-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;767vm-test-run-pgsodium> server # [  107.671837] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE768vm-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;769vm-test-run-pgsodium> server # [  107.681182] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE SCHEMA770vm-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;771vm-test-run-pgsodium> server # [  109.389826] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE EXTENSION772vm-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;773vm-test-run-pgsodium> server # [  110.357774] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE EXTENSION774vm-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;775vm-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;776vm-test-run-pgsodium> server # [  110.374632] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE777vm-test-run-pgsodium> server # [  110.398550] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE778vm-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;779vm-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;780vm-test-run-pgsodium> server # [  110.419102] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE781vm-test-run-pgsodium> server # [  110.419455] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: CREATE ROLE782vm-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;783vm-test-run-pgsodium> server # [  110.426371] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE784vm-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;785vm-test-run-pgsodium> server # [  110.438967] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE786vm-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;787vm-test-run-pgsodium> server # [  110.458568] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE788vm-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;789vm-test-run-pgsodium> server # [  110.496968] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT ROLE790vm-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;791vm-test-run-pgsodium> server # [  110.532885] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT792vm-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;793vm-test-run-pgsodium> server # [  110.561605] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES794vm-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;795vm-test-run-pgsodium> server # [  110.569735] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES796vm-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;797vm-test-run-pgsodium> server # [  110.582875] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES798vm-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;799vm-test-run-pgsodium> server # [  110.598823] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: GRANT800vm-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;801vm-test-run-pgsodium> server # [  110.606272] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE802vm-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 all803vm-test-run-pgsodium> server # [  110.607809] postgres[1421]: 	    on sequences to postgres, anon, authenticated, service_role;804vm-test-run-pgsodium> server # [  110.620101] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES805vm-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 all806vm-test-run-pgsodium> server # [  110.626949] postgres[1421]: 	    on tables to postgres, anon, authenticated, service_role;807vm-test-run-pgsodium> server # [  110.627379] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES808vm-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 all809vm-test-run-pgsodium> server # [  110.636615] postgres[1421]: 	    on functions to postgres, anon, authenticated, service_role;810vm-test-run-pgsodium> server # [  110.642231] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER DEFAULT PRIVILEGES811vm-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';812vm-test-run-pgsodium> server # [  110.658929] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE813vm-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';814vm-test-run-pgsodium> server # [  110.666165] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1420]: ALTER ROLE815vm-test-run-pgsodium> server # [  110.749780] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000001-auth-schema.sql816vm-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;817vm-test-run-pgsodium> server # [  111.045018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE SCHEMA818vm-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 (819vm-test-run-pgsodium> server # [  111.047605] postgres[1452]: 	    instance_id uuid NULL,820vm-test-run-pgsodium> server # [  111.052685] postgres[1452]: 	    id uuid NOT NULL UNIQUE,821vm-test-run-pgsodium> server # [  111.053597] postgres[1452]: 	    aud varchar(255) NULL,822vm-test-run-pgsodium> server # [  111.054271] postgres[1452]: 	    "role" varchar(255) NULL,823vm-test-run-pgsodium> server # [  111.055706] postgres[1452]: 	    email varchar(255) NULL UNIQUE,824vm-test-run-pgsodium> server # [  111.060128] postgres[1452]: 	    encrypted_password varchar(255) NULL,825vm-test-run-pgsodium> server # [  111.065718] postgres[1452]: 	    confirmed_at timestamptz NULL,826vm-test-run-pgsodium> server # [  111.066762] postgres[1452]: 	    invited_at timestamptz NULL,827vm-test-run-pgsodium> server # [  111.067717] postgres[1452]: 	    confirmation_token varchar(255) NULL,828vm-test-run-pgsodium> server # [  111.071723] postgres[1452]: 	    confirmation_sent_at timestamptz NULL,829vm-test-run-pgsodium> server # [  111.073731] postgres[1452]: 	    recovery_token varchar(255) NULL,830vm-test-run-pgsodium> server # [  111.074780] postgres[1452]: 	    recovery_sent_at timestamptz NULL,831vm-test-run-pgsodium> server # [  111.075195] postgres[1452]: 	    email_change_token varchar(255) NULL,832vm-test-run-pgsodium> server # [  111.076303] postgres[1452]: 	    email_change varchar(255) NULL,833vm-test-run-pgsodium> server # [  111.076697] postgres[1452]: 	    email_change_sent_at timestamptz NULL,834vm-test-run-pgsodium> server # [  111.082737] postgres[1452]: 	    last_sign_in_at timestamptz NULL,835vm-test-run-pgsodium> server # [  111.083765] postgres[1452]: 	    raw_app_meta_data jsonb NULL,836vm-test-run-pgsodium> server # [  111.091796] postgres[1452]: 	    raw_user_meta_data jsonb NULL,837vm-test-run-pgsodium> server # [  111.093110] postgres[1452]: 	    is_super_admin bool NULL,838vm-test-run-pgsodium> server # [  111.093995] postgres[1452]: 	    created_at timestamptz NULL,839vm-test-run-pgsodium> server # [  111.096054] postgres[1452]: 	    updated_at timestamptz NULL,840vm-test-run-pgsodium> server # [  111.103942] postgres[1452]: 	    CONSTRAINT users_pkey PRIMARY KEY (id)841vm-test-run-pgsodium> server # [  111.108291] postgres[1452]: 	);842vm-test-run-pgsodium> server # [  111.159957] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE843vm-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);844vm-test-run-pgsodium> server # [  111.187692] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX845vm-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);846vm-test-run-pgsodium> server # [  111.216165] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX847vm-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.';848vm-test-run-pgsodium> server # [  111.223497] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT849vm-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 (850vm-test-run-pgsodium> server # [  111.237366] postgres[1452]: 	    instance_id uuid NULL,851vm-test-run-pgsodium> server # [  111.243086] postgres[1452]: 	    id bigserial NOT NULL,852vm-test-run-pgsodium> server # [  111.243940] postgres[1452]: 	    "token" varchar(255) NULL,853vm-test-run-pgsodium> server # [  111.249551] postgres[1452]: 	    user_id varchar(255) NULL,854vm-test-run-pgsodium> server # [  111.250476] postgres[1452]: 	    revoked bool NULL,855vm-test-run-pgsodium> server # [  111.251596] postgres[1452]: 	    created_at timestamptz NULL,856vm-test-run-pgsodium> server # [  111.251931] postgres[1452]: 	    updated_at timestamptz NULL,857vm-test-run-pgsodium> server # [  111.253100] postgres[1452]: 	    CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)858vm-test-run-pgsodium> server # [  111.253954] postgres[1452]: 	);859vm-test-run-pgsodium> server # [  111.314264] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE860vm-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);861vm-test-run-pgsodium> server # [  111.351215] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX862vm-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);863vm-test-run-pgsodium> server # [  111.377816] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX864vm-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);865vm-test-run-pgsodium> server # [  111.409192] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX866vm-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.';867vm-test-run-pgsodium> server # [  111.419657] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT868vm-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 (869vm-test-run-pgsodium> server # [  111.426093] postgres[1452]: 	    id uuid NOT NULL,870vm-test-run-pgsodium> server # [  111.427477] postgres[1452]: 	    uuid uuid NULL,871vm-test-run-pgsodium> server # [  111.434601] postgres[1452]: 	    raw_base_config text NULL,872vm-test-run-pgsodium> server # [  111.435344] postgres[1452]: 	    created_at timestamptz NULL,873vm-test-run-pgsodium> server # [  111.436099] postgres[1452]: 	    updated_at timestamptz NULL,874vm-test-run-pgsodium> server # [  111.442880] postgres[1452]: 	    CONSTRAINT instances_pkey PRIMARY KEY (id)875vm-test-run-pgsodium> server # [  111.456021] postgres[1452]: 	);876vm-test-run-pgsodium> server # [  111.491928] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE877vm-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.';878vm-test-run-pgsodium> server # [  111.507018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT879vm-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 (880vm-test-run-pgsodium> server # [  111.517700] postgres[1452]: 	    instance_id uuid NULL,881vm-test-run-pgsodium> server # [  111.518598] postgres[1452]: 	    id uuid NOT NULL,882vm-test-run-pgsodium> server # [  111.519518] postgres[1452]: 	    payload json NULL,883vm-test-run-pgsodium> server # [  111.520114] postgres[1452]: 	    created_at timestamptz NULL,884vm-test-run-pgsodium> server # [  111.521629] postgres[1452]: 	    CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)885vm-test-run-pgsodium> server # [  111.522604] postgres[1452]: 	);886vm-test-run-pgsodium> server # [  111.609796] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE887vm-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);888vm-test-run-pgsodium> server # [  111.655451] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE INDEX889vm-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.';890vm-test-run-pgsodium> server # [  111.667220] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT891vm-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 (892vm-test-run-pgsodium> server # [  111.672307] postgres[1452]: 	    "version" varchar(255) NOT NULL,893vm-test-run-pgsodium> server # [  111.687349] postgres[1452]: 	    CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")894vm-test-run-pgsodium> server # [  111.688635] postgres[1452]: 	);895vm-test-run-pgsodium> server # [  111.699189] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE TABLE896vm-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.';897vm-test-run-pgsodium> server # [  111.721365] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: COMMENT898vm-test-run-pgsodium> server # [  111.745416] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: INSERT 0 7899vm-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 $$900vm-test-run-pgsodium> server # [  111.761010] postgres[1452]: 	  select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;901vm-test-run-pgsodium> server # [  111.763044] postgres[1452]: 	$$ language sql stable;902vm-test-run-pgsodium> server # [  111.766948] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE FUNCTION903vm-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 $$904vm-test-run-pgsodium> server # [  111.771202] postgres[1452]: 	  select nullif(current_setting('request.jwt.claim.role', true), '')::text;905vm-test-run-pgsodium> server # [  111.771577] postgres[1452]: 	$$ language sql stable;906vm-test-run-pgsodium> server # [  111.783770] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE FUNCTION907vm-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 $$908vm-test-run-pgsodium> server # [  111.787680] postgres[1452]: 	  select nullif(current_setting('request.jwt.claim.email', true), '')::text;909vm-test-run-pgsodium> server # [  111.788573] postgres[1452]: 	$$ language sql stable;910vm-test-run-pgsodium> server # [  111.805957] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE FUNCTION911vm-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;912vm-test-run-pgsodium> server # [  111.815263] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT913vm-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;914vm-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;915vm-test-run-pgsodium> server # [  111.838678] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: CREATE ROLE916vm-test-run-pgsodium> server # [  111.847121] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT917vm-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;918vm-test-run-pgsodium> server # [  111.859382] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT919vm-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;920vm-test-run-pgsodium> server # [  111.871037] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: GRANT921vm-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";922vm-test-run-pgsodium> server # [  111.879263] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER ROLE923vm-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;924vm-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;925vm-test-run-pgsodium> server # [  111.913052] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE926vm-test-run-pgsodium> server # [  111.919199] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE927vm-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;928vm-test-run-pgsodium> server # [  111.941821] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE929vm-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;930vm-test-run-pgsodium> server # [  111.959199] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE931vm-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;932vm-test-run-pgsodium> server # [  111.980210] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1451]: ALTER TABLE933vm-test-run-pgsodium> server # [  112.044020] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000002-storage-schema.sql934vm-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;935vm-test-run-pgsodium> server # [  112.327778] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: CREATE SCHEMA936vm-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;937vm-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";938vm-test-run-pgsodium> server # [  112.342019] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: CREATE ROLE939vm-test-run-pgsodium> server # [  112.347231] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: ALTER ROLE940vm-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;941vm-test-run-pgsodium> server # [  112.363214] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: GRANT942vm-test-run-pgsodium> server # [  112.390877] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1457]: DO943vm-test-run-pgsodium> server # [  112.418466] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running init-script: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/init-scripts/00000000000003-post-setup.sql944vm-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;945vm-test-run-pgsodium> server # [  112.674601] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: ALTER ROLE946vm-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;947vm-test-run-pgsodium> server # [  112.680927] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: ALTER ROLE948vm-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()949vm-test-run-pgsodium> server # [  112.682910] postgres[1460]: 	RETURNS event_trigger950vm-test-run-pgsodium> server # [  112.684337] postgres[1460]: 	LANGUAGE plpgsql951vm-test-run-pgsodium> server # [  112.686449] postgres[1460]: 	AS $$952vm-test-run-pgsodium> server # [  112.687451] postgres[1460]: 	DECLARE953vm-test-run-pgsodium> server # [  112.690354] postgres[1460]: 	  schema_is_cron bool;954vm-test-run-pgsodium> server # [  112.691450] postgres[1460]: 	BEGIN955vm-test-run-pgsodium> server # [  112.692211] postgres[1460]: 	  schema_is_cron = (956vm-test-run-pgsodium> server # [  112.693614] postgres[1460]: 	    SELECT n.nspname = 'cron'957vm-test-run-pgsodium> server # [  112.695716] postgres[1460]: 	    FROM pg_event_trigger_ddl_commands() AS ev958vm-test-run-pgsodium> server # [  112.697561] postgres[1460]: 	    LEFT JOIN pg_catalog.pg_namespace AS n959vm-test-run-pgsodium> server # [  112.699414] postgres[1460]: 	      ON ev.objid = n.oid960vm-test-run-pgsodium> server # [  112.701206] postgres[1460]: 	  );961vm-test-run-pgsodium> server # [  112.702330] postgres[1460]:962vm-test-run-pgsodium> server # [  112.705052] postgres[1460]: 	  IF schema_is_cron963vm-test-run-pgsodium> server # [  112.705680] postgres[1460]: 	  THEN964vm-test-run-pgsodium> server # [  112.706220] postgres[1460]: 	    grant usage on schema cron to postgres with grant option;965vm-test-run-pgsodium> server # [  112.706788] postgres[1460]:966vm-test-run-pgsodium> server # [  112.707300] postgres[1460]: 	    alter default privileges in schema cron grant all on tables to postgres with grant option;967vm-test-run-pgsodium> server # [  112.709399] postgres[1460]: 	    alter default privileges in schema cron grant all on functions to postgres with grant option;968vm-test-run-pgsodium> server # [  112.710945] postgres[1460]: 	    alter default privileges in schema cron grant all on sequences to postgres with grant option;969vm-test-run-pgsodium> server # [  112.713581] postgres[1460]:970vm-test-run-pgsodium> server # [  112.713988] postgres[1460]: 	    alter default privileges for user supabase_admin in schema cron grant all971vm-test-run-pgsodium> server # [  112.714600] postgres[1460]: 	        on sequences to postgres with grant option;972vm-test-run-pgsodium> server # [  112.714907] postgres[1460]: 	    alter default privileges for user supabase_admin in schema cron grant all973vm-test-run-pgsodium> server # [  112.716719] postgres[1460]: 	        on tables to postgres with grant option;974vm-test-run-pgsodium> server # [  112.717166] postgres[1460]: 	    alter default privileges for user supabase_admin in schema cron grant all975vm-test-run-pgsodium> server # [  112.717740] postgres[1460]: 	        on functions to postgres with grant option;976vm-test-run-pgsodium> server # [  112.718770] postgres[1460]:977vm-test-run-pgsodium> server # [  112.720101] postgres[1460]: 	    grant all privileges on all tables in schema cron to postgres with grant option;978vm-test-run-pgsodium> server # [  112.723085] postgres[1460]:979vm-test-run-pgsodium> server # [  112.723466] postgres[1460]: 	  END IF;980vm-test-run-pgsodium> server # [  112.726640] postgres[1460]:981vm-test-run-pgsodium> server # [  112.729205] postgres[1460]: 	END;982vm-test-run-pgsodium> server # [  112.729573] postgres[1460]: 	$$;983vm-test-run-pgsodium> server # [  112.731386] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE FUNCTION984vm-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')985vm-test-run-pgsodium> server # [  112.733784] postgres[1460]: 	EXECUTE PROCEDURE extensions.grant_pg_cron_access();986vm-test-run-pgsodium> server # [  112.748724] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE EVENT TRIGGER987vm-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';988vm-test-run-pgsodium> server # [  112.759272] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: COMMENT989vm-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()990vm-test-run-pgsodium> server # [  112.769974] postgres[1460]: 	RETURNS event_trigger991vm-test-run-pgsodium> server # [  112.770690] postgres[1460]: 	LANGUAGE plpgsql992vm-test-run-pgsodium> server # [  112.771965] postgres[1460]: 	AS $$993vm-test-run-pgsodium> server # [  112.773363] postgres[1460]: 	BEGIN994vm-test-run-pgsodium> server # [  112.774219] postgres[1460]: 	  IF EXISTS (995vm-test-run-pgsodium> server # [  112.775132] postgres[1460]: 	    SELECT 1996vm-test-run-pgsodium> server # [  112.777892] postgres[1460]: 	    FROM pg_event_trigger_ddl_commands() AS ev997vm-test-run-pgsodium> server # [  112.778632] postgres[1460]: 	    JOIN pg_extension AS ext998vm-test-run-pgsodium> server # [  112.779523] postgres[1460]: 	    ON ev.objid = ext.oid999vm-test-run-pgsodium> server # [  112.780885] postgres[1460]: 	    WHERE ext.extname = 'pg_net'1000vm-test-run-pgsodium> server # [  112.781848] postgres[1460]: 	  )1001vm-test-run-pgsodium> server # [  112.782715] postgres[1460]: 	  THEN1002vm-test-run-pgsodium> server # [  112.783711] postgres[1460]: 	    IF NOT EXISTS (1003vm-test-run-pgsodium> server # [  112.785017] postgres[1460]: 	      SELECT 11004vm-test-run-pgsodium> server # [  112.785389] postgres[1460]: 	      FROM pg_roles1005vm-test-run-pgsodium> server # [  112.786025] postgres[1460]: 	      WHERE rolname = 'supabase_functions_admin'1006vm-test-run-pgsodium> server # [  112.787856] postgres[1460]: 	    )1007vm-test-run-pgsodium> server # [  112.789744] postgres[1460]: 	    THEN1008vm-test-run-pgsodium> server # [  112.790429] postgres[1460]: 	      CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1009vm-test-run-pgsodium> server # [  112.792145] postgres[1460]: 	    END IF;1010vm-test-run-pgsodium> server # [  112.793936] postgres[1460]:1011vm-test-run-pgsodium> server # [  112.795109] postgres[1460]: 	    GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1012vm-test-run-pgsodium> server # [  112.797096] postgres[1460]:1013vm-test-run-pgsodium> server # [  112.798725] postgres[1460]: 	    ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1014vm-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;1015vm-test-run-pgsodium> server # [  112.803829] postgres[1460]:1016vm-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;1017vm-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;1018vm-test-run-pgsodium> server # [  112.807939] postgres[1460]:1019vm-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;1020vm-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;1021vm-test-run-pgsodium> server # [  112.811082] postgres[1460]:1022vm-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;1023vm-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;1024vm-test-run-pgsodium> server # [  112.814841] postgres[1460]: 	  END IF;1025vm-test-run-pgsodium> server # [  112.816174] postgres[1460]: 	END;1026vm-test-run-pgsodium> server # [  112.816787] postgres[1460]: 	$$;1027vm-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';1028vm-test-run-pgsodium> server # [  112.819427] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE FUNCTION1029vm-test-run-pgsodium> server # [  112.819989] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: COMMENT1030vm-test-run-pgsodium> server # [  112.838628] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: DO1031vm-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;1032vm-test-run-pgsodium> server # [  112.846993] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: CREATE ROLE1033vm-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;1034vm-test-run-pgsodium> server # [  112.857974] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1035vm-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;1036vm-test-run-pgsodium> server # [  112.866061] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1037vm-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;1038vm-test-run-pgsodium> server # [  112.882316] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1039vm-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;1040vm-test-run-pgsodium> server # [  112.895745] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1041vm-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;1042vm-test-run-pgsodium> server # [  112.913660] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1043vm-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;1044vm-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;1045vm-test-run-pgsodium> server # [  112.930755] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1046vm-test-run-pgsodium> server # [  112.931286] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1047vm-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;1048vm-test-run-pgsodium> server # [  112.963285] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1049vm-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;1050vm-test-run-pgsodium> server # [  113.033033] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: GRANT1051vm-test-run-pgsodium> server # [  113.057198] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1459]: DO1052vm-test-run-pgsodium> server # [  113.121766] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running pgbouncer auth schema...1053vm-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;1054vm-test-run-pgsodium> server # [  113.586376] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: CREATE ROLE1055vm-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;1056vm-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;1057vm-test-run-pgsodium> server # [  113.605972] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: REVOKE1058vm-test-run-pgsodium> server # [  113.690285] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: CREATE SCHEMA1059vm-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)1060vm-test-run-pgsodium> server # [  113.696880] postgres[1474]: 	RETURNS TABLE(username TEXT, password TEXT) AS1061vm-test-run-pgsodium> server # [  113.699352] postgres[1474]: 	$$1062vm-test-run-pgsodium> server # [  113.705506] postgres[1474]: 	BEGIN1063vm-test-run-pgsodium> server # [  113.706232] postgres[1474]: 	    RAISE WARNING 'PgBouncer auth request: %', p_usename;1064vm-test-run-pgsodium> server # [  113.707836] postgres[1474]:1065vm-test-run-pgsodium> server # [  113.721298] postgres[1474]: 	    RETURN QUERY1066vm-test-run-pgsodium> server # [  113.722672] postgres[1474]: 	    SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1067vm-test-run-pgsodium> server # [  113.723261] postgres[1474]: 	    WHERE usename = p_usename;1068vm-test-run-pgsodium> server # [  113.723691] postgres[1474]: 	END;1069vm-test-run-pgsodium> server # [  113.724000] postgres[1474]: 	$$ LANGUAGE plpgsql1070vm-test-run-pgsodium> server # [  113.727845] postgres[1474]: 	SET search_path = ''1071vm-test-run-pgsodium> server # [  113.740035] postgres[1474]: 	SECURITY DEFINER;1072vm-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;1073vm-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;1074vm-test-run-pgsodium> server # [  113.786961] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: CREATE FUNCTION1075vm-test-run-pgsodium> server # [  113.802763] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: REVOKE1076vm-test-run-pgsodium> server # [  113.814195] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1469]: GRANT1077vm-test-run-pgsodium> server # [  113.816060] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running stat extension...1078vm-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;1079vm-test-run-pgsodium> server # [  114.261018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1482]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE:  schema "extensions" already exists, skipping1080vm-test-run-pgsodium> server # [  114.328102] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1482]: CREATE SCHEMA1081vm-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;1082vm-test-run-pgsodium> server # [  115.193815] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1482]: CREATE EXTENSION1083vm-test-run-pgsodium> server # [  115.255724] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/10000000000000_demote-postgres.sql1084vm-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;1085vm-test-run-pgsodium> server # [  115.587498] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1086vm-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;1087vm-test-run-pgsodium> server # [  115.602916] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1088vm-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;1089vm-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;1090vm-test-run-pgsodium> server # [  115.619096] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1091vm-test-run-pgsodium> server # [  115.639821] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1092vm-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;1093vm-test-run-pgsodium> server # [  115.652742] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1094vm-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;1095vm-test-run-pgsodium> server # [  115.663917] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1096vm-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;1097vm-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;1098vm-test-run-pgsodium> server # [  115.676173] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1099vm-test-run-pgsodium> server # [  115.708025] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1100vm-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;1101vm-test-run-pgsodium> server # [  115.755018] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: GRANT1102vm-test-run-pgsodium> server # [  115.784612] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: DO1103vm-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;1104vm-test-run-pgsodium> server # [  115.802701] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1489]: ALTER ROLE1105vm-test-run-pgsodium> server # [  115.849791] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211115181400_update-auth-permissions.sql1106vm-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;1107vm-test-run-pgsodium> server # [  116.244392] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1108vm-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;1109vm-test-run-pgsodium> server # [  116.247944] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1110vm-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;1111vm-test-run-pgsodium> server # [  116.262641] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1112vm-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;1113vm-test-run-pgsodium> server # [  116.271297] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE1114vm-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;1115vm-test-run-pgsodium> server # [  116.282737] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE1116vm-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;1117vm-test-run-pgsodium> server # [  116.294412] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE1118vm-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;1119vm-test-run-pgsodium> server # [  116.302201] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE1120vm-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;1121vm-test-run-pgsodium> server # [  116.313069] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER TABLE1122vm-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;1123vm-test-run-pgsodium> server # [  116.319540] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1124vm-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;1125vm-test-run-pgsodium> server # [  116.335375] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1126vm-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;1127vm-test-run-pgsodium> server # [  116.342881] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1128vm-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;1129vm-test-run-pgsodium> server # [  116.374496] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: GRANT1130vm-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;1131vm-test-run-pgsodium> server # [  116.407405] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER DEFAULT PRIVILEGES1132vm-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;1133vm-test-run-pgsodium> server # [  116.423385] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER DEFAULT PRIVILEGES1134vm-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;1135vm-test-run-pgsodium> server # [  116.433440] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1495]: ALTER DEFAULT PRIVILEGES1136vm-test-run-pgsodium> server # [  116.478374] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211118015519_create-realtime-schema.sql1137vm-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;1138vm-test-run-pgsodium> server # [  116.864696] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1509]: CREATE SCHEMA1139vm-test-run-pgsodium> server # [  116.892117] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1140vm-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;1141vm-test-run-pgsodium> server # [  117.212641] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT1142vm-test-run-pgsodium> server # [  117.214525] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT1143vm-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;1144vm-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;1145vm-test-run-pgsodium> server # [  117.224880] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT1146vm-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;1147vm-test-run-pgsodium> server # [  117.250279] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1519]: GRANT1148vm-test-run-pgsodium> server # [  117.283947] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211124212715_update-auth-owner.sql1149vm-test-run-pgsodium> server # [  117.610894] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1524]: DO1150vm-test-run-pgsodium> server # [  117.619585] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1524]: DO1151vm-test-run-pgsodium> server # [  117.634945] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1524]: DO1152vm-test-run-pgsodium> server # [  117.679734] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1153vm-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;1154vm-test-run-pgsodium> server # [  117.962997] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1527]: ALTER DEFAULT PRIVILEGES1155vm-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;1156vm-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;1157vm-test-run-pgsodium> server # [  117.971297] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1527]: ALTER DEFAULT PRIVILEGES1158vm-test-run-pgsodium> server # [  117.978348] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1527]: ALTER DEFAULT PRIVILEGES1159vm-test-run-pgsodium> server # [  118.023142] 1q9npbcmdss296g46zmk3v0k1c4khipk-supabase-db-init[1386]: Running migration: /nix/store/mr5g4dffa9ddlvnr1z78p6vddsij51ik-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql