tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine: QEMU running (pid 45) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.8Ahtca7Avr', 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: f73edf28-a915-4c22-86c8-9a59257a0fca 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.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Jul 24 14:17:26 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-0x000000013fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x13fdeb740-0x13fdeeebf] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] 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-0x000000013fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 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 s186392 r8192 d116712 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/96xl253dhdcq9sx7w9axx2dgy4yk866i-nixos-system-machine-test/init regInfo=/nix/store/fvl2l9j4drc2nzzrq0rraidyz5bcv0kp-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/fvl2l9j4drc2nzzrq0rraidyz5bcv0kp-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: 524288 (order: 10, 4194304 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) machine # [ 0.000000] software IO TLB: area num 4. machine # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 machine # [ 0.000000] Policy zone: Normal 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=4, Nodes=1 machine # [ 0.000000] allocated 8388608 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74754 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=4. 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=4 machine # [ 0.000000] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. 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 @100b10000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100b20000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000100b30000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b40000 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.000032] arm-pv: using stolen time PV machine # [ 0.000396] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000640] Console: colour dummy device 80x25 machine # [ 0.000648] printk: legacy console [tty0] enabled machine # [ 0.000826] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000833] pid_max: default: 32768 minimum: 301 machine # [ 0.000918] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001060] landlock: Up and running. machine # [ 0.001063] Yama: becoming mindful. machine # [ 0.001491] LSM support for eBPF active machine # [ 0.001633] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.001698] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.002997] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003711] rcu: Hierarchical SRCU implementation. machine # [ 0.003715] rcu: Max phase no-delay instances is 1000. machine # [ 0.003888] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level machine # [ 0.005009] fsl-mc MSI: its@8080000 domain created machine # [ 0.005104] EFI services will not be available. machine # [ 0.005278] smp: Bringing up secondary CPUs ... machine # [ 0.005954] Detected PIPT I-cache on CPU1 machine # [ 0.006060] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 machine # [ 0.006195] GICv3: CPU1: using allocated LPI pending table @0x0000000100b50000 machine # [ 0.006326] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40] machine # [ 0.007423] Detected PIPT I-cache on CPU2 machine # [ 0.007524] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 machine # [ 0.007656] GICv3: CPU2: using allocated LPI pending table @0x0000000100b60000 machine # [ 0.007791] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40] machine # [ 0.008825] Detected PIPT I-cache on CPU3 machine # [ 0.008926] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 machine # [ 0.009059] GICv3: CPU3: using allocated LPI pending table @0x0000000100b70000 machine # [ 0.009194] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40] machine # [ 0.009779] smp: Brought up 1 node, 4 CPUs machine # [ 0.009807] SMP: Total of 4 processors activated. machine # [ 0.009810] CPU: All CPU(s) started at EL1 machine # [ 0.009820] CPU features: detected: Branch Target Identification machine # [ 0.009824] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.009827] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.009830] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.009834] CPU features: detected: Common not Private translations machine # [ 0.009837] CPU features: detected: CRC32 instructions machine # [ 0.009840] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.009843] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.009846] CPU features: detected: Data independent timing control (DIT) machine # [ 0.009850] CPU features: detected: E0PD machine # [ 0.009852] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.009855] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.009858] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.009861] CPU features: detected: Fine Grained Traps machine # [ 0.009865] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.009869] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.009872] CPU features: detected: LSE atomic instructions machine # [ 0.009875] CPU features: detected: Privileged Access Never machine # [ 0.009877] CPU features: detected: PMUv3 machine # [ 0.009880] CPU features: detected: RAS Extension Support machine # [ 0.009883] CPU features: detected: RASv1p1 Extension Support machine # [ 0.009885] CPU features: detected: Random Number Generator machine # [ 0.009888] CPU features: detected: Speculation barrier (SB) machine # [ 0.009891] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.009893] CPU features: detected: TLB range maintenance instructions machine # [ 0.009897] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.010030] alternatives: applying system-wide alternatives machine # [ 0.013024] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.013656] Memory: 3910016K/4194304K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 233732K reserved, 32768K cma-reserved) machine # [ 0.015018] devtmpfs: initialized machine # [ 0.018683] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.018755] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). machine # [ 0.018952] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.018957] 0 pages in range for non-PLT usage machine # [ 0.018958] 508320 pages in range for PLT usage machine # [ 0.019083] pinctrl core: initialized pinctrl subsystem machine # [ 0.019956] DMI not present or invalid. machine # [ 0.023232] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.025736] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.025922] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.026145] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.026166] audit: initializing netlink subsys (disabled) machine # [ 0.026486] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 machine # [ 0.026982] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.026985] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.026990] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.026992] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.026995] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.027096] cpuidle: using governor ladder machine # [ 0.027119] cpuidle: using governor menu machine # [ 0.027310] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.027527] ASID allocator initialised with 65536 entries machine # [ 0.028718] Serial: AMBA PL011 UART driver machine # [ 0.033921] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.034447] printk: console [ttyAMA0] enabled machine # [ 0.043957] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.043979] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.043988] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.043997] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.044005] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.044013] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.044021] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.044029] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.054780] fbcon: Taking over console machine # [ 0.054792] ACPI: Interpreter disabled. machine # [ 0.055834] iommu: Default domain type: Translated machine # [ 0.055851] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.063574] SCSI subsystem initialized machine # [ 0.063945] usbcore: registered new interface driver usbfs machine # [ 0.063967] usbcore: registered new interface driver hub machine # [ 0.063993] usbcore: registered new device driver usb machine # [ 0.064277] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.064281] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.064290] PTP clock support registered machine # [ 0.064329] EDAC MC: Ver: 3.0.0 machine # [ 0.064825] scmi_core: SCMI protocol bus registered machine # [ 0.065885] FPGA manager framework machine # [ 0.066724] vgaarb: loaded machine # [ 0.070053] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.091723] VFS: Disk quotas dquot_6.6.0 machine # [ 0.091791] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.092370] netfs: FS-Cache loaded machine # [ 0.092502] pnp: PnP ACPI: disabled machine # [ 0.098946] NET: Registered PF_INET protocol family machine # [ 0.099604] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.130644] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.130687] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.130716] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) machine # [ 0.130882] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) machine # [ 0.131158] TCP: Hash tables configured (established 32768 bind 32768) machine # [ 0.131254] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) machine # [ 0.131293] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.131352] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.131506] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.131535] NET: Registered PF_XDP protocol family machine # [ 0.131549] PCI: CLS 0 bytes, default 64 machine # [ 0.131948] Trying to unpack rootfs image as initramfs... machine # [ 0.138482] kvm [1]: HYP mode not available machine # [ 0.184101] Initialise system trusted keyrings machine # [ 0.184442] workingset: timestamp_bits=42 max_order=20 bucket_order=0 machine # [ 0.185335] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.185464] 9p: Installing v9fs 9p2000 file system support machine # [ 0.197444] Key type asymmetric registered machine # [ 0.197449] Asymmetric key parser 'x509' registered machine # [ 0.197505] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.197866] io scheduler mq-deadline registered machine # [ 0.197870] io scheduler kyber registered machine # [ 0.202961] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.206560] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.206930] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.206945] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.206955] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.206961] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.206981] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.207002] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.207075] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.207083] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.207086] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.207089] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.207092] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.207163] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.207694] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.207920] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.207933] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.207965] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.207977] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.208513] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.208727] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.208739] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.208765] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.209293] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.209511] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.209529] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.209561] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.210134] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.210350] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.210362] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.210393] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.210935] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.211150] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.211163] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.211189] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.211736] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.211959] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.211971] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.211997] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.212565] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.212787] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.212799] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.212825] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.212837] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.213371] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.213584] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.213610] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.214164] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.214408] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.214434] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.214900] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.215116] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.215381] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.215682] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.215695] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.215722] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.216261] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.216475] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.216487] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.216518] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.217184] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.217189] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.217193] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.217247] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.217296] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.217340] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.217388] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.217444] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.217493] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.217542] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.217595] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.217642] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.217713] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.217762] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.217780] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.217799] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.217816] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.217839] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.217861] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.217883] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.217906] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.217925] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.217943] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.217962] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.217980] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.217997] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.218022] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.218060] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.218083] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.218101] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.218118] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.218141] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.218158] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.218176] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.218203] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.218208] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.218211] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.219653] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.231955] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.234610] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.236728] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.238957] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.241121] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.244740] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.249848] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.251902] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.254067] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.256020] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.259978] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.268949] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.270999] msm_serial: driver initialized machine # [ 0.271157] SuperH (H)SCI(F) driver initialized machine # [ 0.271206] STM32 USART driver initialized machine # [ 0.296999] loop: module loaded machine # [ 0.297520] virtio_blk virtio5: 4/0/0 default/read/poll queues machine # [ 0.300915] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.304403] megasas: 07.734.00.00-rc1 machine # [ 0.305724] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.316564] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.316671] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.319710] Using buffer write method machine # [ 0.319772] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.323312] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.323341] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.326584] Using buffer write method machine # [ 0.326610] Concatenating MTD devices: machine # [ 0.326614] (0): "0.flash" machine # [ 0.326618] (1): "0.flash" machine # [ 0.326621] into device "0.flash" machine # [ 0.608754] Freeing initrd memory: 27052K machine # [ 0.627276] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.636905] thunder_xcv, ver 1.0 machine # [ 0.637037] thunder_bgx, ver 1.0 machine # [ 0.637101] nicpf, ver 1.0 machine # [ 0.638946] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.638958] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.639042] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.639057] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.639169] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.639179] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.639244] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.639254] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.639668] sky2: driver version 1.30 machine # [ 0.644792] usbcore: registered new interface driver usb-storage machine # [ 0.645391] usbcore: registered new interface driver usbserial_generic machine # [ 0.645444] usbserial: USB Serial support registered for generic machine # [ 0.645467] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.645559] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.646587] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.647345] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.650315] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.650376] rtc-pl031 9010000.pl031: setting system clock to 2026-08-31T08:44:56 UTC (1788165896) machine # [ 0.651371] i2c_dev: i2c /dev entries driver machine # [ 0.658196] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.658937] hub 1-0:1.0: USB hub found machine # [ 0.658964] hub 1-0:1.0: 6 ports detected machine # [ 0.660076] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.660091] sdhci: Copyright(c) Pierre Ossman machine # [ 0.660926] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.662098] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.666583] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.667299] usbcore: registered new interface driver usbhid machine # [ 0.667309] usbhid: USB HID core driver machine # [ 0.714801] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.719088] drop_monitor: Initializing network drop monitor service machine # [ 0.719565] NET: Registered PF_INET6 protocol family machine # [ 0.723226] Segment Routing with IPv6 machine # [ 0.723273] In-situ OAM (IOAM) with IPv6 machine # [ 0.723505] NET: Registered PF_PACKET protocol family machine # [ 0.723851] 9pnet: Installing 9P2000 support machine # [ 0.731225] Key type dns_resolver registered machine # [ 0.751036] registered taskstats version 1 machine # [ 0.751804] Loading compiled-in X.509 certificates machine # [ 0.779509] Demotion targets for Node 0: null machine # [ 0.779852] Key type .fscrypt registered machine # [ 0.779861] Key type fscrypt-provisioning registered machine # [ 0.780146] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.780190] ima: Allocated hash algorithm: sha1 machine # [ 0.780278] ima: No architecture policies found machine # [ 0.781959] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 0.813598] clk: Disabling unused clocks machine # [ 0.813666] PM: genpd: Disabling unused power domains machine # [ 0.819840] Freeing unused kernel memory: 4736K machine # [ 0.820097] Run /init as init process machine # [ 0.849377] systemd[1]: Successfully made /usr/ read-only. machine # [ 0.938127] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.090457] 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.182725] 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.184188] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.184236] systemd[1]: Detected virtualization qemu. machine # [ 1.184303] systemd[1]: Detected architecture arm64. machine # [ 1.184316] systemd[1]: Running in initrd. machine # [ 1.185178] systemd[1]: Initializing machine ID from random generator. machine # [ 1.185541] systemd[1]: Hostname set to . machine # [ 1.302111] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.419030] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.458362] 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.458865] 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.504771] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.542889] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.543299] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.543353] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.543392] systemd[1]: Reached target Path Units. machine # [ 1.543417] systemd[1]: Reached target Slice Units. machine # [ 1.543442] systemd[1]: Reached target Swaps. machine # [ 1.543468] systemd[1]: Reached target Timer Units. machine # [ 1.543810] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.544076] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.544344] systemd[1]: Listening on Journal Sockets. machine # [ 1.544575] systemd[1]: Listening on udev Control Socket. machine # [ 1.544713] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.544744] systemd[1]: Reached target Socket Units. machine # [ 1.547646] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.550597] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.550716] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.553572] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.581310] systemd[1]: Starting Journal Service... machine # [ 1.585732] systemd[1]: Starting Load Kernel Modules... machine # [ 1.586117] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.589836] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.594558] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.597078] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.598167] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.598906] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.603340] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.635354] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.637565] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.638519] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.639184] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.639880] systemd-journald[95]: Collecting audit messages is disabled. machine # [ 1.650870] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.651140] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.651146] [drm] features: -context_init machine # [ 1.652500] [drm] number of scanouts: 1 machine # [ 1.652520] [drm] number of cap sets: 0 machine # [ 1.655121] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.655137] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.669903] Console: switching to colour frame buffer device 160x50 machine # [ 1.671408] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.681794] systemd[1]: Finished Load Kernel Modules. machine # [ 1.685109] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.694398] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.695093] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.695424] systemd[1]: Reached target Local File Systems. machine # [ 1.714483] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.717182] systemd-modules-load[96]: Using 4 probe threads machine # [ 1.721454] systemd[1]: Started Journal Service. machine # [ 1.721938] systemd-modules-load[96]: Module 'virtio_balloon' is built in machine # [ 1.723191] systemd-modules-load[96]: Module 'virtio_console' is built in machine # [ 1.724495] systemd-modules-load[96]: Module 'virtio_rng' is built in machine # [ 1.725604] systemd-modules-load[96]: Inserted module 'dm_mod' machine # [ 1.726638] systemd-modules-load[96]: Inserted module 'virtio_gpu' machine # [ 1.749292] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.760036] systemd[1]: Starting Create System Files and Directories... machine # [ 1.799059] systemd-udevd[108]: Using default interface naming scheme 'v261'. machine # [ 1.806985] systemd[1]: Finished Create System Files and Directories. machine # [ 1.832593] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.864159] systemd[1]: Starting Virtual Console Setup... machine # [ 1.927301] systemd-vconsole-setup[131]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.932535] systemd[1]: Finished Virtual Console Setup. machine # [ 2.272469] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.275824] systemd[1]: Reached target System Initialization. machine # [ 2.278435] systemd[1]: Reached target Basic System. machine # [ 2.451177] (udev-worker)[135]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.473127] (udev-worker)[122]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.478381] (udev-worker)[122]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.527660] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.529900] systemd[1]: Reached target Initrd Root Device. machine # [ 2.531166] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.582250] systemd-fsck[153]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.588407] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.591980] systemd[1]: Mounting /sysroot... machine # [ 2.671332] EXT4-fs (vda): mounted filesystem f73edf28-a915-4c22-86c8-9a59257a0fca r/w with ordered data mode. Quota mode: none. machine # [ 2.675504] systemd[1]: Mounted /sysroot. machine # [ 2.677176] systemd[1]: Reached target Initrd Root File System. machine # [ 2.680362] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 2.685573] systemd[1]: Mounting /sysroot/run... machine # [ 2.698596] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 2.706275] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 2.711309] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.717357] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.736317] systemd-sysroot-fstab-check[166]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.737984] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 2.738797] systemd[1]: Mounted /sysroot/run. machine # [ 2.739483] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 2.743356] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 2.744281] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.745291] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 2.748201] systemd[1]: Reload requested from client PID 166 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.750497] systemd[1]: Reloading... machine # [ 2.853561] systemd[1]: Reloading finished in 104 ms. machine # [ 2.907788] systemd-sysroot-fstab-check[166]: Requesting initrd-fs.target/start/replace... machine # [ 2.909737] systemd-sysroot-fstab-check[166]: Requesting swap.target/start/replace... machine # [ 2.911283] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.915071] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.916380] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.917465] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.935225] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.937175] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.080881] (udev-worker)[121]: mtd0ro: Failed to find and pin callout binary "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/mtd_probe": No such file or directory machine # [ 3.084178] (udev-worker)[121]: mtd0ro: /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/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 # [ 3.090419] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.092300] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.093169] systemd[1]: Stopping Virtual Console Setup... machine # [ 3.093926] systemd[1]: Starting Virtual Console Setup... machine # [ 3.120967] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.122359] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.123278] systemd[1]: Starting Virtual Console Setup... machine # [ 3.144702] systemd-vconsole-setup[195]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 3.148122] systemd[1]: Finished Virtual Console Setup. machine # [ 3.597360] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.671989] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.674367] systemd[1]: Reached target Initrd File Systems. machine # [ 3.678270] systemd[1]: Starting Find NixOS closure... machine # [ 3.681077] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.731527] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.735223] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 3.751153] systemd[1]: Finished Find NixOS closure. machine # [ 3.753616] systemd[1]: Reached target Initrd Default Target. machine # [ 3.756079] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.809136] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.811768] systemd[1]: Stopped target Basic System. machine # [ 3.814097] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.816503] systemd[1]: Stopped target Path Units. machine # [ 3.818592] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.821841] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.825304] systemd[1]: Stopped target Slice Units. machine # [ 3.827415] systemd[1]: Stopped target Socket Units. machine # [ 3.829728] systemd[1]: Stopped target System Initialization. machine # [ 3.835718] systemd[1]: Stopped target Swaps. machine # [ 3.837919] systemd[1]: Stopped target Timer Units. machine # [ 3.842804] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.847087] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.851825] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.858597] systemd[1]: Stopped Find NixOS closure. machine # [ 3.860647] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.862433] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.864136] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.865914] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.867365] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.869852] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.871162] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.873193] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.875178] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.876936] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.878454] systemd[1]: Stopped target Local File Systems. machine # [ 3.879719] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.881238] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.882753] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.883947] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.885536] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.887010] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.888101] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.889573] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.890855] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.892273] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.893422] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.894823] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.896035] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.897322] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.898704] systemd[1]: systemd-udevd.service: Consumed 2.388s CPU time over 2.165s wall clock time, 31M memory peak. machine # [ 3.900804] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. machine # [ 3.902213] systemd[1]: Closed udev Control Socket. machine # [ 3.903207] systemd[1]: Starting Cleanup udev Database... machine # [ 3.904335] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.905854] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.907116] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.910091] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.911510] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.912989] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.981407] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.984629] systemd[1]: Finished Cleanup udev Database. machine # [ 3.986841] systemd[1]: Reached target Switch Root. machine # [ 3.989087] systemd[1]: Starting NixOS Activation... machine # [ 4.284201] initrd-nixos-activation-start[221]: booting system configuration /nix/store/96xl253dhdcq9sx7w9axx2dgy4yk866i-nixos-system-machine-test machine # [ 4.413773] initrd-nixos-activation-start[221]: running activation script... machine # [ 5.022065] initrd-nixos-activation-start[244]: setting up /etc... machine # [ 5.379063] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.381163] systemd[1]: Finished NixOS Activation. machine # [ 5.383576] systemd[1]: Starting Switch Root... machine # [ 5.430046] systemd[1]: Switching root. machine # [ 5.540967] systemd-journald[95]: Received SIGTERM from PID 1 (systemd). machine # [ 6.278739] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 6.278901] systemd[1]: Detected virtualization qemu. machine # [ 6.279005] systemd[1]: Detected architecture arm64. machine # [ 6.279195] systemd[1]: Detected first boot. machine # [ 6.286629] systemd[1]: Initializing machine ID from random generator. machine # [ 6.525137] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 6.695923] systemd[1]: Applying preset policy. machine # [ 7.244586] systemd[1]: Populated /etc with preset unit settings. machine # [ 7.829646] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 7.830830] systemd[1]: Stopped initrd-switch-root.service. machine # [ 7.835114] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 7.842340] systemd[1]: Created slice Slice /system/getty. machine # [ 7.846332] systemd[1]: Created slice User and Session Slice. machine # [ 7.847943] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 7.849426] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 7.850724] systemd[1]: Expecting device /dev/hvc0... machine # [ 7.851881] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 7.853057] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 7.854650] systemd[1]: Reached target flakelet contract providers ready. machine # [ 7.855833] systemd[1]: Stopped target initrd-fs.target. machine # [ 7.857004] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 7.858213] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 7.859388] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 7.860580] systemd[1]: Reached target Path Units. machine # [ 7.861784] systemd[1]: Reached target Remote File Systems. machine # [ 7.862847] systemd[1]: Reached target Slice Units. machine # [ 7.863319] systemd[1]: Reached target Swaps. machine # [ 7.867812] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 7.872652] systemd[1]: Listening on Process Core Dump Socket. machine # [ 7.876725] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 7.880656] systemd[1]: Listening on Factory Reset Management. machine # [ 7.881434] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.890702] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.892243] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.896545] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.897689] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.898403] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.899173] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.910540] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.911382] systemd[1]: Listening on udev Control Socket. machine # [ 7.912200] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.968666] systemd[1]: Mounting Huge Pages File System... machine # [ 7.975384] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 7.983254] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.005967] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.017502] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.026432] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 8.027725] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.038380] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.039794] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.041208] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.056213] systemd[1]: Starting Load Kernel Module fuse... machine # [ 8.058734] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.113093] systemd[1]: Starting Journal Service... machine # [ 8.136107] systemd[1]: Starting Load Kernel Modules... machine # [ 8.161593] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.177919] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.179776] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.197480] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.203713] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.204883] systemd[1]: Mounted Huge Pages File System. machine # [ 8.206223] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.207293] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.208417] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.227486] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.233373] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 8.235914] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 8.237811] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.248486] fuse: init (API version 7.45) machine # [ 8.252894] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.256063] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 8.257750] systemd[1]: Finished Load Kernel Module fuse. machine # [ 8.272658] systemd[1]: Mounting FUSE Control File System... machine # [ 8.283406] systemd[1]: Finished Load Kernel Modules. machine # [ 8.292241] systemd[1]: Starting Firewall... machine # [ 8.301063] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.312722] systemd-journald[315]: Collecting audit messages is enabled. machine # [ 8.340478] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.343969] systemd[1]: Started Journal Service. machine # [ 8.346230] EXT4-fs (vda): re-mounted f73edf28-a915-4c22-86c8-9a59257a0fca. machine # [ 8.348143] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.351070] systemd-modules-load[316]: Using 2 probe threads machine # [ 8.353305] systemd-modules-load[316]: Module 'atkbd' is built in machine # [ 8.355925] systemd-modules-load[316]: Module 'loop' is built in machine # [ 8.362293] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.364082] systemd[1]: Mounted FUSE Control File System. machine # [ 8.373552] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.377230] systemd-oomd[317]: No swap; memory pressure usage will be degraded machine # [ 8.381636] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.384376] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.390997] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.394389] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.398355] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.423257] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 8.427449] systemd[1]: Finished Apply Kernel Variables. machine # [ 8.435297] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 8.458868] systemd-journald[315]: Received client request to flush runtime journal. machine # [ 8.481170] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 8.483132] systemd[1]: Reached target First Boot Complete. machine # [ 8.484929] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 8.515573] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 8.517493] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 8.520095] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.606451] systemd-udevd[355]: Using default interface naming scheme 'v261'. machine # [ 8.720408] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.831109] systemd[1]: Mounting /run/wrappers... machine # [ 8.877177] systemd[1]: Mounted /run/wrappers. machine # [ 8.878896] systemd[1]: Reached target Local File Systems. machine # [ 8.882144] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.886341] systemd[1]: Starting register-nix-paths.service... machine # [ 8.891726] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.893226] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.901258] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 8.923896] systemd[1]: Starting Create System Files and Directories... machine # [ 8.972385] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 8.979742] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.984125] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.030463] systemd[1]: Finished Create System Files and Directories. machine # [ 9.038292] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.048293] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.052157] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 9.098628] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.118136] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 9.135020] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 9.142969] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 9.149669] systemd[1]: Starting Update is Completed... machine # [ 9.198715] systemd[1]: Finished Update is Completed. machine # [ 9.255233] (udev-worker)[436]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.272089] (udev-worker)[436]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.278149] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.305172] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 9.309211] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 9.313548] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.317493] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 9.322241] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 9.326439] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.329808] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.354005] mousedev: PS/2 mouse device common for all mice machine # [ 9.522902] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.525459] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.581502] systemd[1]: Finished Firewall. machine # [ 9.715409] systemd[1]: Finished register-nix-paths.service. machine # [ 9.717956] systemd[1]: Reached target System Initialization. machine # [ 9.719562] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.721480] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.722459] systemd[1]: Reached target Timer Units. machine # [ 9.723336] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.724797] systemd[1]: Listening on Nix Daemon Socket. machine # [ 9.726031] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.727708] systemd[1]: Reached target Socket Units. machine # [ 9.729258] systemd[1]: Reached target Basic System. machine # [ 9.731696] systemd[1]: Started backdoor.service. machine # [ 9.733085] systemd[1]: Starting Re-link flakelet services at boot... machine # [ 9.737756] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 9.743424] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.757827] systemd[1]: Starting Post-Boot Actions...[ 9.760178] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # machine # [ 9.768450] systemd[1]: Started Reset console on configuration changes. machine # [ 9.774898] systemd[1]: Starting resolvconf update... machine # [ 9.796504] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.843555] systemd[1]: Finished Re-link flakelet services at boot. machine # [ 9.848945] systemd[1]: Starting Reconcile flakelet services with the host configuration... machine # [ 9.852908] systemd[1]: Finished Post-Boot Actions. machine # [ 9.866602] nsncd[559]: Aug 31 08:45:05.717 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # connecting to host... machine # [ 9.871289] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.875648] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.883552] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.888987] systemd[1]: Starting User Login Management... machine # [ 9.891068] systemd[1]: Finished Import lastlog data into lastlog2 database. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 10.35 seconds) machine: (finished: waiting for the VM to finish booting, in 10.89 seconds) machine # [ 9.934877] systemd[1]: Finished Reconcile flakelet services with the host configuration. machine # [ 9.982857] dbus-broker-launch[566]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.003841] systemd-logind[588]: New seat seat0. machine # [ 10.006574] dbus-broker-launch[566]: NSS returned no entry for 'systemd-timesync' machine # [ 10.008819] dbus-broker-launch[566]: Invalid user-name in /nix/store/s8sm8xwbfx7y43x6lrfyr9mp58vgim5j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.013894] systemd-logind[588]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 10.016483] systemd-logind[588]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.042496] systemd-logind[588]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 10.060803] dbus-broker-launch[566]: Ready machine # [ 10.083826] systemd[1]: Started User Login Management. machine # [ 10.085870] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.100403] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.103142] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.108231] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.109563] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.110803] systemd[1]: Starting linger-users.service... machine # [ 10.113011] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.117745] systemd[1]: Starting Virtual Console Setup... machine # [ 10.120889] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.124960] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 10.138594] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.166012] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.168331] systemd[1]: Finished linger-users.service. machine # [ 10.190939] nsncd[654]: Aug 31 08:45:06.041 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.198507] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.201340] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.204350] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.206909] systemd[1]: Finished resolvconf update. machine # [ 10.208602] systemd[1]: Reached target Preparation for Network. machine # [ 10.213823] systemd[1]: Starting DHCP Client... machine # [ 10.217602] systemd[1]: Starting Address configuration of eth1... machine # [ 10.223416] systemd[1]: Starting Extra networking commands.... machine # [ 10.322536] network-addresses-eth1-start[678]: adding address 192.168.1.1/24... done machine # [ 10.335889] network-addresses-eth1-start[678]: adding address 2001:db8:1::1/64... done machine # [ 10.352663] systemd[1]: Finished Address configuration of eth1. machine # [ 10.356997] dhcpcd[685]: dhcpcd-10.3.2 starting machine # [ 10.367134] dhcpcd[733]: dev: loaded udev machine # [ 10.388771] systemd[1]: Finished Extra networking commands.. machine # [ 10.393153] systemd[1]: Reached target Network. machine # [ 10.395262] systemd[1]: Starting Permit User Sessions... machine # [ 10.399985] 8021q: 802.1Q VLAN Support v1.8 machine # [ 10.400417] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 10.402152] systemd[1]: Starting Update flakelet service static... machine # [ 10.419555] systemd[1]: Starting Update flakelet service web... machine # [ 10.430114] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 10.448128] systemd[1]: Finished Permit User Sessions. machine # [ 10.453648] systemd[1]: Started Getty on tty1. machine # [ 10.455968] systemd[1]: Reached target Login Prompts. machine # [ 10.457857] flakelet[746]: static: using prebuilt artifact /nix/store/d4wsijyvrlqv20ah5mv211f8zv4gayxk-flakelet-static[ 10.459475] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # machine # [ 10.469550] flakelet[748]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix machine # [ 10.478893] flakelet[746]: static: activating generation 1 machine # [ 10.484924] systemd[1]: Reload requested from client PID 754 ('systemctl') (unit flakelet-static.service)... machine # [ 10.487182] systemd[1]: Reloading... machine # [ 10.499031] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 10.499611] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 10.503390] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 10.503773] cfg80211: failed to load regulatory.db machine # [ 10.585863] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 10.584693] dhcpcd[733]: eth0: waiting for carrier machine # [ 10.586643] dhcpcd[733]: eth0: carrier acquired machine # [ 10.604075] dhcpcd[733]: DUID 00:01:00:01:32:27:fb:92:52:54:00:12:34:56 machine # [ 10.607354] dhcpcd[733]: eth0: IAID 00:12:34:56 machine # [ 10.609291] dhcpcd[733]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 10.628437] systemd-vconsole-setup[644]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 11.098460] systemd[1]: Reloading finished in 610 ms. machine # [ 11.158530] systemd[1]: Finished Virtual Console Setup. machine # [ 11.172184] systemd[1]: Started Nix Daemon. machine # [ 11.301572] nix-daemon[813]: accepted connection from pid 753, user flakelet machine # [ 11.318401] systemd[1]: Reload requested from client PID 817 ('systemctl') (unit flakelet-static.service)... machine # [ 11.320224] systemd[1]: Reloading... machine # [ 11.568484] dhcpcd[733]: eth0: soliciting a DHCP lease machine # [ 11.580804] dhcpcd[733]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 11.596408] dhcpcd[733]: eth0: probing address 10.0.2.15/24 machine # [ 12.013742] systemd[1]: Reloading finished in 689 ms. machine # [ 12.134396] systemd[1]: Created slice Slice /system/static-echo. machine # [ 12.138624] systemd[1]: Listening on static-echo@3.socket. machine # [ 12.145033] systemd[1]: Listening on static-echo@1.socket. machine # [ 12.153090] systemd[1]: Listening on static-echo@2.socket. machine # [ 12.166189] systemd[1]: Starting prebuilt flakelet service static... machine # [ 12.211825] systemd[1]: Started prebuilt flakelet service static. machine # [ 12.445099] flakelet[748]: web: evaluating /nix/store/qss4iprnbw9l96bz3ynr5lbkjavw9wms-flakelet-driver.nix machine # [ 12.567326] nix-daemon[813]: accepted connection from pid 883, user flakelet machine # [ 12.968213] flakelet[746]: static: updated to generation 1 machine # [ 12.971303] systemd[1]: Finished Update flakelet service static. machine # [ 13.198906] dhcpcd[733]: eth0: soliciting an IPv6 router machine # [ 13.200160] dhcpcd[733]: eth0: Router Advertisement from fe80::2 machine # [ 13.201234] dhcpcd[733]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.202322] dhcpcd[733]: eth0: adding route to fec0::/64 machine # [ 13.203173] dhcpcd[733]: eth0: adding default route via fe80::2 machine # [ 16.830902] dhcpcd[733]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 16.831133] dhcpcd[733]: eth0: adding route to 10.0.2.0/24 machine # [ 16.831275] dhcpcd[733]: eth0: adding default route via 10.0.2.2 machine # [ 17.036750] systemd[1]: Started DHCP Client. machine # [ 20.944616] flakelet[748]: web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv machine # [ 22.492376] nix-daemon[813]: accepted connection from pid 1136, user flakelet machine # [ 22.535366] flakelet[748]: web: activating generation 1 machine # [ 22.553670] systemd[1]: Reload requested from client PID 1144 ('systemctl') (unit flakelet-web.service)... machine # [ 22.558318] systemd[1]: Reloading... machine # [ 23.264139] systemd[1]: Reloading finished in 701 ms. machine # [ 23.388188] systemd[1]: Reload requested from client PID 1185 ('systemctl') (unit flakelet-web.service)... machine # [ 23.395589] systemd[1]: Reloading... machine # [ 24.041408] systemd[1]: Reloading finished in 650 ms. machine # [ 24.207566] systemd[1]: Starting flakelet test service... machine # [ 24.250761] systemd[1]: Started flakelet test service. machine # [ 24.421214] flakelet[748]: web: updated to generation 1 machine # [ 24.425433] systemd[1]: Finished Update flakelet service web. machine # [ 24.427073] systemd[1]: Reached target flakelet managed services. machine # [ 24.428954] systemd[1]: Reached target Multi-User System. machine # [ 24.430456] systemd[1]: Startup finished in 825ms (kernel) + 4.897s (initrd) + 18.704s (userspace) = 24.427s. machine: (finished: waiting for unit multi-user.target, in 26.30 seconds) machine: must succeed: systemctl start flakelet-web.service machine: (finished: must succeed: systemctl start flakelet-web.service, in 0.08 seconds) machine: must succeed: systemctl is-active web.service machine: (finished: must succeed: systemctl is-active web.service, in 0.05 seconds) machine: must succeed: systemctl show web.service -p Environment | grep -q GREETING=hello machine: (finished: must succeed: systemctl show web.service -p Environment | grep -q GREETING=hello, in 0.06 seconds) machine: must succeed: test -f /nix/var/nix/gcroots/flakelet/web/gen-1/manifest.json machine: (finished: must succeed: test -f /nix/var/nix/gcroots/flakelet/web/gen-1/manifest.json, in 0.03 seconds) machine: must succeed: flakelet status | grep -q '^web' machine: (finished: must succeed: flakelet status | grep -q '^web', in 0.87 seconds) machine: must succeed: grep -q '"port": 8080' /run/flakelet/exports/web.json machine: (finished: must succeed: grep -q '"port": 8080' /run/flakelet/exports/web.json, in 0.03 seconds) machine: must succeed: systemctl start flakelet-static.service machine: (finished: must succeed: systemctl start flakelet-static.service, in 0.05 seconds) machine: must succeed: systemctl is-active static.service machine: (finished: must succeed: systemctl is-active static.service, in 0.04 seconds) machine: must succeed: systemctl is-active static-echo@1.socket static-echo@2.socket machine: (finished: must succeed: systemctl is-active static-echo@1.socket static-echo@2.socket, in 0.04 seconds) machine: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/static-echo/2.sock machine: (finished: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/static-echo/2.sock, in 0.05 seconds) machine: waiting for success: systemctl is-active static-echo@2.service machine # [ 26.704541] systemd[1]: Starting static-echo@2.service... machine # [ 26.773653] systemd[1]: Started static-echo@2.service. machine: (finished: waiting for success: systemctl is-active static-echo@2.service, in 1.12 seconds) machine: must succeed: systemctl is-active static-echo@3.socket machine: (finished: must succeed: systemctl is-active static-echo@3.socket, in 0.05 seconds) machine: must succeed: flakelet activate cli /nix/store/85x399n5c1linb3lh03s8jwr8d390mmw-flakelet-cli machine # cli: using prebuilt artifact /nix/store/85x399n5c1linb3lh03s8jwr8d390mmw-flakelet-cli machine # cli: activating generation 1 machine # [ 27.958158] systemd[1]: Reload requested from client PID 1299 ('systemctl') (unit backdoor.service)... machine # [ 27.962956] systemd[1]: Reloading... machine # [ 28.657703] systemd[1]: Reloading finished in 691 ms. machine # [ 28.818226] systemd[1]: Reload requested from client PID 1344 ('systemctl') (unit backdoor.service)... machine # [ 28.823595] systemd[1]: Reloading... machine # [ 29.459145] systemd[1]: Reloading finished in 637 ms. machine # [ 29.535217] systemd[1]: Created slice Slice /system/cli-echo. machine # [ 29.540278] systemd[1]: Listening on cli-echo@3.socket. machine # [ 29.546243] systemd[1]: Listening on cli-echo@1.socket. machine # [ 29.558116] systemd[1]: Listening on cli-echo@2.socket. machine # [ 29.574724] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 29.626141] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must succeed: flakelet activate cli /nix/store/85x399n5c1linb3lh03s8jwr8d390mmw-flakelet-cli, in 2.55 seconds) machine: must succeed: systemctl is-active cli.service machine: (finished: must succeed: systemctl is-active cli.service, in 0.05 seconds) machine: must succeed: flakelet status --json | grep -q 'prebuilt:cli' machine: (finished: must succeed: flakelet status --json | grep -q 'prebuilt:cli', in 1.11 seconds) machine: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken machine # cli: using prebuilt artifact /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken machine # cli: activating generation 2 machine # [ 31.782137] systemd[1]: cli-echo@3.socket: Deactivated successfully. machine # [ 31.787466] systemd[1]: Closed cli-echo@3.socket. machine # [ 31.789645] systemd[1]: cli-echo@1.socket: Deactivated successfully. machine # [ 31.792637] systemd[1]: Closed cli-echo@1.socket. machine # [ 31.795004] systemd[1]: cli-echo@2.socket: Deactivated successfully. machine # [ 31.797783] systemd[1]: Closed cli-echo@2.socket. machine # [ 31.806898] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 31.810072] systemd[1]: cli.service: Deactivated successfully. machine # [ 31.812743] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 32.005298] systemd[1]: Reload requested from client PID 1419 ('systemctl') (unit backdoor.service)... machine # [ 32.010339] systemd[1]: Reloading... machine # [ 32.703594] systemd[1]: Reloading finished in 695 ms. machine # [ 32.904470] systemd[1]: Reload requested from client PID 1461 ('systemctl') (unit backdoor.service)... machine # [ 32.909386] systemd[1]: Reloading... machine # [ 33.553399] systemd[1]: Reloading finished in 646 ms. machine # [ 33.659163] systemd[1]: Reload requested from client PID 1499 ('systemctl') (unit backdoor.service)... machine # [ 33.664590] systemd[1]: Reloading... machine # [ 34.303925] systemd[1]: Reloading finished in 641 ms. machine # [ 34.408405] systemd[1]: Reload requested from client PID 1538 ('systemctl') (unit backdoor.service)... machine # [ 34.413497] systemd[1]: Reloading... machine # [ 34.847288] systemd[1]: multi-user.target: Wants dependency dropin /run/systemd/system/multi-user.target.wants/cli.service target /nix/store/z84ijpkkv9wps6zd00rg19z89z9yvb85-cli.service has different name machine # [ 35.066791] systemd[1]: Reloading finished in 655 ms. machine # [ 35.168894] systemd[1]: Starting cli.service... machine # [ 35.223541] (nonexistent)[1578]: cli.service: Unable to locate executable '/nonexistent': No such file or directory machine # [ 35.230559] (nonexistent)[1578]: cli.service: Failed at step EXEC spawning /nonexistent: No such file or directory machine # [ 35.235364] systemd[1]: cli.service: Main process exited, code=exited, status=203/EXEC machine # [ 35.239656] systemd[1]: cli.service: Failed with result 'exit-code'. machine # [ 35.244502] systemd[1]: Failed to start cli.service. machine # [ 35.317692] systemd[1]: Reload requested from client PID 1585 ('systemctl') (unit backdoor.service)... machine # [ 35.322455] systemd[1]: Reloading... machine # [ 35.978322] systemd[1]: Reloading finished in 659 ms. machine # [ 36.117123] systemd[1]: Reload requested from client PID 1624 ('systemctl') (unit backdoor.service)... machine # [ 36.122259] systemd[1]: Reloading... machine # [ 36.785962] systemd[1]: Reloading finished in 665 ms. machine # [ 36.851637] systemd[1]: Reload requested from client PID 1662 ('systemctl') (unit backdoor.service)... machine # [ 36.854707] systemd[1]: Reloading... machine # [ 37.583414] systemd[1]: Reloading finished in 725 ms. machine # [ 37.762229] systemd[1]: Reload requested from client PID 1707 ('systemctl') (unit backdoor.service)... machine # [ 37.764732] systemd[1]: Reloading... machine # [ 38.381262] systemd[1]: Reloading finished in 616 ms. machine # [ 38.472645] systemd[1]: Listening on cli-echo@3.socket. machine # [ 38.479557] systemd[1]: Listening on cli-echo@1.socket. machine # [ 38.484372] systemd[1]: Listening on cli-echo@2.socket. machine # [ 38.499147] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 38.535087] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken, in 6.98 seconds) machine: must succeed: systemctl is-active cli.service machine: (finished: must succeed: systemctl is-active cli.service, in 0.04 seconds) machine: must succeed: test "$(ls /nix/var/nix/gcroots/flakelet/cli)" = gen-1 machine: (finished: must succeed: test "$(ls /nix/var/nix/gcroots/flakelet/cli)" = gen-1, in 0.04 seconds) machine: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken machine # cli: using prebuilt artifact /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken machine: (finished: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken, in 0.05 seconds) machine: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/cli-echo/1.sock machine: (finished: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/cli-echo/1.sock, in 0.04 seconds) machine: waiting for success: systemctl is-active cli-echo@1.service machine # [ 38.726341] systemd[1]: Starting cli-echo@1.service... machine # [ 38.779513] systemd[1]: Started cli-echo@1.service. machine: (finished: waiting for success: systemctl is-active cli-echo@1.service, in 0.06 seconds) machine: must succeed: systemctl show -P MainPID cli-echo@1.service machine: (finished: must succeed: systemctl show -P MainPID cli-echo@1.service, in 0.17 seconds) machine: must succeed: systemctl show -P MainPID cli.service machine: (finished: must succeed: systemctl show -P MainPID cli.service, in 0.10 seconds) machine: must succeed: flakelet activate cli /nix/store/p1wi0s5gddvdx0c85gpmnpnk9nk1f0wi-flakelet-cli2 | grep -q 'generation 2' machine # cli: using prebuilt artifact /nix/store/p1wi0s5gddvdx0c85gpmnpnk9nk1f0wi-flakelet-cli2 machine # cli: activating generation 2 machine # [ 39.151502] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 39.154717] systemd[1]: cli.service: Deactivated successfully. machine # [ 39.156020] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 39.212622] systemd[1]: Reload requested from client PID 1786 ('systemctl') (unit backdoor.service)... machine # [ 39.215330] systemd[1]: Reloading... machine # [ 39.916253] systemd[1]: Reloading finished in 697 ms. machine # [ 40.029733] systemd[1]: Reload requested from client PID 1829 ('systemctl') (unit backdoor.service)... machine # [ 40.034420] systemd[1]: Reloading... machine # [ 40.701619] systemd[1]: Reloading finished in 663 ms. machine # [ 40.787293] systemd[1]: Reload requested from client PID 1868 ('systemctl') (unit backdoor.service)... machine # [ 40.792651] systemd[1]: Reloading... machine # [ 41.437294] systemd[1]: Reloading finished in 646 ms. machine # [ 41.598394] systemd[1]: Reload requested from client PID 1914 ('systemctl') (unit backdoor.service)... machine # [ 41.603645] systemd[1]: Reloading... machine # [ 42.258277] systemd[1]: Reloading finished in 657 ms. machine # [ 42.352352] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 42.414812] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must succeed: flakelet activate cli /nix/store/p1wi0s5gddvdx0c85gpmnpnk9nk1f0wi-flakelet-cli2 | grep -q 'generation 2', in 4.03 seconds) machine: must succeed: systemctl show -P MainPID cli-echo@1.service machine: (finished: must succeed: systemctl show -P MainPID cli-echo@1.service, in 0.05 seconds) machine: must succeed: systemctl show -P MainPID cli.service machine: (finished: must succeed: systemctl show -P MainPID cli.service, in 0.05 seconds) machine: must succeed: flakelet rollback cli | grep -q 'generation 1' machine # [ 43.273224] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 43.276648] systemd[1]: cli.service: Deactivated successfully. machine # [ 43.279376] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 43.331313] systemd[1]: Reload requested from client PID 1979 ('systemctl') (unit backdoor.service)... machine # [ 43.336644] systemd[1]: Reloading... machine # [ 44.011267] systemd[1]: Reloading finished in 676 ms. machine # [ 44.124880] systemd[1]: Reload requested from client PID 2022 ('systemctl') (unit backdoor.service)... machine # [ 44.130337] systemd[1]: Reloading... machine # [ 44.840412] systemd[1]: Reloading finished in 712 ms. machine # [ 44.970264] systemd[1]: Reload requested from client PID 2061 ('systemctl') (unit backdoor.service)... machine # [ 44.975606] systemd[1]: Reloading... machine # [ 45.660929] systemd[1]: Reloading finished in 688 ms. machine # [ 45.836488] systemd[1]: Reload requested from client PID 2108 ('systemctl') (unit backdoor.service)... machine # [ 45.838836] systemd[1]: Reloading... machine # [ 46.578968] systemd[1]: Reloading finished in 737 ms. machine # [ 46.680283] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 46.727935] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must succeed: flakelet rollback cli | grep -q 'generation 1', in 3.56 seconds) machine: must succeed: systemctl is-active cli-echo@3.socket machine: (finished: must succeed: systemctl is-active cli-echo@3.socket, in 0.04 seconds) machine: must succeed: systemctl is-active cli.service machine: (finished: must succeed: systemctl is-active cli.service, in 0.04 seconds) machine: must succeed: flakelet lock cli | grep -q 'prebuilt:cli' machine: (finished: must succeed: flakelet lock cli | grep -q 'prebuilt:cli', in 0.05 seconds) machine: must succeed: flakelet unlock cli machine: (finished: must succeed: flakelet unlock cli, in 0.04 seconds) machine: must succeed: touch /tmp/failjob machine: (finished: must succeed: touch /tmp/failjob, in 0.03 seconds) machine: must fail: systemctl start web-job.service machine # [ 46.987929] systemd[1]: Starting web-job.service... machine # [ 47.061723] systemd[1]: web-job.service: Main process exited, code=exited, status=1/FAILURE machine # [ 47.063982] systemd[1]: web-job.service: Failed with result 'exit-code'. machine # Job for web-job.service failed because the control process exited with error code. machine # [ 47.066058] systemd[1]: Failed to start web-job.service. machine # See "systemctl status web-job.service" and "journalctl -xeu web-job.service" for details. machine: (finished: must fail: systemctl start web-job.service, in 0.13 seconds) machine: must succeed: systemctl is-failed web-job.service && rm /tmp/failjob machine: (finished: must succeed: systemctl is-failed web-job.service && rm /tmp/failjob, in 0.07 seconds) machine: must succeed: flakelet update web --force --no-refresh | grep -q 'updated to generation' machine # web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix machine # [ 47.303593] nix-daemon[813]: accepted connection from pid 2187, user flakelet machine # web: evaluating /nix/store/qss4iprnbw9l96bz3ynr5lbkjavw9wms-flakelet-driver.nix machine # [ 47.563894] nix-daemon[813]: accepted connection from pid 2207, user flakelet machine # web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv machine # [ 52.562152] nix-daemon[813]: accepted connection from pid 2216, user flakelet machine # web: activating generation 2 machine # [ 52.799101] systemd[1]: Stopping flakelet test service... machine # [ 52.801598] systemd[1]: web.service: Deactivated successfully. machine # [ 52.803942] systemd[1]: Stopped flakelet test service. machine # [ 52.869482] systemd[1]: Reload requested from client PID 2229 ('systemctl') (unit backdoor.service)... machine # [ 52.874579] systemd[1]: Reloading... machine # [ 53.515281] systemd[1]: Reloading finished in 643 ms. machine # [ 53.629630] systemd[1]: Reload requested from client PID 2272 ('systemctl') (unit backdoor.service)... machine # [ 53.634793] systemd[1]: Reloading... machine # [ 54.280869] systemd[1]: Reloading finished in 648 ms. machine # [ 54.357995] systemd[1]: Reload requested from client PID 2314 ('systemctl') (unit backdoor.service)... machine # [ 54.363154] systemd[1]: Reloading... machine # [ 55.006689] systemd[1]: Reloading finished in 646 ms. machine # [ 55.094836] systemd[1]: Reload requested from client PID 2357 ('systemctl') (unit backdoor.service)... machine # [ 55.098337] systemd[1]: Reloading... machine # [ 55.765873] systemd[1]: Reloading finished in 664 ms. machine # [ 55.849064] systemd[1]: Starting flakelet test service... machine # [ 55.907210] systemd[1]: Started flakelet test service. machine: (finished: must succeed: flakelet update web --force --no-refresh | grep -q 'updated to generation', in 8.95 seconds) machine: must succeed: flakelet reconcile machine: (finished: must succeed: flakelet reconcile, in 0.05 seconds) machine: must succeed: systemctl is-active web.service static.service cli.service machine: (finished: must succeed: systemctl is-active web.service static.service cli.service, in 0.05 seconds) machine: must succeed: flakelet check --build --no-refresh --gc-roots-dir /tmp/roots | grep -q '^web: built /nix/store/' machine # [ 56.348532] nix-daemon[813]: accepted connection from pid 2414, user flakelet machine # [ 56.611057] nix-daemon[813]: accepted connection from pid 2434, user flakelet machine: (finished: must succeed: flakelet check --build --no-refresh --gc-roots-dir /tmp/roots | grep -q '^web: built /nix/store/', in 5.22 seconds) machine: must succeed: test -L /tmp/roots/web machine: (finished: must succeed: test -L /tmp/roots/web, in 0.03 seconds) machine: must succeed: flakelet build web --no-refresh --out-link /tmp/out && test -L /tmp/out/web machine # [ 61.596784] nix-daemon[813]: accepted connection from pid 2450, user flakelet machine # [ 61.879705] nix-daemon[813]: accepted connection from pid 2470, user flakelet machine: (finished: must succeed: flakelet build web --no-refresh --out-link /tmp/out && test -L /tmp/out/web, in 5.22 seconds) machine: must fail: flakelet check nosuchservice --no-refresh machine # error: service 'nosuchservice' is not configured machine: (finished: must fail: flakelet check nosuchservice --no-refresh, in 0.05 seconds) machine: must succeed: rm /run/systemd/system/web.service machine: (finished: must succeed: rm /run/systemd/system/web.service, in 0.04 seconds) machine: must succeed: flakelet boot machine # [ 66.818554] systemd[1]: Reload requested from client PID 2488 ('systemctl') (unit backdoor.service)... machine # [ 66.823781] systemd[1]: Reloading... machine # [ 67.524690] systemd[1]: Reloading finished in 698 ms. machine # [ 67.695348] systemd[1]: Reload requested from client PID 2535 ('systemctl') (unit backdoor.service)... machine # [ 67.700218] systemd[1]: Reloading... machine # [ 68.397089] systemd[1]: Reloading finished in 692 ms. machine # [ 68.578510] systemd[1]: Reload requested from client PID 2580 ('systemctl') (unit backdoor.service)... machine # [ 68.583407] systemd[1]: Reloading... machine # [ 69.274388] systemd[1]: Reloading finished in 687 ms. machine # [ 69.443934] systemd[1]: Reload requested from client PID 2627 ('systemctl') (unit backdoor.service)... machine # [ 69.448464] systemd[1]: Reloading... machine # [ 70.116321] systemd[1]: Reloading finished in 665 ms. machine # [ 70.229066] systemd[1]: Reload requested from client PID 2672 ('systemctl') (unit backdoor.service)... machine # [ 70.233325] systemd[1]: Reloading... machine # [ 70.870487] systemd[1]: Reloading finished in 634 ms. machine # [ 71.015389] systemd[1]: Reload requested from client PID 2716 ('systemctl') (unit backdoor.service)... machine # [ 71.020258] systemd[1]: Reloading... machine # [ 71.759972] systemd[1]: Reloading finished in 736 ms. machine: (finished: must succeed: flakelet boot, in 5.15 seconds) machine: must succeed: test -L /run/systemd/system/web.service machine: (finished: must succeed: test -L /run/systemd/system/web.service, in 0.03 seconds) machine: waiting for the VM to power off additionally exposed symbols: machine, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh Aug 31 08:45:06 machine systemd[1]: Starting Update flakelet service web... Aug 31 08:45:06 machine flakelet[748]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix Aug 31 08:45:08 machine flakelet[748]: web: evaluating /nix/store/qss4iprnbw9l96bz3ynr5lbkjavw9wms-flakelet-driver.nix Aug 31 08:45:16 machine flakelet[748]: web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv Aug 31 08:45:18 machine flakelet[748]: web: activating generation 1 Aug 31 08:45:20 machine flakelet[748]: web: updated to generation 1 Aug 31 08:45:20 machine systemd[1]: Finished Update flakelet service web. machine # [ 71.943380] systemd-logind[588]: poweroff requested from client PID 2764 ('poweroff') (unit backdoor.service)... machine # [ 71.947817] systemd-logind[588]: The system will power off now! machine # [ 71.955071] systemd-logind[588]: System is powering down. machine # [ 71.980589] systemd[1]: Removed slice Slice /system/modprobe. machine # [ 71.983468] systemd[1]: Stopped target Multi-User System. machine # [ 71.985983] systemd[1]: Stopped target flakelet managed services. machine # [ 71.989321] systemd[1]: Stopped target Login Prompts. machine # [ 71.993219] systemd[1]: Stopped target Virtual Machines and Containers. machine # [ 71.998194] dhcpcd[733]: received SIGTERM, stopping machine # [ 72.001470] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 72.006908] dhcpcd[733]: eth0: removing interface machine # [ 72.009825] systemd[1]: Stopped target Timer Units. machine # [ 72.012334] dhcpcd[733]: eth0: deleting address fec0::5054:ff:fe12:3456/64 machine # [ 72.016455] systemd[1]: fstrim.timer: Deactivated successfully. machine # [ 72.021158] dhcpcd[733]: eth0: deleting route to fec0::/64 machine # [ 72.029314] systemd[1]: Stopped Discard unused filesystem blocks once a week. machine # [ 72.036488] dhcpcd[733]: eth0: deleting default route via fe80::2 machine # [ 72.043898] systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. machine # [ 72.050600] systemd[1]: Stopped Daily Cleanup of Temporary Directories. machine # [ 72.057166] systemd[1]: systemd-rfkill.socket: Deactivated successfully. machine # [ 72.071824] systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 72.078684] systemd[1]: Mounting /run/initramfs... machine # [ 72.086575] dhcpcd[733]: eth0: deleting address fe80::5054:ff:fe12:3456 machine # [ 72.093592] systemd[1]: Stopping backdoor.service... machine # [ 72.099437] network-addresses-eth1-pre-stop[2768]: deleting address 192.168.1.1/24... done machine # [ 72.107290] systemd[1]: Stopping cli-echo@1.service... machine # [ 72.113207] dhcpcd[733]: eth0: deleting route to 10.0.2.0/24 machine # [ 72.115383] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 72.118401] dhcpcd[733]: eth0: deleting default route via 10.0.2.2 machine # [ 72.121424] systemd[1]: Stopping DHCP Client... machine # [ 72.124402] network-addresses-eth1-pre-stop[2768]: deleting address 2001:db8:1::1/64... done machine # [ 72.126485] systemd[1]: Stopping Getty on tty1... machine # [ 72.128218] systemd[1]: lastlog2-import.service: Deactivated successfully. machine # [ 72.131161] systemd[1]: Stopped Import lastlog data into lastlog2 database. machine # [ 72.134299] systemd[1]: Stopping Address configuration of eth1... machine # [ 72.137090] systemd[1]: Stopping Nix Daemon... machine # [ 72.138300] systemd[1]: Stopping Post-Boot Actions... machine # [ 72.139587] systemd[1]: reload-systemd-vconsole-setup.service: Deactivated successfully. machine # [ 72.142174] systemd[1]: Stopped Reset console on configuration changes. machine # [ 72.143963] dhcpcd[733]: dhcpcd exited machine # [ 72.145370] systemd[1]: Stopping static-echo@2.service... machine # [ 72.146777] systemd[1]: Stopping prebuilt flakelet service static... machine # [ 72.148214] systemd[1]: Stopping User Login Management... machine # [ 72.149436] systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. machine # [ 72.151076] systemd[1]: Stopped Save Transient machine-id to Disk. machine # [ 72.152453] systemd[1]: Stopped target First Boot Complete. machine # [ 72.153639] systemd[1]: Stopping Userspace Out-Of-Memory (OOM) Killer... machine # [ 72.155026] systemd[1]: Stopping Load/Save OS Random Seed... machine # [ 72.156229] systemd[1]: Stopping flakelet test service... machine # [ 72.157850] systemd[1]: flakelet-static.service: Deactivated successfully. machine # [ 72.160325] systemd[1]: Stopped Update flakelet service static. machine # [ 72.163042] systemd[1]: flakelet-web.service: Deactivated successfully. machine # [ 72.166198] systemd[1]: Stopped Update flakelet service web. machine # [ 72.168700] systemd[1]: flakelet-web.service: Consumed 4.326s CPU time over 1min 1.627s wall clock time, 125.5M memory peak, 4K written to disk. machine # [ 72.173986] systemd[1]: Stopped target flakelet contract providers ready. machine # [ 72.175670] systemd[1]: flakelet-reconcile.service: Deactivated successfully. machine # [ 72.177163] systemd[1]: Stopped Reconcile flakelet services with the host configuration. machine # [ 72.179316] systemd[1]: flakelet-boot.service: Deactivated successfully. machine # [ 72.181305] systemd[1]: Stopped Re-link flakelet services at boot. machine # [ 72.182824] systemd[1]: systemd-oomd.service: Deactivated successfully. machine # [ 72.185884] systemd[1]: Stopped Userspace Out-Of-Memory (OOM) Killer. machine # [ 72.187205] systemd[1]: backdoor.service: Deactivated successfully. machine # [ 72.188543] systemd[1]: Stopped backdoor.service. machine # [ 72.189553] systemd[1]: backdoor.service: Consumed 10.172s CPU time over 1min 2.326s wall clock time, 120.5M memory peak, 104K written to disk. machine # [ 72.192228] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 72.193587] systemd[1]: Stopped Getty on tty1. machine # [ 72.194537] systemd[1]: nix-daemon.service: Deactivated successfully. machine # [ 72.195832] systemd[1]: Stopped Nix Daemon. machine # [ 72.197041] systemd[1]: nix-daemon.service: Consumed 3.046s CPU time over 1min 895ms wall clock time, 53.6M memory peak, 4.9M written to disk. machine # [ 72.200813] systemd[1]: static.service: Deactivated successfully. machine # [ 72.202031] systemd[1]: Stopped prebuilt flakelet service static. machine # [ 72.203536] systemd[1]: static-echo@2.service: Deactivated successfully. machine # [ 72.205011] systemd[1]: Stopped static-echo@2.service. machine # [ 72.206068] systemd[1]: cli-echo@1.service: Deactivated successfully. machine # [ 72.207326] systemd[1]: Stopped cli-echo@1.service. machine # [ 72.208383] systemd[1]: cli.service: Deactivated successfully. machine # [ 72.209552] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 72.210709] systemd[1]: web.service: Deactivated successfully. machine # [ 72.211842] systemd[1]: Stopped flakelet test service. machine # [ 72.212962] systemd[1]: Mounted /run/initramfs. machine # [ 72.213884] systemd[1]: systemd-logind.service: Deactivated successfully. machine # [ 72.215153] systemd[1]: Stopped User Login Management. machine # [ 72.216139] systemd[1]: post-boot.service: Deactivated successfully. machine # [ 72.217450] systemd[1]: Stopped Post-Boot Actions. machine # [ 72.219368] systemd[1]: Removed slice Slice /system/getty. machine # [ 72.221544] systemd[1]: Starting Generate shutdown ramfs... machine # [ 72.222832] systemd[1]: Stopping Permit User Sessions... machine # [ 72.224725] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 72.227494] systemd[1]: Stopped Address configuration of eth1. machine # [ 72.229502] systemd[1]: dhcpcd.service: Deactivated successfully. machine # [ 72.230629] systemd[1]: Stopped DHCP Client. machine # [ 72.231815] systemd[1]: dhcpcd.service: Consumed 393ms CPU time over 1min 1.944s wall clock time, 6.2M memory peak, 8K written to disk, 96B incoming IP traffic, 56B outgoing IP traffic. machine # [ 72.235471] systemd[1]: systemd-random-seed.service: Deactivated successfully. machine # [ 72.237873] systemd[1]: Stopped Load/Save OS Random Seed. machine # [ 72.238979] systemd[1]: systemd-user-sessions.service: Deactivated successfully. machine # [ 72.241185] systemd[1]: Stopped Permit User Sessions. machine # [ 72.243053] systemd[1]: Stopped target Network. machine # [ 72.244602] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 72.246714] systemd[1]: Stopped target Remote File Systems. machine # [ 72.249263] systemd[1]: network-local-commands.service: Deactivated successfully. machine # [ 72.252083] systemd[1]: Stopped Extra networking commands.. machine # [ 72.253803] systemd[1]: Stopped target Preparation for Network. machine # [ 72.256275] systemd[1]: Stopping Firewall... machine # [ 72.258627] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 72.260103] systemd[1]: resolvconf.service: Deactivated successfully. machine # [ 72.264248] systemd[1]: Stopped resolvconf update. machine # [ 72.267424] systemd[1]: nscd.service: Deactivated successfully. machine # [ 72.271344] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 72.278266] make-initrd-ng[2798]: /etc/initrd-release -> /nix/store/94m2cr9q6r1gdr1df0sz9ayq29mj68kz-etc-os-release machine # [ 72.283325] make-initrd-ng[2798]: /etc/os-release -> /nix/store/94m2cr9q6r1gdr1df0sz9ayq29mj68kz-etc-os-release machine # [ 72.287645] make-initrd-ng[2798]: /shutdown -> /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/systemd/systemd-shutdown machine # [ 72.293807] systemd[1]: Stopped target Basic System. machine # [ 72.297927] systemd[1]: Stopped target Path Units. machine # [ 72.301967] systemd[1]: Stopped target Slice Units. machine # [ 72.304120] systemd[1]: Removed slice User and Session Slice. machine # [ 72.308946] systemd[1]: Stopped target Socket Units. machine # [ 72.311476] systemd[1]: cli-echo@1.socket: Deactivated successfully. machine # [ 72.315933] systemd[1]: Closed cli-echo@1.socket. machine # [ 72.318752] systemd[1]: cli-echo@2.socket: Deactivated successfully. machine # [ 72.324939] systemd[1]: Closed cli-echo@2.socket. machine # [ 72.328981] systemd[1]: cli-echo@3.socket: Deactivated successfully. machine # [ 72.338417] systemd[1]: Closed cli-echo@3.socket. machine # [ 72.342780] systemd[1]: Removed slice Slice /system/cli-echo. machine # [ 72.351520] systemd[1]: nix-daemon.socket: Deactivated successfully. machine # [ 72.356766] systemd[1]: Closed Nix Daemon Socket. machine # [ 72.359884] systemd[1]: static-echo@1.socket: Deactivated successfully. machine # [ 72.364834] systemd[1]: Closed static-echo@1.socket. machine # [ 72.368735] systemd[1]: static-echo@2.socket: Deactivated successfully. machine # [ 72.372873] systemd[1]: Closed static-echo@2.socket. machine # [ 72.374813] systemd[1]: static-echo@3.socket: Deactivated successfully. machine # [ 72.378383] systemd[1]: Closed static-echo@3.socket. machine # [ 72.381612] systemd[1]: Removed slice Slice /system/static-echo. machine # [ 72.383918] systemd[1]: systemd-hostnamed.socket: Deactivated successfully. machine # [ 72.388651] systemd[1]: Closed Hostname Service Socket. machine # [ 72.391936] systemd[1]: systemd-importd.socket: Deactivated successfully. machine # [ 72.396149] systemd[1]: Closed Disk Image Download Service Socket. machine # [ 72.400124] systemd[1]: systemd-machined.socket: Deactivated successfully. machine # [ 72.402503] systemd[1]: Closed Virtual Machine and Container Registration Service Socket. machine # [ 72.405236] systemd[1]: systemd-mute-console.socket: Deactivated successfully. machine # [ 72.407605] systemd[1]: Closed Console Output Muting Service Socket. machine # [ 72.412611] systemd[1]: systemd-oomd.socket: Deactivated successfully. machine # [ 72.417718] systemd[1]: Closed Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 72.423077] make-initrd-ng[2798]: Warning: Couldn't satisfy dependency libcrypt.so.1 for "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/systemd/libsystemd-shared-261.so" machine # [ 72.431833] make-initrd-ng[2798]: Warning: Couldn't satisfy dependency libcrypt.so.1.1 for "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/systemd/libsystemd-shared-261.so" machine # [ 72.441932] systemd[1]: systemd-repart.socket: Deactivated successfully. machine # [ 72.446003] systemd[1]: Closed Disk Repartitioning Service Socket. machine # [ 72.449652] systemd[1]: Stopping D-Bus System Message Bus... machine # [ 72.453390] dbus-broker[637]: Dispatched 24210 messages @ 3(±5)μs / message. machine # [ 72.458603] systemd[1]: dbus-broker.service: Deactivated successfully. machine # [ 72.462746] systemd[1]: Stopped D-Bus System Message Bus. machine # [ 72.467001] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 72.471520] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 72.475408] systemd[1]: Stopped target System Initialization. machine # [ 72.479666] systemd[1]: Stopped target Local Encrypted Volumes. machine # [ 72.481992] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 72.484954] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 72.487784] systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. machine # [ 72.490350] systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. machine # [ 72.492713] systemd[1]: register-nix-paths.service: Deactivated successfully. machine # [ 72.494830] systemd[1]: Stopped register-nix-paths.service. machine # [ 72.496550] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 72.498452] systemd[1]: Stopped Apply Kernel Variables. machine # [ 72.499928] systemd[1]: systemd-coredump.socket: Deactivated successfully. machine # [ 72.501858] systemd[1]: Closed Process Core Dump Socket. machine # [ 72.503311] systemd[1]: systemd-update-done.service: Deactivated successfully. machine # [ 72.505318] systemd[1]: Stopped Update is Completed. machine # [ 72.506695] systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. machine # [ 72.508805] systemd[1]: Stopped Rebuild Journal Catalog. machine # [ 72.510249] systemd[1]: Stopping Record System Boot/Shutdown in UTMP... machine # [ 72.511948] systemd[1]: systemd-update-utmp.service: Deactivated successfully. machine # [ 72.513733] systemd[1]: Stopped Record System Boot/Shutdown in UTMP. machine # [ 72.515277] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 72.518023] systemd[1]: Stopped Create System Files and Directories. machine # [ 72.519467] systemd[1]: Stopped target Local File Systems. machine # [ 72.520828] systemd[1]: Unmounting /run/keys... machine # [ 72.521961] systemd[1]: Unmounting /run/wrappers... machine # [ 72.523185] systemd[1]: run-wrappers.mount: Deactivated successfully. machine # [ 72.524784] systemd[1]: Unmounted /run/wrappers. machine # [ 72.525940] systemd[1]: run-keys.mount: Deactivated successfully. machine # [ 72.527382] systemd[1]: Unmounted /run/keys. machine # [ 72.528524] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 72.530109] systemd[1]: Stopped target Swaps. machine # [ 72.531208] systemd[1]: Reached target Unmount All Filesystems. machine # [ 72.532610] systemd[1]: systemd-remount-fs.service: Deactivated successfully. machine # [ 72.534203] systemd[1]: Stopped Remount Root and Kernel File Systems. machine # [ 72.535669] systemd[1]: systemd-fsck-root.service: Deactivated successfully. machine # [ 72.537357] systemd[1]: Stopped File System Check on Root Device. machine # [ 72.538784] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 72.540555] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 72.541958] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 72.543680] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 72.545214] systemd[1]: firewall.service: Deactivated successfully. machine # [ 72.546516] systemd[1]: Stopped Firewall. machine # [ 72.547413] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 72.548874] systemd[1]: Stopped Load Kernel Modules. machine # [ 72.549883] systemd[1]: generate-shutdown-ramfs.service: Deactivated successfully. machine # [ 72.551321] systemd[1]: Finished Generate shutdown ramfs. machine # [ 72.552386] systemd[1]: Reached target System Shutdown. machine # [ 72.553396] systemd[1]: Reached target Late Shutdown Services. machine # [ 72.554500] systemd[1]: systemd-poweroff.service: Deactivated successfully. machine # [ 72.555780] systemd[1]: Finished System Power Off. machine # [ 72.556773] systemd[1]: Reached target System Power Off. machine # [ 72.557777] systemd[1]: Shutting down. machine # [ 72.655447] systemd-shutdown[1]: Syncing filesystems and block devices. machine # [ 72.664817] systemd-shutdown[1]: Sending SIGTERM to remaining processes... machine # [ 72.677179] systemd-journald[315]: Received SIGTERM from PID 1 (systemd-shutdow). machine # [ 72.718232] systemd-shutdown[1]: Sending SIGKILL to remaining processes... machine # [ 72.729434] systemd-shutdown[1]: Unmounting file systems. machine # [ 72.736095] (sd-umount)[2834]: Unmounting '/run/credentials/systemd-journald.service'. machine # [ 72.742632] (sd-remount)[2835]: Remounting '/nix/store' read-only with options 'lowerdir=/sysroot/nix/.ro-store,upperdir=/sysroot/nix/.rw-store/upper,workdir=/sysroot/nix/.rw-store/work,uuid=on'. machine # [ 72.767336] (sd-remount)[2836]: Remounting '/nix/store' read-only with options 'lowerdir=/sysroot/nix/.ro-store,upperdir=/sysroot/nix/.rw-store/upper,workdir=/sysroot/nix/.rw-store/work,uuid=on'. machine # [ 72.777336] (sd-remount)[2837]: Remounting '/tmp/xchg' read-only with options 'access=client,msize=16384,trans=virtio'. machine # [ 72.780099] (sd-umount)[2838]: Unmounting '/tmp/xchg'. machine # [ 72.824759] (sd-remount)[2839]: Remounting '/tmp/shared' read-only with options 'access=client,msize=16384,trans=virtio'. machine # [ 72.827149] (sd-umount)[2840]: Unmounting '/tmp/shared'. machine # [ 72.852649] (sd-remount)[2841]: Remounting '/nix/.ro-store' read-only with options 'cache=0xf,access=client,msize=16384,trans=virtio'. machine # [ 73.030878] (sd-umount)[2842]: Unmounting '/nix/.ro-store'. machine # [ 73.034908] (sd-remount)[2843]: Remounting '/' read-only with options ''. machine # [ 73.052934] EXT4-fs (vda): re-mounted f73edf28-a915-4c22-86c8-9a59257a0fca ro. machine # [ 73.059275] systemd-shutdown[1]: All filesystems unmounted. machine # [ 73.059529] systemd-shutdown[1]: Deactivating swaps. machine # [ 73.060516] systemd-shutdown[1]: All swaps deactivated. machine # [ 73.061358] systemd-shutdown[1]: Detaching loop devices. machine # [ 73.080832] systemd-shutdown[1]: All loop devices detached. machine # [ 73.081117] systemd-shutdown[1]: Stopping MD devices. machine # [ 73.082721] systemd-shutdown[1]: All MD devices stopped. machine # [ 73.082905] systemd-shutdown[1]: Detaching DM devices. machine # [ 73.084091] systemd-shutdown[1]: All DM devices detached. machine # [ 73.084673] systemd-shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached. machine # [ 73.087304] systemd-shutdown[1]: Failed to chase and open directory '/etc/systemd/system-shutdown', ignoring: Input/output error machine # [ 73.424279] systemd-shutdown[1]: Successfully changed into root pivot. machine # [ 73.424336] systemd-shutdown[1]: Entering exitrd... machine # [ 73.443175] shutdown[1]: Syncing filesystems and block devices. machine # [ 73.444942] shutdown[1]: Sending SIGTERM to remaining processes... machine # [ 73.454570] shutdown[1]: Sending SIGKILL to remaining processes... machine # [ 73.463888] shutdown[1]: Unmounting file systems. machine # [ 73.467803] (sd-umoun[2845]: Unmounting '/run/credentials'. machine # [ 73.473455] (sd-umoun[2846]: Unmounting '/oldroot/sys/fs/fuse/connections'. machine # [ 73.479709] (sd-umoun[2847]: Unmounting '/oldroot/sys/kernel/config'. machine # [ 73.485558] (sd-umoun[2848]: Unmounting '/oldroot/sys/kernel/debug'. machine # [ 73.491394] (sd-umoun[2849]: Unmounting '/oldroot/sys/kernel/tracing'. machine # [ 73.497126] (sd-umoun[2850]: Unmounting '/oldroot/dev/mqueue'. machine # [ 73.502790] (sd-umoun[2851]: Unmounting '/oldroot/dev/hugepages'. machine # [ 73.508481] (sd-umoun[2852]: Unmounting '/oldroot/sys/fs/bpf'. machine # [ 73.514270] (sd-umoun[2853]: Unmounting '/oldroot/sys/fs/pstore'. machine # [ 73.519928] (sd-umoun[2854]: Unmounting '/oldroot/sys/fs/cgroup'. machine # [ 73.525628] (sd-umoun[2855]: Unmounting '/oldroot/sys/kernel/security'. machine # [ 73.531546] (sd-umoun[2856]: Unmounting '/oldroot/nix/store'. machine # [ 73.537062] (sd-umoun[2857]: Unmounting '/oldroot/sys'. machine # [ 73.543477] (sd-umoun[2858]: Unmounting '/oldroot/proc'. machine # [ 73.548839] (sd-umoun[2859]: Unmounting '/oldroot/dev/shm'. machine # [ 73.554309] (sd-umoun[2860]: Unmounting '/oldroot/dev/pts'. machine # [ 73.559546] (sd-umoun[2861]: Unmounting '/oldroot/dev'. machine # [ 73.564737] (sd-umoun[2862]: Unmounting '/oldroot/nix/store'. machine # [ 73.654232] (sd-umoun[2863]: Unmounting '/oldroot/run'. machine # [ 73.659503] (sd-umoun[2864]: Unmounting '/oldroot'. machine # [ 73.685583] EXT4-fs (vda): unmounting filesystem f73edf28-a915-4c22-86c8-9a59257a0fca. machine # [ 73.691264] shutdown[1]: All filesystems unmounted. machine # [ 73.692070] shutdown[1]: Deactivating swaps. machine # [ 73.692646] shutdown[1]: All swaps deactivated. machine # [ 73.693168] shutdown[1]: Detaching loop devices. machine # [ 73.699819] shutdown[1]: All loop devices detached. machine # [ 73.700665] shutdown[1]: Stopping MD devices. machine # [ 73.701878] shutdown[1]: All MD devices stopped. machine # [ 73.702728] shutdown[1]: Detaching DM devices. machine # [ 73.703868] shutdown[1]: All DM devices detached. machine # [ 73.704638] shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached. machine # [ 73.706229] shutdown[1]: Syncing filesystems and block devices. machine # [ 73.707837] shutdown[1]: Powering off. machine # [ 73.770630] Console: switching to colour dummy device 80x25 machine # [ 73.797050] Flash device refused suspend due to active operation (state 20) machine # [ 73.797111] Flash device refused suspend due to active operation (state 20) machine # [ 73.802466] reboot: Power down machine: (finished: waiting for the VM to power off, in 2.25 seconds) machine: starting vm machine: QEMU running (pid 78) machine: waiting for unit web.service machine: waiting for the VM to finish booting machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Jul 24 14:17:26 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-0x000000013fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x13fdeb740-0x13fdeeebf] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] 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-0x000000013fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 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 s186392 r8192 d116712 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/96xl253dhdcq9sx7w9axx2dgy4yk866i-nixos-system-machine-test/init regInfo=/nix/store/fvl2l9j4drc2nzzrq0rraidyz5bcv0kp-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/fvl2l9j4drc2nzzrq0rraidyz5bcv0kp-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: 524288 (order: 10, 4194304 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) machine # [ 0.000000] software IO TLB: area num 4. machine # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 machine # [ 0.000000] Policy zone: Normal 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=4, Nodes=1 machine # [ 0.000000] allocated 8388608 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74754 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=4. 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=4 machine # [ 0.000000] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. 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 @100af0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100b00000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000100b10000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b20000 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.000366] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000565] Console: colour dummy device 80x25 machine # [ 0.000572] printk: legacy console [tty0] enabled machine # [ 0.000784] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000791] pid_max: default: 32768 minimum: 301 machine # [ 0.000880] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001006] landlock: Up and running. machine # [ 0.001009] Yama: becoming mindful. machine # [ 0.001419] LSM support for eBPF active machine # [ 0.001570] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.001628] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.002810] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003538] rcu: Hierarchical SRCU implementation. machine # [ 0.003542] rcu: Max phase no-delay instances is 1000. machine # [ 0.003710] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level machine # [ 0.004755] fsl-mc MSI: its@8080000 domain created machine # [ 0.004847] EFI services will not be available. machine # [ 0.005056] smp: Bringing up secondary CPUs ... machine # [ 0.005725] Detected PIPT I-cache on CPU1 machine # [ 0.005831] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 machine # [ 0.005965] GICv3: CPU1: using allocated LPI pending table @0x0000000100b30000 machine # [ 0.006098] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40] machine # [ 0.007630] Detected PIPT I-cache on CPU2 machine # [ 0.007740] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 machine # [ 0.007873] GICv3: CPU2: using allocated LPI pending table @0x0000000100b40000 machine # [ 0.008001] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40] machine # [ 0.009100] Detected PIPT I-cache on CPU3 machine # [ 0.009213] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 machine # [ 0.009344] GICv3: CPU3: using allocated LPI pending table @0x0000000100b50000 machine # [ 0.009474] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40] machine # [ 0.010013] smp: Brought up 1 node, 4 CPUs machine # [ 0.010040] SMP: Total of 4 processors activated. machine # [ 0.010043] CPU: All CPU(s) started at EL1 machine # [ 0.010053] CPU features: detected: Branch Target Identification machine # [ 0.010057] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.010059] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.010063] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.010067] CPU features: detected: Common not Private translations machine # [ 0.010070] CPU features: detected: CRC32 instructions machine # [ 0.010073] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.010076] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.010079] CPU features: detected: Data independent timing control (DIT) machine # [ 0.010082] CPU features: detected: E0PD machine # [ 0.010085] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.010087] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.010091] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.010094] CPU features: detected: Fine Grained Traps machine # [ 0.010097] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.010101] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.010104] CPU features: detected: LSE atomic instructions machine # [ 0.010107] CPU features: detected: Privileged Access Never machine # [ 0.010110] CPU features: detected: PMUv3 machine # [ 0.010112] CPU features: detected: RAS Extension Support machine # [ 0.010115] CPU features: detected: RASv1p1 Extension Support machine # [ 0.010118] CPU features: detected: Random Number Generator machine # [ 0.010120] CPU features: detected: Speculation barrier (SB) machine # [ 0.010123] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.010126] CPU features: detected: TLB range maintenance instructions machine # [ 0.010130] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.010266] alternatives: applying system-wide alternatives machine # [ 0.013223] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.013441] Memory: 3910124K/4194304K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 233732K reserved, 32768K cma-reserved) machine # [ 0.014787] devtmpfs: initialized machine # [ 0.018750] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.018819] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). machine # [ 0.019042] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.019047] 0 pages in range for non-PLT usage machine # [ 0.019048] 508320 pages in range for PLT usage machine # [ 0.019180] pinctrl core: initialized pinctrl subsystem machine # [ 0.019938] DMI not present or invalid. machine # [ 0.023209] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.025760] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.025952] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.026175] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.026196] audit: initializing netlink subsys (disabled) machine # [ 0.026524] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 machine # [ 0.026994] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.026996] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.027001] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.027004] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.027006] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.027092] cpuidle: using governor ladder machine # [ 0.027116] cpuidle: using governor menu machine # [ 0.027300] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.027509] ASID allocator initialised with 65536 entries machine # [ 0.028597] Serial: AMBA PL011 UART driver machine # [ 0.033733] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.034027] printk: console [ttyAMA0] enabled machine # [ 0.043777] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.043795] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.043804] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.043812] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.043821] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.043829] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.043837] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.043845] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.054451] fbcon: Taking over console machine # [ 0.054461] ACPI: Interpreter disabled. machine # [ 0.055473] iommu: Default domain type: Translated machine # [ 0.055489] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.070892] SCSI subsystem initialized machine # [ 0.071329] usbcore: registered new interface driver usbfs machine # [ 0.071361] usbcore: registered new interface driver hub machine # [ 0.071377] usbcore: registered new device driver usb machine # [ 0.071649] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.071653] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.071660] PTP clock support registered machine # [ 0.071705] EDAC MC: Ver: 3.0.0 machine # [ 0.072239] scmi_core: SCMI protocol bus registered machine # [ 0.073232] FPGA manager framework machine # [ 0.074010] vgaarb: loaded machine # [ 0.075324] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.089341] VFS: Disk quotas dquot_6.6.0 machine # [ 0.089405] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.090370] netfs: FS-Cache loaded machine # [ 0.090666] pnp: PnP ACPI: disabled machine # [ 0.101847] NET: Registered PF_INET protocol family machine # [ 0.103311] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.164318] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.164373] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.164415] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) machine # [ 0.164627] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) machine # [ 0.165101] TCP: Hash tables configured (established 32768 bind 32768) machine # [ 0.165238] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) machine # [ 0.165289] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.165395] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.165578] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.165625] NET: Registered PF_XDP protocol family machine # [ 0.165643] PCI: CLS 0 bytes, default 64 machine # [ 0.166019] Trying to unpack rootfs image as initramfs... machine # [ 0.183639] kvm [1]: HYP mode not available machine # [ 0.263281] Initialise system trusted keyrings machine # [ 0.263675] workingset: timestamp_bits=42 max_order=20 bucket_order=0 machine # [ 0.264333] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.264487] 9p: Installing v9fs 9p2000 file system support machine # [ 0.280467] Key type asymmetric registered machine # [ 0.280480] Asymmetric key parser 'x509' registered machine # [ 0.280532] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.280776] io scheduler mq-deadline registered machine # [ 0.280781] io scheduler kyber registered machine # [ 0.288733] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.291478] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.291912] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.291929] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.291940] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.291948] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.291986] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.292009] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.292137] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.292146] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.292151] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.292155] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.292159] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.292277] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.293057] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.293386] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.293402] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.293436] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.293451] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.294143] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.294432] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.294447] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.294481] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.295162] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.295455] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.295470] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.295509] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.296253] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.296530] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.296545] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.296580] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.297289] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.297579] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.297594] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.297628] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.298375] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.298677] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.298692] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.298726] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.299506] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.299775] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.299791] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.299824] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.299840] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.300571] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.300894] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.300928] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.301623] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.301944] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.301978] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.302587] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.302865] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.303236] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.303656] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.303671] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.303713] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.304383] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.304623] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.304638] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.304679] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.305617] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.305625] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.305630] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.305700] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.305772] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.305844] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.305907] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.305972] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.306035] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.306099] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.306162] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.306226] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.306314] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.306368] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.306400] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.306430] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.306461] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.306493] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.306524] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.306554] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.306585] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.306617] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.306648] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.306680] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.306710] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.306740] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.306763] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.306785] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.306808] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.306831] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.306859] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.306890] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.306921] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.306952] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.306988] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.306994] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.306998] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.308825] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.324005] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.327028] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.329879] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.332848] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.339367] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.342105] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.344908] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.347739] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.354478] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.357112] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.360415] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.368243] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.370710] msm_serial: driver initialized machine # [ 0.370893] SuperH (H)SCI(F) driver initialized machine # [ 0.370955] STM32 USART driver initialized machine # [ 0.409278] loop: module loaded machine # [ 0.409539] virtio_blk virtio5: 4/0/0 default/read/poll queues machine # [ 0.411923] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.414814] megasas: 07.734.00.00-rc1 machine # [ 0.415741] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.426882] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.426997] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.429642] Using buffer write method machine # [ 0.429693] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.432409] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.432435] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.435041] Using buffer write method machine # [ 0.435070] Concatenating MTD devices: machine # [ 0.435074] (0): "0.flash" machine # [ 0.435077] (1): "0.flash" machine # [ 0.435080] into device "0.flash" machine # [ 0.619822] Freeing initrd memory: 27052K machine # [ 0.632586] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.636648] thunder_xcv, ver 1.0 machine # [ 0.636707] thunder_bgx, ver 1.0 machine # [ 0.636731] nicpf, ver 1.0 machine # [ 0.637322] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.637327] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.637359] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.637364] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.637388] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.637391] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.637412] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.637415] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.637550] sky2: driver version 1.30 machine # [ 0.639156] usbcore: registered new interface driver usb-storage machine # [ 0.639272] usbcore: registered new interface driver usbserial_generic machine # [ 0.639288] usbserial: USB Serial support registered for generic machine # [ 0.639965] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.640257] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.640314] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.640913] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.641099] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.641130] rtc-pl031 9010000.pl031: setting system clock to 2026-08-31T08:46:11 UTC (1788165971) machine # [ 0.641453] i2c_dev: i2c /dev entries driver machine # [ 0.644267] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.644272] sdhci: Copyright(c) Pierre Ossman machine # [ 0.644538] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.644916] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.646590] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.646864] usbcore: registered new interface driver usbhid machine # [ 0.646868] usbhid: USB HID core driver machine # [ 0.659509] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.660908] hub 1-0:1.0: USB hub found machine # [ 0.661030] hub 1-0:1.0: 6 ports detected machine # [ 0.680729] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.685980] drop_monitor: Initializing network drop monitor service machine # [ 0.686591] NET: Registered PF_INET6 protocol family machine # [ 0.688981] Segment Routing with IPv6 machine # [ 0.689001] In-situ OAM (IOAM) with IPv6 machine # [ 0.689036] NET: Registered PF_PACKET protocol family machine # [ 0.689426] 9pnet: Installing 9P2000 support machine # [ 0.692406] Key type dns_resolver registered machine # [ 0.699833] registered taskstats version 1 machine # [ 0.700148] Loading compiled-in X.509 certificates machine # [ 0.710512] Demotion targets for Node 0: null machine # [ 0.710676] Key type .fscrypt registered machine # [ 0.710680] Key type fscrypt-provisioning registered machine # [ 0.710814] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.710832] ima: Allocated hash algorithm: sha1 machine # [ 0.710856] ima: No architecture policies found machine # [ 0.711788] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 0.733339] clk: Disabling unused clocks machine # [ 0.733368] PM: genpd: Disabling unused power domains machine # [ 0.737193] Freeing unused kernel memory: 4736K machine # [ 0.737387] Run /init as init process machine # [ 0.764880] systemd[1]: Successfully made /usr/ read-only. machine # [ 0.911442] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.066137] 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.099135] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.099202] systemd[1]: Detected virtualization qemu. machine # [ 1.099271] systemd[1]: Detected architecture arm64. machine # [ 1.099285] systemd[1]: Running in initrd. machine # [ 1.100171] systemd[1]: Initializing machine ID from random generator. machine # [ 1.100425] systemd[1]: Hostname set to . machine # [ 1.211588] 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.331401] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.383651] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.485837] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.491502] 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.491847] 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.509011] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.509494] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.509567] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.509612] systemd[1]: Reached target Path Units. machine # [ 1.509647] systemd[1]: Reached target Slice Units. machine # [ 1.509682] systemd[1]: Reached target Swaps. machine # [ 1.509719] systemd[1]: Reached target Timer Units. machine # [ 1.510057] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.510385] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.510705] systemd[1]: Listening on Journal Sockets. machine # [ 1.511003] systemd[1]: Listening on udev Control Socket. machine # [ 1.511179] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.511219] systemd[1]: Reached target Socket Units. machine # [ 1.514431] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.517670] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.517816] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.521508] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.550403] systemd[1]: Starting Journal Service... machine # [ 1.553191] systemd[1]: Starting Load Kernel Modules... machine # [ 1.553351] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.555531] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.563592] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.572233] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.576327] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.580758] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.591575] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.602983] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.603161] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.603165] [drm] features: -context_init machine # [ 1.603881] systemd-journald[95]: Collecting audit messages is disabled. machine # [ 1.604254] [drm] number of scanouts: 1 machine # [ 1.604279] [drm] number of cap sets: 0 machine # [ 1.616906] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.619435] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.619531] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.619546] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.623102] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.627597] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.631013] Console: switching to colour frame buffer device 160x50 machine # [ 1.638346] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.662902] systemd[1]: Finished Load Kernel Modules. machine # [ 1.667664] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.680884] systemd[1]: Started Journal Service. machine # [ 1.678210] systemd-modules-load[96]: Using 4 probe threads machine # [ 1.682100] systemd-modules-load[96]: Module 'virtio_balloon' is built in machine # [ 1.683394] systemd-modules-load[96]: Module 'virtio_console' is built in machine # [ 1.684665] systemd-modules-load[96]: Module 'virtio_rng' is built in machine # [ 1.686054] systemd-modules-load[96]: Inserted module 'dm_mod' machine # [ 1.687445] systemd-modules-load[96]: Inserted module 'virtio_gpu' machine # [ 1.693547] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.695690] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.702273] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.703820] systemd[1]: Reached target Local File Systems. machine # [ 1.705322] systemd[1]: Starting Create System Files and Directories... machine # [ 1.708922] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.755213] systemd[1]: Finished Create System Files and Directories. machine # [ 1.765269] systemd-udevd[115]: Using default interface naming scheme 'v261'. machine # [ 1.784372] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.808211] systemd[1]: Starting Virtual Console Setup... machine # [ 1.880957] systemd-vconsole-setup[131]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.885419] systemd[1]: Finished Virtual Console Setup. machine # [ 2.187181] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.189872] systemd[1]: Reached target System Initialization. machine # [ 2.192359] systemd[1]: Reached target Basic System. machine # [ 2.394145] (udev-worker)[127]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.397946] (udev-worker)[127]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.430245] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.435108] systemd[1]: Reached target Initrd Root Device. machine # [ 2.439011] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.488777] systemd-fsck[153]: nixos: clean, 1217/65536 files, 18918/262144 blocks machine # [ 2.494990] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.556384] systemd[1]: Mounting /sysroot... machine # [ 2.594456] EXT4-fs (vda): mounted filesystem f73edf28-a915-4c22-86c8-9a59257a0fca r/w with ordered data mode. Quota mode: none. machine # [ 2.596900] systemd[1]: Mounted /sysroot. machine # [ 2.597689] systemd[1]: Reached target Initrd Root File System. machine # [ 2.599407] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.600882] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.630566] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.634268] systemd-sysroot-fstab-check[162]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.636688] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 2.638032] systemd[1]: Reload requested from client PID 162 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.640867] systemd[1]: Reloading... machine # [ 2.740671] systemd[1]: Reloading finished in 105 ms. machine # [ 2.798323] systemd-sysroot-fstab-check[162]: Requesting initrd-fs.target/start/replace... machine # [ 2.800268] systemd-sysroot-fstab-check[162]: Requesting swap.target/start/replace... machine # [ 2.805402] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 2.806492] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.807390] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.808485] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.809700] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.823411] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 2.825218] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 2.826234] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.827580] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 2.941967] (udev-worker)[123]: mtd0ro: Failed to find and pin callout binary "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/mtd_probe": No such file or directory machine # [ 2.945381] (udev-worker)[123]: mtd0ro: /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/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 # [ 2.948990] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 2.950035] systemd[1]: Stopped Virtual Console Setup. machine # [ 2.950774] systemd[1]: Stopping Virtual Console Setup... machine # [ 2.951537] systemd[1]: Starting Virtual Console Setup... machine # [ 2.968994] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 2.970098] systemd[1]: Stopped Virtual Console Setup. machine # [ 2.970818] systemd[1]: Starting Virtual Console Setup... machine # [ 2.994121] systemd-vconsole-setup[189]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.996823] systemd[1]: Finished Virtual Console Setup. machine # [ 3.560167] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.564392] systemd[1]: Mounting /sysroot/run... machine # [ 3.568930] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.584293] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.610613] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.613461] systemd[1]: Mounted /sysroot/run. machine # [ 3.615521] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.621760] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.624949] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.694340] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.696689] systemd[1]: Reached target Initrd File Systems. machine # [ 3.699084] systemd[1]: Starting Find NixOS closure... machine # [ 3.701461] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.753590] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.771831] systemd[1]: Finished Find NixOS closure. machine # [ 3.774226] systemd[1]: Reached target Initrd Default Target. machine # [ 3.776769] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.823384] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.826098] systemd[1]: Stopped target Basic System. machine # [ 3.828287] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.830630] systemd[1]: Stopped target Path Units. machine # [ 3.832767] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.835904] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.839344] systemd[1]: Stopped target Slice Units. machine # [ 3.841556] systemd[1]: Stopped target Socket Units. machine # [ 3.845216] systemd[1]: Stopped target System Initialization. machine # [ 3.847715] systemd[1]: Stopped target Swaps. machine # [ 3.849776] systemd[1]: Stopped target Timer Units. machine # [ 3.851901] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.854393] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.856808] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.860945] systemd[1]: Stopped Find NixOS closure. machine # [ 3.863106] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.865740] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.868316] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.870864] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.873363] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.875609] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.877392] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.879772] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.883693] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.886037] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.888056] systemd[1]: Stopped target Local File Systems. machine # [ 3.889869] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.891921] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.894266] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.895966] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.898236] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.900556] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.902132] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.904046] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.905885] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.907807] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.909571] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.911384] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.912938] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.914556] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.916306] systemd[1]: systemd-udevd.service: Consumed 2.173s CPU time over 2.181s wall clock time, 31.5M memory peak. machine # [ 3.918673] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. machine # [ 3.920309] systemd[1]: Closed udev Control Socket. machine # [ 3.921465] systemd[1]: Starting Cleanup udev Database... machine # [ 3.922653] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.924376] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.925737] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.927461] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.929062] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.930556] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.015495] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.018986] systemd[1]: Finished Cleanup udev Database. machine # [ 4.021420] systemd[1]: Reached target Switch Root. machine # [ 4.023647] systemd[1]: Starting NixOS Activation... machine # [ 4.326964] initrd-nixos-activation-start[222]: booting system configuration /nix/store/96xl253dhdcq9sx7w9axx2dgy4yk866i-nixos-system-machine-test machine # [ 4.432431] initrd-nixos-activation-start[222]: running activation script... machine # [ 4.968455] initrd-nixos-activation-start[245]: setting up /etc... machine # [ 5.378998] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.382545] systemd[1]: Finished NixOS Activation. machine # [ 5.384854] systemd[1]: Starting Switch Root... machine # [ 5.427237] systemd[1]: Switching root. machine # [ 5.529094] systemd-journald[95]: Received SIGTERM from PID 1 (systemd). machine # [ 6.266642] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 6.266787] systemd[1]: Detected virtualization qemu. machine # [ 6.266872] systemd[1]: Detected architecture arm64. machine # [ 6.500100] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.363259] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 7.363586] systemd[1]: Stopped initrd-switch-root.service. machine # [ 7.365405] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 7.374296] systemd[1]: Created slice Slice /system/getty. machine # [ 7.377696] systemd[1]: Created slice User and Session Slice. machine # [ 7.377997] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 7.378754] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 7.379477] systemd[1]: Expecting device /dev/hvc0... machine # [ 7.380220] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 7.381039] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 7.381081] systemd[1]: Reached target flakelet contract providers ready. machine # [ 7.381110] systemd[1]: Stopped target initrd-fs.target. machine # [ 7.381134] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 7.381534] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 7.381846] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 7.382154] systemd[1]: Reached target Path Units. machine # [ 7.382454] systemd[1]: Reached target Remote File Systems. machine # [ 7.382734] systemd[1]: Reached target Slice Units. machine # [ 7.383019] systemd[1]: Reached target Swaps. machine # [ 7.386521] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 7.390533] systemd[1]: Listening on Process Core Dump Socket. machine # [ 7.393812] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 7.397061] systemd[1]: Listening on Factory Reset Management. machine # [ 7.397673] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.404965] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.406312] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.410608] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.411503] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.412035] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.412796] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.422221] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.422872] systemd[1]: Listening on udev Control Socket. machine # [ 7.423653] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.489021] systemd[1]: Mounting Huge Pages File System... machine # [ 7.495438] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 7.502300] systemd[1]: Mounting Kernel Debug File System... machine # [ 7.509325] systemd[1]: Mounting Kernel Trace File System... machine # [ 7.532478] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 7.543853] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 7.545107] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 7.562494] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.563783] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.564950] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.603274] systemd[1]: Starting Load Kernel Module fuse... machine # [ 7.604751] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.625982] systemd[1]: Starting Journal Service... machine # [ 7.640166] systemd[1]: Starting Load Kernel Modules... machine # [ 7.661301] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.674158] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.675561] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.694383] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.700842] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.702082] systemd[1]: Mounted Huge Pages File System. machine # [ 7.703179] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.704246] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.705309] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.706924] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.708488] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 7.711027] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 7.713406] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.736648] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.748516] systemd-journald[316]: Collecting audit messages is enabled. machine # [ 7.759222] fuse: init (API version 7.45) machine # [ 7.762842] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.760712] systemd[1]: Finished Load Kernel Modules. machine # [ 7.769616] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 7.771311] systemd-modules-load[317]: Using 2 probe threads[ 7.774814] systemd[1]: Starting Firewall... machine # machine # [ 7.775375] systemd-modules-load[317]: Module 'atkbd' is built in[ 7.779883] EXT4-fs (vda): re-mounted f73edf28-a915-4c22-86c8-9a59257a0fca. machine # machine # [ 7.783574] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.784156] systemd-modules-load[317]: Module 'loop' is built in machine # [ 7.789090] systemd[1]: Started Journal Service. machine # [ 7.790214] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 7.799331] systemd-oomd[319]: No swap; memory pressure usage will be degraded machine # [ 7.803445] systemd[1]: Finished Load Kernel Module fuse. machine # [ 7.806984] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.811600] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.822962] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.832361] systemd[1]: Mounting FUSE Control File System... machine # [ 7.838172] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.840832] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.852838] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.855680] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.880839] systemd-journald[316]: Received client request to flush runtime journal. machine # [ 7.908799] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.910819] systemd[1]: Mounted FUSE Control File System. machine # [ 7.912191] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.913421] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.914694] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.915780] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.916842] systemd[1]: First Boot Complete skipped, unmet condition check ConditionFirstBoot=yes machine # [ 7.954129] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.955270] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.959004] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.050523] systemd-udevd[354]: Using default interface naming scheme 'v261'. machine # [ 8.155395] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.367963] systemd[1]: Mounting /run/wrappers... machine # [ 8.408652] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.436342] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.456727] systemd[1]: Mounted /run/wrappers. machine # [ 8.461612] systemd[1]: Reached target Local File Systems. machine # [ 8.465095] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.470254] systemd[1]: Starting register-nix-paths.service... machine # [ 8.476592] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.480919] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.492531] systemd[1]: Save Transient machine-id to Disk skipped, unmet condition check ConditionPathIsMountPoint=/etc/machine-id machine # [ 8.511992] systemd[1]: Starting Create System Files and Directories... machine # [ 8.521703] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 8.530635] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 8.583603] systemd[1]: Finished Create System Files and Directories. machine # [ 8.595321] systemd[1]: Rebuild Journal Catalog skipped, unmet condition check ConditionNeedsUpdate=/var machine # [ 8.598901] systemd[1]: Update is Completed skipped, no trigger condition checks were met. machine # [ 8.601406] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.622799] (udev-worker)[424]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 8.626808] (udev-worker)[424]: Network interface NamePolicy= disabled on kernel command line. machine # [ 8.650358] (udev-worker)[382]: Network interface NamePolicy= disabled on kernel command line. machine # [ 8.667231] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 8.674097] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.676104] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.678215] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.680865] systemd[1]: Rebuild Journal Catalog skipped, unmet condition check ConditionNeedsUpdate=/var machine # [ 8.682363] systemd[1]: Save Transient machine-id to Disk skipped, unmet condition check ConditionPathIsMountPoint=/etc/machine-id machine # [ 8.684228] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.686131] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.687783] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.689473] systemd[1]: Update is Completed skipped, no trigger condition checks were met. machine # [ 8.690832] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.723880] mousedev: PS/2 mouse device common for all mice machine # [ 9.022486] systemd[1]: Finished Firewall. machine # [ 9.030787] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.032351] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.112706] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 9.221071] systemd[1]: Starting Virtual Console Setup... machine # [ 9.230683] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 9.232643] systemd[1]: Stopped Virtual Console Setup. machine # [ 9.235669] systemd[1]: Starting Virtual Console Setup... machine # [ 9.266675] systemd[1]: Finished register-nix-paths.service. machine # [ 9.652741] systemd-vconsole-setup[554]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 9.656252] systemd[1]: Finished Virtual Console Setup. machine # [ 9.657742] systemd[1]: Reached target System Initialization. machine # [ 9.658750] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.659840] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.661152] systemd[1]: Reached target Timer Units. machine # [ 9.662222] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.663566] systemd[1]: Listening on Nix Daemon Socket. machine # [ 9.664835] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.666511] systemd[1]: Reached target Socket Units. machine # [ 9.667656] systemd[1]: Reached target Basic System. machine # [ 9.669399] systemd[1]: Started backdoor.service. machine # [ 9.670257] systemd[1]: Starting Re-link flakelet services at boot... machine # [ 9.671231] systemd[1]: Import lastlog data into lastlog2 database skipped, unmet condition check ConditionPathExists=!/var/lib/lastlog/lastlog2.db machine # [ 9.674280] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.676052] systemd[1]: Starting Post-Boot Actions... machine # [ 9.678861] systemd[1]: Started Reset console on configuration changes. machine # [ 9.691752] systemd[1]: Starting resolvconf update... machine # [ 9.700337] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.738089] systemd[1]: Finished Post-Boot Actions. machine # connecting to host... machine # [ 9.769485] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.772118] nsncd[560]: Aug 31 08:46:20.631 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.774660] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.775834] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.780002] systemd[1]: Starting User Login Management... machine # [ 9.783071] systemd[1]: Reload requested from client PID 571 ('systemctl') (unit flakelet-boot.service)... machine # [ 9.787429] systemd[1]: Reloading... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 10.22 seconds) machine: (finished: waiting for the VM to finish booting, in 10.22 seconds) machine # [ 9.835035] dbus-broker-launch[565]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 9.870474] systemd-logind[584]: New seat seat0. machine # [ 9.880423] systemd-logind[584]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 9.882530] systemd-logind[584]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 9.886275] systemd-logind[584]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.450042] systemd[1]: Reloading finished in 662 ms. machine # [ 10.562686] systemd[1]: Started User Login Management. machine # [ 10.583185] dbus-broker-launch[565]: NSS returned no entry for 'systemd-timesync' machine # [ 10.590732] dbus-broker-launch[565]: Invalid user-name in /nix/store/s8sm8xwbfx7y43x6lrfyr9mp58vgim5j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.606472] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.616750] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.622009] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.629684] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.634748] systemd[1]: Starting linger-users.service... machine # [ 10.641036] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.644312] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.646834] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 10.648925] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.679724] dbus-broker-launch[565]: Ready machine # [ 10.685835] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.690228] systemd[1]: Finished linger-users.service. machine # [ 10.702686] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.728698] systemd[1]: Finished resolvconf update. machine # [ 10.734055] systemd[1]: Reached target Preparation for Network. machine # [ 10.742564] systemd[1]: Starting DHCP Client... machine # [ 10.747463] systemd[1]: Starting Address configuration of eth1... machine # [ 10.754685] systemd[1]: Starting Extra networking commands.... machine # [ 10.774151] nsncd[689]: Aug 31 08:46:21.634 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.781057] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.785593] systemd[1]: Reload requested from client PID 692 ('systemctl') (unit flakelet-boot.service)... machine # [ 10.793833] systemd[1]: Reloading... machine # [ 10.883826] network-addresses-eth1-start[696]: adding address 192.168.1.1/24... done machine # [ 10.897792] network-addresses-eth1-start[696]: adding address 2001:db8:1::1/64... done machine # [ 11.390541] systemd[1]: Reloading finished in 614 ms. machine # [ 11.453883] systemd[1]: Finished Address configuration of eth1. machine # [ 11.458455] systemd[1]: Finished Extra networking commands.. machine # [ 11.461805] systemd[1]: Reached target Network. machine # [ 11.463579] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.465586] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.469075] systemd[1]: Starting Permit User Sessions... machine # [ 11.495633] systemd[1]: Created slice Slice /system/cli-echo. machine # [ 11.498097] systemd[1]: Listening on cli-echo@1.socket. machine # [ 11.499253] systemd[1]: Listening on cli-echo@3.socket. machine # [ 11.521972] systemd[1]: Finished Permit User Sessions. machine # [ 11.527364] systemd[1]: Listening on cli-echo@2.socket. machine # [ 11.532317] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 11.538062] systemd[1]: Started Getty on tty1. machine # [ 11.540673] systemd[1]: Reached target Login Prompts. machine # [ 11.560857] systemd[1]: Reload requested from client PID 807 ('systemctl') (unit flakelet-boot.service)... machine # [ 11.566644] systemd[1]: Reloading... machine # [ 11.654269] dhcpcd[799]: dhcpcd-10.3.2 starting machine # [ 11.675270] dhcpcd[842]: dev: loaded udev machine # [ 11.680222] dhcpcd[842]: DUID 00:01:00:01:32:27:fb:92:52:54:00:12:34:56 machine # [ 11.717146] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.717737] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 11.792327] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 11.825052] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.826030] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.829567] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.830193] cfg80211: failed to load regulatory.db machine # [ 11.910647] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 11.908358] dhcpcd[842]: eth0: waiting for carrier machine # [ 11.916785] dhcpcd[842]: eth0: carrier acquired machine # [ 11.925415] dhcpcd[842]: eth0: IAID 00:12:34:56 machine # [ 11.928949] dhcpcd[842]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.207146] systemd[1]: Reloading finished in 643 ms. machine # [ 12.267130] systemd[1]: Started prebuilt flakelet service cli. machine # [ 12.514633] systemd[1]: Reload requested from client PID 882 ('systemctl') (unit flakelet-boot.service)... machine # [ 12.519784] systemd[1]: Reloading... machine # [ 13.193942] systemd[1]: Reloading finished in 677 ms. machine # [ 13.259837] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.272372] systemd[1]: Created slice Slice /system/static-echo. machine # [ 13.274944] systemd[1]: Listening on static-echo@3.socket. machine # [ 13.287006] systemd[1]: Listening on static-echo@1.socket. machine # [ 13.299267] systemd[1]: Listening on static-echo@2.socket. machine # [ 13.318500] systemd[1]: Starting prebuilt flakelet service static... machine # [ 13.341387] systemd[1]: Reload requested from client PID 929 ('systemctl') (unit flakelet-boot.service)... machine # [ 13.346348] systemd[1]: Reloading... machine # [ 13.728517] dhcpcd[842]: eth0: rebinding lease of 10.0.2.15 machine # [ 13.756841] dhcpcd[842]: eth0: probing address 10.0.2.15/24 machine # [ 13.968517] dhcpcd[842]: eth0: soliciting an IPv6 router machine # [ 13.969557] dhcpcd[842]: eth0: Router Advertisement from fe80::2 machine # [ 13.970534] dhcpcd[842]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.971495] dhcpcd[842]: eth0: adding route to fec0::/64 machine # [ 13.972417] dhcpcd[842]: eth0: adding default route via fe80::2 machine # [ 14.025543] systemd[1]: Reloading finished in 674 ms. machine # [ 14.083325] systemd[1]: Started prebuilt flakelet service static. machine # [ 14.193596] systemd[1]: Reload requested from client PID 976 ('systemctl') (unit flakelet-boot.service)... machine # [ 14.196311] systemd[1]: Reloading... machine # [ 14.903178] systemd[1]: Reloading finished in 703 ms. machine # [ 15.022748] systemd[1]: Starting flakelet test service... machine # [ 15.030010] flakelet[559]: cli: units re-linked machine # [ 15.032171] flakelet[559]: static: units re-linked machine # [ 15.034218] flakelet[559]: web: units re-linked machine # [ 15.037133] systemd[1]: Finished Re-link flakelet services at boot. machine # [ 15.065735] systemd[1]: Starting Reconcile flakelet services with the host configuration... machine # [ 15.087011] systemd[1]: Started flakelet test service. machine # [ 15.133325] systemd[1]: Finished Reconcile flakelet services with the host configuration. machine # [ 15.140953] systemd[1]: Starting Update flakelet service static... machine # [ 15.148107] systemd[1]: Starting Update flakelet service web... machine # [ 15.214216] flakelet[1025]: static: using prebuilt artifact /nix/store/d4wsijyvrlqv20ah5mv211f8zv4gayxk-flakelet-static machine # [ 15.228465] flakelet[1025]: static: up to date machine # [ 15.233021] flakelet[1026]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix machine # [ 15.240971] systemd[1]: Finished Update flakelet service static. machine # [ 15.304281] systemd[1]: Started Nix Daemon. machine # [ 15.457647] nix-daemon[1035]: accepted connection from pid 1028, user flakelet machine # [ 15.590306] flakelet[1026]: web: evaluating /nix/store/qss4iprnbw9l96bz3ynr5lbkjavw9wms-flakelet-driver.nix machine # [ 15.709111] nix-daemon[1035]: accepted connection from pid 1059, user flakelet machine: (finished: waiting for unit web.service, in 16.56 seconds) machine: waiting for unit cli.service machine: (finished: waiting for unit cli.service, in 0.13 seconds) (finished: run the VM test script, in 92.18 seconds) test script finished in 92.24s cleanup kill QemuMachine (pid 78) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/ymm5c1kb7p2r6331bgcdxijayqhcbvqy-python3-3.14.6/bin/python3.14) (finished: cleanup, in 0.30 seconds)