vm-test-run-systemd-confinement
checks.aarch64-linux.systemd-confinement
· build #43
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.lEqviA7OO5', fmt=raw size=107374182413machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: 54468da2-83bc-4c45-a58d-67a92d7bb9dc17machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-09-24T02:50:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-24T02:50:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-24T02:50:37Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-09-24T02:50:37Z INFO virtiofsd] Client connected, servicing requests31machine # [2026-09-24T02:50:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32machine # [2026-09-24T02:50:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33machine # [2026-09-24T02:50:37Z INFO virtiofsd] Waiting for vhost-user socket connection...34machine # [2026-09-24T02:50:37Z INFO virtiofsd] Client connected, servicing requests35machine # [2026-09-24T02:50:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)36machine # [2026-09-24T02:50:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether37machine # [2026-09-24T02:50:37Z INFO virtiofsd] Waiting for vhost-user socket connection...38machine # [2026-09-24T02:50:37Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 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 202641machine # [ 0.000000] KASLR enabled42machine # [ 0.000000] random: crng init done43machine # [ 0.000000] Machine model: linux,dummy-virt44machine # [ 0.000000] efi: UEFI not found.45machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT46machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]47machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]48machine # [ 0.000000] Zone ranges:49machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]50machine # [ 0.000000] DMA32 empty51machine # [ 0.000000] Normal empty52machine # [ 0.000000] Device empty53machine # [ 0.000000] Movable zone start for each node54machine # [ 0.000000] Early memory node ranges55machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]56machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]57machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000058machine # [ 0.000000] psci: probing for conduit method from DT.59machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.60machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs61machine # [ 0.000000] psci: Trusted OS migration not required62machine # [ 0.000000] psci: SMC Calling Convention v1.163machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)64machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u31129665machine # [ 0.000000] Detected PIPT I-cache on CPU066machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)67machine # [ 0.000000] CPU features: detected: GICv3 CPU interface68machine # [ 0.000000] CPU features: detected: Spectre-v469machine # [ 0.000000] CPU features: detected: Spectre-BHB70machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3871machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2372machine # [ 0.000000] alternatives: applying boot alternatives73machine # [ 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/8bnw4slnr40lxdrkgzs76p645xna8gvz-nixos-system-machine-test/init regInfo=/nix/.ro-store/l91h9kldhs8kcdfawkp0g0m2awdflngg-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/l91h9kldhs8kcdfawkp0g0m2awdflngg-closure-info/registration", will be passed to user space.75machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes76machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)77machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)78machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB79machine # [ 0.000000] software IO TLB: area num 1.80machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)81machine # [ 0.000000] Fallback order for Node 0: 082machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214483machine # [ 0.000000] Policy zone: DMA84machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off85machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=186machine # [ 0.000000] allocated 2097152 bytes of page_ext87machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages88machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups89machine # [ 0.000000] rcu: Hierarchical RCU implementation.90machine # [ 0.000000] rcu: RCU event tracing is enabled.91machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.92machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.93machine # [ 0.000000] Rude variant of Tasks RCU enabled.94machine # [ 0.000000] Tracing variant of Tasks RCU enabled.95machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.96machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=197machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.98machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.99machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.100machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0101machine # [ 0.000000] GICv3: 256 SPIs implemented102machine # [ 0.000000] GICv3: 0 Extended SPIs implemented103machine # [ 0.000000] Root IRQ handler: gic_handle_irq104machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI105machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0106machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000107machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]108machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)110machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000112machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.113machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns114machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).115machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns116machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns117machine # [ 0.000036] arm-pv: using stolen time PV118machine # [ 0.000723] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000937] Console: colour dummy device 80x25120machine # [ 0.000945] printk: legacy console [tty0] enabled121machine # [ 0.001166] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.001173] pid_max: default: 32768 minimum: 301123machine # [ 0.001264] LSM: initializing lsm=capability,landlock,yama,bpf,ima124machine # [ 0.001441] landlock: Up and running.125machine # [ 0.001444] Yama: becoming mindful.126machine # [ 0.002101] LSM support for eBPF active127machine # [ 0.002263] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.002284] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.004223] rcu: Hierarchical SRCU implementation.130machine # [ 0.004230] rcu: Max phase no-delay instances is 1000.131machine # [ 0.005495] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005621] EFI services will not be available.133machine # [ 0.005694] smp: Bringing up secondary CPUs ...134machine # [ 0.005704] smp: Brought up 1 node, 1 CPU135machine # [ 0.005707] SMP: Total of 1 processors activated.136machine # [ 0.005710] CPU: All CPU(s) started at EL1137machine # [ 0.005725] CPU features: detected: Branch Target Identification138machine # [ 0.005730] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005733] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005737] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005741] CPU features: detected: Common not Private translations142machine # [ 0.005744] CPU features: detected: CRC32 instructions143machine # [ 0.005747] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005750] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005753] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005756] CPU features: detected: E0PD147machine # [ 0.005759] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005762] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005765] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005768] CPU features: detected: Fine Grained Traps151machine # [ 0.005772] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005777] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005780] CPU features: detected: LSE atomic instructions154machine # [ 0.005783] CPU features: detected: Privileged Access Never155machine # [ 0.005786] CPU features: detected: PMUv3156machine # [ 0.005789] CPU features: detected: RAS Extension Support157machine # [ 0.005792] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005795] CPU features: detected: Random Number Generator159machine # [ 0.005797] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005835] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005838] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005843] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005887] alternatives: applying system-wide alternatives164machine # [ 0.009140] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.009413] Memory: 893756K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113500K reserved, 32768K cma-reserved)166machine # [ 0.009847] devtmpfs: initialized167machine # [ 0.011660] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.011682] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.011881] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.011887] 0 pages in range for non-PLT usage171machine # [ 0.011889] 508224 pages in range for PLT usage172machine # [ 0.012015] pinctrl core: initialized pinctrl subsystem173machine # [ 0.012856] DMI not present or invalid.174machine # [ 0.016255] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.018536] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.018708] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.018877] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.018909] audit: initializing netlink subsys (disabled)179machine # [ 0.019526] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.019527] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.019531] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.019534] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.019537] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.019573] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.019581] cpuidle: using governor ladder186machine # [ 0.019587] cpuidle: using governor menu187machine # [ 0.019794] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.019810] ASID allocator initialised with 65536 entries189machine # [ 0.021090] Serial: AMBA PL011 UART driver190machine # [ 0.026956] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.027273] printk: console [ttyAMA0] enabled192machine # [ 0.155324] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.155337] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.155341] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.155344] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.155347] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.155349] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.155353] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.155355] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.156589] fbcon: Taking over console201machine # [ 0.156600] ACPI: Interpreter disabled.202machine # [ 0.166495] iommu: Default domain type: Translated203machine # [ 0.166503] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.166921] SCSI subsystem initialized205machine # [ 0.169025] usbcore: registered new interface driver usbfs206machine # [ 0.169063] usbcore: registered new interface driver hub207machine # [ 0.169087] usbcore: registered new device driver usb208machine # [ 0.169381] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.169385] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.169394] PTP clock support registered211machine # [ 0.169438] EDAC MC: Ver: 3.0.0212machine # [ 0.169634] scmi_core: SCMI protocol bus registered213machine # [ 0.176539] FPGA manager framework214machine # [ 0.177090] vgaarb: loaded215machine # [ 0.177366] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.184873] VFS: Disk quotas dquot_6.6.0217machine # [ 0.184905] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.188652] netfs: FS-Cache loaded219machine # [ 0.188792] pnp: PnP ACPI: disabled220machine # [ 0.192833] NET: Registered PF_INET protocol family221machine # [ 0.192997] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.223173] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.223216] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.223241] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.223285] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.223358] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.223446] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.223477] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.223525] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.223611] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.223694] NET: Registered PF_XDP protocol family232machine # [ 0.223710] PCI: CLS 0 bytes, default 64233machine # [ 0.224101] Trying to unpack rootfs image as initramfs...234machine # [ 0.235314] kvm [1]: HYP mode not available235machine # [ 0.390071] Initialise system trusted keyrings236machine # [ 0.390326] workingset: timestamp_bits=42 max_order=18 bucket_order=0237machine # [ 0.390711] squashfs: version 4.0 (2009/01/31) Phillip Lougher238machine # [ 0.390771] 9p: Installing v9fs 9p2000 file system support239machine # [ 0.413550] Key type asymmetric registered240machine # [ 0.413567] Asymmetric key parser 'x509' registered241machine # [ 0.413651] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242machine # [ 0.413838] io scheduler mq-deadline registered243machine # [ 0.413842] io scheduler kyber registered244machine # [ 0.429570] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245machine # [ 0.430313] ledtrig-cpu: registered to indicate activity on CPUs246machine # [ 0.430700] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247machine # [ 0.430714] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248machine # [ 0.430724] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249machine # [ 0.430729] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250machine # [ 0.430751] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251machine # [ 0.430778] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252machine # [ 0.430895] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253machine # [ 0.430903] pci_bus 0000:00: root bus resource [bus 00-ff]254machine # [ 0.430906] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255machine # [ 0.430909] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256machine # [ 0.430912] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257machine # [ 0.431022] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258machine # [ 0.431503] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259machine # [ 0.431689] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260machine # [ 0.431702] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261machine # [ 0.431728] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262machine # [ 0.431739] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263machine # [ 0.432214] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264machine # [ 0.432395] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.432407] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.432432] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.432895] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.433079] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.433090] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.433115] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.457165] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.457353] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.457365] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.465452] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.465475] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.466073] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.466268] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.466293] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.466761] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.466944] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.466969] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.467384] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.467561] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.467837] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285machine # [ 0.468023] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286machine # [ 0.468048] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287machine # [ 0.468504] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.468694] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.468719] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.469174] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.469356] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.485040] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.486430] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294machine # [ 0.486713] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295machine # [ 0.486725] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296machine # [ 0.486751] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297machine # [ 0.487216] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298machine # [ 0.487398] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299machine # [ 0.487409] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300machine # [ 0.487434] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301machine # [ 0.488072] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302machine # [ 0.488079] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303machine # [ 0.488082] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304machine # [ 0.488126] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305machine # [ 0.488168] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306machine # [ 0.488213] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307machine # [ 0.488257] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308machine # [ 0.488299] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309machine # [ 0.488341] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310machine # [ 0.488383] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311machine # [ 0.488427] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312machine # [ 0.488471] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313machine # [ 0.488568] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314machine # [ 0.488634] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315machine # [ 0.488655] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316machine # [ 0.488675] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317machine # [ 0.488695] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318machine # [ 0.488715] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319machine # [ 0.488735] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320machine # [ 0.488755] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321machine # [ 0.488775] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322machine # [ 0.488798] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323machine # [ 0.488818] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324machine # [ 0.488838] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325machine # [ 0.488856] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326machine # [ 0.488873] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327machine # [ 0.488890] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328machine # [ 0.488907] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329machine # [ 0.488924] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330machine # [ 0.488941] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331machine # [ 0.488958] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332machine # [ 0.488982] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333machine # [ 0.488987] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334machine # [ 0.488990] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335machine # [ 0.533992] pci 0000:00:07.0: enabling device (0000 -> 0002)336machine # [ 0.562942] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)337machine # [ 0.566912] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)338machine # [ 0.569292] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)339machine # [ 0.578764] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)340machine # [ 0.580846] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)341machine # [ 0.584671] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)342machine # [ 0.594938] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)343machine # [ 0.596914] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)344machine # [ 0.600536] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)345machine # [ 0.610901] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)346machine # [ 0.613348] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)347machine # [ 0.626737] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled348machine # [ 0.628524] msm_serial: driver initialized349machine # [ 0.628683] SuperH (H)SCI(F) driver initialized350machine # [ 0.628739] STM32 USART driver initialized351machine # [ 0.666805] loop: module loaded352machine # [ 0.667013] virtio_blk virtio2: 1/0/0 default/read/poll queues353machine # [ 0.667862] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)354machine # [ 0.672274] megasas: 07.734.00.00-rc1355machine # [ 0.673008] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]356machine # [ 0.682586] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000357machine # [ 0.682634] Intel/Sharp Extended Query Table at 0x0031358machine # [ 0.684339] Using buffer write method359machine # [ 0.684382] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]360machine # [ 0.691028] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000361machine # [ 0.691049] Intel/Sharp Extended Query Table at 0x0031362machine # [ 0.693084] Using buffer write method363machine # [ 0.693102] Concatenating MTD devices:364machine # [ 0.693104] (0): "0.flash"365machine # [ 0.693107] (1): "0.flash"366machine # [ 0.693109] into device "0.flash"367machine # [ 0.981685] Freeing initrd memory: 26388K368machine # [ 0.987334] tun: Universal TUN/TAP device driver, 1.6369machine # [ 0.991904] thunder_xcv, ver 1.0370machine # [ 0.991940] thunder_bgx, ver 1.0371machine # [ 0.991961] nicpf, ver 1.0372machine # [ 0.992533] e1000: Intel(R) PRO/1000 Network Driver373machine # [ 0.992537] e1000: Copyright (c) 1999-2006 Intel Corporation.374machine # [ 0.992563] e1000e: Intel(R) PRO/1000 Network Driver375machine # [ 0.992570] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.376machine # [ 0.992594] igb: Intel(R) Gigabit Ethernet Network Driver377machine # [ 0.992597] igb: Copyright (c) 2007-2014 Intel Corporation.378machine # [ 0.992642] igbvf: Intel(R) Gigabit Virtual Function Network Driver379machine # [ 0.992646] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.380machine # [ 0.992818] sky2: driver version 1.30381machine # [ 1.002081] usbcore: registered new interface driver usb-storage382machine # [ 1.002179] usbcore: registered new interface driver usbserial_generic383machine # [ 1.002191] usbserial: USB Serial support registered for generic384machine # [ 1.002827] hv_vmbus: registering driver hyperv_keyboard385machine # [ 1.004984] ehci-pci 0000:00:07.0: EHCI Host Controller386machine # [ 1.005029] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1387machine # [ 1.005296] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000388machine # [ 1.011087] rtc-pl031 9010000.pl031: registered as rtc0389machine # [ 1.011119] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T02:50:38 UTC (1790218238)390machine # [ 1.011617] i2c_dev: i2c /dev entries driver391machine # [ 1.013450] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00392machine # [ 1.013865] hub 1-0:1.0: USB hub found393machine # [ 1.013932] hub 1-0:1.0: 6 ports detected394machine # [ 1.020338] sdhci: Secure Digital Host Controller Interface driver395machine # [ 1.020345] sdhci: Copyright(c) Pierre Ossman396machine # [ 1.020638] Synopsys Designware Multimedia Card Interface Driver397machine # [ 1.021027] sdhci-pltfm: SDHCI platform and OF driver helper398machine # [ 1.025540] hid: raw HID events driver (C) Jiri Kosina399machine # [ 1.025859] usbcore: registered new interface driver usbhid400machine # [ 1.025868] usbhid: USB HID core driver401machine # [ 1.027100] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available402machine # [ 1.028784] drop_monitor: Initializing network drop monitor service403machine # [ 1.028952] NET: Registered PF_INET6 protocol family404machine # [ 1.034046] Segment Routing with IPv6405machine # [ 1.034061] In-situ OAM (IOAM) with IPv6406machine # [ 1.034093] NET: Registered PF_PACKET protocol family407machine # [ 1.034182] 9pnet: Installing 9P2000 support408machine # [ 1.034236] Key type dns_resolver registered409machine # [ 1.043222] registered taskstats version 1410machine # [ 1.043383] Loading compiled-in X.509 certificates411machine # [ 1.052460] Demotion targets for Node 0: null412machine # [ 1.052580] Key type .fscrypt registered413machine # [ 1.052586] Key type fscrypt-provisioning registered414machine # [ 1.052692] ima: No TPM chip found, activating TPM-bypass!415machine # [ 1.052709] ima: Allocated hash algorithm: sha1416machine # [ 1.052732] ima: No architecture policies found417machine # [ 1.057159] input: gpio-keys as /devices/platform/gpio-keys/input/input0418machine # [ 1.076593] clk: Disabling unused clocks419machine # [ 1.076613] PM: genpd: Disabling unused power domains420machine # [ 1.080990] Freeing unused kernel memory: 4736K421machine # [ 1.081172] Run /init as init process422machine # [ 1.102635] systemd[1]: Successfully made /usr/ read-only.423machine # [ 1.261535] usb 1-1: new high-speed USB device number 2 using ehci-pci424machine # [ 1.415828] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1425machine # [ 1.436978] systemd[1]: systemd 263-1f286f9 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)426machine # [ 1.437041] systemd[1]: Detected virtualization qemu.427machine # [ 1.437294] systemd[1]: Detected architecture arm64.428machine # [ 1.437316] systemd[1]: Running in initrd.429machine # [ 1.455812] systemd[1]: Initializing machine ID from random generator.430machine # [ 1.456205] systemd[1]: Hostname set to <machine>.431machine # [ 1.513690] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0432machine # [ 1.611592] systemd[1]: bpf-restrict-fs: LSM BPF program attached433machine # [ 1.637476] usb 1-2: new high-speed USB device number 3 using ehci-pci434machine # [ 1.676286] systemd[1]: Queued start job for default target Initrd Default Target.435machine # [ 1.683817] systemd[1]: Created slice Slice /system/modprobe.436machine # [ 1.684048] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.437machine # [ 1.684076] systemd[1]: Expecting device /dev/disk/by-label/nixos...438machine # [ 1.684097] systemd[1]: Reached target Path Units.439machine # [ 1.684110] systemd[1]: Reached target Slice Units.440machine # [ 1.684126] systemd[1]: Reached target Swaps.441machine # [ 1.684138] systemd[1]: Reached target Timer Units.442machine # [ 1.684282] systemd[1]: Listening on D-Bus System Message Bus Socket.443machine # [ 1.684469] systemd[1]: Listening on Journal Socket (/dev/log).444machine # [ 1.684684] systemd[1]: Listening on Journal Sockets.445machine # [ 1.684778] systemd[1]: Listening on udev Kernel Socket.446machine # [ 1.684792] systemd[1]: Reached target Socket Units.447machine # [ 1.695211] systemd[1]: Starting Create List of Static Device Nodes...448machine # [ 1.695355] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs449machine # [ 1.705680] systemd[1]: Mounting Kernel Configuration File System...450machine # [ 1.713882] systemd[1]: Starting Journal Service...451machine # [ 1.745733] systemd[1]: Starting Load Kernel Modules...452machine # [ 1.746086] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os453machine # [ 1.762324] systemd[1]: Starting Coldplug All udev Devices...454machine # [ 1.768970] systemd-journald[71]: Collecting audit messages is disabled.455machine # [ 1.779268] systemd[1]: Finished Create List of Static Device Nodes.456machine # [ 1.793270] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...457machine # [ 1.794794] systemd[1]: Mounted Kernel Configuration File System.458machine # [ 1.796735] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2459machine # [ 1.796895] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0460machine # [ 1.867777] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.461machine # [ 1.881614] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.462machine # [ 1.886575] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev463machine # [ 1.890969] systemd[1]: Starting Create Static Device Nodes in /dev...464machine # [ 1.903455] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0465machine # [ 1.903694] [drm] features: -virgl +edid -resource_blob -host_visible466machine # [ 1.903697] [drm] features: -context_init467machine # [ 1.904452] [drm] number of scanouts: 1468machine # [ 1.904464] [drm] number of cap sets: 0469machine # [ 1.925832] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic470machine # [ 1.925848] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0471machine # [ 1.958065] Console: switching to colour frame buffer device 160x50472machine # [ 1.958729] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device473machine # [ 1.974234] systemd[1]: Finished Load Kernel Modules.474machine # [ 1.982208] systemd[1]: Starting Apply Kernel Variables...475machine # [ 1.995612] systemd[1]: Finished Create Static Device Nodes in /dev.476machine # [ 1.996837] systemd[1]: Reached target Preparation for Local File Systems.477machine # [ 1.997009] systemd[1]: Reached target Local File Systems.478machine # [ 1.990305] systemd-modules-load[72]: Inserted module 'dm_mod'479machine # [ 1.999050] systemd-modules-load[72]: Module 'virtio_balloon' is built in480machine # [ 2.000605] systemd-modules-load[72]: Module 'virtio_console' is built in481machine # [ 2.001774] systemd-modules-load[72]: Inserted module 'virtio_gpu'482machine # [ 2.002850] systemd-modules-load[72]: Module 'virtio_rng' is built in483machine # [ 2.021748] systemd[1]: Starting Rule-based Manager for Device Events and Files...484machine # [ 2.022181] systemd[1]: Started Journal Service.485machine # [ 2.065228] systemd[1]: Starting Create System Files and Directories...486machine # [ 2.072149] systemd[1]: Finished Apply Kernel Variables.487machine # [ 2.124546] systemd[1]: Finished Create System Files and Directories.488machine # [ 2.131535] systemd-udevd[78]: Using default interface naming scheme 'v261'.489machine # [ 2.169718] systemd[1]: Started Rule-based Manager for Device Events and Files.490machine # [ 2.272230] systemd[1]: Starting Virtual Console Setup...491machine # [ 2.355099] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.492machine # [ 2.367899] systemd[1]: Finished Virtual Console Setup.493machine # [ 3.098173] systemd[1]: Finished Coldplug All udev Devices.494machine # [ 3.099235] systemd[1]: Reached target System Initialization.495machine # [ 3.100187] systemd[1]: Reached target Basic System.496machine # [ 3.312343] (udev-worker)[95]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.497machine # [ 3.316840] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.498machine # [ 3.401527] systemd[1]: Found device /dev/disk/by-label/nixos.499machine # [ 3.404318] systemd[1]: Reached target Initrd Root Device.500machine # [ 3.409333] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...501machine # [ 3.478544] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks502machine # [ 3.501025] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.503machine # [ 3.510382] systemd[1]: Mounting /sysroot...504machine # [ 3.572939] EXT4-fs (vda): mounted filesystem 54468da2-83bc-4c45-a58d-67a92d7bb9dc r/w with ordered data mode. Quota mode: none.505machine # [ 3.563818] systemd[1]: Mounted /sysroot.506machine # [ 3.567306] systemd[1]: Reached target Initrd Root File System.507machine # [ 3.573499] systemd[1]: Starting Mountpoints Configured in the Real Root...508machine # [ 3.603543] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.509machine # [ 3.612467] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...510machine # [ 3.616891] systemd[1]: Reloading...511machine # [ 3.857708] systemd[1]: Reloading finished in 246 ms.512machine # [ 3.887168] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...513machine # [ 3.892695] systemd[1]: Mounting /sysroot/nix/.ro-store...514machine # [ 3.914386] systemd[1]: Mounting /sysroot/nix/.rw-store...515machine # [ 3.919927] systemd[1]: Mounting /sysroot/run...516machine # [ 3.940630] systemd[1]: Mounting /sysroot/tmp/shared...517machine # [ 3.979935] systemd[1]: Mounting /sysroot/tmp/xchg...518machine # [ 3.985381] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...519machine # [ 4.027610] systemd[1]: initrd-parse-etc.service: Deactivated successfully.520machine # [ 4.078675] fuse: init (API version 7.45)521machine # [ 4.085722] virtiofs virtio6: discovered new tag: nix-store522machine # [ 4.086651] virtiofs virtio6: virtio_fs_setup_dax: No cache capability523machine # [ 4.076097] systemd[1]: Finished Mountpoints Configured in the Real Root.524machine # [ 4.078939] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.525machine # [ 4.103549] virtiofs virtio7: discovered new tag: shared526machine # [ 4.104439] virtiofs virtio7: virtio_fs_setup_dax: No cache capability527machine # [ 4.093990] systemd[1]: Mounted /sysroot/nix/.rw-store.528machine # [ 4.115468] virtiofs virtio8: discovered new tag: xchg529machine # [ 4.116319] virtiofs virtio8: virtio_fs_setup_dax: No cache capability530machine # [ 4.109093] systemd[1]: Mounted /sysroot/run.531machine # [ 4.127384] systemd[1]: Mounted /sysroot/nix/.ro-store.532machine # [ 4.138505] systemd[1]: Mounted /sysroot/tmp/shared.533machine # [ 4.150614] systemd[1]: Mounted /sysroot/tmp/xchg.534machine # [ 4.166195] systemd[1]: Starting rw-sysroot-nix-store.service...535machine # [ 4.207715] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.536machine # [ 4.210250] systemd[1]: Finished rw-sysroot-nix-store.service.537machine # [ 4.894050] systemd[1]: Mounting /sysroot/nix/store...538machine # [ 4.970464] systemd[1]: Mounted /sysroot/nix/store.539machine # [ 4.972714] systemd[1]: Reached target Initrd File Systems.540machine # [ 4.978914] systemd[1]: Starting Find NixOS closure...541machine # [ 4.990863] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...542machine # [ 5.056192] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.543machine # [ 5.061368] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.544machine # [ 5.089892] systemd[1]: Finished Find NixOS closure.545machine # [ 5.093793] systemd[1]: Reached target Initrd Default Target.546machine # [ 5.100529] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...547machine # [ 5.160625] systemd[1]: Stopped target Initrd Default Target.548machine # [ 5.162421] systemd[1]: Stopped target Basic System.549machine # [ 5.163295] systemd[1]: Stopped target Initrd Root Device.550machine # [ 5.165437] systemd[1]: Stopped target Path Units.551machine # [ 5.167097] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.552machine # [ 5.171722] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.553machine # [ 5.177215] systemd[1]: Stopped target Slice Units.554machine # [ 5.185983] systemd[1]: Stopped target Socket Units.555machine # [ 5.187923] systemd[1]: Stopped target System Initialization.556machine # [ 5.191003] systemd[1]: Stopped target Swaps.557machine # [ 5.197059] systemd[1]: Stopped target Timer Units.558machine # [ 5.197993] systemd[1]: dbus.socket: Deactivated successfully.559machine # [ 5.198958] systemd[1]: Closed D-Bus System Message Bus Socket.560machine # [ 5.199847] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.561machine # [ 5.203590] systemd[1]: Stopped Find NixOS closure.562machine # [ 5.207042] systemd[1]: Starting rw-sysroot-nix-store.service...563machine # [ 5.209448] systemd[1]: systemd-sysctl.service: Deactivated successfully.564machine # [ 5.219984] systemd[1]: Stopped Apply Kernel Variables.565machine # [ 5.222337] systemd[1]: systemd-modules-load.service: Deactivated successfully.566machine # [ 5.229559] systemd[1]: Stopped Load Kernel Modules.567machine # [ 5.232184] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.568machine # [ 5.235627] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.569machine # [ 5.239477] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.570machine # [ 5.242304] systemd[1]: Stopped Create System Files and Directories.571machine # [ 5.245293] systemd[1]: Stopped target Local File Systems.572machine # [ 5.247095] systemd[1]: Stopped target Preparation for Local File Systems.573machine # [ 5.250337] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.574machine # [ 5.252706] systemd[1]: Stopped Coldplug All udev Devices.575machine # [ 5.254867] systemd[1]: Stopping Rule-based Manager for Device Events and Files...576machine # [ 5.257874] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.577machine # [ 5.260694] systemd[1]: Stopped Virtual Console Setup.578machine # [ 5.264000] systemd[1]: initrd-cleanup.service: Deactivated successfully.579machine # [ 5.266947] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.580machine # [ 5.272197] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.581machine # [ 5.273451] systemd[1]: Finished rw-sysroot-nix-store.service.582machine # [ 5.295287] systemd[1]: systemd-udevd.service: Deactivated successfully.583machine # [ 5.297604] systemd[1]: Stopped Rule-based Manager for Device Events and Files.584machine # [ 5.300357] systemd[1]: systemd-udevd.service: Consumed 1.774s CPU time over 3.292s wall clock time, 18.6M memory peak.585machine # [ 5.306179] systemd[1]: Starting Cleanup udev Database...586machine # [ 5.308418] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.587machine # [ 5.312437] systemd[1]: Stopped Create Static Device Nodes in /dev.588machine # [ 5.314394] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.589machine # [ 5.320268] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.590machine # [ 5.321489] systemd[1]: kmod-static-nodes.service: Deactivated successfully.591machine # [ 5.322516] systemd[1]: Stopped Create List of Static Device Nodes.592machine # [ 5.386189] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.593machine # [ 5.388287] systemd[1]: Finished Cleanup udev Database.594machine # [ 5.390266] systemd[1]: Reached target Switch Root.595machine # [ 5.393928] systemd[1]: Starting NixOS Activation...596machine # [ 5.525622] initrd-nixos-activation-start[167]: booting system configuration /nix/store/8bnw4slnr40lxdrkgzs76p645xna8gvz-nixos-system-machine-test597machine # [ 5.578550] initrd-nixos-activation-start[167]: running activation script...598machine # [ 5.863713] initrd-nixos-activation-start[190]: setting up /etc...599machine # [ 6.003960] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.600machine # [ 6.007305] systemd[1]: Finished NixOS Activation.601machine # [ 6.008719] systemd[1]: Starting Switch Root...602machine # [ 6.034527] systemd[1]: Switching root.603machine # [ 6.228318] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).604machine # [ 6.749873] systemd[1]: systemd 263-1f286f9 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)605machine # [ 6.750006] systemd[1]: Detected virtualization qemu.606machine # [ 6.750090] systemd[1]: Detected architecture arm64.607machine # [ 6.750282] systemd[1]: Detected first boot.608machine # [ 6.770927] systemd[1]: Initializing machine ID from random generator.609machine # [ 6.940915] systemd[1]: bpf-restrict-fs: LSM BPF program attached610machine # [ 7.158628] systemd[1]: Applying preset policy.611machine # [ 7.442016] systemd[1]: Populated /etc with preset unit settings.612machine # [ 7.716143] systemd[1]: initrd-switch-root.service: Deactivated successfully.613machine # [ 7.716946] systemd[1]: Stopped initrd-switch-root.service.614machine # [ 7.722682] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.615machine # [ 7.724412] systemd[1]: Created slice Slice /system/getty.616machine # [ 7.728062] systemd[1]: Created slice User and Session Slice.617machine # [ 7.728636] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.618machine # [ 7.732230] systemd[1]: Started Forward Password Requests to Wall Directory Watch.619machine # [ 7.732657] systemd[1]: Expecting device /dev/hvc0...620machine # [ 7.736105] systemd[1]: Expecting device /dev/ttyAMA0...621machine # [ 7.736513] systemd[1]: Reached target Local Encrypted Volumes.622machine # [ 7.739887] systemd[1]: Stopped target initrd-fs.target.623machine # [ 7.740280] systemd[1]: Stopped target initrd-root-fs.target.624machine # [ 7.741241] systemd[1]: Stopped target initrd-switch-root.target.625machine # [ 7.745263] systemd[1]: Reached target Virtual Machines and Containers.626machine # [ 7.746595] systemd[1]: Reached target Path Units.627machine # [ 7.747522] systemd[1]: Reached target Remote File Systems.628machine # [ 7.748488] systemd[1]: Reached target Slice Units.629machine # [ 7.748797] systemd[1]: Reached target Swaps.630machine # [ 7.754214] systemd[1]: Listening on Query the User Interactively for a Password.631machine # [ 7.756368] systemd[1]: Listening on Process Core Dump Socket.632machine # [ 7.759835] systemd[1]: Listening on Credential Encryption/Decryption.633machine # [ 7.761203] systemd[1]: Listening on Factory Reset Management.634machine # [ 7.764471] systemd[1]: Listening on Hostname Service Socket.635machine # [ 7.769007] systemd[1]: Starting Journal Log Access Socket...636machine # [ 7.772514] systemd[1]: Listening on Journal Audit Socket.637machine # [ 7.776181] systemd[1]: Listening on Console Output Muting Service Socket.638machine # [ 7.776924] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.639machine # [ 7.780502] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os640machine # [ 7.781339] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki641machine # [ 7.788772] systemd[1]: Listening on Disk Repartitioning Service Socket.642machine # [ 7.791122] systemd[1]: Listening on udev Varlink Socket.643machine # [ 7.795323] systemd[1]: Mounting Huge Pages File System...644machine # [ 7.800267] systemd[1]: Mounting POSIX Message Queue File System...645machine # [ 7.817500] systemd[1]: Mounting Kernel Debug File System...646machine # [ 7.827636] systemd[1]: Mounting Kernel Trace File System...647machine # [ 7.837956] systemd[1]: Starting Create List of Static Device Nodes...648machine # [ 7.845579] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs649machine # [ 7.856731] systemd[1]: Mounting Kernel Configuration File System...650machine # [ 7.861562] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm651machine # [ 7.868407] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore652machine # [ 7.868909] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse653machine # [ 7.897712] systemd[1]: Mounting FUSE Control File System...654machine # [ 7.898192] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67655machine # [ 7.932142] systemd[1]: Starting Journal Service...656machine # [ 7.952598] systemd[1]: Starting Load Kernel Modules...657machine # [ 7.975664] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...658machine # [ 7.997278] systemd[1]: Starting Remount Root and Kernel File Systems...659machine # [ 7.997802] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os660machine # [ 8.013196] systemd[1]: Starting Coldplug All udev Devices...661machine # [ 8.035379] systemd-journald[262]: Collecting audit messages is enabled.662machine # [ 8.040556] systemd[1]: Listening on Journal Log Access Socket.663machine # [ 8.041082] systemd[1]: Mounted Huge Pages File System.664machine # [ 8.030521] systemd[1]: Queued start job for default target Multi-User System.665machine # [ 8.040758] systemd[1]: systemd-journald.service: Deactivated successfully.666machine # [ 8.066567] systemd[1]: Started Journal Service.667machine # [ 8.057214] systemd[1]: Mounted POSIX Message Queue File System.668machine # [ 8.065229] systemd[1]: Mounted Kernel Debug File System.669machine # [ 8.066178] systemd[1]: Mounted Kernel Trace File System.670machine # [ 8.073372] systemd[1]: Finished Create List of Static Device Nodes.671machine # [ 8.095714] systemd-modules-load[263]: Module 'atkbd' is built in672machine # [ 8.100486] systemd-modules-load[263]: Module 'loop' is built in673machine # [ 8.101603] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...674machine # [ 8.117198] systemd-oomd[264]: No swap; memory pressure usage will be degraded675machine # [ 8.126945] systemd[1]: Finished Load Kernel Modules.676machine # [ 8.127814] systemd[1]: Mounted Kernel Configuration File System.677machine # [ 8.136580] systemd[1]: Starting Firewall...678machine # [ 8.157537] EXT4-fs (vda): re-mounted 54468da2-83bc-4c45-a58d-67a92d7bb9dc.679machine # [ 8.154790] systemd[1]: Starting Apply Kernel Variables...680machine # [ 8.155745] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.681machine # [ 8.170001] systemd[1]: Finished Remount Root and Kernel File Systems.682machine # [ 8.206860] systemd[1]: Mounted FUSE Control File System.683machine # [ 8.208953] systemd[1]: Listening on Disk Image Download Service Socket.684machine # [ 8.221206] systemd[1]: Starting Flush Journal to Persistent Storage...685machine # [ 8.222285] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore686machine # [ 8.232982] systemd[1]: Starting Load/Save OS Random Seed...687machine # [ 8.241723] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os688machine # [ 8.349874] systemd-journald[262]: Received client request to flush runtime journal.689machine # [ 8.580620] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.690machine # [ 8.583707] systemd[1]: Finished Apply Kernel Variables.691machine # [ 8.587184] systemd[1]: Starting Create Static Device Nodes in /dev...692machine # [ 8.592640] systemd[1]: Finished Load/Save OS Random Seed.693machine # [ 8.593753] systemd[1]: Reached target First Boot Complete.694machine # [ 8.594496] systemd[1]: Finished Flush Journal to Persistent Storage.695machine # [ 8.652981] systemd[1]: Finished Create Static Device Nodes in /dev.696machine # [ 8.655947] systemd[1]: Reached target Preparation for Local File Systems.697machine # [ 8.662457] systemd[1]: Starting Rule-based Manager for Device Events and Files...698machine # [ 8.708276] systemd[1]: Mounting /run/wrappers...699machine # [ 8.807136] systemd[1]: Mounted /run/wrappers.700machine # [ 8.807996] systemd[1]: Reached target Local File Systems.701machine # [ 8.814841] systemd[1]: Listening on Boot Loader Control Service Socket.702machine # [ 8.821798] systemd[1]: Starting register-nix-paths.service...703machine # [ 8.826921] systemd[1]: Starting Create SUID/SGID Wrappers...704machine # [ 8.827903] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.705machine # [ 8.838358] systemd[1]: Starting Save Transient machine-id to Disk...706machine # [ 8.864281] systemd[1]: Starting Create System Files and Directories...707machine # [ 8.977203] systemd-udevd[301]: Using default interface naming scheme 'v261'.708machine # [ 9.144160] systemd[1]: Finished Create System Files and Directories.709machine # [ 9.164552] systemd[1]: Starting Rebuild Journal Catalog...710machine # [ 9.180658] systemd[1]: Starting Record System Boot/Shutdown in UTMP...711machine # [ 9.181832] systemd[1]: Started Rule-based Manager for Device Events and Files.712machine # [ 9.455462] systemd[1]: Finished Record System Boot/Shutdown in UTMP.713machine # [ 9.465842] systemd[1]: Finished Rebuild Journal Catalog.714machine # [ 9.479102] systemd[1]: Starting Update is Completed...715machine # [ 9.605412] systemd[1]: Finished Update is Completed.716machine # [ 9.806115] systemd[1]: Finished Coldplug All udev Devices.717machine # [ 9.907842] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.718machine # [ 9.921235] systemd[1]: Finished Save Transient machine-id to Disk.719machine # [ 10.157489] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3720machine # [ 10.386591] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.721machine # [ 10.393432] systemd[1]: Finished Create SUID/SGID Wrappers.722machine # [ 10.396132] systemd[1]: Finished register-nix-paths.service.723machine # [ 10.397181] systemd[1]: Reached target System Initialization.724machine # [ 10.398071] systemd[1]: Started Discard unused filesystem blocks once a week.725machine # [ 10.405670] systemd[1]: Started Daily Cleanup of Temporary Directories.726machine # [ 10.406870] systemd[1]: Reached target Timer Units.727machine # [ 10.428064] systemd[1]: Listening on Socket for concurrent processes.728machine # [ 10.429240] systemd[1]: Listening on D-Bus System Message Bus Socket.729machine # [ 10.430222] systemd[1]: Listening on Nix Daemon Socket.730machine # [ 10.431046] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.731machine # [ 10.439550] systemd[1]: Reached target Socket Units.732machine # [ 10.446072] systemd[1]: Reached target Basic System.733machine # [ 10.446940] systemd[1]: Starting Import lastlog data into lastlog2 database...734machine # [ 10.447959] systemd[1]: Starting Name Service Cache Daemon (nsncd)...735machine # [ 10.455801] systemd[1]: Starting Post-Boot Actions...736machine # [ 10.463397] systemd[1]: Started Reset console on configuration changes.737machine # [ 10.479565] systemd[1]: Starting resolvconf update...738machine # [ 10.508064] systemd[1]: Starting root, chroot-only confinement with PrivateTmp=true...739machine # [ 10.556938] systemd[1]: Starting dynamic-user, full APIVFS confinement without PrivateTmp...740machine # [ 10.594653] systemd[1]: Starting static-user, chroot-only confinement without PrivateTmp...741machine # [ 10.608112] systemd[1]: Starting static-user, full APIVFS confinement without PrivateTmp...742machine # [ 10.643384] nsncd[438]: Sep 24 02:50:48.146 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"743machine # [ 10.681002] systemd[1]: Starting root, chroot-only confinement with PrivateTmp=disconnected...744machine # [ 10.732096] systemd[1]: Starting root, full APIVFS confinement with PrivateTmp=disconnected...745machine # [ 10.748416] systemd[1]: Starting dynamic-user, chroot-only confinement with PrivateTmp=disconnected...746machine # [ 10.761509] systemd[1]: Starting dynamic-user, full APIVFS confinement with PrivateTmp=disconnected...747machine # [ 10.802608] systemd[1]: Starting static-user, chroot-only confinement with PrivateTmp=disconnected...748machine # [ 10.912283] systemd[1]: Starting static-user, full APIVFS confinement with PrivateTmp=disconnected...749machine # [ 10.928993] systemd[1]: Starting existence of bind-mounted /etc...750machine # [ 10.951049] systemd[1]: Starting root, full APIVFS confinement with PrivateTmp=true...751machine # [ 10.988165] systemd[1]: Starting check if symlinks are properly bind-mounted...752machine # [ 11.083340] systemd[1]: Starting check if StateDirectory works...753machine # [ 11.152831] systemd[1]: Starting check if /bin/sh works...754machine # [ 11.259395] systemd[1]: Starting check if suppressing /bin/sh works...755machine # [ 11.332488] systemd[1]: Starting check if we can set /bin/sh to something different...756machine # [ 11.360768] systemd[1]: Starting check if only Exec* dependencies are included...757machine # [ 11.507093] systemd[1]: Starting check if fullUnit includes all dependencies...758machine # [ 11.606158] systemd[1]: Starting check if shipped unit file still works...759machine # [ 11.745284] systemd[1]: Starting dynamic-user, chroot-only confinement with PrivateTmp=true...760machine # [ 11.884926] systemd[1]: Starting dynamic-user, full APIVFS confinement with PrivateTmp=true...761machine # [ 12.010387] systemd[1]: Starting static-user, chroot-only confinement with PrivateTmp=true...762machine # [ 12.126159] systemd[1]: Starting static-user, full APIVFS confinement with PrivateTmp=true...763machine # [ 12.308558] systemd[1]: Starting root, chroot-only confinement without PrivateTmp...764machine # [ 12.397248] systemd[1]: Starting root, full APIVFS confinement without PrivateTmp...765machine # [ 12.470342] systemd[1]: Starting dynamic-user, chroot-only confinement without PrivateTmp...766machine # [ 12.495081] systemd[1]: Started Name Service Cache Daemon (nsncd).767machine # [ 12.779625] systemd[1]: Finished Post-Boot Actions.768machine # [ 13.373999] systemd[1]: Finished Import lastlog data into lastlog2 database.769machine # [ 13.400271] systemd[1]: Finished Firewall.770machine # [ 13.719854] systemd[1]: Starting D-Bus System Message Bus...771machine # [ 13.739062] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...772machine # [ 13.807657] systemd[1]: Starting Virtual Console Setup...773machine # [ 13.840440] systemd[1]: nscd.service: Deactivated successfully.774machine # [ 13.892105] systemd[1]: Stopped Name Service Cache Daemon (nsncd).775machine # [ 14.823761] systemd[1]: Starting Name Service Cache Daemon (nsncd)...776machine # [ 15.175983] systemd[1]: Finished resolvconf update.777machine # [ 15.312526] systemd[1]: Reached target Preparation for Network.778machine # [ 15.477644] systemd[1]: Starting DHCP Client...779machine # [ 15.677394] systemd[1]: Starting Extra networking commands....780machine # [ 16.217375] nsncd[619]: Sep 24 02:50:53.722 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"781machine # [ 16.344821] systemd[1]: Started Name Service Cache Daemon (nsncd).782machine # [ 16.346143] systemd[1]: Reached target Host and Network Name Lookups.783machine # [ 16.347210] systemd[1]: Reached target User and Group Name Lookups.784machine # [ 16.497393] dbus-broker-launch[592]: Looking up NSS user entry for 'systemd-timesync'...785machine # [ 16.660165] systemd[1]: Starting User Login Management...786machine # [ 16.749201] dbus-broker-launch[592]: NSS returned no entry for 'systemd-timesync'787machine # [ 16.750438] dbus-broker-launch[592]: Invalid user-name in /nix/store/c6dq2gffyhy2xmyriw97yyfgahp7lwjx-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"788machine # [ 17.090736] systemd[1]: Started D-Bus System Message Bus.789machine # [ 17.596315] dbus-broker-launch[592]: Ready790machine # [ 18.422273] systemd-logind[642]: New seat seat0.791machine # [ 18.475874] systemd[1]: Started User Login Management.792machine # [ 18.605377] systemd[1]: Starting linger-users.service...793machine # [ 19.540611] systemd[1]: linger-users.service: Deactivated successfully.794machine # [ 19.577610] dhcpcd[655]: dhcpcd-10.3.2 starting795machine # [ 19.623541] systemd[1]: Finished linger-users.service.796machine # [ 19.733346] dhcpcd[687]: dev: loaded udev797machine # [ 19.771182] dhcpcd[687]: no valid interfaces found798machine # [ 19.807311] dhcpcd[687]: libudev: received NULL device799machine # [ 19.846589] dhcpcd[687]: no valid interfaces found800machine # [ 19.847617] dhcpcd[687]: libudev: received NULL device801machine # [ 20.133208] systemd-vconsole-setup[594]: Configuration of first virtual console was skipped, ignoring remaining ones.802machine # [ 20.344707] systemd[1]: Finished Virtual Console Setup.803machine # [ 21.590792] systemd[1]: Finished Extra networking commands..804machine # [ 21.595593] systemd[1]: Reached target Network.805machine # [ 22.225892] systemd[1]: Starting Driver for orchestrating concurrent processes...806machine # [ 22.227183] systemd[1]: Starting Permit User Sessions...807machine # [ 22.785748] systemd[1]: Finished Permit User Sessions.808machine # [ 22.971344] systemd[1]: Started Getty on tty1.809machine # [ 22.999276] systemd[1]: Reached target Login Prompts.810machine # [ 23.123922] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs811machine: Guest root shell did not produce any data yet...812machine # [ 29.596761] systemd[1]: test19.service: Deactivated successfully.813machine: To debug, enter the VM and run 'systemctl status backdoor.service'.814machine # [ 29.640725] systemd[1]: Finished existence of bind-mounted /etc.815machine # [ 30.055065] systemd[1]: test20.service: Deactivated successfully.816machine # [ 30.093379] systemd[1]: Finished check if symlinks are properly bind-mounted.817machine # [ 30.576458] systemd[1]: test22.service: Deactivated successfully.818machine # [ 30.627179] systemd[1]: Finished check if /bin/sh works.819machine # [ 30.961826] systemd[1]: test23.service: Deactivated successfully.820machine # [ 31.009488] systemd[1]: Finished check if suppressing /bin/sh works.821machine # [ 31.148778] systemd[1]: test27.service: Deactivated successfully.822machine # [ 31.192692] systemd[1]: Finished check if shipped unit file still works.823machine # [ 31.304788] systemd[1]: test24.service: Deactivated successfully.824machine # [ 31.348482] systemd[1]: Finished check if we can set /bin/sh to something different.825machine # [ 31.513733] systemd[1]: test25.service: Deactivated successfully.826machine # [ 31.548421] systemd[1]: Finished check if only Exec* dependencies are included.827machine # [ 31.577318] 6fjlw2vx7clbbd5asbsly9mfnyppcxyy-test21.py[491]: Exiting with failure to check persistence on restart.828machine # [ 34.038390] systemd[1]: test21.service: Main process exited, code=exited, status=1/FAILURE829machine # [ 34.052789] systemd[1]: test21.service: Failed with result 'exit-code'.830machine # [ 34.306307] systemd[1]: test26.service: Deactivated successfully.831machine # [ 34.320120] systemd[1]: Finished check if fullUnit includes all dependencies.832machine # [ 34.563261] systemd[1]: test21.service: Scheduled restart job, restart counter is at 1.833machine # [ 34.703679] systemd[1]: Starting check if StateDirectory works...834machine # [ 41.621036] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.835machine # [ 42.660968] 8021q: 802.1Q VLAN Support v1.8836machine # [ 42.652462] (udev-worker)[819]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.837machine # [ 42.658534] (udev-worker)[819]: Network interface NamePolicy= disabled on kernel command line.838machine # [ 43.601873] systemd[1]: Condition check resulted in Virtio network device being skipped.839machine # [ 43.675168] systemd[1]: Starting Address configuration of eth1...840machine # [ 43.734317] cfg80211: Loading compiled-in X.509 certificates for regulatory database841machine # [ 43.994076] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'842machine # [ 43.994259] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'843machine # [ 44.008591] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2844machine # [ 44.008629] cfg80211: failed to load regulatory.db845machine # [ 44.568189] 8021q: adding VLAN 0 to HW filter on device eth1846machine # [ 44.752743] network-addresses-eth1-start[833]: adding address 192.168.1.1/24... done847machine # [ 44.892039] 8021q: adding VLAN 0 to HW filter on device eth0848machine # [ 44.888839] dhcpcd[687]: eth0: waiting for carrier849machine # [ 44.917136] dhcpcd[687]: eth0: carrier acquired850machine # [ 44.919859] network-addresses-eth1-start[833]: adding address 2001:db8:1::1/64... done851machine # [ 45.045409] dhcpcd[687]: DUID 00:01:00:01:32:47:4c:aa:52:54:00:12:34:56852machine # [ 45.062085] dhcpcd[687]: eth0: IAID 00:12:34:56853machine # [ 45.063217] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.854machine # [ 45.112233] dhcpcd[687]: eth0: adding address fe80::5054:ff:fe12:3456855machine # [ 45.140757] systemd[1]: Started backdoor.service.856machine # [ 45.187740] systemd[1]: Finished Address configuration of eth1.857machine # connecting to host...858machine: Guest shell says: b'Spawning backdoor root shell...\n'859machine: connected to guest root shell860machine: (connecting took 46.58 seconds)861machine: (finished: waiting for the VM to finish booting, in 47.42 seconds)862machine # [ 46.554828] dhcpcd[687]: eth0: soliciting a DHCP lease863machine # [ 46.583899] dhcpcd[687]: eth0: offered 10.0.2.15 from 10.0.2.2864machine # [ 46.604665] dhcpcd[687]: eth0: probing address 10.0.2.15/24865machine # [ 47.281022] dhcpcd[687]: eth0: soliciting an IPv6 router866machine # [ 47.284213] dhcpcd[687]: eth0: Router Advertisement from fe80::2867machine # [ 47.285215] dhcpcd[687]: eth0: adding address fec0::5054:ff:fe12:3456/64868machine # [ 47.286184] dhcpcd[687]: eth0: adding route to fec0::/64869machine # [ 47.286943] dhcpcd[687]: eth0: adding default route via fe80::2870machine # [ 48.941042] systemd[1]: test21.service: Deactivated successfully.871machine # [ 48.974777] systemd[1]: Finished check if StateDirectory works.872machine # [ 49.866969] dhcpcd[687]: timed out873machine # [ 49.867216] dhcpcd[687]: timed out874machine # [ 49.872732] systemd[1]: Started DHCP Client.875machine # [ 51.872996] dhcpcd[687]: eth0: leased 10.0.2.15 for 86400 seconds876machine # [ 51.873206] dhcpcd[687]: eth0: adding route to 10.0.2.0/24877machine # [ 51.873255] dhcpcd[687]: eth0: adding default route via 10.0.2.2878machine # [ 56.441152] systemd-logind[642]: Watching system buttons on /dev/input/event0 (gpio-keys)879machine # [ 60.365472] mousedev: PS/2 mouse device common for all mice880machine # [ 60.778209] systemd-logind[642]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)881machine # [ 92.453422] systemd[1]: Created slice Slice /system/concurrent-client.882machine # [ 92.611880] systemd[1]: Started Process 0/1/::1:12345/::1:36198 running concurrently with others ([::1]:36198).883machine # [ 106.183457] systemd[1]: test1.service: Deactivated successfully.884machine # [ 106.189872] systemd[1]: Finished root, chroot-only confinement with PrivateTmp=true.885machine # [ 106.194591] systemd[1]: test1.service: Consumed 3.941s CPU time over 1min 35.679s wall clock time, 50M memory peak.886machine # [ 106.219331] systemd[1]: test10.service: Deactivated successfully.887machine # [ 106.222895] systemd[1]: Finished dynamic-user, full APIVFS confinement without PrivateTmp.888machine # [ 106.228362] systemd[1]: test10.service: Consumed 3.913s CPU time over 1min 35.666s wall clock time, 24M memory peak.889machine # [ 106.229835] systemd[1]: test11.service: Deactivated successfully.890machine # [ 106.241847] systemd[1]: Finished static-user, chroot-only confinement without PrivateTmp.891machine # [ 106.243099] systemd[1]: test11.service: Consumed 3.838s CPU time over 1min 35.631s wall clock time, 30.3M memory peak.892machine # [ 106.251046] systemd[1]: test12.service: Deactivated successfully.893machine # [ 106.251997] systemd[1]: Finished static-user, full APIVFS confinement without PrivateTmp.894machine # [ 106.257833] systemd[1]: test12.service: Consumed 3.894s CPU time over 1min 35.630s wall clock time, 28.2M memory peak.895machine # [ 106.259283] systemd[1]: test13.service: Deactivated successfully.896machine # [ 106.267096] systemd[1]: Finished root, chroot-only confinement with PrivateTmp=disconnected.897machine # [ 106.274912] systemd[1]: test13.service: Consumed 3.955s CPU time over 1min 35.571s wall clock time, 35.3M memory peak.898machine # [ 106.280139] systemd[1]: test14.service: Deactivated successfully.899machine # [ 106.287074] systemd[1]: Finished root, full APIVFS confinement with PrivateTmp=disconnected.900machine # [ 106.290444] systemd[1]: test14.service: Consumed 3.965s CPU time over 1min 35.549s wall clock time, 36.1M memory peak.901machine # [ 106.303793] systemd[1]: test15.service: Deactivated successfully.902machine # [ 106.306126] systemd[1]: Finished dynamic-user, chroot-only confinement with PrivateTmp=disconnected.903machine # [ 106.312775] systemd[1]: test15.service: Consumed 3.829s CPU time over 1min 35.563s wall clock time, 25.1M memory peak.904machine # [ 106.324175] systemd[1]: test16.service: Deactivated successfully.905machine # [ 106.326433] systemd[1]: Finished dynamic-user, full APIVFS confinement with PrivateTmp=disconnected.906machine # [ 106.327745] systemd[1]: test16.service: Consumed 3.922s CPU time over 1min 35.566s wall clock time, 23.7M memory peak.907machine # [ 106.338740] systemd[1]: test17.service: Deactivated successfully.908machine # [ 106.345419] systemd[1]: Finished static-user, chroot-only confinement with PrivateTmp=disconnected.909machine # [ 106.346734] systemd[1]: test17.service: Consumed 3.847s CPU time over 1min 35.539s wall clock time, 28.2M memory peak.910machine # [ 106.357616] systemd[1]: test18.service: Deactivated successfully.911machine # [ 106.359842] systemd[1]: Finished static-user, full APIVFS confinement with PrivateTmp=disconnected.912machine # [ 106.362309] systemd[1]: test18.service: Consumed 3.885s CPU time over 1min 35.448s wall clock time, 23.8M memory peak.913machine # [ 106.373179] systemd[1]: test2.service: Deactivated successfully.914machine # [ 106.374116] systemd[1]: Finished root, full APIVFS confinement with PrivateTmp=true.915machine # [ 106.375153] systemd[1]: test2.service: Consumed 4.003s CPU time over 1min 35.426s wall clock time, 27.2M memory peak.916machine # [ 106.389307] systemd[1]: test3.service: Deactivated successfully.917machine # [ 106.390522] systemd[1]: Finished dynamic-user, chroot-only confinement with PrivateTmp=true.918machine # [ 106.392502] systemd[1]: test3.service: Consumed 3.877s CPU time over 1min 34.647s wall clock time, 26.1M memory peak.919machine # [ 106.403603] systemd[1]: test4.service: Deactivated successfully.920machine # [ 106.408218] systemd[1]: Finished dynamic-user, full APIVFS confinement with PrivateTmp=true.921machine # [ 106.409399] systemd[1]: test4.service: Consumed 3.888s CPU time over 1min 34.524s wall clock time, 25.2M memory peak.922machine # [ 106.418952] systemd[1]: test5.service: Deactivated successfully.923machine # [ 106.424243] systemd[1]: Finished static-user, chroot-only confinement with PrivateTmp=true.924machine # [ 106.432217] systemd[1]: test5.service: Consumed 3.851s CPU time over 1min 34.420s wall clock time, 26.4M memory peak.925machine # [ 106.438277] systemd[1]: test6.service: Deactivated successfully.926machine # [ 106.439185] systemd[1]: Finished static-user, full APIVFS confinement with PrivateTmp=true.927machine # [ 106.446284] systemd[1]: test6.service: Consumed 3.905s CPU time over 1min 34.326s wall clock time, 24.9M memory peak.928machine # [ 106.453241] systemd[1]: test7.service: Deactivated successfully.929machine # [ 106.455894] systemd[1]: Finished root, chroot-only confinement without PrivateTmp.930machine # [ 106.461372] systemd[1]: test7.service: Consumed 3.903s CPU time over 1min 34.156s wall clock time, 28.8M memory peak.931machine # [ 106.462819] systemd[1]: test8.service: Deactivated successfully.932machine # [ 106.468110] systemd[1]: Finished root, full APIVFS confinement without PrivateTmp.933machine # [ 106.469198] systemd[1]: test8.service: Consumed 3.935s CPU time over 1min 34.079s wall clock time, 27.9M memory peak.934machine # [ 106.470583] systemd[1]: test9.service: Deactivated successfully.935machine # [ 106.480284] systemd[1]: Finished dynamic-user, chroot-only confinement without PrivateTmp.936machine # [ 106.481487] systemd[1]: test9.service: Consumed 3.848s CPU time over 1min 34.001s wall clock time, 24.6M memory peak.937machine # [ 106.538456] systemd[1]: concurrent-client@0-1-::1:12345-::1:36198.service: Deactivated successfully.938machine # [ 106.673908] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: You can reproduce this failure by adding @seed(331665625559361192059654682297525236737) to this test.939machine # [ 106.684119] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: Traceback (most recent call last):940machine # [ 106.685531] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner", line 132, in <module>941machine # [ 106.687340] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: cli()942machine # [ 106.697448] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: ~~~^^943machine # [ 106.698536] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/click/core.py", line 1514, in __call__944machine # [ 106.712164] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: return self.main(*args, **kwargs)945machine # [ 106.713494] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: ~~~~~~~~~^^^^^^^^^^^^^^^^^946machine # [ 106.714712] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/click/core.py", line 1435, in main947machine # [ 106.721824] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: rv = self.invoke(ctx)948machine # [ 106.723008] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/click/core.py", line 1902, in invoke949machine # [ 106.732213] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: return _process_result(sub_ctx.command.invoke(sub_ctx))950machine # [ 106.733743] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^951machine # [ 106.735192] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/click/core.py", line 1298, in invoke952machine # [ 106.748134] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: return ctx.invoke(self.callback, **ctx.params)953machine # [ 106.749619] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^954machine # [ 106.751022] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/click/core.py", line 853, in invoke955machine # [ 106.768156] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: return callback(*args, **kwargs)956machine # [ 106.769446] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner", line 72, in test_driver957machine # [ 106.771272] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: @settings(deadline=None, max_examples=20)958machine # [ 106.779346] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: ^^^959machine # [ 106.784183] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/hypothesis/core.py", line 2274, in wrapped_test960machine # [ 106.786640] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: raise the_error_hypothesis_found961machine # [ 106.787864] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: File "/nix/store/wkckfq9icssz9ip5ql9dmc5ag9vsd0q3-python3-3.14.7-env/lib/python3.14/site-packages/hypothesis/internal/healthcheck.py", line 28, in fail_health_check962machine # [ 106.808159] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: raise FailedHealthCheck(message)963machine # [ 106.809468] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: hypothesis.errors.FailedHealthCheck: Input generation is slow: Hypothesis only generated 2 valid inputs after 44.74 seconds.964machine # [ 106.811682] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: count | fraction | slowest draws (seconds)965machine # [ 106.824256] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: client_actions | 2 | 100% | -- -- -- -- 44.741966machine # [ 106.826000] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: This could be for a few reasons:967machine # [ 106.827260] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: 1. This strategy could be generating too much data per input. Try decreasing the amount of data generated, for example by decreasing the minimum size of collection strategies like st.lists().968machine # [ 106.840232] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: 2. Some other expensive computation could be running during input generation. For example, if @st.composite or st.data() is interspersed with an expensive computation, HealthCheck.too_slow is likely to trigger. If this computation is unrelated to input generation, move it elsewhere. Otherwise, try making it more efficient, or disable this health check if that is not possible.969machine # [ 106.859708] h9w6ky85x5zxjfwnfzwpjk515bszby8n-concurrent-runner[719]: If you expect input generation to take this long, you can disable this health check with @settings(suppress_health_check=[HealthCheck.too_slow]). See https://hypothesis.readthedocs.io/en/latest/reference/api.html#hypothesis.HealthCheck for details.970machine # [ 107.078307] systemd[1]: concurrent-driver.service: Main process exited, code=exited, status=1/FAILURE971machine # [ 107.080380] systemd[1]: concurrent-driver.service: Failed with result 'exit-code'.972machine # [ 107.087324] systemd[1]: Failed to start Driver for orchestrating concurrent processes.973machine # [ 107.092550] systemd[1]: Dependency failed for Multi-User System.974machine # [ 107.093789] systemd[1]: multi-user.target: Job multi-user.target/start failed with result 'dependency'.975machine # [ 107.101459] systemd[1]: Startup finished in 1.068s (kernel) + 5.246s (initrd) + 1min 40.785s (userspace) = 1min 47.100s.976machine # [ 107.103058] systemd[1]: concurrent-driver.service: Consumed 3.737s CPU time over 1min 25.390s wall clock time, 48.3M memory peak, 492K written to disk, 441B incoming IP traffic, 442B outgoing IP traffic.977!!! Traceback (most recent call last):978!!! File "<string>", line 1, in <module>979!!! machine.wait_for_unit("multi-user.target")980!!! 981!!! RequestedAssertionFailed: unit "multi-user.target" is inactive and there are no pending jobs982cleanup983kill QemuMachine (pid 45)984machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)985machine # [2026-09-24T02:52:25Z INFO virtiofsd] Client disconnected, shutting down986machine # [2026-09-24T02:52:25Z INFO virtiofsd] Client disconnected, shutting down987machine # [2026-09-24T02:52:25Z INFO virtiofsd] Client disconnected, shutting down988(finished: cleanup, in 0.42 seconds)989additionally exposed symbols:990 machine,991 vlan1,992 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