tribuchet: building on eliza 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.0 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 45) machine # Formatting '/build/vm-state-machine/tmp.MRYdnU6pBx', fmt=raw size=1073741824 machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: 553f4291-d1b9-4db9-b75b-8c04cea0da88 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Formatting '/build/vm-state-machine/machine-esp.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=273695232 backing_file=/nix/store/rrf5p09hf4bs8gzbl365kh85mhs49sbf-machine-esp-image/esp.qcow2 backing_fmt=qcow2 lazy_refcounts=off refcount_bits=16 machine # Formatting 'empty0.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=536870912 lazy_refcounts=off refcount_bits=16 machine # Starting virtiofs daemons... machine # [2026-09-27T03:13:40Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:13:40Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:13:40Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:13:40Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:13:40Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:13:40Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:13:40Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:13:40Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:13:40Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:13:40Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-27T03:13:40Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-27T03:13:40Z INFO virtiofsd] Client connected, servicing requests machine # UEFI firmware (version built at 00:00:00 on Jan 1 1980) machine: Guest shell says: b'\x1b[2J\x1b[001;001H\x1b[=3h\x1b[2J\x1b[001;001H\x1b[2J\x1b[001;001H\x1b[8;042;160t\x1b[2J\x1b[001;001H' machine # BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x5,0x0) machine # BdsDxe: starting Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x5,0x0) machine: Guest shell says: b'BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x5,0x0)\r\nBdsDxe: starting Boot0002 "UEFI Misc Device" from Pc' machine: Guest shell says: b'iRoot(0x0)/Pci(0x5,0x0)\r\n' machine: Guest shell says: b'\r \r\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m' machine: Guest shell says: b'\x1b[2J\x1b[001;001H\x1b[020;063H\x1b[1m\x1b[30m\x1b[47m\x1b[0m\x1b[30m\x1b[47m NixOS \x1b[021;063H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m ' machine: Guest shell says: b' NixOS (boot-luks) \x1b[022;063H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Reboot Into Firmware Interface \x1b[024;001H\x1b[1m\x1b[37m\x1b' machine: Guest shell says: b'[40m\x1b[0m\x1b[37m\x1b[40m Boot in 5s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m-----------------------------------------' machine: Guest shell says: b'-' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 4s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 3s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 2s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 1s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m\x1b[2J\x1b[001;001H' machine # NixOS NixOS (boot-luks) Reboot Into Firmware Interface Boot in 5s. ------------------------------------------ Boot in 4s. ------------------------------------------ Boot in 3s. ------------------------------------------ Boot in 2s. ------------------------------------------ Boot in 1s. ------------------------------------------[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] efi: EFI v2.7 by EDK II machine # [ 0.000000] efi: SMBIOS 3.0=0x47d60000 TPMFinalLog=0x47fe0000 MEMATTR=0x45ac8018 ACPI 2.0=0x47ff3018 RNG=0x47ff3798 TPMEventLog=0x45a66018 INITRD=0x45a76418 MEMRESERVE=0x45ac2218 machine # [ 0.000000] random: crng init done machine # [ 0.000000] ACPI: Early table checksum verification disabled machine # [ 0.000000] ACPI: RSDP 0x0000000047FF3018 000024 (v02 BOCHS ) machine # [ 0.000000] ACPI: XSDT 0x0000000047FF3F18 000074 (v01 BOCHS BXPC 00000001 01000013) machine # [ 0.000000] ACPI: FACP 0x0000000047FF3B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: DSDT 0x0000000047FF1018 001822 (v02 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: APIC 0x0000000047FF3C98 0000B8 (v04 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: PPTT 0x0000000047FF3D98 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: GTDT 0x0000000047FF3098 000068 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: MCFG 0x0000000047FF3A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: SPCR 0x0000000047FF3E98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: DBG2 0x0000000047FF3818 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: IORT 0x0000000047FF3898 000080 (v05 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: TPM2 0x0000000047FF3998 00004C (v04 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: BGRT 0x0000000047FF3A18 000038 (v01 INTEL EDK2 00000002 01000013) machine # [ 0.000000] ACPI: SPCR: console: pl011,mmio32,0x9000000,9600 machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdf2880-0x7fdf5fff] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x0000000045fcffff] machine # [ 0.000000] node 0: [mem 0x0000000045fd0000-0x000000004604ffff] machine # [ 0.000000] node 0: [mem 0x0000000046050000-0x000000004609ffff] machine # [ 0.000000] node 0: [mem 0x00000000460a0000-0x00000000462affff] machine # [ 0.000000] node 0: [mem 0x00000000462b0000-0x00000000462bffff] machine # [ 0.000000] node 0: [mem 0x00000000462c0000-0x00000000463dffff] machine # [ 0.000000] node 0: [mem 0x00000000463e0000-0x0000000047bbffff] machine # [ 0.000000] node 0: [mem 0x0000000047bc0000-0x0000000047feffff] machine # [ 0.000000] node 0: [mem 0x0000000047ff0000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from ACPI. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Kernel command line: initrd=\EFI\nixos\sjj5d661zyvciwbd8g3ggpzma79fdrk8-initrd-linux-6.18.53-initrd.efi init=/nix/store/wlz2i6xn326ks4bfz6ic9kr96syn9116-nixos-system-machine-test/init console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @408f0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40900000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000040910000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000040920000 machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.000038] arm-pv: using stolen time PV machine # [ 0.000401] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000491] Console: colour dummy device 80x25 machine # [ 0.000496] printk: legacy console [tty0] enabled machine # [ 0.000714] ACPI: Core revision 20250807 machine # [ 0.000812] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000819] pid_max: default: 32768 minimum: 301 machine # [ 0.000877] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001006] landlock: Up and running. machine # [ 0.001009] Yama: becoming mindful. machine # [ 0.001490] LSM support for eBPF active machine # [ 0.001579] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001586] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002674] rcu: Hierarchical SRCU implementation. machine # [ 0.002679] rcu: Max phase no-delay instances is 1000. machine # [ 0.003358] fsl-mc MSI: ITS@0x8080000 domain created machine # [ 0.003379] Remapping and enabling EFI services. machine # [ 0.003495] smp: Bringing up secondary CPUs ... machine # [ 0.003505] smp: Brought up 1 node, 1 CPU machine # [ 0.003508] SMP: Total of 1 processors activated. machine # [ 0.003511] CPU: All CPU(s) started at EL1 machine # [ 0.003522] CPU features: detected: Branch Target Identification machine # [ 0.003527] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.003530] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.003533] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.003537] CPU features: detected: Common not Private translations machine # [ 0.003540] CPU features: detected: CRC32 instructions machine # [ 0.003543] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.003547] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.003550] CPU features: detected: Data independent timing control (DIT) machine # [ 0.003553] CPU features: detected: E0PD machine # [ 0.003556] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.003559] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.003562] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.003565] CPU features: detected: Fine Grained Traps machine # [ 0.003569] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.003574] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.003577] CPU features: detected: LSE atomic instructions machine # [ 0.003580] CPU features: detected: Privileged Access Never machine # [ 0.003583] CPU features: detected: PMUv3 machine # [ 0.003585] CPU features: detected: RAS Extension Support machine # [ 0.003588] CPU features: detected: RASv1p1 Extension Support machine # [ 0.003590] CPU features: detected: Random Number Generator machine # [ 0.003593] CPU features: detected: Speculation barrier (SB) machine # [ 0.003596] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.003599] CPU features: detected: TLB range maintenance instructions machine # [ 0.003604] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.003648] alternatives: applying system-wide alternatives machine # [ 0.006415] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.006543] Memory: 886656K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 120632K reserved, 32768K cma-reserved) machine # [ 0.006819] devtmpfs: initialized machine # [ 0.007762] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.007772] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.007936] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.007941] 0 pages in range for non-PLT usage machine # [ 0.007942] 508224 pages in range for PLT usage machine # [ 0.008016] pinctrl core: initialized pinctrl subsystem machine # [ 0.008647] SMBIOS 3.0.0 present. machine # [ 0.008652] DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 machine # [ 0.008661] DMI: Memory slots populated: 1/1 machine # [ 0.010110] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.010800] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.010936] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.011093] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.011113] audit: initializing netlink subsys (disabled) machine # [ 0.011535] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.011537] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.011541] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.011544] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.011546] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.011572] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 machine # [ 0.011586] cpuidle: using governor ladder machine # [ 0.011595] cpuidle: using governor menu machine # [ 0.011765] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.011791] ASID allocator initialised with 65536 entries machine # [ 0.012546] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.012742] Serial: AMBA PL011 UART driver machine # [ 0.018704] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.018710] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.018713] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.018716] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.018719] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.018721] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.018724] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.018727] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.019544] fbcon: Taking over console machine # [ 0.019584] ACPI: Added _OSI(Module Device) machine # [ 0.019587] ACPI: Added _OSI(Processor Device) machine # [ 0.019590] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.020625] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.026576] ACPI: Interpreter enabled machine # [ 0.026582] ACPI: Using GIC for interrupt routing machine # [ 0.026608] ACPI: MCFG table detected, 1 entries machine # [ 0.030689] ACPI: CPU0 has been hot-added machine # [ 0.031132] ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA machine # [ 0.031207] printk: console [ttyAMA0] enabled machine # [ 0.070283] ACPI: PCI: Interrupt link L000 configured for IRQ 35 machine # [ 0.070300] ACPI: PCI: Interrupt link L001 configured for IRQ 36 machine # [ 0.070312] ACPI: PCI: Interrupt link L002 configured for IRQ 37 machine # [ 0.070323] ACPI: PCI: Interrupt link L003 configured for IRQ 38 machine # [ 0.070341] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.070380] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.070471] acpi PNP0A08:00: _OSC: platform does not support [LTR] machine # [ 0.070566] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] machine # [ 0.070813] acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 machine # [ 0.070831] acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.070855] ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] machine # [ 0.071238] PCI host bridge to bus 0000:00 machine # [ 0.071266] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] machine # [ 0.071271] pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] machine # [ 0.071274] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] machine # [ 0.071278] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.071281] PCI: OF: of_root node is NULL, cannot create PCI host bridge node machine # [ 0.071338] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.071843] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.072037] pci 0000:00:01.0: BAR 0 [io 0x0220-0x023f] machine # [ 0.072049] pci 0000:00:01.0: BAR 1 [mem 0x1004d000-0x1004dfff] machine # [ 0.072075] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] machine # [ 0.072087] pci 0000:00:01.0: ROM [mem 0xfffc0000-0xffffffff pref] machine # [ 0.072594] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.073576] pci 0000:00:02.0: BAR 0 [io 0x0200-0x021f] machine # [ 0.073591] pci 0000:00:02.0: BAR 1 [mem 0x1004c000-0x1004cfff] machine # [ 0.073617] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref] machine # [ 0.074153] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.074948] pci 0000:00:03.0: BAR 0 [io 0x0100-0x017f] machine # [ 0.074960] pci 0000:00:03.0: BAR 1 [mem 0x1004b000-0x1004bfff] machine # [ 0.074986] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref] machine # [ 0.224023] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.224215] pci 0000:00:04.0: BAR 0 [io 0x0080-0x00ff] machine # [ 0.224228] pci 0000:00:04.0: BAR 1 [mem 0x1004a000-0x1004afff] machine # [ 0.224254] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref] machine # [ 0.228140] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.230229] pci 0000:00:05.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.230242] pci 0000:00:05.0: BAR 1 [mem 0x10049000-0x10049fff] machine # [ 0.230269] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref] machine # [ 0.230841] pci 0000:00:06.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.231031] pci 0000:00:06.0: BAR 0 [io 0x01e0-0x01ff] machine # [ 0.231042] pci 0000:00:06.0: BAR 1 [mem 0x10048000-0x10048fff] machine # [ 0.231070] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref] machine # [ 0.231084] pci 0000:00:06.0: ROM [mem 0xfffc0000-0xffffffff pref] machine # [ 0.231607] pci 0000:00:07.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.240361] pci 0000:00:07.0: BAR 1 [mem 0x10047000-0x10047fff] machine # [ 0.240391] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref] machine # [ 0.242995] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.243191] pci 0000:00:08.0: BAR 1 [mem 0x10046000-0x10046fff] machine # [ 0.243217] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref] machine # [ 0.243677] pci 0000:00:09.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.244613] pci 0000:00:09.0: BAR 0 [mem 0x10045000-0x10045fff] machine # [ 0.244924] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.250673] pci 0000:00:0a.0: BAR 1 [mem 0x10044000-0x10044fff] machine # [ 0.250700] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref] machine # [ 0.251222] pci 0000:00:0b.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.251408] pci 0000:00:0b.0: BAR 1 [mem 0x10043000-0x10043fff] machine # [ 0.251433] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref] machine # [ 0.251944] pci 0000:00:0c.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.252139] pci 0000:00:0c.0: BAR 1 [mem 0x10042000-0x10042fff] machine # [ 0.252165] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref] machine # [ 0.252679] pci 0000:00:0d.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.261916] pci 0000:00:0d.0: BAR 0 [io 0x0180-0x01bf] machine # [ 0.261933] pci 0000:00:0d.0: BAR 1 [mem 0x10041000-0x10041fff] machine # [ 0.261959] pci 0000:00:0d.0: BAR 4 [mem 0x800002c000-0x800002ffff 64bit pref] machine # [ 0.264827] pci 0000:00:0e.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.265980] pci 0000:00:0e.0: BAR 0 [io 0x01c0-0x01df] machine # [ 0.265994] pci 0000:00:0e.0: BAR 1 [mem 0x10040000-0x10040fff] machine # [ 0.266023] pci 0000:00:0e.0: BAR 4 [mem 0x8000030000-0x8000033fff 64bit pref] machine # [ 0.269908] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.269916] pci 0000:00:06.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.269919] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.269968] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.270011] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.270057] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.270100] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.270143] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.270190] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.280226] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.280282] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.280327] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.280372] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.280416] pci 0000:00:0d.0: BAR 4 [mem 0x800002c000-0x800002ffff 64bit pref]: assigned machine # [ 0.280463] pci 0000:00:0e.0: BAR 4 [mem 0x8000030000-0x8000033fff 64bit pref]: assigned machine # [ 0.280507] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.280525] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.280543] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.280561] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.280645] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.280789] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.280808] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.281643] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.281663] pci 0000:00:09.0: BAR 0 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.282217] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.282236] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.282255] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.282273] pci 0000:00:0d.0: BAR 1 [mem 0x1008c000-0x1008cfff]: assigned machine # [ 0.282292] pci 0000:00:0e.0: BAR 1 [mem 0x1008d000-0x1008dfff]: assigned machine # [ 0.282309] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.282929] pci 0000:00:04.0: BAR 0 [io 0x1080-0x10ff]: assigned machine # [ 0.282949] pci 0000:00:05.0: BAR 0 [io 0x1100-0x117f]: assigned machine # [ 0.283528] pci 0000:00:0d.0: BAR 0 [io 0x1180-0x11bf]: assigned machine # [ 0.284114] pci 0000:00:01.0: BAR 0 [io 0x11c0-0x11df]: assigned machine # [ 0.284136] pci 0000:00:02.0: BAR 0 [io 0x11e0-0x11ff]: assigned machine # [ 0.308078] pci 0000:00:06.0: BAR 0 [io 0x1200-0x121f]: assigned machine # [ 0.308101] pci 0000:00:0e.0: BAR 0 [io 0x1220-0x123f]: assigned machine # [ 0.308765] pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] machine # [ 0.308772] pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] machine # [ 0.308775] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] machine # [ 0.316220] iommu: Default domain type: Translated machine # [ 0.316227] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.316425] SCSI subsystem initialized machine # [ 0.316561] ACPI: bus type USB registered machine # [ 0.316606] usbcore: registered new interface driver usbfs machine # [ 0.316627] usbcore: registered new interface driver hub machine # [ 0.316642] usbcore: registered new device driver usb machine # [ 0.316849] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.316853] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.316861] PTP clock support registered machine # [ 0.316899] EDAC MC: Ver: 3.0.0 machine # [ 0.317349] scmi_core: SCMI protocol bus registered machine # [ 0.317475] efivars: Registered efivars operations machine # [ 0.317842] FPGA manager framework machine # [ 0.318280] vgaarb: loaded machine # [ 0.318521] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.330443] VFS: Disk quotas dquot_6.6.0 machine # [ 0.330467] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.332063] netfs: FS-Cache loaded machine # [ 0.332173] pnp: PnP ACPI init machine # [ 0.332380] system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved machine # [ 0.332434] pnp: PnP ACPI: found 1 devices machine # [ 0.337936] NET: Registered PF_INET protocol family machine # [ 0.337995] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.358366] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.358407] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.358431] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.358477] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.362579] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.362666] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.362697] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.362718] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.362796] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.362813] NET: Registered PF_XDP protocol family machine # [ 0.363561] PCI: CLS 0 bytes, default 64 machine # [ 0.363804] Trying to unpack rootfs image as initramfs... machine # [ 0.372460] kvm [1]: HYP mode not available machine # [ 0.475108] Initialise system trusted keyrings machine # [ 0.475259] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.475633] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.475700] 9p: Installing v9fs 9p2000 file system support machine # [ 0.498855] Key type asymmetric registered machine # [ 0.498871] Asymmetric key parser 'x509' registered machine # [ 0.498952] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.499086] io scheduler mq-deadline registered machine # [ 0.499091] io scheduler kyber registered machine # [ 0.513789] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.515863] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 machine # [ 0.515911] ACPI: button: Power Button [PWRB] machine # [ 0.542176] ACPI: \_SB_.L001: Enabled at IRQ 36 machine # [ 0.542232] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.546280] ACPI: \_SB_.L002: Enabled at IRQ 37 machine # [ 0.546317] virtio-pci 0000:00:02.0: enabling device (0005 -> 0007) machine # [ 0.555769] ACPI: \_SB_.L003: Enabled at IRQ 38 machine # [ 0.555805] virtio-pci 0000:00:03.0: enabling device (0005 -> 0007) machine # [ 0.557685] ACPI: \_SB_.L000: Enabled at IRQ 35 machine # [ 0.557711] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.562916] virtio-pci 0000:00:05.0: enabling device (0005 -> 0007) machine # [ 0.564609] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.576538] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.586938] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.590218] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0002) machine # [ 0.594167] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0002) machine # [ 0.604223] virtio-pci 0000:00:0d.0: enabling device (0005 -> 0007) machine # [ 0.606281] virtio-pci 0000:00:0e.0: enabling device (0005 -> 0007) machine # [ 0.619816] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.621638] msm_serial: driver initialized machine # [ 0.621749] SuperH (H)SCI(F) driver initialized machine # [ 0.621784] STM32 USART driver initialized machine # [ 0.644322] ACPI: bus type drm_connector registered machine # [ 0.655092] loop: module loaded machine # [ 0.655296] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.656079] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.660926] virtio_blk virtio3: 1/0/0 default/read/poll queues machine # [ 0.661811] virtio_blk virtio3: [vdb] 1048576 512-byte logical blocks (537 MB/512 MiB) machine # [ 0.666745] virtio_blk virtio4: 1/0/0 default/read/poll queues machine # [ 0.667496] virtio_blk virtio4: [vdc] 534561 512-byte logical blocks (274 MB/261 MiB) machine # [ 0.706587] vdc: vdc1 machine # [ 0.707452] megasas: 07.734.00.00-rc1 machine # [ 0.709944] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.713177] thunder_xcv, ver 1.0 machine # [ 0.713204] thunder_bgx, ver 1.0 machine # [ 0.713233] nicpf, ver 1.0 machine # [ 0.713798] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.713802] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.713826] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.713830] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.713853] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.713857] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.713884] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.713888] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.714006] sky2: driver version 1.30 machine # [ 0.724322] usbcore: registered new interface driver usb-storage machine # [ 0.724380] usbcore: registered new interface driver usbserial_generic machine # [ 0.724394] usbserial: USB Serial support registered for generic machine # [ 0.724938] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.726029] ehci-pci 0000:00:09.0: EHCI Host Controller machine # [ 0.726049] ehci-pci 0000:00:09.0: new USB bus registered, assigned bus number 1 machine # [ 0.726251] ehci-pci 0000:00:09.0: irq 45, io mem 0x10088000 machine # [ 0.732589] rtc-efi rtc-efi.0: registered as rtc0 machine # [ 0.732625] rtc-efi rtc-efi.0: setting system clock to 2026-09-27T03:13:55 UTC (1790478835) machine # [ 0.732972] i2c_dev: i2c /dev entries driver machine # [ 0.734569] ehci-pci 0000:00:09.0: USB 2.0 started, EHCI 1.00 machine # [ 0.734890] hub 1-0:1.0: USB hub found machine # [ 0.734912] hub 1-0:1.0: 6 ports detected machine # [ 0.740823] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.740828] sdhci: Copyright(c) Pierre Ossman machine # [ 0.741208] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.741538] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.753411] pstore: Using crash dump compression: deflate machine # [ 0.753421] pstore: Registered efi_pstore as persistent store backend machine # [ 0.756088] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.756410] usbcore: registered new interface driver usbhid machine # [ 0.756414] usbhid: USB HID core driver machine # [ 0.757480] hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 11 (0,800003ff) counters available machine # [ 0.762232] drop_monitor: Initializing network drop monitor service machine # [ 0.762423] NET: Registered PF_INET6 protocol family machine # [ 0.764422] Segment Routing with IPv6 machine # [ 0.764434] In-situ OAM (IOAM) with IPv6 machine # [ 0.764459] NET: Registered PF_PACKET protocol family machine # [ 0.764575] 9pnet: Installing 9P2000 support machine # [ 0.764627] Key type dns_resolver registered machine # [ 0.787055] registered taskstats version 1 machine # [ 0.787233] Loading compiled-in X.509 certificates machine # [ 0.802598] Demotion targets for Node 0: null machine # [ 0.802884] Key type .fscrypt registered machine # [ 0.802889] Key type fscrypt-provisioning registered machine # [ 0.803005] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.803023] ima: Allocated hash algorithm: sha1 machine # [ 0.803044] ima: No architecture policies found machine # [ 0.836307] clk: Disabling unused clocks machine # [ 0.836325] PM: genpd: Disabling unused power domains machine # [ 0.986567] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.018677] Freeing initrd memory: 26448K machine # [ 1.021637] Freeing unused kernel memory: 4736K machine # [ 1.021810] Run /init as init process machine # [ 1.043932] fuse: init (API version 7.45) machine # [ 1.047546] virtiofs virtio8: discovered new tag: nix-store machine # [ 1.048320] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 1.064264] virtiofs virtio9: discovered new tag: shared machine # [ 1.065184] virtiofs virtio9: virtio_fs_setup_dax: No cache capability machine # [ 1.066516] virtiofs virtio10: discovered new tag: xchg machine # [ 1.069530] virtiofs virtio10: virtio_fs_setup_dax: No cache capability machine # [ 1.083234] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.083727] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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) machine # [ 1.083741] systemd[1]: Detected virtualization kvm. machine # [ 1.083764] systemd[1]: Detected architecture arm64. machine # [ 1.083770] systemd[1]: Running in initrd. machine # [ 1.084078] systemd[1]: Initializing machine ID from random generator. machine # [ 1.084176] systemd[1]: Hostname set to . machine # [ 1.162403] input: QEMU QEMU USB Keyboard as /devices/pci0000:00/0000:00:09.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.184834] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.244212] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.246980] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:09.0-1/input0 machine # [ 1.252983] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.253231] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.253261] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.253272] systemd[1]: Expecting device /dev/tpm0... machine # [ 1.253281] systemd[1]: Expecting device /dev/tpmrm0... machine # [ 1.253309] systemd[1]: Reached target Path Units. machine # [ 1.253322] systemd[1]: Reached target Slice Units. machine # [ 1.253338] systemd[1]: Reached target Swaps. machine # [ 1.253352] systemd[1]: Reached target Timer Units. machine # [ 1.253517] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.253662] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.253781] systemd[1]: Listening on Journal Sockets. machine # [ 1.253852] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.253865] systemd[1]: Reached target Socket Units. machine # [ 1.265452] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.265528] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.271295] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.282779] systemd[1]: Starting Journal Service... machine # [ 1.290941] systemd[1]: Starting Load Kernel Modules... machine # [ 1.305845] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.322753] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.349257] systemd-journald[70]: Collecting audit messages is disabled. machine # [ 1.362772] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.364313] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.366567] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.385438] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.398583] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.404538] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.410910] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.420833] [drm] pci: virtio-gpu-pci detected at 0000:00:07.0 machine # [ 1.421097] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.421101] [drm] features: -context_init machine # [ 1.421872] [drm] number of scanouts: 1 machine # [ 1.421883] [drm] number of cap sets: 0 machine # [ 1.434998] virtio-pci 0000:00:07.0: [drm] Registered 1 planes with drm panic machine # [ 1.435010] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:07.0 on minor 0 machine # [ 1.452985] Console: switching to colour frame buffer device 160x50 machine # [ 1.471137] virtio-pci 0000:00:07.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.476989] systemd[1]: Finished Load Kernel Modules. machine # [ 1.498788] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.499980] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.500197] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.500228] systemd[1]: Reached target Local File Systems. machine # [ 1.505959] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.527374] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:09.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.527651] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:09.0-2/input0 machine # [ 1.558918] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.560536] systemd[1]: Started Journal Service. machine # [ 1.529112] systemd-modules-load[71]: Inserted module 'dm_mod' machine # [ 1.537594] systemd-modules-load[71]: Module 'virtio_balloon' is built in machine # [ 1.548432] systemd-modules-load[71]: Module 'virtio_console' is built in machine # [ 1.549651] systemd-modules-load[71]: Inserted module 'virtio_gpu' machine # [ 1.550850] systemd-modules-load[71]: Module 'virtio_rng' is built in machine # [ 1.551874] systemd[1]: Starting Create System Files and Directories... machine # [ 1.597613] systemd[1]: Finished Create System Files and Directories. machine # [ 1.600288] systemd-udevd[78]: Using default interface naming scheme 'v261'. machine # [ 1.624270] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.720107] systemd[1]: Starting Virtual Console Setup... machine # [ 1.788521] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.792357] systemd[1]: Finished Virtual Console Setup. machine # [ 2.469386] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.607484] (udev-worker)[92]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.649025] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.704837] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.708466] systemd[1]: Reached target Initrd Root Device. machine # [ 2.712340] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.762831] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.770707] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.776185] systemd[1]: Mounting /sysroot... machine # [ 2.842160] EXT4-fs (vda): mounted filesystem 553f4291-d1b9-4db9-b75b-8c04cea0da88 r/w with ordered data mode. Quota mode: none. machine # [ 2.817465] systemd[1]: Mounted /sysroot. machine # [ 2.822814] systemd[1]: Reached target Initrd Root File System. machine # [ 2.828148] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.859865] systemd-sysroot-fstab-check[116]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.868087] systemd[1]: Reload requested from client PID 116 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.873510] systemd[1]: Reloading... machine # [ 3.093176] systemd[1]: Reloading finished in 221 ms. machine # [ 3.126286] systemd-sysroot-fstab-check[116]: Requesting initrd-fs.target/start/replace... machine # [ 3.142339] systemd-sysroot-fstab-check[116]: Requesting swap.target/start/replace... machine # [ 3.147250] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.150757] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.153342] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.279947] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.299617] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.306827] systemd[1]: Mounting /sysroot/run... machine # [ 3.332269] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.347094] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.379996] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.398384] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.423330] systemd[1]: Mounted /sysroot/run. machine # [ 3.431937] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.448725] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.451792] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.481022] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.482610] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.066029] tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x1, rev-id 1) machine # [ 4.172733] systemd[1]: Found device /dev/tpm0. machine # [ 4.190847] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.197529] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.198375] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.204148] systemd[1]: Starting Virtual Console Setup... machine # [ 4.223884] systemd[1]: Found device /dev/tpmrm0. machine # [ 4.229921] systemd[1]: Reached target Trusted Platform Module. machine # [ 4.233266] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 4.236280] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.239325] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.240630] systemd[1]: Starting Virtual Console Setup... machine # [ 4.271169] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.275608] systemd[1]: Finished Virtual Console Setup. machine # [ 4.277739] systemd[1]: Reached target System Initialization. machine # [ 4.280163] systemd[1]: Reached target Basic System. machine # [ 4.281389] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.320332] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.321230] systemd[1]: Reached target Initrd File Systems. machine # [ 4.322079] systemd[1]: Starting Find NixOS closure... machine # [ 4.323901] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.354115] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.357827] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.370358] systemd[1]: Finished Find NixOS closure. machine # [ 4.372250] systemd[1]: Reached target Initrd Default Target. machine # [ 4.374155] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.396751] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.398682] systemd[1]: Stopped target Basic System. machine # [ 4.399774] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.404448] systemd[1]: Stopped target Path Units. machine # [ 4.405211] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.406324] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.407538] systemd[1]: Stopped target Slice Units. machine # [ 4.410210] systemd[1]: Stopped target Socket Units. machine # [ 4.414241] systemd[1]: Stopped target System Initialization. machine # [ 4.421222] systemd[1]: Stopped target Swaps. machine # [ 4.422869] systemd[1]: Stopped target Timer Units. machine # [ 4.424572] systemd[1]: Stopped target Trusted Platform Module. machine # [ 4.428137] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.431438] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.432567] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.436327] systemd[1]: Stopped Find NixOS closure. machine # [ 4.439160] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.441724] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.443736] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.444685] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.445755] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.446637] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.447802] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.454825] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.463095] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.464910] systemd[1]: Stopped target Local File Systems. machine # [ 4.465799] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.466823] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.467875] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.472287] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.474195] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.478835] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.479811] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.484555] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.488173] systemd[1]: systemd-udevd.service: Consumed 1.623s CPU time over 2.968s wall clock time, 22.9M memory peak. machine # [ 4.492437] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.493700] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.496230] systemd[1]: Starting Cleanup udev Database... machine # [ 4.500163] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.501392] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.502335] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.508091] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.509179] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.510202] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.511215] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.516138] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.545831] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.547154] systemd[1]: Finished Cleanup udev Database. machine # [ 4.552215] systemd[1]: Reached target Switch Root. machine # [ 4.553095] systemd[1]: Starting NixOS Activation... machine # [ 4.642730] initrd-nixos-activation-start[176]: booting system configuration /nix/store/wlz2i6xn326ks4bfz6ic9kr96syn9116-nixos-system-machine-test machine # [ 4.677920] initrd-nixos-activation-start[176]: running activation script... machine # [ 4.919346] initrd-nixos-activation-start[199]: setting up /etc... machine # [ 5.041647] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.044769] systemd[1]: Finished NixOS Activation. machine # [ 5.046050] systemd[1]: Starting Switch Root... machine # [ 5.064952] systemd[1]: Switching root. machine # [ 5.264461] systemd-journald[70]: Received SIGTERM from PID 1 (systemd). machine # [ 5.364589] NET: Registered PF_VSOCK protocol family machine # [ 5.401657] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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) machine # [ 5.402595] systemd[1]: Detected virtualization kvm. machine # [ 5.402636] systemd[1]: Detected architecture arm64. machine # [ 5.402701] systemd[1]: Detected first boot. machine # [ 5.410744] systemd[1]: Initializing machine ID from random generator. machine # [ 5.522838] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.660057] systemd[1]: Applying preset policy. machine # [ 5.878297] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.106146] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.108021] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.110367] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.115103] systemd[1]: Created slice Slice /system/getty. machine # [ 6.116464] systemd[1]: Created slice Slice /system/systemd-fsck. machine # [ 6.117773] systemd[1]: Created slice User and Session Slice. machine # [ 6.118343] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.122898] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.123339] systemd[1]: Expecting device /dev/disk/by-label/ESP... machine # [ 6.124050] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.124731] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.125447] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.126146] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.132165] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.132601] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.133514] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.134248] systemd[1]: Reached target Path Units. machine # [ 6.138654] systemd[1]: Reached target Remote File Systems. machine # [ 6.139043] systemd[1]: Reached target Slice Units. machine # [ 6.139776] systemd[1]: Reached target Swaps. machine # [ 6.141936] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.147943] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.149495] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.152515] systemd[1]: Listening on Factory Reset Management. machine # [ 6.153036] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.158039] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.161118] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.163903] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.164682] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.165403] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.165990] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.176768] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.177455] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.182391] systemd[1]: Mounting Huge Pages File System... machine # [ 6.194681] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.202398] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.214343] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.227580] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.228140] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.256909] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.257362] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.257623] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.257894] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.298062] systemd[1]: Mounting FUSE Control File System... machine # [ 6.310861] systemd[1]: Starting seed-system-profile.service... machine # [ 6.312262] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.334692] systemd[1]: Starting Journal Service... machine # [ 6.352204] systemd[1]: Starting Load Kernel Modules... machine # [ 6.378621] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.388457] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.388856] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.409544] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.437318] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.437824] systemd[1]: Mounted Huge Pages File System. machine # [ 6.438256] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.446483] systemd-journald[271]: Collecting audit messages is enabled. machine # [ 6.431286] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.442724] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.471639] systemd[1]: Started Journal Service. machine # [ 6.446571] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.447697] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.457978] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.459251] systemd[1]: Mounted Kernel Configuration File System. machine # [ 6.471713] systemd[1]: Finished seed-system-profile.service. machine # [ 6.475980] systemd-modules-load[272]: Module 'atkbd' is built in machine # [ 6.481441] systemd-modules-load[272]: Module 'loop' is built in machine # [ 6.493551] systemd[1]: Finished Load Kernel Modules. machine # [ 6.510329] systemd[1]: Starting Firewall... machine # [ 6.539831] EXT4-fs (vda): re-mounted 553f4291-d1b9-4db9-b75b-8c04cea0da88. machine # [ 6.516445] systemd[1]: Starting Apply Kernel Variables... machine # [ 6.539896] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 6.566559] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.576470] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.588212] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 6.589326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.604703] systemd-oomd[274]: No swap; memory pressure usage will be degraded machine # [ 6.623992] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 6.626223] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.634548] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.641324] systemd[1]: Mounted FUSE Control File System. machine # [ 6.768878] systemd-journald[271]: Received client request to flush runtime journal. machine # [ 6.816901] systemd[1]: Finished Apply Kernel Variables. machine # [ 6.817863] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 6.818709] systemd[1]: Reached target First Boot Complete. machine # [ 6.827295] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 6.849130] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 6.856599] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.977121] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 6.984089] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 6.987643] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.083354] systemd[1]: Mounting /run/wrappers... machine # [ 7.134939] systemd-udevd[315]: Using default interface naming scheme 'v261'. machine # [ 7.162235] systemd[1]: Mounted /run/wrappers. machine # [ 7.169873] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.285640] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.877261] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 7.884307] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.188996] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.228245] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.256604] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 8.471434] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 8.489966] systemd[1]: Found device /dev/tpmrm0. machine # [ 8.490889] systemd[1]: Expecting device /dev/tpm0... machine # [ 8.526998] (udev-worker)[345]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 8.535345] (udev-worker)[345]: Network interface NamePolicy= disabled on kernel command line. machine # [ 8.540798] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 8.671197] systemd[1]: Finished Firewall. machine # [ 8.713271] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 8.719801] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.723572] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.728397] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.730730] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.784174] systemd[1]: Condition check resulted in /dev/disk/by-label/ESP being skipped. machine # [ 8.790407] systemd[1]: Starting File System Check on /dev/disk/by-label/ESP... machine # [ 8.894559] systemd-fsck[486]: fsck.fat 4.2 (2021-01-31) machine # [ 8.895486] systemd-fsck[486]: /dev/vdc1: 20 files, 29138/66398 clusters machine # [ 8.908412] systemd[1]: Finished File System Check on /dev/disk/by-label/ESP. machine # [ 8.919413] systemd[1]: Mounting /boot... machine # [ 9.014902] systemd[1]: Mounted /boot. machine # [ 9.018295] systemd[1]: Reached target Local File Systems. machine # [ 9.024743] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.032241] systemd[1]: Starting Update Boot Loader Random Seed... machine # [ 9.040794] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.053939] systemd[1]: Starting Create System Files and Directories... machine # [ 9.143762] bootctl[507]: Mount point '/boot' which backs the random seed file is world accessible, which is a security hole! machine # [ 9.159690] bootctl[507]: Random seed file '/boot/loader/random-seed' is world accessible, which is a security hole! machine # [ 9.173076] bootctl[507]: Random seed file /boot/loader/random-seed successfully refreshed (32 bytes). machine # [ 9.182410] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.191374] bootctl[507]: Successfully initialized system token in EFI variable with 32 bytes. machine # [ 9.198730] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.205005] systemd[1]: Finished Update Boot Loader Random Seed. machine # [ 9.254070] systemd[1]: Finished Create System Files and Directories. machine # [ 9.258948] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.265215] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.357901] mousedev: PS/2 mouse device common for all mice machine # [ 9.357560] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.392886] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 9.402035] systemd[1]: Starting Update is Completed... machine # [ 9.462920] systemd[1]: Finished Update is Completed. machine # [ 9.538484] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.540275] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 10.330841] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:08.0/virtio7/input/input3 machine # [ 10.380948] systemd[1]: Found device /dev/tpm0. machine # [ 10.385103] systemd[1]: Reached target Trusted Platform Module. machine # [ 10.386761] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 10.393353] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 10.398010] systemd[1]: Reached target System Initialization. machine # [ 10.404214] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.406829] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.407847] systemd[1]: Reached target Timer Units. machine # [ 10.410451] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.414207] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.420802] systemd[1]: Reached target Socket Units. machine # [ 10.422632] systemd[1]: Reached target Basic System. machine # [ 10.424608] systemd[1]: Started backdoor.service. machine # [ 10.426558] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.430775] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.438621] systemd[1]: Starting Post-Boot Actions... machine # [ 10.463867] systemd[1]: Started Reset console on configuration changes. machine # [ 10.495236] systemd[1]: Starting resolvconf update... machine # [ 10.571790] systemd[1]: Starting D-Bus System Message Bus... machine # [ 10.593075] systemd[1]: Finished Post-Boot Actions. machine # connecting to host... machine # [ 10.631282] nsncd[570]: Sep 27 03:14:05.428 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.648659] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.669151] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.677048] systemd[1]: Reached target User and Group Name Lookups. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 10.699992] systemd[1]: Starting User Login Management... machine: connected to guest root shell machine: (connecting took 24.89 seconds) machine: (finished: waiting for the VM to finish booting, in 25.27 seconds) machine # [ 10.713224] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 10.840585] dbus-broker-launch[578]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.909650] systemd-logind[596]: New seat seat0. machine # [ 10.914057] systemd-logind[596]: Watching system buttons on /dev/input/event0 (Power Button) machine # [ 10.917312] systemd-logind[596]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 10.954248] systemd-logind[596]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 10.977303] systemd[1]: Started User Login Management. machine # [ 10.998054] dbus-broker-launch[578]: NSS returned no entry for 'systemd-timesync' machine # [ 10.999307] dbus-broker-launch[578]: Invalid user-name in /nix/store/f16aal2y5lya3pjhviz68x6qgs2x3nq4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 11.030760] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 11.031950] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 11.038384] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 11.039501] systemd[1]: Stopping User and Group Name Lookups... machine # [ 11.049254] systemd[1]: Starting linger-users.service... machine # [ 11.050160] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 11.058339] systemd[1]: Starting Virtual Console Setup... machine # [ 11.059330] systemd[1]: Started D-Bus System Message Bus. machine # [ 11.063865] systemd[1]: nscd.service: Deactivated successfully. machine # [ 11.067367] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 11.092600] dbus-broker-launch[578]: Ready machine # [ 11.136963] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.167438] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 11.171739] systemd[1]: Finished linger-users.service. machine # [ 11.178952] systemd[1]: Finished resolvconf update. machine # [ 11.182534] systemd[1]: Reached target Preparation for Network. machine # [ 11.195132] systemd[1]: Starting DHCP Client... machine # [ 11.203029] systemd[1]: Starting Address configuration of eth1... machine # [ 11.225410] nsncd[665]: Sep 27 03:14:06.022 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.229081] systemd[1]: Starting Extra networking commands.... machine # [ 11.231449] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.236501] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.239729] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.406220] network-addresses-eth1-start[674]: adding address 192.168.1.1/24... done machine # [ 11.428890] network-addresses-eth1-start[674]: adding address 2001:db8:1::1/64... done machine # [ 11.463545] systemd[1]: Finished Address configuration of eth1. machine # [ 11.521450] dhcpcd[693]: dhcpcd-10.3.2 starting machine # [ 11.530625] dhcpcd[741]: dev: loaded udev machine # [ 11.596551] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.597025] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 11.580505] systemd[1]: Finished Extra networking commands.. machine # [ 11.585282] systemd[1]: Reached target Network. machine # [ 11.594643] systemd[1]: Starting Permit User Sessions... machine # [ 11.627257] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 11.688090] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 11.679645] systemd[1]: Finished Permit User Sessions. machine # [ 11.687243] systemd[1]: Started Getty on tty1. machine # [ 11.692200] systemd[1]: Reached target Login Prompts. machine # [ 11.731770] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.732279] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.733332] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.733654] cfg80211: failed to load regulatory.db machine # [ 11.758550] dhcpcd[741]: eth0: waiting for carrier machine # [ 11.788037] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 11.762467] dhcpcd[741]: eth0: carrier acquired machine # [ 11.768397] systemd-vconsole-setup[651]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 11.774654] systemd[1]: Finished Virtual Console Setup. machine # [ 11.778459] dhcpcd[741]: DUID 00:01:00:01:32:4b:46:7e:52:54:00:12:34:56 machine # [ 11.779468] dhcpcd[741]: eth0: IAID 00:12:34:56 machine # [ 11.780573] dhcpcd[741]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 13.040581] dhcpcd[741]: eth0: soliciting a DHCP lease machine # [ 13.044899] dhcpcd[741]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 13.052520] dhcpcd[741]: eth0: probing address 10.0.2.15/24 machine # [ 13.508324] dhcpcd[741]: eth0: soliciting an IPv6 router machine # [ 13.510675] dhcpcd[741]: eth0: Router Advertisement from fe80::2 machine # [ 13.512119] dhcpcd[741]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.513621] dhcpcd[741]: eth0: adding route to fec0::/64 machine # [ 13.514817] dhcpcd[741]: eth0: adding default route via fe80::2 machine # [ 17.918677] dhcpcd[741]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 17.922123] dhcpcd[741]: eth0: adding route to 10.0.2.0/24 machine # [ 17.923425] dhcpcd[741]: eth0: adding default route via 10.0.2.2 machine # [ 18.034930] systemd[1]: Started DHCP Client. machine # [ 18.038091] systemd[1]: Reached target Multi-User System. machine # [ 18.039814] systemd[1]: Startup finished in 7.189s (firmware) + 6.315s (loader) + 996ms (kernel) + 4.314s (initrd) + 12.728s (userspace) = 31.543s. machine: (finished: waiting for unit multi-user.target, in 33.20 seconds) machine: must succeed: echo -n supersecret | cryptsetup luksFormat -q --iter-time=1 /dev/vdb - machine: (finished: must succeed: echo -n supersecret | cryptsetup luksFormat -q --iter-time=1 /dev/vdb -, in 2.66 seconds) machine: must succeed: echo -n supersecret | cryptsetup luksOpen -q /dev/vdb cryptroot machine # [ 21.889317] Key type trusted registered machine # [ 21.896399] Key type encrypted registered machine: (finished: must succeed: echo -n supersecret | cryptsetup luksOpen -q /dev/vdb cryptroot, in 0.63 seconds) machine: must succeed: mkfs.ext4 /dev/mapper/cryptroot machine # mke2fs 1.47.4 (6-Mar-2025) machine: (finished: must succeed: mkfs.ext4 /dev/mapper/cryptroot, in 0.07 seconds) machine: must succeed: PASSWORD=supersecret SYSTEMD_LOG_LEVEL=debug systemd-cryptenroll --tpm2-pcrs= --tpm2-device=auto /dev/vdb |& systemd-cat machine # [ 22.042740] cat[925]: Successfully called mlockall(). machine # [ 22.043690] cat[925]: Failed to find TPM2 pcrlock policy file 'pcrlock.json': No such file or directory machine # [ 22.049082] cat[925]: Loaded shared library 'libcryptsetup.so.12' via dlopen(). machine # [ 22.050249] cat[925]: Allocating context for crypt device /dev/vdb. machine # [ 22.051187] cat[925]: Trying to open device /dev/vdb with direct-io. machine # [ 22.052822] cat[925]: Initialising device-mapper backend library. machine # [ 22.054514] cat[925]: Trying to load LUKS2 crypt type from device /dev/vdb. machine # [ 22.057726] cat[925]: Crypto backend (OpenSSL 3.6.4 25 Aug 2026 [default][legacy][threads][argon2]) initialized in cryptsetup library version 2.8.7. machine # [ 22.059614] cat[925]: Detected kernel Linux 6.18.53 aarch64. machine # [ 22.060649] cat[925]: Loading LUKS2 header (repair disabled). machine # [ 22.068121] cat[925]: Acquiring read lock for device /dev/vdb. machine # [ 22.069091] cat[925]: Opening lock resource file /run/cryptsetup/L_254:16 machine # [ 22.070085] cat[925]: Verifying lock handle for /dev/vdb. machine # [ 22.070980] cat[925]: Device /dev/vdb READ lock taken. machine # [ 22.071734] cat[925]: Trying to read primary LUKS2 header at offset 0x0. machine # [ 22.076117] cat[925]: Opening locked device /dev/vdb machine # [ 22.076882] cat[925]: Verifying locked device handle (bdev) machine # [ 22.077689] cat[925]: LUKS2 header version 2 of size 16384 bytes, checksum sha256. machine # [ 22.078841] cat[925]: Checksum:273fcbb2e1e85178932491324367523ead128633551321bc35b00836e501ee66 (on-disk) machine # [ 22.084090] cat[925]: Checksum:273fcbb2e1e85178932491324367523ead128633551321bc35b00836e501ee66 (in-memory) machine # [ 22.085526] cat[925]: Trying to read secondary LUKS2 header at offset 0x4000. machine # [ 22.086595] cat[925]: Reusing open ro fd on device /dev/vdb machine # [ 22.087404] cat[925]: LUKS2 header version 2 of size 16384 bytes, checksum sha256. machine # [ 22.092084] cat[925]: Checksum:575c7ab10290c32618eb0627b89fd23c25359de8bb5b5a9383c59e1049d82400 (on-disk) machine # [ 22.093406] cat[925]: Checksum:575c7ab10290c32618eb0627b89fd23c25359de8bb5b5a9383c59e1049d82400 (in-memory) machine # [ 22.094821] cat[925]: Device size 536870912, offset 16777216. machine # [ 22.095626] cat[925]: Device /dev/vdb READ lock released. machine # [ 22.100079] cat[925]: Only 1 active CPUs detected, PBKDF threads decreased from 4 to 1. machine # [ 22.101222] cat[925]: Not enough physical memory detected, PBKDF max memory decreased from 1048576kB to 346002kB. machine # [ 22.102709] cat[925]: PBKDF argon2id, time_ms 2000 (iterations 0), max_memory_kb 346002, parallel_threads 1. machine # [ 22.108085] cat[925]: Requesting JSON for token 0. machine # [ 22.108824] cat[925]: Requesting JSON for token 1. machine # [ 22.109532] cat[925]: Requesting JSON for token 2. machine # [ 22.110234] cat[925]: Requesting JSON for token 3. machine # [ 22.111031] cat[925]: Requesting JSON for token 4. machine # [ 22.111732] cat[925]: Requesting JSON for token 5. machine # [ 22.120132] cat[925]: Requesting JSON for token 6. machine # [ 22.120882] cat[925]: Requesting JSON for token 7. machine # [ 22.121592] cat[925]: Requesting JSON for token 8. machine # [ 22.122293] cat[925]: Requesting JSON for token 9. machine # [ 22.123038] cat[925]: Requesting JSON for token 10. machine # [ 22.123756] cat[925]: Requesting JSON for token 11. machine # [ 22.128122] cat[925]: Requesting JSON for token 12. machine # [ 22.128884] cat[925]: Requesting JSON for token 13. machine # [ 22.129591] cat[925]: Requesting JSON for token 14. machine # [ 22.130310] cat[925]: Requesting JSON for token 15. machine # [ 22.131031] cat[925]: Requesting JSON for token 16. machine # [ 22.131734] cat[925]: Requesting JSON for token 17. machine # [ 22.136083] cat[925]: Requesting JSON for token 18. machine # [ 22.136816] cat[925]: Requesting JSON for token 19. machine # [ 22.137524] cat[925]: Requesting JSON for token 20. machine # [ 22.138232] cat[925]: Requesting JSON for token 21. machine # [ 22.138954] cat[925]: Requesting JSON for token 22. machine # [ 22.139698] cat[925]: Requesting JSON for token 23. machine # [ 22.144085] cat[925]: Requesting JSON for token 24. machine # [ 22.144827] cat[925]: Requesting JSON for token 25. machine # [ 22.145567] cat[925]: Requesting JSON for token 26. machine # [ 22.146266] cat[925]: Requesting JSON for token 27. machine # [ 22.147020] cat[925]: Requesting JSON for token 28. machine # [ 22.147734] cat[925]: Requesting JSON for token 29. machine # [ 22.152088] cat[925]: Requesting JSON for token 30. machine # [ 22.152847] cat[925]: Requesting JSON for token 31. machine # [ 22.153553] cat[925]: Keyslot 0 priority 1 != 2 (required), skipped. machine # [ 22.154440] cat[925]: Trying to open LUKS2 keyslot 0. machine # [ 22.155176] cat[925]: Running keyslot key derivation. machine # [ 22.446489] cat[925]: Reading keyslot area [0x8000]. machine # [ 22.448771] cat[925]: Acquiring read lock for device /dev/vdb. machine # [ 22.452123] cat[925]: Opening lock resource file /run/cryptsetup/L_254:16 machine # [ 22.453129] cat[925]: Verifying lock handle for /dev/vdb. machine # [ 22.453893] cat[925]: Device /dev/vdb READ lock taken. machine # [ 22.454738] cat[925]: Reusing open ro fd on device /dev/vdb machine # [ 22.455549] cat[925]: Device /dev/vdb READ lock released. machine # [ 22.459424] cat[925]: Verifying key from keyslot 0, digest 0. machine # [ 22.524382] cat[925]: Failed to find TPM PCR public key file 'tpm2-pcr-public-key.pem': No such file or directory machine # [ 22.525925] cat[925]: Failed to read TPM2 PCR public key, proceeding without: No such file or directory machine # [ 22.528941] cat[925]: Loaded shared library 'libtss2-esys.so.0' via dlopen(). machine # [ 22.530594] cat[925]: Loaded shared library 'libtss2-rc.so.0' via dlopen(). machine # [ 22.531591] cat[925]: Loaded shared library 'libtss2-mu.so.0' via dlopen(). machine # [ 22.533381] cat[925]: Loaded shared library 'libtss2-tcti-device.so.0' via dlopen(). machine # [ 22.535089] cat[925]: Using TPM2 TCTI driver 'device' with device '/dev/tpmrm0'. machine # [ 22.536671] cat[925]: Loaded 'libtss2-tcti-device.so.0' via dlopen() machine # [ 22.538902] cat[925]: Loaded TCTI module 'tcti-device' (TCTI module for communication with Linux kernel interface.) [Version 2] machine # [ 22.540723] cat[925]: TPM successfully started up. machine # [ 22.541426] cat[925]: Getting TPM2 capability 0x0000 property 0x0001 count 127. machine # [ 22.542585] cat[925]: Getting TPM2 capability 0x0002 property 0x011f count 256. machine # [ 22.546696] cat[925]: Getting TPM2 capability 0x0008 property 0x0000 count 508. machine # [ 22.549215] cat[925]: Getting TPM2 capability 0x0005 property 0x0000 count 1. machine # [ 22.551823] cat[925]: Getting TPM2 capability 0x0006 property 0x012c count 1. machine # [ 22.557180] cat[925]: Added 32 bytes of TPM2 entropy to the kernel random pool in 1.484ms. machine # [ 22.558453] cat[925]: Getting TPM2 capability 0x0001 property 0x81000001 count 1. machine # [ 22.560941] cat[925]: TPM handle 0x81000001 not populated. machine # [ 22.563186] cat[925]: Creating primary key on TPM. machine # [ 22.572948] cat[925]: Successfully created primary key on TPM in 9ms. machine # [ 22.583173] cat[925]: Getting TPM2 capability 0x0001 property 0x81000001 count 1. machine # [ 22.618776] cat[925]: Starting HMAC encryption session. machine # [ 22.632139] cat[925]: Generating secret key data. machine # [ 22.636065] cat[925]: Creating HMAC key on TPM for shard 0. machine # [ 22.637189] cat[925]: Creating object on TPM. machine # [ 22.651422] cat[925]: Successfully created object on TPM in 17ms. machine # [ 22.655756] cat[925]: Marshalling private and public part of HMAC key for shard 0. machine # [ 22.657035] cat[925]: Completed TPM2 key sealing in 95.780ms. machine # [ 22.660676] cat[925]: Requesting JSON for token 0. machine # [ 22.661515] cat[925]: Requesting JSON for token 1. machine # [ 22.664799] cat[925]: Requesting JSON for token 2. machine # [ 22.665665] cat[925]: Requesting JSON for token 3. machine # [ 22.666971] cat[925]: Requesting JSON for token 4. machine # [ 22.667755] cat[925]: Requesting JSON for token 5. machine # [ 22.670948] cat[925]: Requesting JSON for token 6. machine # [ 22.671769] cat[925]: Requesting JSON for token 7. machine # [ 22.672560] cat[925]: Requesting JSON for token 8. machine # [ 22.673307] cat[925]: Requesting JSON for token 9. machine # [ 22.674052] cat[925]: Requesting JSON for token 10. machine # [ 22.674813] cat[925]: Requesting JSON for token 11. machine # [ 22.675566] cat[925]: Requesting JSON for token 12. machine # [ 22.681957] cat[925]: Requesting JSON for token 13. machine # [ 22.682778] cat[925]: Requesting JSON for token 14. machine # [ 22.684393] cat[925]: Requesting JSON for token 15. machine # [ 22.685120] cat[925]: Requesting JSON for token 16. machine # [ 22.686316] cat[925]: Requesting JSON for token 17. machine # [ 22.687150] cat[925]: Requesting JSON for token 18. machine # [ 22.690035] cat[925]: Requesting JSON for token 19. machine # [ 22.690797] cat[925]: Requesting JSON for token 20. machine # [ 22.691750] cat[925]: Requesting JSON for token 21. machine # [ 22.694470] cat[925]: Requesting JSON for token 22. machine # [ 22.695181] cat[925]: Requesting JSON for token 23. machine # [ 22.695880] cat[925]: Requesting JSON for token 24. machine # [ 22.700097] cat[925]: Requesting JSON for token 25. machine # [ 22.700875] cat[925]: Requesting JSON for token 26. machine # [ 22.701588] cat[925]: Requesting JSON for token 27. machine # [ 22.702296] cat[925]: Requesting JSON for token 28. machine # [ 22.703108] cat[925]: Requesting JSON for token 29. machine # [ 22.703818] cat[925]: Requesting JSON for token 30. machine # [ 22.708869] cat[925]: Requesting JSON for token 31. machine # [ 22.709707] cat[925]: PCR policy hash not yet enrolled, enrolling now. machine # [ 22.712783] cat[925]: Unsealing for verification... machine # [ 22.713522] cat[925]: Loading HMAC key into TPM for shard 0. machine # [ 22.714390] cat[925]: Loading object into TPM. machine # [ 22.715121] cat[925]: Starting HMAC encryption session. machine # [ 22.715873] cat[925]: Starting policy session. machine # [ 22.732904] cat[925]: Building sealing policy. machine # [ 22.733803] cat[925]: Acquiring policy digest. machine # [ 22.755844] cat[925]: Session policy digest: 0000000000000000000000000000000000000000000000000000000000000000 machine # [ 22.760148] cat[925]: Unsealing HMAC key for shard 0. machine # [ 22.828093] cat[925]: Completed TPM2 key unsealing in 162.741ms. machine # [ 22.829259] cat[925]: PBKDF pbkdf2-sha512, time_ms 0 (iterations 1000). machine # [ 22.830462] cat[925]: Adding new keyslot -1 by passphrase, volume key provided by key (-1). machine # [ 22.831962] cat[925]: Selected keyslot 1. machine # [ 22.897416] cat[925]: Keyslot 1 assigned to digest 0. machine # [ 22.898668] cat[925]: Trying to allocate LUKS2 keyslot 1. machine # [ 22.899525] cat[925]: Found area 290816 -> 548864 machine # [ 22.900490] cat[925]: Reusing PBKDF values (no benchmark flag is set). machine # [ 22.904124] cat[925]: Calculating attributes for LUKS2 keyslot 1. machine # [ 22.905026] cat[925]: Acquiring write lock for device /dev/vdb. machine # [ 22.905892] cat[925]: Opening lock resource file /run/cryptsetup/L_254:16 machine # [ 22.906830] cat[925]: Verifying lock handle for /dev/vdb. machine # [ 22.907608] cat[925]: Device /dev/vdb WRITE lock taken. machine # [ 22.912073] cat[925]: Checking context sequence id matches value stored on disk. machine # [ 22.913121] cat[925]: Reusing open ro fd on device /dev/vdb machine # [ 22.913917] cat[925]: Running keyslot key derivation. machine # [ 22.915396] cat[925]: Updating keyslot area [0x47000]. machine # [ 22.916811] cat[925]: Opening locked device /dev/vdb machine # [ 22.917608] cat[925]: Verifying locked device handle (bdev) machine # [ 22.918787] cat[925]: Device size 536870912, offset 16777216. machine # [ 22.919739] cat[925]: Device /dev/vdb WRITE lock already held. machine # [ 22.920857] cat[925]: Trying to write LUKS2 header (1039 bytes) at offset 0. machine # [ 22.921936] cat[925]: Reusing open rw fd on device /dev/vdb machine # [ 22.922986] cat[925]: Checksum:42dd3f264108edae3cfb624c3046974e94cb9efb65a57a2587b0910f795cb344 (in-memory) machine # [ 22.925091] cat[925]: Trying to write LUKS2 header (1039 bytes) at offset 16384. machine # [ 22.926455] cat[925]: Reusing open rw fd on device /dev/vdb machine # [ 22.927374] cat[925]: Checksum:e0199a42da6d7fa12f1a324a6b9427fc173794a1f706dcd08c1c0014e179bfef (in-memory) machine # [ 22.928828] cat[925]: Device /dev/vdb WRITE lock released. machine # [ 22.930376] cat[925]: Unknown hash algorithm id 0x0 machine # [ 22.931336] cat[925]: Adding token text <{"type":"systemd-tpm2","keyslots":["1"],"tpm2-blob":"AJ4AIH3rGrRkHLWQh2PYGYCrTyLczmkVoQPomK5vOWva8a3GABDbfFU6by8I/rhbgHJqRTBXMVk6VZc2fgTyn8e1panZqfG1jQo7OOTHzYbqBMUkgtkyrKDsI7+LW14UOJo7Wxv7EC7XH5MC8WwSjbs+wgSFTD6Atjvy5csf25SMK2iIzeBzqK1svTT+rpKorGOC/KbtHXtuarjY7FnEQgBOAAgACwAABBIAIAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAABAAIK6wJh+jOjzVyX9cMt1it2bnvw635CneAm/GieK4OCX5","tpm2-pcrs":[],"tpm2-primary-alg":"ecc","tpm2-policy-hash":"0000000000000000000000000000000000000000000000000000000000000000","tpm2_srk":"gQAAAQAiAAvQpXDiv3P+/y8dJz8Wl8y88kQeG/xkaJbGP92KHIQlbwAAAAEAWgAjAAsAAwRyAAAABgCAAEMAEAADABAAIIZGyb2eD1/80h9WTjyPjOoZG7e11M5ntdPQ7lDslRwjACD+r1OIpZiFG4H5e0RZRT/9W2Vwi9CXLtb1CV+3OMBTYQ=="}> machine # [ 22.949648] cat[925]: Updating JSON for token -1. machine # [ 22.950550] cat[925]: Trying to load libcryptsetup-token-systemd-tpm2.so. machine # [ 22.951563] cat[925]: Loading symbol cryptsetup_token_open@CRYPTSETUP_TOKEN_1.0. machine # [ 22.956719] cat[925]: Loading symbol cryptsetup_token_buffer_free@CRYPTSETUP_TOKEN_1.0. machine # [ 22.957927] cat[925]: Loading symbol cryptsetup_token_validate@CRYPTSETUP_TOKEN_1.0. machine # [ 22.959128] cat[925]: Loading symbol cryptsetup_token_dump@CRYPTSETUP_TOKEN_1.0. machine # [ 22.964356] [925]: Loading symbol cryptsetup_token_open_pin@CRYPTSETUP_TOKEN_1.0. machine # [ 22.965425] [925]: Loading symbol cryptsetup_token_version@CRYPTSETUP_TOKEN_1.0. machine # [ 22.966545] [925]: Token handler systemd-tpm2-1.0 systemd-v263~devel (263-885fe07) loaded successfully. machine # [ 22.967804] [925]: Device size 536870912, offset 16777216. machine # [ 22.977964] [925]: Acquiring write lock for device /dev/vdb. machine # [ 22.978862] [925]: Opening lock resource file /run/cryptsetup/L_254:16 machine # [ 22.979763] [925]: Verifying lock handle for /dev/vdb. machine # [ 22.983045] [925]: Device /dev/vdb WRITE lock taken. machine # [ 22.983821] [925]: Checking context sequence id matches value stored on disk. machine # [ 22.984969] [925]: Reusing open ro fd on device /dev/vdb machine # [ 22.985721] [925]: Trying to write LUKS2 header (1742 bytes) at offset 0. machine # [ 22.986682] [925]: Reusing open rw fd on device /dev/vdb machine # [ 22.987459] [925]: Checksum:d9dc6f5de8a0f9dd2dce2282720e31b837a53e5eb7ee4472995aea9dd33cbb47 (in-memory) machine # [ 22.989014] [925]: Trying to write LUKS2 header (1742 bytes) at offset 16384. machine # [ 22.989990] [925]: Reusing open rw fd on device /dev/vdb machine # [ 22.990821] [925]: Checksum:cfa3ef5541efbf529c9b78d4faa8c679aa9745d010207215d8829d7785e1d8a7 (in-memory) machine: (finished: must succeed: PASSWORD=supersecret SYSTEMD_LOG_LEVEL=debug systemd-cryptenroll --tpm2-pcrs= --tpm2-device=auto /dev/vdb |& systemd-cat, in 1.00 seconds) machine # [ 22.993003] [925]: Device /dev/vdb WRITE lock released. machine: must succeed: /nix/store/jd0hmwxkv6izbashfvhw47v55b6r8n40-nixos-system-machine-test/bin/switch-to-configuration boot machine # [ 22.993749] [925]: New TPM2 token enrolled as key slot 1. machine # [ 22.994512] [925]: Releasing crypt device /dev/vdb context. machine # [ 22.995262] [925]: Releasing device-mapper backend. machine # [ 22.995914] [925]: Closing read only fd for /dev/vdb. machine # [ 22.996679] [925]: Closing read write fd for /dev/vdb. machine # [ 22.997363] [925]: Unloading systemd-tpm2 token handler. machine # Not checking switch inhibitors (action = boot) machine # Skipping "/boot/EFI/systemd/systemd-bootaa64.efi", same boot loader version in place already. machine # Skipping "/boot/EFI/BOOT/BOOTAA64.EFI", same boot loader version in place already. machine: (finished: must succeed: /nix/store/jd0hmwxkv6izbashfvhw47v55b6r8n40-nixos-system-machine-test/bin/switch-to-configuration boot, in 0.40 seconds) machine: must succeed: sync machine: (finished: must succeed: sync, in 0.04 seconds) machine: forced crash machine: waiting for the VM to power off additionally exposed symbols: machine, 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 machine # [2026-09-27T03:14:17Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-27T03:14:17Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-27T03:14:17Z INFO virtiofsd] Client disconnected, shutting down machine: (finished: waiting for the VM to power off, in 0.13 seconds) machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine # Starting virtiofs daemons... machine: QEMU running (pid 106) machine # [2026-09-27T03:14:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:14:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:14:18Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:14:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:14:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:14:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:14:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:14:18Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:14:18Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:14:18Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-27T03:14:18Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-27T03:14:18Z INFO virtiofsd] Client connected, servicing requests machine # UEFI firmware (version built at 00:00:00 on Jan 1 1980) machine: Guest shell says: b'\x1b[2J\x1b[001;001H\x1b[=3h\x1b[2J\x1b[001;001H\x1b[2J\x1b[001;001H\x1b[8;042;160t\x1b[2J\x1b[001;001H' machine # BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x5,0x0) machine # BdsDxe: starting Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x5,0x0) machine: Guest shell says: b'BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x5,0x0)\r\nBdsDxe: starting Boot0002 "UEFI Misc Device" from Pc' machine: Guest shell says: b'iRoot(0x0)/Pci(0x5,0x0)\r\n' machine: Guest shell says: b'\r \r\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m\x1b[2J\x1b[001;001H\x1b[020;063H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m NixOS \x1b[021;063' machine: Guest shell says: b'H\x1b[1m\x1b[30m\x1b[47m\x1b[0m\x1b[30m\x1b[47m NixOS (boot-luks) \x1b[022;063H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Reboot Into Firmware I' machine: Guest shell says: b'nterface \x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot ' machine: Guest shell says: b'in 5s. \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m----------' machine: Guest shell says: b'--------------------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 4s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 3s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 2s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[024;001H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m Boot in 1s. ' machine: Guest shell says: b' \x1b[023;060H\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m---------------------' machine: Guest shell says: b'---------------------' machine: Guest shell says: b'\x1b[1m\x1b[37m\x1b[40m\x1b[0m\x1b[37m\x1b[40m\x1b[2J\x1b[001;001H' machine # NixOS NixOS (boot-luks) Reboot Into Firmware Interface Boot in 5s. ------------------------------------------ Boot in 4s. ------------------------------------------ Boot in 3s. ------------------------------------------ Boot in 2s. ------------------------------------------ Boot in 1s. ------------------------------------------[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] efi: EFI v2.7 by EDK II machine # [ 0.000000] efi: SMBIOS 3.0=0x47d60000 TPMFinalLog=0x47fc0000 MEMATTR=0x45ae7018 ACPI 2.0=0x47ff3018 RNG=0x47ff3798 TPMEventLog=0x45a76018 INITRD=0x45a86418 MEMRESERVE=0x45ae1298 machine # [ 0.000000] random: crng init done machine # [ 0.000000] ACPI: Early table checksum verification disabled machine # [ 0.000000] ACPI: RSDP 0x0000000047FF3018 000024 (v02 BOCHS ) machine # [ 0.000000] ACPI: XSDT 0x0000000047FF3F18 000074 (v01 BOCHS BXPC 00000001 01000013) machine # [ 0.000000] ACPI: FACP 0x0000000047FF3B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: DSDT 0x0000000047FF1018 001822 (v02 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: APIC 0x0000000047FF3C98 0000B8 (v04 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: PPTT 0x0000000047FF3D98 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: GTDT 0x0000000047FF3098 000068 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: MCFG 0x0000000047FF3A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: SPCR 0x0000000047FF3E98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: DBG2 0x0000000047FF3818 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: IORT 0x0000000047FF3898 000080 (v05 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: TPM2 0x0000000047FF3998 00004C (v04 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.000000] ACPI: BGRT 0x0000000047FF3A18 000038 (v01 INTEL EDK2 00000002 01000013) machine # [ 0.000000] ACPI: SPCR: console: pl011,mmio32,0x9000000,9600 machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdf1880-0x7fdf4fff] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x0000000045b0ffff] machine # [ 0.000000] node 0: [mem 0x0000000045b10000-0x0000000045b1ffff] machine # [ 0.000000] node 0: [mem 0x0000000045b20000-0x0000000045fcffff] machine # [ 0.000000] node 0: [mem 0x0000000045fd0000-0x000000004604ffff] machine # [ 0.000000] node 0: [mem 0x0000000046050000-0x000000004609ffff] machine # [ 0.000000] node 0: [mem 0x00000000460a0000-0x00000000462affff] machine # [ 0.000000] node 0: [mem 0x00000000462b0000-0x00000000462bffff] machine # [ 0.000000] node 0: [mem 0x00000000462c0000-0x00000000463dffff] machine # [ 0.000000] node 0: [mem 0x00000000463e0000-0x0000000047bbffff] machine # [ 0.000000] node 0: [mem 0x0000000047bc0000-0x0000000047feffff] machine # [ 0.000000] node 0: [mem 0x0000000047ff0000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from ACPI. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Kernel command line: initrd=\EFI\nixos\178fjj83s7wpqzjdlc30py1sz96hc6c7-initrd-linux-6.18.53-initrd.efi init=/nix/store/jd0hmwxkv6izbashfvhw47v55b6r8n40-nixos-system-machine-test/init console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @428f0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @42900000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000042910000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000042920000 machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.000037] arm-pv: using stolen time PV machine # [ 0.000398] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000499] Console: colour dummy device 80x25 machine # [ 0.000503] printk: legacy console [tty0] enabled machine # [ 0.000732] ACPI: Core revision 20250807 machine # [ 0.000823] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000830] pid_max: default: 32768 minimum: 301 machine # [ 0.000888] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001018] landlock: Up and running. machine # [ 0.001021] Yama: becoming mindful. machine # [ 0.001488] LSM support for eBPF active machine # [ 0.001582] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001590] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002715] rcu: Hierarchical SRCU implementation. machine # [ 0.002720] rcu: Max phase no-delay instances is 1000. machine # [ 0.003431] fsl-mc MSI: ITS@0x8080000 domain created machine # [ 0.003454] Remapping and enabling EFI services. machine # [ 0.003578] smp: Bringing up secondary CPUs ... machine # [ 0.003587] smp: Brought up 1 node, 1 CPU machine # [ 0.003591] SMP: Total of 1 processors activated. machine # [ 0.003594] CPU: All CPU(s) started at EL1 machine # [ 0.003605] CPU features: detected: Branch Target Identification machine # [ 0.003610] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.003613] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.003617] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.003620] CPU features: detected: Common not Private translations machine # [ 0.003623] CPU features: detected: CRC32 instructions machine # [ 0.003626] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.003629] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.003632] CPU features: detected: Data independent timing control (DIT) machine # [ 0.003636] CPU features: detected: E0PD machine # [ 0.003638] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.003641] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.003645] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.003648] CPU features: detected: Fine Grained Traps machine # [ 0.003651] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.003656] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.003659] CPU features: detected: LSE atomic instructions machine # [ 0.003662] CPU features: detected: Privileged Access Never machine # [ 0.003664] CPU features: detected: PMUv3 machine # [ 0.003667] CPU features: detected: RAS Extension Support machine # [ 0.003670] CPU features: detected: RASv1p1 Extension Support machine # [ 0.003673] CPU features: detected: Random Number Generator machine # [ 0.003675] CPU features: detected: Speculation barrier (SB) machine # [ 0.003678] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.003681] CPU features: detected: TLB range maintenance instructions machine # [ 0.003686] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.003727] alternatives: applying system-wide alternatives machine # [ 0.006602] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.006730] Memory: 886376K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 120904K reserved, 32768K cma-reserved) machine # [ 0.006997] devtmpfs: initialized machine # [ 0.007937] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.007946] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.008117] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.008121] 0 pages in range for non-PLT usage machine # [ 0.008123] 508224 pages in range for PLT usage machine # [ 0.008196] pinctrl core: initialized pinctrl subsystem machine # [ 0.008766] SMBIOS 3.0.0 present. machine # [ 0.008771] DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 machine # [ 0.008780] DMI: Memory slots populated: 1/1 machine # [ 0.010111] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.010810] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.010950] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.011114] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.011134] audit: initializing netlink subsys (disabled) machine # [ 0.011564] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.011566] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.011569] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.011572] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.011575] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.011601] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 machine # [ 0.011614] cpuidle: using governor ladder machine # [ 0.011623] cpuidle: using governor menu machine # [ 0.011788] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.011810] ASID allocator initialised with 65536 entries machine # [ 0.012573] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.012764] Serial: AMBA PL011 UART driver machine # [ 0.018872] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.018877] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.018881] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.018883] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.018886] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.018889] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.018892] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.018895] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.019712] fbcon: Taking over console machine # [ 0.019752] ACPI: Added _OSI(Module Device) machine # [ 0.019756] ACPI: Added _OSI(Processor Device) machine # [ 0.019758] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.020777] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.026733] ACPI: Interpreter enabled machine # [ 0.026737] ACPI: Using GIC for interrupt routing machine # [ 0.026759] ACPI: MCFG table detected, 1 entries machine # [ 0.030760] ACPI: CPU0 has been hot-added machine # [ 0.031188] ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA machine # [ 0.031253] printk: console [ttyAMA0] enabled machine # [ 0.078824] ACPI: PCI: Interrupt link L000 configured for IRQ 35 machine # [ 0.078841] ACPI: PCI: Interrupt link L001 configured for IRQ 36 machine # [ 0.078853] ACPI: PCI: Interrupt link L002 configured for IRQ 37 machine # [ 0.078866] ACPI: PCI: Interrupt link L003 configured for IRQ 38 machine # [ 0.078883] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.078923] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.079010] acpi PNP0A08:00: _OSC: platform does not support [LTR] machine # [ 0.079106] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] machine # [ 0.079360] acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 machine # [ 0.079385] acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.079407] ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] machine # [ 0.079789] PCI host bridge to bus 0000:00 machine # [ 0.079816] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] machine # [ 0.079821] pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] machine # [ 0.079824] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] machine # [ 0.079829] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.079833] PCI: OF: of_root node is NULL, cannot create PCI host bridge node machine # [ 0.079890] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.080399] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.080593] pci 0000:00:01.0: BAR 0 [io 0x0220-0x023f] machine # [ 0.080607] pci 0000:00:01.0: BAR 1 [mem 0x1004d000-0x1004dfff] machine # [ 0.080636] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] machine # [ 0.080648] pci 0000:00:01.0: ROM [mem 0xfffc0000-0xffffffff pref] machine # [ 0.081157] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.082284] pci 0000:00:02.0: BAR 0 [io 0x0200-0x021f] machine # [ 0.082301] pci 0000:00:02.0: BAR 1 [mem 0x1004c000-0x1004cfff] machine # [ 0.082327] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref] machine # [ 0.082895] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.083614] pci 0000:00:03.0: BAR 0 [io 0x0100-0x017f] machine # [ 0.083627] pci 0000:00:03.0: BAR 1 [mem 0x1004b000-0x1004bfff] machine # [ 0.083653] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref] machine # [ 0.225970] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.226168] pci 0000:00:04.0: BAR 0 [io 0x0080-0x00ff] machine # [ 0.226180] pci 0000:00:04.0: BAR 1 [mem 0x1004a000-0x1004afff] machine # [ 0.226207] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref] machine # [ 0.226742] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.227577] pci 0000:00:05.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.227591] pci 0000:00:05.0: BAR 1 [mem 0x10049000-0x10049fff] machine # [ 0.227617] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref] machine # [ 0.228146] pci 0000:00:06.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.228331] pci 0000:00:06.0: BAR 0 [io 0x01e0-0x01ff] machine # [ 0.228342] pci 0000:00:06.0: BAR 1 [mem 0x10048000-0x10048fff] machine # [ 0.228368] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref] machine # [ 0.228381] pci 0000:00:06.0: ROM [mem 0xfffc0000-0xffffffff pref] machine # [ 0.239892] pci 0000:00:07.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.242373] pci 0000:00:07.0: BAR 1 [mem 0x10047000-0x10047fff] machine # [ 0.242403] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref] machine # [ 0.243079] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.243276] pci 0000:00:08.0: BAR 1 [mem 0x10046000-0x10046fff] machine # [ 0.243303] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref] machine # [ 0.243765] pci 0000:00:09.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.244467] pci 0000:00:09.0: BAR 0 [mem 0x10045000-0x10045fff] machine # [ 0.244779] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.244978] pci 0000:00:0a.0: BAR 1 [mem 0x10044000-0x10044fff] machine # [ 0.245004] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref] machine # [ 0.254986] pci 0000:00:0b.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.255179] pci 0000:00:0b.0: BAR 1 [mem 0x10043000-0x10043fff] machine # [ 0.255205] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref] machine # [ 0.255722] pci 0000:00:0c.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.255916] pci 0000:00:0c.0: BAR 1 [mem 0x10042000-0x10042fff] machine # [ 0.255942] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref] machine # [ 0.256457] pci 0000:00:0d.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.257385] pci 0000:00:0d.0: BAR 0 [io 0x0180-0x01bf] machine # [ 0.257401] pci 0000:00:0d.0: BAR 1 [mem 0x10041000-0x10041fff] machine # [ 0.257427] pci 0000:00:0d.0: BAR 4 [mem 0x800002c000-0x800002ffff 64bit pref] machine # [ 0.257966] pci 0000:00:0e.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.258735] pci 0000:00:0e.0: BAR 0 [io 0x01c0-0x01df] machine # [ 0.258747] pci 0000:00:0e.0: BAR 1 [mem 0x10040000-0x10040fff] machine # [ 0.270275] pci 0000:00:0e.0: BAR 4 [mem 0x8000030000-0x8000033fff 64bit pref] machine # [ 0.270905] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.270912] pci 0000:00:06.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.270915] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.270962] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.271005] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.271048] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.271093] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.271137] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.271182] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.282665] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.282721] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.282766] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.282813] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.282858] pci 0000:00:0d.0: BAR 4 [mem 0x800002c000-0x800002ffff 64bit pref]: assigned machine # [ 0.282904] pci 0000:00:0e.0: BAR 4 [mem 0x8000030000-0x8000033fff 64bit pref]: assigned machine # [ 0.282949] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.282968] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.282987] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.283006] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.283024] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.283042] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.283064] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.283654] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.283674] pci 0000:00:09.0: BAR 0 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.284246] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.284266] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.284284] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.284303] pci 0000:00:0d.0: BAR 1 [mem 0x1008c000-0x1008cfff]: assigned machine # [ 0.284321] pci 0000:00:0e.0: BAR 1 [mem 0x1008d000-0x1008dfff]: assigned machine # [ 0.284340] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.284908] pci 0000:00:04.0: BAR 0 [io 0x1080-0x10ff]: assigned machine # [ 0.284927] pci 0000:00:05.0: BAR 0 [io 0x1100-0x117f]: assigned machine # [ 0.306341] pci 0000:00:0d.0: BAR 0 [io 0x1180-0x11bf]: assigned machine # [ 0.307188] pci 0000:00:01.0: BAR 0 [io 0x11c0-0x11df]: assigned machine # [ 0.307208] pci 0000:00:02.0: BAR 0 [io 0x11e0-0x11ff]: assigned machine # [ 0.310084] pci 0000:00:06.0: BAR 0 [io 0x1200-0x121f]: assigned machine # [ 0.310105] pci 0000:00:0e.0: BAR 0 [io 0x1220-0x123f]: assigned machine # [ 0.310668] pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] machine # [ 0.310674] pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] machine # [ 0.310677] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] machine # [ 0.318159] iommu: Default domain type: Translated machine # [ 0.318166] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.318366] SCSI subsystem initialized machine # [ 0.318561] ACPI: bus type USB registered machine # [ 0.318596] usbcore: registered new interface driver usbfs machine # [ 0.318621] usbcore: registered new interface driver hub machine # [ 0.318634] usbcore: registered new device driver usb machine # [ 0.318848] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.318852] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.318860] PTP clock support registered machine # [ 0.318900] EDAC MC: Ver: 3.0.0 machine # [ 0.319242] scmi_core: SCMI protocol bus registered machine # [ 0.319364] efivars: Registered efivars operations machine # [ 0.319739] FPGA manager framework machine # [ 0.328926] vgaarb: loaded machine # [ 0.329178] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.329497] VFS: Disk quotas dquot_6.6.0 machine # [ 0.329518] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.331349] netfs: FS-Cache loaded machine # [ 0.331449] pnp: PnP ACPI init machine # [ 0.331651] system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved machine # [ 0.331703] pnp: PnP ACPI: found 1 devices machine # [ 0.339814] NET: Registered PF_INET protocol family machine # [ 0.339872] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.361570] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.361620] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.361645] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.361693] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.361766] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.361859] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.361889] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.361911] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.361993] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.362016] NET: Registered PF_XDP protocol family machine # [ 0.362811] PCI: CLS 0 bytes, default 64 machine # [ 0.363049] Trying to unpack rootfs image as initramfs... machine # [ 0.381270] kvm [1]: HYP mode not available machine # [ 0.484108] Initialise system trusted keyrings machine # [ 0.485852] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.486244] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.486310] 9p: Installing v9fs 9p2000 file system support machine # [ 0.514664] Key type asymmetric registered machine # [ 0.514682] Asymmetric key parser 'x509' registered machine # [ 0.514758] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.514898] io scheduler mq-deadline registered machine # [ 0.514903] io scheduler kyber registered machine # [ 0.526549] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.527798] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 machine # [ 0.527852] ACPI: button: Power Button [PWRB] machine # [ 0.557967] ACPI: \_SB_.L001: Enabled at IRQ 36 machine # [ 0.558020] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.560529] ACPI: \_SB_.L002: Enabled at IRQ 37 machine # [ 0.560562] virtio-pci 0000:00:02.0: enabling device (0005 -> 0007) machine # [ 0.565274] ACPI: \_SB_.L003: Enabled at IRQ 38 machine # [ 0.565301] virtio-pci 0000:00:03.0: enabling device (0005 -> 0007) machine # [ 0.567081] ACPI: \_SB_.L000: Enabled at IRQ 35 machine # [ 0.567109] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.582031] virtio-pci 0000:00:05.0: enabling device (0005 -> 0007) machine # [ 0.583905] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.589121] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.598900] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.609314] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0002) machine # [ 0.612578] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0002) machine # [ 0.616386] virtio-pci 0000:00:0d.0: enabling device (0005 -> 0007) machine # [ 0.619185] virtio-pci 0000:00:0e.0: enabling device (0005 -> 0007) machine # [ 0.631714] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.634193] msm_serial: driver initialized machine # [ 0.634308] SuperH (H)SCI(F) driver initialized machine # [ 0.634342] STM32 USART driver initialized machine # [ 0.659062] ACPI: bus type drm_connector registered machine # [ 0.671125] loop: module loaded machine # [ 0.671350] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.672095] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.677408] virtio_blk virtio3: 1/0/0 default/read/poll queues machine # [ 0.678121] virtio_blk virtio3: [vdb] 1048576 512-byte logical blocks (537 MB/512 MiB) machine # [ 0.683171] virtio_blk virtio4: 1/0/0 default/read/poll queues machine # [ 0.683873] virtio_blk virtio4: [vdc] 534561 512-byte logical blocks (274 MB/261 MiB) machine # [ 0.721232] vdc: vdc1 machine # [ 0.722087] megasas: 07.734.00.00-rc1 machine # [ 0.724411] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.727682] thunder_xcv, ver 1.0 machine # [ 0.727709] thunder_bgx, ver 1.0 machine # [ 0.727738] nicpf, ver 1.0 machine # [ 0.728298] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.728302] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.728327] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.728330] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.728354] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.728357] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.728385] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.728389] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.728509] sky2: driver version 1.30 machine # [ 0.738874] usbcore: registered new interface driver usb-storage machine # [ 0.738971] usbcore: registered new interface driver usbserial_generic machine # [ 0.738984] usbserial: USB Serial support registered for generic machine # [ 0.739533] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.740606] ehci-pci 0000:00:09.0: EHCI Host Controller machine # [ 0.740626] ehci-pci 0000:00:09.0: new USB bus registered, assigned bus number 1 machine # [ 0.740812] ehci-pci 0000:00:09.0: irq 45, io mem 0x10088000 machine # [ 0.747065] rtc-efi rtc-efi.0: registered as rtc0 machine # [ 0.747099] rtc-efi rtc-efi.0: setting system clock to 2026-09-27T03:14:31 UTC (1790478871) machine # [ 0.747437] i2c_dev: i2c /dev entries driver machine # [ 0.749234] ehci-pci 0000:00:09.0: USB 2.0 started, EHCI 1.00 machine # [ 0.749552] hub 1-0:1.0: USB hub found machine # [ 0.749574] hub 1-0:1.0: 6 ports detected machine # [ 0.762782] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.762794] sdhci: Copyright(c) Pierre Ossman machine # [ 0.763065] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.763410] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.764522] pstore: Using crash dump compression: deflate machine # [ 0.764527] pstore: Registered efi_pstore as persistent store backend machine # [ 0.765269] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.765546] usbcore: registered new interface driver usbhid machine # [ 0.765550] usbhid: USB HID core driver machine # [ 0.772777] hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 11 (0,800003ff) counters available machine # [ 0.774250] drop_monitor: Initializing network drop monitor service machine # [ 0.774409] NET: Registered PF_INET6 protocol family machine # [ 0.785726] Segment Routing with IPv6 machine # [ 0.785760] In-situ OAM (IOAM) with IPv6 machine # [ 0.785789] NET: Registered PF_PACKET protocol family machine # [ 0.785904] 9pnet: Installing 9P2000 support machine # [ 0.785971] Key type dns_resolver registered machine # [ 0.801751] registered taskstats version 1 machine # [ 0.801931] Loading compiled-in X.509 certificates machine # [ 0.821253] Demotion targets for Node 0: null machine # [ 0.821605] Key type .fscrypt registered machine # [ 0.821609] Key type fscrypt-provisioning registered machine # [ 0.821734] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.821752] ima: Allocated hash algorithm: sha1 machine # [ 0.821774] ima: No architecture policies found machine # [ 0.865916] clk: Disabling unused clocks machine # [ 0.865938] PM: genpd: Disabling unused power domains machine # [ 0.997226] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.060351] Freeing initrd memory: 26652K machine # [ 1.063938] Freeing unused kernel memory: 4736K machine # [ 1.064196] Run /init as init process machine # [ 1.088530] fuse: init (API version 7.45) machine # [ 1.092581] virtiofs virtio8: discovered new tag: nix-store machine # [ 1.094158] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 1.111042] virtiofs virtio9: discovered new tag: shared machine # [ 1.111827] virtiofs virtio9: virtio_fs_setup_dax: No cache capability machine # [ 1.114819] virtiofs virtio10: discovered new tag: xchg machine # [ 1.115542] virtiofs virtio10: virtio_fs_setup_dax: No cache capability machine # [ 1.130009] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.130535] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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) machine # [ 1.130550] systemd[1]: Detected virtualization kvm. machine # [ 1.130571] systemd[1]: Detected architecture arm64. machine # [ 1.130578] systemd[1]: Running in initrd. machine # [ 1.130902] systemd[1]: Initializing machine ID from random generator. machine # [ 1.131006] systemd[1]: Hostname set to . machine # [ 1.193534] input: QEMU QEMU USB Keyboard as /devices/pci0000:00/0000:00:09.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.235695] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.281547] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:09.0-1/input0 machine # [ 1.299454] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.307441] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.308018] systemd[1]: Created slice Slice /system/systemd-cryptsetup. machine # [ 1.308113] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.308129] systemd[1]: Expecting device /dev/mapper/cryptroot... machine # [ 1.308150] systemd[1]: Expecting device /dev/tpm0... machine # [ 1.308160] systemd[1]: Expecting device /dev/tpmrm0... machine # [ 1.308168] systemd[1]: Expecting device /dev/vdb... machine # [ 1.308188] systemd[1]: Reached target Path Units. machine # [ 1.308200] systemd[1]: Reached target Slice Units. machine # [ 1.308213] systemd[1]: Reached target Swaps. machine # [ 1.308226] systemd[1]: Reached target Timer Units. machine # [ 1.308359] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.308513] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.308637] systemd[1]: Listening on Journal Sockets. machine # [ 1.308710] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.308724] systemd[1]: Reached target Socket Units. machine # [ 1.321813] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.321885] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.329397] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.341362] systemd[1]: Starting Journal Service... machine # [ 1.377808] systemd[1]: Starting Load Kernel Modules... machine # [ 1.379324] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.387665] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.390377] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.391312] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.401326] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.406229] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.462031] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.463880] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.476455] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.497370] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.515577] [drm] pci: virtio-gpu-pci detected at 0000:00:07.0 machine # [ 1.515855] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.515858] [drm] features: -context_init machine # [ 1.516640] [drm] number of scanouts: 1 machine # [ 1.516651] [drm] number of cap sets: 0 machine # [ 1.538088] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.538546] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.538572] systemd[1]: Reached target Local File Systems. machine # [ 1.544221] virtio-pci 0000:00:07.0: [drm] Registered 1 planes with drm panic machine # [ 1.544232] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:07.0 on minor 0 machine # [ 1.549980] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.559117] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:09.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.559283] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:09.0-2/input0 machine # [ 1.586374] Console: switching to colour frame buffer device 160x50 machine # [ 1.587097] virtio-pci 0000:00:07.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.562642] systemd-modules-load[73]: Inserted module 'dm_mod' machine # [ 1.565254] systemd-modules-load[73]: Module 'virtio_balloon' is built in machine # [ 1.566592] systemd-modules-load[73]: Module 'virtio_console' is built in machine # [ 1.567723] systemd-modules-load[73]: Inserted module 'virtio_gpu' machine # [ 1.602257] systemd[1]: Started Journal Service. machine # [ 1.584494] systemd-modules-load[73]: Module 'virtio_rng' is built in machine # [ 1.585762] systemd[1]: Finished Load Kernel Modules. machine # [ 1.589327] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.617413] systemd[1]: Starting Create System Files and Directories... machine # [ 1.627200] systemd-udevd[79]: Using default interface naming scheme 'v261'. machine # [ 1.663158] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.674448] systemd[1]: Finished Create System Files and Directories. machine # [ 1.685881] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.774220] systemd[1]: Starting Virtual Console Setup... machine # [ 1.848558] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.852416] systemd[1]: Finished Virtual Console Setup. machine # [ 2.512495] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.666659] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.682846] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.689029] systemd[1]: Found device /dev/vdb. machine # [ 3.715060] tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x1, rev-id 1) machine # [ 3.834104] systemd[1]: Found device /dev/tpm0. machine # [ 3.846619] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.852192] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.854636] systemd[1]: Stopping Virtual Console Setup... machine # [ 3.860472] systemd[1]: Starting Virtual Console Setup... machine # [ 3.867143] systemd[1]: Found device /dev/tpmrm0. machine # [ 3.868953] systemd[1]: Reached target Trusted Platform Module. machine # [ 3.870359] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 3.876511] systemd[1]: Starting Cryptography Setup for cryptroot... machine # [ 3.919002] systemd-vconsole-setup[113]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 3.924849] systemd[1]: Finished Virtual Console Setup. machine # [ 4.282140] Key type trusted registered machine # [ 4.284891] Key type encrypted registered machine # [ 4.295615] systemd[1]: Found device /dev/mapper/cryptroot. machine # [ 4.299824] systemd[1]: Finished Cryptography Setup for cryptroot. machine # [ 4.302010] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 4.303382] systemd[1]: Reached target Initrd Root Device. machine # [ 4.305910] systemd[1]: Reached target System Initialization. machine # [ 4.307113] systemd[1]: Reached target Basic System. machine # [ 4.312275] systemd[1]: Starting File System Check on /dev/mapper/cryptroot... machine # [ 4.341811] systemd-fsck[128]: /dev/mapper/cryptroot: clean, 12/126976 files, 42920/507904 blocks machine # [ 4.356854] systemd[1]: Finished File System Check on /dev/mapper/cryptroot. machine # [ 4.361698] systemd[1]: Mounting /sysroot... machine # [ 4.427845] systemd[1]: Mounted /sysroot.[ 4.457705] EXT4-fs (dm-0): mounted filesystem 7b6c26c5-cca5-4852-94de-6a5b6f166f71 r/w with ordered data mode. Quota mode: none. machine # machine # [ 4.430634] systemd[1]: Reached target Initrd Root File System. machine # [ 4.433144] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 4.453713] systemd-sysroot-fstab-check[135]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 4.457337] systemd[1]: Reload requested from client PID 135 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 4.460193] systemd[1]: Reloading... machine # [ 4.584198] systemd[1]: Reloading finished in 124 ms. machine # [ 4.608526] systemd-sysroot-fstab-check[135]: Requesting initrd-fs.target/start/replace... machine # [ 4.611327] systemd-sysroot-fstab-check[135]: Requesting swap.target/start/replace... machine # [ 4.616304] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 4.618416] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 4.619466] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 4.892581] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 4.897830] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 4.911152] systemd[1]: Mounting /sysroot/run... machine # [ 4.921946] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 4.948392] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 4.997083] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 5.000220] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 5.001458] systemd[1]: Mounted /sysroot/run. machine # [ 5.004651] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 5.016382] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 5.019124] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 5.047719] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 5.049636] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 5.891282] systemd[1]: Mounting /sysroot/nix/store... machine # [ 5.936658] systemd[1]: Mounted /sysroot/nix/store. machine # [ 5.940127] systemd[1]: Reached target Initrd File Systems. machine # [ 5.943681] systemd[1]: Starting Find NixOS closure... machine # [ 5.952384] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 5.995550] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 6.000596] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 6.010718] systemd[1]: Finished Find NixOS closure. machine # [ 6.013616] systemd[1]: Reached target Initrd Default Target. machine # [ 6.015097] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 6.043166] systemd[1]: Stopped target Initrd Default Target. machine # [ 6.044849] systemd[1]: Stopped target Basic System. machine # [ 6.046243] systemd[1]: Stopped target Initrd Root Device. machine # [ 6.048443] systemd[1]: Stopped target Path Units. machine # [ 6.052396] systemd[1]: Stopped target Slice Units. machine # [ 6.053557] systemd[1]: Stopped target Socket Units. machine # [ 6.055023] systemd[1]: Stopped target System Initialization. machine # [ 6.059242] systemd[1]: Stopped target Local Encrypted Volumes. machine # [ 6.064789] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 6.066104] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 6.067326] systemd[1]: Stopped target Swaps. machine # [ 6.074576] systemd[1]: Stopped target Timer Units. machine # [ 6.075426] systemd[1]: Stopped target Trusted Platform Module. machine # [ 6.079649] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 6.080907] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 6.084425] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 6.087103] systemd[1]: Stopped Find NixOS closure. machine # [ 6.093227] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 6.096332] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 6.097567] systemd[1]: Stopped Apply Kernel Variables. machine # [ 6.103082] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 6.106909] systemd[1]: Stopped Load Kernel Modules. machine # [ 6.107702] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 6.111330] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 6.112734] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 6.116362] systemd[1]: Stopped Create System Files and Directories. machine # [ 6.117492] systemd[1]: Stopped target Local File Systems. machine # [ 6.119154] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 6.123082] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 6.124281] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 6.125213] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 6.126428] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 6.127499] systemd[1]: Stopped Virtual Console Setup. machine # [ 6.128389] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 6.129395] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 6.130356] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 6.131331] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 6.136259] systemd[1]: systemd-udevd.service: Consumed 1.626s CPU time over 4.578s wall clock time, 24M memory peak. machine # [ 6.140333] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 6.144532] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 6.145681] systemd[1]: Starting Cleanup udev Database... machine # [ 6.146519] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 6.152109] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 6.153066] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 6.154242] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 6.155286] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 6.160482] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 6.187486] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 6.191222] systemd[1]: Finished Cleanup udev Database. machine # [ 6.192360] systemd[1]: Reached target Switch Root. machine # [ 6.196378] systemd[1]: Starting NixOS Activation... machine # [ 6.278223] initrd-nixos-activation-start[188]: booting system configuration /nix/store/jd0hmwxkv6izbashfvhw47v55b6r8n40-nixos-system-machine-test machine # [ 6.312918] initrd-nixos-activation-start[188]: running activation script... machine # [ 6.536387] initrd-nixos-activation-start[211]: setting up /etc... machine # [ 6.656588] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 6.659534] systemd[1]: Finished NixOS Activation. machine # [ 6.660918] systemd[1]: Starting Switch Root... machine # [ 6.685004] systemd[1]: Switching root. machine # [ 6.891316] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 6.991325] NET: Registered PF_VSOCK protocol family machine # [ 7.028519] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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) machine # [ 7.034391] systemd[1]: Detected virtualization kvm. machine # [ 7.034450] systemd[1]: Detected architecture arm64. machine # [ 7.034523] systemd[1]: Detected first boot. machine # [ 7.035631] systemd[1]: Initializing machine ID from random generator. machine # [ 7.153950] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.289919] systemd[1]: Applying preset policy. machine # [ 7.511721] systemd[1]: Populated /etc with preset unit settings. machine # [ 7.740013] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 7.740475] systemd[1]: Stopped initrd-switch-root.service. machine # [ 7.746060] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 7.747586] systemd[1]: Created slice Slice /system/getty. machine # [ 7.748609] systemd[1]: Created slice Slice /system/systemd-fsck. machine # [ 7.752658] systemd[1]: Created slice User and Session Slice. machine # [ 7.752931] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 7.755656] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 7.756300] systemd[1]: Expecting device /dev/disk/by-label/ESP... machine # [ 7.756950] systemd[1]: Expecting device /dev/hvc0... machine # [ 7.760040] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 7.760760] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 7.763584] systemd[1]: Stopped target initrd-fs.target. machine # [ 7.763998] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 7.764653] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 7.768112] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 7.768545] systemd[1]: Reached target Path Units. machine # [ 7.771409] systemd[1]: Reached target Remote File Systems. machine # [ 7.771725] systemd[1]: Reached target Slice Units. machine # [ 7.771981] systemd[1]: Reached target Swaps. machine # [ 7.775579] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 7.778833] systemd[1]: Listening on Process Core Dump Socket. machine # [ 7.780263] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 7.783476] systemd[1]: Listening on Factory Reset Management. machine # [ 7.784021] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.789786] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.790963] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.793015] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.796217] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.796879] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.800372] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.807108] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.807744] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.812252] systemd[1]: Mounting Huge Pages File System... machine # [ 7.815996] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 7.826953] systemd[1]: Mounting Kernel Debug File System... machine # [ 7.833473] systemd[1]: Mounting Kernel Trace File System... machine # [ 7.846709] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 7.850208] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 7.865788] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.870222] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.876337] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.879456] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.900711] systemd[1]: Mounting FUSE Control File System... machine # [ 7.927650] systemd[1]: Starting seed-system-profile.service... machine # [ 7.928042] systemd[1]: systemd-cryptsetup@cryptroot.service: Deactivated successfully. machine # [ 7.928315] systemd[1]: Stopped systemd-cryptsetup@cryptroot.service. machine # [ 7.944696] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.989471] systemd[1]: Starting Journal Service... machine # [ 8.005430] systemd[1]: Starting Load Kernel Modules... machine # [ 8.023922] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.037627] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.038028] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.068691] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.080757] systemd-journald[283]: Collecting audit messages is enabled. machine # [ 8.088682] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.089168] systemd[1]: Mounted Huge Pages File System. machine # [ 8.059016] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.062873] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.070356] systemd-modules-load[288]: Module 'atkbd' is built in machine # [ 8.074265] systemd-modules-load[288]: Module 'loop' is built in machine # [ 8.106010] systemd[1]: Started Journal Service. machine # [ 8.082182] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.083337] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.091665] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.099206] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.103084] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.111169] systemd[1]: Finished seed-system-profile.service. machine # [ 8.113884] systemd[1]: Finished Load Kernel Modules. machine # [ 8.114686] systemd[1]: Mounted FUSE Control File System. machine # [ 8.149288] EXT4-fs (dm-0): re-mounted 7b6c26c5-cca5-4852-94de-6a5b6f166f71. machine # [ 8.130267] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.138690] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.152139] systemd[1]: Starting Firewall... machine # [ 8.162419] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.164338] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.176143] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.187057] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.213622] systemd-oomd[290]: No swap; memory pressure usage will be degraded machine # [ 8.226103] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.227342] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.236563] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.342258] systemd-journald[283]: Received client request to flush runtime journal. machine # [ 8.378987] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 8.379996] systemd[1]: Reached target First Boot Complete. machine # [ 8.390354] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 8.396800] systemd[1]: Finished Apply Kernel Variables. machine # [ 8.449411] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 8.458044] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 8.551262] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 8.552555] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 8.554563] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.693883] systemd-udevd[327]: Using default interface naming scheme 'v261'. machine # [ 8.713459] systemd[1]: Mounting /run/wrappers... machine # [ 8.787035] systemd[1]: Mounted /run/wrappers. machine # [ 8.800428] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.830418] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 9.493698] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.500270] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.817519] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.866152] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 9.897469] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 10.155750] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 10.159390] systemd[1]: Found device /dev/tpmrm0. machine # [ 10.163319] systemd[1]: Expecting device /dev/tpm0... machine # [ 10.217548] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 10.223507] (udev-worker)[349]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 10.229111] (udev-worker)[349]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.244913] systemd[1]: Finished Firewall. machine # [ 10.378930] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.383972] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 10.388840] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 10.389834] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 10.400886] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 10.426472] systemd[1]: Condition check resulted in /dev/disk/by-label/ESP being skipped. machine # [ 10.435835] systemd[1]: Starting File System Check on /dev/disk/by-label/ESP... machine # [ 10.542224] systemd-fsck[495]: fsck.fat 4.2 (2021-01-31) machine # [ 10.543174] systemd-fsck[495]: There are differences between boot sector and its backup. machine # [ 10.552164] systemd-fsck[495]: This is mostly harmless. Differences: (offset:original/backup) machine # [ 10.553492] systemd-fsck[495]: 65:01/00 machine # [ 10.554136] systemd-fsck[495]: Not automatically fixing this. machine # [ 10.555017] systemd-fsck[495]: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. machine # [ 10.566463] systemd-fsck[495]: Automatically removing dirty bit. machine # [ 10.567497] systemd-fsck[495]: *** Filesystem was changed *** machine # [ 10.577545] systemd-fsck[495]: Writing changes. machine # [ 10.578286] systemd-fsck[495]: /dev/vdc1: 20 files, 29138/66398 clusters machine # [ 10.579637] systemd[1]: Finished File System Check on /dev/disk/by-label/ESP. machine # [ 10.586069] systemd[1]: Mounting /boot... machine # [ 10.670087] systemd[1]: Mounted /boot. machine # [ 10.671019] systemd[1]: Reached target Local File Systems. machine # [ 10.676903] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 10.683874] systemd[1]: Starting Update Boot Loader Random Seed... machine # [ 10.691024] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 10.705620] systemd[1]: Starting Create System Files and Directories... machine # [ 10.778435] bootctl[516]: Mount point '/boot' which backs the random seed file is world accessible, which is a security hole! machine # [ 10.787349] bootctl[516]: Random seed file '/boot/loader/random-seed' is world accessible, which is a security hole! machine # [ 10.801445] bootctl[516]: Random seed file /boot/loader/random-seed successfully refreshed (32 bytes). machine # [ 10.802891] systemd[1]: Finished Update Boot Loader Random Seed. machine # [ 10.886868] systemd[1]: Finished Create System Files and Directories. machine # [ 10.898001] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 10.906822] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 10.910240] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 11.015217] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 11.064427] mousedev: PS/2 mouse device common for all mice machine # [ 11.042675] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 11.048384] systemd[1]: Starting Update is Completed... machine # [ 11.108486] systemd[1]: Finished Update is Completed. machine # [ 11.198250] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 11.201573] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 11.464718] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 11.953653] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:08.0/virtio7/input/input3 machine # [ 12.005109] systemd[1]: Found device /dev/tpm0. machine # [ 12.009164] systemd[1]: Reached target Trusted Platform Module. machine # [ 12.011383] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 12.016734] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 12.022016] systemd[1]: Reached target System Initialization. machine # [ 12.025003] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 12.032534] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 12.033654] systemd[1]: Reached target Timer Units. machine # [ 12.034426] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 12.035352] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 12.044412] systemd[1]: Reached target Socket Units. machine # [ 12.045247] systemd[1]: Reached target Basic System. machine # [ 12.046021] systemd[1]: Started backdoor.service. machine # [ 12.046756] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 12.052078] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 12.065450] systemd[1]: Starting Post-Boot Actions... machine # [ 12.091113] systemd[1]: Started Reset console on configuration changes. machine # [ 12.133969] systemd[1]: Starting resolvconf update... machine # [ 12.186868] nsncd[582]: Sep 27 03:14:42.968 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 12.201796] systemd[1]: Starting D-Bus System Message Bus... machine # [ 12.202733] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 12.203642] systemd[1]: Reached target Host and Network Name Lookups. machine # connecting to host... machine # [ 12.221525] systemd[1]: Reached target User and Group Name Lookups. machine # [ 12.247704] systemd[1]: Starting User Login Management... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 12.276096] systemd[1]: Finished Post-Boot Actions. machine: connected to guest root shell machine: (connecting took 25.32 seconds) machine: (finished: waiting for the VM to finish booting, in 25.53 seconds) machine # [ 12.466584] dbus-broker-launch[591]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 12.489989] systemd-logind[602]: New seat seat0. machine # [ 12.500146] systemd-logind[602]: Watching system buttons on /dev/input/event0 (Power Button) machine # [ 12.504640] systemd-logind[602]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.574419] systemd[1]: Started User Login Management. machine # [ 12.578685] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 12.608651] dbus-broker-launch[591]: NSS returned no entry for 'systemd-timesync' machine # [ 12.609850] dbus-broker-launch[591]: Invalid user-name in /nix/store/65vd3s6k4y44l2l20gqradj2ccwg6ryi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 12.626425] systemd[1]: Starting linger-users.service... machine # [ 12.627331] systemd-logind[602]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 12.640498] systemd[1]: Starting Virtual Console Setup... machine # [ 12.678371] systemd[1]: Started D-Bus System Message Bus. machine # [ 12.701558] dbus-broker-launch[591]: Ready machine # [ 12.725563] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 12.729166] systemd[1]: Finished linger-users.service. machine # [ 12.748066] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 12.749136] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 12.750032] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 12.750917] systemd[1]: Stopping User and Group Name Lookups... machine # [ 12.761133] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 12.762229] systemd[1]: nscd.service: Deactivated successfully. machine # [ 12.763111] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 12.781393] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 12.815327] systemd[1]: Finished resolvconf update. machine # [ 12.819607] systemd[1]: Reached target Preparation for Network. machine # [ 12.828168] systemd[1]: Starting DHCP Client... machine # [ 12.832262] systemd[1]: Starting Address configuration of eth1... machine # [ 12.846602] nsncd[678]: Sep 27 03:14:43.632 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 12.854115] systemd[1]: Starting Extra networking commands.... machine # [ 12.855077] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 12.855955] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 12.861101] systemd[1]: Reached target User and Group Name Lookups. machine # [ 13.066762] network-addresses-eth1-start[686]: adding address 192.168.1.1/24... done machine # [ 13.084371] network-addresses-eth1-start[686]: adding address 2001:db8:1::1/64... done machine # [ 13.112676] systemd[1]: Finished Address configuration of eth1. machine # [ 13.186570] dhcpcd[706]: dhcpcd-10.3.2 starting machine # [ 13.195132] dhcpcd[753]: dev: loaded udev machine # [ 13.265698] 8021q: 802.1Q VLAN Support v1.8 machine # [ 13.266087] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 13.240843] systemd[1]: Finished Extra networking commands.. machine # [ 13.246773] systemd[1]: Reached target Network. machine # [ 13.259436] systemd[1]: Starting Permit User Sessions... machine # [ 13.281259] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.329666] systemd[1]: Finished Permit User Sessions. machine # [ 13.339313] systemd[1]: Started Getty on tty1. machine # [ 13.341348] systemd[1]: Reached target Login Prompts. machine # [ 13.380260] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 13.402702] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 13.403192] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 13.407936] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 13.408278] cfg80211: failed to load regulatory.db machine # [ 13.388387] systemd-vconsole-setup[660]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.394164] systemd[1]: Finished Virtual Console Setup. machine # [ 13.452497] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 13.421138] dhcpcd[753]: eth0: waiting for carrier machine # [ 13.423163] dhcpcd[753]: eth0: carrier acquired machine # [ 13.429273] dhcpcd[753]: DUID 00:01:00:01:32:4b:46:a4:52:54:00:12:34:56 machine # [ 13.430309] dhcpcd[753]: eth0: IAID 00:12:34:56 machine # [ 13.430976] dhcpcd[753]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 13.596655] dhcpcd[753]: eth0: soliciting a DHCP lease machine # [ 13.600783] dhcpcd[753]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 13.604227] dhcpcd[753]: eth0: probing address 10.0.2.15/24 machine # [ 15.008794] dhcpcd[753]: eth0: soliciting an IPv6 router machine # [ 15.011085] dhcpcd[753]: eth0: Router Advertisement from fe80::2 machine # [ 15.012590] dhcpcd[753]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 15.014224] dhcpcd[753]: eth0: adding route to fec0::/64 machine # [ 15.015405] dhcpcd[753]: eth0: adding default route via fe80::2 machine # [ 18.165620] dhcpcd[753]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 18.168445] dhcpcd[753]: eth0: adding route to 10.0.2.0/24 machine # [ 18.169817] dhcpcd[753]: eth0: adding default route via 10.0.2.2 machine # [ 18.277688] systemd[1]: Started DHCP Client. machine # [ 18.280260] systemd[1]: Reached target Multi-User System. machine # [ 18.284394] systemd[1]: Startup finished in 6.003s (firmware) + 6.337s (loader) + 1.036s (kernel) + 5.898s (initrd) + 11.346s (userspace) = 30.622s. machine: (finished: waiting for unit multi-user.target, in 32.42 seconds) machine: must succeed: mount machine: (finished: must succeed: mount, in 0.02 seconds) (finished: run the VM test script, in 70.60 seconds) test script finished in 70.65s cleanup kill QemuMachine (pid 106) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) machine # [2026-09-27T03:14:50Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-27T03:14:50Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-27T03:14:50Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.05 seconds)