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.01 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script machine: must succeed: portablectl machine: waiting for the VM to finish booting machine: starting vm machine: QEMU running (pid 45) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.BN45l1oPmg', 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: ff47869c-f810-4af5-b24c-746c5b111735 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 # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] 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-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 DT. 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 s186648 r8192 d116456 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: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/853yrp0gwjpvzs1rqsqm7db44d0c0qhx-nixos-system-machine-test/init regInfo=/nix/store/ijc5bn7r4scg30gzyddsigcnfsqv7l33-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/ijc5bn7r4scg30gzyddsigcnfsqv7l33-closure-info/registration", will be passed to user space. 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 74885 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 @44cf0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 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.000030] arm-pv: using stolen time PV machine # [ 0.000430] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000597] Console: colour dummy device 80x25 machine # [ 0.000604] printk: legacy console [tty0] enabled machine # [ 0.000790] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000797] pid_max: default: 32768 minimum: 301 machine # [ 0.000874] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001040] landlock: Up and running. machine # [ 0.001043] Yama: becoming mindful. machine # [ 0.001565] LSM support for eBPF active machine # [ 0.001702] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001721] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002865] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003620] rcu: Hierarchical SRCU implementation. machine # [ 0.003624] rcu: Max phase no-delay instances is 1000. machine # [ 0.004860] fsl-mc MSI: its@8080000 domain created machine # [ 0.004953] EFI services will not be available. machine # [ 0.005052] smp: Bringing up secondary CPUs ... machine # [ 0.005061] smp: Brought up 1 node, 1 CPU machine # [ 0.005065] SMP: Total of 1 processors activated. machine # [ 0.005067] CPU: All CPU(s) started at EL1 machine # [ 0.005081] CPU features: detected: Branch Target Identification machine # [ 0.005087] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005090] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005094] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005097] CPU features: detected: Common not Private translations machine # [ 0.005100] CPU features: detected: CRC32 instructions machine # [ 0.005103] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005107] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005110] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005113] CPU features: detected: E0PD machine # [ 0.005115] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005118] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005122] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.005125] CPU features: detected: Fine Grained Traps machine # [ 0.005128] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005133] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005136] CPU features: detected: LSE atomic instructions machine # [ 0.005139] CPU features: detected: Privileged Access Never machine # [ 0.005141] CPU features: detected: PMUv3 machine # [ 0.005144] CPU features: detected: RAS Extension Support machine # [ 0.005147] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005149] CPU features: detected: Random Number Generator machine # [ 0.005152] CPU features: detected: Speculation barrier (SB) machine # [ 0.005155] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005158] CPU features: detected: TLB range maintenance instructions machine # [ 0.005163] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.005201] alternatives: applying system-wide alternatives machine # [ 0.008192] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008347] Memory: 894176K/1048576K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 113068K reserved, 32768K cma-reserved) machine # [ 0.008794] devtmpfs: initialized machine # [ 0.010546] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010569] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.010751] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.010755] 0 pages in range for non-PLT usage machine # [ 0.010757] 508288 pages in range for PLT usage machine # [ 0.010884] pinctrl core: initialized pinctrl subsystem machine # [ 0.011650] DMI not present or invalid. machine # [ 0.014916] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.017389] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.017536] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.017700] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.017723] audit: initializing netlink subsys (disabled) machine # [ 0.018298] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.018300] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.018303] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.018306] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.018312] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.018337] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.018346] cpuidle: using governor ladder machine # [ 0.018351] cpuidle: using governor menu machine # [ 0.018565] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.018582] ASID allocator initialised with 65536 entries machine # [ 0.019768] Serial: AMBA PL011 UART driver machine # [ 0.025223] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.025383] printk: console [ttyAMA0] enabled machine # [ 0.152746] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.152766] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.152771] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.152776] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.152780] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.152784] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.152788] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.152792] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.160453] fbcon: Taking over console machine # [ 0.160472] ACPI: Interpreter disabled. machine # [ 0.162395] iommu: Default domain type: Translated machine # [ 0.162405] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.164140] SCSI subsystem initialized machine # [ 0.169202] usbcore: registered new interface driver usbfs machine # [ 0.169233] usbcore: registered new interface driver hub machine # [ 0.169252] usbcore: registered new device driver usb machine # [ 0.169526] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.169533] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.169543] PTP clock support registered machine # [ 0.169591] EDAC MC: Ver: 3.0.0 machine # [ 0.174397] scmi_core: SCMI protocol bus registered machine # [ 0.175377] FPGA manager framework machine # [ 0.176350] vgaarb: loaded machine # [ 0.177004] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.183231] VFS: Disk quotas dquot_6.6.0 machine # [ 0.183277] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.184957] netfs: FS-Cache loaded machine # [ 0.185558] pnp: PnP ACPI: disabled machine # [ 0.189093] NET: Registered PF_INET protocol family machine # [ 0.189274] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.220031] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.220088] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.220116] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.220163] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.220248] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.220358] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.220391] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.220418] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.220520] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.220543] NET: Registered PF_XDP protocol family machine # [ 0.220563] PCI: CLS 0 bytes, default 64 machine # [ 0.220833] Trying to unpack rootfs image as initramfs... machine # [ 0.238970] kvm [1]: HYP mode not available machine # [ 0.335534] Initialise system trusted keyrings machine # [ 0.336297] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.337645] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.338431] 9p: Installing v9fs 9p2000 file system support machine # [ 0.358468] Key type asymmetric registered machine # [ 0.358497] Asymmetric key parser 'x509' registered machine # [ 0.358576] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.365077] io scheduler mq-deadline registered machine # [ 0.365096] io scheduler kyber registered machine # [ 0.377202] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.378671] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.379076] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.379094] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.379106] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.379130] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.379155] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.379181] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.379263] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.379272] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.379279] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.379284] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.379289] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.379348] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.379788] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.379977] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.379994] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.380023] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.380040] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.380482] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.380663] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.380680] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.380710] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.405295] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.405512] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.405529] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.405558] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.406033] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.406219] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.406236] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.406265] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.406725] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.406909] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.406927] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.406957] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.407411] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.407594] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.407610] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.407639] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.408088] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.408276] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.408292] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.408322] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.408339] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.408791] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.408979] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.427679] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.428155] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.428349] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.428381] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.428780] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.428966] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.441381] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.441702] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.441721] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.441750] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.442214] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.442396] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.442412] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.442442] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.443040] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.443053] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.443058] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.443105] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.443153] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.443208] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.443256] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.443305] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.443354] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.443404] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.443453] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.443502] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.443574] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.443620] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.443641] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.443663] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.443684] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.443706] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.443731] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.443753] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.443776] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.443798] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.443820] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.443844] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.443867] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.443888] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.443910] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.443931] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.443954] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.443976] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.443999] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.444022] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.444044] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.444069] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.444096] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.444107] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.444111] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.445329] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.511896] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.515028] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.518004] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.520197] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.530545] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.532737] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.544443] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.554693] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.559942] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.566244] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.577394] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.590493] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.592453] msm_serial: driver initialized machine # [ 0.592626] SuperH (H)SCI(F) driver initialized machine # [ 0.592685] STM32 USART driver initialized machine # [ 0.628771] loop: module loaded machine # [ 0.629561] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 0.630446] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.641881] megasas: 07.734.00.00-rc1 machine # [ 0.642748] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.644761] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.644808] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.649999] Using buffer write method machine # [ 0.650111] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.651937] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.651980] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.669886] Using buffer write method machine # [ 0.669954] Concatenating MTD devices: machine # [ 0.669958] (0): "0.flash" machine # [ 0.669963] (1): "0.flash" machine # [ 0.669967] into device "0.flash" machine # [ 0.958228] Freeing initrd memory: 26208K machine # [ 0.964772] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.968996] thunder_xcv, ver 1.0 machine # [ 0.969165] thunder_bgx, ver 1.0 machine # [ 0.969188] nicpf, ver 1.0 machine # [ 0.969734] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.969744] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.969772] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.969783] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.969807] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.969813] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.969835] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.969841] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.969975] sky2: driver version 1.30 machine # [ 0.971551] usbcore: registered new interface driver usb-storage machine # [ 0.971649] usbcore: registered new interface driver usbserial_generic machine # [ 0.971667] usbserial: USB Serial support registered for generic machine # [ 0.972265] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.973797] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.973834] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.974019] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.985952] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.986362] hub 1-0:1.0: USB hub found machine # [ 0.986384] hub 1-0:1.0: 6 ports detected machine # [ 0.989441] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.989478] rtc-pl031 9010000.pl031: setting system clock to 2026-09-12T02:47:01 UTC (1789181221) machine # [ 0.989917] i2c_dev: i2c /dev entries driver machine # [ 0.995027] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.995048] sdhci: Copyright(c) Pierre Ossman machine # [ 0.995357] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.995753] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.000302] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.000585] usbcore: registered new interface driver usbhid machine # [ 1.000596] usbhid: USB HID core driver machine # [ 1.003632] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.006392] drop_monitor: Initializing network drop monitor service machine # [ 1.006542] NET: Registered PF_INET6 protocol family machine # [ 1.008632] Segment Routing with IPv6 machine # [ 1.008672] In-situ OAM (IOAM) with IPv6 machine # [ 1.008715] NET: Registered PF_PACKET protocol family machine # [ 1.010641] 9pnet: Installing 9P2000 support machine # [ 1.012986] Key type dns_resolver registered machine # [ 1.019972] registered taskstats version 1 machine # [ 1.020135] Loading compiled-in X.509 certificates machine # [ 1.029254] Demotion targets for Node 0: null machine # [ 1.029394] Key type .fscrypt registered machine # [ 1.029402] Key type fscrypt-provisioning registered machine # [ 1.029507] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.029532] ima: Allocated hash algorithm: sha1 machine # [ 1.029556] ima: No architecture policies found machine # [ 1.034138] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.053273] clk: Disabling unused clocks machine # [ 1.053305] PM: genpd: Disabling unused power domains machine # [ 1.057755] Freeing unused kernel memory: 4736K machine # [ 1.057969] Run /init as init process machine # [ 1.075787] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.233109] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.387636] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.410898] systemd[1]: systemd 262-b504aa0 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.423371] systemd[1]: Detected virtualization qemu. machine # [ 1.425591] systemd[1]: Detected architecture arm64. machine # [ 1.427586] systemd[1]: Running in initrd. machine # [ 1.430385] systemd[1]: Initializing machine ID from random generator. machine # [ 1.433490] systemd[1]: Hostname set to . machine # [ 1.485345] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 machine # [ 1.599382] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.609095] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.655769] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.667524] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.668566] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.669791] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.670686] systemd[1]: Reached target Path Units. machine # [ 1.671340] systemd[1]: Reached target Slice Units. machine # [ 1.672031] systemd[1]: Reached target Swaps. machine # [ 1.672658] systemd[1]: Reached target Timer Units. machine # [ 1.673752] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.674801] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.675768] systemd[1]: Listening on Journal Sockets. machine # [ 1.676554] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.677352] systemd[1]: Reached target Socket Units. machine # [ 1.679548] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.686188] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.687246] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.697355] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.706053] systemd[1]: Starting Journal Service... machine # [ 1.745507] systemd[1]: Starting Load Kernel Modules... machine # [ 1.746445] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.753944] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.760244] systemd-journald[73]: Collecting audit messages is disabled. machine # [ 1.761518] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.762913] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.767248] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.767414] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 machine # [ 1.789291] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.789837] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.793525] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.838295] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.857129] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.862287] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.862548] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.862561] [drm] features: -context_init machine # [ 1.863376] [drm] number of scanouts: 1 machine # [ 1.863398] [drm] number of cap sets: 0 machine # [ 1.877915] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.880846] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.885537] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.885571] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.921540] Console: switching to colour frame buffer device 160x50 machine # [ 1.928563] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.948858] systemd[1]: Started Journal Service. machine # [ 1.939619] systemd-modules-load[75]: Inserted module 'dm_mod' machine # [ 1.945764] systemd-modules-load[75]: Module 'virtio_balloon' is built in machine # [ 1.948712] systemd-modules-load[75]: Module 'virtio_console' is built in machine # [ 1.952413] systemd-modules-load[75]: Inserted module 'virtio_gpu' machine # [ 1.960452] systemd-modules-load[75]: Module 'virtio_rng' is built in machine # [ 1.961680] systemd[1]: Finished Load Kernel Modules. machine # [ 1.963898] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.968547] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.979109] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.980649] systemd[1]: Reached target Local File Systems. machine # [ 1.992317] systemd[1]: Starting Create System Files and Directories... machine # [ 2.007259] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 2.032353] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.042213] systemd[1]: Finished Create System Files and Directories. machine # [ 2.090516] systemd-udevd[88]: Using default interface naming scheme 'v261'. machine # [ 2.122728] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.208835] systemd[1]: Starting Virtual Console Setup... machine # [ 2.292581] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.297083] systemd[1]: Finished Virtual Console Setup. machine # [ 2.880559] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.881993] systemd[1]: Reached target System Initialization. machine # [ 2.883843] systemd[1]: Reached target Basic System. machine # [ 3.015587] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 3.035915] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.045388] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.121234] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.126888] systemd[1]: Reached target Initrd Root Device. machine # [ 3.131575] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.186756] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.194157] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.200375] systemd[1]: Mounting /sysroot... machine # [ 3.253681] EXT4-fs (vda): mounted filesystem ff47869c-f810-4af5-b24c-746c5b111735 r/w with ordered data mode. Quota mode: none. machine # [ 3.242261] systemd[1]: Mounted /sysroot. machine # [ 3.244473] systemd[1]: Reached target Initrd Root File System. machine # [ 3.248496] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.279597] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.284130] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.288742] systemd[1]: Reloading... machine # [ 3.484985] systemd[1]: Reloading finished in 198 ms. machine # [ 3.514331] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... machine # [ 3.518414] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... machine # [ 3.524261] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.526955] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.535337] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.536797] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.564822] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.566394] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.742554] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.755799] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.769578] systemd[1]: Mounting /sysroot/run... machine # [ 3.807802] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.817419] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.837541] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.866737] systemd[1]: Mounted /sysroot/run. machine # [ 3.870740] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.889957] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.897366] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.917879] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.935437] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.936830] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.429188] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/zmx0fkcv0rzx39v279ji6d69ps1wah29-systemd-262-b504aa0/lib/udev/mtd_probe": No such file or directory machine # [ 4.435776] (udev-worker)[99]: mtd0ro: /nix/store/zmx0fkcv0rzx39v279ji6d69ps1wah29-systemd-262-b504aa0/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory machine # [ 4.454215] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.461582] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.462494] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.463810] systemd[1]: Starting Virtual Console Setup... machine # [ 4.482334] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.483960] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.488271] systemd[1]: Starting Virtual Console Setup... machine # [ 4.518738] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.522592] systemd[1]: Finished Virtual Console Setup. machine # [ 4.745331] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.810952] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.816503] systemd[1]: Reached target Initrd File Systems. machine # [ 4.820754] systemd[1]: Starting Find NixOS closure... machine # [ 4.830185] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.882412] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.891128] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.905420] systemd[1]: Finished Find NixOS closure. machine # [ 4.908354] systemd[1]: Reached target Initrd Default Target. machine # [ 4.911079] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.943494] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.948234] systemd[1]: Stopped target Basic System. machine # [ 4.949813] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.950821] systemd[1]: Stopped target Path Units. machine # [ 4.952156] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.955074] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.959293] systemd[1]: Stopped target Slice Units. machine # [ 4.960311] systemd[1]: Stopped target Socket Units. machine # [ 4.964462] systemd[1]: Stopped target System Initialization. machine # [ 4.965552] systemd[1]: Stopped target Swaps. machine # [ 4.966275] systemd[1]: Stopped target Timer Units. machine # [ 4.968432] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.972746] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.976794] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.980781] systemd[1]: Stopped Find NixOS closure. machine # [ 4.985266] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 4.987212] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.992867] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.996260] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.998258] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 5.001552] systemd[1]: Stopped Load Kernel Modules. machine # [ 5.004670] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 5.006968] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 5.016504] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 5.017762] systemd[1]: Stopped Create System Files and Directories. machine # [ 5.018896] systemd[1]: Stopped target Local File Systems. machine # [ 5.019811] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 5.036681] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 5.037961] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 5.038799] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 5.039860] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 5.044553] systemd[1]: Stopped Virtual Console Setup. machine # [ 5.047095] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 5.049735] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 5.052349] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 5.056285] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 5.057465] systemd[1]: systemd-udevd.service: Consumed 1.465s CPU time over 3.019s wall clock time, 22M memory peak. machine # [ 5.059661] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 5.061156] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 5.062057] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 5.063101] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 5.063994] systemd[1]: Starting Cleanup udev Database... machine # [ 5.064978] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 5.066128] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 5.067027] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 5.072348] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 5.073504] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 5.075019] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.113973] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.116631] systemd[1]: Finished Cleanup udev Database. machine # [ 5.119067] systemd[1]: Reached target Switch Root. machine # [ 5.121073] systemd[1]: Starting NixOS Activation... machine # [ 5.304931] initrd-nixos-activation-start[181]: booting system configuration /nix/store/853yrp0gwjpvzs1rqsqm7db44d0c0qhx-nixos-system-machine-test machine # [ 5.378368] initrd-nixos-activation-start[181]: running activation script... machine # [ 5.821477] initrd-nixos-activation-start[204]: setting up /etc... machine # [ 6.093095] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 6.096122] systemd[1]: Finished NixOS Activation. machine # [ 6.097500] systemd[1]: Starting Switch Root... machine # [ 6.121944] systemd[1]: Switching root. machine # [ 6.320524] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). machine # [ 6.927346] systemd[1]: systemd 262-b504aa0 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 # [ 6.940363] systemd[1]: Detected virtualization qemu. machine # [ 6.944561] systemd[1]: Detected architecture arm64. machine # [ 6.946935] systemd[1]: Detected first boot. machine # [ 6.953594] systemd[1]: Initializing machine ID from random generator. machine # [ 7.126827] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.307858] systemd[1]: Applying preset policy. machine # [ 7.801172] systemd[1]: Populated /etc with preset unit settings. machine # [ 8.318652] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 8.320109] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.323843] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 8.327737] systemd[1]: Created slice Slice /system/getty. machine # [ 8.330756] systemd[1]: Created slice User and Session Slice. machine # [ 8.332772] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 8.335161] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 8.337274] systemd[1]: Expecting device /dev/hvc0... machine # [ 8.338951] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 8.340634] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 8.342496] systemd[1]: Stopped target initrd-fs.target. machine # [ 8.342928] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 8.343202] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 8.343480] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 8.343758] systemd[1]: Reached target Path Units. machine # [ 8.344032] systemd[1]: Reached target Remote File Systems. machine # [ 8.344281] systemd[1]: Reached target Slice Units. machine # [ 8.344531] systemd[1]: Reached target Swaps. machine # [ 8.353625] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 8.358888] systemd[1]: Listening on Process Core Dump Socket. machine # [ 8.363260] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 8.367126] systemd[1]: Listening on Factory Reset Management. machine # [ 8.368460] systemd[1]: Listening on Hostname Service Socket. machine # [ 8.374827] systemd[1]: Starting Journal Log Access Socket... machine # [ 8.378097] systemd[1]: Listening on Journal Audit Socket. machine # [ 8.383374] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 8.386320] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 8.388769] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.392080] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 8.403150] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 8.405864] systemd[1]: Listening on udev Varlink Socket. machine # [ 8.411493] systemd[1]: Mounting Huge Pages File System... machine # [ 8.417721] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 8.430219] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.445515] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.458007] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.474111] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 8.476584] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.487898] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.490672] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.493209] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.532504] systemd[1]: Starting Load Kernel Module fuse... machine # [ 8.534995] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.598661] fuse: init (API version 7.45) machine # [ 8.610148] systemd[1]: Starting Journal Service... machine # [ 8.633652] systemd[1]: Starting Load Kernel Modules... machine # [ 8.656761] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.673449] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.676976] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.702938] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.723626] systemd-journald[276]: Collecting audit messages is enabled. machine # [ 8.734406] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.739653] systemd[1]: Mounted Huge Pages File System. machine # [ 8.744278] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.746674] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.733697] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.735022] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.753424] systemd[1]: Started Journal Service. machine # [ 8.744248] systemd-modules-load[277]: Module 'atkbd' is built in machine # [ 8.745613] systemd-modules-load[277]: Module 'loop' is built in machine # [ 8.746607] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.747511] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.761530] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 8.772247] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 8.778559] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.784569] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 8.791598] systemd[1]: Finished Load Kernel Module fuse. machine # [ 8.793134] systemd[1]: Finished Load Kernel Modules. machine # [ 8.804581] systemd[1]: Mounting FUSE Control File System... machine # [ 8.823234] EXT4-fs (vda): re-mounted ff47869c-f810-4af5-b24c-746c5b111735. machine # [ 8.824656] systemd[1]: Starting Firewall... machine # [ 8.844055] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.865648] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.871229] systemd-oomd[279]: No swap; memory pressure usage will be degraded machine # [ 8.876286] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.888837] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.919575] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.935137] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.943024] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.953777] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.961185] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.017781] systemd[1]: Mounted FUSE Control File System. machine # [ 9.058770] systemd[1]: Finished Apply Kernel Variables. machine # [ 9.099133] systemd-journald[276]: Received client request to flush runtime journal. machine # [ 9.160619] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 9.164100] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 9.166040] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 9.173469] systemd[1]: Reached target First Boot Complete. machine # [ 9.176396] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 9.242618] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 9.244555] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 9.250683] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 9.311192] systemd[1]: Mounting /run/wrappers... machine # [ 9.390632] systemd[1]: Mounted /run/wrappers. machine # [ 9.393858] systemd[1]: Reached target Local File Systems. machine # [ 9.399445] systemd-udevd[316]: Using default interface naming scheme 'v261'. machine # [ 9.404845] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.408537] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.413676] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.426103] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.459498] systemd[1]: Starting Create System Files and Directories... machine # [ 9.560400] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.570011] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.652512] systemd[1]: Finished Create System Files and Directories. machine # [ 9.665852] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.684319] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.720307] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 9.821890] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.880209] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 9.889272] systemd[1]: Starting Update is Completed... machine # [ 9.961599] systemd[1]: Finished Update is Completed. machine # [ 10.484103] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 10.485945] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 10.693913] systemd[1]: Finished Coldplug All udev Devices. machine # [ 10.695869] systemd[1]: Reached target System Initialization. machine # [ 10.699343] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.705566] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.710309] systemd[1]: Reached target Timer Units. machine # [ 10.716931] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.719550] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.732570] systemd[1]: Reached target Socket Units. machine # [ 10.733401] systemd[1]: Reached target Basic System. machine # [ 10.734198] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.735244] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.748986] systemd[1]: Starting Post-Boot Actions... machine # [ 10.777718] systemd[1]: Started Reset console on configuration changes. machine # [ 10.820253] systemd[1]: Starting resolvconf update... machine # [ 10.875798] systemd[1]: Starting D-Bus System Message Bus... machine # [ 10.887771] systemd[1]: Finished Post-Boot Actions. machine # [ 10.966718] nsncd[462]: Sep 12 02:47:11.491 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.983273] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.990662] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.998036] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.004670] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 11.015573] systemd[1]: Starting User Login Management... machine # [ 11.037465] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 11.147718] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 11.162345] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync' machine # [ 11.165966] dbus-broker-launch[474]: Invalid user-name in /nix/store/9kv8dq8g2d35d432gq16rzvw9i61zlx4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 11.215485] systemd[1]: Started D-Bus System Message Bus. machine # [ 11.270998] dbus-broker-launch[474]: Ready machine # [ 11.273955] systemd-logind[496]: New seat seat0. machine # [ 11.281608] systemd[1]: Started User Login Management. machine # [ 11.287444] systemd[1]: Starting linger-users.service... machine # [ 11.291499] systemd[1]: Finished Firewall. machine # [ 11.324284] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 11.330976] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 11.336577] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 11.341187] systemd[1]: Stopping User and Group Name Lookups... machine # [ 11.347643] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 11.355279] systemd[1]: nscd.service: Deactivated successfully. machine # [ 11.359120] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 11.375031] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.403337] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 11.407904] systemd[1]: Finished linger-users.service. machine # [ 11.467157] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.474414] nsncd[559]: Sep 12 02:47:11.997 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.481839] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.485037] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.495094] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 11.509421] systemd[1]: Finished resolvconf update. machine # [ 11.513526] systemd[1]: Reached target Preparation for Network. machine # [ 11.524367] systemd[1]: Starting DHCP Client... machine # [ 11.535025] systemd[1]: Starting Extra networking commands.... machine # [ 11.552988] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 11.567532] systemd[1]: Started backdoor.service. machine # connecting to host... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 12.10 seconds) machine: (finished: waiting for the VM to finish booting, in 12.73 seconds) machine # [ 11.874101] dhcpcd[592]: dhcpcd-10.3.2 starting machine # [ 11.896456] dhcpcd[635]: dev: loaded udev machine # [ 11.904266] systemd[1]: Load Kernel Module loop skipped, unmet condition check ConditionKernelModuleLoaded=!loop machine # [ 11.911867] systemd[1]: Starting Portable Service Manager... machine # [ 11.985195] 8021q: 802.1Q VLAN Support v1.8 machine # [ 12.004732] systemd[1]: Finished Extra networking commands.. machine # [ 12.010142] systemd[1]: Reached target Network. machine # [ 12.018151] systemd[1]: Starting Permit User Sessions... machine # [ 12.060245] systemd[1]: Started Portable Service Manager. machine: (finished: must succeed: portablectl, in 13.03 seconds) machine: waiting for unit systemd-portabled.service machine # [ 12.113466] systemd[1]: Finished Permit User Sessions. machine # [ 12.125639] systemd[1]: Started Getty on tty1. machine # [ 12.130543] systemd[1]: Reached target Login Prompts. machine # [ 12.141559] (udev-worker)[363]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 12.143835] (udev-worker)[363]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.164771] (udev-worker)[467]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.208973] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 12.255057] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 12.255633] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 12.261604] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 12.261998] cfg80211: failed to load regulatory.db machine: (finished: waiting for unit systemd-portabled.service, in 0.25 seconds) machine: must succeed: portablectl attach --now --runtime /nix/store/kpzfyvp3hx7km54x6l6lc7qcn251sgkq-demo-img-1.0/demo_1.0.raw machine # (Matching unit files with prefix 'demo'.) machine # [ 12.405288] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 12.391013] dhcpcd[635]: eth0: waiting for carrier machine # [ 12.394670] dhcpcd[635]: libudev: received NULL device machine # [ 12.395587] dhcpcd[635]: libudev: received NULL device machine # [ 12.399845] dhcpcd[635]: eth0: carrier acquired machine # [ 12.439456] loop0: detected capacity change from 0 to 23976 machine # [ 12.427702] dhcpcd[635]: DUID 00:01:00:01:32:37:79:b0:52:54:00:12:34:56 machine # [ 12.428960] dhcpcd[635]: eth0: IAID 00:12:34:56 machine # [ 12.429688] dhcpcd[635]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.441444] systemd-logind[496]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 12.461616] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 12.479514] systemd[1]: Starting Address configuration of eth1... machine # [ 12.482229] dhcpcd[635]: eth0: IAID 00:12:34:56 machine # [ 12.555862] systemd-portabled[639]: Successfully attached ephemeral '/nix/store/kpzfyvp3hx7km54x6l6lc7qcn251sgkq-demo-img-1.0/demo_1.0.raw' using profile 'default' machine # [ 12.560545] systemd[1]: Reload requested from client PID 666 ('portablectl') (unit backdoor.service)... machine # [ 12.562651] systemd[1]: Reloading... machine # [ 12.648403] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 12.664215] network-addresses-eth1-start[676]: adding address 192.168.1.1/24... done machine # [ 12.686897] network-addresses-eth1-start[676]: adding address 2001:db8:1::1/64... done machine # [ 12.724894] dhcpcd[635]: eth0: soliciting a DHCP lease machine # [ 12.732705] dhcpcd[635]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.740315] dhcpcd[635]: eth0: probing address 10.0.2.15/24 machine # [ 12.876827] mousedev: PS/2 mouse device common for all mice machine # [ 13.000314] systemd-logind[496]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 13.516970] systemd[1]: Reloading finished in 950 ms. machine # Created directory /run/systemd/system.attached. machine # Created directory /run/systemd/system.attached/demo.service.d. machine # Written /run/systemd/system.attached/demo.service.d/20-portable.conf. machine # [ 13.591087] systemd[1]: Finished Address configuration of eth1. machine # Created symlink /run/systemd/system.attached/demo.service.d/10-profile.conf → /nix/store/zmx0fkcv0rzx39v279ji6d69ps1wah29-systemd-262-b504aa0/lib/systemd/portable/profile/default/service.conf. machine # Copied /run/systemd/system.attached/demo.service. machine # Created directory /run/systemd/system.attached/demo.socket.d. machine # Written /run/systemd/system.attached/demo.socket.d/20-portable.conf. machine # Copied /run/systemd/system.attached/demo.socket. machine # Created symlink /run/portables/demo_1.0.raw → /nix/store/kpzfyvp3hx7km54x6l6lc7qcn251sgkq-demo-img-1.0/demo_1.0.raw. machine # Queued /org/freedesktop/systemd1/job/578 to call StartUnit on portable service demo.socket. machine # Queued /org/freedesktop/systemd1/job/577 to call StartUnit on portable service demo.service. machine # [ 13.633789] systemd[1]: Listening on demo socket. machine # [ 13.636437] systemd[1]: Load Kernel Module dm_mod skipped, unmet condition check ConditionKernelModuleLoaded=!dm_mod machine # [ 13.643572] systemd[1]: Starting Load Kernel Module dm_verity... machine # [ 13.647274] systemd[1]: Load Kernel Module loop skipped, unmet condition check ConditionKernelModuleLoaded=!loop machine # [ 13.727345] systemd[1]: modprobe@dm_verity.service: Deactivated successfully. machine # [ 13.730643] systemd[1]: Finished Load Kernel Module dm_verity. machine # [ 13.744221] systemd[1]: Started demo service. machine: (finished: must succeed: portablectl attach --now --runtime /nix/store/kpzfyvp3hx7km54x6l6lc7qcn251sgkq-demo-img-1.0/demo_1.0.raw, in 1.43 seconds) machine: waiting for unit demo.service machine # [ 13.828441] loop1: detected capacity change from 0 to 23976 machine: (finished: waiting for unit demo.service, in 0.31 seconds) machine: must succeed: portablectl detach --now --runtime demo_1.0 machine # (Matching unit files with prefix 'demo'.) machine # [ 14.158347] loop2: detected capacity change from 0 to 23976 machine # [ 14.290461] systemd[1]: Stopping demo service... machine # Queued /org/freedesktop/systemd1/job/647 to call StopUnit on portable service demo.service. machine # Queued /org/freedesktop/systemd1/job/648 to call StopUnit on portable service demo.socket. machine # [ 14.364969] systemd[1]: demo.service: Deactivated successfully. machine # [ 14.367520] systemd[1]: Stopped demo service. machine # [ 14.371301] systemd[1]: demo.service: Consumed 234ms CPU time over 623ms wall clock time, 5.6M memory peak, 1.9M read from disk. machine # [ 14.377104] systemd[1]: demo.socket: Deactivated successfully. machine # [ 14.378026] systemd[1]: Closed demo socket. machine # [ 14.412848] systemd-portabled[639]: Successfully detached ephemeral 'demo_1.0' machine # [ 14.418996] systemd[1]: Reload requested from client PID 740 ('portablectl') (unit backdoor.service)... machine # [ 14.422970] systemd[1]: Reloading... machine # [ 14.618879] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 14.768500] dhcpcd[635]: eth0: soliciting an IPv6 router machine # [ 14.769959] dhcpcd[635]: eth0: Router Advertisement from fe80::2 machine # [ 14.770862] dhcpcd[635]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 14.771744] dhcpcd[635]: eth0: adding route to fec0::/64 machine # [ 14.772963] dhcpcd[635]: eth0: adding default route via fe80::2 machine # [ 15.256412] systemd-logind[496]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 15.353786] systemd[1]: Reloading finished in 927 ms. machine # Removed /run/systemd/system.attached/demo.socket. machine # Removed /run/systemd/system.attached/demo.socket.d/20-portable.conf. machine # Removed /run/systemd/system.attached/demo.socket.d. machine # Removed /run/systemd/system.attached/demo.service. machine # Removed /run/systemd/system.attached/demo.service.d/10-profile.conf. machine # Removed /run/systemd/system.attached/demo.service.d/20-portable.conf. machine # Removed /run/systemd/system.attached/demo.service.d. machine # Removed /run/portables/demo_1.0.raw. machine # Removed /run/systemd/system.attached. machine: (finished: must succeed: portablectl detach --now --runtime demo_1.0, in 1.34 seconds) machine: must fail: systemctl status demo.service machine # Unit demo.service could not be found. machine: (finished: must fail: systemctl status demo.service, in 0.07 seconds) (finished: run the VM test script, in 16.43 seconds) machine # [ 15.474081] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 15.484989] systemd[1]: Starting Virtual Console Setup... test script finished in 16.50s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.17 seconds) 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