vm-test-run-pg_hashids
checks.aarch64-darwin.ext-pg_hashids
· build #37
· raw
1Machine state will be reset. To keep it, pass --keep-vm-state2start all VLans3start vlan4running vlan (pid 69974; ctl /nix/var/nix/builds/nix-69939-1010607838/vde1.ctl)5(finished: start all VLans, in 0.00 seconds)6Test will time out and terminate in 3600 seconds7run the VM test script8additionally exposed symbols:9 server,10 vlan1,11 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, debug12Running tests for extension: pg_hashids13start all VMs14server: starting vm15mke2fs 1.47.3 (8-Jul-2025)16server # Disk image does not exist, creating the virtualisation disk image...17server # Formatting '/nix/var/nix/builds/nix-69939-1010607838/vm-state-server/tmp.lxNfthPwDN', fmt=raw size=107374182418server # Creating filesystem with 262144 4k blocks and 65536 inodes19server # Filesystem UUID: 8193f694-9e65-4b8e-bec4-8f27bfd7d49720server # Superblock backups stored on blocks:21server # 32768, 98304, 163840, 22937622server # 23server # Allocating group tables: 0/8 done24server # Writing inode tables: 0/8 done25server # Creating journal (8192 blocks): done26server # Writing superblocks and filesystem accounting information: 0/8 done27server # 28server # Virtualisation disk image created.29server: QEMU running (pid 69976)30(finished: start all VMs, in 0.62 seconds)31server: waiting for unit multi-user.target32server: waiting for the VM to finish booting33server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x610f0000]34server # [ 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 202535server # [ 0.000000] KASLR enabled36server # [ 0.000000] random: crng init done37server # [ 0.000000] Machine model: linux,dummy-virt38server # [ 0.000000] efi: UEFI not found.39server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT40server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]41server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f6032c0-0x13f6069ff]42server # [ 0.000000] Zone ranges:43server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff]44server # [ 0.000000] DMA32 empty45server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff]46server # [ 0.000000] Device empty47server # [ 0.000000] Movable zone start for each node48server # [ 0.000000] Early memory node ranges49server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff]50server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]51server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -152server # [ 0.000000] psci: probing for conduit method from DT.53server # [ 0.000000] psci: PSCIv1.1 detected in firmware.54server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs55server # [ 0.000000] psci: Trusted OS migration not required56server # [ 0.000000] psci: SMC Calling Convention v1.057server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u36044858server # [ 0.000000] Detected PIPT I-cache on CPU059server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)60server # [ 0.000000] CPU features: detected: Spectre-v461server # [ 0.000000] CPU features: detected: Spectre-BHB62server # [ 0.000000] alternatives: applying boot alternatives63server # [ 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/azbkglm7ah24mbl2jwpar59bhzsh8r8k-nixos-system-server-test/init regInfo=/nix/store/qvblmlf376ni0p3hwj0rc32jaq15bp2n-closure-info/registration console=ttyAMA0,115200n8 console=tty064server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/qvblmlf376ni0p3hwj0rc32jaq15bp2n-closure-info/registration", will be passed to user space.65server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)66server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)67server # [ 0.000000] Fallback order for Node 0: 068server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 104857669server # [ 0.000000] Policy zone: Normal70server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off71server # [ 0.000000] software IO TLB: area num 2.72server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)73server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=174server # [ 0.000000] allocated 8388608 bytes of page_ext75server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages76server # [ 0.000000] ftrace: allocated 283 pages with 5 groups77server # [ 0.000000] rcu: Hierarchical RCU implementation.78server # [ 0.000000] rcu: RCU event tracing is enabled.79server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.80server # [ 0.000000] Rude variant of Tasks RCU enabled.81server # [ 0.000000] Tracing variant of Tasks RCU enabled.82server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.83server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=284server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.85server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.86server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 087server # [ 0.000000] Root IRQ handler: gic_handle_irq88server # [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143]89server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.90server # [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt).91server # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns92server # [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns93server # [ 0.000146] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)94server # [ 0.000189] Console: colour dummy device 80x2595server # [ 0.000191] printk: legacy console [tty0] enabled96server # [ 0.000250] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)97server # [ 0.000253] pid_max: default: 32768 minimum: 30198server # [ 0.000284] LSM: initializing lsm=capability,landlock,yama,bpf99server # [ 0.000338] landlock: Up and running.100server # [ 0.000339] Yama: becoming mindful.101server # [ 0.000508] LSM support for eBPF active102server # [ 0.000537] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)103server # [ 0.000546] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)104server # [ 0.000782] cacheinfo: Unable to detect cache hierarchy for CPU 0105server # [ 0.000975] rcu: Hierarchical SRCU implementation.106server # [ 0.000977] rcu: Max phase no-delay instances is 1000.107server # [ 0.001001] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level108server # [ 0.001315] EFI services will not be available.109server # [ 0.001342] smp: Bringing up secondary CPUs ...110server # [ 0.001576] Detected PIPT I-cache on CPU1111server # [ 0.001687] CPU1: Booted secondary processor 0x0000000001 [0x610f0000]112server # [ 0.001859] smp: Brought up 1 node, 2 CPUs113server # [ 0.001866] SMP: Total of 2 processors activated.114server # [ 0.001867] CPU: All CPU(s) started at EL1115server # [ 0.001871] CPU features: detected: ARMv8.4 Translation Table Level116server # [ 0.001873] CPU features: detected: Data cache clean to the PoU not required for I/D coherence117server # [ 0.001875] CPU features: detected: Common not Private translations118server # [ 0.001876] CPU features: detected: CRC32 instructions119server # [ 0.001877] CPU features: detected: Data cache clean to Point of Deep Persistence120server # [ 0.001879] CPU features: detected: Data cache clean to Point of Persistence121server # [ 0.001880] CPU features: detected: Data independent timing control (DIT)122server # [ 0.001881] CPU features: detected: E0PD123server # [ 0.001882] CPU features: detected: Enhanced Privileged Access Never124server # [ 0.001884] CPU features: detected: Fine Grained Traps125server # [ 0.001885] CPU features: detected: Generic authentication (IMP DEF algorithm)126server # [ 0.001903] CPU features: detected: RCpc load-acquire (LDAPR)127server # [ 0.001906] CPU features: detected: LSE atomic instructions128server # [ 0.001907] CPU features: detected: Privileged Access Never129server # [ 0.001908] CPU features: detected: RAS Extension Support130server # [ 0.001910] CPU features: detected: Speculation barrier (SB)131server # [ 0.001933] CPU features: detected: TLB range maintenance instructions132server # [ 0.001985] alternatives: applying system-wide alternatives133server # [ 0.002984] Memory: 3925200K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 221240K reserved, 32768K cma-reserved)134server # [ 0.003366] devtmpfs: initialized135server # [ 0.004176] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns136server # [ 0.004190] futex hash table entries: 512 (order: 3, 32768 bytes, linear)137server # [ 0.004289] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL138server # [ 0.004291] 0 pages in range for non-PLT usage139server # [ 0.004292] 509248 pages in range for PLT usage140server # [ 0.004348] pinctrl core: initialized pinctrl subsystem141server # [ 0.004617] DMI not present or invalid.142server # [ 0.005730] NET: Registered PF_NETLINK/PF_ROUTE protocol family143server # [ 0.006452] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations144server # [ 0.006546] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations145server # [ 0.006661] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations146server # [ 0.006709] audit: initializing netlink subsys (disabled)147server # [ 0.006910] audit: type=2000 audit(0.000:1): state=initialized audit_enabled=0 res=1148server # [ 0.007167] thermal_sys: Registered thermal governor 'fair_share'149server # [ 0.007169] thermal_sys: Registered thermal governor 'bang_bang'150server # [ 0.007171] thermal_sys: Registered thermal governor 'step_wise'151server # [ 0.007172] thermal_sys: Registered thermal governor 'user_space'152server # [ 0.007173] thermal_sys: Registered thermal governor 'power_allocator'153server # [ 0.007283] cpuidle: using governor ladder154server # [ 0.007345] cpuidle: using governor menu155server # [ 0.007445] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.156server # [ 0.007519] ASID allocator initialised with 65536 entries157server # [ 0.007833] Serial: AMBA PL011 UART driver158server # [ 0.009150] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1159server # [ 0.009198] printk: legacy console [ttyAMA0] enabled160server # [ 0.051543] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages161server # [ 0.051956] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page162server # [ 0.052329] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages163server # [ 0.052718] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page164server # [ 0.053113] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages165server # [ 0.053523] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page166server # [ 0.053821] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages167server # [ 0.054247] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page168server # [ 0.055437] fbcon: Taking over console169server # [ 0.055656] ACPI: Interpreter disabled.170server # [ 0.056202] iommu: Default domain type: Translated171server # [ 0.056373] iommu: DMA domain TLB invalidation policy: strict mode172server # [ 0.056832] SCSI subsystem initialized173server # [ 0.057229] usbcore: registered new interface driver usbfs174server # [ 0.057534] usbcore: registered new interface driver hub175server # [ 0.057856] usbcore: registered new device driver usb176server # [ 0.058233] pps_core: LinuxPPS API ver. 1 registered177server # [ 0.058436] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>178server # [ 0.058801] PTP clock support registered179server # [ 0.059088] EDAC MC: Ver: 3.0.0180server # [ 0.059428] scmi_core: SCMI protocol bus registered181server # [ 0.059946] FPGA manager framework182server # [ 0.060194] Advanced Linux Sound Architecture Driver Initialized.183server # [ 0.060903] vgaarb: loaded184server # [ 0.061519] clocksource: Switched to clocksource arch_sys_counter185server # [ 0.062060] VFS: Disk quotas dquot_6.6.0186server # [ 0.062284] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)187server # [ 0.062904] netfs: FS-Cache loaded188server # [ 0.063133] pnp: PnP ACPI: disabled189server # [ 0.070802] NET: Registered PF_INET protocol family190server # [ 0.071250] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)191server # [ 0.083490] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)192server # [ 0.084200] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)193server # [ 0.084790] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)194server # [ 0.085160] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)195server # [ 0.085638] TCP: Hash tables configured (established 32768 bind 32768)196server # [ 0.086052] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)197server # [ 0.086507] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)198server # [ 0.086927] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)199server # [ 0.087402] NET: Registered PF_UNIX/PF_LOCAL protocol family200server # [ 0.087700] NET: Registered PF_XDP protocol family201server # [ 0.088032] PCI: CLS 0 bytes, default 64202server # [ 0.088317] Trying to unpack rootfs image as initramfs...203server # [ 0.093642] kvm [1]: HYP mode not available204server # [ 0.104550] Initialise system trusted keyrings205server # [ 0.104764] workingset: timestamp_bits=42 max_order=20 bucket_order=0206server # [ 0.105495] squashfs: version 4.0 (2009/01/31) Phillip Lougher207server # [ 0.106134] 9p: Installing v9fs 9p2000 file system support208server # [ 0.114618] Key type asymmetric registered209server # [ 0.114767] Asymmetric key parser 'x509' registered210server # [ 0.114957] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)211server # [ 0.115243] io scheduler mq-deadline registered212server # [ 0.115566] io scheduler kyber registered213server # [ 0.116734] pl061_gpio 9030000.pl061: PL061 GPIO chip registered214server # [ 0.117185] ledtrig-cpu: registered to indicate activity on CPUs215server # [ 0.117823] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:216server # [ 0.118269] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000217server # [ 0.118581] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000218server # [ 0.118973] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000219server # [ 0.119420] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits220server # [ 0.119697] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]221server # [ 0.120109] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00222server # [ 0.120541] pci_bus 0000:00: root bus resource [bus 00-ff]223server # [ 0.120877] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]224server # [ 0.121194] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]225server # [ 0.121428] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]226server # [ 0.121967] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint227server # [ 0.122537] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint228server # [ 0.123071] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]229server # [ 0.123378] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]230server # [ 0.123579] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]231server # [ 0.123808] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]232server # [ 0.124278] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint233server # [ 0.124787] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]234server # [ 0.125081] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]235server # [ 0.125440] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]236server # [ 0.126350] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint237server # [ 0.126789] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]238server # [ 0.127121] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]239server # [ 0.127526] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]240server # [ 0.128087] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint241server # [ 0.128562] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]242server # [ 0.128869] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]243server # [ 0.129190] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]244server # [ 0.129781] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint245server # [ 0.130106] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]246server # [ 0.130306] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]247server # [ 0.130604] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]248server # [ 0.131235] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint249server # [ 0.131565] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]250server # [ 0.131746] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]251server # [ 0.131942] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]252server # [ 0.132485] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint253server # [ 0.132943] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]254server # [ 0.133115] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]255server # [ 0.133311] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]256server # [ 0.133492] Freeing initrd memory: 10684K257server # [ 0.134571] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]258server # [ 0.135793] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint259server # [ 0.136075] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]260server # [ 0.136280] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]261server # [ 0.136619] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint262server # [ 0.136932] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]263server # [ 0.137637] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]264server # [ 0.138417] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint265server # [ 0.138694] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]266server # [ 0.138961] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint267server # [ 0.139245] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]268server # [ 0.139519] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]269server # [ 0.139734] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270server # [ 0.140087] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint271server # [ 0.140580] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]272server # [ 0.140959] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]273server # [ 0.141172] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]274server # [ 0.141710] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned275server # [ 0.141938] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned276server # [ 0.142306] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned277server # [ 0.142600] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned278server # [ 0.143859] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned279server # [ 0.144333] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned280server # [ 0.145140] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned281server # [ 0.145674] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned282server # [ 0.146094] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned283server # [ 0.147296] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned284server # [ 0.148491] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned285server # [ 0.149110] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned286server # [ 0.149422] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned287server # [ 0.150418] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned288server # [ 0.150660] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned289server # [ 0.150877] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned290server # [ 0.151748] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned291server # [ 0.152210] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned292server # [ 0.152439] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned293server # [ 0.152782] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned294server # [ 0.153044] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned295server # [ 0.153326] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned296server # [ 0.153614] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned297server # [ 0.154126] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned298server # [ 0.154548] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned299server # [ 0.155018] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned300server # [ 0.155259] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned301server # [ 0.155490] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned302server # [ 0.155709] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned303server # [ 0.155929] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned304server # [ 0.156227] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned305server # [ 0.156426] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned306server # [ 0.156611] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned307server # [ 0.156811] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned308server # [ 0.157027] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]309server # [ 0.157216] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]310server # [ 0.157418] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]311server # [ 0.157731] pci 0000:00:0a.0: enabling device (0000 -> 0002)312server # [ 0.161145] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)313server # [ 0.162222] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)314server # [ 0.162925] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)315server # [ 0.163610] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)316server # [ 0.164270] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)317server # [ 0.165236] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)318server # [ 0.166590] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)319server # [ 0.167738] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)320server # [ 0.169263] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)321server # [ 0.170977] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)322server # [ 0.172749] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)323server # [ 0.174358] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled324server # [ 0.174995] msm_serial: driver initialized325server # [ 0.175238] SuperH (H)SCI(F) driver initialized326server # [ 0.175425] STM32 USART driver initialized327server # [ 0.182219] loop: module loaded328server # [ 0.182458] virtio_blk virtio5: 2/0/0 default/read/poll queues329server # [ 0.183119] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)330server # [ 0.184388] megasas: 07.727.03.00-rc1331server # [ 0.185089] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]332server # [ 0.187595] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000333server # [ 0.187984] Intel/Sharp Extended Query Table at 0x0031334server # [ 0.188631] Using buffer write method335server # [ 0.188844] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]336server # [ 0.189690] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000337server # [ 0.190181] Intel/Sharp Extended Query Table at 0x0031338server # [ 0.190802] Using buffer write method339server # [ 0.191060] Concatenating MTD devices:340server # [ 0.191288] (0): "0.flash"341server # [ 0.191428] (1): "0.flash"342server # [ 0.191599] into device "0.flash"343server # [ 0.193457] tun: Universal TUN/TAP device driver, 1.6344server # [ 0.195618] thunder_xcv, ver 1.0345server # [ 0.195755] thunder_bgx, ver 1.0346server # [ 0.195883] nicpf, ver 1.0347server # [ 0.196152] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version348server # [ 0.196423] hns3: Copyright (c) 2017 Huawei Corporation.349server # [ 0.196667] hclge is initializing350server # [ 0.196863] e1000: Intel(R) PRO/1000 Network Driver351server # [ 0.197039] e1000: Copyright (c) 1999-2006 Intel Corporation.352server # [ 0.197266] e1000e: Intel(R) PRO/1000 Network Driver353server # [ 0.197717] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.354server # [ 0.198172] igb: Intel(R) Gigabit Ethernet Network Driver355server # [ 0.198419] igb: Copyright (c) 2007-2014 Intel Corporation.356server # [ 0.198738] igbvf: Intel(R) Gigabit Virtual Function Network Driver357server # [ 0.198965] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.358server # [ 0.199264] sky2: driver version 1.30359server # [ 0.199895] usbcore: registered new interface driver usb-storage360server # [ 0.200196] ehci-pci 0000:00:0a.0: EHCI Host Controller361server # [ 0.201354] usbcore: registered new interface driver usbserial_generic362server # [ 0.201455] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1363server # [ 0.201746] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000364server # [ 0.202047] usbserial: USB Serial support registered for generic365server # [ 0.204108] rtc-pl031 9010000.pl031: registered as rtc0366server # [ 0.204664] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:35:16 UTC (1791196516)367server # [ 0.205797] i2c_dev: i2c /dev entries driver368server # [ 0.207213] sdhci: Secure Digital Host Controller Interface driver369server # [ 0.207512] sdhci: Copyright(c) Pierre Ossman370server # [ 0.207844] Synopsys Designware Multimedia Card Interface Driver371server # [ 0.208170] sdhci-pltfm: SDHCI platform and OF driver helper372server # [ 0.208754] hid: raw HID events driver (C) Jiri Kosina373server # [ 0.209175] usbcore: registered new interface driver usbhid374server # [ 0.209508] usbhid: USB HID core driver375server # [ 0.210614] armv8-pmu pmu: hw perfevents: failed to probe PMU!376server # [ 0.212213] drop_monitor: Initializing network drop monitor service377server # [ 0.213052] NET: Registered PF_INET6 protocol family378server # [ 0.213614] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00379server # [ 0.214290] hub 1-0:1.0: USB hub found380server # [ 0.214682] hub 1-0:1.0: 6 ports detected381server # [ 0.215542] Segment Routing with IPv6382server # [ 0.215991] In-situ OAM (IOAM) with IPv6383server # [ 0.216474] NET: Registered PF_PACKET protocol family384server # [ 0.217270] 9pnet: Installing 9P2000 support385server # [ 0.218907] Key type dns_resolver registered386server # [ 0.220795] registered taskstats version 1387server # [ 0.221464] Loading compiled-in X.509 certificates388server # [ 0.223812] Demotion targets for Node 0: null389server # [ 0.224613] Key type .fscrypt registered390server # [ 0.225140] Key type fscrypt-provisioning registered391server # [ 0.226511] input: gpio-keys as /devices/platform/gpio-keys/input/input0392server # [ 0.231792] clk: Disabling unused clocks393server # [ 0.232178] PM: genpd: Disabling unused power domains394server # [ 0.232703] ALSA device list:395server # [ 0.233100] No soundcards found.396server # [ 0.234779] Freeing unused kernel memory: 4416K397server # [ 0.235091] Run /init as init process398server # [ 0.263037] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev399server # [ 0.270301] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0400server # [ 0.270590] [drm] features: -virgl +edid -resource_blob -host_visible401server # [ 0.270591] [drm] features: -context_init402server # [ 0.271158] [drm] number of scanouts: 1403server # [ 0.271349] [drm] number of cap sets: 0404server # [ 0.272154] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0405server # [ 0.274332] Console: switching to colour frame buffer device 160x50406server # [ 0.278050] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device407server # [ 0.488656] usb 1-1: new high-speed USB device number 2 using ehci-pci408server # [ 0.571679] EXT4-fs (vda): mounted filesystem 8193f694-9e65-4b8e-bec4-8f27bfd7d497 r/w with ordered data mode. Quota mode: none.409server # [ 0.682391] 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/input1410server # [ 0.793902] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0411server # [ 0.885427] EXT4-fs (vda): re-mounted 8193f694-9e65-4b8e-bec4-8f27bfd7d497.412server # [ 0.887798] booting system configuration /nix/store/azbkglm7ah24mbl2jwpar59bhzsh8r8k-nixos-system-server-test413server # [ 0.921586] usb 1-2: new high-speed USB device number 3 using ehci-pci414server # [ 1.086052] 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/input2415server # [ 1.087681] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0416server # [ 3.377725] 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)417server # [ 3.379881] systemd[1]: Detected virtualization qemu.418server # [ 3.380339] systemd[1]: Detected architecture arm64.419server # [ 3.380776] systemd[1]: Detected first boot.420server # [ 3.385364] systemd[1]: Initializing machine ID from random generator.421server # [ 3.390178] systemd[1]: Hostname set to <server>.422server # [ 3.504745] systemd[1]: bpf-restrict-fs: LSM BPF program attached423server # [ 3.649474] NET: Registered PF_VSOCK protocol family424server # [ 3.656294] Guest personality initialized and is inactive425server # [ 3.657202] VMCI host device registered (name=vmci, major=10, minor=261)426server # [ 3.658147] Initialized host personality427server # [ 3.868208] systemd[1]: Applying preset policy.428server # [ 4.552449] systemd[1]: Populated /etc with preset unit settings.429server # [ 5.257572] systemd[1]: Queued start job for default target Multi-User System.430server # [ 5.329648] systemd[1]: Created slice Slice /system/getty.431server # [ 5.330962] systemd[1]: Created slice Slice /system/modprobe.432server # [ 5.332065] systemd[1]: Created slice User and Session Slice.433server # [ 5.333938] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.434server # [ 5.334762] systemd[1]: Started Forward Password Requests to Wall Directory Watch.435server # [ 5.335705] systemd[1]: Expecting device /dev/hvc0...436server # [ 5.336304] systemd[1]: Expecting device /dev/ttyAMA0...437server # [ 5.337067] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...438server # [ 5.337823] systemd[1]: Reached target Local Encrypted Volumes.439server # [ 5.338760] systemd[1]: Reached target Virtual Machines and Containers.440server # [ 5.339584] systemd[1]: Reached target Path Units.441server # [ 5.340680] systemd[1]: Reached target Remote File Systems.442server # [ 5.341885] systemd[1]: Reached target Slice Units.443server # [ 5.342404] systemd[1]: Reached target Swaps.444server # [ 5.349172] systemd[1]: Listening on Process Core Dump Socket.445server # [ 5.353920] systemd[1]: Listening on Credential Encryption/Decryption.446server # [ 5.355257] systemd[1]: Listening on Journal Audit Socket.447server # [ 5.355862] systemd[1]: Listening on Journal Socket (/dev/log).448server # [ 5.356481] systemd[1]: Listening on Journal Sockets.449server # [ 5.357685] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.450server # [ 5.358183] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).451server # [ 5.358820] systemd[1]: Listening on udev Control Socket.452server # [ 5.359278] systemd[1]: Listening on udev Kernel Socket.453server # [ 5.365081] systemd[1]: Mounting Huge Pages File System...454server # [ 5.369262] systemd[1]: Mounting POSIX Message Queue File System...455server # [ 5.374137] systemd[1]: Mounting Kernel Debug File System...456server # [ 5.379717] systemd[1]: Mounting Kernel Trace File System...457server # [ 5.393421] systemd[1]: Starting Create List of Static Device Nodes...458server # [ 5.398510] systemd[1]: Starting Load Kernel Module 9pnet_virtio...459server # [ 5.399999] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).460server # [ 5.405264] systemd[1]: Mounting Kernel Configuration File System...461server # [ 5.408424] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).462server # [ 5.411161] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).463server # [ 5.425019] systemd[1]: Starting Load Kernel Module fuse...464server # [ 5.426480] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).465server # [ 5.428824] 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).466server # [ 5.439533] systemd[1]: Starting Journal Service...467server # [ 5.444484] systemd[1]: Starting Load Kernel Modules...468server # [ 5.451049] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...469server # [ 5.457987] systemd[1]: Starting Remount Root and Kernel File Systems...470server # [ 5.459412] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).471server # [ 5.470252] systemd[1]: Starting Coldplug All udev Devices...472server # [ 5.491567] systemd[1]: Mounted Huge Pages File System.473server # [ 5.494023] systemd[1]: Mounted POSIX Message Queue File System.474server # [ 5.494491] systemd[1]: Mounted Kernel Debug File System.475server # [ 5.495680] systemd[1]: Mounted Kernel Trace File System.476server # [ 5.496030] systemd[1]: Mounted Kernel Configuration File System.477server # [ 5.508885] systemd[1]: Finished Create List of Static Device Nodes.478server # [ 5.515823] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...479server # [ 5.551040] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480server # [ 5.562333] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481server # [ 5.585506] systemd-journald[397]: Collecting audit messages is enabled.482server # [ 5.595647] fuse: init (API version 7.41)483server # [ 5.603014] systemd[1]: modprobe@fuse.service: Deactivated successfully.484server # [ 5.605146] systemd[1]: Finished Load Kernel Module fuse.485server # [ 5.612998] systemd[1]: Mounting FUSE Control File System...486server # [ 5.613095] EXT4-fs (vda): re-mounted 8193f694-9e65-4b8e-bec4-8f27bfd7d497.487server # [ 5.623567] systemd[1]: Finished Load Kernel Modules.488server # [ 5.626671] systemd[1]: Finished Remount Root and Kernel File Systems.489server # [ 5.636125] systemd[1]: Starting Firewall...490server # [ 5.637484] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).491server # [ 5.643541] systemd[1]: Starting Load/Save OS Random Seed...492server # [ 5.625017] systemd-modules-load[398]: Module 'atkbd' is built in493server # [ 5.626639] systemd-modules-load[398]: Module 'loop' is built in494server # [ 5.659668] systemd[1]: Starting Apply Kernel Variables...495server # [ 5.660388] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).496server # [ 5.664538] systemd[1]: Started Journal Service.497server # [ 5.642698] systemd-oomd[399]: No swap; memory pressure usage will be degraded498server # [ 5.652143] systemd[1]: Starting Flush Journal to Persistent Storage...499server # [ 5.653751] systemd[1]: Mounted FUSE Control File System.500server # [ 5.660772] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.501server # [ 5.709681] systemd[1]: Finished Load/Save OS Random Seed.502server # [ 5.711432] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.503server # [ 5.712565] systemd[1]: Reached target First Boot Complete.504server # [ 5.714355] systemd[1]: Starting Create Static Device Nodes in /dev...505server # [ 5.780106] systemd-journald[397]: Received client request to flush runtime journal.506server # [ 5.767398] systemd[1]: Finished Apply Kernel Variables.507server # [ 5.768558] systemd[1]: Finished Flush Journal to Persistent Storage.508server # [ 5.813432] systemd[1]: Finished Coldplug All udev Devices.509server # [ 5.833924] systemd[1]: Finished Create Static Device Nodes in /dev.510server # [ 5.834488] systemd[1]: Reached target Preparation for Local File Systems.511server # [ 5.837654] systemd[1]: Starting Rule-based Manager for Device Events and Files...512server # [ 5.929707] systemd-udevd[439]: Using default interface naming scheme 'v258'.513server # [ 6.104604] systemd[1]: Started Rule-based Manager for Device Events and Files.514server # [ 6.233022] systemd[1]: Found device /dev/hvc0.515server # [ 6.261256] systemd[1]: Found device /dev/ttyAMA0.516server # [ 6.393944] (udev-worker)[469]: Network interface NamePolicy= disabled on kernel command line.517server # [ 6.395544] (udev-worker)[482]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.518server # [ 6.398279] (udev-worker)[482]: Network interface NamePolicy= disabled on kernel command line.519server # [ 6.479043] mousedev: PS/2 mouse device common for all mice520server # [ 6.463281] systemd[1]: Mounting /run/wrappers...521server # [ 6.553521] systemd[1]: Found device Virtio network device.522server # [ 6.554707] systemd[1]: Mounted /run/wrappers.523server # [ 6.555789] systemd[1]: Reached target Local File Systems.524server # [ 6.560573] systemd[1]: Listening on Boot Entries Service Socket.525server # [ 6.565478] systemd[1]: Starting Create SUID/SGID Wrappers...526server # [ 6.568367] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.527server # [ 6.572207] systemd[1]: Starting Save Transient machine-id to Disk...528server # [ 6.576265] systemd[1]: Starting Create System Files and Directories...529server # [ 6.657981] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.530server # [ 6.665178] systemd[1]: Finished Save Transient machine-id to Disk.531server # [ 6.740399] systemd[1]: Finished Create System Files and Directories.532server # [ 6.747227] systemd[1]: Starting Rebuild Journal Catalog...533server # [ 6.751065] systemd[1]: Starting Record System Boot/Shutdown in UTMP...534server # [ 6.802354] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3535server # [ 6.921245] systemd[1]: Finished Record System Boot/Shutdown in UTMP.536server # [ 6.981281] systemd[1]: Starting Virtual Console Setup...537server # [ 7.045856] systemd[1]: Finished Rebuild Journal Catalog.538server # [ 7.053733] systemd[1]: Starting Update is Completed...539server # [ 7.184298] systemd[1]: Finished Update is Completed.540server # [ 8.300111] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.541server # [ 8.301118] systemd[1]: Finished Create SUID/SGID Wrappers.542server # [ 8.598231] systemd[1]: Finished Firewall.543server # [ 8.966818] systemd[1]: Finished Virtual Console Setup.544server # [ 8.967268] systemd[1]: Reached target System Initialization.545server # [ 8.969092] systemd[1]: Started Discard unused filesystem blocks once a week.546server # [ 8.971606] systemd[1]: Started Daily Cleanup of Temporary Directories.547server # [ 8.972419] systemd[1]: Reached target Timer Units.548server # [ 8.972615] systemd[1]: Listening on D-Bus System Message Bus Socket.549server # [ 8.972878] systemd[1]: Listening on Nix Daemon Socket.550server # [ 8.976470] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).551server # [ 8.982520] systemd[1]: Starting OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK)...552server # [ 8.984100] systemd[1]: Listening on Hostname Service Socket.553server # [ 9.062771] (sh-issue)[653]: sshd-vsock.socket: Unable to locate executable '/usr/lib/systemd/systemd-ssh-issue': No such file or directory554server # [ 9.073311] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).555server # [ 9.074109] systemd[1]: Reached target Socket Units.556server # [ 9.074365] systemd[1]: Reached target Basic System.557server # [ 9.082798] systemd[1]: Started backdoor.service.558server # [ 9.087485] systemd[1]: Starting D-Bus System Message Bus...559server # [ 9.095310] systemd[1]: Starting Import lastlog data into lastlog2 database...560server # [ 9.102831] systemd[1]: Starting Name Service Cache Daemon (nsncd)...561server # [ 9.111881] systemd[1]: Started Reset console on configuration changes.562server # [ 9.122397] systemd[1]: Starting resolvconf update...563server # [ 9.128813] systemd[1]: Starting SSH Host Keys Generation...564server # connecting to host...565server # [ 9.331240] systemd[1]: Finished Import lastlog data into lastlog2 database.566server # [ 9.337074] systemd[1]: Started Name Service Cache Daemon (nsncd).567server # [ 9.338036] systemd[1]: Reached target Host and Network Name Lookups.568server # [ 9.340929] systemd[1]: Reached target User and Group Name Lookups.569server # [ 9.343733] nsncd[658]: Oct 05 10:35:25.659 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"570server # [ 9.346441] systemd[1]: Starting User Login Management...571server # [ 9.412243] dbus-daemon[656]: dbus[656]: Unknown username "systemd-timesync" in message bus configuration file572server: Guest shell says: b'Spawning backdoor root shell...\n'573server: connected to guest root shell574server: (connecting took 9.59 seconds)575server: (finished: waiting for the VM to finish booting, in 9.59 seconds)576server # [ 9.553723] systemd-logind[685]: New seat seat0.577server # [ 9.557380] systemd-logind[685]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)578server # [ 9.559355] systemd-logind[685]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)579server # [ 9.561827] systemd-logind[685]: Watching system buttons on /dev/input/event0 (gpio-keys)580server # [ 9.568548] systemd[1]: Started User Login Management.581server # [ 9.585904] systemd[1]: Starting linger-users.service...582server # [ 9.596539] systemd[1]: Stopped target Host and Network Name Lookups.583server # [ 9.599164] systemd[1]: Stopping Host and Network Name Lookups...584server # [ 9.600119] systemd[1]: Stopped target User and Group Name Lookups.585server # [ 9.600296] systemd[1]: Stopping User and Group Name Lookups...586server # [ 9.602525] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...587server # [ 9.616824] systemd[1]: nscd.service: Deactivated successfully.588server # [ 9.617023] systemd[1]: Stopped Name Service Cache Daemon (nsncd).589server # [ 9.629530] systemd[1]: Starting Name Service Cache Daemon (nsncd)...590server # [ 9.800651] nsncd[731]: Oct 05 10:35:26.125 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"591server # [ 9.972650] systemd[1]: Started D-Bus System Message Bus.592server # [ 9.977702] dbus-daemon[656]: [system] Successfully activated service 'org.freedesktop.systemd1'593server # [ 9.981485] systemd[1]: Started Name Service Cache Daemon (nsncd).594server # [ 9.982398] systemd[1]: linger-users.service: Deactivated successfully.595server # [ 9.982822] systemd[1]: Finished linger-users.service.596server # [ 9.983081] systemd[1]: Reached target Host and Network Name Lookups.597server # [ 9.983613] systemd[1]: Reached target User and Group Name Lookups.598server # [ 10.005007] sshd-keygen-start[690]: Generating public/private rsa key pair.599server # [ 10.005354] sshd-keygen-start[690]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key600server # [ 10.006691] sshd-keygen-start[690]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub601server # [ 10.008583] sshd-keygen-start[690]: The key fingerprint is:602server # [ 10.009359] sshd-keygen-start[690]: SHA256:QVSYlkx9O8h9mRRE+1uHvM+zBdzngWP4F5igQ9VNyYo root@server603server # [ 10.010671] sshd-keygen-start[690]: The key's randomart image is:604server # [ 10.012809] sshd-keygen-start[690]: +---[RSA 4096]----+605server # [ 10.012953] sshd-keygen-start[690]: | ++*...oBo.|606server # [ 10.013178] sshd-keygen-start[690]: | .* o ...= |607server # [ 10.013504] sshd-keygen-start[690]: | ..o =.ooo |608server # [ 10.014104] sshd-keygen-start[690]: | ..+E*+B+ |609server # [ 10.014763] sshd-keygen-start[690]: | So . O=o*|610server # [ 10.015949] sshd-keygen-start[690]: | . o .+B|611server # [ 10.016794] sshd-keygen-start[690]: | ..oo|612server # [ 10.016997] sshd-keygen-start[690]: | .+.|613server # [ 10.017959] sshd-keygen-start[690]: | .=|614server # [ 10.019715] sshd-keygen-start[690]: +----[SHA256]-----+615server # [ 10.046919] systemd[1]: Finished resolvconf update.616server # [ 10.047612] systemd[1]: Reached target Preparation for Network.617server # [ 10.053654] systemd[1]: Starting DHCP Client...618server # [ 10.059180] systemd[1]: Starting Address configuration of eth1...619server # [ 10.137031] sshd-keygen-start[786]: Generating public/private ed25519 key pair.620server # [ 10.137575] sshd-keygen-start[786]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key621server # [ 10.138533] sshd-keygen-start[786]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub622server # [ 10.140932] sshd-keygen-start[786]: The key fingerprint is:623server # [ 10.141464] sshd-keygen-start[786]: SHA256:Guaj1bfJDZ4a6yRWsqBH80LfpWM+W2AYy7L+daip4v0 root@server624server # [ 10.142274] sshd-keygen-start[786]: The key's randomart image is:625server # [ 10.143951] sshd-keygen-start[786]: +--[ED25519 256]--+626server # [ 10.146493] sshd-keygen-start[786]: | |627server # [ 10.147535] sshd-keygen-start[786]: | |628server # [ 10.148180] sshd-keygen-start[786]: | . |629server # [ 10.148702] sshd-keygen-start[786]: | . + |630server # [ 10.149116] sshd-keygen-start[786]: | * B S . |631server # [ 10.149636] sshd-keygen-start[786]: | + X X = |632server # [ 10.150680] sshd-keygen-start[786]: | . + @ @ = |633server # [ 10.151690] sshd-keygen-start[786]: | .+ = X.X * |634server # [ 10.152736] sshd-keygen-start[786]: | ...=+E.*+* . |635server # [ 10.153285] sshd-keygen-start[786]: +----[SHA256]-----+636server # [ 10.153657] systemd[1]: sshd-keygen.service: Deactivated successfully.637server # [ 10.154941] systemd[1]: Finished SSH Host Keys Generation.638server # [ 10.239640] network-addresses-eth1-start[782]: adding address 192.168.1.1/24... done639server # [ 10.259980] dhcpcd[792]: dhcpcd-10.2.4 starting640server # [ 10.266677] network-addresses-eth1-start[782]: adding address 2001:db8:1::1/64... done641server # [ 10.276930] dhcpcd[802]: dev: loaded udev642server # [ 10.296902] systemd[1]: Finished Address configuration of eth1.643server # [ 10.302753] systemd[1]: Starting Networking Setup...644server # [ 10.380569] 8021q: 802.1Q VLAN Support v1.8645server # [ 10.381717] 8021q: adding VLAN 0 to HW filter on device eth1646server # [ 10.410769] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.647server # [ 10.470699] cfg80211: Loading compiled-in X.509 certificates for regulatory database648server # [ 10.550426] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'649server # [ 10.551635] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'650server # [ 10.558022] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2651server # [ 10.559084] cfg80211: failed to load regulatory.db652server # [ 10.571932] systemd[1]: Finished Networking Setup.653server # [ 10.572836] systemd[1]: Reached target Network.654server # [ 10.578168] systemd[1]: Starting PostgreSQL Server...655server # [ 10.583778] systemd[1]: Starting SSH Daemon...656server # [ 10.589389] systemd[1]: Starting Permit User Sessions...657server # [ 10.720012] 8021q: adding VLAN 0 to HW filter on device eth0658server # [ 10.694819] dhcpcd[802]: eth0: waiting for carrier659server # [ 10.723453] systemd[1]: Finished Permit User Sessions.660server # [ 10.729089] sshd[855]: Server listening on 0.0.0.0 port 22.661server # [ 10.729529] systemd[1]: Started Getty on tty1.662server # [ 10.729958] systemd[1]: Reached target Login Prompts.663server # [ 10.730339] sshd[855]: Server listening on :: port 22.664server # [ 10.731090] systemd[1]: Started SSH Daemon.665server # [ 11.102258] postgresql-pre-start[867]: The files belonging to this database system will be owned by user "postgres".666server # [ 11.103117] postgresql-pre-start[867]: This user must also own the server process.667server # [ 11.134103] postgresql-pre-start[867]: The database cluster will be initialized with locale "en_US.UTF-8".668server # [ 11.134664] postgresql-pre-start[867]: The default database encoding has accordingly been set to "UTF8".669server # [ 11.136309] postgresql-pre-start[867]: The default text search configuration will be set to "english".670server # [ 11.137267] postgresql-pre-start[867]: Data page checksums are disabled.671server # [ 11.137715] postgresql-pre-start[867]: fixing permissions on existing directory /var/lib/postgresql/15 ... ok672server # [ 11.138356] postgresql-pre-start[867]: creating subdirectories ... ok673server # [ 11.139512] postgresql-pre-start[867]: selecting dynamic shared memory implementation ... posix674server # [ 11.229091] dhcpcd[802]: eth0: carrier acquired675server # [ 11.263190] dhcpcd[802]: DUID 00:01:00:01:32:56:39:ef:52:54:00:12:34:56676server # [ 11.264148] dhcpcd[802]: eth0: IAID 00:12:34:56677server # [ 11.264607] dhcpcd[802]: eth0: adding address fe80::5054:ff:fe12:3456678server # [ 11.348412] postgresql-pre-start[867]: selecting default max_connections ... 100679server # [ 11.498965] postgresql-pre-start[867]: selecting default shared_buffers ... 128MB680server # [ 12.363772] dhcpcd[802]: eth0: soliciting a DHCP lease681server # [ 12.376725] dhcpcd[802]: eth0: offered 10.0.2.15 from 10.0.2.2682server # [ 12.400534] dhcpcd[802]: eth0: probing address 10.0.2.15/24683server # [ 12.823692] dhcpcd[802]: eth0: soliciting an IPv6 router684server # [ 12.825293] dhcpcd[802]: eth0: Router Advertisement from fe80::2685server # [ 12.826612] dhcpcd[802]: eth0: adding address fec0::5054:ff:fe12:3456/64686server # [ 12.827264] dhcpcd[802]: eth0: adding route to fec0::/64687server # [ 12.828386] dhcpcd[802]: eth0: adding default route via fe80::2688server # [ 14.002813] postgresql-pre-start[867]: selecting default time zone ... UTC689server # [ 14.012833] postgresql-pre-start[867]: creating configuration files ... ok690server # [ 14.226631] postgresql-pre-start[867]: running bootstrap script ... ok691server # [ 14.780568] postgresql-pre-start[867]: performing post-bootstrap initialization ... ok692server # [ 15.004474] postgresql-pre-start[867]: syncing data to disk ... ok693server # [ 15.005011] postgresql-pre-start[867]: initdb: warning: enabling "trust" authentication for local connections694server # [ 15.005832] postgresql-pre-start[867]: 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.695server # [ 15.009047] postgresql-pre-start[867]: Success. You can now start the database server using:696server # [ 15.009974] postgresql-pre-start[867]: /nix/store/hh248w54in40mh1ndaq8ybl2scw42vvz-postgresql-15-pg_hashids/bin/pg_ctl -D /var/lib/postgresql/15 -l logfile start697server # [ 15.221890] postgres[914]: [914] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit698server # [ 15.224686] postgres[914]: [914] LOG: listening on IPv4 address "0.0.0.0", port 5432699server # [ 15.225712] postgres[914]: [914] LOG: listening on IPv6 address "::", port 5432700server # [ 15.228074] postgres[914]: [914] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"701server # [ 15.238943] postgres[928]: [928] LOG: database system was shut down at 2026-10-05 10:35:31 GMT702server # [ 15.242736] postgres[914]: [914] LOG: database system is ready to accept connections703server # [ 15.244646] systemd[1]: Started PostgreSQL Server.704server # [ 15.247916] systemd[1]: Starting PostgreSQL Setup Scripts...705server # [ 15.434182] postgresql-setup-start[940]: CREATE ROLE706server # [ 15.465461] postgresql-setup-start[942]: ALTER ROLE707server # [ 15.470624] systemd[1]: Finished PostgreSQL Setup Scripts.708server # [ 15.471018] systemd[1]: Reached target PostgreSQL.709server # [ 17.637220] dhcpcd[802]: eth0: leased 10.0.2.15 for 86400 seconds710server # [ 17.638064] dhcpcd[802]: eth0: adding route to 10.0.2.0/24711server # [ 17.638713] dhcpcd[802]: eth0: adding default route via 10.0.2.2712server # [ 17.858670] systemd[1]: Started DHCP Client.713server # [ 17.859364] systemd[1]: Reached target Multi-User System.714server # [ 17.860531] systemd[1]: Startup finished in 3.081s (kernel) + 14.776s (userspace) = 17.858s.715server: (finished: waiting for unit multi-user.target, in 19.36 seconds)716server: waiting for unit postgresql.service717server: (finished: waiting for unit postgresql.service, in 0.17 seconds)718server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" 719server # NOTICE: schema "public" already exists, skipping720server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.07 seconds)721subtest: Check upgrade path with postgresql 15722server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" 723server # NOTICE: extension "pg_hashids" does not exist, skipping724server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" , in 0.07 seconds)725server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.2.1' CASCADE;" 726server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.2.1' CASCADE;" , in 0.12 seconds)727server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 728server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.06 seconds)729server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pg_hashids UPDATE TO '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6';" 730server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pg_hashids UPDATE TO '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6';" , in 0.11 seconds)731server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 732server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.06 seconds)733(finished: subtest: Check upgrade path with postgresql 15, in 0.43 seconds)734subtest: Check pg_regress with postgresql 15 after extension upgrade735server: must succeed: sudo -u postgres /nix/store/hh248w54in40mh1ndaq8ybl2scw42vvz-postgresql-15-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 "pg_hashids" 736server # [ 19.989073] sudo[1047]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/hh248w54in40mh1ndaq8ybl2scw42vvz-postgresql-15-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 pg_hashids737server # [ 20.014121] sudo[1047]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)738server # pg_regress: could not set core size: disallowed by hard limit739server # [ 20.151220] sudo[1047]: pam_unix(sudo:session): session closed for user postgres740server: (finished: must succeed: sudo -u postgres /nix/store/hh248w54in40mh1ndaq8ybl2scw42vvz-postgresql-15-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 "pg_hashids" , in 0.30 seconds)741(using postmaster on Unix socket, default port)742============== running regression test queries ==============743test pg_hashids ... ok 76 ms744745=====================746 All 1 tests passed. 747=====================748749750(finished: subtest: Check pg_regress with postgresql 15 after extension upgrade, in 0.30 seconds)751subtest: Check the install of the last version of the extension752server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" 753server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" , in 0.10 seconds)754server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6' CASCADE;" 755server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6' CASCADE;" , in 0.10 seconds)756server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 757server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.07 seconds)758(finished: subtest: Check the install of the last version of the extension, in 0.27 seconds)759subtest: switch to postgresql 17760server # Checking switch inhibitors... done761server # stopping the following units: postgresql-setup.service, postgresql.service762server # [ 22.383746] nixos[1073]: switching to system configuration /nix/store/vzrswmw0rvyydd55y5baf233vwsm0r4v-nixos-system-server-test763server # [ 22.385550] systemd[1]: Stopped target Local File Systems.764server # [ 22.386111] systemd[1]: Stopped target PostgreSQL.765server # [ 22.386654] systemd[1]: postgresql-setup.service: Deactivated successfully.766server # [ 22.388085] postgres[914]: [914] LOG: received fast shutdown request767server # [ 22.389052] systemd[1]: Stopped PostgreSQL Setup Scripts.768server # [ 22.389428] systemd[1]: Stopping PostgreSQL Server...769server # [ 22.389677] systemd[1]: Stopped target Remote File Systems.770server # [ 22.390303] postgres[914]: [914] LOG: aborting any active transactions771server # [ 22.392521] postgres[914]: [914] LOG: background worker "logical replication launcher" (PID 931) exited with exit code 1772server # [ 22.393019] postgres[926]: [926] LOG: shutting down773server # [ 22.394423] postgres[926]: [926] LOG: checkpoint starting: shutdown immediate774server # [ 22.413904] postgres[926]: [926] LOG: checkpoint complete: wrote 69 buffers (0.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.006 s, sync=0.008 s, total=0.022 s; sync files=26, longest=0.004 s, average=0.001 s; distance=377 kB, estimate=377 kB775server # [ 22.415669] postgres[914]: [914] LOG: database system is shut down776server # [ 22.422104] systemd[1]: postgresql.service: Deactivated successfully.777server # activating the configuration...778server # [ 22.423379] systemd[1]: Stopped PostgreSQL Server.779server # [ 22.423709] systemd[1]: postgresql.service: Consumed 986ms CPU time, 91M memory peak, 39.1M written to disk.780server # setting up /etc...781server # [ 23.473831] systemd[1]: Reload requested from client PID 1073 ('.switch-to-conf') (unit backdoor.service)...782server # [ 23.475146] systemd[1]: Reloading...783server # [ 24.779698] systemd[1]: Reloading finished in 1301 ms.784server # restarting sysinit-reactivation.target785server # [ 24.858799] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....786server # [ 25.014530] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..787server # [ 25.015944] systemd[1]: Reached target Reactivate sysinit units.788server # reloading the following units: dbus.service789server # [ 25.021152] systemd[1]: Reloading D-Bus System Message Bus...790server # [ 25.110950] dbus-daemon[656]: Unknown username "systemd-timesync" in message bus configuration file791server # [ 25.170397] dbus-daemon[656]: [system] Reloaded configuration792server # [ 25.171413] dbus-send[1154]: method return time=1791196541.495961 sender=org.freedesktop.DBus -> destination=:1.5 serial=3 reply_serial=2793server # [ 25.178329] dbus-daemon[656]: Unknown username "systemd-timesync" in message bus configuration file794server # [ 25.234538] dbus-daemon[656]: [system] Reloaded configuration795server # [ 25.235249] systemd[1]: Reloaded D-Bus System Message Bus.796server # starting the following units: postgresql-setup.service, postgresql.service797server # [ 25.240744] systemd[1]: Reached target Remote File Systems.798server # [ 25.244847] systemd[1]: Starting linger-users.service...799server # [ 25.250959] systemd[1]: Starting postgresql-migrate.service...800server # [ 25.252450] systemd[1]: SSH Host Keys Generation was skipped because no trigger condition checks were met.801server # [ 25.254551] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).802server # [ 25.256530] systemd[1]: Reached target Local File Systems.803server # [ 25.259744] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).804server # [ 25.277329] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).805server # [ 25.286294] systemd[1]: Starting Create SUID/SGID Wrappers...806server # [ 25.287324] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.807server # [ 25.287993] 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).808server # [ 25.289660] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).809server # [ 25.291042] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).810server # [ 25.291939] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).811server # [ 25.327348] systemd[1]: linger-users.service: Deactivated successfully.812server # [ 25.328035] systemd[1]: Finished linger-users.service.813server # [ 25.610027] postgresql-migrate-start[1164]: The files belonging to this database system will be owned by user "postgres".814server # [ 25.611586] postgresql-migrate-start[1164]: This user must also own the server process.815server # [ 25.641661] postgresql-migrate-start[1164]: The database cluster will be initialized with locale "en_US.UTF-8".816server # [ 25.642578] postgresql-migrate-start[1164]: The default database encoding has accordingly been set to "UTF8".817server # [ 25.644363] postgresql-migrate-start[1164]: The default text search configuration will be set to "english".818server # [ 25.645360] postgresql-migrate-start[1164]: Data page checksums are disabled.819server # [ 25.645639] postgresql-migrate-start[1164]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok820server # [ 25.646316] postgresql-migrate-start[1164]: creating subdirectories ... ok821server # [ 25.647052] postgresql-migrate-start[1164]: selecting dynamic shared memory implementation ... posix822server # [ 25.809008] postgresql-migrate-start[1164]: selecting default "max_connections" ... 100823server # [ 25.912139] postgresql-migrate-start[1164]: selecting default "shared_buffers" ... 128MB824server # [ 26.325618] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.825server # [ 26.326254] systemd[1]: Finished Create SUID/SGID Wrappers.826server # [ 28.578692] postgresql-migrate-start[1164]: selecting default time zone ... UTC827server # [ 28.587454] postgresql-migrate-start[1164]: creating configuration files ... ok828server # [ 28.836904] postgresql-migrate-start[1164]: running bootstrap script ... ok829server # [ 29.371393] postgresql-migrate-start[1164]: performing post-bootstrap initialization ... ok830server # [ 29.678239] postgresql-migrate-start[1164]: syncing data to disk ... ok831server # [ 29.678939] postgresql-migrate-start[1164]: initdb: warning: enabling "trust" authentication for local connections832server # [ 29.679800] postgresql-migrate-start[1164]: 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.833server # [ 29.681079] postgresql-migrate-start[1164]: Success. You can now start the database server using:834server # [ 29.681677] postgresql-migrate-start[1164]: /nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start835server # [ 30.772958] postgresql-migrate-start[1242]: Performing Consistency Checks836server # [ 30.773018] postgresql-migrate-start[1242]: -----------------------------837server # [ 30.773028] postgresql-migrate-start[1242]: Checking cluster versions ok838server # [ 31.365333] postgresql-migrate-start[1242]: Checking database user is the install user ok839server # [ 31.368794] postgresql-migrate-start[1242]: Checking database connection settings ok840server # [ 31.373516] postgresql-migrate-start[1242]: Checking for prepared transactions ok841server # [ 31.374301] postgresql-migrate-start[1242]: Checking for contrib/isn with bigint-passing mismatch ok842server # [ 31.409395] postgresql-migrate-start[1242]: Checking data type usage ok843server # [ 31.420221] postgresql-migrate-start[1242]: Checking for not-null constraint inconsistencies ok844server # [ 31.551552] postgresql-migrate-start[1242]: Creating dump of global objects ok845server # [ 31.819521] postgresql-migrate-start[1242]: Creating dump of database schemas ok846server # [ 32.450313] postgresql-migrate-start[1242]: Checking for presence of required libraries ok847server # [ 32.453610] postgresql-migrate-start[1242]: Checking database user is the install user ok848server # [ 32.456879] postgresql-migrate-start[1242]: Checking for prepared transactions ok849server # [ 32.457680] postgresql-migrate-start[1242]: Checking for new cluster tablespace directories ok850server # [ 32.458033] postgresql-migrate-start[1242]: If pg_upgrade fails after this point, you must re-initdb the851server # [ 32.458348] postgresql-migrate-start[1242]: new cluster before continuing.852server # [ 32.458572] postgresql-migrate-start[1242]: Performing Upgrade853server # [ 32.458765] postgresql-migrate-start[1242]: ------------------854server # [ 32.461950] postgresql-migrate-start[1242]: Setting locale and encoding for new cluster ok855server # [ 32.711514] postgresql-migrate-start[1242]: Analyzing all rows in the new cluster ok856server # [ 32.866824] postgresql-migrate-start[1242]: Freezing all rows in the new cluster ok857server # [ 33.140623] postgresql-migrate-start[1242]: Deleting files from new pg_xact ok858server # [ 33.213599] postgresql-migrate-start[1242]: Copying old pg_xact to new server ok859server # [ 33.283983] postgresql-migrate-start[1242]: Setting oldest XID for new cluster ok860server # [ 33.475946] postgresql-migrate-start[1242]: Setting next transaction ID and epoch for new cluster ok861server # [ 33.477152] postgresql-migrate-start[1242]: Deleting files from new pg_multixact/offsets ok862server # [ 33.525601] postgresql-migrate-start[1242]: Copying old pg_multixact/offsets to new server ok863server # [ 33.526267] postgresql-migrate-start[1242]: Deleting files from new pg_multixact/members ok864server # [ 33.575015] postgresql-migrate-start[1242]: Copying old pg_multixact/members to new server ok865server # [ 33.675342] postgresql-migrate-start[1242]: Setting next multixact ID and offset for new cluster ok866server # [ 33.758248] postgresql-migrate-start[1242]: Resetting WAL archives ok867server # [ 34.242205] postgresql-migrate-start[1242]: Setting frozenxid and minmxid counters in new cluster ok868server # [ 34.328096] postgresql-migrate-start[1242]: Restoring global objects in the new cluster ok869server # [ 34.621455] postgresql-migrate-start[1242]: Restoring database schemas in the new cluster ok870server # [ 34.802639] postgresql-migrate-start[1242]: Copying user relation files ok871server # [ 34.872992] postgresql-migrate-start[1242]: Setting next OID for new cluster ok872server # [ 35.244425] postgresql-migrate-start[1242]: Sync data directory to disk ok873server # [ 35.245181] postgresql-migrate-start[1242]: Creating script to delete old cluster ok874server # [ 36.120809] postgresql-migrate-start[1242]: Checking for extension updates ok875server # [ 36.311231] postgresql-migrate-start[1242]: Upgrade Complete876server # [ 36.311700] postgresql-migrate-start[1242]: ----------------877server # [ 36.312023] postgresql-migrate-start[1242]: Optimizer statistics are not transferred by pg_upgrade.878server # [ 36.312694] postgresql-migrate-start[1242]: Once you start the new server, consider running:879server # [ 36.313245] postgresql-migrate-start[1242]: /nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/bin/vacuumdb --all --analyze-in-stages880server # [ 36.314360] postgresql-migrate-start[1242]: Running this script will delete the old cluster's data files:881server # [ 36.315113] postgresql-migrate-start[1242]: ./delete_old_cluster.sh882server # [ 36.327559] systemd[1]: Finished postgresql-migrate.service.883server # [ 36.333005] systemd[1]: Starting PostgreSQL Server...884server # [ 36.713442] postgres[1404]: [1404] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit885server # [ 36.716936] postgres[1404]: [1404] LOG: listening on IPv4 address "0.0.0.0", port 5432886server # [ 36.717523] postgres[1404]: [1404] LOG: listening on IPv6 address "::", port 5432887server # [ 36.719540] postgres[1404]: [1404] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"888server # [ 36.729101] postgres[1410]: [1410] LOG: database system was shut down at 2026-10-05 10:35:52 GMT889server # [ 36.733668] postgres[1404]: [1404] LOG: database system is ready to accept connections890server # [ 36.736714] systemd[1]: Started PostgreSQL Server.891server # [ 36.741398] systemd[1]: Starting PostgreSQL Setup Scripts...892server # [ 37.043814] postgresql-setup-start[1420]: ALTER ROLE893server # [ 37.052963] systemd[1]: Finished PostgreSQL Setup Scripts.894server # [ 37.053426] systemd[1]: Reached target PostgreSQL.895server # the following new units were started: postgresql-migrate.service, sysinit-reactivation.target, systemd-tmpfiles-resetup.service896server # [ 37.320267] nixos[1073]: finished switching to system configuration /nix/store/vzrswmw0rvyydd55y5baf233vwsm0r4v-nixos-system-server-test897server: waiting for unit postgresql.service898server: (finished: waiting for unit postgresql.service, in 0.26 seconds)899server: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no'900server: (finished: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no', in 0.03 seconds)901(finished: subtest: switch to postgresql 17, in 17.19 seconds)902subtest: Check last version of the extension after postgresql upgrade903server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 904server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.08 seconds)905(finished: subtest: Check last version of the extension after postgresql upgrade, in 0.08 seconds)906subtest: Check upgrade path with postgresql 17907server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" 908server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" , in 0.09 seconds)909server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.2.1' CASCADE;" 910server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.2.1' CASCADE;" , in 0.16 seconds)911server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 912server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.11 seconds)913server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pg_hashids UPDATE TO '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6';" 914server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pg_hashids UPDATE TO '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6';" , in 0.19 seconds)915server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 916server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.12 seconds)917(finished: subtest: Check upgrade path with postgresql 17, in 0.66 seconds)918subtest: Check pg_regress with postgresql 17 after extension upgrade919server: must succeed: sudo -u postgres /nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pg_hashids" 920server # [ 38.461175] sudo[1455]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 pg_hashids921server # [ 38.490441] sudo[1455]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)922server # # could not set core size: disallowed by hard limit923server # [ 38.631067] sudo[1455]: pam_unix(sudo:session): session closed for user postgres924server: (finished: must succeed: sudo -u postgres /nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pg_hashids" , in 0.28 seconds)925# using postmaster on Unix socket, default port926ok 1 - pg_hashids 68 ms9271..1928# All 1 tests passed.929930(finished: subtest: Check pg_regress with postgresql 17 after extension upgrade, in 0.28 seconds)931subtest: Check the install of the last version of the extension932server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" 933server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" , in 0.10 seconds)934server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6' CASCADE;" 935server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6' CASCADE;" , in 0.12 seconds)936server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 937server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.07 seconds)938(finished: subtest: Check the install of the last version of the extension, in 0.29 seconds)939subtest: Check pg_regress with postgresql 17 after installing the last version940server: must succeed: sudo -u postgres /nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pg_hashids" 941server # [ 39.029795] sudo[1477]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 pg_hashids942server # [ 39.062367] sudo[1477]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)943server # # could not set core size: disallowed by hard limit944server # [ 39.305918] sudo[1477]: pam_unix(sudo:session): session closed for user postgres945server: (finished: must succeed: sudo -u postgres /nix/store/c4w47wzqmdkmj68k7fm3jrqsdjm9475h-postgresql-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pg_hashids" , in 0.39 seconds)946# using postmaster on Unix socket, default port947ok 1 - pg_hashids 134 ms9481..1949# All 1 tests passed.950951(finished: subtest: Check pg_regress with postgresql 17 after installing the last version, in 0.39 seconds)952subtest: switch to orioledb 17953server # Checking switch inhibitors... done954server # stopping the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service955server # [ 42.007283] nixos[1491]: switching to system configuration /nix/store/ccs5s94fy3ciarw0n8mpxli0kr4ggh97-nixos-system-server-test956server # [ 42.008149] systemd[1]: Stopped target Local File Systems.957server # [ 42.009549] systemd[1]: Stopped target PostgreSQL.958server # [ 42.010122] postgres[1404]: [1404] LOG: received fast shutdown request959server # [ 42.010830] systemd[1]: postgresql-setup.service: Deactivated successfully.960server # [ 42.012381] systemd[1]: Stopped PostgreSQL Setup Scripts.961server # [ 42.013091] systemd[1]: Stopping PostgreSQL Server...962server # [ 42.013765] postgres[1404]: [1404] LOG: aborting any active transactions963server # [ 42.015924] postgres[1404]: [1404] LOG: background worker "logical replication launcher" (PID 1413) exited with exit code 1964server # [ 42.017614] systemd[1]: Stopped target Remote File Systems.965server # [ 42.018561] postgres[1408]: [1408] LOG: shutting down966server # [ 42.019725] postgres[1408]: [1408] LOG: checkpoint starting: shutdown immediate967server # [ 42.039707] postgres[1408]: [1408] LOG: checkpoint complete: wrote 68 buffers (0.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.005 s, sync=0.009 s, total=0.023 s; sync files=22, longest=0.004 s, average=0.001 s; distance=435 kB, estimate=435 kB; lsn=0/806CCF0, redo lsn=0/806CCF0968server # [ 42.041447] postgres[1404]: [1404] LOG: database system is shut down969server # [ 42.050282] systemd[1]: postgresql.service: Deactivated successfully.970server # [ 42.050692] systemd[1]: Stopped PostgreSQL Server.971server # [ 42.051806] systemd[1]: postgresql-migrate.service: Deactivated successfully.972server # [ 42.052778] systemd[1]: Stopped postgresql-migrate.service.973server # [ 42.053178] systemd[1]: postgresql-migrate.service: Consumed 1.827s CPU time, 97.8M memory peak, 166M written to disk.974server # activating the configuration...975server # setting up /etc...976server # [ 43.440094] systemd[1]: Reload requested from client PID 1491 ('.switch-to-conf') (unit backdoor.service)...977server # [ 43.440204] systemd[1]: Reloading...978server # [ 45.348864] systemd[1]: Reloading finished in 1903 ms.979server # restarting sysinit-reactivation.target980server # [ 45.470054] systemd[1]: Stopped target Reactivate sysinit units.981server # [ 45.470762] systemd[1]: Stopping Reactivate sysinit units...982server # [ 45.471273] systemd[1]: Reached target Reactivate sysinit units.983server # reloading the following units: dbus.service984server # [ 45.476416] systemd[1]: Reloading D-Bus System Message Bus...985server # [ 45.573120] dbus-daemon[656]: Unknown username "systemd-timesync" in message bus configuration file986server # [ 45.669769] dbus-daemon[656]: [system] Reloaded configuration987server # [ 45.672667] dbus-send[1568]: method return time=1791196561.995981 sender=org.freedesktop.DBus -> destination=:1.7 serial=3 reply_serial=2988server # [ 45.683478] dbus-daemon[656]: Unknown username "systemd-timesync" in message bus configuration file989server # [ 45.761715] dbus-daemon[656]: [system] Reloaded configuration990server # [ 45.762245] systemd[1]: Reloaded D-Bus System Message Bus.991server # starting the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service992server # [ 45.768952] systemd[1]: Starting postgresql-migrate.service...993server # [ 45.791654] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).994server # [ 45.804197] systemd[1]: Reached target Remote File Systems.995server # [ 45.805190] systemd[1]: Starting linger-users.service...996server # [ 45.805584] systemd[1]: SSH Host Keys Generation was skipped because no trigger condition checks were met.997server # [ 45.816302] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).998server # [ 45.834000] systemd[1]: Starting Create SUID/SGID Wrappers...999server # [ 45.836651] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).1000server # [ 45.838101] systemd[1]: Reached target Local File Systems.1001server # [ 45.838433] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.1002server # [ 45.841042] 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).1003server # [ 45.841653] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).1004server # [ 45.843867] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1005server # [ 45.844779] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1006server # [ 45.959310] systemd[1]: linger-users.service: Deactivated successfully.1007server # [ 45.961870] systemd[1]: Finished linger-users.service.1008server # [ 45.977992] systemd[1]: Finished postgresql-migrate.service.1009server # [ 47.417221] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1010server # [ 47.418027] systemd[1]: Finished Create SUID/SGID Wrappers.1011server # [ 47.423014] systemd[1]: Starting PostgreSQL Server...1012server # [ 47.912897] postgresql-pre-start[1652]: The files belonging to this database system will be owned by user "postgres".1013server # [ 47.913485] postgresql-pre-start[1652]: This user must also own the server process.1014server # [ 47.966269] postgresql-pre-start[1652]: Using language tag "en-US" for ICU locale "en_US.UTF-8".1015server # [ 47.967665] postgresql-pre-start[1652]: The database cluster will be initialized with this locale configuration:1016server # [ 47.968236] postgresql-pre-start[1652]: locale provider: icu1017server # [ 47.968681] postgresql-pre-start[1652]: default collation: en-US1018server # [ 47.969395] postgresql-pre-start[1652]: LC_COLLATE: en_US.UTF-81019server # [ 47.970137] postgresql-pre-start[1652]: LC_CTYPE: en_US.UTF-81020server # [ 47.971026] postgresql-pre-start[1652]: LC_MESSAGES: en_US.UTF-81021server # [ 47.971674] postgresql-pre-start[1652]: LC_MONETARY: en_US.UTF-81022server # [ 47.972373] postgresql-pre-start[1652]: LC_NUMERIC: en_US.UTF-81023server # [ 47.972620] postgresql-pre-start[1652]: LC_TIME: en_US.UTF-81024server # [ 47.972901] postgresql-pre-start[1652]: The default text search configuration will be set to "english".1025server # [ 47.973428] postgresql-pre-start[1652]: Data page checksums are disabled.1026server # [ 47.973618] postgresql-pre-start[1652]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1027server # [ 47.974348] postgresql-pre-start[1652]: creating subdirectories ... ok1028server # [ 47.975329] postgresql-pre-start[1652]: selecting dynamic shared memory implementation ... posix1029server # [ 48.230533] postgresql-pre-start[1652]: selecting default "max_connections" ... 1001030server # [ 48.382826] postgresql-pre-start[1652]: selecting default "shared_buffers" ... 128MB1031server # [ 51.944754] postgresql-pre-start[1652]: selecting default time zone ... UTC1032server # [ 51.958701] postgresql-pre-start[1652]: creating configuration files ... ok1033server # [ 52.349447] postgresql-pre-start[1652]: running bootstrap script ... ok1034server # [ 53.074278] postgresql-pre-start[1652]: performing post-bootstrap initialization ... ok1035server # [ 53.359073] postgresql-pre-start[1652]: syncing data to disk ... ok1036server # [ 53.359625] postgresql-pre-start[1652]: initdb: warning: enabling "trust" authentication for local connections1037server # [ 53.360770] postgresql-pre-start[1652]: 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.1038server # [ 53.362200] postgresql-pre-start[1652]: Success. You can now start the database server using:1039server # [ 53.363882] postgresql-pre-start[1652]: /nix/store/ib1in8ccwkxkncjhnipz40p1y1d1mdc5-postgresql-orioledb-17-pg_hashids/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start1040server # [ 53.743164] postgres[1665]: [1665] LOG: registered custom resource manager "OrioleDB resource manager" with ID 1291041server # [ 53.768413] postgres[1665]: [1665] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit1042server # [ 53.793254] postgres[1665]: [1665] LOG: listening on IPv6 address "::1", port 54321043server # [ 53.793571] postgres[1665]: [1665] LOG: listening on IPv4 address "127.0.0.1", port 54321044server # [ 53.798069] postgres[1665]: [1665] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1045server # [ 53.810874] postgres[1671]: [1671] LOG: Cleanup of old files at startup. Checkpoint 01046server # [ 53.811600] postgres[1672]: [1672] LOG: orioledb background writer 0 started1047server # [ 53.813469] postgres[1671]: [1671] LOG: database system was shut down at 2026-10-05 10:36:09 GMT1048server # [ 53.821196] postgres[1665]: [1665] LOG: database system is ready to accept connections1049server # [ 53.826072] systemd[1]: Started PostgreSQL Server.1050server # [ 53.833000] systemd[1]: Starting PostgreSQL Setup Scripts...1051server # [ 54.301554] postgresql-setup-start[1679]: CREATE EXTENSION1052server # [ 54.458565] postgresql-setup-start[1685]: CREATE ROLE1053server # [ 54.517122] postgresql-setup-start[1687]: ALTER ROLE1054server # [ 54.526599] systemd[1]: Finished PostgreSQL Setup Scripts.1055server # [ 54.527068] systemd[1]: Reached target PostgreSQL.1056server # [ 54.873430] nixos[1491]: finished switching to system configuration /nix/store/ccs5s94fy3ciarw0n8mpxli0kr4ggh97-nixos-system-server-test1057server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" 1058server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" , in 0.12 seconds)1059server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" 1060server # NOTICE: schema "public" already exists, skipping1061server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.11 seconds)1062(finished: subtest: switch to orioledb 17, in 15.80 seconds)1063subtest: Check upgrade path with orioledb 171064server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" 1065server # NOTICE: extension "pg_hashids" does not exist, skipping1066server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pg_hashids;" , in 0.08 seconds)1067server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.2.1' CASCADE;" 1068server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pg_hashids WITH VERSION '1.2.1' CASCADE;" , in 0.21 seconds)1069server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 1070server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.08 seconds)1071server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pg_hashids UPDATE TO '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6';" 1072server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pg_hashids UPDATE TO '1.3.0-cd0e1b31d52b394a0df64079406a14a4f7387cd6';" , in 0.14 seconds)1073server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" 1074server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pg_hashids';" , in 0.07 seconds)1075(finished: subtest: Check upgrade path with orioledb 17, in 0.58 seconds)1076subtest: Check pg_regress with orioledb 17 after installing the last version1077server: must succeed: sudo -u postgres /nix/store/ib1in8ccwkxkncjhnipz40p1y1d1mdc5-postgresql-orioledb-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 "pg_hashids" 1078server # [ 55.780627] sudo[1718]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/ib1in8ccwkxkncjhnipz40p1y1d1mdc5-postgresql-orioledb-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 pg_hashids1079server # [ 55.805296] sudo[1718]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)1080server # # could not set core size: disallowed by hard limit1081server # [ 55.989923] sudo[1718]: pam_unix(sudo:session): session closed for user postgres1082server: (finished: must succeed: sudo -u postgres /nix/store/ib1in8ccwkxkncjhnipz40p1y1d1mdc5-postgresql-orioledb-17-pg_hashids/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/r86pzb9rc8wphg4zfpkva18f5ja5h8kl-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 "pg_hashids" , in 0.30 seconds)1083# using postmaster on Unix socket, default port1084ok 1 - pg_hashids 107 ms10851..11086# All 1 tests passed.10871088(finished: subtest: Check pg_regress with orioledb 17 after installing the last version, in 0.30 seconds)1089(finished: run the VM test script, in 56.78 seconds)1090test script finished in 56.85s1091cleanup1092kill machine (pid 69976)1093qemu-system-aarch64: terminating on signal 15 from pid 69956 (<unknown process>)1094vde_switch: EOF data port: Interrupted system call1095vde_switch: Reading data: Connection reset by peer1096kill vlan (pid 69974)1097vde_switch: EOF on stdin, cleaning up and exiting1098vde_switch: Caught signal 15, cleaning up and exiting1099(finished: cleanup, in 0.00 seconds)