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 # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 45) machine # Formatting '/build/vm-state-machine/tmp.tlj8ayqyfz', 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: c35653cb-96ed-4b4d-92b2-e1c9136b04ec 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/9zdaz362i3f0hlg11y8dx19vi1bf8n88-nixos-system-machine-test/init regInfo=/nix/store/4gk3c584g2dgzzw27q32wdzk81r0awrm-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4gk3c584g2dgzzw27q32wdzk81r0awrm-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.000032] arm-pv: using stolen time PV machine # [ 0.000520] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000759] Console: colour dummy device 80x25 machine # [ 0.000766] printk: legacy console [tty0] enabled machine # [ 0.000970] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000976] pid_max: default: 32768 minimum: 301 machine # [ 0.001077] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001274] landlock: Up and running. machine # [ 0.001277] Yama: becoming mindful. machine # [ 0.001806] LSM support for eBPF active machine # [ 0.001974] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.002033] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.003424] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.004133] rcu: Hierarchical SRCU implementation. machine # [ 0.004137] rcu: Max phase no-delay instances is 1000. machine # [ 0.004295] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level machine # [ 0.005367] fsl-mc MSI: its@8080000 domain created machine # [ 0.005463] EFI services will not be available. machine # [ 0.005626] smp: Bringing up secondary CPUs ... machine # [ 0.006347] Detected PIPT I-cache on CPU1 machine # [ 0.006466] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 machine # [ 0.006605] GICv3: CPU1: using allocated LPI pending table @0x0000000100b30000 machine # [ 0.006748] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40] machine # [ 0.007933] Detected PIPT I-cache on CPU2 machine # [ 0.008051] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 machine # [ 0.008189] GICv3: CPU2: using allocated LPI pending table @0x0000000100b40000 machine # [ 0.008330] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40] machine # [ 0.009497] Detected PIPT I-cache on CPU3 machine # [ 0.009629] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 machine # [ 0.009773] GICv3: CPU3: using allocated LPI pending table @0x0000000100b50000 machine # [ 0.009913] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40] machine # [ 0.010517] smp: Brought up 1 node, 4 CPUs machine # [ 0.010545] SMP: Total of 4 processors activated. machine # [ 0.010548] CPU: All CPU(s) started at EL1 machine # [ 0.010558] CPU features: detected: Branch Target Identification machine # [ 0.010561] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.010564] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.010567] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.010571] CPU features: detected: Common not Private translations machine # [ 0.010574] CPU features: detected: CRC32 instructions machine # [ 0.010576] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.010579] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.010582] CPU features: detected: Data independent timing control (DIT) machine # [ 0.010584] CPU features: detected: E0PD machine # [ 0.010587] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.010589] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.010593] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.010595] CPU features: detected: Fine Grained Traps machine # [ 0.010598] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.010602] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.010605] CPU features: detected: LSE atomic instructions machine # [ 0.010607] CPU features: detected: Privileged Access Never machine # [ 0.010609] CPU features: detected: PMUv3 machine # [ 0.010612] CPU features: detected: RAS Extension Support machine # [ 0.010614] CPU features: detected: RASv1p1 Extension Support machine # [ 0.010617] CPU features: detected: Random Number Generator machine # [ 0.010619] CPU features: detected: Speculation barrier (SB) machine # [ 0.010622] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.010624] CPU features: detected: TLB range maintenance instructions machine # [ 0.010628] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.010796] alternatives: applying system-wide alternatives machine # [ 0.013809] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.014040] Memory: 3910076K/4194304K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 233732K reserved, 32768K cma-reserved) machine # [ 0.015561] devtmpfs: initialized machine # [ 0.019427] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.019503] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). machine # [ 0.019716] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.019720] 0 pages in range for non-PLT usage machine # [ 0.019722] 508320 pages in range for PLT usage machine # [ 0.019815] pinctrl core: initialized pinctrl subsystem machine # [ 0.020667] DMI not present or invalid. machine # [ 0.024204] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.026794] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.027000] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.027251] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.027272] audit: initializing netlink subsys (disabled) machine # [ 0.027632] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 machine # [ 0.028069] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.028072] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.028077] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.028080] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.028082] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.028183] cpuidle: using governor ladder machine # [ 0.028209] cpuidle: using governor menu machine # [ 0.028434] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.028689] ASID allocator initialised with 65536 entries machine # [ 0.029903] Serial: AMBA PL011 UART driver machine # [ 0.035350] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.035680] printk: console [ttyAMA0] enabled machine # [ 0.041305] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.041331] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.041341] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.041349] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.041358] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.041366] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.041374] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.041381] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.049204] fbcon: Taking over console machine # [ 0.049215] ACPI: Interpreter disabled. machine # [ 0.050257] iommu: Default domain type: Translated machine # [ 0.050261] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.057052] SCSI subsystem initialized machine # [ 0.057414] usbcore: registered new interface driver usbfs machine # [ 0.057457] usbcore: registered new interface driver hub machine # [ 0.057473] usbcore: registered new device driver usb machine # [ 0.057724] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.057727] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.057735] PTP clock support registered machine # [ 0.057781] EDAC MC: Ver: 3.0.0 machine # [ 0.058185] scmi_core: SCMI protocol bus registered machine # [ 0.058698] FPGA manager framework machine # [ 0.059791] vgaarb: loaded machine # [ 0.061205] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.061637] VFS: Disk quotas dquot_6.6.0 machine # [ 0.061659] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.062028] netfs: FS-Cache loaded machine # [ 0.063084] pnp: PnP ACPI: disabled machine # [ 0.073293] NET: Registered PF_INET protocol family machine # [ 0.074775] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.135300] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.135356] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.135398] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) machine # [ 0.135597] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) machine # [ 0.136059] TCP: Hash tables configured (established 32768 bind 32768) machine # [ 0.136178] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) machine # [ 0.136404] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.136668] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.136898] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.137017] NET: Registered PF_XDP protocol family machine # [ 0.137038] PCI: CLS 0 bytes, default 64 machine # [ 0.137443] Trying to unpack rootfs image as initramfs... machine # [ 0.149869] kvm [1]: HYP mode not available machine # [ 0.227615] Initialise system trusted keyrings machine # [ 0.228018] workingset: timestamp_bits=42 max_order=20 bucket_order=0 machine # [ 0.228826] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.228970] 9p: Installing v9fs 9p2000 file system support machine # [ 0.243598] Key type asymmetric registered machine # [ 0.243604] Asymmetric key parser 'x509' registered machine # [ 0.243658] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.243874] io scheduler mq-deadline registered machine # [ 0.243892] io scheduler kyber registered machine # [ 0.257538] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.261336] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.262466] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.262522] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.262591] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.262610] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.262658] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.262708] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.263044] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.263064] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.263097] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.263108] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.263118] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.263444] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.265159] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.265958] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.266001] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.266085] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.266124] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.267771] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.268442] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.268491] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.268583] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.270218] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.270879] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.270916] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.270997] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.272659] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.273339] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.273377] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.273462] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.275057] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.275666] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.275704] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.275797] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.277392] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.278096] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.278132] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.278213] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.279864] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.280499] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.280537] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.280619] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.280671] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.282392] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.283077] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.283162] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.284813] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.285534] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.285620] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.287024] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.287716] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.288540] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.289319] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.289357] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.289443] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.291069] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.291765] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.291802] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.291896] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.293847] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.293865] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.293876] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.294033] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.294191] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.294347] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.294494] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.294639] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.294788] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.294935] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.295072] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.295233] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.295405] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.295553] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.295624] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.295679] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.295733] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.295789] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.295846] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.295914] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.295971] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.296029] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.296096] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.296154] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.296222] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.296276] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.296331] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.296385] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.296439] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.296493] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.296548] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.296603] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.296660] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.296727] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.296794] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.296810] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.296819] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.301084] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.336331] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.341914] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.347929] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.354156] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.360177] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.366786] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.372787] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.379420] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.384488] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.390989] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.397018] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.412466] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.416134] msm_serial: driver initialized machine # [ 0.416522] SuperH (H)SCI(F) driver initialized machine # [ 0.416638] STM32 USART driver initialized machine # [ 0.470628] loop: module loaded machine # [ 0.471098] virtio_blk virtio5: 4/0/0 default/read/poll queues machine # [ 0.474830] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.478934] megasas: 07.734.00.00-rc1 machine # [ 0.480400] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.485149] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.485242] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.488657] Using buffer write method machine # [ 0.488744] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.492637] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.492663] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.495093] Using buffer write method machine # [ 0.495121] Concatenating MTD devices: machine # [ 0.495125] (0): "0.flash" machine # [ 0.495128] (1): "0.flash" machine # [ 0.495132] into device "0.flash" machine # [ 0.601583] Freeing initrd memory: 27052K machine # [ 0.619661] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.628569] thunder_xcv, ver 1.0 machine # [ 0.628726] thunder_bgx, ver 1.0 machine # [ 0.628787] nicpf, ver 1.0 machine # [ 0.630675] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.630687] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.630766] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.630780] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.630861] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.630871] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.630933] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.630944] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.631346] sky2: driver version 1.30 machine # [ 0.636305] usbcore: registered new interface driver usb-storage machine # [ 0.636521] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.636599] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.636600] usbcore: registered new interface driver usbserial_generic machine # [ 0.636639] usbserial: USB Serial support registered for generic machine # [ 0.637345] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.638484] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.640819] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.640879] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T12:32:06 UTC (1788697926) machine # [ 0.641824] i2c_dev: i2c /dev entries driver machine # [ 0.649323] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.649981] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.649995] sdhci: Copyright(c) Pierre Ossman machine # [ 0.650628] hub 1-0:1.0: USB hub found machine # [ 0.650696] hub 1-0:1.0: 6 ports detected machine # [ 0.650782] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.651806] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.655865] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.656555] usbcore: registered new interface driver usbhid machine # [ 0.656564] usbhid: USB HID core driver machine # [ 0.689844] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.694892] drop_monitor: Initializing network drop monitor service machine # [ 0.695431] NET: Registered PF_INET6 protocol family machine # [ 0.697615] Segment Routing with IPv6 machine # [ 0.697686] In-situ OAM (IOAM) with IPv6 machine # [ 0.697897] NET: Registered PF_PACKET protocol family machine # [ 0.698226] 9pnet: Installing 9P2000 support machine # [ 0.704678] Key type dns_resolver registered machine # [ 0.724776] registered taskstats version 1 machine # [ 0.725288] Loading compiled-in X.509 certificates machine # [ 0.748785] Demotion targets for Node 0: null machine # [ 0.749025] Key type .fscrypt registered machine # [ 0.749032] Key type fscrypt-provisioning registered machine # [ 0.749304] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.749328] ima: Allocated hash algorithm: sha1 machine # [ 0.749392] ima: No architecture policies found machine # [ 0.750970] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 0.792426] clk: Disabling unused clocks machine # [ 0.792461] PM: genpd: Disabling unused power domains machine # [ 0.797547] Freeing unused kernel memory: 4736K machine # [ 0.797779] Run /init as init process machine # [ 0.839830] systemd[1]: Successfully made /usr/ read-only. machine # [ 0.901300] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.056027] 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.141976] 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.174742] 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.174813] systemd[1]: Detected virtualization qemu. machine # [ 1.174887] systemd[1]: Detected architecture arm64. machine # [ 1.174902] systemd[1]: Running in initrd. machine # [ 1.175792] systemd[1]: Initializing machine ID from random generator. machine # [ 1.176158] systemd[1]: Hostname set to . machine # [ 1.261321] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.415591] 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.416168] 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.439150] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.537577] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.575118] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.575603] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.575683] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.575728] systemd[1]: Reached target Path Units. machine # [ 1.575762] systemd[1]: Reached target Slice Units. machine # [ 1.575793] systemd[1]: Reached target Swaps. machine # [ 1.575828] systemd[1]: Reached target Timer Units. machine # [ 1.576157] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.576572] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.576890] systemd[1]: Listening on Journal Sockets. machine # [ 1.577190] systemd[1]: Listening on udev Control Socket. machine # [ 1.577420] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.577459] systemd[1]: Reached target Socket Units. machine # [ 1.580877] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.584328] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.584458] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.588150] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.613520] systemd[1]: Starting Journal Service... machine # [ 1.617565] systemd[1]: Starting Load Kernel Modules... machine # [ 1.617809] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.621409] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.630733] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.636854] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.640911] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.644527] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.653525] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.668778] systemd-journald[95]: Collecting audit messages is disabled. machine # [ 1.670645] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.671521] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.671727] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.671731] [drm] features: -context_init machine # [ 1.672025] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.673164] [drm] number of scanouts: 1 machine # [ 1.673182] [drm] number of cap sets: 0 machine # [ 1.685506] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.685539] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.694122] Console: switching machine # [ 1.694137] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.694153] to colour frame buffer device 160x50 machine # [ 1.702417] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.709089] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.721890] systemd[1]: Finished Load Kernel Modules. machine # [ 1.729511] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.745038] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.747632] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.748037] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.748675] systemd[1]: Reached target Local File Systems. machine # [ 1.753895] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.756862] systemd[1]: Started Journal Service. machine # [ 1.753011] systemd-modules-load[96]: Using 4 probe threads machine # [ 1.755615] systemd-modules-load[96]: Module 'virtio_balloon' is built in machine # [ 1.758169] systemd-modules-load[96]: Module 'virtio_console' is built in machine # [ 1.759520] systemd-modules-load[96]: Module 'virtio_rng' is built in machine # [ 1.762132] systemd-modules-load[96]: Inserted module 'dm_mod' machine # [ 1.763207] systemd-modules-load[96]: Inserted module 'virtio_gpu' machine # [ 1.764301] systemd[1]: Starting Create System Files and Directories... machine # [ 1.780634] systemd[1]: Finished Create System Files and Directories. machine # [ 1.783610] systemd-udevd[107]: Using default interface naming scheme 'v261'. machine # [ 1.799223] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.827470] systemd[1]: Starting Virtual Console Setup... machine # [ 1.897725] systemd-vconsole-setup[130]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.902771] systemd[1]: Finished Virtual Console Setup. machine # [ 2.342186] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.344777] systemd[1]: Reached target System Initialization. machine # [ 2.347363] systemd[1]: Reached target Basic System. machine # [ 2.520150] (udev-worker)[141]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.543320] (udev-worker)[129]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.547716] (udev-worker)[129]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.602221] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.603727] systemd[1]: Reached target Initrd Root Device. machine # [ 2.605451] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.642624] systemd-fsck[152]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.649826] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.652042] systemd[1]: Mounting /sysroot... machine # [ 2.689027] EXT4-fs (vda): mounted filesystem c35653cb-96ed-4b4d-92b2-e1c9136b04ec r/w with ordered data mode. Quota mode: none. machine # [ 2.688228] systemd[1]: Mounted /sysroot. machine # [ 2.689100] systemd[1]: Reached target Initrd Root File System. machine # [ 2.690124] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.691324] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.714665] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.718569] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 2.720831] systemd-sysroot-fstab-check[160]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.723039] systemd[1]: Reload requested from client PID 160 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.724889] systemd[1]: Reloading... machine # [ 2.814455] systemd[1]: Reloading finished in 95 ms. machine # [ 2.870387] systemd-sysroot-fstab-check[160]: Requesting initrd-fs.target/start/replace... machine # [ 2.871674] systemd-sysroot-fstab-check[160]: Requesting swap.target/start/replace... machine # [ 2.874402] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 2.878818] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.880133] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.888676] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.891722] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.895964] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 2.898590] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 2.900152] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.901226] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.109287] (udev-worker)[134]: mtd0ro: Failed to find and pin callout binary "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/mtd_probe": No such file or directory machine # [ 3.112250] (udev-worker)[134]: 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.119397] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.120787] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.121586] systemd[1]: Stopping Virtual Console Setup... machine # [ 3.122352] systemd[1]: Starting Virtual Console Setup... machine # [ 3.149000] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.150107] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.150869] systemd[1]: Starting Virtual Console Setup... machine # [ 3.176393] systemd-vconsole-setup[187]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 3.178954] systemd[1]: Finished Virtual Console Setup. machine # [ 3.624512] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.631545] systemd[1]: Mounting /sysroot/run... machine # [ 3.634625] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.648608] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.671973] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.674841] systemd[1]: Mounted /sysroot/run. machine # [ 3.676965] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.690243] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 4.623231] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.702988] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.705480] systemd[1]: Reached target Initrd File Systems. machine # [ 4.707915] systemd[1]: Starting Find NixOS closure... machine # [ 4.711012] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.761534] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.765328] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.780459] systemd[1]: Finished Find NixOS closure. machine # [ 4.782834] systemd[1]: Reached target Initrd Default Target. machine # [ 4.785420] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.840401] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.843012] systemd[1]: Stopped target Basic System. machine # [ 4.845295] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.847640] systemd[1]: Stopped target Path Units. machine # [ 4.849905] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.853230] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.856540] systemd[1]: Stopped target Slice Units. machine # [ 4.858684] systemd[1]: Stopped target Socket Units. machine # [ 4.862675] systemd[1]: Stopped target System Initialization. machine # [ 4.867039] systemd[1]: Stopped target Swaps. machine # [ 4.869071] systemd[1]: Stopped target Timer Units. machine # [ 4.871193] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.879593] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.882423] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.886683] systemd[1]: Stopped Find NixOS closure. machine # [ 4.888879] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 4.890970] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.893448] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.895641] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.897632] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.899947] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.901667] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.904255] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.906822] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.909426] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.911491] systemd[1]: Stopped target Local File Systems. machine # [ 4.913511] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.915701] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.918166] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.919972] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.922302] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.924532] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.926089] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.927973] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.929817] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.931709] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.933364] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 4.935161] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 4.936721] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.938299] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.939961] systemd[1]: systemd-udevd.service: Consumed 2.229s CPU time over 3.163s wall clock time, 31.5M memory peak. machine # [ 4.942299] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. machine # [ 4.943916] systemd[1]: Closed udev Control Socket. machine # [ 4.945370] systemd[1]: Starting Cleanup udev Database... machine # [ 4.946565] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.948200] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.949541] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.951170] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.952663] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.954041] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.033295] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.037734] systemd[1]: Finished Cleanup udev Database. machine # [ 5.040259] systemd[1]: Reached target Switch Root. machine # [ 5.042450] systemd[1]: Starting NixOS Activation... machine # [ 5.318008] initrd-nixos-activation-start[222]: booting system configuration /nix/store/9zdaz362i3f0hlg11y8dx19vi1bf8n88-nixos-system-machine-test machine # [ 5.433345] initrd-nixos-activation-start[222]: running activation script... machine # [ 5.987784] initrd-nixos-activation-start[245]: setting up /etc... machine # [ 6.318761] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 6.321436] systemd[1]: Finished NixOS Activation. machine # [ 6.323082] systemd[1]: Starting Switch Root... machine # [ 6.358360] systemd[1]: Switching root. machine # [ 6.487867] systemd-journald[95]: Received SIGTERM from PID 1 (systemd). machine # [ 7.216021] 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 # [ 7.216157] systemd[1]: Detected virtualization qemu. machine # [ 7.216245] systemd[1]: Detected architecture arm64. machine # [ 7.216410] systemd[1]: Detected first boot. machine # [ 7.241482] systemd[1]: Initializing machine ID from random generator. machine # [ 7.469148] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.630562] systemd[1]: Applying preset policy. machine # [ 8.167669] systemd[1]: Populated /etc with preset unit settings. machine # [ 8.726080] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 8.726729] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.728801] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 8.735129] systemd[1]: Created slice Slice /system/getty. machine # [ 8.738936] systemd[1]: Created slice User and Session Slice. machine # [ 8.740438] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 8.742009] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 8.743231] systemd[1]: Expecting device /dev/hvc0... machine # [ 8.744356] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 8.745521] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 8.746693] systemd[1]: Reached target flakelet contract providers ready. machine # [ 8.747835] systemd[1]: Stopped target initrd-fs.target. machine # [ 8.748968] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 8.750145] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 8.751329] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 8.752480] systemd[1]: Reached target Path Units. machine # [ 8.753634] systemd[1]: Reached target Remote File Systems. machine # [ 8.754753] systemd[1]: Reached target Slice Units. machine # [ 8.755873] systemd[1]: Reached target Swaps. machine # [ 8.765709] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 8.773186] systemd[1]: Listening on Process Core Dump Socket. machine # [ 8.777869] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 8.783282] systemd[1]: Listening on Factory Reset Management. machine # [ 8.784462] systemd[1]: Listening on Hostname Service Socket. machine # [ 8.793656] systemd[1]: Starting Journal Log Access Socket... machine # [ 8.795929] systemd[1]: Listening on Journal Audit Socket. machine # [ 8.801433] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 8.802796] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 8.803631] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.804382] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 8.816349] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 8.817347] systemd[1]: Listening on udev Control Socket. machine # [ 8.818255] systemd[1]: Listening on udev Varlink Socket. machine # [ 8.831544] systemd[1]: Mounting Huge Pages File System... machine # [ 8.838887] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 8.846847] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.854521] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.863813] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.876688] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 8.877949] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.930920] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.932296] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.933779] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.950162] systemd[1]: Starting Load Kernel Module fuse... machine # [ 8.954625] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.989044] systemd[1]: Starting Journal Service... machine # [ 8.997707] systemd[1]: Starting Load Kernel Modules... machine # [ 9.009398] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 9.018987] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 9.020253] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.028369] systemd[1]: Starting Coldplug All udev Devices... machine # [ 9.034840] systemd[1]: Listening on Journal Log Access Socket. machine # [ 9.038067] systemd[1]: Mounted Huge Pages File System. machine # [ 9.039090] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 9.040139] systemd[1]: Mounted Kernel Debug File System. machine # [ 9.041133] systemd[1]: Mounted Kernel Trace File System. machine # [ 9.042657] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 9.044304] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 9.047646] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 9.060376] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 9.064918] systemd[1]: Mounted Kernel Configuration File System. machine # [ 9.101741] fuse: init (API version 7.45) machine # [ 9.109033] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 9.110260] systemd[1]: Finished Load Kernel Module fuse. machine # [ 9.122933] systemd[1]: Mounting FUSE Control File System... machine # [ 9.128756] EXT4-fs (vda): re-mounted c35653cb-96ed-4b4d-92b2-e1c9136b04ec. machine # [ 9.139097] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 9.142404] systemd-journald[316]: Collecting audit messages is enabled. machine # [ 9.149758] systemd[1]: Finished Load Kernel Modules. machine # [ 9.154322] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 9.159834] systemd[1]: Queued start job for default target Multi-User System. machine # [ 9.164871] systemd[1]: Starting Firewall... machine # [ 9.165856] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 9.165438] systemd[1]: systemd-journald.service: Deactivated successfully.[ 9.179388] systemd[1]: Starting Load/Save OS Random Seed... machine # machine # [ 9.178518] systemd-modules-load[317]: Using 2 probe threads machine # [ 9.182682] systemd-modules-load[317]: Module 'atkbd' is built in machine # [ 9.185361] systemd-modules-load[317]: Module 'loop' is built in[ 9.191391] systemd[1]: Starting Apply Kernel Variables... machine # machine # [ 9.199427] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.202631] systemd[1]: Started Journal Service. machine # [ 9.195941] systemd-oomd[318]: No swap; memory pressure usage will be degraded machine # [ 9.209834] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 9.223290] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 9.241687] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 9.246211] systemd[1]: Mounted FUSE Control File System. machine # [ 9.252297] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 9.265430] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 9.268847] systemd[1]: Reached target First Boot Complete. machine # [ 9.287018] systemd-journald[316]: Received client request to flush runtime journal. machine # [ 9.315786] systemd[1]: Finished Apply Kernel Variables. machine # [ 9.319398] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 9.337697] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 9.339593] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 9.343807] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 9.448582] systemd-udevd[353]: Using default interface naming scheme 'v261'. machine # [ 9.562736] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 9.725633] systemd[1]: Mounting /run/wrappers... machine # [ 9.776413] systemd[1]: Mounted /run/wrappers. machine # [ 9.779147] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.781887] systemd[1]: Reached target Local File Systems. machine # [ 9.785718] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.792910] systemd[1]: Starting register-nix-paths.service... machine # [ 9.803844] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.810547] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.816533] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.826530] systemd[1]: Starting Create System Files and Directories... machine # [ 9.833874] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 9.859548] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.868592] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.897339] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 9.927171] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 9.936185] systemd[1]: Finished Create System Files and Directories. machine # [ 9.943619] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.952774] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 10.002771] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 10.028443] (udev-worker)[385]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.038335] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 10.042204] (udev-worker)[380]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 10.050255] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.054149] systemd[1]: Starting Update is Completed... machine # [ 10.106247] systemd[1]: Finished Update is Completed. machine # [ 10.113928] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.118011] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 10.123030] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 10.127672] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 10.133027] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 10.136829] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 10.139483] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 10.183066] mousedev: PS/2 mouse device common for all mice machine # [ 10.404031] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 10.407529] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 10.588541] systemd[1]: Finished register-nix-paths.service. machine # [ 10.589573] systemd[1]: Reached target System Initialization. machine # [ 10.592352] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.595387] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.600357] systemd[1]: Reached target Timer Units. machine # [ 10.601162] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.602166] systemd[1]: Listening on Nix Daemon Socket. machine # [ 10.604446] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.605722] systemd[1]: Reached target Socket Units. machine # [ 10.606463] systemd[1]: Reached target Basic System. machine # [ 10.607430] systemd[1]: Started backdoor.service. machine # [ 10.617696] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 10.619001] systemd[1]: Starting Re-link flakelet services at boot... machine # [ 10.625250] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.635916] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.650078] systemd[1]: Starting Post-Boot Actions... machine # [ 10.653690] systemd[1]: Started Reset console on configuration changes. machine # [ 10.664497] systemd[1]: Starting resolvconf update... machine # [ 10.685871] systemd[1]: Finished Firewall. machine # [ 10.691116] systemd[1]: Starting D-Bus System Message Bus... machine # connecting to host... machine # [ 10.719133] systemd[1]: Finished Re-link flakelet services at boot. machine # [ 10.722068] systemd[1]: Finished Post-Boot Actions. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 10.742172] nsncd[562]: Sep 06 12:32:16.605 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.750688] systemd[1]: Starting Reconcile flakelet services with the host configuration... machine: connected to guest root shell machine # [ 10.754747] systemd[1]: Started Name Service Cache Daemon (nsncd). machine: (connecting took 11.21 seconds) machine: (finished: waiting for the VM to finish booting, in 11.65 seconds) machine # [ 10.757772] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.760911] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.783546] systemd[1]: Starting User Login Management... machine # [ 10.788058] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 10.812416] systemd[1]: Starting Virtual Console Setup... machine # [ 10.822819] dbus-broker-launch[571]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.826431] systemd[1]: Finished Reconcile flakelet services with the host configuration. machine # [ 10.878255] systemd-logind[600]: New seat seat0. machine # [ 10.884861] systemd-logind[600]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 10.890277] systemd-logind[600]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 10.895626] systemd-logind[600]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.902828] systemd[1]: Started User Login Management. machine # [ 10.906440] dbus-broker-launch[571]: NSS returned no entry for 'systemd-timesync' machine # [ 10.908850] dbus-broker-launch[571]: Invalid user-name in /nix/store/zg5jbfcpdnf3w6rp73i6nhi6zcn6x83p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.917882] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.919872] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.924061] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.925827] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.926976] systemd[1]: Starting linger-users.service... machine # [ 10.928100] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.929361] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.931045] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 10.944441] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.949392] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.975025] dbus-broker-launch[571]: Ready machine # [ 10.975709] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.976692] systemd[1]: Finished linger-users.service. machine # [ 10.988791] nsncd[658]: Sep 06 12:32:16.852 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.993541] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.995432] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.997437] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.999360] systemd[1]: Finished resolvconf update. machine # [ 11.001101] systemd[1]: Reached target Preparation for Network. machine # [ 11.003011] systemd[1]: Starting DHCP Client... machine # [ 11.006427] systemd[1]: Starting Address configuration of eth1... machine # [ 11.010419] systemd[1]: Starting Extra networking commands.... machine # [ 11.097895] network-addresses-eth1-start[681]: adding address 192.168.1.1/24... done machine # [ 11.109081] network-addresses-eth1-start[681]: adding address 2001:db8:1::1/64... done machine # [ 11.124467] systemd[1]: Finished Address configuration of eth1. machine # [ 11.134701] dhcpcd[690]: dhcpcd-10.3.2 starting machine # [ 11.144449] dhcpcd[736]: dev: loaded udev machine # [ 11.168438] systemd[1]: Finished Extra networking commands.. machine # [ 11.169439] systemd[1]: Reached target Network. machine # [ 11.172443] systemd[1]: Starting Permit User Sessions... machine # [ 11.179681] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.177256] systemd[1]: Starting Update flakelet service static...[ 11.180121] 8021q: adding VLAN 0 to HW filter on device eth1 machine # machine # [ 11.186121] systemd[1]: Starting Update flakelet service web... machine # [ 11.207504] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 11.211033] systemd[1]: Finished Permit User Sessions. machine # [ 11.214523] systemd[1]: Started Getty on tty1. machine # [ 11.216077] systemd[1]: Reached target Login Prompts. machine # [ 11.228621] flakelet[752]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix machine # [ 11.234738] flakelet[750]: static: using prebuilt artifact /nix/store/5n6bggiblbxz1iv4lknj72qidd7kcblf-flakelet-static[ 11.244348] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # machine # [ 11.245952] flakelet[750]: static: activating generation 1 machine # [ 11.257498] systemd[1]: Reload requested from client PID 757 ('systemctl') (unit flakelet-static.service)... machine # [ 11.264923] systemd[1]: Reloading... machine # [ 11.274065] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.274577] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.278433] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.278788] cfg80211: failed to load regulatory.db machine # [ 11.309147] systemd-vconsole-setup[612]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 11.356734] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 11.353102] dhcpcd[736]: eth0: waiting for carrier machine # [ 11.354190] dhcpcd[736]: eth0: carrier acquired machine # [ 11.364128] dhcpcd[736]: DUID 00:01:00:01:32:30:19:d1:52:54:00:12:34:56 machine # [ 11.365475] dhcpcd[736]: eth0: IAID 00:12:34:56 machine # [ 11.366140] dhcpcd[736]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 11.873949] systemd[1]: Reloading finished in 613 ms. machine # [ 11.947253] systemd[1]: Finished Virtual Console Setup. machine # [ 11.975581] systemd[1]: Started Nix Daemon. machine # [ 12.180202] systemd[1]: Reload requested from client PID 821 ('systemctl') (unit flakelet-static.service)... machine # [ 12.186480] systemd[1]: Reloading... machine # [ 12.467867] dhcpcd[736]: eth0: soliciting a DHCP lease machine # [ 12.481017] dhcpcd[736]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.504385] dhcpcd[736]: eth0: probing address 10.0.2.15/24 machine # [ 12.859100] systemd[1]: Reloading finished in 678 ms. machine # [ 12.937305] nix-daemon[813]: accepted connection from pid 755, user flakelet machine # [ 12.952083] systemd[1]: Created slice Slice /system/static-echo. machine # [ 12.955436] systemd[1]: Listening on static-echo@1.socket. machine # [ 12.963975] systemd[1]: Listening on static-echo@2.socket. machine # [ 12.972520] systemd[1]: Starting prebuilt flakelet service static... machine # [ 12.991605] systemd[1]: Listening on static-echo@3.socket. machine # [ 13.013345] systemd[1]: Started prebuilt flakelet service static. machine # [ 13.055952] flakelet[750]: static: updated to generation 1 machine # [ 13.058630] systemd[1]: Finished Update flakelet service static. machine # [ 13.127948] flakelet[752]: web: evaluating /nix/store/ilx4whk0c9p26mms3p29h4b29a049jpq-flakelet-driver.nix machine # [ 13.234210] nix-daemon[813]: accepted connection from pid 888, user flakelet machine # [ 13.616442] dhcpcd[736]: eth0: soliciting an IPv6 router machine # [ 13.619960] dhcpcd[736]: eth0: Router Advertisement from fe80::2 machine # [ 13.623913] dhcpcd[736]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.627915] dhcpcd[736]: eth0: adding route to fec0::/64 machine # [ 13.631158] dhcpcd[736]: eth0: adding default route via fe80::2 machine # [ 16.772365] dhcpcd[736]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 16.773923] dhcpcd[736]: eth0: adding route to 10.0.2.0/24 machine # [ 16.780247] dhcpcd[736]: eth0: adding default route via 10.0.2.2 machine # [ 17.136622] systemd[1]: Started DHCP Client. machine # [ 22.873096] flakelet[752]: web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv machine # [ 24.347400] nix-daemon[813]: accepted connection from pid 1140, user flakelet machine # [ 24.397939] flakelet[752]: web: activating generation 1 machine # [ 24.411763] systemd[1]: Reload requested from client PID 1156 ('systemctl') (unit flakelet-web.service)... machine # [ 24.417348] systemd[1]: Reloading... machine # [ 25.043079] systemd[1]: Reloading finished in 629 ms. machine # [ 25.160439] systemd[1]: Reload requested from client PID 1195 ('systemctl') (unit flakelet-web.service)... machine # [ 25.171159] systemd[1]: Reloading... machine # [ 25.762725] systemd[1]: Reloading finished in 598 ms. machine # [ 25.838650] systemd[1]: Starting flakelet test service... machine # [ 25.902694] systemd[1]: Started flakelet test service. machine # [ 25.922656] flakelet[752]: web: updated to generation 1 machine # [ 25.927142] systemd[1]: Finished Update flakelet service web. machine # [ 25.929696] systemd[1]: Reached target flakelet managed services. machine # [ 25.932294] systemd[1]: Reached target Multi-User System. machine # [ 25.934618] systemd[1]: Startup finished in 805ms (kernel) + 5.828s (initrd) + 19.295s (userspace) = 25.929s. machine: (finished: waiting for unit multi-user.target, in 27.18 seconds) machine: must succeed: systemctl start flakelet-web.service machine: (finished: must succeed: systemctl start flakelet-web.service, in 0.07 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.13 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.13 seconds) machine: must succeed: flakelet status --json web | /nix/store/l18as28b4wwqds62yqci9l8xhhdhf78l-jq-1.8.2-bin/bin/jq -c '.[0].changed.by' machine: (finished: must succeed: flakelet status --json web | /nix/store/l18as28b4wwqds62yqci9l8xhhdhf78l-jq-1.8.2-bin/bin/jq -c '.[0].changed.by', in 0.13 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.04 seconds) machine: must succeed: systemctl start flakelet-static.service machine: (finished: must succeed: systemctl start flakelet-static.service, in 0.07 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.05 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 # [ 27.141646] systemd[1]: Starting static-echo@2.service... machine # [ 27.197886] 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/4v71dw5b68ckmbr32244pnbvlvwl6l14-flakelet-cli machine # cli: using prebuilt artifact /nix/store/4v71dw5b68ckmbr32244pnbvlvwl6l14-flakelet-cli machine # cli: activating generation 1 machine # [ 28.396251] systemd[1]: Reload requested from client PID 1312 ('systemctl') (unit backdoor.service)... machine # [ 28.399966] systemd[1]: Reloading... machine # [ 29.112605] systemd[1]: Reloading finished in 709 ms. machine # [ 29.371721] systemd[1]: Reload requested from client PID 1358 ('systemctl') (unit backdoor.service)... machine # [ 29.376820] systemd[1]: Reloading... machine # [ 30.067425] systemd[1]: Reloading finished in 687 ms. machine # [ 30.197640] systemd[1]: Created slice Slice /system/cli-echo. machine # [ 30.200565] systemd[1]: Listening on cli-echo@1.socket. machine # [ 30.209849] systemd[1]: Listening on cli-echo@2.socket. machine # [ 30.226060] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 30.233649] systemd[1]: Listening on cli-echo@3.socket. machine # [ 30.282521] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must succeed: flakelet activate cli /nix/store/4v71dw5b68ckmbr32244pnbvlvwl6l14-flakelet-cli, in 2.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 status --json | grep -q 'prebuilt:cli' machine: (finished: must succeed: flakelet status --json | grep -q 'prebuilt:cli', in 0.18 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.417789] systemd[1]: cli-echo@3.socket: Deactivated successfully. machine # [ 31.420290] systemd[1]: Closed cli-echo@3.socket. machine # [ 31.428742] systemd[1]: cli-echo@1.socket: Deactivated successfully. machine # [ 31.433319] systemd[1]: Closed cli-echo@1.socket. machine # [ 31.435711] systemd[1]: cli-echo@2.socket: Deactivated successfully. machine # [ 31.438775] systemd[1]: Closed cli-echo@2.socket. machine # [ 31.488659] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 31.491592] systemd[1]: cli.service: Deactivated successfully. machine # [ 31.494175] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 31.689259] systemd[1]: Reload requested from client PID 1435 ('systemctl') (unit backdoor.service)... machine # [ 31.694385] systemd[1]: Reloading... machine # [ 32.352424] systemd[1]: Reloading finished in 660 ms. machine # [ 32.479129] systemd[1]: Reload requested from client PID 1477 ('systemctl') (unit backdoor.service)... machine # [ 32.484301] systemd[1]: Reloading... machine # [ 33.153844] systemd[1]: Reloading finished in 672 ms. machine # [ 33.242065] systemd[1]: Reload requested from client PID 1515 ('systemctl') (unit backdoor.service)... machine # [ 33.247274] systemd[1]: Reloading... machine # [ 33.943816] systemd[1]: Reloading finished in 697 ms. machine # [ 34.052502] systemd[1]: Reload requested from client PID 1554 ('systemctl') (unit backdoor.service)... machine # [ 34.057663] systemd[1]: Reloading... machine # [ 34.478521] 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 # [ 34.702637] systemd[1]: Reloading finished in 648 ms. machine # [ 34.783123] systemd[1]: Starting cli.service... machine # [ 34.823681] (nonexistent)[1594]: cli.service: Unable to locate executable '/nonexistent': No such file or directory machine # [ 34.827732] (nonexistent)[1594]: cli.service: Failed at step EXEC spawning /nonexistent: No such file or directory machine # [ 34.831755] systemd[1]: cli.service: Main process exited, code=exited, status=203/EXEC machine # [ 34.833880] systemd[1]: cli.service: Failed with result 'exit-code'. machine # [ 34.835722] systemd[1]: Failed to start cli.service. machine # [ 34.950572] systemd[1]: Reload requested from client PID 1602 ('systemctl') (unit backdoor.service)... machine # [ 34.955635] systemd[1]: Reloading... machine # [ 35.635461] systemd[1]: Reloading finished in 682 ms. machine # [ 35.720719] systemd[1]: Reload requested from client PID 1641 ('systemctl') (unit backdoor.service)... machine # [ 35.725815] systemd[1]: Reloading... machine # [ 36.372941] systemd[1]: Reloading finished in 648 ms. machine # [ 36.500240] systemd[1]: Reload requested from client PID 1679 ('systemctl') (unit backdoor.service)... machine # [ 36.505549] systemd[1]: Reloading... machine # [ 37.189208] systemd[1]: Reloading finished in 686 ms. machine # [ 37.447742] systemd[1]: Reload requested from client PID 1725 ('systemctl') (unit backdoor.service)... machine # [ 37.452643] systemd[1]: Reloading... machine # [ 38.131650] systemd[1]: Reloading finished in 682 ms. machine # [ 38.220466] systemd[1]: Listening on cli-echo@1.socket. machine # [ 38.232855] systemd[1]: Listening on cli-echo@2.socket. machine # [ 38.248887] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 38.254287] systemd[1]: Listening on cli-echo@3.socket. machine # [ 38.297123] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken, in 7.74 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.05 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.03 seconds) machine: waiting for success: systemctl is-active cli-echo@1.service machine # [ 38.491059] systemd[1]: Starting cli-echo@1.service... machine # [ 38.547559] systemd[1]: Started cli-echo@1.service. machine: (finished: waiting for success: systemctl is-active cli-echo@1.service, in 1.11 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.20 seconds) machine: must succeed: systemctl show -P MainPID cli.service machine: (finished: must succeed: systemctl show -P MainPID cli.service, in 0.12 seconds) machine: must succeed: flakelet activate cli /nix/store/z83mq6m1c41gvqqhz4dbvdjb2c8sxigg-flakelet-cli2 | grep -q 'generation 2' machine # cli: using prebuilt artifact /nix/store/z83mq6m1c41gvqqhz4dbvdjb2c8sxigg-flakelet-cli2 machine # cli: activating generation 2 machine # [ 40.669297] systemd[1]: cli-echo@3.socket: Deactivated successfully. machine # [ 40.669791] systemd[1]: Closed cli-echo@3.socket. machine # [ 40.677254] systemd[1]: cli-echo@2.socket: Deactivated successfully. machine # [ 40.680448] systemd[1]: Closed cli-echo@2.socket. machine # [ 40.714575] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 40.717913] systemd[1]: cli.service: Deactivated successfully. machine # [ 40.720838] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 40.871547] systemd[1]: Reload requested from client PID 1816 ('systemctl') (unit backdoor.service)... machine # [ 40.876882] systemd[1]: Reloading... machine # [ 41.538291] systemd[1]: Reloading finished in 664 ms. machine # [ 41.685860] systemd[1]: Reload requested from client PID 1859 ('systemctl') (unit backdoor.service)... machine # [ 41.691035] systemd[1]: Reloading... machine # [ 42.359543] systemd[1]: Reloading finished in 671 ms. machine # [ 42.446481] systemd[1]: Reload requested from client PID 1898 ('systemctl') (unit backdoor.service)... machine # [ 42.451249] systemd[1]: Reloading... machine # [ 43.221655] systemd[1]: Reloading finished in 766 ms. machine # [ 43.506365] systemd[1]: Reload requested from client PID 1946 ('systemctl') (unit backdoor.service)... machine # [ 43.511657] systemd[1]: Reloading... machine # [ 44.226589] systemd[1]: Reloading finished in 716 ms. machine # [ 44.309373] systemd[1]: Listening on cli-echo@2.socket. machine # [ 44.317361] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 44.321354] systemd[1]: Listening on cli-echo@3.socket. machine # [ 44.360841] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must succeed: flakelet activate cli /nix/store/z83mq6m1c41gvqqhz4dbvdjb2c8sxigg-flakelet-cli2 | grep -q 'generation 2', in 4.50 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.18 seconds) machine: must succeed: systemctl show -P MainPID cli.service machine: (finished: must succeed: systemctl show -P MainPID cli.service, in 0.12 seconds) machine: must succeed: systemctl stop cli-echo@1.service machine # [ 44.775555] systemd[1]: Stopping cli-echo@1.service... machine # [ 44.780112] systemd[1]: cli-echo@1.service: Deactivated successfully. machine # [ 44.781406] systemd[1]: Stopped cli-echo@1.service. machine # [ 44.802123] systemd[1]: Starting cli-echo@1.service... machine # Stopping 'cli-echo@1.service', but its triggering units are still active: machine # cli-echo@1.socket machine: (finished: must succeed: systemctl stop cli-echo@1.service, in 0.08 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: must succeed: flakelet rollback cli | grep -q 'generation 1' machine # [ 44.853944] systemd[1]: Started cli-echo@1.service. machine # [ 45.527643] systemd[1]: cli-echo@3.socket: Deactivated successfully. machine # [ 45.527978] systemd[1]: Closed cli-echo@3.socket. machine # [ 45.540030] systemd[1]: cli-echo@2.socket: Deactivated successfully. machine # [ 45.541870] systemd[1]: Closed cli-echo@2.socket. machine # [ 45.594951] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 45.598281] systemd[1]: cli.service: Deactivated successfully. machine # [ 45.601066] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 45.805116] systemd[1]: Reload requested from client PID 2028 ('systemctl') (unit backdoor.service)... machine # [ 45.810278] systemd[1]: Reloading... machine # [ 46.487560] systemd[1]: Reloading finished in 680 ms. machine # [ 46.621800] systemd[1]: Reload requested from client PID 2071 ('systemctl') (unit backdoor.service)... machine # [ 46.624139] systemd[1]: Reloading... machine # [ 47.303805] systemd[1]: Reloading finished in 676 ms. machine # [ 47.386421] systemd[1]: Reload requested from client PID 2110 ('systemctl') (unit backdoor.service)... machine # [ 47.391838] systemd[1]: Reloading... machine # [ 48.090780] systemd[1]: Reloading finished in 702 ms. machine # [ 48.343046] systemd[1]: Reload requested from client PID 2157 ('systemctl') (unit backdoor.service)... machine # [ 48.347114] systemd[1]: Reloading... machine # [ 49.019496] systemd[1]: Reloading finished in 669 ms. machine # [ 49.119451] systemd[1]: Listening on cli-echo@2.socket. machine # [ 49.137198] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 49.143954] systemd[1]: Listening on cli-echo@3.socket. machine # [ 49.190743] systemd[1]: Started prebuilt flakelet service cli. machine: (finished: must succeed: flakelet rollback cli | grep -q 'generation 1', in 4.35 seconds) machine: must succeed: flakelet status --json cli | /nix/store/l18as28b4wwqds62yqci9l8xhhdhf78l-jq-1.8.2-bin/bin/jq -c '.[0].changed' machine: (finished: must succeed: flakelet status --json cli | /nix/store/l18as28b4wwqds62yqci9l8xhhdhf78l-jq-1.8.2-bin/bin/jq -c '.[0].changed', in 0.18 seconds) machine: must succeed: systemctl is-active cli-echo@3.socket machine: (finished: must succeed: systemctl is-active cli-echo@3.socket, in 0.05 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 lock cli | grep -q 'prebuilt:cli' machine: (finished: must succeed: flakelet lock cli | grep -q 'prebuilt:cli', in 0.06 seconds) machine: must succeed: flakelet unlock cli machine: (finished: must succeed: flakelet unlock cli, in 0.06 seconds) machine: must succeed: touch /tmp/failjob machine: (finished: must succeed: touch /tmp/failjob, in 0.05 seconds) machine: must fail: systemctl start web-job.service machine # [ 49.699033] systemd[1]: Starting web-job.service... machine # [ 49.775744] systemd[1]: web-job.service: Main process exited, code=exited, status=1/FAILURE machine # [ 49.779231] 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 # [ 49.782223] 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.15 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: echo '{"kind":"external","agent":"ci","id":"j1","caller":"me"}' > /tmp/by.json machine: (finished: must succeed: echo '{"kind":"external","agent":"ci","id":"j1","caller":"me"}' > /tmp/by.json, in 0.03 seconds) machine: must succeed: flakelet update web --force --no-refresh --by-file /tmp/by.json | grep -q 'updated to generation' machine # web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix machine # [ 50.028934] nix-daemon[813]: accepted connection from pid 2251, user flakelet machine # web: evaluating /nix/store/ilx4whk0c9p26mms3p29h4b29a049jpq-flakelet-driver.nix machine # [ 50.267653] nix-daemon[813]: accepted connection from pid 2271, user flakelet machine # web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv machine # [ 56.105195] nix-daemon[813]: accepted connection from pid 2280, user flakelet machine # web: activating generation 2 machine # [ 56.362662] systemd[1]: Stopping flakelet test service... machine # [ 56.366721] systemd[1]: web.service: Deactivated successfully. machine # [ 56.369504] systemd[1]: Stopped flakelet test service. machine # [ 56.440882] systemd[1]: Reload requested from client PID 2293 ('systemctl') (unit backdoor.service)... machine # [ 56.446130] systemd[1]: Reloading... machine # [ 57.215932] systemd[1]: Reloading finished in 772 ms. machine # [ 57.339486] systemd[1]: Reload requested from client PID 2336 ('systemctl') (unit backdoor.service)... machine # [ 57.344375] systemd[1]: Reloading... machine # [ 58.064369] systemd[1]: Reloading finished in 715 ms. machine # [ 58.154167] systemd[1]: Reload requested from client PID 2378 ('systemctl') (unit backdoor.service)... machine # [ 58.160995] systemd[1]: Reloading... machine # [ 58.932869] systemd[1]: Reloading finished in 774 ms. machine # [ 59.066664] systemd[1]: Reload requested from client PID 2421 ('systemctl') (unit backdoor.service)... machine # [ 59.071902] systemd[1]: Reloading... machine # [ 59.785323] systemd[1]: Reloading finished in 716 ms. machine # [ 59.874503] systemd[1]: Starting flakelet test service... machine # [ 59.915989] systemd[1]: Started flakelet test service. machine: (finished: must succeed: flakelet update web --force --no-refresh --by-file /tmp/by.json | grep -q 'updated to generation', in 10.04 seconds) machine: must succeed: flakelet status --json web | /nix/store/l18as28b4wwqds62yqci9l8xhhdhf78l-jq-1.8.2-bin/bin/jq -c '.[0].changed.by' machine: (finished: must succeed: flakelet status --json web | /nix/store/l18as28b4wwqds62yqci9l8xhhdhf78l-jq-1.8.2-bin/bin/jq -c '.[0].changed.by', in 0.19 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 # [ 60.392956] nix-daemon[813]: accepted connection from pid 2490, user flakelet machine # [ 60.687253] nix-daemon[813]: accepted connection from pid 2510, user flakelet machine: (finished: must succeed: flakelet check --build --no-refresh --gc-roots-dir /tmp/roots | grep -q '^web: built /nix/store/', in 5.58 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 # [ 65.991980] nix-daemon[813]: accepted connection from pid 2526, user flakelet machine # [ 66.245217] nix-daemon[813]: accepted connection from pid 2546, user flakelet machine: (finished: must succeed: flakelet build web --no-refresh --out-link /tmp/out && test -L /tmp/out/web, in 5.55 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.06 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 # [ 71.564633] systemd[1]: Reload requested from client PID 2564 ('systemctl') (unit backdoor.service)... machine # [ 71.567972] systemd[1]: Reloading... machine # [ 72.340695] systemd[1]: Reloading finished in 769 ms. machine # [ 72.588674] systemd[1]: Reload requested from client PID 2612 ('systemctl') (unit backdoor.service)... machine # [ 72.593822] systemd[1]: Reloading... machine # [ 73.296275] systemd[1]: Reloading finished in 706 ms. machine # [ 73.431939] systemd[1]: Reload requested from client PID 2657 ('systemctl') (unit backdoor.service)... machine # [ 73.435698] systemd[1]: Reloading... machine # [ 74.128904] systemd[1]: Reloading finished in 689 ms. machine # [ 74.391900] systemd[1]: Reload requested from client PID 2705 ('systemctl') (unit backdoor.service)... machine # [ 74.397186] systemd[1]: Reloading... machine # [ 75.127586] systemd[1]: Reloading finished in 733 ms. machine # [ 75.245115] systemd[1]: Reload requested from client PID 2750 ('systemctl') (unit backdoor.service)... machine # [ 75.250090] systemd[1]: Reloading... machine # [ 75.882794] systemd[1]: Reloading finished in 636 ms. machine # [ 75.995922] systemd[1]: Reload requested from client PID 2794 ('systemctl') (unit backdoor.service)... machine # [ 75.998170] systemd[1]: Reloading... machine # [ 76.694038] systemd[1]: Reloading finished in 692 ms. machine: (finished: must succeed: flakelet boot, in 5.28 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 Sep 06 12:32:17 machine systemd[1]: Starting Update flakelet service web... Sep 06 12:32:17 machine flakelet[752]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix Sep 06 12:32:18 machine flakelet[752]: web: evaluating /nix/store/ilx4whk0c9p26mms3p29h4b29a049jpq-flakelet-driver.nix Sep 06 12:32:28 machine flakelet[752]: web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv Sep 06 12:32:30 machine flakelet[752]: web: activating generation 1 Sep 06 12:32:31 machine flakelet[752]: web: updated to generation 1 Sep 06 12:32:31 machine systemd[1]: Finished Update flakelet service web. machine # [ 76.824718] systemd-logind[600]: poweroff requested from client PID 2842 ('poweroff') (unit backdoor.service)... machine # [ 76.831202] systemd-logind[600]: The system will power off now! machine # [ 76.835127] systemd-logind[600]: System is powering down. machine # [ 76.854432] systemd[1]: Removed slice Slice /system/modprobe. machine # [ 76.857314] systemd[1]: Stopped target Multi-User System. machine # [ 76.859642] systemd[1]: Stopped target flakelet managed services. machine # [ 76.862529] systemd[1]: Stopped target Login Prompts. machine # [ 76.865409] systemd[1]: Stopped target Virtual Machines and Containers. machine # [ 76.869496] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 76.875644] dhcpcd[736]: received SIGTERM, stopping machine # [ 76.879478] systemd[1]: Stopped target Timer Units. machine # [ 76.883517] dhcpcd[736]: eth0: removing interface machine # [ 76.886589] systemd[1]: fstrim.timer: Deactivated successfully. machine # [ 76.898931] dhcpcd[736]: eth0: deleting address fec0::5054:ff:fe12:3456/64 machine # [ 76.907519] systemd[1]: Stopped Discard unused filesystem blocks once a week. machine # [ 76.918876] dhcpcd[736]: eth0: deleting route to fec0::/64 machine # [ 76.924464] systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. machine # [ 76.933723] dhcpcd[736]: eth0: deleting default route via fe80::2 machine # [ 76.939959] systemd[1]: Stopped Daily Cleanup of Temporary Directories. machine # [ 76.954338] systemd[1]: systemd-rfkill.socket: Deactivated successfully. machine # [ 76.959458] systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 76.967200] systemd[1]: Mounting /run/initramfs... machine # [ 76.971348] systemd[1]: Stopping backdoor.service... machine # [ 76.974420] systemd[1]: Stopping cli-echo@1.service... machine # [ 76.979098] systemd[1]: Stopping prebuilt flakelet service cli... machine # [ 76.983455] systemd[1]: Stopping DHCP Client... machine # [ 76.988541] systemd[1]: Stopping Getty on tty1... machine # [ 76.991545] dhcpcd[736]: eth0: deleting address fe80::5054:ff:fe12:3456 machine # [ 76.996290] systemd[1]: lastlog2-import.service: Deactivated successfully. machine # [ 77.003209] network-addresses-eth1-pre-stop[2846]: deleting address 192.168.1.1/24... done machine # [ 77.010335] dhcpcd[736]: eth0: deleting route to 10.0.2.0/24 machine # [ 77.017524] systemd[1]: Stopped Import lastlog data into lastlog2 database. machine # [ 77.022336] network-addresses-eth1-pre-stop[2846]: deleting address 2001:db8:1::1/64... done machine # [ 77.026884] dhcpcd[736]: eth0: deleting default route via 10.0.2.2 machine # [ 77.030137] systemd[1]: Stopping Address configuration of eth1... machine # [ 77.034218] dhcpcd[736]: dhcpcd exited machine # [ 77.038296] systemd[1]: Stopping Nix Daemon... machine # [ 77.040805] systemd[1]: Stopping Post-Boot Actions... machine # [ 77.047604] systemd[1]: reload-systemd-vconsole-setup.service: Deactivated successfully. machine # [ 77.053431] systemd[1]: Stopped Reset console on configuration changes. machine # [ 77.058466] systemd[1]: Stopping static-echo@2.service... machine # [ 77.061218] systemd[1]: Stopping prebuilt flakelet service static... machine # [ 77.064221] systemd[1]: Stopping User Login Management... machine # [ 77.066761] systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. machine # [ 77.070102] systemd[1]: Stopped Save Transient machine-id to Disk. machine # [ 77.073927] systemd[1]: Stopped target First Boot Complete. machine # [ 77.076474] systemd[1]: Stopping Userspace Out-Of-Memory (OOM) Killer... machine # [ 77.079570] systemd[1]: Stopping Load/Save OS Random Seed... machine # [ 77.082185] systemd[1]: Stopping flakelet test service... machine # [ 77.084502] systemd[1]: flakelet-static.service: Deactivated successfully. machine # [ 77.086985] systemd[1]: Stopped Update flakelet service static. machine # [ 77.089445] systemd[1]: flakelet-web.service: Deactivated successfully. machine # [ 77.092924] systemd[1]: Stopped Update flakelet service web. machine # [ 77.096084] systemd[1]: flakelet-web.service: Consumed 4.788s CPU time over 1min 5.725s wall clock time, 125.3M memory peak, 4K written to disk. machine # [ 77.102190] systemd[1]: Stopped target flakelet contract providers ready. machine # [ 77.105480] systemd[1]: flakelet-reconcile.service: Deactivated successfully. machine # [ 77.108903] systemd[1]: Stopped Reconcile flakelet services with the host configuration. machine # [ 77.112635] systemd[1]: flakelet-boot.service: Deactivated successfully. machine # [ 77.115491] systemd[1]: Stopped Re-link flakelet services at boot. machine # [ 77.118507] systemd[1]: systemd-oomd.service: Deactivated successfully. machine # [ 77.123454] systemd[1]: Stopped Userspace Out-Of-Memory (OOM) Killer. machine # [ 77.128309] systemd[1]: backdoor.service: Deactivated successfully. machine # [ 77.133480] systemd[1]: Stopped backdoor.service. machine # [ 77.138055] systemd[1]: backdoor.service: Consumed 11.787s CPU time over 1min 6.322s wall clock time, 120.6M memory peak, 128K written to disk. machine # [ 77.145871] make-initrd-ng[2876]: /etc/initrd-release -> /nix/store/94m2cr9q6r1gdr1df0sz9ayq29mj68kz-etc-os-release machine # [ 77.153891] make-initrd-ng[2876]: /etc/os-release -> /nix/store/94m2cr9q6r1gdr1df0sz9ayq29mj68kz-etc-os-release machine # [ 77.162650] make-initrd-ng[2876]: /shutdown -> /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/systemd/systemd-shutdown machine # [ 77.171109] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 77.180899] systemd[1]: Stopped Getty on tty1. machine # [ 77.187199] systemd[1]: nix-daemon.service: Deactivated successfully. machine # [ 77.194124] systemd[1]: Stopped Nix Daemon. machine # [ 77.198215] systemd[1]: nix-daemon.service: Consumed 3.526s CPU time over 1min 4.988s wall clock time, 53.8M memory peak, 4.8M written to disk. machine # [ 77.206533] systemd[1]: static.service: Deactivated successfully. machine # [ 77.209732] systemd[1]: Stopped prebuilt flakelet service static. machine # [ 77.218243] systemd[1]: static-echo@2.service: Deactivated successfully. machine # [ 77.226350] systemd[1]: Stopped static-echo@2.service. machine # [ 77.229612] systemd[1]: cli-echo@1.service: Deactivated successfully. machine # [ 77.233600] systemd[1]: Stopped cli-echo@1.service. machine # [ 77.236802] systemd[1]: cli.service: Deactivated successfully. machine # [ 77.244617] systemd[1]: Stopped prebuilt flakelet service cli. machine # [ 77.251194] systemd[1]: web.service: Deactivated successfully. machine # [ 77.259028] systemd[1]: Stopped flakelet test service. machine # [ 77.264525] systemd[1]: Mounted /run/initramfs. machine # [ 77.273445] systemd[1]: post-boot.service: Deactivated successfully. machine # [ 77.278042] systemd[1]: Stopped Post-Boot Actions. machine # [ 77.281550] systemd[1]: systemd-logind.service: Deactivated successfully. machine # [ 77.286617] systemd[1]: Stopped User Login Management. machine # [ 77.289697] systemd[1]: systemd-random-seed.service: Deactivated successfully. machine # [ 77.292778] systemd[1]: Stopped Load/Save OS Random Seed. machine # [ 77.295125] systemd[1]: Removed slice Slice /system/getty. machine # [ 77.297597] systemd[1]: Starting Generate shutdown ramfs... machine # [ 77.301977] systemd[1]: Stopping Permit User Sessions... machine # [ 77.306269] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 77.312349] systemd[1]: Stopped Address configuration of eth1. machine # [ 77.317354] make-initrd-ng[2876]: Warning: Couldn't satisfy dependency libcrypt.so.1 for "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/systemd/libsystemd-shared-261.so" machine # [ 77.325916] make-initrd-ng[2876]: Warning: Couldn't satisfy dependency libcrypt.so.1.1 for "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/systemd/libsystemd-shared-261.so" machine # [ 77.337032] systemd[1]: dhcpcd.service: Deactivated successfully. machine # [ 77.340764] systemd[1]: Stopped DHCP Client. machine # [ 77.343695] systemd[1]: dhcpcd.service: Consumed 541ms CPU time over 1min 6.033s wall clock time, 6.2M memory peak, 8K written to disk, 96B incoming IP traffic, 56B outgoing IP traffic. machine # [ 77.354655] systemd[1]: systemd-user-sessions.service: Deactivated successfully. machine # [ 77.359653] systemd[1]: Stopped Permit User Sessions. machine # [ 77.363629] systemd[1]: Stopped target Network. machine # [ 77.367102] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 77.372270] systemd[1]: Stopped target Remote File Systems. machine # [ 77.375956] systemd[1]: network-local-commands.service: Deactivated successfully. machine # [ 77.380669] systemd[1]: Stopped Extra networking commands.. machine # [ 77.383958] systemd[1]: Stopped target Preparation for Network. machine # [ 77.386598] systemd[1]: Stopping Firewall... machine # [ 77.388479] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 77.390853] systemd[1]: resolvconf.service: Deactivated successfully. machine # [ 77.393203] systemd[1]: Stopped resolvconf update. machine # [ 77.394868] systemd[1]: nscd.service: Deactivated successfully. machine # [ 77.396911] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 77.398948] systemd[1]: Stopped target Basic System. machine # [ 77.400783] systemd[1]: Stopped target Path Units. machine # [ 77.402332] systemd[1]: Stopped target Slice Units. machine # [ 77.403892] systemd[1]: Removed slice User and Session Slice. machine # [ 77.405893] systemd[1]: Stopped target Socket Units. machine # [ 77.407423] systemd[1]: cli-echo@1.socket: Deactivated successfully. machine # [ 77.409334] systemd[1]: Closed cli-echo@1.socket. machine # [ 77.410687] systemd[1]: cli-echo@2.socket: Deactivated successfully. machine # [ 77.412446] systemd[1]: Closed cli-echo@2.socket. machine # [ 77.413787] systemd[1]: cli-echo@3.socket: Deactivated successfully. machine # [ 77.415468] systemd[1]: Closed cli-echo@3.socket. machine # [ 77.416855] systemd[1]: Removed slice Slice /system/cli-echo. machine # [ 77.418352] systemd[1]: nix-daemon.socket: Deactivated successfully. machine # [ 77.419952] systemd[1]: Closed Nix Daemon Socket. machine # [ 77.421211] systemd[1]: static-echo@1.socket: Deactivated successfully. machine # [ 77.422777] systemd[1]: Closed static-echo@1.socket. machine # [ 77.424035] systemd[1]: static-echo@2.socket: Deactivated successfully. machine # [ 77.425570] systemd[1]: Closed static-echo@2.socket. machine # [ 77.426712] systemd[1]: static-echo@3.socket: Deactivated successfully. machine # [ 77.428158] systemd[1]: Closed static-echo@3.socket. machine # [ 77.429367] systemd[1]: Removed slice Slice /system/static-echo. machine # [ 77.430727] systemd[1]: systemd-hostnamed.socket: Deactivated successfully. machine # [ 77.432275] systemd[1]: Closed Hostname Service Socket. machine # [ 77.433450] systemd[1]: systemd-importd.socket: Deactivated successfully. machine # [ 77.434800] systemd[1]: Closed Disk Image Download Service Socket. machine # [ 77.436051] systemd[1]: systemd-machined.socket: Deactivated successfully. machine # [ 77.437434] systemd[1]: Closed Virtual Machine and Container Registration Service Socket. machine # [ 77.438963] systemd[1]: systemd-mute-console.socket: Deactivated successfully. machine # [ 77.440356] systemd[1]: Closed Console Output Muting Service Socket. machine # [ 77.441548] systemd[1]: systemd-oomd.socket: Deactivated successfully. machine # [ 77.442750] systemd[1]: Closed Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 77.444032] systemd[1]: systemd-repart.socket: Deactivated successfully. machine # [ 77.445285] systemd[1]: Closed Disk Repartitioning Service Socket. machine # [ 77.446682] dbus-broker[661]: Dispatched 24334 messages @ 4(±5)μs / message. machine # [ 77.448003] systemd[1]: Stopping D-Bus System Message Bus... machine # [ 77.449065] systemd[1]: dbus-broker.service: Deactivated successfully. machine # [ 77.450173] systemd[1]: Stopped D-Bus System Message Bus. machine # [ 77.451114] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 77.452132] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 77.453184] systemd[1]: Stopped target System Initialization. machine # [ 77.454152] systemd[1]: Stopped target Local Encrypted Volumes. machine # [ 77.455150] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 77.456469] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 77.457747] systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. machine # [ 77.458917] systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. machine # [ 77.460310] systemd[1]: register-nix-paths.service: Deactivated successfully. machine # [ 77.461431] systemd[1]: Stopped register-nix-paths.service. machine # [ 77.462340] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 77.463384] systemd[1]: Stopped Apply Kernel Variables. machine # [ 77.464247] systemd[1]: systemd-coredump.socket: Deactivated successfully. machine # [ 77.465297] systemd[1]: Closed Process Core Dump Socket. machine # [ 77.466143] systemd[1]: systemd-update-done.service: Deactivated successfully. machine # [ 77.467214] systemd[1]: Stopped Update is Completed. machine # [ 77.467993] systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. machine # [ 77.469211] systemd[1]: Stopped Rebuild Journal Catalog. machine # [ 77.470055] systemd[1]: Stopping Record System Boot/Shutdown in UTMP... machine # [ 77.471040] systemd[1]: systemd-update-utmp.service: Deactivated successfully. machine # [ 77.472109] systemd[1]: Stopped Record System Boot/Shutdown in UTMP. machine # [ 77.473167] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 77.474248] systemd[1]: Stopped Create System Files and Directories. machine # [ 77.475183] systemd[1]: Stopped target Local File Systems. machine # [ 77.476038] systemd[1]: Unmounting /run/keys... machine # [ 77.476814] systemd[1]: Unmounting /run/wrappers... machine # [ 77.477568] systemd[1]: firewall.service: Deactivated successfully. machine # [ 77.478481] systemd[1]: Stopped Firewall. machine # [ 77.479121] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 77.480179] systemd[1]: Stopped Load Kernel Modules. machine # [ 77.480960] systemd[1]: run-keys.mount: Deactivated successfully. machine # [ 77.481864] systemd[1]: Unmounted /run/keys. machine # [ 77.482538] systemd[1]: run-wrappers.mount: Deactivated successfully. machine # [ 77.483491] systemd[1]: Unmounted /run/wrappers. machine # [ 77.484245] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 77.485254] systemd[1]: Stopped target Swaps. machine # [ 77.485961] systemd[1]: Reached target Unmount All Filesystems. machine # [ 77.486853] systemd[1]: systemd-remount-fs.service: Deactivated successfully. machine # [ 77.487879] systemd[1]: Stopped Remount Root and Kernel File Systems. machine # [ 77.488905] systemd[1]: systemd-fsck-root.service: Deactivated successfully. machine # [ 77.489916] systemd[1]: Stopped File System Check on Root Device. machine # [ 77.490806] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 77.491926] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 77.492934] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 77.494103] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 77.495128] systemd[1]: generate-shutdown-ramfs.service: Deactivated successfully. machine # [ 77.496240] systemd[1]: Finished Generate shutdown ramfs. machine # [ 77.497061] systemd[1]: Reached target System Shutdown. machine # [ 77.497845] systemd[1]: Reached target Late Shutdown Services. machine # [ 77.498710] systemd[1]: systemd-poweroff.service: Deactivated successfully. machine # [ 77.499700] systemd[1]: Finished System Power Off. machine # [ 77.500501] systemd[1]: Reached target System Power Off. machine # [ 77.501299] systemd[1]: Shutting down. machine # [ 77.568551] systemd-shutdown[1]: Syncing filesystems and block devices. machine # [ 77.578454] systemd-shutdown[1]: Sending SIGTERM to remaining processes... machine # [ 77.591125] systemd-journald[316]: Received SIGTERM from PID 1 (systemd-shutdow). machine # [ 77.626823] systemd-shutdown[1]: Sending SIGKILL to remaining processes... machine # [ 77.639483] systemd-shutdown[1]: Unmounting file systems. machine # [ 77.646563] (sd-umount)[2913]: Unmounting '/run/credentials/systemd-journald.service'. machine # [ 77.654183] (sd-remount)[2914]: 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 # [ 77.682503] (sd-remount)[2915]: 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 # [ 77.694551] (sd-remount)[2916]: Remounting '/tmp/xchg' read-only with options 'access=client,msize=16384,trans=virtio'. machine # [ 77.697603] (sd-umount)[2917]: Unmounting '/tmp/xchg'. machine # [ 77.728425] (sd-remount)[2918]: Remounting '/tmp/shared' read-only with options 'access=client,msize=16384,trans=virtio'. machine # [ 77.731459] (sd-umount)[2919]: Unmounting '/tmp/shared'. machine # [ 77.740231] (sd-remount)[2920]: Remounting '/nix/.ro-store' read-only with options 'cache=0xf,access=client,msize=16384,trans=virtio'. machine # [ 77.925432] (sd-umount)[2921]: Unmounting '/nix/.ro-store'. machine # [ 77.929762] (sd-remount)[2922]: Remounting '/' read-only with options ''. machine # [ 77.955693] EXT4-fs (vda): re-mounted c35653cb-96ed-4b4d-92b2-e1c9136b04ec ro. machine # [ 77.961366] systemd-shutdown[1]: All filesystems unmounted. machine # [ 77.961723] systemd-shutdown[1]: Deactivating swaps. machine # [ 77.962992] systemd-shutdown[1]: All swaps deactivated. machine # [ 77.963994] systemd-shutdown[1]: Detaching loop devices. machine # [ 77.985573] systemd-shutdown[1]: All loop devices detached. machine # [ 77.985673] systemd-shutdown[1]: Stopping MD devices. machine # [ 77.986131] systemd-shutdown[1]: All MD devices stopped. machine # [ 77.986167] systemd-shutdown[1]: Detaching DM devices. machine # [ 77.986453] systemd-shutdown[1]: All DM devices detached. machine # [ 77.986487] systemd-shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached. machine # [ 77.989068] systemd-shutdown[1]: Failed to chase and open directory '/etc/systemd/system-shutdown', ignoring: Input/output error machine # [ 78.326535] systemd-shutdown[1]: Successfully changed into root pivot. machine # [ 78.326653] systemd-shutdown[1]: Entering exitrd... machine # [ 78.345766] shutdown[1]: Syncing filesystems and block devices. machine # [ 78.347601] shutdown[1]: Sending SIGTERM to remaining processes... machine # [ 78.358246] shutdown[1]: Sending SIGKILL to remaining processes... machine # [ 78.368114] shutdown[1]: Unmounting file systems. machine # [ 78.372715] (sd-umoun[2924]: Unmounting '/run/credentials'. machine # [ 78.379257] (sd-umoun[2925]: Unmounting '/oldroot/sys/fs/fuse/connections'. machine # [ 78.385986] (sd-umoun[2926]: Unmounting '/oldroot/sys/kernel/config'. machine # [ 78.392376] (sd-umoun[2927]: Unmounting '/oldroot/dev/hugepages'. machine # [ 78.398865] (sd-umoun[2928]: Unmounting '/oldroot/sys/kernel/debug'. machine # [ 78.405033] (sd-umoun[2929]: Unmounting '/oldroot/sys/kernel/tracing'. machine # [ 78.411692] (sd-umoun[2930]: Unmounting '/oldroot/dev/mqueue'. machine # [ 78.418791] (sd-umoun[2931]: Unmounting '/oldroot/sys/fs/bpf'. machine # [ 78.424911] (sd-umoun[2932]: Unmounting '/oldroot/sys/fs/pstore'. machine # [ 78.431398] (sd-umoun[2933]: Unmounting '/oldroot/sys/fs/cgroup'. machine # [ 78.437757] (sd-umoun[2934]: Unmounting '/oldroot/sys/kernel/security'. machine # [ 78.444249] (sd-umoun[2935]: Unmounting '/oldroot/nix/store'. machine # [ 78.450457] (sd-umoun[2936]: Unmounting '/oldroot/sys'. machine # [ 78.456408] (sd-umoun[2937]: Unmounting '/oldroot/proc'. machine # [ 78.462359] (sd-umoun[2938]: Unmounting '/oldroot/dev/shm'. machine # [ 78.468025] (sd-umoun[2939]: Unmounting '/oldroot/dev/pts'. machine # [ 78.473859] (sd-umoun[2940]: Unmounting '/oldroot/dev'. machine # [ 78.479368] (sd-umoun[2941]: Unmounting '/oldroot/nix/store'. machine # [ 78.558892] (sd-umoun[2942]: Unmounting '/oldroot/run'. machine # [ 78.564741] (sd-umoun[2943]: Unmounting '/oldroot'. machine # [ 78.592365] EXT4-fs (vda): unmounting filesystem c35653cb-96ed-4b4d-92b2-e1c9136b04ec. machine # [ 78.596441] shutdown[1]: All filesystems unmounted. machine # [ 78.597297] shutdown[1]: Deactivating swaps. machine # [ 78.597944] shutdown[1]: All swaps deactivated. machine # [ 78.598455] shutdown[1]: Detaching loop devices. machine # [ 78.605882] shutdown[1]: All loop devices detached. machine # [ 78.606827] shutdown[1]: Stopping MD devices. machine # [ 78.608371] shutdown[1]: All MD devices stopped. machine # [ 78.609286] shutdown[1]: Detaching DM devices. machine # [ 78.610690] shutdown[1]: All DM devices detached. machine # [ 78.611513] shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached. machine # [ 78.613378] shutdown[1]: Syncing filesystems and block devices. machine # [ 78.614986] shutdown[1]: Powering off. machine # [ 78.670142] Console: switching to colour dummy device 80x25 machine # [ 78.701931] Flash device refused suspend due to active operation (state 20) machine # [ 78.701983] Flash device refused suspend due to active operation (state 20) machine # [ 78.707357] reboot: Power down machine: (finished: waiting for the VM to power off, in 2.24 seconds) machine: starting vm machine: QEMU running (pid 83) 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/9zdaz362i3f0hlg11y8dx19vi1bf8n88-nixos-system-machine-test/init regInfo=/nix/store/4gk3c584g2dgzzw27q32wdzk81r0awrm-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4gk3c584g2dgzzw27q32wdzk81r0awrm-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.000035] arm-pv: using stolen time PV machine # [ 0.000410] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000659] Console: colour dummy device 80x25 machine # [ 0.000668] printk: legacy console [tty0] enabled machine # [ 0.000856] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000863] pid_max: default: 32768 minimum: 301 machine # [ 0.000949] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001135] landlock: Up and running. machine # [ 0.001138] Yama: becoming mindful. machine # [ 0.001582] LSM support for eBPF active machine # [ 0.001783] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.001859] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.003144] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003936] rcu: Hierarchical SRCU implementation. machine # [ 0.003940] rcu: Max phase no-delay instances is 1000. machine # [ 0.004459] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level machine # [ 0.005612] fsl-mc MSI: its@8080000 domain created machine # [ 0.005706] EFI services will not be available. machine # [ 0.005926] smp: Bringing up secondary CPUs ... machine # [ 0.006628] Detected PIPT I-cache on CPU1 machine # [ 0.006731] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 machine # [ 0.006865] GICv3: CPU1: using allocated LPI pending table @0x0000000100b50000 machine # [ 0.006998] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40] machine # [ 0.008250] Detected PIPT I-cache on CPU2 machine # [ 0.008362] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 machine # [ 0.008496] GICv3: CPU2: using allocated LPI pending table @0x0000000100b60000 machine # [ 0.008630] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40] machine # [ 0.009840] Detected PIPT I-cache on CPU3 machine # [ 0.009973] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 machine # [ 0.010106] GICv3: CPU3: using allocated LPI pending table @0x0000000100b70000 machine # [ 0.010242] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40] machine # [ 0.010792] smp: Brought up 1 node, 4 CPUs machine # [ 0.010819] SMP: Total of 4 processors activated. machine # [ 0.010822] CPU: All CPU(s) started at EL1 machine # [ 0.010832] CPU features: detected: Branch Target Identification machine # [ 0.010836] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.010838] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.010842] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.010845] CPU features: detected: Common not Private translations machine # [ 0.010848] CPU features: detected: CRC32 instructions machine # [ 0.010850] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.010853] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.010856] CPU features: detected: Data independent timing control (DIT) machine # [ 0.010859] CPU features: detected: E0PD machine # [ 0.010861] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.010864] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.010867] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.010870] CPU features: detected: Fine Grained Traps machine # [ 0.010872] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.010876] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.010879] CPU features: detected: LSE atomic instructions machine # [ 0.010881] CPU features: detected: Privileged Access Never machine # [ 0.010884] CPU features: detected: PMUv3 machine # [ 0.010886] CPU features: detected: RAS Extension Support machine # [ 0.010888] CPU features: detected: RASv1p1 Extension Support machine # [ 0.010891] CPU features: detected: Random Number Generator machine # [ 0.010893] CPU features: detected: Speculation barrier (SB) machine # [ 0.010896] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.010898] CPU features: detected: TLB range maintenance instructions machine # [ 0.010902] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.011039] alternatives: applying system-wide alternatives machine # [ 0.014068] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.014319] Memory: 3910028K/4194304K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 233732K reserved, 32768K cma-reserved) machine # [ 0.015797] devtmpfs: initialized machine # [ 0.019470] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.019581] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). machine # [ 0.019794] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.019799] 0 pages in range for non-PLT usage machine # [ 0.019800] 508320 pages in range for PLT usage machine # [ 0.019944] pinctrl core: initialized pinctrl subsystem machine # [ 0.020746] DMI not present or invalid. machine # [ 0.024194] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.026704] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.026892] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.027128] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.027151] audit: initializing netlink subsys (disabled) machine # [ 0.027525] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 machine # [ 0.028034] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.028038] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.028042] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.028045] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.028048] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.028148] cpuidle: using governor ladder machine # [ 0.028172] cpuidle: using governor menu machine # [ 0.028377] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.028589] ASID allocator initialised with 65536 entries machine # [ 0.029779] Serial: AMBA PL011 UART driver machine # [ 0.035087] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.035461] printk: console [ttyAMA0] enabled machine # [ 0.045931] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.045952] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.045961] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.045970] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.045978] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.045986] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.045994] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.046002] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.059510] fbcon: Taking over console machine # [ 0.059528] ACPI: Interpreter disabled. machine # [ 0.060578] iommu: Default domain type: Translated machine # [ 0.060583] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.073881] SCSI subsystem initialized machine # [ 0.074325] usbcore: registered new interface driver usbfs machine # [ 0.074361] usbcore: registered new interface driver hub machine # [ 0.074378] usbcore: registered new device driver usb machine # [ 0.074658] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.074662] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.074671] PTP clock support registered machine # [ 0.074718] EDAC MC: Ver: 3.0.0 machine # [ 0.075160] scmi_core: SCMI protocol bus registered machine # [ 0.075709] FPGA manager framework machine # [ 0.076299] vgaarb: loaded machine # [ 0.077138] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.077641] VFS: Disk quotas dquot_6.6.0 machine # [ 0.077666] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.078060] netfs: FS-Cache loaded machine # [ 0.078194] pnp: PnP ACPI: disabled machine # [ 0.084129] NET: Registered PF_INET protocol family machine # [ 0.084621] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.115295] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.115354] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.115396] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) machine # [ 0.115535] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) machine # [ 0.115811] TCP: Hash tables configured (established 32768 bind 32768) machine # [ 0.115932] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) machine # [ 0.115981] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.116044] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) machine # [ 0.116240] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.116262] NET: Registered PF_XDP protocol family machine # [ 0.116279] PCI: CLS 0 bytes, default 64 machine # [ 0.116651] Trying to unpack rootfs image as initramfs... machine # [ 0.125705] kvm [1]: HYP mode not available machine # [ 0.172321] Initialise system trusted keyrings machine # [ 0.172623] workingset: timestamp_bits=42 max_order=20 bucket_order=0 machine # [ 0.173381] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.173474] 9p: Installing v9fs 9p2000 file system support machine # [ 0.185397] Key type asymmetric registered machine # [ 0.185404] Asymmetric key parser 'x509' registered machine # [ 0.185460] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.185682] io scheduler mq-deadline registered machine # [ 0.185687] io scheduler kyber registered machine # [ 0.193645] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.201282] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.201680] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.201697] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.201707] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.201713] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.201734] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.201757] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.201851] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.201858] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.201863] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.201866] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.201869] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.201954] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.202474] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.202688] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.202701] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.202733] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.202745] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.203294] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.203520] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.203532] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.203563] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.204106] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.204319] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.204330] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.204362] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.204891] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.205112] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.205124] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.205170] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.205723] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.205929] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.205945] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.205972] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.206520] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.206735] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.206746] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.206772] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.207324] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.207533] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.207545] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.207571] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.207583] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.208133] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.208356] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.208382] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.208926] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.209171] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.209201] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.209684] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.209902] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.210185] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.210506] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.210519] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.210551] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.211097] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.211320] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.211332] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.211358] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.211997] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.212004] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.212008] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.212051] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.212099] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.212148] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.212193] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.212245] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.212293] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.212337] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.212385] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.212435] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.212512] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.212571] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.212594] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.212617] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.212640] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.212658] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.212676] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.212698] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.212720] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.212744] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.212765] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.212788] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.212810] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.212829] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.212846] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.212863] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.212885] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.212907] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.212930] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.212953] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.212975] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.212998] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.213025] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.213030] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.213033] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.214504] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.226745] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.229288] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.231573] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.234103] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.236545] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.243343] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.245749] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.248200] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.250685] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.255709] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.259326] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.264888] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.266687] msm_serial: driver initialized machine # [ 0.266855] SuperH (H)SCI(F) driver initialized machine # [ 0.266905] STM32 USART driver initialized machine # [ 0.285643] loop: module loaded machine # [ 0.285872] virtio_blk virtio5: 4/0/0 default/read/poll queues machine # [ 0.287986] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.290618] megasas: 07.734.00.00-rc1 machine # [ 0.291546] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.301137] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.301196] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.302938] Using buffer write method machine # [ 0.302993] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.304974] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.305001] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.306791] Using buffer write method machine # [ 0.306816] Concatenating MTD devices: machine # [ 0.306819] (0): "0.flash" machine # [ 0.306821] (1): "0.flash" machine # [ 0.306823] into device "0.flash" machine # [ 0.642191] Freeing initrd memory: 27052K machine # [ 0.660698] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.669599] thunder_xcv, ver 1.0 machine # [ 0.669710] thunder_bgx, ver 1.0 machine # [ 0.669775] nicpf, ver 1.0 machine # [ 0.671628] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.671644] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.671742] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.671758] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.671833] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.671843] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.671924] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.671935] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.672347] sky2: driver version 1.30 machine # [ 0.677477] usbcore: registered new interface driver usb-storage machine # [ 0.677765] usbcore: registered new interface driver usbserial_generic machine # [ 0.677812] usbserial: USB Serial support registered for generic machine # [ 0.678030] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.678127] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.678902] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.679739] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.682692] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.682770] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T12:33:25 UTC (1788698005) machine # [ 0.683925] i2c_dev: i2c /dev entries driver machine # [ 0.689290] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.690437] hub 1-0:1.0: USB hub found machine # [ 0.690502] hub 1-0:1.0: 6 ports detected machine # [ 0.692494] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.692510] sdhci: Copyright(c) Pierre Ossman machine # [ 0.693395] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.694561] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.699554] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.700392] usbcore: registered new interface driver usbhid machine # [ 0.700404] usbhid: USB HID core driver machine # [ 0.736222] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.740243] drop_monitor: Initializing network drop monitor service machine # [ 0.740627] NET: Registered PF_INET6 protocol family machine # [ 0.742434] Segment Routing with IPv6 machine # [ 0.742468] In-situ OAM (IOAM) with IPv6 machine # [ 0.742526] NET: Registered PF_PACKET protocol family machine # [ 0.742783] 9pnet: Installing 9P2000 support machine # [ 0.748413] Key type dns_resolver registered machine # [ 0.765122] registered taskstats version 1 machine # [ 0.765610] Loading compiled-in X.509 certificates machine # [ 0.787566] Demotion targets for Node 0: null machine # [ 0.787863] Key type .fscrypt registered machine # [ 0.787870] Key type fscrypt-provisioning registered machine # [ 0.788069] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.788091] ima: Allocated hash algorithm: sha1 machine # [ 0.788123] ima: No architecture policies found machine # [ 0.789529] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 0.814397] clk: Disabling unused clocks machine # [ 0.814437] PM: genpd: Disabling unused power domains machine # [ 0.819180] Freeing unused kernel memory: 4736K machine # [ 0.819447] Run /init as init process machine # [ 0.847793] systemd[1]: Successfully made /usr/ read-only. machine # [ 0.937225] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.096639] 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.182363] 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.182438] systemd[1]: Detected virtualization qemu. machine # [ 1.182533] systemd[1]: Detected architecture arm64. machine # [ 1.182548] systemd[1]: Running in initrd. machine # [ 1.183544] systemd[1]: Initializing machine ID from random generator. machine # [ 1.183815] systemd[1]: Hostname set to . machine # [ 1.185745] 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.305253] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.421046] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.465572] 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.465959] 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.508669] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.528675] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.528989] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.529032] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.529066] systemd[1]: Reached target Path Units. machine # [ 1.529086] systemd[1]: Reached target Slice Units. machine # [ 1.529107] systemd[1]: Reached target Swaps. machine # [ 1.529131] systemd[1]: Reached target Timer Units. machine # [ 1.529430] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.529620] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.529797] systemd[1]: Listening on Journal Sockets. machine # [ 1.529972] systemd[1]: Listening on udev Control Socket. machine # [ 1.530079] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.530101] systemd[1]: Reached target Socket Units. machine # [ 1.532412] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.534663] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.534752] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.536934] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.562702] systemd[1]: Starting Journal Service... machine # [ 1.568734] systemd[1]: Starting Load Kernel Modules... machine # [ 1.568885] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.573420] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.575527] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.576724] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.578615] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.586139] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.601575] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.618136] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.619970] systemd-journald[95]: Collecting audit messages is disabled. machine # [ 1.624252] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.624453] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.624457] [drm] features: -context_init machine # [ 1.625941] [drm] number of scanouts: 1 machine # [ 1.625972] [drm] number of cap sets: 0 machine # [ 1.633726] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.633777] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.633798] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.642145] Console: switching to colour frame buffer device 160x50 machine # [ 1.642323] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.649495] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.654319] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.682949] systemd[1]: Finished Load Kernel Modules. machine # [ 1.691432] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.697733] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.698895] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.699301] systemd[1]: Reached target Local File Systems. machine # [ 1.712762] systemd-modules-load[96]: Using 4 probe threads machine # [ 1.719512] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.720320] systemd[1]: Started Journal Service. machine # [ 1.714624] systemd-modules-load[96]: Module 'virtio_balloon' is built in machine # [ 1.723308] systemd-modules-load[96]: Module 'virtio_console' is built in machine # [ 1.724992] systemd-modules-load[96]: Module 'virtio_rng' is built in machine # [ 1.726479] systemd-modules-load[96]: Inserted module 'dm_mod' machine # [ 1.727830] systemd-modules-load[96]: Inserted module 'virtio_gpu' machine # [ 1.732965] systemd[1]: Starting Create System Files and Directories... machine # [ 1.747003] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.765257] systemd[1]: Finished Create System Files and Directories. machine # [ 1.772768] systemd-udevd[109]: Using default interface naming scheme 'v261'. machine # [ 1.794352] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.824266] systemd[1]: Starting Virtual Console Setup... machine # [ 1.886819] systemd-vconsole-setup[131]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.890502] systemd[1]: Finished Virtual Console Setup. machine # [ 2.257694] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.259355] systemd[1]: Reached target System Initialization. machine # [ 2.260273] systemd[1]: Reached target Basic System. machine # [ 2.439865] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.451089] (udev-worker)[124]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.455427] (udev-worker)[124]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.488783] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.490660] systemd[1]: Reached target Initrd Root Device. machine # [ 2.492460] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.542781] systemd-fsck[153]: nixos: clean, 1218/65536 files, 18921/262144 blocks machine # [ 2.548396] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.571461] systemd[1]: Mounting /sysroot... machine # [ 2.614583] EXT4-fs (vda): mounted filesystem c35653cb-96ed-4b4d-92b2-e1c9136b04ec r/w with ordered data mode. Quota mode: none. machine # [ 2.613026] systemd[1]: Mounted /sysroot. machine # [ 2.614488] systemd[1]: Reached target Initrd Root File System. machine # [ 2.617702] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 2.621858] systemd[1]: Mounting /sysroot/run... machine # [ 2.645479] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 2.656534] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 2.658924] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.674253] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.679694] systemd-sysroot-fstab-check[165]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.681515] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 2.682446] systemd[1]: Mounted /sysroot/run. machine # [ 2.683147] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 2.683871] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 2.684639] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.686383] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 2.688233] systemd[1]: Mounting /sysroot/nix/store... machine # [ 2.690126] systemd[1]: Reload requested from client PID 165 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.695319] systemd[1]: Reloading... machine # [ 2.862588] systemd[1]: Reloading finished in 170 ms. machine # [ 2.911815] systemd-sysroot-fstab-check[165]: Requesting initrd-fs.target/start/replace... machine # [ 2.915032] systemd[1]: Mounted /sysroot/nix/store. machine # [ 2.916721] systemd-sysroot-fstab-check[165]: Requesting swap.target/start/replace... machine # [ 2.921329] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.923058] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.924311] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.926141] systemd[1]: Reached target Initrd File Systems. machine # [ 2.926947] systemd[1]: Starting Find NixOS closure... machine # [ 2.927663] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 2.957971] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 2.970479] systemd[1]: Finished Find NixOS closure. machine # [ 2.971961] systemd[1]: Reached target Initrd Default Target. machine # [ 2.975385] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 2.995461] systemd[1]: Stopped target Initrd Default Target. machine # [ 2.996738] systemd[1]: Stopped target Basic System. machine # [ 2.997496] systemd[1]: Stopped target Initrd Root Device. machine # [ 2.998317] systemd[1]: Stopped target Path Units. machine # [ 2.999019] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.000613] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.001733] systemd[1]: Stopped target Slice Units. machine # [ 3.002491] systemd[1]: Stopped target Socket Units. machine # [ 3.003224] systemd[1]: Stopped target System Initialization. machine # [ 3.004129] systemd[1]: Stopped target Swaps. machine # [ 3.004803] systemd[1]: Stopped target Timer Units. machine # [ 3.005512] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.006358] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.007198] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.008343] systemd[1]: Stopped Find NixOS closure. machine # [ 3.009053] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.010164] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.011501] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.012820] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.014819] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.015897] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.016728] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.017866] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.018968] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.019992] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.020893] systemd[1]: Stopped target Local File Systems. machine # [ 3.021769] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.022905] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.024146] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.026816] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.028033] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.029078] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.029822] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.030788] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.032874] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.034233] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.043359] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.044767] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.063164] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.064360] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.065384] systemd[1]: systemd-udevd.service: Consumed 2.037s CPU time over 1.348s wall clock time, 25.4M memory peak. machine # [ 3.066827] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. machine # [ 3.067816] systemd[1]: Closed udev Control Socket. machine # [ 3.068626] systemd[1]: Starting Cleanup udev Database... machine # [ 3.069401] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.070476] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.071356] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.072535] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.074768] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.075757] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.147756] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.151290] systemd[1]: Finished Cleanup udev Database. machine # [ 3.153665] systemd[1]: Reached target Switch Root. machine # [ 3.155825] systemd[1]: Starting NixOS Activation... machine # [ 3.457337] initrd-nixos-activation-start[210]: booting system configuration /nix/store/9zdaz362i3f0hlg11y8dx19vi1bf8n88-nixos-system-machine-test machine # [ 3.562631] initrd-nixos-activation-start[210]: running activation script... machine # [ 4.205458] initrd-nixos-activation-start[233]: setting up /etc... machine # [ 4.648140] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.651516] systemd[1]: Finished NixOS Activation. machine # [ 4.653884] systemd[1]: Starting Switch Root... machine # [ 4.705482] systemd[1]: Switching root. machine # [ 4.867372] systemd-journald[95]: Received SIGTERM from PID 1 (systemd). machine # [ 5.558010] 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 # [ 5.558157] systemd[1]: Detected virtualization qemu. machine # [ 5.558240] systemd[1]: Detected architecture arm64. machine # [ 5.820110] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 6.758236] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.759073] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.762178] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.771891] systemd[1]: Created slice Slice /system/getty. machine # [ 6.776023] systemd[1]: Created slice User and Session Slice. machine # [ 6.776736] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.777823] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.779084] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.780264] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.781462] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.782642] systemd[1]: Reached target flakelet contract providers ready. machine # [ 6.783822] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.784959] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.786124] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.787289] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.788463] systemd[1]: Reached target Path Units. machine # [ 6.789655] systemd[1]: Reached target Remote File Systems. machine # [ 6.790783] systemd[1]: Reached target Slice Units. machine # [ 6.791936] systemd[1]: Reached target Swaps. machine # [ 6.798731] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.806927] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.813265] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.819488] systemd[1]: Listening on Factory Reset Management. machine # [ 6.820966] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.832827] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.835505] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.841545] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.843286] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.844349] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.845291] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.859972] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.861272] systemd[1]: Listening on udev Control Socket. machine # [ 6.862514] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.908339] systemd[1]: Mounting Huge Pages File System... machine # [ 6.915905] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.924014] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.963525] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.975101] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.985884] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 6.989526] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.998230] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.000172] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.001535] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.019503] systemd[1]: Starting Load Kernel Module fuse... machine # [ 7.021325] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.037677] systemd[1]: Starting Journal Service... machine # [ 7.081662] systemd[1]: Starting Load Kernel Modules... machine # [ 7.092141] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.128057] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.129709] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.153818] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.172715] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.176103] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.188874] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.192422] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 7.193960] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 7.203779] systemd[1]: Mounted Huge Pages File System. machine # [ 7.205264] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.206376] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.207485] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.208540] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.224023] fuse: init (API version 7.45) machine # [ 7.234119] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 7.242517] systemd[1]: Finished Load Kernel Module fuse. machine # [ 7.251624] systemd[1]: Mounting FUSE Control File System... machine # [ 7.274603] systemd-journald[305]: Collecting audit messages is enabled. machine # [ 7.282671] systemd[1]: Finished Load Kernel Modules. machine # [ 7.288061] systemd[1]: Starting Firewall... machine # [ 7.293562] EXT4-fs (vda): re-mounted c35653cb-96ed-4b4d-92b2-e1c9136b04ec. machine # [ 7.291874] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.297225] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.297261] systemd[1]: systemd-journald.service: Deactivated successfully.[ 7.302254] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # machine # [ 7.306088] systemd[1]: Started Journal Service. machine # [ 7.301883] systemd-modules-load[306]: Using 2 probe threads machine # [ 7.305360] systemd-modules-load[306]: Module 'atkbd' is built in machine # [ 7.307676] systemd-modules-load[306]: Module 'loop' is built in machine # [ 7.310437] systemd-oomd[307]: No swap; memory pressure usage will be degraded machine # [ 7.314049] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.331589] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.338302] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.341810] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.346819] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.350655] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.355260] systemd[1]: Mounted FUSE Control File System. machine # [ 7.375348] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.382766] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.405776] systemd-journald[305]: Received client request to flush runtime journal. machine # [ 7.438465] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.442711] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.446706] systemd[1]: First Boot Complete skipped, unmet condition check ConditionFirstBoot=yes machine # [ 7.454665] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.457547] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.458962] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.461243] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.600934] systemd-udevd[342]: Using default interface naming scheme 'v261'. machine # [ 7.722532] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.766443] systemd[1]: Mounting /run/wrappers... machine # [ 7.827406] systemd[1]: Mounted /run/wrappers. machine # [ 7.829299] systemd[1]: Reached target Local File Systems. machine # [ 7.833293] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.841105] systemd[1]: Starting register-nix-paths.service... machine # [ 7.849763] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.852358] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.854249] systemd[1]: Save Transient machine-id to Disk skipped, unmet condition check ConditionPathIsMountPoint=/etc/machine-id machine # [ 7.875566] systemd[1]: Starting Create System Files and Directories... machine # [ 8.011114] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.013824] systemd[1]: Finished Create System Files and Directories. machine # [ 8.016679] systemd[1]: Rebuild Journal Catalog skipped, unmet condition check ConditionNeedsUpdate=/var machine # [ 8.020178] systemd[1]: Update is Completed skipped, no trigger condition checks were met. machine # [ 8.024387] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.080194] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.311976] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 8.442000] systemd[1]: Starting Virtual Console Setup... machine # [ 8.461060] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.471618] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 8.476152] systemd[1]: Stopped Virtual Console Setup. machine # [ 8.488141] systemd[1]: Starting Virtual Console Setup... machine # [ 8.553767] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 8.576795] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.581934] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.615238] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 8.663117] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line. machine # [ 8.678787] (udev-worker)[389]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 8.682326] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line. machine # [ 8.716136] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 8.723088] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.729594] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.732893] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.735036] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.738906] systemd[1]: Rebuild Journal Catalog skipped, unmet condition check ConditionNeedsUpdate=/var machine # [ 8.742978] systemd[1]: Save Transient machine-id to Disk skipped, unmet condition check ConditionPathIsMountPoint=/etc/machine-id machine # [ 8.745508] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.751497] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.756937] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.760652] systemd[1]: Update is Completed skipped, no trigger condition checks were met. machine # [ 8.819121] mousedev: PS/2 mouse device common for all mice machine # [ 8.881026] systemd[1]: Finished Firewall. machine # [ 8.907173] systemd[1]: Finished register-nix-paths.service. machine # [ 9.159700] systemd-vconsole-setup[498]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 9.165857] systemd[1]: Finished Virtual Console Setup. machine # [ 9.382809] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.385736] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.390823] systemd[1]: Reached target System Initialization. machine # [ 9.392127] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.393209] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.394438] systemd[1]: Reached target Timer Units. machine # [ 9.395591] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.399074] systemd[1]: Listening on Nix Daemon Socket. machine # [ 9.400049] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.401476] systemd[1]: Reached target Socket Units. machine # [ 9.402359] systemd[1]: Reached target Basic System. machine # [ 9.403331] systemd[1]: Started backdoor.service. machine # [ 9.405763] systemd[1]: Starting Re-link flakelet services at boot... machine # [ 9.408187] systemd[1]: Import lastlog data into lastlog2 database skipped, unmet condition check ConditionPathExists=!/var/lib/lastlog/lastlog2.db machine # [ 9.436122] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.443464] systemd[1]: Starting Post-Boot Actions... machine # [ 9.454792] systemd[1]: Started Reset console on configuration changes. machine # [ 9.465486] systemd[1]: Starting resolvconf update... machine # [ 9.487840] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.543683] systemd[1]: Finished Post-Boot Actions. machine # connecting to host... machine # [ 9.551847] nsncd[609]: Sep 06 12:33:34.372 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.556319] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.563063] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.565316] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.574062] systemd[1]: Starting User Login Management... machine # [ 9.576297] systemd[1]: Reload requested from client PID 618 ('systemctl') (unit flakelet-boot.service)... machine # [ 9.580687] systemd[1]: Reloading... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 10.04 seconds) machine: (finished: waiting for the VM to finish booting, in 10.04 seconds) machine # [ 9.689169] dbus-broker-launch[615]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 9.698797] systemd-logind[635]: New seat seat0. machine # [ 9.730404] systemd-logind[635]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 9.733531] systemd-logind[635]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 9.828129] systemd-logind[635]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 10.406249] systemd[1]: Reloading finished in 818 ms. machine # [ 10.485222] systemd[1]: Started User Login Management. machine # [ 10.552182] dbus-broker-launch[615]: NSS returned no entry for 'systemd-timesync' machine # [ 10.558803] dbus-broker-launch[615]: Invalid user-name in /nix/store/zg5jbfcpdnf3w6rp73i6nhi6zcn6x83p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.610177] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.617733] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.628605] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.631040] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.633420] systemd[1]: Starting linger-users.service... machine # [ 10.636920] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.639832] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 10.643577] systemd[1]: Stopped Virtual Console Setup. machine # [ 10.645424] systemd[1]: Stopping Virtual Console Setup... machine # [ 10.649135] systemd[1]: Starting Virtual Console Setup... machine # [ 10.652163] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.676808] dbus-broker-launch[615]: Ready machine # [ 10.686516] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.689859] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 10.723142] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.726226] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.728446] systemd[1]: Finished linger-users.service. machine # [ 10.793074] nsncd[744]: Sep 06 12:33:35.613 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.841369] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.846841] systemd[1]: Finished resolvconf update. machine # [ 10.852185] systemd[1]: Reached target Preparation for Network. machine # [ 10.857516] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.863677] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.870728] systemd[1]: Starting DHCP Client... machine # [ 10.873935] systemd[1]: Starting Address configuration of eth1... machine # [ 10.883053] systemd[1]: Starting Extra networking commands.... machine # [ 10.904761] systemd[1]: Reload requested from client PID 761 ('systemctl') (unit flakelet-boot.service)... machine # [ 10.915567] systemd[1]: Reloading... machine # [ 11.011141] network-addresses-eth1-start[763]: adding address 192.168.1.1/24... done machine # [ 11.027672] network-addresses-eth1-start[763]: adding address 2001:db8:1::1/64... done machine # [ 11.127386] systemd-vconsole-setup[728]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 11.663765] systemd[1]: Reloading finished in 753 ms. machine # [ 11.760688] systemd[1]: Finished Virtual Console Setup. machine # [ 11.764127] systemd[1]: Finished Address configuration of eth1. machine # [ 11.766348] systemd[1]: Finished Extra networking commands.. machine # [ 11.800498] systemd[1]: Created slice Slice /system/cli-echo. machine # [ 11.803015] systemd[1]: Reached target Network. machine # [ 11.805219] systemd[1]: Listening on cli-echo@1.socket. machine # [ 11.807291] systemd[1]: Listening on cli-echo@2.socket. machine # [ 11.810360] systemd[1]: Starting Permit User Sessions... machine # [ 11.839127] systemd[1]: Starting prebuilt flakelet service cli... machine # [ 11.866572] systemd[1]: Listening on cli-echo@3.socket. machine # [ 11.868743] systemd[1]: Finished Permit User Sessions. machine # [ 11.879686] systemd[1]: Started prebuilt flakelet service cli. machine # [ 11.882502] systemd[1]: Reload requested from client PID 874 ('systemctl') (unit flakelet-boot.service)... machine # [ 11.885870] systemd[1]: Reloading... machine # [ 12.565689] systemd[1]: Reloading finished in 681 ms. machine # [ 12.650519] systemd[1]: Started Getty on tty1. machine # [ 12.651810] systemd[1]: Reached target Login Prompts. machine # [ 12.737725] dhcpcd[915]: dhcpcd-10.3.2 starting machine # [ 12.752148] dhcpcd[926]: dev: loaded udev machine # [ 12.760963] dhcpcd[926]: DUID 00:01:00:01:32:30:19:d1:52:54:00:12:34:56 machine # [ 12.805938] 8021q: 802.1Q VLAN Support v1.8 machine # [ 12.807556] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 12.802710] systemd[1]: Reload requested from client PID 928 ('systemctl') (unit flakelet-boot.service)... machine # [ 12.811996] systemd[1]: Reloading... machine # [ 12.892666] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 12.949940] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 12.950708] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 12.955731] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 12.956236] cfg80211: failed to load regulatory.db machine # [ 13.032420] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 13.028782] dhcpcd[926]: eth0: waiting for carrier machine # [ 13.035099] dhcpcd[926]: eth0: carrier acquired machine # [ 13.047400] dhcpcd[926]: eth0: IAID 00:12:34:56 machine # [ 13.050411] dhcpcd[926]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 13.502783] systemd[1]: Reloading finished in 696 ms. machine # [ 13.602518] systemd[1]: Created slice Slice /system/static-echo. machine # [ 13.604400] systemd[1]: Listening on static-echo@1.socket. machine # [ 13.617381] systemd[1]: Listening on static-echo@2.socket. machine # [ 13.635177] systemd[1]: Starting prebuilt flakelet service static... machine # [ 13.643132] systemd[1]: Listening on static-echo@3.socket. machine # [ 13.670909] systemd[1]: Reload requested from client PID 992 ('systemctl') (unit flakelet-boot.service)... machine # [ 13.680237] systemd[1]: Reloading... machine # [ 14.386941] systemd[1]: Reloading finished in 712 ms. machine # [ 14.467316] systemd[1]: Started prebuilt flakelet service static. machine # [ 14.506152] systemd[1]: Reload requested from client PID 1034 ('systemctl') (unit flakelet-boot.service)... machine # [ 14.515002] systemd[1]: Reloading... machine # [ 14.554077] dhcpcd[926]: eth0: rebinding lease of 10.0.2.15 machine # [ 14.585002] dhcpcd[926]: eth0: probing address 10.0.2.15/24 machine # [ 15.186414] systemd[1]: Reloading finished in 677 ms. machine # [ 15.383739] flakelet[608]: cli: units re-linked machine # [ 15.386506] flakelet[608]: static: units re-linked machine # [ 15.391676] flakelet[608]: web: units re-linked machine # [ 15.398129] systemd[1]: Finished Re-link flakelet services at boot. machine # [ 15.401853] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 15.406869] systemd[1]: Starting Reconcile flakelet services with the host configuration... machine # [ 15.411264] systemd[1]: Starting flakelet test service... machine # [ 15.441877] systemd[1]: Started flakelet test service. machine # [ 15.468592] systemd[1]: Finished Reconcile flakelet services with the host configuration. machine # [ 15.474602] systemd[1]: Starting Update flakelet service static... machine # [ 15.480989] systemd[1]: Starting Update flakelet service web... machine # [ 15.545406] flakelet[1101]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix machine # [ 15.555567] flakelet[1100]: static: using prebuilt artifact /nix/store/5n6bggiblbxz1iv4lknj72qidd7kcblf-flakelet-static machine # [ 15.568602] dhcpcd[926]: eth0: soliciting an IPv6 router machine # [ 15.572150] systemd[1]: Finished Update flakelet service static. machine # [ 15.576270] flakelet[1100]: static: up to date machine # [ 15.579285] dhcpcd[926]: eth0: Router Advertisement from fe80::2 machine # [ 15.582764] dhcpcd[926]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 15.587362] dhcpcd[926]: eth0: adding route to fec0::/64 machine # [ 15.591143] dhcpcd[926]: eth0: adding default route via fe80::2 machine # [ 15.682076] systemd[1]: Started Nix Daemon. machine # [ 15.858400] nix-daemon[1109]: accepted connection from pid 1102, user flakelet machine # [ 16.029525] flakelet[1101]: web: evaluating /nix/store/ilx4whk0c9p26mms3p29h4b29a049jpq-flakelet-driver.nix machine # [ 16.174524] nix-daemon[1109]: accepted connection from pid 1128, user flakelet machine: (finished: waiting for unit web.service, in 16.94 seconds) machine: waiting for unit cli.service machine: (finished: waiting for unit cli.service, in 0.14 seconds) (finished: run the VM test script, in 97.38 seconds) test script finished in 97.46s cleanup kill QemuMachine (pid 83) 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)