elastic-arm-3c88ad8c: building xzpx080m6x4ijl1gy6jpfanc09xxzl9x-vm-test-run-horae-e2e-oidc.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.01 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: server, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh server: starting vm server # Disk image does not exist, creating the virtualisation disk image... server: QEMU running (pid 12) server # Formatting '/build/vm-state-server/tmp.VlcIB5ZJhX', fmt=raw size=1073741824 server # mke2fs 1.47.4 (6-Mar-2025) server # Discarding device blocks: 0/262144 done server # Creating filesystem with 262144 4k blocks and 65536 inodes server # Filesystem UUID: b4730de7-7260-4382-b53f-90bb14e2932b server # Superblock backups stored on blocks: server # 32768, 98304, 163840, 229376 server # server # Allocating group tables: 0/8 done server # Writing inode tables: 0/8 done server # Creating journal (8192 blocks): done server # Writing superblocks and filesystem accounting information: 0/8 done server # server # Virtualisation disk image created. server # Creating Nix store image... server # Created Nix store image. server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory server # qemu-system-aarch64: failed to initialize kvm: No such file or directory server # qemu-system-aarch64: falling back to tcg server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] server # [ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jul 4 11:44:22 UTC 2026 server # [ 0.000000] KASLR enabled server # [ 0.000000] random: crng init done server # [ 0.000000] Machine model: linux,dummy-virt server # [ 0.000000] efi: UEFI not found. server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf] server # [ 0.000000] Zone ranges: server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] DMA32 empty server # [ 0.000000] Normal empty server # [ 0.000000] Device empty server # [ 0.000000] Movable zone start for each node server # [ 0.000000] Early memory node ranges server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 server # [ 0.000000] psci: probing for conduit method from DT. server # [ 0.000000] psci: PSCIv1.1 detected in firmware. server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs server # [ 0.000000] psci: Trusted OS migration not required server # [ 0.000000] psci: SMC Calling Convention v1.0 server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296 server # [ 0.000000] Detected PIPT I-cache on CPU0 server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) server # [ 0.000000] CPU features: detected: GICv3 CPU interface server # [ 0.000000] CPU features: detected: HCRX_EL2 register server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE) server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) server # [ 0.000000] CPU features: detected: Spectre-v4 server # [ 0.000000] alternatives: applying boot alternatives server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test/init regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration console=ttyAMA0,115200n8 console=tty0 server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration", will be passed to user space. server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB server # [ 0.000000] software IO TLB: area num 1. server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB) server # [ 0.000000] Fallback order for Node 0: 0 server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 server # [ 0.000000] Policy zone: DMA server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 server # [ 0.000000] allocated 2097152 bytes of page_ext server # [ 0.000000] ftrace: allocating 74713 entries in 292 pages server # [ 0.000000] ftrace: allocated 292 pages with 3 groups server # [ 0.000000] rcu: Hierarchical RCU implementation. server # [ 0.000000] rcu: RCU event tracing is enabled. server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. server # [ 0.000000] Trampoline variant of Tasks RCU enabled. server # [ 0.000000] Rude variant of Tasks RCU enabled. server # [ 0.000000] Tracing variant of Tasks RCU enabled. server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 server # [ 0.000000] GICv3: 256 SPIs implemented server # [ 0.000000] GICv3: 0 Extended SPIs implemented server # [ 0.000000] Root IRQ handler: gic_handle_irq server # [ 0.000000] GICv3: GICv3 features: 16 PPIs server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns server # [ 0.000082] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns server # [ 0.007712] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) server # [ 0.039566] Console: colour dummy device 80x25 server # [ 0.043963] printk: legacy console [tty0] enabled server # [ 0.060473] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) server # [ 0.061480] pid_max: default: 32768 minimum: 301 server # [ 0.070407] LSM: initializing lsm=capability,landlock,yama,bpf,ima server # [ 0.074448] landlock: Up and running. server # [ 0.074723] Yama: becoming mindful. server # [ 0.077569] LSM support for eBPF active server # [ 0.084397] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.084723] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.159501] cacheinfo: Unable to detect cache hierarchy for CPU 0 server # [ 0.192611] rcu: Hierarchical SRCU implementation. server # [ 0.192849] rcu: Max phase no-delay instances is 1000. server # [ 0.213038] fsl-mc MSI: its@8080000 domain created server # [ 0.217561] EFI services will not be available. server # [ 0.220965] smp: Bringing up secondary CPUs ... server # [ 0.222743] smp: Brought up 1 node, 1 CPU server # [ 0.222902] SMP: Total of 1 processors activated. server # [ 0.223009] CPU: All CPU(s) started at EL1 server # [ 0.223661] CPU features: detected: Branch Target Identification server # [ 0.223806] CPU features: detected: 32-bit EL0 Support server # [ 0.223882] CPU features: detected: 32-bit EL1 Support server # [ 0.223947] CPU features: detected: ARMv8.4 Translation Table Level server # [ 0.224030] CPU features: detected: Instruction cache invalidation not required for I/D coherence server # [ 0.224128] CPU features: detected: Data cache clean to the PoU not required for I/D coherence server # [ 0.224280] CPU features: detected: Common not Private translations server # [ 0.224352] CPU features: detected: CRC32 instructions server # [ 0.224411] CPU features: detected: Data cache clean to Point of Deep Persistence server # [ 0.224485] CPU features: detected: Data cache clean to Point of Persistence server # [ 0.224553] CPU features: detected: Data independent timing control (DIT) server # [ 0.224620] CPU features: detected: E0PD server # [ 0.224672] CPU features: detected: Enhanced Counter Virtualization server # [ 0.224759] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) server # [ 0.224828] CPU features: detected: Enhanced Privileged Access Never server # [ 0.224892] CPU features: detected: Enhanced Virtualization Traps server # [ 0.224969] CPU features: detected: Fine Grained Traps server # [ 0.225053] CPU features: detected: Guarded Control Stack (GCS) server # [ 0.225237] CPU features: detected: Generic authentication (IMP DEF algorithm) server # [ 0.226386] CPU features: detected: RCpc load-acquire (LDAPR) server # [ 0.226557] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) server # [ 0.226630] CPU features: detected: LSE atomic instructions server # [ 0.226692] CPU features: detected: Memory Copy and Memory Set instructions server # [ 0.226761] CPU features: detected: Privileged Access Never server # [ 0.226870] CPU features: detected: PMUv3 server # [ 0.226926] CPU features: detected: SCTLR2 server # [ 0.226980] CPU features: detected: RAS Extension Support server # [ 0.227115] CPU features: detected: RASv1p1 Extension Support server # [ 0.227179] CPU features: detected: Random Number Generator server # [ 0.227256] CPU features: detected: Speculation barrier (SB) server # [ 0.227320] CPU features: detected: Stage-2 Force Write-Back server # [ 0.227383] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality server # [ 0.227458] CPU features: detected: TLB range maintenance instructions server # [ 0.227523] CPU features: detected: WFx with timeout server # [ 0.227734] CPU features: detected: Scalable Matrix Extension server # [ 0.227807] CPU features: detected: FA64 server # [ 0.227860] CPU features: detected: SME2 server # [ 0.227914] CPU features: detected: Speculative Store Bypassing Safe (SSBS) server # [ 0.227981] CPU features: detected: Scalable Vector Extension server # [ 0.246108] alternatives: applying system-wide alternatives server # [ 0.275985] CPU features: detected: Hardware dirty bit management on CPU0 server # [ 0.277513] SVE: maximum available vector length 256 bytes per vector server # [ 0.277703] SVE: default vector length 64 bytes per vector server # [ 0.280334] SME: minimum available vector length 16 bytes per vector server # [ 0.280496] SME: maximum available vector length 256 bytes per vector server # [ 0.280581] SME: default vector length 32 bytes per vector server # [ 0.311674] Memory: 891112K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 116168K reserved, 32768K cma-reserved) server # [ 0.396260] devtmpfs: initialized server # [ 0.493141] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns server # [ 0.494590] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.495182] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). server # [ 0.503497] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL server # [ 0.503715] 0 pages in range for non-PLT usage server # [ 0.503821] 508320 pages in range for PLT usage server # [ 0.510610] pinctrl core: initialized pinctrl subsystem server # [ 0.537357] DMI not present or invalid. server # [ 0.575286] NET: Registered PF_NETLINK/PF_ROUTE protocol family server # [ 0.601181] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations server # [ 0.604712] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations server # [ 0.608536] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations server # [ 0.609207] audit: initializing netlink subsys (disabled) server # [ 0.613507] audit: type=2000 audit(0.452:1): state=initialized audit_enabled=0 res=1 server # [ 0.630075] thermal_sys: Registered thermal governor 'fair_share' server # [ 0.630230] thermal_sys: Registered thermal governor 'bang_bang' server # [ 0.630358] thermal_sys: Registered thermal governor 'step_wise' server # [ 0.630424] thermal_sys: Registered thermal governor 'user_space' server # [ 0.630488] thermal_sys: Registered thermal governor 'power_allocator' server # [ 0.631619] cpuidle: using governor ladder server # [ 0.632071] cpuidle: using governor menu server # [ 0.636887] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. server # [ 0.638173] ASID allocator initialised with 65536 entries server # [ 0.662647] Serial: AMBA PL011 UART driver server # [ 0.818609] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 server # [ 0.824079] printk: console [ttyAMA0] enabled server # [ 1.028618] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages server # [ 1.028961] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page server # [ 1.029098] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages server # [ 1.029222] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page server # [ 1.029329] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages server # [ 1.029427] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page server # [ 1.029519] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages server # [ 1.029606] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page server # [ 1.108056] fbcon: Taking over console server # [ 1.108839] ACPI: Interpreter disabled. server # [ 1.160414] iommu: Default domain type: Translated server # [ 1.160722] iommu: DMA domain TLB invalidation policy: strict mode server # [ 1.168973] SCSI subsystem initialized server # [ 1.193994] usbcore: registered new interface driver usbfs server # [ 1.196494] usbcore: registered new interface driver hub server # [ 1.197184] usbcore: registered new device driver usb server # [ 1.212992] pps_core: LinuxPPS API ver. 1 registered server # [ 1.213289] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti server # [ 1.213559] PTP clock support registered server # [ 1.214449] EDAC MC: Ver: 3.0.0 server # [ 1.232139] scmi_core: SCMI protocol bus registered server # [ 1.250115] FPGA manager framework server # [ 1.305868] vgaarb: loaded server # [ 1.324018] clocksource: Switched to clocksource arch_sys_counter server # [ 1.364206] VFS: Disk quotas dquot_6.6.0 server # [ 1.364873] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) server # [ 1.378470] netfs: FS-Cache loaded server # [ 1.382872] pnp: PnP ACPI: disabled server # [ 1.534972] NET: Registered PF_INET protocol family server # [ 1.545760] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) server # [ 2.059110] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 2.069440] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) server # [ 2.069911] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) server # [ 2.070580] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) server # [ 2.070999] TCP: Hash tables configured (established 8192 bind 8192) server # [ 2.086775] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) server # [ 2.088827] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 2.089537] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 2.105164] NET: Registered PF_UNIX/PF_LOCAL protocol family server # [ 2.106524] NET: Registered PF_XDP protocol family server # [ 2.107188] PCI: CLS 0 bytes, default 64 server # [ 2.132265] kvm [1]: HYP mode not available server # [ 2.158592] Trying to unpack rootfs image as initramfs... server # [ 3.028869] Initialise system trusted keyrings server # [ 3.038544] workingset: timestamp_bits=42 max_order=18 bucket_order=0 server # [ 3.113657] squashfs: version 4.0 (2009/01/31) Phillip Lougher server # [ 3.117960] 9p: Installing v9fs 9p2000 file system support server # [ 3.750154] Key type asymmetric registered server # [ 3.750843] Asymmetric key parser 'x509' registered server # [ 3.769539] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) server # [ 3.772562] io scheduler mq-deadline registered server # [ 3.772914] io scheduler kyber registered server # [ 4.070438] pl061_gpio 9030000.pl061: PL061 GPIO chip registered server # [ 4.093011] ledtrig-cpu: registered to indicate activity on CPUs server # [ 4.110884] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: server # [ 4.125006] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 server # [ 4.126600] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 server # [ 4.126933] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 server # [ 4.134660] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits server # [ 4.138569] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] server # [ 4.144869] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 server # [ 4.145703] pci_bus 0000:00: root bus resource [bus 00-ff] server # [ 4.145981] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] server # [ 4.146155] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] server # [ 4.146313] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] server # [ 4.161412] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint server # [ 4.188395] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 4.189114] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] server # [ 4.189413] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.189587] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.189839] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] server # [ 4.191413] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 4.200364] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] server # [ 4.200622] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.200771] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.202277] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 4.202584] pci 0000:00:03.0: BAR 0 [io 0x0000-0x001f] server # [ 4.202701] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.202816] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.212851] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 4.213260] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] server # [ 4.213402] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.213518] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.214878] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 4.215161] pci 0000:00:05.0: BAR 0 [io 0x0000-0x007f] server # [ 4.215339] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.215462] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.233631] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 4.234069] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] server # [ 4.234223] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.234356] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.238324] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 4.238719] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] server # [ 4.238847] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.238963] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.239078] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] server # [ 4.257958] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint server # [ 4.258417] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.258572] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.262712] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint server # [ 4.263103] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.263267] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.271043] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint server # [ 4.271447] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] server # [ 4.283263] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint server # [ 4.285918] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] server # [ 4.286187] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.286373] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.292054] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 4.292454] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] server # [ 4.292594] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 4.292737] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 4.313521] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned server # [ 4.314092] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned server # [ 4.314446] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned server # [ 4.314860] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned server # [ 4.315098] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned server # [ 4.315291] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned server # [ 4.315460] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned server # [ 4.328348] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned server # [ 4.328704] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned server # [ 4.328890] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned server # [ 4.329053] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned server # [ 4.329231] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned server # [ 4.329514] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned server # [ 4.329749] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned server # [ 4.329919] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned server # [ 4.330059] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned server # [ 4.330192] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned server # [ 4.330352] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned server # [ 4.330484] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned server # [ 4.330612] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned server # [ 4.330956] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned server # [ 4.331106] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned server # [ 4.331297] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned server # [ 4.331444] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned server # [ 4.356281] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned server # [ 4.356672] pci 0000:00:05.0: BAR 0 [io 0x1000-0x107f]: assigned server # [ 4.356940] pci 0000:00:06.0: BAR 0 [io 0x1080-0x10ff]: assigned server # [ 4.357086] pci 0000:00:0b.0: BAR 0 [io 0x1100-0x113f]: assigned server # [ 4.357230] pci 0000:00:01.0: BAR 0 [io 0x1140-0x115f]: assigned server # [ 4.357367] pci 0000:00:02.0: BAR 0 [io 0x1160-0x117f]: assigned server # [ 4.357501] pci 0000:00:03.0: BAR 0 [io 0x1180-0x119f]: assigned server # [ 4.357630] pci 0000:00:04.0: BAR 0 [io 0x11a0-0x11bf]: assigned server # [ 4.357754] pci 0000:00:07.0: BAR 0 [io 0x11c0-0x11df]: assigned server # [ 4.357878] pci 0000:00:0c.0: BAR 0 [io 0x11e0-0x11ff]: assigned server # [ 4.358569] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] server # [ 4.358740] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] server # [ 4.358852] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] server # [ 4.362606] pci 0000:00:0a.0: enabling device (0000 -> 0002) server # [ 4.381614] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa60 took 19221 usecs server # [ 4.746711] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) server # [ 4.763447] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) server # [ 4.776459] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) server # [ 4.792959] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) server # [ 4.806985] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) server # [ 4.813373] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) server # [ 4.825344] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) server # [ 4.838982] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) server # [ 4.845189] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) server # [ 4.857470] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) server # [ 4.870875] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) server # [ 4.981222] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled server # [ 5.038990] msm_serial: driver initialized server # [ 5.050886] SuperH (H)SCI(F) driver initialized server # [ 5.053825] STM32 USART driver initialized server # [ 5.474053] loop: module loaded server # [ 5.493833] virtio_blk virtio4: 1/0/0 default/read/poll queues server # [ 5.507323] virtio_blk virtio4: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) server # [ 5.604905] virtio_blk virtio5: 1/0/0 default/read/poll queues server # [ 5.610057] virtio_blk virtio5: [vdb] 2919560 512-byte logical blocks (1.49 GB/1.39 GiB) server # [ 5.655018] megasas: 07.734.00.00-rc1 server # [ 5.701082] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] server # [ 5.714307] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 server # [ 5.715446] Intel/Sharp Extended Query Table at 0x0031 server # [ 5.719226] Using buffer write method server # [ 5.729710] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] server # [ 5.732946] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 server # [ 5.733304] Intel/Sharp Extended Query Table at 0x0031 server # [ 5.735031] Using buffer write method server # [ 5.735494] Concatenating MTD devices: server # [ 5.743963] (0): "0.flash" server # [ 5.744364] (1): "0.flash" server # [ 5.744488] into device "0.flash" server # [ 7.649436] Freeing initrd memory: 25572K server # [ 7.917171] tun: Universal TUN/TAP device driver, 1.6 server # [ 7.990230] thunder_xcv, ver 1.0 server # [ 7.990991] thunder_bgx, ver 1.0 server # [ 7.991517] nicpf, ver 1.0 server # [ 8.018568] e1000: Intel(R) PRO/1000 Network Driver server # [ 8.018848] e1000: Copyright (c) 1999-2006 Intel Corporation. server # [ 8.019372] e1000e: Intel(R) PRO/1000 Network Driver server # [ 8.019517] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. server # [ 8.027209] igb: Intel(R) Gigabit Ethernet Network Driver server # [ 8.027508] igb: Copyright (c) 2007-2014 Intel Corporation. server # [ 8.028961] igbvf: Intel(R) Gigabit Virtual Function Network Driver server # [ 8.029182] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. server # [ 8.031328] sky2: driver version 1.30 server # [ 8.082802] ehci-pci 0000:00:0a.0: EHCI Host Controller server # [ 8.084365] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 server # [ 8.088761] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 server # [ 8.097190] usbcore: registered new interface driver usb-storage server # [ 8.098438] usbcore: registered new interface driver usbserial_generic server # [ 8.099019] usbserial: USB Serial support registered for generic server # [ 8.101113] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 server # [ 8.124992] hub 1-0:1.0: USB hub found server # [ 8.129839] hv_vmbus: registering driver hyperv_keyboard server # [ 8.132797] hub 1-0:1.0: 6 ports detected server # [ 8.164909] rtc-pl031 9010000.pl031: registered as rtc0 server # [ 8.166072] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T22:26:27 UTC (1791498387) server # [ 8.173611] i2c_dev: i2c /dev entries driver server # [ 8.217421] sdhci: Secure Digital Host Controller Interface driver server # [ 8.217704] sdhci: Copyright(c) Pierre Ossman server # [ 8.222975] Synopsys Designware Multimedia Card Interface Driver server # [ 8.229858] sdhci-pltfm: SDHCI platform and OF driver helper server # [ 8.253580] hid: raw HID events driver (C) Jiri Kosina server # [ 8.259558] usbcore: registered new interface driver usbhid server # [ 8.260288] usbhid: USB HID core driver server # [ 8.290737] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available server # [ 8.316302] drop_monitor: Initializing network drop monitor service server # [ 8.319045] NET: Registered PF_INET6 protocol family server # [ 8.338202] Segment Routing with IPv6 server # [ 8.338915] In-situ OAM (IOAM) with IPv6 server # [ 8.341654] NET: Registered PF_PACKET protocol family server # [ 8.344919] 9pnet: Installing 9P2000 support server # [ 8.355382] Key type dns_resolver registered server # [ 8.408787] usb 1-1: new high-speed USB device number 2 using ehci-pci server # [ 8.472726] registered taskstats version 1 server # [ 8.480600] Loading compiled-in X.509 certificates server # [ 8.644477] 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/input0 server # [ 8.685402] Demotion targets for Node 0: null server # [ 8.689384] Key type .fscrypt registered server # [ 8.689671] Key type fscrypt-provisioning registered server # [ 8.708087] ima: No TPM chip found, activating TPM-bypass! server # [ 8.708531] ima: Allocated hash algorithm: sha1 server # [ 8.725364] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 server # [ 8.729804] ima: No architecture policies found server # [ 8.749934] input: gpio-keys as /devices/platform/gpio-keys/input/input1 server # [ 8.865069] usb 1-2: new high-speed USB device number 3 using ehci-pci server # [ 8.926177] clk: Disabling unused clocks server # [ 8.926824] PM: genpd: Disabling unused power domains server # [ 9.309961] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 server # [ 9.312706] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 server # [ 9.936313] Freeing unused kernel memory: 4736K server # [ 9.938499] Run /init as init process server # [ 10.298081] systemd[1]: Successfully made /usr/ read-only. server # [ 10.712548] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 10.720487] systemd[1]: Detected virtualization qemu. server # [ 10.724259] systemd[1]: Detected architecture arm64. server # [ 10.726658] systemd[1]: Running in initrd. server # [ 10.765918] systemd[1]: Initializing machine ID from random generator. server # [ 10.789862] systemd[1]: Hostname set to . server # [ 13.147093] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 13.354721] systemd[1]: Created slice Slice /system/modprobe. server # [ 13.378855] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 13.384366] systemd[1]: Expecting device /dev/disk/by-label/nix-store... server # [ 13.388708] systemd[1]: Expecting device /dev/disk/by-label/nixos... server # [ 13.392705] systemd[1]: Reached target Path Units. server # [ 13.395970] systemd[1]: Reached target Slice Units. server # [ 13.398795] systemd[1]: Reached target Swaps. server # [ 13.401978] systemd[1]: Reached target Timer Units. server # [ 13.413012] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 13.421992] systemd[1]: Listening on Journal Socket (/dev/log). server # [ 13.429073] systemd[1]: Listening on Journal Sockets. server # [ 13.434822] systemd[1]: Listening on udev Control Socket. server # [ 13.441102] systemd[1]: Listening on udev Kernel Socket. server # [ 13.444549] systemd[1]: Reached target Socket Units. server # [ 13.517396] systemd[1]: Starting Create List of Static Device Nodes... server # [ 13.581812] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 13.590166] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 13.702562] systemd[1]: Mounting Kernel Configuration File System... server # [ 14.206502] systemd[1]: Starting Journal Service... server # [ 14.342024] systemd[1]: Starting Load Kernel Modules... server # [ 14.349685] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 14.468039] systemd[1]: Starting Coldplug All udev Devices... server # [ 14.838793] systemd[1]: Finished Create List of Static Device Nodes. server # [ 15.061514] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 15.121399] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 15.182367] systemd[1]: Mounted Kernel Configuration File System. server # [ 15.370178] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 16.344847] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. server # [ 16.374024] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev server # [ 16.692495] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 server # [ 16.737877] [drm] features: -virgl +edid -resource_blob -host_visible server # [ 16.738145] [drm] features: -context_init server # [ 16.743396] systemd-journald[74]: Collecting audit messages is disabled. server # [ 16.766942] [drm] number of scanouts: 1 server # [ 16.767337] [drm] number of cap sets: 0 server # [ 16.791474] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 16.868911] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic server # [ 16.869377] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 server # [ 16.961827] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 17.287498] Console: switching to colour frame buffer device 160x50 server # [ 17.350476] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device server # [ 17.485701] systemd[1]: Finished Load Kernel Modules. server # [ 17.765911] systemd[1]: Starting Apply Kernel Variables... server # [ 18.739471] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 18.805410] systemd[1]: Reached target Preparation for Local File Systems. server # [ 18.810991] systemd[1]: Reached target Local File Systems. server # [ 18.990156] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 19.278538] systemd[1]: Finished Apply Kernel Variables. server # [ 23.289767] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 23.638093] systemd[1]: Started Journal Service. server # [ 23.449238] systemd-modules-load[75]: Inserted module 'dm_mod' server # [ 23.538398] systemd-modules-load[75]: Module 'virtio_balloon' is built in server # [ 23.593511] systemd-modules-load[75]: Module 'virtio_console' is built in server # [ 23.626891] systemd-modules-load[75]: Inserted module 'virtio_gpu' server # [ 23.674780] systemd-modules-load[75]: Module 'virtio_rng' is built in server # [ 23.799051] systemd-udevd[81]: Using default interface naming scheme 'v260'. server # [ 23.866228] systemd[1]: Starting Create System Files and Directories... server # [ 25.001869] systemd[1]: Finished Create System Files and Directories. server # [ 26.195979] systemd[1]: Starting Virtual Console Setup... server # [ 28.171765] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 28.296619] systemd[1]: Finished Virtual Console Setup. server # [ 59.482071] systemd[1]: Finished Coldplug All udev Devices. server # [ 59.505205] systemd[1]: Reached target System Initialization. server # [ 59.510528] systemd[1]: Reached target Basic System. server # [ 73.610944] (udev-worker)[141]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 73.636990] (udev-worker)[141]: Network interface NamePolicy= disabled on kernel command line. server # [ 73.663666] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line. server # [ 76.641948] systemd[1]: Found device /dev/disk/by-label/nix-store. server # [ 76.933347] systemd[1]: Found device /dev/disk/by-label/nixos. server # [ 76.946101] systemd[1]: Reached target Initrd Root Device. server # [ 77.017339] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... server # [ 78.294565] systemd-fsck[162]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 78.483581] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. server # [ 78.585374] systemd[1]: Mounting /sysroot... server # [ 79.615075] EXT4-fs (vda): mounted filesystem b4730de7-7260-4382-b53f-90bb14e2932b r/w with ordered data mode. Quota mode: none. server # [ 79.569905] systemd[1]: Mounted /sysroot. server # [ 79.581721] systemd[1]: Reached target Initrd Root File System. server # [ 79.775746] systemd[1]: Mounting /sysroot/nix/.ro-store... server # [ 79.911792] systemd[1]: Mounting /sysroot/nix/.rw-store... server # [ 80.129643] systemd[1]: Mounting /sysroot/run... server # [ 80.421180] systemd[1]: Mounting /sysroot/tmp/shared... server # [ 80.833885] systemd[1]: Mounting /sysroot/tmp/xchg... server # [ 81.287587] systemd[1]: Starting Mountpoints Configured in the Real Root... server # [ 82.278362] erofs (device vdb): mounted with root inode @ nid 36. server # [ 82.831482] systemd-sysroot-fstab-check[185]: /sysroot should be mounted in the initrd, will request daemon-reload. server # [ 82.981977] systemd[1]: Mounted /sysroot/nix/.ro-store. server # [ 82.990425] systemd[1]: Mounted /sysroot/nix/.rw-store. server # [ 83.005334] systemd[1]: Mounted /sysroot/run. server # [ 83.014384] systemd[1]: Mounted /sysroot/tmp/shared. server # [ 83.030317] systemd[1]: Mounted /sysroot/tmp/xchg. server # [ 83.190107] systemd[1]: Reload requested from client PID 185 ('systemd-sysroot') (unit initrd-parse-etc.service)... server # [ 83.202867] systemd[1]: Reloading... server # [ 87.406805] systemd[1]: Reloading finished in 4172 ms. server # [ 87.668952] systemd-sysroot-fstab-check[185]: Requesting initrd-fs.target/start/replace... server # [ 87.723932] systemd-sysroot-fstab-check[185]: Requesting swap.target/start/replace... server # [ 87.803723] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 87.832818] systemd[1]: initrd-parse-etc.service: Deactivated successfully. server # [ 87.858445] systemd[1]: Finished Mountpoints Configured in the Real Root. server # [ 87.873245] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. server # [ 88.479313] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 88.491492] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 88.567488] systemd[1]: Mounting /sysroot/nix/store... server # [ 89.711163] systemd[1]: Mounted /sysroot/nix/store. server # [ 89.745300] systemd[1]: Reached target Initrd File Systems. server # [ 89.811246] systemd[1]: Starting Find NixOS closure... server # [ 89.902113] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... server # [ 91.110342] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. server # [ 91.213052] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. server # [ 91.703292] systemd[1]: Finished Find NixOS closure. server # [ 91.747361] systemd[1]: Reached target Initrd Default Target. server # [ 91.837096] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... server # [ 92.794433] systemd[1]: Stopped target Initrd Default Target. server # [ 92.807044] systemd[1]: Stopped target Basic System. server # [ 92.826130] systemd[1]: Stopped target Initrd Root Device. server # [ 92.849267] systemd[1]: Stopped target Path Units. server # [ 92.859127] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. server # [ 92.875063] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. server # [ 92.914402] systemd[1]: Stopped target Slice Units. server # [ 92.937326] systemd[1]: Stopped target Socket Units. server # [ 92.955072] systemd[1]: Stopped target System Initialization. server # [ 92.983619] systemd[1]: Stopped target Swaps. server # [ 92.998120] systemd[1]: Stopped target Timer Units. server # [ 93.014032] systemd[1]: dbus.socket: Deactivated successfully. server # [ 93.037570] systemd[1]: Closed D-Bus System Message Bus Socket. server # [ 93.057496] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. server # [ 93.082076] systemd[1]: Stopped Find NixOS closure. server # [ 93.193381] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 93.369895] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 93.387376] systemd[1]: systemd-sysctl.service: Deactivated successfully. server # [ 93.441390] systemd[1]: Stopped Apply Kernel Variables. server # [ 93.474678] systemd[1]: systemd-modules-load.service: Deactivated successfully. server # [ 93.554980] systemd[1]: Stopped Load Kernel Modules. server # [ 93.641302] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. server # [ 93.697609] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. server # [ 93.749035] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. server # [ 93.837981] systemd[1]: Stopped Create System Files and Directories. server # [ 93.878888] systemd[1]: Stopped target Local File Systems. server # [ 93.933341] systemd[1]: Stopped target Preparation for Local File Systems. server # [ 93.958133] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. server # [ 94.003881] systemd[1]: Stopped Coldplug All udev Devices. server # [ 94.047320] systemd[1]: systemd-udev-trigger.service: Consumed 22.915s CPU time over 1min 19.759s wall clock time, 9.3M memory peak. server # [ 94.065515] systemd[1]: Stopping Rule-based Manager for Device Events and Files... server # [ 94.086697] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 94.106929] systemd[1]: Stopped Virtual Console Setup. server # [ 94.555474] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 94.582025] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 94.638912] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 94.659127] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 94.777581] systemd[1]: initrd-cleanup.service: Deactivated successfully. server # [ 94.793489] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. server # [ 95.786900] systemd[1]: systemd-udevd.service: Deactivated successfully. server # [ 95.799837] systemd[1]: Stopped Rule-based Manager for Device Events and Files. server # [ 95.827049] systemd[1]: systemd-udevd.service: Consumed 27.718s CPU time over 1min 17.021s wall clock time, 21.2M memory peak. server # [ 95.871418] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. server # [ 95.883993] systemd[1]: Closed udev Control Socket. server # [ 95.915264] systemd[1]: Starting Cleanup udev Database... server # [ 95.923766] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. server # [ 95.938177] systemd[1]: Stopped Create Static Device Nodes in /dev. server # [ 95.946658] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. server # [ 95.958883] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. server # [ 95.967175] systemd[1]: kmod-static-nodes.service: Deactivated successfully. server # [ 95.978980] systemd[1]: Stopped Create List of Static Device Nodes. server # [ 96.369068] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. server # [ 96.385837] systemd[1]: Finished Cleanup udev Database. server # [ 96.423323] systemd[1]: Reached target Switch Root. server # [ 96.470847] systemd[1]: Starting NixOS Activation... server # [ 98.342919] initrd-nixos-activation-start[241]: booting system configuration /nix/store/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test server # [ 99.137323] initrd-nixos-activation-start[241]: running activation script... server # [ 105.489506] initrd-nixos-activation-start[264]: setting up /etc... server # [ 108.301725] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. server # [ 108.311539] systemd[1]: Finished NixOS Activation. server # [ 108.321896] systemd[1]: initrd-nixos-activation.service: Consumed 9.517s CPU time over 11.824s wall clock time, 36.6M memory peak. server # [ 108.362638] systemd[1]: Starting Switch Root... server # [ 108.673040] systemd[1]: Switching root. server # [ 109.210766] systemd-journald[74]: Received SIGTERM from PID 1 (systemd). server # [ 111.358802] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 111.366645] systemd[1]: Detected virtualization qemu. server # [ 111.373634] systemd[1]: Detected architecture arm64. server # [ 111.378918] systemd[1]: Detected first boot. server # [ 111.405316] systemd[1]: Initializing machine ID from random generator. server # [ 114.468293] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 116.651494] systemd[1]: Applying preset policy. server # [ 122.003401] systemd[1]: Populated /etc with preset unit settings. server # [ 126.436828] systemd[1]: initrd-switch-root.service: Deactivated successfully. server # [ 126.451142] systemd[1]: Stopped initrd-switch-root.service. server # [ 126.535435] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. server # [ 126.575149] systemd[1]: Created slice Slice /system/getty. server # [ 126.603459] systemd[1]: Created slice User and Session Slice. server # [ 126.617922] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 126.629102] systemd[1]: Started Forward Password Requests to Wall Directory Watch. server # [ 126.638381] systemd[1]: Expecting device /dev/hvc0... server # [ 126.646427] systemd[1]: Expecting device /dev/ttyAMA0... server # [ 126.654795] systemd[1]: Reached target Local Encrypted Volumes. server # [ 126.663147] systemd[1]: Stopped target initrd-fs.target. server # [ 126.671387] systemd[1]: Stopped target initrd-root-fs.target. server # [ 126.679043] systemd[1]: Stopped target initrd-switch-root.target. server # [ 126.687599] systemd[1]: Reached target Virtual Machines and Containers. server # [ 126.696881] systemd[1]: Reached target Path Units. server # [ 126.705365] systemd[1]: Reached target Remote File Systems. server # [ 126.714015] systemd[1]: Reached target Slice Units. server # [ 126.721853] systemd[1]: Reached target Swaps. server # [ 126.763586] systemd[1]: Listening on Query the User Interactively for a Password. server # [ 126.807109] systemd[1]: Listening on Process Core Dump Socket. server # [ 126.839109] systemd[1]: Listening on Credential Encryption/Decryption. server # [ 126.876815] systemd[1]: Listening on Factory Reset Management. server # [ 126.941886] systemd[1]: Starting Journal Log Access Socket... server # [ 126.972737] systemd[1]: Listening on Journal Audit Socket. server # [ 127.032224] systemd[1]: Listening on Console Output Muting Service Socket. server # [ 127.045289] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. server # [ 127.054516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki server # [ 127.065103] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki server # [ 127.136415] systemd[1]: Listening on Disk Repartitioning Service Socket. server # [ 127.148008] systemd[1]: Listening on udev Control Socket. server # [ 127.161421] systemd[1]: Listening on udev Varlink Socket. server # [ 127.235283] systemd[1]: Mounting Huge Pages File System... server # [ 127.325972] systemd[1]: Mounting POSIX Message Queue File System... server # [ 127.474077] systemd[1]: Mounting Kernel Debug File System... server # [ 127.658348] systemd[1]: Mounting Kernel Trace File System... server # [ 127.914412] systemd[1]: Starting Create List of Static Device Nodes... server # [ 128.254068] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 128.302468] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 128.650214] systemd[1]: Mounting Kernel Configuration File System... server # [ 128.726002] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm server # [ 128.790213] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 128.997994] systemd[1]: Starting Load Kernel Module fuse... server # [ 129.032014] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 129.342265] systemd[1]: Starting Journal Service... server # [ 129.625977] systemd[1]: Starting Load Kernel Modules... server # [ 129.888497] fuse: init (API version 7.45) server # [ 129.977950] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... server # [ 130.146174] systemd[1]: Starting Remount Root and Kernel File Systems... server # [ 130.215283] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 130.589933] systemd[1]: Starting Coldplug All udev Devices... server # [ 131.073723] systemd-journald[335]: Collecting audit messages is enabled. server # [ 131.443729] systemd[1]: Listening on Journal Log Access Socket. server # [ 131.547314] systemd[1]: Mounted Huge Pages File System. server # [ 131.606453] systemd[1]: Mounted POSIX Message Queue File System. server # [ 131.666036] systemd[1]: Mounted Kernel Debug File System. server # [ 131.715207] systemd[1]: Mounted Kernel Trace File System. server # [ 131.834818] systemd[1]: Finished Create List of Static Device Nodes. server # [ 131.933009] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 131.997531] EXT4-fs (vda): re-mounted b4730de7-7260-4382-b53f-90bb14e2932b. server # [ 132.026094] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 131.895504] systemd[1]: Queued start job for default target Multi-User System. server # [ 131.955382] systemd[1]: Unnecessary job was removed for /dev/vdb. server # [ 132.031850] systemd[1]: systemd-journald.service: Deactivated successfully. server # [ 132.081298] systemd[1]: systemd-journald.service: Consumed 3.296s CPU time over 1min 52.109s wall clock time, 4M memory peak. server # [ 132.362838] systemd[1]: Started Journal Service. server # [ 132.162360] systemd-modules-load[336]: Module 'atkbd' is built in server # [ 132.201898] systemd-modules-load[336]: Module 'loop' is built in server # [ 132.235060] systemd[1]: Mounted Kernel Configuration File System. server # [ 132.317314] systemd[1]: modprobe@fuse.service: Deactivated successfully. server # [ 132.386894] systemd[1]: Finished Load Kernel Module fuse. server # [ 132.466054] systemd[1]: Finished Load Kernel Modules. server # [ 132.527529] systemd[1]: Finished Remount Root and Kernel File Systems. server # [ 133.157784] systemd[1]: Listening on Disk Image Download Service Socket. server # [ 133.269090] systemd[1]: Mounting FUSE Control File System... server # [ 133.523458] systemd[1]: Starting Firewall... server # [ 133.853452] systemd[1]: Starting Flush Journal to Persistent Storage... server # [ 133.897303] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore server # [ 134.257993] systemd-oomd[337]: No swap; memory pressure usage will be degraded server # [ 134.435531] systemd[1]: Starting Load/Save OS Random Seed... server # [ 134.835828] systemd[1]: Starting Apply Kernel Variables... server # [ 135.339496] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 135.478169] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 136.040814] systemd-journald[335]: Received client request to flush runtime journal. server # [ 138.210695] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. server # [ 138.262002] systemd[1]: Mounted FUSE Control File System. server # [ 138.301692] systemd[1]: Finished Load/Save OS Random Seed. server # [ 138.337350] systemd[1]: Reached target First Boot Complete. server # [ 138.380021] systemd[1]: Finished Flush Journal to Persistent Storage. server # [ 138.555322] systemd[1]: Finished Apply Kernel Variables. server # [ 139.331810] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 139.577836] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 141.071900] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 141.197388] systemd[1]: Reached target Preparation for Local File Systems. server # [ 141.353641] systemd[1]: Mounting /run/wrappers... server # [ 141.596981] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 142.834270] systemd[1]: Mounted /run/wrappers. server # [ 142.869030] systemd[1]: Reached target Local File Systems. server # [ 143.001024] systemd[1]: Listening on Boot Loader Control Service Socket. server # [ 143.181910] systemd[1]: Starting register-nix-paths.service... server # [ 143.486728] systemd[1]: Starting Create SUID/SGID Wrappers... server # [ 143.525213] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 143.991306] systemd[1]: Starting Save Transient machine-id to Disk... server # [ 144.597235] systemd[1]: Starting Create System Files and Directories... server # [ 147.262998] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. server # [ 147.366380] systemd[1]: Finished Save Transient machine-id to Disk. server # [ 150.934450] systemd[1]: Finished Create System Files and Directories. server # [ 151.449114] systemd[1]: Starting Rebuild Journal Catalog... server # [ 151.952047] systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [ 155.042732] systemd[1]: Finished Record System Boot/Shutdown in UTMP. server # [ 157.761506] systemd[1]: Finished Rebuild Journal Catalog. server # [ 158.037002] systemd[1]: Starting Update is Completed... server # [ 159.782160] systemd[1]: Finished Update is Completed. server # [ 162.393422] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. server # [ 162.460264] systemd[1]: Finished Create SUID/SGID Wrappers. server # [ 162.494408] systemd[1]: suid-sgid-wrappers.service: Consumed 2.547s CPU time over 18.899s wall clock time, 5.1M memory peak, 1.9M read from disk. server # [ 165.171018] systemd[1]: Finished Firewall. server # [ 168.254672] systemd[1]: Finished register-nix-paths.service. server # [ 171.922174] systemd-udevd[394]: Using default interface naming scheme 'v260'. server # [ 173.879100] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 194.539034] systemd[1]: Finished Coldplug All udev Devices. server # [ 194.562233] systemd[1]: Reached target System Initialization. server # [ 194.580952] systemd[1]: Started Discard unused filesystem blocks once a week. server # [ 194.587882] systemd[1]: Started postgresqlBackup-horae.timer. server # [ 194.598456] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 194.618195] systemd[1]: Reached target Timer Units. server # [ 194.637389] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 194.650159] systemd[1]: Listening on Nix Daemon Socket. server # [ 194.658275] systemd[1]: Listening on Hostname Service Socket. server # [ 194.666564] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. server # [ 194.678551] systemd[1]: Reached target Socket Units. server # [ 194.690376] systemd[1]: Reached target Basic System. server # [ 194.755242] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 194.941652] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 195.143158] systemd[1]: Starting Post-Boot Actions... server # [ 195.469080] systemd[1]: Started Reset console on configuration changes. server # [ 195.875069] systemd[1]: Starting resolvconf update... server # [ 196.276953] systemd[1]: Started test-console-diagnostics.service. server # [ 197.040918] systemd[1]: Finished Post-Boot Actions. server # [ 197.179438] nsncd[582]: Oct 08 22:29:36.675 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 197.298275] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 197.825480] systemd[1]: Reached target Host and Network Name Lookups. server # [ 197.846094] systemd[1]: Reached target User and Group Name Lookups. server # [ 198.185562] systemd[1]: Starting D-Bus System Message Bus... server # [ 198.897282] systemd[1]: Starting User Login Management... server # [ 199.101295] systemd[1]: Finished Import lastlog data into lastlog2 database. server # [ 200.422273] test-console-diagnostics-start[616]: JOB UNIT TYPE STATE server # [ 200.450664] test-console-diagnostics-start[616]: 294 dev-ttyAMA0.device start running server # [ 200.473843] test-console-diagnostics-start[616]: 285 postgresql-setup.service start waiting server # [ 200.493728] test-console-diagnostics-start[616]: 295 dev-hvc0.device start running server # [ 200.516852] test-console-diagnostics-start[616]: 296 network.target start waiting server # [ 200.553791] test-console-diagnostics-start[616]: 315 dbus-broker.service start running server # [ 200.577767] test-console-diagnostics-start[616]: 297 network-local-commands.service start waiting server # [ 200.598993] test-console-diagnostics-start[616]: 278 systemd-logind.service start running server # [ 200.605833] test-console-diagnostics-start[616]: 175 multi-user.target start waiting server # [ 200.607684] test-console-diagnostics-start[616]: 283 postgresql.target start waiting server # [ 200.649224] test-console-diagnostics-start[616]: 269 getty.target start waiting server # [ 200.662238] test-console-diagnostics-start[616]: 270 getty@tty1.service start waiting server # [ 200.691039] test-console-diagnostics-start[616]: 299 dhcpcd.service start waiting server # [ 200.707121] test-console-diagnostics-start[616]: 293 backdoor.service start waiting server # [ 200.740559] test-console-diagnostics-start[616]: 274 dex.service start waiting server # [ 200.759435] test-console-diagnostics-start[616]: 282 horae.service start waiting server # [ 200.785783] test-console-diagnostics-start[616]: 284 postgresql.service start waiting server # [ 200.787821] test-console-diagnostics-start[616]: 231 network-pre.target start waiting server # [ 200.821357] test-console-diagnostics-start[616]: 276 systemd-user-sessions.service start waiting server # [ 200.846221] test-console-diagnostics-start[616]: 277 linger-users.service start waiting server # [ 200.861402] test-console-diagnostics-start[616]: 275 resolvconf.service start running server # [ 200.881241] test-console-diagnostics-start[616]: 20 jobs listed. server # [ 201.435181] dbus-broker-launch[603]: Looking up NSS user entry for 'systemd-timesync'... server # [ 201.885810] dbus-broker-launch[603]: NSS returned no entry for 'systemd-timesync' server # [ 201.906414] dbus-broker-launch[603]: Invalid user-name in /nix/store/s8i6b1w4ph66lzniglnwcmzrxxzbqy2b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" server # [ 202.570820] systemd[1]: Started D-Bus System Message Bus. server # [ 203.505467] dbus-broker-launch[603]: Ready server # [ 203.982025] systemd-logind[607]: New seat seat0. server # [ 204.103949] test-console-diagnostics-start[627]: Id=backdoor.service server # [ 204.126058] test-console-diagnostics-start[627]: Requires=sysinit.target dev-ttyAMA0.device dev-hvc0.device system.slice server # [ 204.145845] test-console-diagnostics-start[627]: After=sysinit.target dev-ttyAMA0.device systemd-journald.socket basic.target dev-hvc0.device system.slice server # [ 204.167006] test-console-diagnostics-start[627]: ActiveState=inactive server # [ 204.173727] test-console-diagnostics-start[627]: SubState=dead server # [ 204.175428] test-console-diagnostics-start[627]: Result=success server # [ 204.190038] test-console-diagnostics-start[627]: Id=dev-hvc0.device server # [ 204.201778] test-console-diagnostics-start[627]: Requires= server # [ 204.217309] test-console-diagnostics-start[627]: After= server # [ 204.221191] test-console-diagnostics-start[627]: ActiveState=activating server # [ 204.233536] test-console-diagnostics-start[627]: SubState=tentative server # [ 204.249449] test-console-diagnostics-start[627]: Id=dev-ttyAMA0.device server # [ 204.253379] test-console-diagnostics-start[627]: Requires= server # [ 204.270892] test-console-diagnostics-start[627]: After= server # [ 204.286602] test-console-diagnostics-start[627]: ActiveState=activating server # [ 204.301238] test-console-diagnostics-start[627]: SubState=tentative server # [ 204.309324] test-console-diagnostics-start[627]: Id=dev-ttyS0.device server # [ 204.321678] test-console-diagnostics-start[627]: Requires= server # [ 204.333293] test-console-diagnostics-start[627]: After= server # [ 204.341464] test-console-diagnostics-start[627]: ActiveState=inactive server # [ 204.353268] test-console-diagnostics-start[627]: SubState=dead server # [ 204.398099] systemd[1]: Started User Login Management. server # [ 204.561675] test-console-diagnostics-start[644]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 204.573612] test-console-diagnostics-start[644]: DEVNAME=/dev/hvc0 server # [ 204.587926] test-console-diagnostics-start[644]: MAJOR=229 server # [ 204.602482] test-console-diagnostics-start[644]: MINOR=0 server # [ 204.621027] test-console-diagnostics-start[644]: SUBSYSTEM=tty server # [ 204.631032] systemd[1]: Stopped target Host and Network Name Lookups. server # [ 204.654320] systemd[1]: Stopping Host and Network Name Lookups... server # [ 204.670478] systemd[1]: Stopped target User and Group Name Lookups. server # [ 204.690841] systemd[1]: Stopping User and Group Name Lookups... server # [ 204.838328] systemd[1]: Starting linger-users.service... server # [ 204.933295] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... server # [ 205.058452] test-console-diagnostics-start[646]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 205.073541] test-console-diagnostics-start[646]: DEVNAME=/dev/ttyAMA0 server # [ 205.089457] test-console-diagnostics-start[646]: MAJOR=204 server # [ 205.105760] test-console-diagnostics-start[646]: MINOR=64 server # [ 205.117582] test-console-diagnostics-start[646]: SUBSYSTEM=tty server # [ 205.165192] systemd[1]: nscd.service: Deactivated successfully. server # [ 205.276992] systemd[1]: Stopped Name Service Cache Daemon (nsncd). server # [ 205.281017] systemd[1]: nscd.service: Consumed 541ms CPU time over 10.256s wall clock time, 7.8M memory peak, 5.9M read from disk. server # [ 205.425863] test-console-diagnostics-start[652]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 205.442495] test-console-diagnostics-start[652]: DEVNAME=/dev/ttyS0 server # [ 205.457388] test-console-diagnostics-start[652]: MAJOR=4 server # [ 205.466172] test-console-diagnostics-start[652]: MINOR=64 server # [ 205.478432] test-console-diagnostics-start[652]: SUBSYSTEM=tty server # [ 205.991569] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 206.321178] systemd[1]: Finished resolvconf update. server # [ 206.378605] systemd[1]: linger-users.service: Deactivated successfully. server # [ 206.406613] systemd[1]: Finished linger-users.service. server # [ 206.589939] systemd[1]: Reached target Preparation for Network. server # [ 206.768951] systemd[1]: Starting DHCP Client... server # [ 206.997432] systemd[1]: Starting Extra networking commands.... server # [ 207.087582] nsncd[664]: Oct 08 22:29:46.619 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 207.130153] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 207.238870] systemd[1]: Reached target Host and Network Name Lookups. server # [ 207.270546] systemd[1]: Reached target User and Group Name Lookups. server # [ 211.874667] systemd[1]: Finished Extra networking commands.. server # [ 211.937728] systemd[1]: Reached target Network. server # [ 212.119046] systemd[1]: Starting dex identity provider... server # [ 212.266550] dhcpcd[693]: dhcpcd-10.3.2 starting server # [ 212.382166] dhcpcd[739]: dev: loaded udev server # [ 212.487137] systemd[1]: Starting PostgreSQL Server... server # [ 212.521174] dhcpcd[739]: no valid interfaces found server # [ 212.529789] dhcpcd[739]: no valid interfaces found server # [ 212.630186] systemd[1]: Starting Permit User Sessions... server # [ 213.921643] systemd[1]: Finished Permit User Sessions. server # [ 214.239253] systemd[1]: Started Getty on tty1. server # [ 214.316944] systemd[1]: Reached target Login Prompts. server # [ 218.371194] postgresql-pre-start[750]: The files belonging to this database system will be owned by user "postgres". server # [ 218.387242] postgresql-pre-start[750]: This user must also own the server process. server # [ 218.456944] postgresql-pre-start[750]: The database cluster will be initialized with locale "en_US.UTF-8". server # [ 218.461529] postgresql-pre-start[750]: The default database encoding has accordingly been set to "UTF8". server # [ 218.478334] postgresql-pre-start[750]: The default text search configuration will be set to "english". server # [ 218.489446] postgresql-pre-start[750]: Data page checksums are disabled. server # [ 218.493935] postgresql-pre-start[750]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok server # [ 218.600734] postgresql-pre-start[750]: creating subdirectories ... ok server # [ 218.610222] postgresql-pre-start[750]: selecting dynamic shared memory implementation ... posix server # [ 218.764054] systemd[1]: Started dex identity provider. server # [ 220.919346] postgresql-pre-start[750]: selecting default "max_connections" ... 100 server # [ 221.346207] systemd-logind[607]: Watching system buttons on /dev/input/event1 (gpio-keys) server # [ 223.257954] postgresql-pre-start[750]: selecting default "shared_buffers" ... 128MB server # [ 228.391196] dex[765]: time=2026-10-08T22:30:07.899Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=arm64 server # [ 228.419601] dex[765]: time=2026-10-08T22:30:07.943Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex server # [ 228.441126] dex[765]: time=2026-10-08T22:30:07.982Z level=INFO msg="config storage" storage_type=memory server # [ 228.457125] dex[765]: time=2026-10-08T22:30:07.983Z level=INFO msg="config static client" client_name=Horae server # [ 228.469851] dex[765]: time=2026-10-08T22:30:07.984Z level=INFO msg="config connector" connector_id=mock server # [ 228.477680] dex[765]: time=2026-10-08T22:30:07.985Z level=INFO msg="config skipping approval screen" server # [ 228.493871] dex[765]: time=2026-10-08T22:30:07.986Z level=INFO msg="continue on connector failure feature flag enabled" server # [ 228.511917] dex[765]: time=2026-10-08T22:30:08.000Z level=INFO msg="signer configured" type=local keys_rotation_period=6h server # [ 228.517859] dex[765]: time=2026-10-08T22:30:08.002Z level=INFO msg="config refresh tokens rotation" enabled=true server # [ 228.569155] dex[765]: time=2026-10-08T22:30:08.132Z level=ERROR msg="failed to get signing algorithm" err="no signing key found" server # [ 228.665920] dex[765]: time=2026-10-08T22:30:08.214Z level=INFO msg="keys expired, rotating" server # [ 233.643848] dex[765]: time=2026-10-08T22:30:13.180Z level=INFO msg="keys rotated" next_rotation=2026-10-09T04:30:13.180Z server # [ 233.729517] dex[765]: time=2026-10-08T22:30:13.293Z level=INFO msg="listening on" server=http address=127.0.0.1:5556 server # [ 241.956730] mousedev: PS/2 mouse device common for all mice server # [ 242.592017] dhcpcd[739]: timed out server # [ 242.606911] dhcpcd[739]: timed out server # [ 242.667639] systemd[1]: Started DHCP Client. server # [ 249.427858] systemd-logind[607]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard) server # [ 250.805931] postgresql-pre-start[750]: selecting default time zone ... UTC server # [ 250.858045] postgresql-pre-start[750]: creating configuration files ... ok elastic-arm-0f89b4f7: building xzpx080m6x4ijl1gy6jpfanc09xxzl9x-vm-test-run-horae-e2e-oidc.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: server, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh server: starting vm server: QEMU running (pid 12) server # Disk image does not exist, creating the virtualisation disk image... server # Formatting '/build/vm-state-server/tmp.j97Ac7hmHD', fmt=raw size=1073741824 server # mke2fs 1.47.4 (6-Mar-2025) server # Discarding device blocks: 0/262144 done server # Creating filesystem with 262144 4k blocks and 65536 inodes server # Filesystem UUID: dbc17c46-8b04-4a5b-a960-c9035675691b server # Superblock backups stored on blocks: server # 32768, 98304, 163840, 229376 server # server # Allocating group tables: 0/8 done server # Writing inode tables: 0/8 done server # Creating journal (8192 blocks): done server # Writing superblocks and filesystem accounting information: 0/8 done server # server # Virtualisation disk image created. server # Creating Nix store image... server # Created Nix store image. server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory server # qemu-system-aarch64: failed to initialize kvm: No such file or directory server # qemu-system-aarch64: falling back to tcg server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] server # [ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jul 4 11:44:22 UTC 2026 server # [ 0.000000] KASLR enabled server # [ 0.000000] random: crng init done server # [ 0.000000] Machine model: linux,dummy-virt server # [ 0.000000] efi: UEFI not found. server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf] server # [ 0.000000] Zone ranges: server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] DMA32 empty server # [ 0.000000] Normal empty server # [ 0.000000] Device empty server # [ 0.000000] Movable zone start for each node server # [ 0.000000] Early memory node ranges server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 server # [ 0.000000] psci: probing for conduit method from DT. server # [ 0.000000] psci: PSCIv1.1 detected in firmware. server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs server # [ 0.000000] psci: Trusted OS migration not required server # [ 0.000000] psci: SMC Calling Convention v1.0 server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296 server # [ 0.000000] Detected PIPT I-cache on CPU0 server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) server # [ 0.000000] CPU features: detected: GICv3 CPU interface server # [ 0.000000] CPU features: detected: HCRX_EL2 register server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE) server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) server # [ 0.000000] CPU features: detected: Spectre-v4 server # [ 0.000000] alternatives: applying boot alternatives server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test/init regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration console=ttyAMA0,115200n8 console=tty0 server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration", will be passed to user space. server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB server # [ 0.000000] software IO TLB: area num 1. server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB) server # [ 0.000000] Fallback order for Node 0: 0 server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 server # [ 0.000000] Policy zone: DMA server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 server # [ 0.000000] allocated 2097152 bytes of page_ext server # [ 0.000000] ftrace: allocating 74713 entries in 292 pages server # [ 0.000000] ftrace: allocated 292 pages with 3 groups server # [ 0.000000] rcu: Hierarchical RCU implementation. server # [ 0.000000] rcu: RCU event tracing is enabled. server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. server # [ 0.000000] Trampoline variant of Tasks RCU enabled. server # [ 0.000000] Rude variant of Tasks RCU enabled. server # [ 0.000000] Tracing variant of Tasks RCU enabled. server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 server # [ 0.000000] GICv3: 256 SPIs implemented server # [ 0.000000] GICv3: 0 Extended SPIs implemented server # [ 0.000000] Root IRQ handler: gic_handle_irq server # [ 0.000000] GICv3: GICv3 features: 16 PPIs server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) server # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns server # [ 0.000084] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns server # [ 0.006102] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) server # [ 0.034322] Console: colour dummy device 80x25 server # [ 0.038193] printk: legacy console [tty0] enabled server # [ 0.053571] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) server # [ 0.055223] pid_max: default: 32768 minimum: 301 server # [ 0.062947] LSM: initializing lsm=capability,landlock,yama,bpf,ima server # [ 0.066850] landlock: Up and running. server # [ 0.067052] Yama: becoming mindful. server # [ 0.070276] LSM support for eBPF active server # [ 0.076953] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.077570] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.157256] cacheinfo: Unable to detect cache hierarchy for CPU 0 server # [ 0.193616] rcu: Hierarchical SRCU implementation. server # [ 0.193855] rcu: Max phase no-delay instances is 1000. server # [ 0.216391] fsl-mc MSI: its@8080000 domain created server # [ 0.221400] EFI services will not be available. server # [ 0.225270] smp: Bringing up secondary CPUs ... server # [ 0.226778] smp: Brought up 1 node, 1 CPU server # [ 0.226956] SMP: Total of 1 processors activated. server # [ 0.229572] CPU: All CPU(s) started at EL1 server # [ 0.230397] CPU features: detected: Branch Target Identification server # [ 0.230565] CPU features: detected: 32-bit EL0 Support server # [ 0.230640] CPU features: detected: 32-bit EL1 Support server # [ 0.230706] CPU features: detected: ARMv8.4 Translation Table Level server # [ 0.230788] CPU features: detected: Instruction cache invalidation not required for I/D coherence server # [ 0.230886] CPU features: detected: Data cache clean to the PoU not required for I/D coherence server # [ 0.231019] CPU features: detected: Common not Private translations server # [ 0.231085] CPU features: detected: CRC32 instructions server # [ 0.231155] CPU features: detected: Data cache clean to Point of Deep Persistence server # [ 0.231229] CPU features: detected: Data cache clean to Point of Persistence server # [ 0.231295] CPU features: detected: Data independent timing control (DIT) server # [ 0.231361] CPU features: detected: E0PD server # [ 0.231413] CPU features: detected: Enhanced Counter Virtualization server # [ 0.231519] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) server # [ 0.232392] CPU features: detected: Enhanced Privileged Access Never server # [ 0.232478] CPU features: detected: Enhanced Virtualization Traps server # [ 0.232572] CPU features: detected: Fine Grained Traps server # [ 0.232666] CPU features: detected: Guarded Control Stack (GCS) server # [ 0.232935] CPU features: detected: Generic authentication (IMP DEF algorithm) server # [ 0.233199] CPU features: detected: RCpc load-acquire (LDAPR) server # [ 0.233356] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) server # [ 0.233453] CPU features: detected: LSE atomic instructions server # [ 0.233524] CPU features: detected: Memory Copy and Memory Set instructions server # [ 0.233592] CPU features: detected: Privileged Access Never server # [ 0.233710] CPU features: detected: PMUv3 server # [ 0.233766] CPU features: detected: SCTLR2 server # [ 0.233818] CPU features: detected: RAS Extension Support server # [ 0.234001] CPU features: detected: RASv1p1 Extension Support server # [ 0.234081] CPU features: detected: Random Number Generator server # [ 0.234155] CPU features: detected: Speculation barrier (SB) server # [ 0.234222] CPU features: detected: Stage-2 Force Write-Back server # [ 0.234286] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality server # [ 0.234359] CPU features: detected: TLB range maintenance instructions server # [ 0.234424] CPU features: detected: WFx with timeout server # [ 0.234628] CPU features: detected: Scalable Matrix Extension server # [ 0.234696] CPU features: detected: FA64 server # [ 0.234748] CPU features: detected: SME2 server # [ 0.234801] CPU features: detected: Speculative Store Bypassing Safe (SSBS) server # [ 0.234867] CPU features: detected: Scalable Vector Extension server # [ 0.254960] alternatives: applying system-wide alternatives server # [ 0.281307] CPU features: detected: Hardware dirty bit management on CPU0 server # [ 0.282958] SVE: maximum available vector length 256 bytes per vector server # [ 0.283153] SVE: default vector length 64 bytes per vector server # [ 0.285842] SME: minimum available vector length 16 bytes per vector server # [ 0.286031] SME: maximum available vector length 256 bytes per vector server # [ 0.286111] SME: default vector length 32 bytes per vector server # [ 0.319483] Memory: 891156K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 116168K reserved, 32768K cma-reserved) server # [ 0.370082] devtmpfs: initialized server # [ 0.436388] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns server # [ 0.438869] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.439541] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). server # [ 0.446970] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL server # [ 0.447208] 0 pages in range for non-PLT usage server # [ 0.447303] 508320 pages in range for PLT usage server # [ 0.454264] pinctrl core: initialized pinctrl subsystem server # [ 0.483842] DMI not present or invalid. server # [ 0.521372] NET: Registered PF_NETLINK/PF_ROUTE protocol family server # [ 0.547567] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations server # [ 0.551223] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations server # [ 0.555088] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations server # [ 0.555756] audit: initializing netlink subsys (disabled) server # [ 0.562660] audit: type=2000 audit(0.452:1): state=initialized audit_enabled=0 res=1 server # [ 0.578427] thermal_sys: Registered thermal governor 'fair_share' server # [ 0.578562] thermal_sys: Registered thermal governor 'bang_bang' server # [ 0.578692] thermal_sys: Registered thermal governor 'step_wise' server # [ 0.578808] thermal_sys: Registered thermal governor 'user_space' server # [ 0.579082] thermal_sys: Registered thermal governor 'power_allocator' server # [ 0.580355] cpuidle: using governor ladder server # [ 0.580855] cpuidle: using governor menu server # [ 0.588189] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. server # [ 0.589219] ASID allocator initialised with 65536 entries server # [ 0.611628] Serial: AMBA PL011 UART driver server # [ 0.769342] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 server # [ 0.774237] printk: console [ttyAMA0] enabled server # [ 0.895229] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages server # [ 0.895574] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page server # [ 0.895699] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages server # [ 0.895795] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page server # [ 0.895888] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages server # [ 0.895976] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page server # [ 0.896067] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages server # [ 0.896202] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page server # [ 0.960770] fbcon: Taking over console server # [ 0.961550] ACPI: Interpreter disabled. server # [ 1.008768] iommu: Default domain type: Translated server # [ 1.009091] iommu: DMA domain TLB invalidation policy: strict mode server # [ 1.017227] SCSI subsystem initialized server # [ 1.042471] usbcore: registered new interface driver usbfs server # [ 1.043394] usbcore: registered new interface driver hub server # [ 1.044071] usbcore: registered new device driver usb server # [ 1.052225] pps_core: LinuxPPS API ver. 1 registered server # [ 1.052540] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti server # [ 1.052816] PTP clock support registered server # [ 1.062599] EDAC MC: Ver: 3.0.0 server # [ 1.067513] scmi_core: SCMI protocol bus registered server # [ 1.085207] FPGA manager framework server # [ 1.144285] vgaarb: loaded server # [ 1.161340] clocksource: Switched to clocksource arch_sys_counter server # [ 1.194507] VFS: Disk quotas dquot_6.6.0 server # [ 1.195245] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) server # [ 1.207916] netfs: FS-Cache loaded server # [ 1.211827] pnp: PnP ACPI: disabled server # [ 1.358398] NET: Registered PF_INET protocol family server # [ 1.360658] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) server # [ 1.760598] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 1.762164] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) server # [ 1.762648] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) server # [ 1.763305] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) server # [ 1.763786] TCP: Hash tables configured (established 8192 bind 8192) server # [ 1.768623] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) server # [ 1.770480] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 1.771191] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 1.775425] NET: Registered PF_UNIX/PF_LOCAL protocol family server # [ 1.779018] NET: Registered PF_XDP protocol family server # [ 1.779650] PCI: CLS 0 bytes, default 64 server # [ 1.790682] Trying to unpack rootfs image as initramfs... server # [ 1.795966] kvm [1]: HYP mode not available server # [ 2.543807] Initialise system trusted keyrings server # [ 2.563074] workingset: timestamp_bits=42 max_order=18 bucket_order=0 server # [ 2.619054] squashfs: version 4.0 (2009/01/31) Phillip Lougher server # [ 2.629908] 9p: Installing v9fs 9p2000 file system support server # [ 3.111801] Key type asymmetric registered server # [ 3.125700] Asymmetric key parser 'x509' registered server # [ 3.128702] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) server # [ 3.141601] io scheduler mq-deadline registered server # [ 3.142034] io scheduler kyber registered server # [ 3.395615] pl061_gpio 9030000.pl061: PL061 GPIO chip registered server # [ 3.438296] ledtrig-cpu: registered to indicate activity on CPUs server # [ 3.472264] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: server # [ 3.486943] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 server # [ 3.488561] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 server # [ 3.490643] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 server # [ 3.492217] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits server # [ 3.503177] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] server # [ 3.510579] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 server # [ 3.512348] pci_bus 0000:00: root bus resource [bus 00-ff] server # [ 3.523283] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] server # [ 3.523703] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] server # [ 3.523832] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] server # [ 3.528608] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint server # [ 3.556812] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 3.570256] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] server # [ 3.572696] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.575351] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.576090] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] server # [ 3.589626] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 3.590501] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] server # [ 3.590976] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.591265] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.606992] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 3.607475] pci 0000:00:03.0: BAR 0 [io 0x0000-0x001f] server # [ 3.607782] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.607923] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.612487] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 3.615018] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] server # [ 3.615463] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.615753] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.630153] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 3.630979] pci 0000:00:05.0: BAR 0 [io 0x0000-0x007f] server # [ 3.631333] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.631931] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.638349] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 3.639019] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] server # [ 3.639476] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.639632] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.654952] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 3.656177] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] server # [ 3.656512] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.660195] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.660734] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] server # [ 3.664797] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint server # [ 3.666039] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.666279] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.668016] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint server # [ 3.668354] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.668494] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.678822] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint server # [ 3.679568] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] server # [ 3.683191] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint server # [ 3.683698] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] server # [ 3.683857] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.683975] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.695195] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 3.695908] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] server # [ 3.696086] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 3.696402] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 3.710665] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned server # [ 3.711346] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned server # [ 3.711673] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned server # [ 3.712072] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned server # [ 3.712336] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned server # [ 3.712514] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned server # [ 3.712679] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned server # [ 3.712850] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned server # [ 3.721643] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned server # [ 3.721968] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned server # [ 3.722159] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned server # [ 3.722327] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned server # [ 3.722580] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned server # [ 3.722828] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned server # [ 3.722990] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned server # [ 3.723173] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned server # [ 3.723341] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned server # [ 3.723478] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned server # [ 3.723608] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned server # [ 3.723736] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned server # [ 3.723868] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned server # [ 3.724007] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned server # [ 3.724164] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned server # [ 3.724306] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned server # [ 3.724442] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned server # [ 3.724616] pci 0000:00:05.0: BAR 0 [io 0x1000-0x107f]: assigned server # [ 3.724844] pci 0000:00:06.0: BAR 0 [io 0x1080-0x10ff]: assigned server # [ 3.741925] pci 0000:00:0b.0: BAR 0 [io 0x1100-0x113f]: assigned server # [ 3.742229] pci 0000:00:01.0: BAR 0 [io 0x1140-0x115f]: assigned server # [ 3.742375] pci 0000:00:02.0: BAR 0 [io 0x1160-0x117f]: assigned server # [ 3.742508] pci 0000:00:03.0: BAR 0 [io 0x1180-0x119f]: assigned server # [ 3.742639] pci 0000:00:04.0: BAR 0 [io 0x11a0-0x11bf]: assigned server # [ 3.742768] pci 0000:00:07.0: BAR 0 [io 0x11c0-0x11df]: assigned server # [ 3.742896] pci 0000:00:0c.0: BAR 0 [io 0x11e0-0x11ff]: assigned server # [ 3.743673] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] server # [ 3.743878] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] server # [ 3.743991] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] server # [ 3.747633] pci 0000:00:0a.0: enabling device (0000 -> 0002) server # [ 3.762261] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa60 took 14915 usecs server # [ 4.218738] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) server # [ 4.236606] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) server # [ 4.248472] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) server # [ 4.263263] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) server # [ 4.276162] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) server # [ 4.288580] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) server # [ 4.294333] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) server # [ 4.309912] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) server # [ 4.323505] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) server # [ 4.332910] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) server # [ 4.344832] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) server # [ 4.488216] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled server # [ 4.566013] msm_serial: driver initialized server # [ 4.568851] SuperH (H)SCI(F) driver initialized server # [ 4.579385] STM32 USART driver initialized server # [ 4.865858] loop: module loaded server # [ 4.867438] virtio_blk virtio4: 1/0/0 default/read/poll queues server # [ 4.884010] virtio_blk virtio4: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) server # [ 4.934001] virtio_blk virtio5: 1/0/0 default/read/poll queues server # [ 4.954414] virtio_blk virtio5: [vdb] 2919560 512-byte logical blocks (1.49 GB/1.39 GiB) server # [ 4.986330] megasas: 07.734.00.00-rc1 server # [ 5.018771] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] server # [ 5.030919] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 server # [ 5.032314] Intel/Sharp Extended Query Table at 0x0031 server # [ 5.035961] Using buffer write method server # [ 5.045989] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] server # [ 5.048466] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 server # [ 5.048841] Intel/Sharp Extended Query Table at 0x0031 server # [ 5.051146] Using buffer write method server # [ 5.051632] Concatenating MTD devices: server # [ 5.051801] (0): "0.flash" server # [ 5.051893] (1): "0.flash" server # [ 5.051973] into device "0.flash" server # [ 6.697300] Freeing initrd memory: 25572K server # [ 6.977971] tun: Universal TUN/TAP device driver, 1.6 server # [ 7.016058] thunder_xcv, ver 1.0 server # [ 7.016838] thunder_bgx, ver 1.0 server # [ 7.018909] nicpf, ver 1.0 server # [ 7.030517] e1000: Intel(R) PRO/1000 Network Driver server # [ 7.030858] e1000: Copyright (c) 1999-2006 Intel Corporation. server # [ 7.031526] e1000e: Intel(R) PRO/1000 Network Driver server # [ 7.031735] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. server # [ 7.032209] igb: Intel(R) Gigabit Ethernet Network Driver server # [ 7.032367] igb: Copyright (c) 2007-2014 Intel Corporation. server # [ 7.032872] igbvf: Intel(R) Gigabit Virtual Function Network Driver server # [ 7.035517] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. server # [ 7.039452] sky2: driver version 1.30 server # [ 7.065663] ehci-pci 0000:00:0a.0: EHCI Host Controller server # [ 7.066921] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 server # [ 7.080108] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 server # [ 7.083786] usbcore: registered new interface driver usb-storage server # [ 7.086415] usbcore: registered new interface driver usbserial_generic server # [ 7.087225] usbserial: USB Serial support registered for generic server # [ 7.090680] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 server # [ 7.120720] hv_vmbus: registering driver hyperv_keyboard server # [ 7.129366] hub 1-0:1.0: USB hub found server # [ 7.137735] hub 1-0:1.0: 6 ports detected server # [ 7.159625] rtc-pl031 9010000.pl031: registered as rtc0 server # [ 7.162012] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T22:32:27 UTC (1791498747) server # [ 7.170249] i2c_dev: i2c /dev entries driver server # [ 7.220232] sdhci: Secure Digital Host Controller Interface driver server # [ 7.220576] sdhci: Copyright(c) Pierre Ossman server # [ 7.232313] Synopsys Designware Multimedia Card Interface Driver server # [ 7.248570] sdhci-pltfm: SDHCI platform and OF driver helper server # [ 7.303540] hid: raw HID events driver (C) Jiri Kosina server # [ 7.310639] usbcore: registered new interface driver usbhid server # [ 7.310977] usbhid: USB HID core driver server # [ 7.333493] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available server # [ 7.359940] drop_monitor: Initializing network drop monitor service server # [ 7.366417] NET: Registered PF_INET6 protocol family server # [ 7.388270] Segment Routing with IPv6 server # [ 7.390436] In-situ OAM (IOAM) with IPv6 server # [ 7.391916] NET: Registered PF_PACKET protocol family server # [ 7.395485] 9pnet: Installing 9P2000 support server # [ 7.405725] Key type dns_resolver registered server # [ 7.418143] usb 1-1: new high-speed USB device number 2 using ehci-pci server # [ 7.527740] registered taskstats version 1 server # [ 7.534593] Loading compiled-in X.509 certificates server # [ 7.664781] 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/input0 server # [ 7.759301] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 server # [ 7.775593] Demotion targets for Node 0: null server # [ 7.779182] Key type .fscrypt registered server # [ 7.779537] Key type fscrypt-provisioning registered server # [ 7.786677] ima: No TPM chip found, activating TPM-bypass! server # [ 7.787159] ima: Allocated hash algorithm: sha1 server # [ 7.791576] ima: No architecture policies found server # [ 7.810885] input: gpio-keys as /devices/platform/gpio-keys/input/input1 server # [ 7.893713] usb 1-2: new high-speed USB device number 3 using ehci-pci server # [ 7.934875] clk: Disabling unused clocks server # [ 7.935548] PM: genpd: Disabling unused power domains server # [ 8.180573] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 server # [ 8.185374] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 server # [ 8.619650] Freeing unused kernel memory: 4736K server # [ 8.623017] Run /init as init process server # [ 8.971162] systemd[1]: Successfully made /usr/ read-only. server # [ 9.361280] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 9.370303] systemd[1]: Detected virtualization qemu. server # [ 9.372827] systemd[1]: Detected architecture arm64. server # [ 9.374720] systemd[1]: Running in initrd. server # [ 9.398724] systemd[1]: Initializing machine ID from random generator. server # [ 9.410482] systemd[1]: Hostname set to . server # [ 11.825811] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 11.950490] systemd[1]: Created slice Slice /system/modprobe. server # [ 11.964640] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 11.968857] systemd[1]: Expecting device /dev/disk/by-label/nix-store... server # [ 11.971460] systemd[1]: Expecting device /dev/disk/by-label/nixos... server # [ 11.974175] systemd[1]: Reached target Path Units. server # [ 11.976319] systemd[1]: Reached target Slice Units. server # [ 11.978697] systemd[1]: Reached target Swaps. server # [ 11.980716] systemd[1]: Reached target Timer Units. server # [ 11.991925] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 12.000479] systemd[1]: Listening on Journal Socket (/dev/log). server # [ 12.006777] systemd[1]: Listening on Journal Sockets. server # [ 12.012742] systemd[1]: Listening on udev Control Socket. server # [ 12.018454] systemd[1]: Listening on udev Kernel Socket. server # [ 12.020476] systemd[1]: Reached target Socket Units. server # [ 12.093259] systemd[1]: Starting Create List of Static Device Nodes... server # [ 12.179462] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 12.183598] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 12.307517] systemd[1]: Mounting Kernel Configuration File System... server # [ 12.844408] systemd[1]: Starting Journal Service... server # [ 13.100195] systemd[1]: Starting Load Kernel Modules... server # [ 13.107495] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 13.274723] systemd[1]: Starting Coldplug All udev Devices... server # [ 13.615901] systemd[1]: Finished Create List of Static Device Nodes. server # [ 13.928573] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 13.995663] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 14.108666] systemd[1]: Mounted Kernel Configuration File System. server # [ 14.304138] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 14.878765] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. server # [ 14.903207] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev server # [ 15.250947] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 server # [ 15.277947] [drm] features: -virgl +edid -resource_blob -host_visible server # [ 15.278281] [drm] features: -context_init server # [ 15.300461] [drm] number of scanouts: 1 server # [ 15.309759] [drm] number of cap sets: 0 server # [ 15.418971] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic server # [ 15.419508] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 server # [ 15.459586] systemd-journald[74]: Collecting audit messages is disabled. server # [ 15.695785] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 15.883541] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 15.896305] Console: switching to colour frame buffer device 160x50 server # [ 15.973221] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device server # [ 16.167336] systemd[1]: Finished Load Kernel Modules. server # [ 16.432143] systemd[1]: Starting Apply Kernel Variables... server # [ 17.432119] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 17.502658] systemd[1]: Reached target Preparation for Local File Systems. server # [ 17.508415] systemd[1]: Reached target Local File Systems. server # [ 17.720602] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 17.907592] systemd[1]: Finished Apply Kernel Variables. server # [ 22.545799] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 22.783985] systemd[1]: Started Journal Service. server # [ 22.713270] systemd-modules-load[75]: Inserted module 'dm_mod' server # [ 22.782812] systemd-modules-load[75]: Module 'virtio_balloon' is built in server # [ 22.849997] systemd-modules-load[75]: Module 'virtio_console' is built in server # [ 22.905804] systemd-modules-load[75]: Inserted module 'virtio_gpu' server # [ 22.945968] systemd-modules-load[75]: Module 'virtio_rng' is built in server # [ 23.035248] systemd-udevd[81]: Using default interface naming scheme 'v260'. server # [ 23.131799] systemd[1]: Starting Create System Files and Directories... server # [ 24.619989] systemd[1]: Finished Create System Files and Directories. server # [ 25.887874] systemd[1]: Starting Virtual Console Setup... server # [ 28.415750] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 28.566883] systemd[1]: Finished Virtual Console Setup. server # [ 60.999126] systemd[1]: Finished Coldplug All udev Devices. server # [ 61.041853] systemd[1]: Reached target System Initialization. server # [ 61.050546] systemd[1]: Reached target Basic System. server # [ 76.591365] (udev-worker)[140]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 76.606138] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line. server # [ 76.639630] (udev-worker)[143]: Network interface NamePolicy= disabled on kernel command line. server # [ 78.299841] systemd[1]: Found device /dev/disk/by-label/nix-store. server # [ 78.627218] systemd[1]: Found device /dev/disk/by-label/nixos. server # [ 78.639503] systemd[1]: Reached target Initrd Root Device. server # [ 78.717877] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... server # [ 80.110671] systemd-fsck[161]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 80.243388] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. server # [ 80.327892] systemd[1]: Mounting /sysroot... server # [ 81.307502] EXT4-fs (vda): mounted filesystem dbc17c46-8b04-4a5b-a960-c9035675691b r/w with ordered data mode. Quota mode: none. server # [ 81.359174] systemd[1]: Mounted /sysroot. server # [ 81.395845] systemd[1]: Reached target Initrd Root File System. server # [ 81.575472] systemd[1]: Mounting /sysroot/nix/.ro-store... server # [ 81.744763] systemd[1]: Mounting /sysroot/nix/.rw-store... server # [ 81.991505] systemd[1]: Mounting /sysroot/run... server # [ 82.330716] systemd[1]: Mounting /sysroot/tmp/shared... server # [ 82.794213] systemd[1]: Mounting /sysroot/tmp/xchg... server # [ 83.217802] systemd[1]: Starting Mountpoints Configured in the Real Root... server # [ 84.184098] erofs (device vdb): mounted with root inode @ nid 36. server # [ 84.798826] systemd-sysroot-fstab-check[184]: /sysroot should be mounted in the initrd, will request daemon-reload. server # [ 84.963832] systemd[1]: Mounted /sysroot/nix/.ro-store. server # [ 84.972001] systemd[1]: Mounted /sysroot/nix/.rw-store. server # [ 84.983110] systemd[1]: Mounted /sysroot/run. server # [ 85.001775] systemd[1]: Mounted /sysroot/tmp/shared. server # [ 85.017510] systemd[1]: Mounted /sysroot/tmp/xchg. server # [ 85.177187] systemd[1]: Reload requested from client PID 184 ('systemd-sysroot') (unit initrd-parse-etc.service)... server # [ 85.183533] systemd[1]: Reloading... server # [ 88.843521] systemd[1]: Reloading finished in 3625 ms. server # [ 89.087200] systemd-sysroot-fstab-check[184]: Requesting initrd-fs.target/start/replace... server # [ 89.149255] systemd-sysroot-fstab-check[184]: Requesting swap.target/start/replace... server # [ 89.231622] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 89.260929] systemd[1]: initrd-parse-etc.service: Deactivated successfully. server # [ 89.290763] systemd[1]: Finished Mountpoints Configured in the Real Root. server # [ 89.298438] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. server # [ 89.783586] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 89.799998] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 89.898704] systemd[1]: Mounting /sysroot/nix/store... server # [ 90.815093] systemd[1]: Mounted /sysroot/nix/store. server # [ 90.861426] systemd[1]: Reached target Initrd File Systems. server # [ 90.951012] systemd[1]: Starting Find NixOS closure... server # [ 91.046951] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... server # [ 92.169731] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. server # [ 92.279691] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. server # [ 92.757715] systemd[1]: Finished Find NixOS closure. server # [ 92.787618] systemd[1]: Reached target Initrd Default Target. server # [ 92.877239] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... server # [ 93.754908] systemd[1]: Stopped target Initrd Default Target. server # [ 93.767528] systemd[1]: Stopped target Basic System. server # [ 93.782025] systemd[1]: Stopped target Initrd Root Device. server # [ 93.799348] systemd[1]: Stopped target Path Units. server # [ 93.814811] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. server # [ 93.847164] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. server # [ 93.887949] systemd[1]: Stopped target Slice Units. server # [ 93.902158] systemd[1]: Stopped target Socket Units. server # [ 93.930635] systemd[1]: Stopped target System Initialization. server # [ 93.951060] systemd[1]: Stopped target Swaps. server # [ 93.975828] systemd[1]: Stopped target Timer Units. server # [ 93.990498] systemd[1]: dbus.socket: Deactivated successfully. server # [ 94.007460] systemd[1]: Closed D-Bus System Message Bus Socket. server # [ 94.033397] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. server # [ 94.054903] systemd[1]: Stopped Find NixOS closure. server # [ 94.177888] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 94.354937] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 94.370921] systemd[1]: systemd-sysctl.service: Deactivated successfully. server # [ 94.410519] systemd[1]: Stopped Apply Kernel Variables. server # [ 94.465596] systemd[1]: systemd-modules-load.service: Deactivated successfully. server # [ 94.515509] systemd[1]: Stopped Load Kernel Modules. server # [ 94.561452] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. server # [ 94.621608] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. server # [ 94.668022] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. server # [ 94.725079] systemd[1]: Stopped Create System Files and Directories. server # [ 94.783600] systemd[1]: Stopped target Local File Systems. server # [ 94.811138] systemd[1]: Stopped target Preparation for Local File Systems. server # [ 94.841666] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. server # [ 94.869150] systemd[1]: Stopped Coldplug All udev Devices. server # [ 94.902502] systemd[1]: systemd-udev-trigger.service: Consumed 23.915s CPU time over 1min 21.739s wall clock time, 9.2M memory peak. server # [ 94.934528] systemd[1]: Stopping Rule-based Manager for Device Events and Files... server # [ 94.950503] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 94.990899] systemd[1]: Stopped Virtual Console Setup. server # [ 95.261449] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 95.278039] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 95.313580] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 95.323634] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 95.460007] systemd[1]: initrd-cleanup.service: Deactivated successfully. server # [ 95.478540] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. server # [ 96.500931] systemd[1]: systemd-udevd.service: Deactivated successfully. server # [ 96.518017] systemd[1]: Stopped Rule-based Manager for Device Events and Files. server # [ 96.528890] systemd[1]: systemd-udevd.service: Consumed 27.859s CPU time over 1min 18.927s wall clock time, 19.3M memory peak. server # [ 96.548912] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. server # [ 96.557547] systemd[1]: Closed udev Control Socket. server # [ 96.599804] systemd[1]: Starting Cleanup udev Database... server # [ 96.607705] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. server # [ 96.619390] systemd[1]: Stopped Create Static Device Nodes in /dev. server # [ 96.627963] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. server # [ 96.638471] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. server # [ 96.649858] systemd[1]: kmod-static-nodes.service: Deactivated successfully. server # [ 96.659895] systemd[1]: Stopped Create List of Static Device Nodes. server # [ 97.091490] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. server # [ 97.107932] systemd[1]: Finished Cleanup udev Database. server # [ 97.127775] systemd[1]: Reached target Switch Root. server # [ 97.175168] systemd[1]: Starting NixOS Activation... server # [ 99.036207] initrd-nixos-activation-start[240]: booting system configuration /nix/store/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test server # [ 99.801526] initrd-nixos-activation-start[240]: running activation script... server # [ 106.131800] initrd-nixos-activation-start[263]: setting up /etc... server # [ 108.817903] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. server # [ 108.827729] systemd[1]: Finished NixOS Activation. server # [ 108.839968] systemd[1]: initrd-nixos-activation.service: Consumed 9.211s CPU time over 11.632s wall clock time, 36.6M memory peak. server # [ 108.883278] systemd[1]: Starting Switch Root... server # [ 109.220609] systemd[1]: Switching root. server # [ 109.682260] systemd-journald[74]: Received SIGTERM from PID 1 (systemd). server # [ 111.826257] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 111.831661] systemd[1]: Detected virtualization qemu. server # [ 111.835717] systemd[1]: Detected architecture arm64. server # [ 111.842230] systemd[1]: Detected first boot. server # [ 111.866835] systemd[1]: Initializing machine ID from random generator. server # [ 115.041988] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 117.297147] systemd[1]: Applying preset policy. server # [ 123.243207] systemd[1]: Populated /etc with preset unit settings. server # [ 127.946376] systemd[1]: initrd-switch-root.service: Deactivated successfully. server # [ 127.966433] systemd[1]: Stopped initrd-switch-root.service. server # [ 128.050897] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. server # [ 128.091673] systemd[1]: Created slice Slice /system/getty. server # [ 128.121943] systemd[1]: Created slice User and Session Slice. server # [ 128.136622] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 128.149619] systemd[1]: Started Forward Password Requests to Wall Directory Watch. server # [ 128.160218] systemd[1]: Expecting device /dev/hvc0... server # [ 128.168872] systemd[1]: Expecting device /dev/ttyAMA0... server # [ 128.178245] systemd[1]: Reached target Local Encrypted Volumes. server # [ 128.187486] systemd[1]: Stopped target initrd-fs.target. server # [ 128.197727] systemd[1]: Stopped target initrd-root-fs.target. server # [ 128.207053] systemd[1]: Stopped target initrd-switch-root.target. server # [ 128.219191] systemd[1]: Reached target Virtual Machines and Containers. server # [ 128.228625] systemd[1]: Reached target Path Units. server # [ 128.238126] systemd[1]: Reached target Remote File Systems. server # [ 128.246987] systemd[1]: Reached target Slice Units. server # [ 128.256185] systemd[1]: Reached target Swaps. server # [ 128.300039] systemd[1]: Listening on Query the User Interactively for a Password. server # [ 128.347171] systemd[1]: Listening on Process Core Dump Socket. server # [ 128.383512] systemd[1]: Listening on Credential Encryption/Decryption. server # [ 128.431284] systemd[1]: Listening on Factory Reset Management. server # [ 128.533775] systemd[1]: Starting Journal Log Access Socket... server # [ 128.579206] systemd[1]: Listening on Journal Audit Socket. server # [ 128.639334] systemd[1]: Listening on Console Output Muting Service Socket. server # [ 128.655806] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. server # [ 128.666236] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki server # [ 128.676411] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki server # [ 128.755931] systemd[1]: Listening on Disk Repartitioning Service Socket. server # [ 128.769345] systemd[1]: Listening on udev Control Socket. server # [ 128.782622] systemd[1]: Listening on udev Varlink Socket. server # [ 128.864639] systemd[1]: Mounting Huge Pages File System... server # [ 128.963259] systemd[1]: Mounting POSIX Message Queue File System... server # [ 129.119205] systemd[1]: Mounting Kernel Debug File System... server # [ 129.371469] systemd[1]: Mounting Kernel Trace File System... server # [ 129.699522] systemd[1]: Starting Create List of Static Device Nodes... server # [ 130.020025] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 130.083760] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 130.483438] systemd[1]: Mounting Kernel Configuration File System... server # [ 130.590883] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm server # [ 130.655344] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 130.927619] systemd[1]: Starting Load Kernel Module fuse... server # [ 130.967763] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 131.311641] systemd[1]: Starting Journal Service... server # [ 131.693716] systemd[1]: Starting Load Kernel Modules... server # [ 132.034016] fuse: init (API version 7.45) server # [ 132.123342] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... server # [ 132.319549] systemd[1]: Starting Remount Root and Kernel File Systems... server # [ 132.383122] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 132.867221] systemd[1]: Starting Coldplug All udev Devices... server # [ 133.436163] systemd-journald[334]: Collecting audit messages is enabled. server # [ 133.845146] systemd[1]: Listening on Journal Log Access Socket. server # [ 133.926950] systemd[1]: Mounted Huge Pages File System. server # [ 134.002031] systemd[1]: Mounted POSIX Message Queue File System. server # [ 134.048423] systemd[1]: Mounted Kernel Debug File System. server # [ 134.138367] systemd[1]: Mounted Kernel Trace File System. server # [ 134.245393] systemd[1]: Finished Create List of Static Device Nodes. server # [ 134.343914] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 134.451266] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 134.514364] EXT4-fs (vda): re-mounted dbc17c46-8b04-4a5b-a960-c9035675691b. server # [ 134.527797] systemd[1]: Mounted Kernel Configuration File System. server # [ 134.540027] systemd[1]: Queued start job for default target Multi-User System. server # [ 134.602922] systemd[1]: Unnecessary job was removed for /dev/vdb. server # [ 134.651729] systemd[1]: systemd-journald.service: Deactivated successfully. server # [ 134.687984] systemd[1]: systemd-journald.service: Consumed 3.334s CPU time over 1min 54.988s wall clock time, 3.9M memory peak. server # [ 134.882972] systemd[1]: Started Journal Service. server # [ 134.790044] systemd-modules-load[335]: Module 'atkbd' is built in server # [ 134.838947] systemd-modules-load[335]: Module 'loop' is built in server # [ 134.911215] systemd[1]: modprobe@fuse.service: Deactivated successfully. server # [ 134.999400] systemd[1]: Finished Load Kernel Module fuse. server # [ 135.101581] systemd[1]: Finished Load Kernel Modules. server # [ 135.197845] systemd[1]: Finished Remount Root and Kernel File Systems. server # [ 135.907940] systemd[1]: Listening on Disk Image Download Service Socket. server # [ 136.053216] systemd[1]: Mounting FUSE Control File System... server # [ 136.323699] systemd[1]: Starting Firewall... server # [ 136.709773] systemd[1]: Starting Flush Journal to Persistent Storage... server # [ 136.745613] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore server # [ 137.186552] systemd-oomd[336]: No swap; memory pressure usage will be degraded server # [ 137.356045] systemd[1]: Starting Load/Save OS Random Seed... server # [ 137.821196] systemd[1]: Starting Apply Kernel Variables... server # [ 138.559497] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 138.606484] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 139.143886] systemd-journald[334]: Received client request to flush runtime journal. server # [ 141.862550] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. server # [ 141.939280] systemd[1]: Mounted FUSE Control File System. server # [ 142.057597] systemd[1]: Finished Load/Save OS Random Seed. server # [ 142.106627] systemd[1]: Reached target First Boot Complete. server # [ 142.123078] systemd[1]: Finished Flush Journal to Persistent Storage. server # [ 142.394033] systemd[1]: Finished Apply Kernel Variables. server # [ 143.122565] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 143.370780] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 145.223635] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 145.373450] systemd[1]: Reached target Preparation for Local File Systems. server # [ 145.553533] systemd[1]: Mounting /run/wrappers... server # [ 145.833944] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 147.255378] systemd[1]: Mounted /run/wrappers. server # [ 147.341934] systemd[1]: Reached target Local File Systems. server # [ 147.483492] systemd[1]: Listening on Boot Loader Control Service Socket. server # [ 147.697655] systemd[1]: Starting register-nix-paths.service... server # [ 148.093393] systemd[1]: Starting Create SUID/SGID Wrappers... server # [ 148.103159] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 148.729934] systemd[1]: Starting Save Transient machine-id to Disk... server # [ 149.401361] systemd[1]: Starting Create System Files and Directories... server # [ 152.535958] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. server # [ 152.674197] systemd[1]: Finished Save Transient machine-id to Disk. server # [ 156.955501] systemd[1]: Finished Create System Files and Directories. server # [ 157.589361] systemd[1]: Starting Rebuild Journal Catalog... server # [ 158.253612] systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [ 161.849553] systemd[1]: Finished Record System Boot/Shutdown in UTMP. server # [ 164.687671] systemd[1]: Finished Rebuild Journal Catalog. server # [ 165.057460] systemd[1]: Starting Update is Completed... server # [ 167.253850] systemd[1]: Finished Update is Completed. server # [ 169.883744] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. server # [ 169.969211] systemd[1]: Finished Create SUID/SGID Wrappers. server # [ 169.983893] systemd[1]: suid-sgid-wrappers.service: Consumed 2.880s CPU time over 21.796s wall clock time, 5M memory peak, 1.9M read from disk. server # [ 174.676021] systemd[1]: Finished Firewall. server # [ 176.515788] systemd[1]: Finished register-nix-paths.service. server # [ 182.282084] systemd-udevd[391]: Using default interface naming scheme 'v260'. server # [ 184.434505] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 210.653648] systemd[1]: Finished Coldplug All udev Devices. server # [ 210.693729] systemd[1]: Reached target System Initialization. server # [ 210.733683] systemd[1]: Started Discard unused filesystem blocks once a week. server # [ 210.746854] systemd[1]: Started postgresqlBackup-horae.timer. server # [ 210.767811] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 210.779622] systemd[1]: Reached target Timer Units. server # [ 210.800063] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 210.814155] systemd[1]: Listening on Nix Daemon Socket. server # [ 210.839762] systemd[1]: Listening on Hostname Service Socket. server # [ 210.869919] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. server # [ 210.883987] systemd[1]: Reached target Socket Units. server # [ 210.906631] systemd[1]: Reached target Basic System. server # [ 210.988945] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 211.259360] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 211.500771] systemd[1]: Starting Post-Boot Actions... server # [ 211.947113] systemd[1]: Started Reset console on configuration changes. server # [ 212.375982] systemd[1]: Starting resolvconf update... server # [ 213.070854] systemd[1]: Started test-console-diagnostics.service. server # [ 214.163231] nsncd[592]: Oct 08 22:35:54.542 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 214.262566] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 214.442800] systemd[1]: Finished Post-Boot Actions. server # [ 215.289782] systemd[1]: Reached target Host and Network Name Lookups. server # [ 215.307419] systemd[1]: Reached target User and Group Name Lookups. server # [ 215.629166] systemd[1]: Starting D-Bus System Message Bus... server # [ 216.571701] systemd[1]: Starting User Login Management... server # [ 216.829060] systemd[1]: Finished Import lastlog data into lastlog2 database. server # [ 218.459591] test-console-diagnostics-start[627]: JOB UNIT TYPE STATE server # [ 218.492393] test-console-diagnostics-start[627]: 280 postgresql-setup.service start waiting server # [ 218.543047] test-console-diagnostics-start[627]: 316 dbus-broker.service start running server # [ 218.559161] test-console-diagnostics-start[627]: 298 linger-users.service start waiting server # [ 218.583195] test-console-diagnostics-start[627]: 243 network-pre.target start waiting server # [ 218.626389] test-console-diagnostics-start[627]: 266 network.target start waiting server # [ 218.650789] test-console-diagnostics-start[627]: 175 multi-user.target start waiting server # [ 218.678852] test-console-diagnostics-start[627]: 297 dev-ttyAMA0.device start running server # [ 218.718365] test-console-diagnostics-start[627]: 279 postgresql.target start waiting server # [ 218.750110] test-console-diagnostics-start[627]: 294 dhcpcd.service start waiting server # [ 218.771240] test-console-diagnostics-start[627]: 291 horae.service start waiting server # [ 218.790288] test-console-diagnostics-start[627]: 273 getty.target start waiting server # [ 218.838366] test-console-diagnostics-start[627]: 283 systemd-logind.service start running server # [ 218.855511] test-console-diagnostics-start[627]: 292 systemd-user-sessions.service start waiting server # [ 218.898266] test-console-diagnostics-start[627]: 278 resolvconf.service start running server # [ 218.919604] test-console-diagnostics-start[627]: 274 getty@tty1.service start waiting server # [ 218.953860] test-console-diagnostics-start[627]: 267 network-local-commands.service start waiting server # [ 218.974546] test-console-diagnostics-start[627]: 281 postgresql.service start waiting server # [ 219.014107] test-console-diagnostics-start[627]: 296 dev-hvc0.device start running server # [ 219.034787] test-console-diagnostics-start[627]: 289 dex.service start waiting server # [ 219.073644] test-console-diagnostics-start[627]: 295 backdoor.service start waiting server # [ 219.115075] test-console-diagnostics-start[627]: 20 jobs listed. server # [ 220.053782] dbus-broker-launch[614]: Looking up NSS user entry for 'systemd-timesync'... server # [ 220.437961] dbus-broker-launch[614]: NSS returned no entry for 'systemd-timesync' server # [ 220.454319] dbus-broker-launch[614]: Invalid user-name in /nix/store/s8i6b1w4ph66lzniglnwcmzrxxzbqy2b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" server # [ 221.202075] systemd[1]: Started D-Bus System Message Bus. server # [ 222.394459] dbus-broker-launch[614]: Ready server # [ 222.790982] systemd-logind[618]: New seat seat0. server # [ 222.899592] test-console-diagnostics-start[639]: Id=backdoor.service server # [ 222.915528] test-console-diagnostics-start[639]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device server # [ 222.946428] test-console-diagnostics-start[639]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket server # [ 222.962213] test-console-diagnostics-start[639]: ActiveState=inactive server # [ 222.979583] test-console-diagnostics-start[639]: SubState=dead server # [ 223.001860] test-console-diagnostics-start[639]: Result=success server # [ 223.018034] test-console-diagnostics-start[639]: Id=dev-hvc0.device server # [ 223.041787] test-console-diagnostics-start[639]: Requires= server # [ 223.053937] test-console-diagnostics-start[639]: After= server # [ 223.063771] test-console-diagnostics-start[639]: ActiveState=activating server # [ 223.098409] test-console-diagnostics-start[639]: SubState=tentative server # [ 223.114126] test-console-diagnostics-start[639]: Id=dev-ttyAMA0.device server # [ 223.129725] test-console-diagnostics-start[639]: Requires= server # [ 223.141485] test-console-diagnostics-start[639]: After= server # [ 223.157999] test-console-diagnostics-start[639]: ActiveState=activating server # [ 223.169971] test-console-diagnostics-start[639]: SubState=tentative server # [ 223.181596] test-console-diagnostics-start[639]: Id=dev-ttyS0.device server # [ 223.193864] test-console-diagnostics-start[639]: Requires= server # [ 223.205795] test-console-diagnostics-start[639]: After= server # [ 223.217433] test-console-diagnostics-start[639]: ActiveState=inactive server # [ 223.229661] test-console-diagnostics-start[639]: SubState=dead server # [ 223.275510] systemd[1]: Started User Login Management. server # [ 223.522516] test-console-diagnostics-start[654]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 223.547755] test-console-diagnostics-start[654]: DEVNAME=/dev/hvc0 server # [ 223.569861] test-console-diagnostics-start[654]: MAJOR=229 server # [ 223.582401] test-console-diagnostics-start[654]: MINOR=0 server # [ 223.601945] test-console-diagnostics-start[654]: SUBSYSTEM=tty server # [ 223.615865] systemd[1]: Stopped target Host and Network Name Lookups. server # [ 223.651763] systemd[1]: Stopping Host and Network Name Lookups... server # [ 223.675521] systemd[1]: Stopped target User and Group Name Lookups. server # [ 223.699425] systemd[1]: Stopping User and Group Name Lookups... server # [ 223.801780] systemd[1]: Starting linger-users.service... server # [ 223.902240] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... server # [ 224.082985] test-console-diagnostics-start[657]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 224.121107] test-console-diagnostics-start[657]: DEVNAME=/dev/ttyAMA0 server # [ 224.129888] test-console-diagnostics-start[657]: MAJOR=204 server # [ 224.143443] test-console-diagnostics-start[657]: MINOR=64 server # [ 224.157732] test-console-diagnostics-start[657]: SUBSYSTEM=tty server # [ 224.239917] systemd[1]: nscd.service: Deactivated successfully. server # [ 224.465713] systemd[1]: Stopped Name Service Cache Daemon (nsncd). server # [ 224.482846] systemd[1]: nscd.service: Consumed 665ms CPU time over 13.015s wall clock time, 7.8M memory peak, 5.9M read from disk. server # [ 224.642245] test-console-diagnostics-start[662]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 224.662247] test-console-diagnostics-start[662]: DEVNAME=/dev/ttyS0 server # [ 224.682525] test-console-diagnostics-start[662]: MAJOR=4 server # [ 224.697797] test-console-diagnostics-start[662]: MINOR=64 server # [ 224.713632] test-console-diagnostics-start[662]: SUBSYSTEM=tty server # [ 225.482237] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 225.883194] systemd[1]: Finished resolvconf update. server # [ 226.001273] systemd[1]: linger-users.service: Deactivated successfully. server # [ 226.059159] systemd[1]: Finished linger-users.service. server # [ 226.314508] systemd[1]: Reached target Preparation for Network. server # [ 226.659064] systemd[1]: Starting DHCP Client... server # [ 226.950082] systemd[1]: Starting Extra networking commands.... server # [ 227.095962] nsncd[675]: Oct 08 22:36:07.527 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 227.225832] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 227.401311] systemd[1]: Reached target Host and Network Name Lookups. server # [ 227.439390] systemd[1]: Reached target User and Group Name Lookups. server # [ 233.155515] systemd[1]: Finished Extra networking commands.. server # [ 233.238573] systemd[1]: Reached target Network. server # [ 233.438009] systemd[1]: Starting dex identity provider... server # [ 233.526986] dhcpcd[698]: dhcpcd-10.3.2 starting server # [ 233.663999] dhcpcd[749]: dev: loaded udev server # [ 233.830828] dhcpcd[749]: no valid interfaces found server # [ 233.844981] dhcpcd[749]: no valid interfaces found server # [ 233.862517] systemd[1]: Starting PostgreSQL Server... server # [ 234.034334] systemd[1]: Starting Permit User Sessions... server # [ 235.575146] systemd[1]: Finished Permit User Sessions. server # [ 235.850139] systemd[1]: Started Getty on tty1. server # [ 235.947236] systemd[1]: Reached target Login Prompts. server # [ 240.619632] postgresql-pre-start[760]: The files belonging to this database system will be owned by user "postgres". server # [ 240.651442] postgresql-pre-start[760]: This user must also own the server process. server # [ 240.723692] postgresql-pre-start[760]: The database cluster will be initialized with locale "en_US.UTF-8". server # [ 240.746324] postgresql-pre-start[760]: The default database encoding has accordingly been set to "UTF8". server # [ 240.759971] postgresql-pre-start[760]: The default text search configuration will be set to "english". server # [ 240.782655] postgresql-pre-start[760]: Data page checksums are disabled. server # [ 240.813351] postgresql-pre-start[760]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok server # [ 240.917591] postgresql-pre-start[760]: creating subdirectories ... ok server # [ 240.961073] postgresql-pre-start[760]: selecting dynamic shared memory implementation ... posix server # [ 241.043029] systemd[1]: Started dex identity provider. server # [ 243.723082] postgresql-pre-start[760]: selecting default "max_connections" ... 100 server # [ 246.182072] postgresql-pre-start[760]: selecting default "shared_buffers" ... 128MB server # [ 252.934880] dex[766]: time=2026-10-08T22:36:33.319Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=arm64 server # [ 252.966972] dex[766]: time=2026-10-08T22:36:33.375Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex server # [ 253.073422] dex[766]: time=2026-10-08T22:36:33.554Z level=INFO msg="config storage" storage_type=memory server # [ 253.097644] dex[766]: time=2026-10-08T22:36:33.578Z level=INFO msg="config static client" client_name=Horae server # [ 253.110894] dex[766]: time=2026-10-08T22:36:33.591Z level=INFO msg="config connector" connector_id=mock server # [ 253.142844] dex[766]: time=2026-10-08T22:36:33.607Z level=INFO msg="config skipping approval screen" server # [ 253.162337] dex[766]: time=2026-10-08T22:36:33.636Z level=INFO msg="continue on connector failure feature flag enabled" server # [ 253.195962] dex[766]: time=2026-10-08T22:36:33.665Z level=INFO msg="signer configured" type=local keys_rotation_period=6h server # [ 253.221562] dex[766]: time=2026-10-08T22:36:33.667Z level=INFO msg="config refresh tokens rotation" enabled=true server # [ 253.473952] dex[766]: time=2026-10-08T22:36:33.954Z level=ERROR msg="failed to get signing algorithm" err="no signing key found" server # [ 253.673289] dex[766]: time=2026-10-08T22:36:34.158Z level=INFO msg="keys expired, rotating" server # [ 258.845326] dex[766]: time=2026-10-08T22:36:39.248Z level=INFO msg="keys rotated" next_rotation=2026-10-09T04:36:39.247Z server # [ 258.862623] dex[766]: time=2026-10-08T22:36:39.340Z level=INFO msg="listening on" server=http address=127.0.0.1:5556 server # [ 263.950665] dhcpcd[749]: timed out server # [ 263.981782] dhcpcd[749]: timed out server # [ 264.074590] systemd[1]: Started DHCP Client. server # [ 273.995465] systemd-logind[618]: Watching system buttons on /dev/input/event1 (gpio-keys) server # [ 280.127620] postgresql-pre-start[760]: selecting default time zone ... UTC server # [ 280.196506] postgresql-pre-start[760]: creating configuration files ... ok server # [ 285.934269] test-console-diagnostics-start[832]: JOB UNIT TYPE STATE server # [ 285.959889] test-console-diagnostics-start[832]: 280 postgresql-setup.service start waiting server # [ 285.994422] test-console-diagnostics-start[832]: 175 multi-user.target start waiting server # [ 286.006070] test-console-diagnostics-start[832]: 297 dev-ttyAMA0.device start running server # [ 286.021921] test-console-diagnostics-start[832]: 279 postgresql.target start waiting server # [ 286.034134] test-console-diagnostics-start[832]: 291 horae.service start waiting server # [ 286.050048] test-console-diagnostics-start[832]: 281 postgresql.service start running server # [ 286.058240] test-console-diagnostics-start[832]: 296 dev-hvc0.device start running server # [ 286.074031] test-console-diagnostics-start[832]: 295 backdoor.service start waiting server # [ 286.090958] test-console-diagnostics-start[832]: 8 jobs listed. server # [ 287.201536] test-console-diagnostics-start[833]: Id=backdoor.service server # [ 287.216774] test-console-diagnostics-start[833]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device server # [ 287.222035] test-console-diagnostics-start[833]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket server # [ 287.238514] test-console-diagnostics-start[833]: ActiveState=inactive server # [ 287.253957] test-console-diagnostics-start[833]: SubState=dead server # [ 287.271829] test-console-diagnostics-start[833]: Result=success server # [ 287.289262] test-console-diagnostics-start[833]: Id=dev-hvc0.device server # [ 287.301687] test-console-diagnostics-start[833]: Requires= server # [ 287.318036] test-console-diagnostics-start[833]: After= server # [ 287.327542] test-console-diagnostics-start[833]: ActiveState=activating server # [ 287.343625] test-console-diagnostics-start[833]: SubState=tentative server # [ 287.369513] test-console-diagnostics-start[833]: Id=dev-ttyAMA0.device server # [ 287.385897] test-console-diagnostics-start[833]: Requires= server # [ 287.405225] test-console-diagnostics-start[833]: After= server # [ 287.415499] test-console-diagnostics-start[833]: ActiveState=activating server # [ 287.441695] test-console-diagnostics-start[833]: SubState=tentative server # [ 287.466239] test-console-diagnostics-start[833]: Id=dev-ttyS0.device server # [ 287.478582] test-console-diagnostics-start[833]: Requires= server # [ 287.489672] test-console-diagnostics-start[833]: After= server # [ 287.505460] test-console-diagnostics-start[833]: ActiveState=inactive server # [ 287.518850] test-console-diagnostics-start[833]: SubState=dead server # [ 287.747135] test-console-diagnostics-start[834]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 287.774863] test-console-diagnostics-start[834]: DEVNAME=/dev/hvc0 server # [ 287.798352] test-console-diagnostics-start[834]: MAJOR=229 server # [ 287.807078] test-console-diagnostics-start[834]: MINOR=0 server # [ 287.823618] test-console-diagnostics-start[834]: SUBSYSTEM=tty server # [ 288.230554] test-console-diagnostics-start[835]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 288.251892] test-console-diagnostics-start[835]: DEVNAME=/dev/ttyAMA0 server # [ 288.279859] test-console-diagnostics-start[835]: MAJOR=204 server # [ 288.293735] test-console-diagnostics-start[835]: MINOR=64 server # [ 288.314258] test-console-diagnostics-start[835]: SUBSYSTEM=tty server # [ 288.733984] test-console-diagnostics-start[836]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 288.750640] test-console-diagnostics-start[836]: DEVNAME=/dev/ttyS0 server # [ 288.781458] test-console-diagnostics-start[836]: MAJOR=4 server # [ 288.791785] test-console-diagnostics-start[836]: MINOR=64 server # [ 288.809899] test-console-diagnostics-start[836]: SUBSYSTEM=tty server # [ 297.443004] postgresql-pre-start[760]: running bootstrap script ... ok server # [ 299.790040] mousedev: PS/2 mouse device common for all mice server # [ 308.878696] systemd-logind[618]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard) server # [ 340.114373] postgresql-pre-start[760]: performing post-bootstrap initialization ... ok server # [ 349.113924] postgresql-pre-start[760]: syncing data to disk ... ok server # [ 349.130307] postgresql-pre-start[760]: initdb: warning: enabling "trust" authentication for local connections server # [ 349.155011] postgresql-pre-start[760]: 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. server # [ 349.186815] postgresql-pre-start[760]: Success. You can now start the database server using: server # [ 349.214193] postgresql-pre-start[760]: pg_ctl -D /var/lib/postgresql/17 -l logfile start server # [ 350.079936] test-console-diagnostics-start[926]: JOB UNIT TYPE STATE server # [ 350.099616] test-console-diagnostics-start[926]: 280 postgresql-setup.service start waiting server # [ 350.109599] test-console-diagnostics-start[926]: 175 multi-user.target start waiting server # [ 350.122192] test-console-diagnostics-start[926]: 297 dev-ttyAMA0.device start running server # [ 350.130488] test-console-diagnostics-start[926]: 279 postgresql.target start waiting server # [ 350.142928] test-console-diagnostics-start[926]: 291 horae.service start waiting server # [ 350.154595] test-console-diagnostics-start[926]: 281 postgresql.service start running server # [ 350.165770] test-console-diagnostics-start[926]: 296 dev-hvc0.device start running server # [ 350.181970] test-console-diagnostics-start[926]: 295 backdoor.service start waiting server # [ 350.193981] test-console-diagnostics-start[926]: 8 jobs listed. server # [ 351.314914] test-console-diagnostics-start[927]: Id=backdoor.service server # [ 351.330400] test-console-diagnostics-start[927]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device server # [ 351.353867] test-console-diagnostics-start[927]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket server # [ 351.365835] test-console-diagnostics-start[927]: ActiveState=inactive server # [ 351.382083] test-console-diagnostics-start[927]: SubState=dead server # [ 351.390568] test-console-diagnostics-start[927]: Result=success server # [ 351.409765] test-console-diagnostics-start[927]: Id=dev-hvc0.device server # [ 351.421922] test-console-diagnostics-start[927]: Requires= server # [ 351.441917] test-console-diagnostics-start[927]: After= server # [ 351.457942] test-console-diagnostics-start[927]: ActiveState=activating server # [ 351.470107] test-console-diagnostics-start[927]: SubState=tentative server # [ 351.487590] test-console-diagnostics-start[927]: Id=dev-ttyAMA0.device server # [ 351.503124] test-console-diagnostics-start[927]: Requires= server # [ 351.515975] test-console-diagnostics-start[927]: After= server # [ 351.545816] test-console-diagnostics-start[927]: ActiveState=activating server # [ 351.554658] test-console-diagnostics-start[927]: SubState=tentative server # [ 351.570527] test-console-diagnostics-start[927]: Id=dev-ttyS0.device server # [ 351.589831] test-console-diagnostics-start[927]: Requires= server # [ 351.602050] test-console-diagnostics-start[927]: After= server # [ 351.615860] test-console-diagnostics-start[927]: ActiveState=inactive server # [ 351.629611] test-console-diagnostics-start[927]: SubState=dead server # [ 351.834827] test-console-diagnostics-start[931]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 351.865388] test-console-diagnostics-start[931]: DEVNAME=/dev/hvc0 server # [ 351.869719] test-console-diagnostics-start[931]: MAJOR=229 server # [ 351.885773] test-console-diagnostics-start[931]: MINOR=0 server # [ 351.902055] test-console-diagnostics-start[931]: SUBSYSTEM=tty server # [ 352.206889] test-console-diagnostics-start[932]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 352.222848] test-console-diagnostics-start[932]: DEVNAME=/dev/ttyAMA0 server # [ 352.245994] test-console-diagnostics-start[932]: MAJOR=204 server # [ 352.262556] test-console-diagnostics-start[932]: MINOR=64 server # [ 352.273551] test-console-diagnostics-start[932]: SUBSYSTEM=tty server # [ 352.665829] test-console-diagnostics-start[933]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 352.670379] test-console-diagnostics-start[933]: DEVNAME=/dev/ttyS0 server # [ 352.693551] test-console-diagnostics-start[933]: MAJOR=4 server # [ 352.705693] test-console-diagnostics-start[933]: MINOR=64 server # [ 352.717509] test-console-diagnostics-start[933]: SUBSYSTEM=tty server # [ 354.742310] postgres[924]: [924] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit server # [ 354.809295] postgres[924]: [924] LOG: listening on IPv6 address "::1", port 5432 server # [ 354.819094] postgres[924]: [924] LOG: listening on IPv4 address "127.0.0.1", port 5432 server # [ 354.850688] postgres[924]: [924] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" server # [ 355.164512] postgres[937]: [937] LOG: database system was shut down at 2026-10-08 22:38:00 GMT server # [ 355.401720] postgres[924]: [924] LOG: database system is ready to accept connections server # [ 355.530242] systemd[1]: Started PostgreSQL Server. server # [ 355.654313] systemd[1]: Starting PostgreSQL Setup Scripts... server # [ 363.375650] postgresql-setup-start[958]: CREATE DATABASE server # [ 364.713447] postgresql-setup-start[973]: CREATE ROLE server # [ 365.191631] postgresql-setup-start[975]: ALTER ROLE server # [ 365.869229] postgresql-setup-start[977]: ALTER DATABASE server # [ 365.994950] systemd[1]: Finished PostgreSQL Setup Scripts. server # [ 366.046617] systemd[1]: Reached target PostgreSQL. server # [ 366.121300] systemd[1]: Starting Horae time tracking server... server # [ 369.950702] horae[981]: 2026-10-08T22:38:30.379066Z INFO horae: Running migrations... server # [ 372.411482] horae[981]: 2026-10-08T22:38:32.832248Z WARN sqlx::query: slow statement: execution time exceeded alert threshold summary="-- Horae Phase 1 …" db.statement="\n\n-- Horae Phase 1 schema\n-- All IDs are UUID v7 (time-ordered).\n-- Time stored as integer minutes; money as integer cents + ISO 4217 currency.\n\n-- ── Enum types ───────────────────────────────────────────────────────────────\n\nCREATE TYPE org_role AS ENUM ('admin', 'manager', 'member');\nCREATE TYPE project_role AS ENUM ('lead', 'freelancer', 'admin');\nCREATE TYPE project_type AS ENUM ('time_and_materials', 'fixed_fee', 'non_billable', 'retainer');\nCREATE TYPE entry_state AS ENUM ('open', 'submitted', 'approved', 'invoiced');\nCREATE TYPE budget_kind AS ENUM ('none', 'amount', 'hours');\nCREATE TYPE round_dir AS ENUM ('nearest', 'up', 'down');\n\n-- ── Tables ───────────────────────────────────────────────────────────────────\n\nCREATE TABLE organizations (\n id uuid PRIMARY KEY,\n name text NOT NULL,\n default_currency char(3) NOT NULL DEFAULT 'EUR',\n week_start smallint NOT NULL DEFAULT 1, -- 1 = Monday\n round_minutes smallint NOT NULL DEFAULT 0, -- 0 = no rounding\n round_dir round_dir NOT NULL DEFAULT 'nearest',\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE users (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n email text NOT NULL UNIQUE,\n name text NOT NULL,\n oidc_subject text UNIQUE, -- null until first OIDC login\n org_role org_role NOT NULL DEFAULT 'member',\n cost_rate_cents bigint,\n billable_rate_cents bigint,\n active boolean NOT NULL DEFAULT true,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE clients (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n name text NOT NULL,\n currency char(3) NOT NULL,\n address text,\n tax_id text,\n active boolean NOT NULL DEFAULT true,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE projects (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n client_id uuid NOT NULL REFERENCES clients(id),\n code text,\n name text NOT NULL,\n project_type project_type NOT NULL DEFAULT 'time_and_materials',\n currency char(3) NOT NULL,\n starts_on date,\n ends_on date,\n budget_kind budget_kind NOT NULL DEFAULT 'none',\n budget_amount_cents bigint,\n budget_minutes bigint,\n active boolean NOT NULL DEFAULT true,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE tasks ( -- org-level task catalog\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n name text NOT NULL,\n billable_default boolean NOT NULL DEFAULT true,\n default_rate_cents bigint,\n active boolean NOT NULL DEFAULT true\n);\n\nCREATE TABLE project_tasks ( -- task enabled per project + overrides\n project_id uuid NOT NULL REFERENCES projects(id),\n task_id uuid NOT NULL REFERENCES tasks(id),\n billable boolean NOT NULL,\n rate_cents bigint,\n PRIMARY KEY (project_id, task_id)\n);\n\nCREATE TABLE assignments (\n id uuid PRIMARY KEY,\n project_id uuid NOT NULL REFERENCES projects(id),\n user_id uuid NOT NULL REFERENCES users(id),\n role project_role NOT NULL DEFAULT 'freelancer',\n rate_cents bigint,\n created_at timestamptz NOT NULL DEFAULT now(),\n UNIQUE (project_id, user_id)\n);\n\nCREATE TABLE time_entries (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n user_id uuid NOT NULL REFERENCES users(id),\n project_id uuid NOT NULL REFERENCES projects(id),\n task_id uuid NOT NULL REFERENCES tasks(id),\n spent_date date NOT NULL,\n minutes integer NOT NULL DEFAULT 0,\n rounded_minutes integer, -- persisted at lock (submit)\n notes text,\n billable boolean NOT NULL,\n is_running boolean NOT NULL DEFAULT false,\n started_at timestamptz, -- non-null only while running\n state entry_state NOT NULL DEFAULT 'open',\n invoice_id uuid, -- Phase 4\n created_at timestamptz NOT NULL DEFAULT now(),\n updated_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE INDEX ON time_entries (user_id, spent_date);\nCREATE INDEX ON time_entries (project_id, spent_date);\n\n-- Enforces the one-running-timer-per-user invariant at the database level.\nCREATE UNIQUE INDEX one_running_timer_per_user ON time_entries (user_id) WHERE is_running;\n\nCREATE TABLE approvals (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n user_id uuid NOT NULL REFERENCES users(id),\n period_start date NOT NULL,\n period_end date NOT NULL,\n state entry_state NOT NULL DEFAULT 'submitted', -- reject deletes the row\n submitted_at timestamptz NOT NULL DEFAULT now(),\n approved_by uuid REFERENCES users(id),\n approved_at timestamptz,\n UNIQUE (user_id, period_start)\n);\n\nCREATE TABLE audit_log (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL,\n actor_user_id uuid,\n action text NOT NULL, -- e.g. 'entry.submit'\n entity_type text NOT NULL,\n entity_id uuid,\n data jsonb,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\n" rows_affected=0 rows_returned=0 elapsed=1.870090147s elapsed_secs=1.870090147 slow_threshold=1s server # [ 381.515942] horae[981]: 2026-10-08T22:38:41.948000Z WARN sqlx::query: slow statement: execution time exceeded alert threshold summary="-- New creation settings …" db.statement="\n\n-- New creation settings are opt-in. Existing and imported projects retain the\n-- legacy billing cascade when no project_settings row exists.\nALTER TABLE users ADD CONSTRAINT users_id_org_unique UNIQUE (id, org_id);\nALTER TABLE projects ADD CONSTRAINT projects_id_org_unique UNIQUE (id, org_id);\nALTER TABLE tasks ADD CONSTRAINT tasks_id_org_unique UNIQUE (id, org_id);\n\nALTER TABLE clients ADD COLUMN default_rate_cents bigint\n CHECK (default_rate_cents IS NULL OR default_rate_cents >= 0);\n\nCREATE TABLE project_drafts (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n creator_id uuid NOT NULL,\n revision bigint NOT NULL DEFAULT 1 CHECK (revision > 0),\n payload_version smallint NOT NULL DEFAULT 1 CHECK (payload_version = 1),\n payload jsonb NOT NULL CHECK (\n jsonb_typeof(payload) = 'object'\n AND octet_length(payload::text) <= 262144),\n completed_project_id uuid,\n discarded_at timestamptz,\n created_at timestamptz NOT NULL DEFAULT now(),\n updated_at timestamptz NOT NULL DEFAULT now(),\n FOREIGN KEY (creator_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (completed_project_id, org_id) REFERENCES projects(id, org_id),\n CHECK (completed_project_id IS NULL OR discarded_at IS NULL)\n);\n\nCREATE UNIQUE INDEX project_drafts_current_creator_idx\n ON project_drafts (org_id, creator_id)\n WHERE completed_project_id IS NULL AND discarded_at IS NULL;\n\nCREATE TABLE project_settings (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL UNIQUE,\n creator_id uuid NOT NULL,\n rate_mode text NOT NULL CHECK (rate_mode IN ('person', 'task', 'project')),\n budget_scope text NOT NULL DEFAULT 'project'\n CHECK (budget_scope IN ('project', 'task', 'person')),\n monthly_reset boolean NOT NULL DEFAULT false,\n include_nonbillable boolean NOT NULL DEFAULT false,\n alert_enabled boolean NOT NULL DEFAULT false,\n alert_threshold smallint NOT NULL DEFAULT 80 CHECK (alert_threshold BETWEEN 0 AND 100),\n report_visibility text NOT NULL DEFAULT 'managers'\n CHECK (report_visibility IN ('managers', 'project_members')),\n fee_mode text CHECK (fee_mode IN ('single', 'milestones', 'monthly')),\n fee_amount_cents bigint CHECK (fee_amount_cents >= 0),\n monthly_day text CHECK (monthly_day IN ('first', 'fifteenth', 'last')),\n terms_days smallint NOT NULL DEFAULT 30 CHECK (terms_days BETWEEN 0 AND 365),\n po_number text NOT NULL DEFAULT '' CHECK (char_length(po_number) <= 200),\n discount_bps smallint NOT NULL DEFAULT 0 CHECK (discount_bps BETWEEN 0 AND 10000),\n tax1_bps smallint NOT NULL DEFAULT 0 CHECK (tax1_bps BETWEEN 0 AND 10000),\n tax2_name text CHECK (char_length(btrim(tax2_name)) BETWEEN 1 AND 100),\n tax2_bps smallint CHECK (tax2_bps BETWEEN 0 AND 10000),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (creator_id, org_id) REFERENCES users(id, org_id),\n CHECK ((tax2_name IS NULL) = (tax2_bps IS NULL)),\n CHECK (CASE fee_mode\n WHEN 'single' THEN fee_amount_cents IS NOT NULL AND monthly_day IS NULL\n WHEN 'milestones' THEN fee_amount_cents IS NULL AND monthly_day IS NULL\n WHEN 'monthly' THEN fee_amount_cents IS NOT NULL AND monthly_day IS NOT NULL\n ELSE fee_amount_cents IS NULL AND monthly_day IS NULL\n END)\n);\n\n-- Private fields must not inherit table-level SELECT grants on projects or\n-- assignments held by the plugin database role.\nCREATE TABLE project_private_settings (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL UNIQUE,\n admin_notes text NOT NULL DEFAULT '' CHECK (char_length(admin_notes) <= 10000),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_member_costs (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n user_id uuid NOT NULL,\n cost_rate_cents bigint NOT NULL CHECK (cost_rate_cents >= 0),\n UNIQUE (project_id, user_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (user_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (project_id, user_id) REFERENCES assignments(project_id, user_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_tags (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n name text NOT NULL CHECK (char_length(btrim(name)) BETWEEN 1 AND 50),\n UNIQUE (id, org_id)\n);\n\nCREATE UNIQUE INDEX project_tags_name_idx ON project_tags (org_id, lower(btrim(name)));\n\nCREATE TABLE project_tag_links (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n tag_id uuid NOT NULL,\n UNIQUE (project_id, tag_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (tag_id, org_id) REFERENCES project_tags(id, org_id)\n);\n\nCREATE TABLE project_task_settings (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n task_id uuid NOT NULL,\n restricted boolean NOT NULL DEFAULT false,\n budget_minutes bigint CHECK (budget_minutes >= 0),\n budget_cents bigint CHECK (budget_cents >= 0),\n UNIQUE (project_id, task_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (task_id, org_id) REFERENCES tasks(id, org_id),\n FOREIGN KEY (project_id, task_id) REFERENCES project_tasks(project_id, task_id) ON DELETE CASCADE,\n CHECK (budget_minutes IS NULL OR budget_cents IS NULL)\n);\n\nCREATE TABLE project_task_members (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n task_id uuid NOT NULL,\n user_id uuid NOT NULL,\n UNIQUE (project_id, task_id, user_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (task_id, org_id) REFERENCES tasks(id, org_id),\n FOREIGN KEY (user_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (project_id, task_id) REFERENCES project_task_settings(project_id, task_id) ON DELETE CASCADE,\n FOREIGN KEY (project_id, user_id) REFERENCES assignments(project_id, user_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_member_budgets (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n user_id uuid NOT NULL,\n budget_minutes bigint NOT NULL CHECK (budget_minutes >= 0),\n UNIQUE (project_id, user_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (user_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (project_id, user_id) REFERENCES assignments(project_id, user_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_fee_milestones (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n name text NOT NULL CHECK (char_length(btrim(name)) BETWEEN 1 AND 200),\n due_on date NOT NULL,\n amount_cents bigint NOT NULL CHECK (amount_cents >= 0),\n position smallint NOT NULL CHECK (position BETWEEN 0 AND 99),\n UNIQUE (project_id, position),\n UNIQUE (id, org_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE\n);\n\nREVOKE ALL ON project_drafts, project_settings, project_private_settings,\n project_member_costs, project_tags, project_tag_links, project_task_settings,\n project_task_members, project_member_budgets, project_fee_milestones FROM PUBLIC;\n\n" rows_affected=0 rows_returned=0 elapsed=2.90775578s elapsed_secs=2.90775578 slow_threshold=1s server # [ 388.590982] horae[981]: 2026-10-08T22:38:49.010607Z INFO horae: Migrations complete. server # [ 388.843635] systemd[1]: Started Horae time tracking server. server # [ 390.417565] horae[1018]: 2026-10-08T22:38:50.870951Z INFO horae: Starting Horae on 127.0.0.1:3000 server # [ 390.805829] horae[1018]: 2026-10-08T22:38:51.289762Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping server # [ 391.553831] horae[1018]: 2026-10-08T22:38:51.983347Z INFO horae::plugin::registry: plugins directory does not exist: plugins server # [ 392.102800] horae[1018]: 2026-10-08T22:38:52.588954Z INFO dioxus_server::server: Registering: POST /api/logout16144359270796124307 server # [ 392.137388] horae[1018]: 2026-10-08T22:38:52.622776Z INFO dioxus_server::server: Registering: POST /api/get_me16144359270796124307 server # [ 392.155026] horae[1018]: 2026-10-08T22:38:52.630923Z INFO dioxus_server::server: Registering: POST /api/set_user_active13739675588719463496 server # [ 392.175161] horae[1018]: 2026-10-08T22:38:52.632237Z INFO dioxus_server::server: Registering: POST /api/set_user_role13739675588719463496 server # [ 392.198704] horae[1018]: 2026-10-08T22:38:52.647484Z INFO dioxus_server::server: Registering: POST /api/create_user13739675588719463496 server # [ 392.223019] horae[1018]: 2026-10-08T22:38:52.648070Z INFO dioxus_server::server: Registering: POST /api/list_users13739675588719463496 server # [ 392.249920] horae[1018]: 2026-10-08T22:38:52.648545Z INFO dioxus_server::server: Registering: POST /api/approve_submissions13590059188800848654 server # [ 392.258420] horae[1018]: 2026-10-08T22:38:52.670295Z INFO dioxus_server::server: Registering: POST /api/approve_submission13590059188800848654 server # [ 392.278518] horae[1018]: 2026-10-08T22:38:52.671701Z INFO dioxus_server::server: Registering: POST /api/reject_submission13590059188800848654 server # [ 392.299189] horae[1018]: 2026-10-08T22:38:52.672194Z INFO dioxus_server::server: Registering: POST /api/list_approvals13590059188800848654 server # [ 392.325344] horae[1018]: 2026-10-08T22:38:52.672537Z INFO dioxus_server::server: Registering: POST /api/submit_week13590059188800848654 server # [ 392.347129] horae[1018]: 2026-10-08T22:38:52.689802Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status2064041990223864818 server # [ 392.362022] horae[1018]: 2026-10-08T22:38:52.690497Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit2064041990223864818 server # [ 392.374228] horae[1018]: 2026-10-08T22:38:52.690784Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft2064041990223864818 server # [ 392.387474] horae[1018]: 2026-10-08T22:38:52.691130Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor2064041990223864818 server # [ 392.409186] horae[1018]: 2026-10-08T22:38:52.691865Z INFO dioxus_server::server: Registering: POST /api/generate_invoice2064041990223864818 server # [ 392.426081] horae[1018]: 2026-10-08T22:38:52.692130Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice2064041990223864818 server # [ 392.436036] horae[1018]: 2026-10-08T22:38:52.692482Z INFO dioxus_server::server: Registering: POST /api/list_invoices2064041990223864818 server # [ 392.450647] horae[1018]: 2026-10-08T22:38:52.714158Z INFO dioxus_server::server: Registering: POST /api/get_invoice2064041990223864818 server # [ 392.467351] horae[1018]: 2026-10-08T22:38:52.714771Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets7690553569863186185 server # [ 392.485496] horae[1018]: 2026-10-08T22:38:52.715034Z INFO dioxus_server::server: Registering: POST /api/report_detailed7690553569863186185 server # [ 392.554850] horae[1018]: 2026-10-08T22:38:52.715299Z INFO dioxus_server::server: Registering: POST /api/report_time7690553569863186185 server # [ 392.570380] horae[1018]: 2026-10-08T22:38:52.715577Z INFO dioxus_server::server: Registering: POST /api/list_plugins15265546378964521800 server # [ 392.589344] horae[1018]: 2026-10-08T22:38:52.715810Z INFO dioxus_server::server: Registering: POST /api/update_org_branding13047172052397277026 server # [ 392.613626] horae[1018]: 2026-10-08T22:38:52.716032Z INFO dioxus_server::server: Registering: POST /api/get_org_branding13047172052397277026 server # [ 392.627244] horae[1018]: 2026-10-08T22:38:52.716371Z INFO dioxus_server::server: Registering: POST /api/get_week_start13047172052397277026 server # [ 392.643679] horae[1018]: 2026-10-08T22:38:52.724155Z INFO dioxus_server::server: Registering: POST /api/get_org_name13047172052397277026 server # [ 392.664914] horae[1018]: 2026-10-08T22:38:52.730372Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection1604479113674082919 server # [ 392.681676] horae[1018]: 2026-10-08T22:38:52.731144Z INFO dioxus_server::server: Registering: POST /api/project_creation_options1604479113674082919 server # [ 392.690732] horae[1018]: 2026-10-08T22:38:52.731913Z INFO dioxus_server::server: Registering: POST /api/project_creation_client1604479113674082919 server # [ 392.707254] horae[1018]: 2026-10-08T22:38:52.732200Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft1604479113674082919 server # [ 392.723004] horae[1018]: 2026-10-08T22:38:52.732422Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft1604479113674082919 server # [ 392.733853] horae[1018]: 2026-10-08T22:38:52.751671Z INFO dioxus_server::server: Registering: POST /api/save_project_editor1604479113674082919 server # [ 392.754509] horae[1018]: 2026-10-08T22:38:52.752287Z INFO dioxus_server::server: Registering: POST /api/load_project_editor1604479113674082919 server # [ 392.769892] horae[1018]: 2026-10-08T22:38:52.752572Z INFO dioxus_server::server: Registering: POST /api/save_project_draft1604479113674082919 server # [ 392.781676] horae[1018]: 2026-10-08T22:38:52.762024Z INFO dioxus_server::server: Registering: POST /api/load_project_draft1604479113674082919 server # [ 392.789471] horae[1018]: 2026-10-08T22:38:52.762613Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts16386063561583833955 server # [ 392.812891] horae[1018]: 2026-10-08T22:38:52.762866Z INFO dioxus_server::server: Registering: POST /api/reorder_untimed_entries16386063561583833955 server # [ 392.817993] horae[1018]: 2026-10-08T22:38:52.763062Z INFO dioxus_server::server: Registering: POST /api/reschedule_time_entry16386063561583833955 server # [ 392.834173] horae[1018]: 2026-10-08T22:38:52.763331Z INFO dioxus_server::server: Registering: POST /api/update_time_entry16386063561583833955 server # [ 392.854075] horae[1018]: 2026-10-08T22:38:52.763559Z INFO dioxus_server::server: Registering: POST /api/list_time_entries16386063561583833955 server # [ 392.874351] horae[1018]: 2026-10-08T22:38:52.763931Z INFO dioxus_server::server: Registering: POST /api/get_current_timer16386063561583833955 server # [ 392.895311] horae[1018]: 2026-10-08T22:38:52.764153Z INFO dioxus_server::server: Registering: POST /api/delete_time_entry16386063561583833955 server # [ 392.910257] horae[1018]: 2026-10-08T22:38:52.764431Z INFO dioxus_server::server: Registering: POST /api/create_time_entry16386063561583833955 server # [ 392.938128] horae[1018]: 2026-10-08T22:38:52.774056Z INFO dioxus_server::server: Registering: POST /api/start_timer16386063561583833955 server # [ 392.954298] horae[1018]: 2026-10-08T22:38:52.774837Z INFO dioxus_server::server: Registering: POST /api/stop_timer16386063561583833955 server # [ 392.997714] horae[1018]: 2026-10-08T22:38:52.798939Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection server # [ 393.010222] horae[1018]: 2026-10-08T22:38:52.799847Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel server # [ 393.034125] horae[1018]: 2026-10-08T22:38:52.800231Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode} server # [ 393.046224] horae[1018]: 2026-10-08T22:38:52.811158Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start server # [ 393.070278] horae[1018]: 2026-10-08T22:38:52.811754Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry server # [ 393.114806] horae[1018]: 2026-10-08T22:38:52.811989Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history server # [ 393.126756] horae[1018]: 2026-10-08T22:38:52.812220Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account1479223498380199393 server # [ 393.146473] horae[1018]: 2026-10-08T22:38:52.812436Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status server # [ 393.182813] horae[1018]: 2026-10-08T22:38:52.830903Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start1479223498380199393 server # [ 393.198761] horae[1018]: 2026-10-08T22:38:52.831572Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect1479223498380199393 server # [ 393.222344] horae[1018]: 2026-10-08T22:38:52.832198Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress13963200375258473206 server # [ 393.241990] horae[1018]: 2026-10-08T22:38:52.848001Z INFO dioxus_server::server: Registering: POST /api/get_project_fee_balances13963200375258473206 server # [ 393.253934] horae[1018]: 2026-10-08T22:38:52.858629Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects13963200375258473206 server # [ 393.273573] horae[1018]: 2026-10-08T22:38:52.859280Z INFO dioxus_server::server: Registering: POST /api/set_projects_active13963200375258473206 server # [ 393.281959] horae[1018]: 2026-10-08T22:38:52.859566Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks13963200375258473206 server # [ 393.301918] horae[1018]: 2026-10-08T22:38:52.859797Z INFO dioxus_server::server: Registering: POST /api/get_project_details13963200375258473206 server # [ 393.313806] horae[1018]: 2026-10-08T22:38:52.860035Z INFO dioxus_server::server: Registering: POST /api/set_project_active13963200375258473206 server # [ 393.333754] horae[1018]: 2026-10-08T22:38:52.860274Z INFO dioxus_server::server: Registering: POST /api/list_project_tasks13963200375258473206 server # [ 393.349467] horae[1018]: 2026-10-08T22:38:52.860515Z INFO dioxus_server::server: Registering: POST /api/list_project_spend13963200375258473206 server # [ 393.388582] horae[1018]: 2026-10-08T22:38:52.868547Z INFO dioxus_server::server: Registering: POST /api/list_project_tags13963200375258473206 server # [ 393.407162] horae[1018]: 2026-10-08T22:38:52.877820Z INFO dioxus_server::server: Registering: POST /api/link_project_task13963200375258473206 server # [ 393.423482] horae[1018]: 2026-10-08T22:38:52.878530Z INFO dioxus_server::server: Registering: POST /api/delete_assignment13963200375258473206 server # [ 393.446171] horae[1018]: 2026-10-08T22:38:52.878854Z INFO dioxus_server::server: Registering: POST /api/create_assignment13963200375258473206 server # [ 393.469204] horae[1018]: 2026-10-08T22:38:52.879081Z INFO dioxus_server::server: Registering: POST /api/list_assignments13963200375258473206 server # [ 393.481587] horae[1018]: 2026-10-08T22:38:52.879316Z INFO dioxus_server::server: Registering: POST /api/set_task_active13963200375258473206 server # [ 393.486327] horae[1018]: 2026-10-08T22:38:52.879522Z INFO dioxus_server::server: Registering: POST /api/list_projects13963200375258473206 server # [ 393.555869] horae[1018]: 2026-10-08T22:38:52.879796Z INFO dioxus_server::server: Registering: POST /api/update_task13963200375258473206 server # [ 393.571628] horae[1018]: 2026-10-08T22:38:52.880027Z INFO dioxus_server::server: Registering: POST /api/create_task13963200375258473206 server # [ 393.598514] horae[1018]: 2026-10-08T22:38:52.880258Z INFO dioxus_server::server: Registering: POST /api/list_tasks13963200375258473206 server # [ 393.610834] horae[1018]: 2026-10-08T22:38:52.900487Z INFO dioxus_server::server: Registering: POST /api/update_client_profile10485135825777491571 server # [ 393.622971] horae[1018]: 2026-10-08T22:38:52.904586Z INFO dioxus_server::server: Registering: POST /api/list_client_summaries10485135825777491571 server # [ 393.640194] horae[1018]: 2026-10-08T22:38:52.909664Z INFO dioxus_server::server: Registering: POST /api/create_project_client10485135825777491571 server # [ 393.658098] horae[1018]: 2026-10-08T22:38:52.909942Z INFO dioxus_server::server: Registering: POST /api/create_client_profile10485135825777491571 server # [ 393.670183] horae[1018]: 2026-10-08T22:38:52.910158Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices10485135825777491571 server # [ 393.685318] horae[1018]: 2026-10-08T22:38:52.910398Z INFO dioxus_server::server: Registering: POST /api/get_client_details10485135825777491571 server # [ 393.698073] horae[1018]: 2026-10-08T22:38:52.910609Z INFO dioxus_server::server: Registering: POST /api/set_client_active10485135825777491571 server # [ 393.709675] horae[1018]: 2026-10-08T22:38:52.910825Z INFO dioxus_server::server: Registering: POST /api/update_client10485135825777491571 server # [ 393.722136] horae[1018]: 2026-10-08T22:38:52.911029Z INFO dioxus_server::server: Registering: POST /api/create_client10485135825777491571 server # [ 393.736832] horae[1018]: 2026-10-08T22:38:52.911252Z INFO dioxus_server::server: Registering: POST /api/list_clients10485135825777491571 server # [ 393.741804] horae[1018]: 2026-10-08T22:38:53.299889Z INFO horae: Listening on 127.0.0.1:3000 server # [ 413.582728] test-console-diagnostics-start[1099]: JOB UNIT TYPE STATE server # [ 413.626859] test-console-diagnostics-start[1099]: 175 multi-user.target start waiting server # [ 413.637655] test-console-diagnostics-start[1099]: 297 dev-ttyAMA0.device start running server # [ 413.654485] test-console-diagnostics-start[1099]: 296 dev-hvc0.device start running server # [ 413.670321] test-console-diagnostics-start[1099]: 295 backdoor.service start waiting server # [ 413.691988] test-console-diagnostics-start[1099]: 4 jobs listed. server # [ 414.737004] test-console-diagnostics-start[1100]: Id=backdoor.service server # [ 414.743056] test-console-diagnostics-start[1100]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device server # [ 414.758623] test-console-diagnostics-start[1100]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket server # [ 414.771160] test-console-diagnostics-start[1100]: ActiveState=inactive server # [ 414.785580] test-console-diagnostics-start[1100]: SubState=dead server # [ 414.797842] test-console-diagnostics-start[1100]: Result=success server # [ 414.809849] test-console-diagnostics-start[1100]: Id=dev-hvc0.device server # [ 414.817661] test-console-diagnostics-start[1100]: Requires= server # [ 414.827970] test-console-diagnostics-start[1100]: After= server # [ 414.841804] test-console-diagnostics-start[1100]: ActiveState=activating server # [ 414.853604] test-console-diagnostics-start[1100]: SubState=tentative server # [ 414.863474] test-console-diagnostics-start[1100]: Id=dev-ttyAMA0.device server # [ 414.877515] test-console-diagnostics-start[1100]: Requires= server # [ 414.890822] test-console-diagnostics-start[1100]: After= server # [ 414.905480] test-console-diagnostics-start[1100]: ActiveState=activating server # [ 414.917501] test-console-diagnostics-start[1100]: SubState=tentative server # [ 414.929895] test-console-diagnostics-start[1100]: Id=dev-ttyS0.device server # [ 414.937720] test-console-diagnostics-start[1100]: Requires= server # [ 414.951693] test-console-diagnostics-start[1100]: After= server # [ 414.961568] test-console-diagnostics-start[1100]: ActiveState=inactive server # [ 414.973832] test-console-diagnostics-start[1100]: SubState=dead server # [ 415.106506] test-console-diagnostics-start[1101]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 415.122601] test-console-diagnostics-start[1101]: DEVNAME=/dev/hvc0 server # [ 415.141611] test-console-diagnostics-start[1101]: MAJOR=229 server # [ 415.165048] test-console-diagnostics-start[1101]: MINOR=0 server # [ 415.174236] test-console-diagnostics-start[1101]: SUBSYSTEM=tty server # [ 415.514790] test-console-diagnostics-start[1102]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 415.531860] test-console-diagnostics-start[1102]: DEVNAME=/dev/ttyAMA0 server # [ 415.574634] test-console-diagnostics-start[1102]: MAJOR=204 server # [ 415.621676] test-console-diagnostics-start[1102]: MINOR=64 server # [ 415.646161] test-console-diagnostics-start[1102]: SUBSYSTEM=tty server # [ 416.075796] test-console-diagnostics-start[1103]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 416.090874] test-console-diagnostics-start[1103]: DEVNAME=/dev/ttyS0 server # [ 416.117981] test-console-diagnostics-start[1103]: MAJOR=4 server # [ 416.138604] test-console-diagnostics-start[1103]: MINOR=64 server # [ 416.158322] test-console-diagnostics-start[1103]: SUBSYSTEM=tty server # [ 477.270378] test-console-diagnostics-start[1301]: JOB UNIT TYPE STATE server # [ 477.339284] test-console-diagnostics-start[1301]: 175 multi-user.target start waiting server # [ 477.354844] test-console-diagnostics-start[1301]: 297 dev-ttyAMA0.device start running server # [ 477.373545] test-console-diagnostics-start[1301]: 296 dev-hvc0.device start running server # [ 477.387123] test-console-diagnostics-start[1301]: 295 backdoor.service start waiting server # [ 477.411621] test-console-diagnostics-start[1301]: 4 jobs listed. server # [ 478.662519] test-console-diagnostics-start[1302]: Id=backdoor.service server # [ 478.682676] test-console-diagnostics-start[1302]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device server # [ 478.702956] test-console-diagnostics-start[1302]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket server # [ 478.714341] test-console-diagnostics-start[1302]: ActiveState=inactive server # [ 478.733877] test-console-diagnostics-start[1302]: SubState=dead server # [ 478.747243] test-console-diagnostics-start[1302]: Result=success server # [ 478.762019] test-console-diagnostics-start[1302]: Id=dev-hvc0.device server # [ 478.772738] test-console-diagnostics-start[1302]: Requires= server # [ 478.785018] test-console-diagnostics-start[1302]: After= server # [ 478.803023] test-console-diagnostics-start[1302]: ActiveState=activating server # [ 478.818001] test-console-diagnostics-start[1302]: SubState=tentative server # [ 478.829741] test-console-diagnostics-start[1302]: Id=dev-ttyAMA0.device server # [ 478.849616] test-console-diagnostics-start[1302]: Requires= server # [ 478.851963] test-console-diagnostics-start[1302]: After= server # [ 478.878324] test-console-diagnostics-start[1302]: ActiveState=activating server # [ 478.889490] test-console-diagnostics-start[1302]: SubState=tentative server # [ 478.901833] test-console-diagnostics-start[1302]: Id=dev-ttyS0.device server # [ 478.909546] test-console-diagnostics-start[1302]: Requires= server # [ 478.925452] test-console-diagnostics-start[1302]: After= server # [ 478.933585] test-console-diagnostics-start[1302]: ActiveState=inactive server # [ 478.953661] test-console-diagnostics-start[1302]: SubState=dead server # [ 479.085716] test-console-diagnostics-start[1311]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 479.090225] test-console-diagnostics-start[1311]: DEVNAME=/dev/hvc0 server # [ 479.105893] test-console-diagnostics-start[1311]: MAJOR=229 server # [ 479.113479] test-console-diagnostics-start[1311]: MINOR=0 server # [ 479.125558] test-console-diagnostics-start[1311]: SUBSYSTEM=tty server # [ 479.319533] test-console-diagnostics-start[1313]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 479.346859] test-console-diagnostics-start[1313]: DEVNAME=/dev/ttyAMA0 server # [ 479.354506] test-console-diagnostics-start[1313]: MAJOR=204 server # [ 479.361351] test-console-diagnostics-start[1313]: MINOR=64 server # [ 479.369800] test-console-diagnostics-start[1313]: SUBSYSTEM=tty server # [ 479.582427] test-console-diagnostics-start[1315]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 479.598496] test-console-diagnostics-start[1315]: DEVNAME=/dev/ttyS0 server # [ 479.606090] test-console-diagnostics-start[1315]: MAJOR=4 server # [ 479.613590] test-console-diagnostics-start[1315]: MINOR=64 server # [ 479.621599] test-console-diagnostics-start[1315]: SUBSYSTEM=tty server # [ 525.352385] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 server # [ 540.454877] test-console-diagnostics-start[1513]: JOB UNIT TYPE STATE server # [ 540.479464] test-console-diagnostics-start[1513]: 175 multi-user.target start waiting server # [ 540.497948] test-console-diagnostics-start[1513]: 297 dev-ttyAMA0.device start running server # [ 540.505727] test-console-diagnostics-start[1513]: 296 dev-hvc0.device start running server # [ 540.517793] test-console-diagnostics-start[1513]: 295 backdoor.service start waiting server # [ 540.522296] test-console-diagnostics-start[1513]: 4 jobs listed. server # [ 541.475475] test-console-diagnostics-start[1514]: Id=backdoor.service server # [ 541.507634] test-console-diagnostics-start[1514]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device server # [ 541.530061] test-console-diagnostics-start[1514]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket server # [ 541.545827] test-console-diagnostics-start[1514]: ActiveState=inactive server # [ 541.557485] test-console-diagnostics-start[1514]: SubState=dead server # [ 541.565806] test-console-diagnostics-start[1514]: Result=success server # [ 541.573577] test-console-diagnostics-start[1514]: Id=dev-hvc0.device server # [ 541.586160] test-console-diagnostics-start[1514]: Requires= server # [ 541.594073] test-console-diagnostics-start[1514]: After= server # [ 541.614021] test-console-diagnostics-start[1514]: ActiveState=activating server # [ 541.625873] test-console-diagnostics-start[1514]: SubState=tentative server # [ 541.633762] test-console-diagnostics-start[1514]: Id=dev-ttyAMA0.device server # [ 541.642061] test-console-diagnostics-start[1514]: Requires= server # [ 541.653932] test-console-diagnostics-start[1514]: After= server # [ 541.665753] test-console-diagnostics-start[1514]: ActiveState=activating server # [ 541.677694] test-console-diagnostics-start[1514]: SubState=tentative server # [ 541.685453] test-console-diagnostics-start[1514]: Id=dev-ttyS0.device server # [ 541.694036] test-console-diagnostics-start[1514]: Requires= server # [ 541.705448] test-console-diagnostics-start[1514]: After= server # [ 541.717897] test-console-diagnostics-start[1514]: ActiveState=inactive server # [ 541.725952] test-console-diagnostics-start[1514]: SubState=dead server # [ 541.831313] test-console-diagnostics-start[1515]: DEVPATH=/devices/virtual/tty/hvc0 server # [ 541.846891] test-console-diagnostics-start[1515]: DEVNAME=/dev/hvc0 server # [ 541.871125] test-console-diagnostics-start[1515]: MAJOR=229 server # [ 541.882630] test-console-diagnostics-start[1515]: MINOR=0 server # [ 541.897588] test-console-diagnostics-start[1515]: SUBSYSTEM=tty server # [ 542.189188] test-console-diagnostics-start[1516]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0 server # [ 542.206459] test-console-diagnostics-start[1516]: DEVNAME=/dev/ttyAMA0 server # [ 542.227032] test-console-diagnostics-start[1516]: MAJOR=204 server # [ 542.238520] test-console-diagnostics-start[1516]: MINOR=64 server # [ 542.249689] test-console-diagnostics-start[1516]: SUBSYSTEM=tty server # [ 542.574505] test-console-diagnostics-start[1517]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0 server # [ 542.590439] test-console-diagnostics-start[1517]: DEVNAME=/dev/ttyS0 server # [ 542.599842] test-console-diagnostics-start[1517]: MAJOR=4 server # [ 542.617178] test-console-diagnostics-start[1517]: MINOR=64 server # [ 542.626330] test-console-diagnostics-start[1517]: SUBSYSTEM=tty server # [ 557.834844] systemd[1]: Starting Virtual Console Setup... server # [ 561.031803] systemd-vconsole-setup[1573]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 561.147401] systemd[1]: Finished Virtual Console Setup. server # [ 570.159703] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 591.909823] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. server # [ 592.895624] 8021q: 802.1Q VLAN Support v1.8 server # [ 594.420290] cfg80211: Loading compiled-in X.509 certificates for regulatory database server # [ 594.800163] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' server # [ 594.823197] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' server # [ 594.869024] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 server # [ 594.870837] cfg80211: failed to load regulatory.db server # [ 599.161885] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. server # [ 599.295764] systemd[1]: Started backdoor.service. server # [ 599.382066] systemd[1]: Reached target Multi-User System. server # [ 599.417617] systemd[1]: Startup finished in 8.551s (kernel) + 1min 42.212s (initrd) + 8min 8.649s (userspace) = 9min 59.413s. server # [ 599.571993] (udev-worker)[1688]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 599.603744] (udev-worker)[1688]: Network interface NamePolicy= disabled on kernel command line. server # [ 599.666280] (udev-worker)[1687]: Network interface NamePolicy= disabled on kernel command line. server # connecting to host... server: waiting for unit register-nix-paths.service server: waiting for the VM to finish booting server: Guest shell says: b'Spawning backdoor root shell...\n' server: connected to guest root shell server: (connecting took 0.00 seconds) server: (finished: waiting for the VM to finish booting, in 0.00 seconds) server # [ 602.019169] systemd[1]: Condition check resulted in Virtio network device being skipped. server # [ 602.261419] systemd[1]: Starting Address configuration of eth1... server: (finished: waiting for unit register-nix-paths.service, in 2.59 seconds) server: waiting for unit postgresql.service server # [ 604.082592] systemd[1]: test-console-diagnostics.service: Deactivated successfully. server # [ 604.125924] systemd[1]: test-console-diagnostics.service: Consumed 4.796s CPU time over 6min 31.082s wall clock time, 2.3M memory peak, 396K read from disk. server # [ 604.850786] 8021q: adding VLAN 0 to HW filter on device eth1 server # [ 605.023048] 8021q: adding VLAN 0 to HW filter on device eth0 server # [ 604.946952] dhcpcd[749]: eth0: waiting for carrier server # [ 605.014169] dhcpcd[749]: eth0: carrier acquired server # [ 605.458738] network-addresses-eth1-start[1709]: adding address 192.168.1.1/24... done server # [ 605.672670] dhcpcd[749]: DUID 00:01:00:01:32:5a:d8:d2:52:54:00:12:34:56 server # [ 605.675834] dhcpcd[749]: eth0: IAID 00:12:34:56 server # [ 605.700036] dhcpcd[749]: eth0: adding address fe80::5054:ff:fe12:3456 server # [ 605.885395] network-addresses-eth1-start[1709]: adding address 2001:db8:1::1/64... done server # [ 606.326853] systemd[1]: Finished Address configuration of eth1. server: (finished: waiting for unit postgresql.service, in 2.62 seconds) server: waiting for unit dex.service server # [ 607.303117] dhcpcd[749]: eth0: soliciting a DHCP lease server # [ 607.331020] dhcpcd[749]: eth0: soliciting an IPv6 router server # [ 607.404005] dhcpcd[749]: eth0: offered 10.0.2.15 from 10.0.2.2 server # [ 607.434978] dhcpcd[749]: eth0: Router Advertisement from fe80::2 server # [ 607.474488] dhcpcd[749]: eth0: adding address fec0::5054:ff:fe12:3456/64 server # [ 607.505742] dhcpcd[749]: eth0: adding route to fec0::/64 server # [ 607.521885] dhcpcd[749]: eth0: adding default route via fe80::2 server # [ 607.545850] dhcpcd[749]: eth0: probing address 10.0.2.15/24 server: (finished: waiting for unit dex.service, in 1.60 seconds) server: waiting for unit horae.service server: (finished: waiting for unit horae.service, in 1.58 seconds) server: waiting for TCP port 5556 on localhost server # Connection to localhost (127.0.0.1) 5556 port [tcp/freeciv] succeeded! server: (finished: waiting for TCP port 5556 on localhost, in 1.47 seconds) server: waiting for TCP port 3000 on localhost server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded! server: (finished: waiting for TCP port 3000 on localhost, in 0.92 seconds) server: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok server: (finished: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok, in 1.44 seconds) server: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null server # [ 613.582702] dhcpcd[749]: eth0: leased 10.0.2.15 for 86400 seconds server # [ 613.609443] dhcpcd[749]: eth0: adding route to 10.0.2.0/24 server # [ 613.612050] dhcpcd[749]: eth0: adding default route via 10.0.2.2 server: (finished: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null, in 1.79 seconds) server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed server # [ 617.793558] sudo[1786]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae seed server # [ 619.739005] sudo[1786]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0) server # [ 623.503518] postgres[1827]: [1827] LOG: could not receive data from client: Connection reset by peer server # [ 623.673534] sudo[1786]: pam_unix(sudo:session): session closed for user horae server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed, in 8.52 seconds) server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member server # [ 624.847297] sudo[1838]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member server # [ 626.069421] sudo[1838]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0) server # [ 627.622472] postgres[1846]: [1846] LOG: could not receive data from client: Connection reset by peer server # [ 627.747664] sudo[1838]: pam_unix(sudo:session): session closed for user horae server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member, in 4.09 seconds) server: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null server # [ 630.066376] dex[766]: time=2026-10-08T22:42:50.484Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=bf7af4bd-9289-409d-8030-f0e9b29e3454 server: (finished: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 4.73 seconds) server: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries server # [ 635.515150] horae[1018]: 2026-10-08T22:42:55.939365Z WARN call: sqlx::query: slow statement: execution time exceeded alert threshold summary="SELECT te.id, te.spent_date as …" db.statement="\n\nSELECT te.id,\n te.spent_date as \"spent_date: chrono::NaiveDate\",\n te.minutes, te.start_minute, te.rounded_minutes, te.notes,\n (te.billable AND (te.invoice_id IS NOT NULL OR (p.project_type <> 'non_billable' AND COALESCE(pt.billable, t.billable_default)))) as \"billable!\", te.is_running,\n te.started_at as \"started_at: chrono::DateTime\",\n te.state::text AS \"state!: String\", te.invoice_id,\n te.created_at as \"created_at: chrono::DateTime\",\n te.updated_at as \"updated_at: chrono::DateTime\",\n te.user_id, u.name AS user_name,\n te.project_id, p.name AS project_name, p.code AS project_code,\n te.task_id, t.name AS task_name,\n p.client_id, c.name AS client_name,\n CASE WHEN viewer.org_role IN ('admin', 'manager') THEN COALESCE(line.rate_cents,\n CASE WHEN ps.project_id IS NULL OR p.project_type = 'time_and_materials'\n THEN resolve_project_rate(ps.rate_mode, pt.rate_cents, a.rate_cents, p.rate_cents,\n CASE WHEN ps.project_id IS NULL OR p.currency = o.default_currency THEN u.billable_rate_cents END,\n CASE WHEN p.currency = c.currency THEN c.default_rate_cents END\n ) END) END AS \"billable_rate_cents?\",\n CASE WHEN viewer.org_role = 'admin' OR (viewer.org_role = 'manager' AND mc.id IS NULL)\n THEN COALESCE(mc.cost_rate_cents, u.cost_rate_cents) END AS user_cost_rate_cents,\n CASE WHEN access.can_view_progress THEN p.budget_kind::text END AS \"budget_kind?: String\"\n FROM time_entries te\n JOIN users viewer ON viewer.id = $10 AND viewer.org_id = te.org_id AND viewer.active\n JOIN users u ON u.id = te.user_id\n JOIN projects p ON p.id = te.project_id\n LEFT JOIN project_read_access access ON access.project_id = p.id AND access.org_id = p.org_id AND access.user_id = viewer.id\n JOIN tasks t ON t.id = te.task_id\n LEFT JOIN project_tasks pt ON pt.project_id = te.project_id AND pt.task_id = te.task_id\n LEFT JOIN assignments a ON a.project_id = te.project_id AND a.user_id = te.user_id\n LEFT JOIN invoice_line_items line ON line.invoice_id = te.invoice_id AND line.time_entry_id = te.id\n JOIN clients c ON c.id = p.client_id\n JOIN organizations o ON o.id = te.org_id\n LEFT JOIN project_settings ps ON ps.project_id = p.id\n LEFT JOIN project_member_costs mc ON mc.project_id = te.project_id AND mc.user_id = te.user_id\n WHERE te.org_id = $1 AND (viewer.org_role IN ('admin', 'manager') OR te.user_id = viewer.id)\n AND ($2::uuid IS NULL OR te.user_id = $2)\n AND ($3::uuid IS NULL OR te.project_id = $3)\n AND ($4::date IS NULL OR te.spent_date >= $4::date)\n AND ($5::date IS NULL OR te.spent_date <= $5::date)\n AND ($6::bool IS NULL OR te.is_running = $6)\n AND ($7::timestamptz IS NULL OR te.updated_at >= $7::timestamptz)\n ORDER BY te.spent_date DESC, te.created_at DESC, te.id DESC\n LIMIT $8 OFFSET $9\n" rows_affected=0 rows_returned=0 elapsed=1.720413485s elapsed_secs=1.720413485 slow_threshold=1s server: (finished: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 3.04 seconds) server: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'" server # [ 636.818856] sudo[1864]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -c 'UPDATE users SET active = false WHERE email = \'kilgore@kilgore.trout\'' server # [ 637.883737] sudo[1864]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0) server # [ 638.667834] sudo[1864]: pam_unix(sudo:session): session closed for user horae server: (finished: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'", in 3.17 seconds) server # [ 640.247259] dex[766]: time=2026-10-08T22:43:00.676Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=7676858c-d099-499f-8a00-88e7689aafd1 server # [ 640.759719] horae[1018]: 2026-10-08T22:43:01.199350Z WARN call: horae::auth::oidc: OIDC login denied: account is deactivated server: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries server: (finished: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.93 seconds) (finished: run the VM test script, in 646.16 seconds) test script finished in 646.98s cleanup kill QemuMachine (pid 12) server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13) (finished: cleanup, in 0.12 seconds)