tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.01 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm sshClient # Disk image does not exist, creating the virtualisation disk image... sshClient # Formatting '/build/vm-state-sshClient/tmp.HoFwkznlso', fmt=raw size=1073741824 sshClient: QEMU running (pid 48) sshClient # mke2fs 1.47.4 (6-Mar-2025) sshClient # Discarding device blocks: 0/262144 done sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes sshClient # Filesystem UUID: f4329853-74ce-4408-808f-d2de807d141b sshClient # Superblock backups stored on blocks: sshClient # 32768, 98304, 163840, 229376 sshClient # sshClient # Allocating group tables: 0/8 done sshClient # Writing inode tables: 0/8 done sshClient # Creating journal (8192 blocks): done sshClient # Writing superblocks and filesystem accounting information: 0/8 done sshClient # sshClient # Virtualisation disk image created. sshClient # Starting virtiofs daemons... sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 47) machine # Formatting '/build/vm-state-machine/tmp.vLYDeirXLK', 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: 313095f8-9271-440c-b058-9bbbdd1fbe6e machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 (finished: start all VMs, in 5.15 seconds) machine # subtest: create systemd-homed user on first boot prompt machine # Allocating group tables: 0/8 done machine: waiting for unit systemd-homed.service machine # Writing inode tables: 0/8 done machine: waiting for the VM to finish booting machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] sshClient # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 sshClient # [ 0.000000] KASLR enabled sshClient # [ 0.000000] random: crng init done sshClient # [ 0.000000] Machine model: linux,dummy-virt sshClient # [ 0.000000] efi: UEFI not found. sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] sshClient # [ 0.000000] Zone ranges: sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] DMA32 empty sshClient # [ 0.000000] Normal empty sshClient # [ 0.000000] Device empty sshClient # [ 0.000000] Movable zone start for each node sshClient # [ 0.000000] Early memory node ranges sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 sshClient # [ 0.000000] psci: probing for conduit method from DT. sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware. sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs sshClient # [ 0.000000] psci: Trusted OS migration not required sshClient # [ 0.000000] psci: SMC Calling Convention v1.1 sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 sshClient # [ 0.000000] Detected PIPT I-cache on CPU0 sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface sshClient # [ 0.000000] CPU features: detected: Spectre-v4 sshClient # [ 0.000000] CPU features: detected: Spectre-BHB sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 sshClient # [ 0.000000] alternatives: applying boot alternatives sshClient # [ 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/5rkl1zpvxpi0gfiqglh9l3x1vzj96pry-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/yi8ib0m3vkglp4zjfjb6q5qrny37sdqv-closure-info/registration console=ttyAMA0,115200n8 console=tty0 sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yi8ib0m3vkglp4zjfjb6q5qrny37sdqv-closure-info/registration", will be passed to user space. sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB sshClient # [ 0.000000] software IO TLB: area num 1. sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) sshClient # [ 0.000000] Fallback order for Node 0: 0 sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 sshClient # [ 0.000000] Policy zone: DMA sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.000000] allocated 2097152 bytes of page_ext sshClient # [ 0.000000] ftrace: allocating 74963 entries in 294 pages sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups sshClient # [ 0.000000] rcu: Hierarchical RCU implementation. sshClient # [ 0.000000] rcu: RCU event tracing is enabled. sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.000000] Rude variant of Tasks RCU enabled. sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 sshClient # [ 0.000000] GICv3: 256 SPIs implemented sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns sshClient # [ 0.000035] arm-pv: using stolen time PV sshClient # [ 0.000458] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.000635] Console: colour dummy device 80x25 sshClient # [ 0.000643] printk: legacy console [tty0] enabled sshClient # [ 0.000842] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.000849] pid_max: default: 32768 minimum: 301 sshClient # [ 0.000927] LSM: initializing lsm=capability,landlock,yama,bpf,ima sshClient # [ 0.001113] landlock: Up and running. sshClient # [ 0.001116] Yama: becoming mindful. sshClient # [ 0.001608] LSM support for eBPF active sshClient # [ 0.001758] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.001777] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.003670] rcu: Hierarchical SRCU implementation. sshClient # [ 0.003676] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.004954] fsl-mc MSI: its@8080000 domain created sshClient # [ 0.005070] EFI services will not be available. sshClient # [ 0.005157] smp: Bringing up secondary CPUs ... sshClient # [ 0.005165] smp: Brought up 1 node, 1 CPU sshClient # [ 0.005169] SMP: Total of 1 processors activated. sshClient # [ 0.005172] CPU: All CPU(s) started at EL1 sshClient # [ 0.005186] CPU features: detected: Branch Target Identification sshClient # [ 0.005190] CPU features: detected: ARMv8.4 Translation Table Level sshClient # [ 0.005193] CPU features: detected: Instruction cache invalidation not required for I/D coherence sshClient # [ 0.005196] CPU features: detected: Data cache clean to the PoU not required for I/D coherence sshClient # [ 0.005200] CPU features: detected: Common not Private translations sshClient # [ 0.005203] CPU features: detected: CRC32 instructions sshClient # [ 0.005206] CPU features: detected: Data cache clean to Point of Deep Persistence sshClient # [ 0.005210] CPU features: detected: Data cache clean to Point of Persistence sshClient # [ 0.005213] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.005216] CPU features: detected: E0PD sshClient # [ 0.005218] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.005221] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) sshClient # [ 0.005225] CPU features: detected: Enhanced Virtualization Traps sshClient # [ 0.005228] CPU features: detected: Fine Grained Traps sshClient # [ 0.005232] CPU features: detected: Generic authentication (architected QARMA5 algorithm) sshClient # [ 0.005237] CPU features: detected: RCpc load-acquire (LDAPR) sshClient # [ 0.005240] CPU features: detected: LSE atomic instructions sshClient # [ 0.005243] CPU features: detected: Privileged Access Never sshClient # [ 0.005246] CPU features: detected: PMUv3 sshClient # [ 0.005249] CPU features: detected: RAS Extension Support sshClient # [ 0.005252] CPU features: detected: RASv1p1 Extension Support sshClient # [ 0.005254] CPU features: detected: Random Number Generator sshClient # [ 0.005257] CPU features: detected: Speculation barrier (SB) sshClient # [ 0.005259] CPU features: detected: Stage-2 Force Write-Back sshClient # [ 0.005262] CPU features: detected: TLB range maintenance instructions sshClient # [ 0.005267] CPU features: detected: Speculative Store Bypassing Safe (SSBS) sshClient # [ 0.005309] alternatives: applying system-wide alternatives sshClient # [ 0.008359] CPU features: detected: BBM Level 2 without TLB conflict abort sshClient # [ 0.008601] Memory: 893736K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113504K reserved, 32768K cma-reserved) sshClient # [ 0.009003] devtmpfs: initialized sshClient # [ 0.010759] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.010782] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.010972] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL sshClient # [ 0.010976] 0 pages in range for non-PLT usage sshClient # [ 0.010977] 508224 pages in range for PLT usage sshClient # [ 0.011080] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.011884] DMI not present or invalid. sshClient # [ 0.016423] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.018931] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.019093] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.019265] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.019290] audit: initializing netlink subsys (disabled) sshClient # [ 0.019932] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.019934] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.019938] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.019941] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.019944] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.019973] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.019983] cpuidle: using governor ladder sshClient # [ 0.019988] cpuidle: using governor menu sshClient # [ 0.020210] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. sshClient # [ 0.020226] ASID allocator initialised with 65536 entries sshClient # [ 0.021477] Serial: AMBA PL011 UART driver sshClient # [ 0.027179] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.027356] printk: console [ttyAMA0] enabled sshClient # [ 0.155661] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages sshClient # [ 0.155671] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page sshClient # [ 0.155674] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages sshClient # [ 0.155677] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page sshClient # [ 0.155680] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.155682] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.155685] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages sshClient # [ 0.155688] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page sshClient # [ 0.156869] fbcon: Taking over console sshClient # [ 0.156880] ACPI: Interpreter disabled. sshClient # [ 0.157861] iommu: Default domain type: Translated sshClient # [ 0.157865] iommu: DMA domain TLB invalidation policy: strict mode sshClient # [ 0.158275] SCSI subsystem initialized sshClient # [ 0.158444] usbcore: registered new interface driver usbfs sshClient # [ 0.158474] usbcore: registered new interface driver hub sshClient # [ 0.158496] usbcore: registered new device driver usb sshClient # [ 0.158786] pps_core: LinuxPPS API ver. 1 registered sshClient # [ 0.158789] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.158805] PTP clock support registered sshClient # [ 0.158855] EDAC MC: Ver: 3.0.0 sshClient # [ 0.173627] scmi_core: SCMI protocol bus registered sshClient # [ 0.174050] FPGA manager framework sshClient # [ 0.177803] vgaarb: loaded sshClient # [ 0.178159] clocksource: Switched to clocksource arch_sys_counter sshClient # [ 0.184510] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.184557] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.187765] netfs: FS-Cache loaded sshClient # [ 0.187904] pnp: PnP ACPI: disabled sshClient # [ 0.192844] NET: Registered PF_INET protocol family sshClient # [ 0.193004] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.224363] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.224411] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.224436] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.224488] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.224560] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.224651] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) sshClient # [ 0.224718] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.224766] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.224843] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.224876] NET: Registered PF_XDP protocol family sshClient # [ 0.224893] PCI: CLS 0 bytes, default 64 sshClient # [ 0.225163] Trying to unpack rootfs image as initramfs... sshClient # [ 0.242340] kvm [1]: HYP mode not available sshClient # [ 0.354717] Initialise system trusted keyrings sshClient # [ 0.354861] workingset: timestamp_bits=42 max_order=18 bucket_order=0 sshClient # [ 0.355262] squashfs: version 4.0 (2009/01/31) Phillip Lougher sshClient # [ 0.355329] 9p: Installing v9fs 9p2000 file system support machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required sshClient # [ 0.383386] Key type asymmetric registered machine # [ 0.000000] psci: SMC Calling Convention v1.1 sshClient # [ 0.383403] Asymmetric key parser 'x509' registered machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) sshClient # [ 0.383484] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) sshClient # [ 0.383639] io scheduler mq-deadline registered machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 sshClient # [ 0.383643] io scheduler kyber registered 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/wqgq817fldswva01wys37cyff9a9l9vl-nixos-system-machine-test/init regInfo=/nix/.ro-store/qq4drd6h59ip06px0jzah413jaaa7k40-closure-info/registration console=ttyAMA0,115200n8 console=tty0 sshClient # [ 0.396077] pl061_gpio 9030000.pl061: PL061 GPIO chip registered sshClient # [ 0.396663] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qq4drd6h59ip06px0jzah413jaaa7k40-closure-info/registration", will be passed to user space. sshClient # [ 0.397033] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes sshClient # [ 0.397047] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.397057] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB sshClient # [ 0.397062] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.000000] software IO TLB: area num 1. sshClient # [ 0.397079] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 sshClient # [ 0.397097] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA sshClient # [ 0.397175] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.397189] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.397193] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext sshClient # [ 0.397196] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages sshClient # [ 0.397199] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. sshClient # [ 0.397261] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.000000] rcu: RCU event tracing is enabled. sshClient # [ 0.397702] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.397887] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. sshClient # [ 0.397899] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.000000] Tracing variant of Tasks RCU enabled. sshClient # [ 0.397925] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 sshClient # [ 0.397936] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) sshClient # [ 0.423323] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 sshClient # [ 0.423533] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 sshClient # [ 0.423548] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.423576] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.424070] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). sshClient # [ 0.424256] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] sshClient # [ 0.424268] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.424293] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns sshClient # [ 0.424754] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.000041] arm-pv: using stolen time PV sshClient # [ 0.424934] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.000807] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.424945] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.001004] Console: colour dummy device 80x25 sshClient # [ 0.424973] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.001012] printk: legacy console [tty0] enabled sshClient # [ 0.424984] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.001215] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.425455] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.001222] pid_max: default: 32768 minimum: 301 sshClient # [ 0.425639] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.001304] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001502] landlock: Up and running. sshClient # [ 0.425665] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.001505] Yama: becoming mindful. machine # [ 0.002340] LSM support for eBPF active sshClient # [ 0.426139] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.002499] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002518] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.004380] rcu: Hierarchical SRCU implementation. sshClient # [ 0.440630] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.004385] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.440656] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.005621] fsl-mc MSI: its@8080000 domain created machine # [ 0.005744] EFI services will not be available. sshClient # [ 0.441094] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.005857] smp: Bringing up secondary CPUs ... sshClient # [ 0.441273] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.005865] smp: Brought up 1 node, 1 CPU machine # [ 0.005868] SMP: Total of 1 processors activated. sshClient # [ 0.441520] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.005871] CPU: All CPU(s) started at EL1 sshClient # [ 0.441706] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.005885] CPU features: detected: Branch Target Identification sshClient # [ 0.441732] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.005890] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005893] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005897] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005900] CPU features: detected: Common not Private translations sshClient # [ 0.448234] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.005903] CPU features: detected: CRC32 instructions sshClient # [ 0.448418] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.005906] CPU features: detected: Data cache clean to Point of Deep Persistence sshClient # [ 0.448444] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.005910] CPU features: detected: Data cache clean to Point of Persistence sshClient # [ 0.448920] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.005913] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.449108] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.005916] CPU features: detected: E0PD sshClient # [ 0.449133] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.005918] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.449616] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.005921] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) sshClient # [ 0.449931] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.005925] CPU features: detected: Enhanced Virtualization Traps sshClient # [ 0.449943] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.005928] CPU features: detected: Fine Grained Traps sshClient # [ 0.449968] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.005931] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005936] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005939] CPU features: detected: LSE atomic instructions machine # [ 0.005941] CPU features: detected: Privileged Access Never machine # [ 0.005944] CPU features: detected: PMUv3 machine # [ 0.005947] CPU features: detected: RAS Extension Support machine # [ 0.005950] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005953] CPU features: detected: Random Number Generator machine # [ 0.005955] CPU features: detected: Speculation barrier (SB) machine # [ 0.005958] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005961] CPU features: detected: TLB range maintenance instructions machine # [ 0.005965] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.006009] alternatives: applying system-wide alternatives machine # [ 0.009037] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.009252] Memory: 893736K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113500K reserved, 32768K cma-reserved) machine # [ 0.009630] devtmpfs: initialized sshClient # [ 0.466582] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.011443] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.466779] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.466792] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.011466] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.011654] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL sshClient # [ 0.466817] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.011658] 0 pages in range for non-PLT usage sshClient # [ 0.467476] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.011659] 508224 pages in range for PLT usage machine # [ 0.011782] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.467482] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.012587] DMI not present or invalid. sshClient # [ 0.467485] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.016537] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.467536] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.018881] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.019042] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.467586] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.019205] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.467636] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned sshClient # [ 0.467683] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.019230] audit: initializing netlink subsys (disabled) sshClient # [ 0.467727] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.019849] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.019851] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.467771] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.019855] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.467814] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.019858] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.467857] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.019860] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.467900] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.019890] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.019898] cpuidle: using governor ladder sshClient # [ 0.468049] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.019904] cpuidle: using governor menu sshClient # [ 0.468092] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.020118] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.020134] ASID allocator initialised with 65536 entries sshClient # [ 0.468110] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.021461] Serial: AMBA PL011 UART driver sshClient # [ 0.468126] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.027276] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.468144] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.027419] printk: console [ttyAMA0] enabled sshClient # [ 0.468161] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.468178] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.468195] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned sshClient # [ 0.468214] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned sshClient # [ 0.468235] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned sshClient # [ 0.468252] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.468270] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.468288] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.468305] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned sshClient # [ 0.468321] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned sshClient # [ 0.468338] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned sshClient # [ 0.468355] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned sshClient # [ 0.468371] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.152184] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages sshClient # [ 0.468388] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.152195] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page sshClient # [ 0.468410] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.152198] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages sshClient # [ 0.468414] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.152201] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.152204] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.152207] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.468417] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] sshClient # [ 0.469270] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.152210] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.152212] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.153396] fbcon: Taking over console machine # [ 0.153405] ACPI: Interpreter disabled. machine # [ 0.163198] iommu: Default domain type: Translated machine # [ 0.163204] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.163635] SCSI subsystem initialized machine # [ 0.163859] usbcore: registered new interface driver usbfs machine # [ 0.163886] usbcore: registered new interface driver hub machine # [ 0.167047] usbcore: registered new device driver usb machine # [ 0.167489] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.167493] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.167501] PTP clock support registered machine # [ 0.167542] EDAC MC: Ver: 3.0.0 machine # [ 0.171181] scmi_core: SCMI protocol bus registered machine # [ 0.172669] FPGA manager framework machine # [ 0.175953] vgaarb: loaded machine # [ 0.177026] clocksource: Switched to clocksource arch_sys_counter sshClient # [ 0.524311] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.182992] VFS: Disk quotas dquot_6.6.0 machine # [ 0.183021] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.186620] netfs: FS-Cache loaded machine # [ 0.186750] pnp: PnP ACPI: disabled machine # [ 0.190964] NET: Registered PF_INET protocol family machine # [ 0.191127] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.535172] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) sshClient # [ 0.537910] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.549244] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) sshClient # [ 0.552227] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) sshClient # [ 0.562413] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) sshClient # [ 0.564559] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.222281] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.222326] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.222353] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.222401] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.222473] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.222600] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.222633] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.222658] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.222757] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.222778] NET: Registered PF_XDP protocol family machine # [ 0.222795] PCI: CLS 0 bytes, default 64 sshClient # [ 0.574409] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.223072] Trying to unpack rootfs image as initramfs... sshClient # [ 0.576982] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.235195] kvm [1]: HYP mode not available sshClient # [ 0.580565] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) sshClient # [ 0.591775] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) sshClient # [ 0.597762] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.607399] msm_serial: driver initialized sshClient # [ 0.607537] SuperH (H)SCI(F) driver initialized sshClient # [ 0.607593] STM32 USART driver initialized sshClient # [ 0.636963] loop: module loaded sshClient # [ 0.637160] virtio_blk virtio2: 1/0/0 default/read/poll queues sshClient # [ 0.637998] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 0.650919] megasas: 07.734.00.00-rc1 sshClient # [ 0.651683] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] sshClient # [ 0.653960] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.653979] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.659042] Using buffer write method sshClient # [ 0.659112] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] sshClient # [ 0.660834] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.660879] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.674843] Using buffer write method sshClient # [ 0.674870] Concatenating MTD devices: sshClient # [ 0.674872] (0): "0.flash" sshClient # [ 0.674875] (1): "0.flash" sshClient # [ 0.674877] into device "0.flash" machine # [ 0.360970] Initialise system trusted keyrings machine # [ 0.361745] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.362184] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.362242] 9p: Installing v9fs 9p2000 file system support machine # [ 0.390333] Key type asymmetric registered machine # [ 0.390353] Asymmetric key parser 'x509' registered machine # [ 0.390433] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.390559] io scheduler mq-deadline registered machine # [ 0.390563] io scheduler kyber registered machine # [ 0.402953] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.403545] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.403904] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.403918] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.403928] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.403934] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.403957] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.403976] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.404047] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.404054] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.404058] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.404061] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.404064] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.404128] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.404556] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.404739] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.404751] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.404776] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.404787] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.429351] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.429546] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.429559] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.429584] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.430074] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.430251] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.430263] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.430288] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.430752] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.430928] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.430940] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.430964] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.430976] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.431428] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.431611] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.431636] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.432078] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.432256] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.432281] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.432656] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.432827] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.450219] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.450400] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.450425] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.450880] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.451063] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.451088] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.451533] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.451714] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.451739] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.452193] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.452487] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.452498] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.452522] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.452976] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.469223] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.469241] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.469267] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.469955] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.469961] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.469964] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.470010] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.470053] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.470096] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.470139] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.470183] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.470226] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.470269] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.470312] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.470354] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.470465] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.470507] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.470524] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.470542] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.470558] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.470575] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.470592] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.470610] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.470629] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.470650] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.470668] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.470687] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.470705] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.470721] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.470738] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.470755] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.470772] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.470790] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.470807] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.470829] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.470834] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.470837] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.471666] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.532604] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.535771] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.546183] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.548194] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.551844] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.562056] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.563857] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.567449] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.577316] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.579240] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.581527] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.594356] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.596198] msm_serial: driver initialized machine # [ 0.596364] SuperH (H)SCI(F) driver initialized machine # [ 0.596420] STM32 USART driver initialized sshClient # [ 0.945595] Freeing initrd memory: 26392K sshClient # [ 0.951766] tun: Universal TUN/TAP device driver, 1.6 sshClient # [ 0.955731] thunder_xcv, ver 1.0 sshClient # [ 0.955760] thunder_bgx, ver 1.0 sshClient # [ 0.955785] nicpf, ver 1.0 sshClient # [ 0.956364] e1000: Intel(R) PRO/1000 Network Driver sshClient # [ 0.956368] e1000: Copyright (c) 1999-2006 Intel Corporation. sshClient # [ 0.956392] e1000e: Intel(R) PRO/1000 Network Driver sshClient # [ 0.956399] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. sshClient # [ 0.956421] igb: Intel(R) Gigabit Ethernet Network Driver sshClient # [ 0.956425] igb: Copyright (c) 2007-2014 Intel Corporation. sshClient # [ 0.956444] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 0.956447] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. sshClient # [ 0.956583] sky2: driver version 1.30 sshClient # [ 0.958942] ehci-pci 0000:00:07.0: EHCI Host Controller sshClient # [ 0.958961] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 sshClient # [ 0.959230] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 sshClient # [ 0.969240] usbcore: registered new interface driver usb-storage sshClient # [ 0.969333] usbcore: registered new interface driver usbserial_generic sshClient # [ 0.969344] usbserial: USB Serial support registered for generic sshClient # [ 0.969975] hv_vmbus: registering driver hyperv_keyboard sshClient # [ 0.973182] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 sshClient # [ 0.973513] hub 1-0:1.0: USB hub found sshClient # [ 0.973541] hub 1-0:1.0: 6 ports detected sshClient # [ 0.976444] rtc-pl031 9010000.pl031: registered as rtc0 sshClient # [ 0.976465] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T02:50:44 UTC (1790218244) sshClient # [ 0.976775] i2c_dev: i2c /dev entries driver machine # [ 0.637302] loop: module loaded machine # [ 0.637507] virtio_blk virtio2: 1/0/0 default/read/poll queues sshClient # [ 0.982676] sdhci: Secure Digital Host Controller Interface driver sshClient # [ 0.982683] sdhci: Copyright(c) Pierre Ossman machine # [ 0.638604] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 0.982966] Synopsys Designware Multimedia Card Interface Driver sshClient # [ 0.983345] sdhci-pltfm: SDHCI platform and OF driver helper sshClient # [ 0.984911] hid: raw HID events driver (C) Jiri Kosina sshClient # [ 0.985148] usbcore: registered new interface driver usbhid sshClient # [ 0.985156] usbhid: USB HID core driver machine # [ 0.643943] megasas: 07.734.00.00-rc1 sshClient # [ 0.990982] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.644707] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] sshClient # [ 0.992589] drop_monitor: Initializing network drop monitor service sshClient # [ 0.992806] NET: Registered PF_INET6 protocol family sshClient # [ 0.993368] Segment Routing with IPv6 sshClient # [ 0.993379] In-situ OAM (IOAM) with IPv6 sshClient # [ 0.993408] NET: Registered PF_PACKET protocol family sshClient # [ 0.993467] 9pnet: Installing 9P2000 support sshClient # [ 0.993503] Key type dns_resolver registered machine # [ 0.658277] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 1.004719] registered taskstats version 1 machine # [ 0.658299] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 1.004864] Loading compiled-in X.509 certificates machine # [ 0.659900] Using buffer write method machine # [ 0.659940] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] sshClient # [ 1.013687] Demotion targets for Node 0: null sshClient # [ 1.013793] Key type .fscrypt registered sshClient # [ 1.013803] Key type fscrypt-provisioning registered sshClient # [ 1.013899] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 1.013916] ima: Allocated hash algorithm: sha1 sshClient # [ 1.013937] ima: No architecture policies found machine # [ 0.670826] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.670883] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.672575] Using buffer write method machine # [ 0.672591] Concatenating MTD devices: machine # [ 0.672593] (0): "0.flash" machine # [ 0.672595] (1): "0.flash" machine # [ 0.672597] into device "0.flash" sshClient # [ 1.018268] input: gpio-keys as /devices/platform/gpio-keys/input/input0 sshClient # [ 1.036972] clk: Disabling unused clocks sshClient # [ 1.036990] PM: genpd: Disabling unused power domains sshClient # [ 1.041238] Freeing unused kernel memory: 4736K sshClient # [ 1.041429] Run /init as init process sshClient # [ 1.052514] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.218261] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 0.960732] Freeing initrd memory: 26388K machine # [ 0.967176] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.971086] thunder_xcv, ver 1.0 machine # [ 0.971117] thunder_bgx, ver 1.0 machine # [ 0.971140] nicpf, ver 1.0 machine # [ 0.971693] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.971697] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.971721] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.971727] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.971753] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.971756] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.971776] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.971780] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.971913] sky2: driver version 1.30 machine # [ 0.980856] usbcore: registered new interface driver usb-storage machine # [ 0.980941] usbcore: registered new interface driver usbserial_generic machine # [ 0.980952] usbserial: USB Serial support registered for generic machine # [ 0.981766] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 0.981785] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 0.982076] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 0.987321] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.988231] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.988256] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T02:50:45 UTC (1790218245) machine # [ 0.988569] i2c_dev: i2c /dev entries driver machine # [ 0.994911] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.994918] sdhci: Copyright(c) Pierre Ossman machine # [ 0.995201] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.995585] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.999817] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 1.000133] hub 1-0:1.0: USB hub found machine # [ 1.000185] hub 1-0:1.0: 6 ports detected machine # [ 1.001004] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.003610] usbcore: registered new interface driver usbhid machine # [ 1.003620] usbhid: USB HID core driver machine # [ 1.004805] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.008706] drop_monitor: Initializing network drop monitor service machine # [ 1.008840] NET: Registered PF_INET6 protocol family machine # [ 1.011583] Segment Routing with IPv6 machine # [ 1.011596] In-situ OAM (IOAM) with IPv6 machine # [ 1.011634] NET: Registered PF_PACKET protocol family machine # [ 1.011703] 9pnet: Installing 9P2000 support machine # [ 1.011743] Key type dns_resolver registered machine # [ 1.020982] registered taskstats version 1 machine # [ 1.022188] Loading compiled-in X.509 certificates machine # [ 1.029826] Demotion targets for Node 0: null machine # [ 1.029938] Key type .fscrypt registered machine # [ 1.029944] Key type fscrypt-provisioning registered sshClient # [ 1.370692] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.030038] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.030055] ima: Allocated hash algorithm: sha1 machine # [ 1.030076] ima: No architecture policies found machine # [ 1.030783] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.053150] clk: Disabling unused clocks sshClient # [ 1.386994] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.053168] PM: genpd: Disabling unused power domains machine # [ 1.056198] Freeing unused kernel memory: 4736K machine # [ 1.056407] Run /init as init process sshClient # [ 1.387042] systemd[1]: Detected virtualization qemu. sshClient # [ 1.387162] systemd[1]: Detected architecture arm64. sshClient # [ 1.387178] systemd[1]: Running in initrd. sshClient # [ 1.388148] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.388526] systemd[1]: Hostname set to . machine # [ 1.075456] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.462751] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 sshClient # [ 1.571832] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.586234] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.245130] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 1.627260] systemd[1]: Queued start job for default target Initrd Default Target. sshClient # [ 1.636177] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.636350] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.636375] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.636392] systemd[1]: Reached target Path Units. sshClient # [ 1.636405] systemd[1]: Reached target Slice Units. sshClient # [ 1.636418] systemd[1]: Reached target Swaps. sshClient # [ 1.636431] systemd[1]: Reached target Timer Units. sshClient # [ 1.636554] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.636691] systemd[1]: Listening on Journal Socket (/dev/log). sshClient # [ 1.636828] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.636897] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.636910] systemd[1]: Reached target Socket Units. sshClient # [ 1.638267] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.638326] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 1.658325] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.663687] systemd[1]: Starting Journal Service... sshClient # [ 1.681678] systemd[1]: Starting Load Kernel Modules... sshClient # [ 1.681796] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 1.702688] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.707636] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 1.708383] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 1.732821] systemd-journald[71]: Collecting audit messages is disabled. sshClient # [ 1.735597] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.397276] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 sshClient # [ 1.747192] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.753653] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 sshClient # [ 1.753779] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 sshClient # [ 1.753953] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.409476] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.409519] systemd[1]: Detected virtualization qemu. machine # [ 1.409642] systemd[1]: Detected architecture arm64. sshClient # [ 1.773754] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.409659] systemd[1]: Running in initrd. sshClient # [ 1.773987] [drm] features: -virgl +edid -resource_blob -host_visible sshClient # [ 1.773991] [drm] features: -context_init machine # [ 1.410632] systemd[1]: Initializing machine ID from random generator. machine # [ 1.410899] systemd[1]: Hostname set to . sshClient # [ 1.790885] [drm] number of scanouts: 1 sshClient # [ 1.790904] [drm] number of cap sets: 0 sshClient # [ 1.791998] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic sshClient # [ 1.792009] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 sshClient # [ 1.797213] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.800119] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 1.818502] Console: switching to colour frame buffer device 160x50 machine # [ 1.489290] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 sshClient # [ 1.850855] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device sshClient # [ 1.862890] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.863302] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.863333] systemd[1]: Reached target Local File Systems. sshClient # [ 1.868970] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.886298] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.889078] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 1.902781] systemd-modules-load[72]: Inserted module 'dm_mod' sshClient # [ 1.905719] systemd-modules-load[72]: Module 'virtio_balloon' is built in sshClient # [ 1.922399] systemd[1]: Started Journal Service. machine # [ 1.581430] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.916419] systemd-modules-load[72]: Module 'virtio_console' is built in sshClient # [ 1.925272] systemd-modules-load[72]: Inserted module 'virtio_gpu' sshClient # [ 1.929526] systemd-modules-load[72]: Module 'virtio_rng' is built in sshClient # [ 1.932283] systemd[1]: Starting Create System Files and Directories... machine # [ 1.609114] usb 1-2: new high-speed USB device number 3 using ehci-pci sshClient # [ 1.940417] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.636941] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.646112] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.646310] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.646337] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.977590] systemd-udevd[78]: Using default interface naming scheme 'v261'. machine # [ 1.646360] systemd[1]: Reached target Path Units. machine # [ 1.646372] systemd[1]: Reached target Slice Units. machine # [ 1.646386] systemd[1]: Reached target Swaps. machine # [ 1.646401] systemd[1]: Reached target Timer Units. machine # [ 1.646541] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.646692] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.646819] systemd[1]: Listening on Journal Sockets. machine # [ 1.646892] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.646906] systemd[1]: Reached target Socket Units. machine # [ 1.648306] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.984610] systemd[1]: Finished Create System Files and Directories. machine # [ 1.648372] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.666301] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.677209] systemd[1]: Starting Journal Service... sshClient # [ 2.007394] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.689594] systemd[1]: Starting Load Kernel Modules... machine # [ 1.689744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.717296] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.723107] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.733333] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 2.089914] systemd[1]: Starting Virtual Console Setup... machine # [ 1.771664] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.772414] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.775435] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.775593] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 machine # [ 1.826205] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.837884] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.839813] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 2.169539] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.842994] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 2.172550] systemd[1]: Finished Virtual Console Setup. machine # [ 1.861928] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.862164] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.862168] [drm] features: -context_init machine # [ 1.862906] [drm] number of scanouts: 1 machine # [ 1.862917] [drm] number of cap sets: 0 machine # [ 1.881426] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.881448] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.905868] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.906082] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.906106] systemd[1]: Reached target Local File Systems. machine # [ 1.907973] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.913454] Console: switching to colour frame buffer device 160x50 machine # [ 1.903158] systemd-modules-load[73]: Inserted module 'dm_mod' machine # [ 1.910273] systemd-modules-load[73]: Module 'virtio_balloon' is built in machine # [ 1.911925] systemd-modules-load[73]: Module 'virtio_console' is built in[ 1.925401] systemd[1]: Started Journal Service. machine # machine # [ 1.926138] systemd[1]: Starting Create System Files and Directories... machine # [ 1.941722] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.936829] systemd-modules-load[73]: Inserted module 'virtio_gpu' machine # [ 1.938828] systemd-modules-load[73]: Module 'virtio_rng' is built in machine # [ 1.949215] systemd[1]: Finished Load Kernel Modules. machine # [ 1.980202] systemd[1]: Starting Apply Kernel Variables... machine # [ 2.025020] systemd[1]: Finished Create System Files and Directories. machine # [ 2.027835] systemd-udevd[78]: Using default interface naming scheme 'v261'. machine # [ 2.039796] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.061796] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.136956] systemd[1]: Starting Virtual Console Setup... machine # [ 2.216565] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.220243] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.772454] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 2.774270] systemd[1]: Reached target System Initialization. sshClient # [ 2.780838] systemd[1]: Reached target Basic System. sshClient # [ 2.949951] (udev-worker)[98]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.953969] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 3.015427] systemd[1]: Found device /dev/disk/by-label/nixos. sshClient # [ 3.020080] systemd[1]: Reached target Initrd Root Device. sshClient # [ 3.023653] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 3.077627] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.812339] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.813485] systemd[1]: Reached target System Initialization. machine # [ 2.814336] systemd[1]: Reached target Basic System. sshClient # [ 3.204292] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 3.216211] systemd[1]: Mounting /sysroot... machine # [ 2.963955] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 3.009025] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.059581] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.062142] systemd[1]: Reached target Initrd Root Device. machine # [ 3.068386] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 3.469239] EXT4-fs (vda): mounted filesystem f4329853-74ce-4408-808f-d2de807d141b r/w with ordered data mode. Quota mode: none. sshClient # [ 3.456111] systemd[1]: Mounted /sysroot. sshClient # [ 3.459170] systemd[1]: Reached target Initrd Root File System. sshClient # [ 3.465035] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.125024] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 3.500410] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. sshClient # [ 3.506948] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 3.512876] systemd[1]: Reloading... machine # [ 3.313386] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.320873] systemd[1]: Mounting /sysroot... sshClient # [ 3.719270] systemd[1]: Reloading finished in 209 ms. sshClient # [ 3.750236] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.754886] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... sshClient # [ 3.760374] systemd[1]: Mounting /sysroot/nix/.ro-store... sshClient # [ 3.780292] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.784917] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.801107] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.486940] EXT4-fs (vda): mounted filesystem 313095f8-9271-440c-b058-9bbbdd1fbe6e r/w with ordered data mode. Quota mode: none. machine # [ 3.475867] systemd[1]: Mounted /sysroot. machine # [ 3.476868] systemd[1]: Reached target Initrd Root File System. machine # [ 3.488181] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 3.834839] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 3.850531] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.508391] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.514946] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.520241] systemd[1]: Reloading... sshClient # [ 3.876722] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 3.878994] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 3.921091] fuse: init (API version 7.45) sshClient # [ 3.931824] virtiofs virtio6: discovered new tag: nix-store sshClient # [ 3.932613] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 3.948008] virtiofs virtio7: discovered new tag: shared sshClient # [ 3.948843] virtiofs virtio7: virtio_fs_setup_dax: No cache capability sshClient # [ 3.956600] virtiofs virtio8: discovered new tag: xchg sshClient # [ 3.957488] virtiofs virtio8: virtio_fs_setup_dax: No cache capability sshClient # [ 3.945966] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 3.950562] systemd[1]: Mounted /sysroot/run. sshClient # [ 3.966289] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 3.970297] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 3.978227] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 3.991262] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.014499] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.017718] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.021014] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.725317] systemd[1]: Reloading finished in 207 ms. sshClient # [ 4.072799] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 4.074931] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.076659] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.087528] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.757954] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... machine # [ 3.762666] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... machine # [ 3.770553] systemd[1]: Mounting /sysroot/nix/.ro-store... sshClient # [ 4.129953] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.787662] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 4.141392] systemd[1]: Finished Find NixOS closure. machine # [ 3.798677] systemd[1]: Mounting /sysroot/run... sshClient # [ 4.145374] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.149478] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.819663] systemd[1]: Mounting /sysroot/tmp/shared... sshClient # [ 4.183618] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 4.188287] systemd[1]: Stopped target Basic System. sshClient # [ 4.189878] systemd[1]: Stopped target Initrd Root Device. sshClient # [ 4.193435] systemd[1]: Stopped target Path Units. sshClient # [ 4.196335] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.855576] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 4.199601] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. sshClient # [ 4.205721] systemd[1]: Stopped target Slice Units. sshClient # [ 4.206513] systemd[1]: Stopped target Socket Units. sshClient # [ 4.207236] systemd[1]: Stopped target System Initialization. sshClient # [ 4.213377] systemd[1]: Stopped target Swaps. sshClient # [ 4.214775] systemd[1]: Stopped target Timer Units. sshClient # [ 4.219176] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.222018] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.222915] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.889527] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 4.233088] systemd[1]: Stopped Find NixOS closure. sshClient # [ 4.233961] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.234967] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 4.235960] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.894063] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.896335] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 4.247344] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 4.252603] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.911694] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 4.257477] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 4.259198] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 4.261801] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.262893] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 4.263818] systemd[1]: Stopped target Local File Systems. sshClient # [ 4.264791] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 4.265759] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.266776] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.939142] fuse: init (API version 7.45) sshClient # [ 4.267567] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.274256] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.949535] virtiofs virtio6: discovered new tag: nix-store sshClient # [ 4.280450] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.950442] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 4.281320] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 4.282275] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 4.283209] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.285551] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.286413] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.287390] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.288691] systemd[1]: systemd-udevd.service: Consumed 1.187s CPU time over 2.421s wall clock time, 17.7M memory peak. sshClient # [ 4.290208] systemd[1]: Starting Cleanup udev Database... sshClient # [ 4.291050] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.296268] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.968965] virtiofs virtio7: discovered new tag: shared sshClient # [ 4.297264] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.299708] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.974484] virtiofs virtio7: virtio_fs_setup_dax: No cache capability sshClient # [ 4.304284] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.305350] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.964046] systemd[1]: Mounted /sysroot/run. machine # [ 3.983992] virtiofs virtio8: discovered new tag: xchg machine # [ 3.984806] virtiofs virtio8: virtio_fs_setup_dax: No cache capability sshClient # [ 4.333189] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.992955] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.336537] systemd[1]: Finished Cleanup udev Database. sshClient # [ 4.337775] systemd[1]: Reached target Switch Root. machine # [ 3.997076] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 4.340337] systemd[1]: Starting NixOS Activation... machine # [ 3.999151] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 4.001396] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 4.030631] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.033848] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.036167] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.089233] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.090132] systemd[1]: Reached target Initrd File Systems. machine # [ 4.092430] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.435196] initrd-nixos-activation-start[166]: booting system configuration /nix/store/5rkl1zpvxpi0gfiqglh9l3x1vzj96pry-nixos-system-sshClient-test machine # [ 4.098254] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.472258] initrd-nixos-activation-start[166]: running activation script... machine # [ 4.143421] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.161068] systemd[1]: Finished Find NixOS closure. machine # [ 4.164110] systemd[1]: Reached target Initrd Default Target. machine # [ 4.177158] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.205383] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.208104] systemd[1]: Stopped target Basic System. machine # [ 4.210387] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.212918] systemd[1]: Stopped target Path Units. machine # [ 4.216762] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.220114] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.225655] systemd[1]: Stopped target Slice Units. machine # [ 4.227653] systemd[1]: Stopped target Socket Units. machine # [ 4.228567] systemd[1]: Stopped target System Initialization. machine # [ 4.232274] systemd[1]: Stopped target Swaps. machine # [ 4.234120] systemd[1]: Stopped target Timer Units. machine # [ 4.237044] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.240899] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.244192] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.248155] systemd[1]: Stopped Find NixOS closure. machine # [ 4.250707] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.255108] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.257430] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.258327] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.259463] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.269292] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.270542] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.279473] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.284477] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.285992] systemd[1]: Stopped target Local File Systems. machine # [ 4.288222] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.290332] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.294142] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.295130] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.298698] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.299813] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.300792] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.301825] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.302818] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.303892] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.310084] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.311150] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.316570] systemd[1]: systemd-udevd.service: Consumed 1.174s CPU time over 2.417s wall clock time, 17.1M memory peak. machine # [ 4.318093] systemd[1]: Starting Cleanup udev Database... machine # [ 4.318898] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.319995] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.322886] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.324222] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.325271] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.326271] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.362833] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.366822] systemd[1]: Finished Cleanup udev Database. machine # [ 4.367751] systemd[1]: Reached target Switch Root. machine # [ 4.369742] systemd[1]: Starting NixOS Activation... sshClient # [ 4.722782] initrd-nixos-activation-start[189]: setting up /etc... machine # [ 4.465075] initrd-nixos-activation-start[167]: booting system configuration /nix/store/wqgq817fldswva01wys37cyff9a9l9vl-nixos-system-machine-test machine # [ 4.503456] initrd-nixos-activation-start[167]: running activation script... sshClient # [ 4.857081] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 4.860329] systemd[1]: Finished NixOS Activation. sshClient # [ 4.864430] systemd[1]: Starting Switch Root... sshClient # [ 4.889102] systemd[1]: Switching root. sshClient # [ 5.076202] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 4.759736] initrd-nixos-activation-start[190]: setting up /etc... machine # [ 4.890879] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.893914] systemd[1]: Finished NixOS Activation. machine # [ 4.895216] systemd[1]: Starting Switch Root... machine # [ 4.921095] systemd[1]: Switching root. machine # [ 5.106813] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). sshClient # [ 5.601783] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) sshClient # [ 5.601899] systemd[1]: Detected virtualization qemu. sshClient # [ 5.601983] systemd[1]: Detected architecture arm64. sshClient # [ 5.618990] systemd[1]: Detected first boot. sshClient # [ 5.621889] systemd[1]: Initializing machine ID from random generator. sshClient # [ 5.845666] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.629631] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 5.629800] systemd[1]: Detected virtualization qemu. machine # [ 5.629886] systemd[1]: Detected architecture arm64. machine # [ 5.630112] systemd[1]: Detected first boot. machine # [ 5.650002] systemd[1]: Initializing machine ID from random generator. sshClient # [ 6.056666] systemd[1]: Applying preset policy. machine # [ 5.773152] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.947956] NET: Registered PF_VSOCK protocol family machine # [ 5.956226] Guest personality initialized and is inactive machine # [ 5.959527] VMCI host device registered (name=vmci, major=10, minor=261) machine # [ 5.959555] Initialized host personality sshClient # [ 6.353613] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.019576] systemd[1]: Applying preset policy. sshClient # [ 6.602572] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.603459] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 6.608137] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.609829] systemd[1]: Created slice Slice /system/getty. sshClient # [ 6.614461] systemd[1]: Created slice User and Session Slice. sshClient # [ 6.615055] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.616262] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.616977] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 6.617821] systemd[1]: Expecting device /dev/ttyAMA0... sshClient # [ 6.623790] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 6.624260] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 6.625245] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 6.629192] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 6.629592] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.633006] systemd[1]: Reached target Path Units. sshClient # [ 6.633396] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.636623] systemd[1]: Reached target Slice Units. sshClient # [ 6.637017] systemd[1]: Reached target Swaps. machine # [ 6.296007] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 6.641359] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 6.645147] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.648140] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 6.649567] systemd[1]: Listening on Factory Reset Management. sshClient # [ 6.650134] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 6.657180] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 6.660080] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.662123] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 6.664529] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 6.665219] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.666077] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki sshClient # [ 6.675555] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 6.676268] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 6.677223] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 6.683637] systemd[1]: Mounting Huge Pages File System... sshClient # [ 6.687883] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.702126] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 6.716216] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.729157] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.734446] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 6.753475] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.762730] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.763404] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.771376] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 6.792835] systemd[1]: Mounting FUSE Control File System... sshClient # [ 6.807090] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 6.842123] systemd[1]: Starting Journal Service... sshClient # [ 6.871069] systemd[1]: Starting Load Kernel Modules... machine # [ 6.541584] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.542567] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.547508] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.550881] systemd[1]: Created slice Slice /system/getty. machine # [ 6.552341] systemd[1]: Created slice User and Session Slice. machine # [ 6.552957] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.901677] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.557428] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.557934] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.558929] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.559893] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.560879] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.566227] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.567235] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.568309] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.572655] systemd[1]: Reached target Path Units. machine # [ 6.573888] systemd[1]: Reached target Remote File Systems. machine # [ 6.574901] systemd[1]: Reached target Slice Units. machine # [ 6.575924] systemd[1]: Reached target Swaps. machine # [ 6.581414] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.584106] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.926989] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 6.927538] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.588272] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.591704] systemd[1]: Listening on Factory Reset Management. machine # [ 6.592403] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.599602] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 6.945431] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.602356] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.604974] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.608057] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.612052] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.612434] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.620758] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.622488] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.623545] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 6.971172] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 6.971890] systemd[1]: Mounted Huge Pages File System. sshClient # [ 6.972492] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 6.973097] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.629217] systemd[1]: Mounting Huge Pages File System... sshClient # [ 6.973621] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.632399] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.980575] systemd-journald[261]: Collecting audit messages is enabled. sshClient # [ 6.971564] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 6.978165] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.649978] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 7.000321] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 7.001203] systemd[1]: Started Journal Service. machine # [ 6.659820] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.994458] systemd-modules-load[262]: Module 'atkbd' is built in sshClient # [ 6.995807] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 7.008682] systemd-modules-load[262]: Module 'loop' is built in machine # [ 6.681201] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.681896] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 7.025453] systemd[1]: Finished Load Kernel Modules. machine # [ 6.701321] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.703006] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.703543] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.713005] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 7.050726] systemd[1]: Starting Firewall... sshClient # [ 7.063742] systemd[1]: Starting Apply Kernel Variables... machine # [ 6.743021] systemd[1]: Mounting FUSE Control File System... machine # [ 6.743529] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 7.095000] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 6.776553] systemd[1]: Starting Journal Service... sshClient # [ 7.104150] systemd[1]: Mounted FUSE Control File System. sshClient # [ 7.126311] EXT4-fs (vda): re-mounted f4329853-74ce-4408-808f-d2de807d141b. sshClient # [ 7.133121] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.816670] systemd[1]: Starting Load Kernel Modules... sshClient # [ 7.152575] systemd-oomd[264]: No swap; memory pressure usage will be degraded sshClient # [ 7.154084] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.844123] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 7.172485] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 7.173612] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 7.182870] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 7.183915] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.198389] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.877958] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.878429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.904719] systemd-journald[263]: Collecting audit messages is enabled. machine # [ 6.915841] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.923847] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.924405] systemd[1]: Mounted Huge Pages File System. machine # [ 6.924983] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.914836] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.916467] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.943661] systemd[1]: Started Journal Service. machine # [ 6.937436] systemd-modules-load[264]: Module 'atkbd' is built in machine # [ 6.944243] systemd-modules-load[264]: Module 'loop' is built in machine # [ 6.947877] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.951454] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 7.315502] systemd-journald[261]: Received client request to flush runtime journal. machine # [ 6.963872] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.973284] systemd[1]: Finished Load Kernel Modules. machine # [ 7.011911] systemd-oomd[265]: No swap; memory pressure usage will be degraded machine # [ 7.021164] systemd[1]: Starting Firewall... sshClient # [ 7.371248] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.041218] EXT4-fs (vda): re-mounted 313095f8-9271-440c-b058-9bbbdd1fbe6e. sshClient # [ 7.377484] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 7.380473] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.040506] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.062472] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.068261] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.071304] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.075335] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.101069] systemd[1]: Mounted FUSE Control File System. machine # [ 7.116214] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.136095] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.137329] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.155602] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.160392] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.513157] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.517790] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.249162] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.282396] systemd-journald[263]: Received client request to flush runtime journal. machine # [ 7.329513] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.338020] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 7.745856] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 7.752593] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 7.756230] systemd[1]: Mounting /run/wrappers... sshClient # [ 7.764276] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.433125] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.441941] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 7.891691] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.893234] systemd[1]: Reached target Local File Systems. sshClient # [ 7.901687] systemd[1]: Listening on Boot Loader Control Service Socket. sshClient # [ 7.924434] systemd[1]: Starting Create SUID/SGID Wrappers... sshClient # [ 7.925554] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 7.937378] systemd[1]: Starting Create System Files and Directories... machine # [ 7.650350] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.655630] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.661952] systemd[1]: Mounting /run/wrappers... machine # [ 7.673873] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.797873] systemd[1]: Mounted /run/wrappers. machine # [ 7.798802] systemd[1]: Reached target Local File Systems. machine # [ 7.808306] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.814047] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.815044] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.823861] systemd[1]: Starting Create System Files and Directories... sshClient # [ 8.167528] systemd-udevd[304]: Using default interface naming scheme 'v261'. sshClient # [ 8.242375] systemd[1]: Starting User Database Manager... sshClient # [ 8.248148] systemd[1]: Finished Create System Files and Directories. sshClient # [ 8.264351] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 8.292596] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.071253] systemd-udevd[306]: Using default interface naming scheme 'v261'. machine # [ 8.133480] systemd[1]: Starting User Database Manager... machine # [ 8.164205] systemd[1]: Finished Create System Files and Directories. machine # [ 8.186894] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 8.545368] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.207123] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 8.560802] systemd[1]: Started User Database Manager. sshClient # [ 8.570609] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 8.590504] systemd[1]: Starting Update is Completed... sshClient # [ 8.745943] systemd[1]: Finished Update is Completed. machine # [ 8.444407] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.449776] systemd[1]: Started User Database Manager. machine # [ 8.502847] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.517116] systemd[1]: Starting Update is Completed... sshClient # [ 8.872227] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.677912] systemd[1]: Finished Update is Completed. sshClient # [ 9.149074] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.814006] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 9.721954] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 9.731736] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 9.737316] systemd[1]: Reached target System Initialization. sshClient # [ 9.741364] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 9.742494] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 9.743423] systemd[1]: Reached target Timer Units. sshClient # [ 9.758352] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 9.765117] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 9.766670] systemd[1]: Reached target Socket Units. sshClient # [ 9.777082] systemd[1]: Reached target Basic System. sshClient # [ 9.782669] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 9.806749] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 9.841042] systemd[1]: Starting Post-Boot Actions... sshClient # [ 9.884710] systemd[1]: Started Reset console on configuration changes. sshClient # [ 9.956271] systemd[1]: Starting resolvconf update... sshClient # [ 10.100131] systemd[1]: Starting Home Area Manager... sshClient # [ 10.334648] nsncd[437]: Sep 24 02:50:53.871 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.036260] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 10.412668] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 10.422107] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 10.439815] systemd[1]: Finished Post-Boot Actions. sshClient # [ 10.464910] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 10.466598] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 10.683236] systemd[1]: Starting User Login Management... sshClient # [ 10.975356] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 11.009273] systemd-homed[443]: Watching /home. machine # [ 10.697211] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 10.701869] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 10.706896] systemd[1]: Reached target System Initialization. machine # [ 10.707912] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.711929] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.719792] systemd[1]: Reached target Timer Units. machine # [ 10.722156] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.723120] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine # [ 10.731386] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.736885] systemd[1]: Reached target Socket Units. machine # [ 10.737722] systemd[1]: Reached target Basic System. machine # [ 10.738511] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.748186] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.754896] systemd[1]: Starting Post-Boot Actions... machine # [ 10.777539] systemd[1]: Started Reset console on configuration changes. sshClient # [ 11.184497] dbus-broker-launch[462]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 11.193860] dbus-broker-launch[462]: NSS returned no entry for 'systemd-timesync' sshClient # [ 11.195062] dbus-broker-launch[462]: Invalid user-name in /nix/store/srzcs2blg5a1pa0b5x1chg2br8i4igk4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.855342] systemd[1]: Starting resolvconf update... machine # [ 10.871704] systemd[1]: Starting SSH Host Keys Generation... sshClient # [ 11.249462] systemd-logind[469]: New seat seat0. sshClient # [ 11.251968] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 11.263316] systemd[1]: Started User Login Management. sshClient # [ 11.273522] systemd[1]: Starting linger-users.service... machine # [ 10.949211] systemd[1]: Starting Home Area Manager... sshClient # [ 11.307548] dbus-broker-launch[462]: Ready sshClient # [ 11.322045] systemd[1]: Started Home Area Manager. sshClient # [ 11.332557] systemd[1]: Finished Home Area Activation. sshClient # [ 11.344053] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 11.019857] nsncd[445]: Sep 24 02:50:55.547 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.033278] systemd[1]: Finished Post-Boot Actions. machine # [ 11.034173] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 11.406777] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 11.418572] systemd[1]: Finished linger-users.service. machine # [ 11.092979] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.094203] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.132400] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 11.477393] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 11.478941] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 11.479966] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 11.493249] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 11.494229] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 11.495173] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 11.506820] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 11.177586] systemd[1]: Starting User Login Management... sshClient # [ 11.548381] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 11.553656] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 11.210787] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 11.559617] systemd[1]: Reached target First Boot Complete. machine # [ 11.233890] systemd-homed[450]: Watching /home. sshClient # [ 11.587727] systemd[1]: Starting Save Transient machine-id to Disk... sshClient # [ 11.732404] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 11.741707] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 11.743320] nsncd[549]: Sep 24 02:50:55.278 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 11.750174] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 11.796131] systemd[1]: Finished Firewall. sshClient # [ 11.816916] systemd[1]: Finished resolvconf update. sshClient # [ 11.821955] systemd[1]: Reached target Preparation for Network. machine # [ 11.485183] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 11.832302] systemd[1]: Starting DHCP Client... machine # [ 11.490979] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync' sshClient # [ 11.836323] systemd[1]: Starting Extra networking commands.... machine # [ 11.496297] dbus-broker-launch[474]: Invalid user-name in /nix/store/f3z8a7vpbmkym312xhqpyvjgpliwhihh-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 11.894371] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 11.548496] systemd-logind[477]: New seat seat0. machine # [ 11.561572] systemd[1]: Started User Login Management. sshClient # [ 11.915784] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 11.573375] systemd[1]: Starting linger-users.service... machine # [ 11.589179] systemd[1]: Started D-Bus System Message Bus. machine # [ 11.684585] dbus-broker-launch[474]: Ready sshClient # [ 12.057509] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine # [ 11.706172] systemd[1]: Started Home Area Manager. machine # [ 11.710333] systemd[1]: Finished Home Area Activation. machine # [ 11.727265] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 11.763916] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 11.773552] systemd[1]: Finished linger-users.service. machine # [ 11.824178] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 11.830622] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 11.837935] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 11.838979] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 12.182905] dhcpcd[594]: dhcpcd-10.3.2 starting machine # [ 11.839842] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 11.851372] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 12.195544] dhcpcd[618]: dev: loaded udev sshClient # [ 12.201290] dhcpcd[618]: no valid interfaces found machine # [ 11.858154] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 12.202279] dhcpcd[618]: no valid interfaces found machine # [ 11.893919] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.961118] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 11.997532] systemd[1]: Started Console Output Muting Service (PID 548/UID 0). machine # [ 12.086253] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 12.091048] nsncd[558]: Sep 24 02:50:56.618 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 12.448386] systemd[1]: Finished Extra networking commands.. machine # [ 12.106636] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 12.107668] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 12.453674] systemd[1]: Reached target Network. sshClient # [ 12.457671] systemd[1]: Starting Permit User Sessions... sshClient # [ 12.526766] systemd[1]: Finished Permit User Sessions. sshClient # [ 12.537650] systemd[1]: Started Getty on tty1. sshClient # [ 12.540184] systemd[1]: Reached target Login Prompts. machine # [ 12.241059] systemd[1]: Finished resolvconf update. machine # [ 12.265142] systemd[1]: Starting DHCP Client... sshClient # [ 12.647531] systemd[1]: Starting Virtual Console Setup... sshClient # [ 12.678202] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 12.682567] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 12.700609] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 12.708479] systemd[1]: Starting Virtual Console Setup... machine # [ 12.448828] systemd[1]: Finished Firewall. machine # [ 12.452369] systemd[1]: Reached target Preparation for Network. machine # [ 12.475362] systemd[1]: Starting Extra networking commands.... sshClient # [ 12.896446] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 12.916380] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. sshClient # [ 12.940347] systemd[1]: Started backdoor.service. sshClient # [ 13.025587] (udev-worker)[540]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 13.043877] (udev-worker)[540]: Network interface NamePolicy= disabled on kernel command line. sshClient # connecting to host... machine # [ 12.719697] dhcpcd[603]: dhcpcd-10.3.2 starting machine # [ 12.774996] dhcpcd[611]: dev: loaded udev machine # [ 12.786147] dhcpcd[611]: no valid interfaces found machine # [ 12.788234] dhcpcd[611]: no valid interfaces found sshClient # [ 13.160318] 8021q: 802.1Q VLAN Support v1.8 sshClient # [ 13.375760] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 13.403169] systemd[1]: Condition check resulted in Virtio network device being skipped.[ 13.418571] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 13.419099] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' sshClient # sshClient # [ 13.427366] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 13.427753] cfg80211: failed to load regulatory.db sshClient # [ 13.424817] systemd[1]: Starting Address configuration of eth1... sshClient # [ 13.458402] systemd-logind[469]: Watching system buttons on /dev/input/event0 (gpio-keys) sshClient # [ 13.553551] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 13.540551] dhcpcd[618]: eth0: waiting for carrier sshClient # [ 13.541522] dhcpcd[618]: eth0: carrier acquired sshClient # [ 13.577604] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 13.568153] dhcpcd[618]: DUID 00:01:00:01:32:47:4c:91:52:54:00:12:34:56 sshClient # [ 13.569374] dhcpcd[618]: eth0: IAID 00:12:34:56 sshClient # [ 13.570078] dhcpcd[618]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 13.592463] network-addresses-eth1-start[671]: adding address 192.168.1.2/24... done sshClient # [ 13.620301] network-addresses-eth1-start[671]: adding address 2001:db8:1::2/64... done sshClient # [ 13.650380] systemd[1]: Finished Address configuration of eth1. sshClient # [ 13.659604] systemd-vconsole-setup[653]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 13.667370] systemd[1]: Finished Virtual Console Setup. sshClient # [ 13.719578] mousedev: PS/2 mouse device common for all mice machine # [ 13.420613] systemd[1]: Finished Extra networking commands.. machine # [ 13.425013] systemd[1]: Reached target Network. sshClient # [ 13.819248] systemd-logind[469]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 13.609128] systemd[1]: Starting Virtual Console Setup... machine # [ 13.640278] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.648427] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.656504] systemd[1]: Starting Virtual Console Setup... machine # [ 13.710134] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 14.184140] dhcpcd[618]: eth0: soliciting a DHCP lease sshClient # [ 14.192688] dhcpcd[618]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 14.200298] dhcpcd[618]: eth0: probing address 10.0.2.15/24 machine # [ 13.990843] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 14.059817] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 14.072615] systemd[1]: Started backdoor.service. machine # [ 14.153458] (udev-worker)[523]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 14.159820] (udev-worker)[523]: Network interface NamePolicy= disabled on kernel command line. machine # [ 14.274899] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 14.290169] systemd[1]: Starting Address configuration of eth1... machine # connecting to host... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 14.91 seconds) machine: (finished: waiting for the VM to finish booting, in 14.91 seconds) sshClient # [ 14.887027] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 14.889369] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 14.890370] systemd[1]: Stopping Virtual Console Setup... sshClient # [ 14.897456] systemd[1]: Starting Virtual Console Setup... sshClient # [ 14.933627] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 14.599368] network-addresses-eth1-start[672]: adding address 192.168.1.1/24... done sshClient # [ 14.946298] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 14.952104] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 14.958605] systemd[1]: Starting Virtual Console Setup... machine # [ 14.638621] network-addresses-eth1-start[672]: adding address 2001:db8:1::1/64... done sshClient # [ 14.986921] systemd-logind[469]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine: (finished: waiting for unit systemd-homed.service, in 15.11 seconds) machine: waiting for Please enter user name to create to appear on tty 1 machine # [ 14.690427] systemd[1]: Finished Address configuration of eth1. sshClient # [ 15.075945] systemd-vconsole-setup[707]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 15.080434] systemd[1]: Finished Virtual Console Setup. machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.23 seconds) machine: sending keys 'test-homed-user\n' sshClient # [ 15.357762] dhcpcd[618]: eth0: soliciting an IPv6 router machine # [ 15.016458] systemd-logind[477]: Watching system buttons on /dev/input/event0 (gpio-keys) sshClient # [ 15.361819] dhcpcd[618]: eth0: Router Advertisement from fe80::2 sshClient # [ 15.364820] dhcpcd[618]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 15.367665] dhcpcd[618]: eth0: adding route to fec0::/64 sshClient # [ 15.370185] dhcpcd[618]: eth0: adding default route via fe80::2 machine: (finished: sending keys 'test-homed-user\n', in 0.18 seconds) machine: waiting for Please enter new password for user test-homed-user: to appear on tty 1 machine # [ 15.187832] sshd-keygen-start[488]: Generating public/private rsa key pair. machine # [ 15.194326] sshd-keygen-start[488]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 15.195622] sshd-keygen-start[488]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine # [ 15.208513] sshd-keygen-start[488]: The key fingerprint is: machine # [ 15.210184] sshd-keygen-start[488]: SHA256:yFDJLvapxP4vaHEw/BhI3MZpq1zP7rEC+U1rSzf/AIM root@machine machine # [ 15.211383] sshd-keygen-start[488]: The key's randomart image is: machine # [ 15.225222] sshd-keygen-start[488]: +---[RSA 4096]----+ machine # [ 15.226053] sshd-keygen-start[488]: | . o o.. | machine # [ 15.226778] sshd-keygen-start[488]: | o *.o | machine # [ 15.227498] sshd-keygen-start[488]: | . =.o | machine # [ 15.244256] sshd-keygen-start[488]: | . Xo.o | machine # [ 15.245063] sshd-keygen-start[488]: | . * @E.S | machine # [ 15.245796] sshd-keygen-start[488]: | = = O o | machine # [ 15.246522] sshd-keygen-start[488]: | = Oo.o . | machine # [ 15.247284] sshd-keygen-start[488]: | B.*+ o . | machine # [ 15.248005] sshd-keygen-start[488]: | . ==+. ... | machine # [ 15.258344] sshd-keygen-start[488]: +----[SHA256]-----+ machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 0.19 seconds) machine: sending keys 'foobarfoo\n' machine # [ 15.259479] dhcpcd[611]: eth0: waiting for carrier machine # [ 15.267759] dhcpcd[611]: eth0: carrier acquired machine # [ 15.269803] dhcpcd[611]: DUID 00:01:00:01:32:47:4c:93:52:54:00:12:34:56 machine # [ 15.270747] dhcpcd[611]: eth0: IAID 00:12:34:56 machine # [ 15.271444] dhcpcd[611]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 15.315200] sshd-keygen-start[732]: Generating public/private ed25519 key pair. machine # [ 15.318885] sshd-keygen-start[732]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 15.322583] sshd-keygen-start[732]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 15.323887] sshd-keygen-start[732]: The key fingerprint is: machine # [ 15.327071] sshd-keygen-start[732]: SHA256:KJ0ILcR+rBfl+JMyBjRlzj+y4ZNPRLCkfkHVT6RzeLc root@machine machine # [ 15.332654] sshd-keygen-start[732]: The key's randomart image is: machine # [ 15.333566] sshd-keygen-start[732]: +--[ED25519 256]--+ machine # [ 15.334292] sshd-keygen-start[732]: | ...*... .. | machine # [ 15.335020] sshd-keygen-start[732]: | .+O o. .o. | machine # [ 15.335756] sshd-keygen-start[732]: | o+o*+. +oo . | machine # [ 15.343416] sshd-keygen-start[732]: | .oo=*.o +.. . | machine # [ 15.346376] sshd-keygen-start[732]: | .+*oB.S E | machine # [ 15.353476] sshd-keygen-start[732]: | .o=B+. | machine # [ 15.354294] sshd-keygen-start[732]: | o=o.. | machine # [ 15.355026] sshd-keygen-start[732]: | + | machine # [ 15.355764] sshd-keygen-start[732]: | . | machine # [ 15.366413] sshd-keygen-start[732]: +----[SHA256]-----+ machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for (repeat) to appear on tty 1 machine # [ 15.372350] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 15.373327] systemd[1]: Finished SSH Host Keys Generation. machine # [ 15.377719] systemd[1]: sshd-keygen.service: Consumed 1.328s CPU time over 4.474s wall clock time, 6.5M memory peak. machine # [ 15.379287] systemd[1]: Starting SSH Daemon... machine # [ 15.390699] systemd-vconsole-setup[663]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 15.402041] systemd[1]: Finished Virtual Console Setup. machine # [ 15.469898] sshd[734]: Server listening on 0.0.0.0 port 22. machine # [ 15.470875] systemd[1]: Started SSH Daemon. machine # [ 15.471529] sshd[734]: Server listening on :: port 22. machine: (finished: waiting for (repeat) to appear on tty 1, in 0.13 seconds) machine: sending keys 'foobarfoo\n' machine # [ 15.582591] systemd-logind[477]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for unit systemd-homed-firstboot.service machine # [ 16.789964] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 16.793098] systemd[1]: Stopped Virtual Console Setup. machine # [ 16.793981] systemd[1]: Stopping Virtual Console Setup... machine # [ 16.800482] systemd[1]: Starting Virtual Console Setup... machine # [ 16.826905] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 16.831164] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 16.835640] systemd[1]: Stopped Virtual Console Setup. machine # [ 16.847953] systemd[1]: Starting Virtual Console Setup... machine # [ 16.887312] systemd-logind[477]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 16.988728] systemd-vconsole-setup[757]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 16.993047] systemd[1]: Finished Virtual Console Setup. machine # [ 17.129187] dhcpcd[611]: eth0: soliciting a DHCP lease machine # [ 17.133393] dhcpcd[611]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 17.140479] dhcpcd[611]: eth0: probing address 10.0.2.15/24 machine # [ 17.720617] dhcpcd[611]: eth0: soliciting an IPv6 router machine # [ 17.725465] dhcpcd[611]: eth0: Router Advertisement from fe80::2 machine # [ 17.728514] dhcpcd[611]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 17.731482] dhcpcd[611]: eth0: adding route to fec0::/64 machine # [ 17.734112] dhcpcd[611]: eth0: adding default route via fe80::2 sshClient # [ 19.226566] dhcpcd[618]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 19.229978] dhcpcd[618]: eth0: adding route to 10.0.2.0/24 sshClient # [ 19.234566] dhcpcd[618]: eth0: adding default route via 10.0.2.2 sshClient # [ 19.383724] systemd[1]: Started DHCP Client. sshClient # [ 19.386230] systemd[1]: Reached target Multi-User System. sshClient # [ 19.387481] systemd[1]: Startup finished in 1.027s (kernel) + 4.129s (initrd) + 14.229s (userspace) = 19.387s. machine # [ 22.129239] dhcpcd[611]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 22.132953] dhcpcd[611]: eth0: adding route to 10.0.2.0/24 machine # [ 22.135211] dhcpcd[611]: eth0: adding default route via 10.0.2.2 machine # [ 22.261760] systemd[1]: Started DHCP Client. machine # [ 25.874659] systemd-homed[450]: Generating key pair for signing local user identity records. machine # [ 25.881842] systemd-homed[450]: Successfully created Ed25519 key pair. machine # [ 25.904823] systemd-homed[450]: test-homed-user: changing state absent → creating machine # [ 26.024348] systemd-homework[852]: Using automatic default storage of 'luks'. machine # [ 26.030288] systemd-homework[852]: Sizing home to 83% of available disk space, which is 744M. machine # [ 26.032909] systemd-homework[852]: Allocating image file completed. machine # [ 26.131784] systemd-homework[852]: Writing of partition table completed. machine # [ 26.135695] systemd-homework[852]: Setting up loopback device /dev/loop0 completed. machine # [ 27.413872] systemd-homework[852]: LUKS formatting completed. machine # [ 29.688462] systemd-homework[852]: Writing password to LUKS keyslot 0 completed. machine # [ 29.951354] systemd-homework[852]: LUKS activation by volume key succeeded. machine # [ 29.969728] systemd-homework[852]: Writing user record as LUKS token completed. machine # [ 29.970901] systemd-homework[852]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 29.974167] systemd-homework[852]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 30.789939] systemd[1]: proc-887-mounts.mount: Deactivated successfully. machine # [ 30.790673] systemd-homework[852]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid ebd9827e-c690-49c0-8649-650ba720b43f) machine # [ 30.790830] systemd-homework[852]: Formatting file system completed. machine # [ 30.826546] systemd-homework[852]: Mounting file system completed. machine # [ 30.829245] systemd-homework[852]: Skeleton directory /etc/skel missing, ignoring. machine # [ 30.831434] systemd-homework[852]: Wrote embedded .identity file. machine # [ 30.833379] systemd-homework[852]: Replaced embedded blob dir with contents of system blob dir. machine # [ 30.834851] systemd-homework[852]: Recursive changing of ownership completed. machine # [ 30.835998] systemd-homework[852]: Changed top-level directory access mode to 0700. machine # [ 30.843568] systemd-homework[852]: Synchronized disk. machine # [ 30.845736] systemd-homework[852]: Discarded unused 652.8M. machine # [ 30.855870] systemd-homed[450]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 30.889509] systemd-homed[450]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 30.909593] systemd-homed[450]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 30.922226] systemd-homework[852]: Moved image file into place. machine # [ 30.927717] systemd-homework[852]: Creation completed. machine # [ 30.928736] systemd-homework[852]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M. machine # [ 30.939063] systemd-homed[450]: test-homed-user: changing state creating → inactive machine # [ 30.948437] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 30.951790] systemd[1]: systemd-mute-console@0-1-548_549-0.service: Deactivated successfully. machine # [ 30.957744] systemd[1]: Reached target First Boot Complete. machine # [ 30.963054] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 30.965911] systemd[1]: Starting Permit User Sessions... machine # [ 31.011324] systemd[1]: Finished Permit User Sessions. machine # [ 31.018711] systemd[1]: Started Getty on tty1. machine # [ 31.021932] systemd[1]: Reached target Login Prompts. machine # [ 31.028322] systemd[1]: Reached target Multi-User System. machine # [ 31.056359] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 31.061172] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 31.076423] systemd[1]: Startup finished in 1.045s (kernel) + 4.143s (initrd) + 25.887s (userspace) = 31.076s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.16 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 32.22 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 31.825794] systemd-homed[450]: test-homed-user: changing state inactive → updating machine # [ 31.835404] systemd-homework[932]: Offline update requested. Not touching embedded records. machine # [ 31.840163] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 31.851477] systemd-homed[450]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.09 seconds) subtest: login as homed user machine: waiting for login: to appear on tty 1 machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds) machine: sending keys 'test-homed-user\n' machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Password: to appear on tty 1 machine # [ 32.271861] systemd-homed[450]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 32.285697] systemd-homework[947]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 32.299739] systemd-homed[450]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 32.304772] systemd-homed[450]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 32.308374] systemd-homed[450]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 32.311819] systemd-homed[450]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: to appear on tty 1, in 1.18 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for success: pgrep -u test-homed-user -t tty1 bash machine # [ 33.452709] systemd-homed[450]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 33.514503] systemd-homework[959]: Provided password unlocks user record. machine # [ 33.520605] systemd-homework[959]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 33.529545] systemd-homework[959]: Allocated additional 652.8M. machine # [ 33.535211] systemd-homework[959]: Setting up loopback device /dev/loop1 completed.[ 33.550485] loop1: detected capacity change from 0 to 1519616 machine # machine # [ 34.425998] systemd-homework[959]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 34.463364] systemd-homework[959]: Provided password unlocks user record. machine # [ 34.469450] systemd-homework[959]: Probing file system completed (found btrfs). machine # [ 34.472094] systemd-homework[959]: No fsck for file system btrfs installed, ignoring. machine # [ 34.491491] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (959) machine # [ 34.496345] BTRFS info (device dm-0): first mount of filesystem ebd9827e-c690-49c0-8649-650ba720b43f machine # [ 34.496396] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 34.486481] systemd-homework[959]: Mounting file system completed.[ 34.503198] BTRFS info (device dm-0): enabling free space tree machine # [ 34.503223] BTRFS info (device dm-0): use zstd compression, level 1 machine # machine # [ 34.491764] systemd-homework[959]: Discovered used loopback device /dev/loop1. machine # [ 34.494662] systemd-homework[959]: offset = 1048576, size = 778043392, image = 780140544 machine # [ 34.503339] systemd-homework[959]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M. machine # [ 34.505755] systemd-homework[959]: Growing of image file from 744M to 897M completed. machine # [ 34.520313] systemd-homework[959]: Refreshing loop device size completed. machine # [ 34.533085] loop1: detected capacity change from 1519616 to 1832960 machine # [ 34.541789] systemd-homework[959]: Growing of partition completed. machine # [ 34.604680] async_tx: api initialized (async) machine # [ 34.645100] systemd-homework[959]: LUKS device growing completed. machine # [ 34.669368] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 921698304 machine # [ 34.657721] systemd-homework[959]: File system resizing from 726M to 879M completed. machine # [ 34.660081] systemd-homework[959]: Synchronized disk. machine # [ 34.660845] systemd-homework[959]: Synchronized disk. machine # [ 34.661550] systemd-homework[959]: Resizing completed. machine # [ 34.662276] systemd-homework[959]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 34.668181] systemd-homework[959]: Read embedded .identity file. machine # [ 34.688894] systemd-homework[959]: Provided password unlocks user record. machine # [ 34.691187] systemd-homework[959]: Reconciling header user identity completed (host version was newer). machine # [ 34.693361] systemd-homework[959]: Reconciling embedded user identity completed (host version was newer). machine # [ 34.713596] systemd-homework[959]: Wrote LUKS header user record. machine # [ 34.715084] systemd-homework[959]: Wrote embedded .identity file. machine # [ 34.716732] systemd-homework[959]: Replaced embedded blob dir with contents of system blob dir. machine # [ 34.718307] systemd-homework[959]: Recursive changing of ownership not necessary, skipped. machine # [ 34.723247] systemd-homework[959]: Synchronized disk. machine # [ 34.726132] systemd-homework[959]: Moving to final mount point /home/test-homed-user completed. machine # [ 34.757248] systemd-homework[959]: Activation completed. machine # [ 34.759912] systemd-homework[959]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 34.779387] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 34.785247] systemd-homed[450]: test-homed-user: changing state activating-for-acquire → active machine # [ 34.786864] login[923]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 34.794875] systemd-homed[450]: test-homed-user: changing state active → resizing-while-active machine # [ 34.808116] systemd-homework[1004]: Successfully acquired home volume key from kernel keyring. machine # [ 34.812473] systemd-homework[1004]: LUKS volume key from keyring unlocks user record. machine # [ 34.876676] login[923]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 34.932040] systemd[1]: Created slice Slice /user/60388. machine # [ 34.937831] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 34.950356] systemd-homework[1004]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 34.966146] systemd-logind[477]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 35.107919] systemd-homework[1004]: Successfully re-activated LUKS device. machine # [ 35.116518] systemd-homework[1004]: LUKS volume key from keyring unlocks user record. machine # [ 35.121837] systemd-homework[1004]: Discovered used loopback device /dev/loop1. machine # [ 35.124865] systemd-homework[1004]: Read embedded .identity file. machine # [ 35.125818] systemd-homework[1004]: LUKS volume key from keyring unlocks user record. machine # [ 35.126848] systemd-homework[1004]: Reconciling header user identity completed (host version was newer). machine # [ 35.134973] systemd-homework[1004]: Reconciling embedded user identity completed (host version was newer). machine # [ 35.136405] systemd-homework[1004]: offset = 1048576, size = 938475520, image = 940572672 machine # [ 35.137777] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 35.146077] systemd-homework[1004]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M. machine # [ 35.152150] systemd-homework[1004]: Wrote embedded .identity file. machine # [ 35.153174] systemd-homework[1004]: Replaced embedded blob dir with contents of system blob dir. machine # [ 35.154397] systemd-homework[1004]: Backing file is fully allocated already. machine # [ 35.155378] systemd[1]: Starting User Manager for UID 60388... machine # [ 35.202110] systemd-homework[1004]: File system resizing from 879M to 747M completed. machine # [ 35.218898] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 783286272 machine # [ 35.210474] systemd-homework[1004]: Synchronized disk. machine # [ 35.220282] (systemd)[1008]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 35.298234] (systemd)[1008]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 35.307047] systemd-logind[477]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 35.331981] systemd-homework[1004]: LUKS device shrinking completed. machine # [ 35.357160] loop1: detected capacity change from 1832960 to 1562624 machine # [ 35.347478] systemd-homework[1004]: Refreshing loop device size completed. machine # [ 35.350547] systemd-homework[1004]: Shrinking of image file completed. machine # [ 35.364132] systemd[1008]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.376753] systemd-homework[1004]: Shrinking of partition completed. machine # [ 35.404733] systemd-homework[1004]: Wrote LUKS header user record. machine # [ 35.405743] systemd-homework[1004]: Synchronized disk. machine # [ 35.422644] systemd-homework[1004]: Resizing completed. machine # [ 35.423563] systemd-homework[1004]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 35.437702] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 35.446223] systemd-homed[450]: test-homed-user: changing state resizing-while-active → active machine # [ 35.447848] systemd-homed[450]: Rebalancing complete. machine # [ 35.531105] systemd[1008]: Queued start job for default target Main User Target. machine # [ 35.537226] systemd[1008]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.541413] systemd[1008]: Created slice User Application Slice. machine # [ 35.542352] systemd[1008]: Started Daily Cleanup of User's Temporary Directories. machine # [ 35.543395] systemd[1008]: Reached target Paths. machine # [ 35.544377] systemd[1008]: Reached target Timers. machine # [ 35.546595] systemd[1008]: Starting D-Bus User Message Bus Socket... machine # [ 35.548803] systemd[1008]: Starting Create User Files and Directories... machine # [ 35.592678] systemd[1008]: Finished Create User Files and Directories. machine # [ 35.615136] systemd[1008]: Listening on D-Bus User Message Bus Socket. machine # [ 35.617906] systemd[1008]: Reached target Sockets. machine # [ 35.618648] systemd[1008]: Reached target Basic System. machine # [ 35.619374] systemd[1]: Started User Manager for UID 60388. machine # [ 35.624298] systemd[1008]: Starting Run user-specific NixOS activation... machine # [ 35.629373] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 35.687095] systemd[1008]: Finished Run user-specific NixOS activation. machine # [ 35.689528] systemd[1008]: Reached target Main User Target. machine # [ 35.690363] systemd[1008]: Startup finished in 350ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 2.33 seconds) machine: sending keys 'whoami > /tmp/2\n' machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds) machine: waiting for file '/tmp/2' machine: (finished: waiting for file '/tmp/2', in 1.14 seconds) machine: must succeed: cat /tmp/2 machine: (finished: must succeed: cat /tmp/2, in 0.04 seconds) (finished: subtest: login as homed user, in 5.20 seconds) subtest: login as regular user sshClient: waiting for login: to appear on tty 1 sshClient: waiting for the VM to finish booting sshClient: Guest shell says: b'Spawning backdoor root shell...\n' sshClient: connected to guest root shell sshClient: (connecting took 0.00 seconds) sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds) sshClient: (finished: waiting for login: to appear on tty 1, in 0.10 seconds) sshClient: sending keys 'test-normal-user\n' sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds) sshClient: waiting for Password: to appear on tty 1 sshClient: (finished: waiting for Password: to appear on tty 1, in 1.18 seconds) sshClient: sending keys 'foobarfoo\n' sshClient: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) sshClient: waiting for success: pgrep -u test-normal-user bash sshClient # [ 39.085902] login[647]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 39.130908] systemd[1]: Created slice Slice /user/1000. sshClient # [ 39.134629] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 39.175754] systemd-logind[469]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 39.210660] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 39.216163] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 39.244366] (systemd)[809]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 39.251504] systemd-logind[469]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 39.279544] systemd[809]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 39.408877] systemd[809]: Queued start job for default target Main User Target. sshClient # [ 39.416967] systemd[809]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 39.420785] systemd[809]: Created slice User Application Slice. sshClient # [ 39.421681] systemd[809]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 39.422679] systemd[809]: Reached target Paths. sshClient # [ 39.423308] systemd[809]: Reached target Timers. sshClient # [ 39.425649] systemd[809]: Starting D-Bus User Message Bus Socket... sshClient # [ 39.427639] systemd[809]: Starting Create User Files and Directories... sshClient # [ 39.468484] systemd[809]: Finished Create User Files and Directories. sshClient # [ 39.487726] systemd[809]: Listening on D-Bus User Message Bus Socket. sshClient # [ 39.492286] systemd[809]: Reached target Sockets. sshClient # [ 39.493033] systemd[809]: Reached target Basic System. sshClient # [ 39.493731] systemd[1]: Started User Manager for UID 1000. sshClient # [ 39.494488] systemd[809]: Starting Run user-specific NixOS activation... sshClient # [ 39.496771] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 39.557484] systemd[809]: Finished Run user-specific NixOS activation. sshClient # [ 39.562797] systemd[809]: Reached target Main User Target. sshClient # [ 39.563714] systemd[809]: Startup finished in 291ms. sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.19 seconds) sshClient: sending keys 'whoami > /tmp/1\n' sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds) sshClient: waiting for file '/tmp/1' sshClient: (finished: waiting for file '/tmp/1', in 1.09 seconds) sshClient: must succeed: cat /tmp/1 sshClient: (finished: must succeed: cat /tmp/1, in 0.04 seconds) (finished: subtest: login as regular user, in 4.05 seconds) subtest: add homed ssh authorized key sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n' sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.50 seconds) sshClient: waiting for file '/tmp/id_ed25519.pub' sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.11 seconds) sshClient: must succeed: cat /tmp/id_ed25519.pub sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.04 seconds) machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOCbeHV4ZhGKL5mVswKwYvA96ev3gVHxck+2sSHFwPrq test-normal-user@sshClient' machine # [ 42.844911] systemd-homed[450]: test-homed-user: changing state active → updating-while-active machine # [ 42.861776] systemd-homework[1058]: Offline update requested. Not touching embedded records. machine # [ 42.874396] systemd-homed[450]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOCbeHV4ZhGKL5mVswKwYvA96ev3gVHxck+2sSHFwPrq test-normal-user@sshClient', in 0.12 seconds) machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519 machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.05 seconds) (finished: subtest: add homed ssh authorized key, in 1.82 seconds) subtest: change homed user password machine: sending keys 'passwd; echo $? > /tmp/3\n' machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.27 seconds) machine: waiting for New password: to appear on tty 1 machine: (finished: waiting for New password: to appear on tty 1, in 1.18 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds) machine: waiting for Retype new password: to appear on tty 1 machine: (finished: waiting for Retype new password: to appear on tty 1, in 0.13 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds) ??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead. File "/nix/store/8jd7437maa67c2lshz41ajlc2wfp49j5-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 44.883481] systemd-homed[450]: test-homed-user: changing state active → passwd-while-active machine # [ 45.676578] systemd-homework[1090]: No valid password for LUKS superblock. machine # [ 47.914247] systemd-homed[450]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 47.922504] systemd-homed[450]: test-homed-user: changing state passwd-while-active → active machine: (finished: must succeed: sleep 4.0, in 4.04 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for file '/tmp/3' machine # [ 49.012331] systemd-homed[450]: test-homed-user: changing state active → passwd-while-active machine # [ 50.326089] systemd-homework[1096]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 50.453296] systemd-homework[1096]: Successfully re-activated LUKS device. machine # [ 50.508069] systemd-homework[1096]: Provided password unlocks user record. machine # [ 50.509942] systemd-homework[1096]: Discovered used loopback device /dev/loop1. machine # [ 50.511128] systemd-homework[1096]: Read embedded .identity file. machine # [ 50.563328] systemd-homework[1096]: Provided password unlocks user record. machine # [ 50.568221] systemd-homework[1096]: Reconciling header user identity completed (host version was newer). machine # [ 50.569499] systemd-homework[1096]: Reconciling embedded user identity completed (host version was newer). machine # [ 53.798406] systemd-homework[1096]: Added LUKS key slot 1. machine # [ 53.822399] systemd-homework[1096]: Destroyed LUKS key slot 0. machine # [ 53.832815] systemd-homework[1096]: Wrote LUKS header user record. machine # [ 53.834305] systemd-homework[1096]: Wrote embedded .identity file. machine # [ 53.835287] systemd-homework[1096]: Replaced embedded blob dir with contents of system blob dir. machine # [ 53.840059] systemd-homework[1096]: Synchronized disk. machine # [ 53.844707] systemd-homework[1096]: Everything completed. machine # [ 53.860725] systemd-homed[450]: test-homed-user: changing state passwd-while-active → active machine # [ 53.863698] passwd[1071]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine: (finished: waiting for file '/tmp/3', in 5.21 seconds) machine: must succeed: cat /tmp/3 machine: (finished: must succeed: cat /tmp/3, in 0.03 seconds) (finished: subtest: change homed user password, in 11.17 seconds) subtest: escalate to root from homed user machine: sending keys 'sudo id | tee /tmp/4\n' machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds) machine: waiting for password for test-homed-user to appear on tty 1 machine # [ 54.594629] systemd-homed[450]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 54.605503] systemd-homework[1133]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 54.615486] systemd-homed[450]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 54.621583] systemd-homed[450]: test-homed-user: changing state authenticating-for-acquire → active machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.16 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds) machine: waiting for file '/tmp/4' machine: (finished: waiting for file '/tmp/4', in 0.04 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 55.672730] systemd-homed[450]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 55.731888] systemd-homework[1149]: Provided password unlocks user record. machine # [ 56.394316] systemd-homework[1149]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 56.521216] systemd-homework[1149]: Successfully re-activated LUKS device. machine # [ 56.549578] systemd-homework[1149]: Provided password unlocks user record. machine # [ 56.551358] systemd-homework[1149]: Discovered used loopback device /dev/loop1. machine # [ 56.552666] systemd-homework[1149]: Read embedded .identity file. machine # [ 56.577832] systemd-homework[1149]: Provided password unlocks user record. machine # [ 56.579794] systemd-homework[1149]: Reconciling user identities completed (host and header version were identical). machine # [ 56.581869] systemd-homework[1149]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 56.592310] systemd-homework[1149]: Everything completed. machine # [ 56.603539] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 56.609825] systemd-homed[450]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 56.611452] sudo[1131]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 56.616744] sudo[1131]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 56.629041] sudo[1131]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 56.638328] sudo[1131]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.09 seconds) (finished: subtest: escalate to root from homed user, in 2.62 seconds) subtest: log out and deactivate homed user's home area machine: sending keys 'exit\n' machine: (finished: sending keys 'exit\n', in 0.05 seconds) machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive' machine # [ 56.818700] login[923]: pam_unix(login:session): session closed for user test-homed-user machine # [ 56.824611] login[923]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 56.835500] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 56.855709] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 56.874040] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 56.889586] systemd-logind[477]: Session 1 logged out. Waiting for processes to exit. machine # [ 56.891136] systemd[1]: Started Getty on tty1. machine # [ 56.900524] systemd-logind[477]: Removed session 1. machine # [ 67.135926] systemd[1]: Stopping User Manager for UID 60388... machine # [ 67.149569] systemd[1008]: Activating special unit Exit the Session... machine # [ 67.161967] systemd[1008]: Stopped target Main User Target. machine # [ 67.168098] systemd[1008]: Stopped Run user-specific NixOS activation. machine # [ 67.170833] systemd[1008]: Stopped target Basic System. machine # [ 67.173240] systemd[1008]: Stopped target Paths. machine # [ 67.175251] systemd[1008]: Stopped target Sockets. machine # [ 67.177479] systemd[1008]: Stopped target Timers. machine # [ 67.179522] systemd[1008]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 67.184241] systemd[1008]: Closed D-Bus User Message Bus Socket. machine # [ 67.186384] systemd[1008]: Stopped Create User Files and Directories. machine # [ 67.188785] systemd[1008]: Removed slice User Application Slice. machine # [ 67.190728] systemd[1008]: Reached target Shutdown. machine # [ 67.192583] systemd[1008]: Finished Exit the Session. machine # [ 67.194174] systemd[1008]: Reached target Exit the Session. machine # [ 67.213802] systemd-homed[450]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 67.225795] systemd-logind[477]: Removed session 2. machine # [ 67.227495] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 67.231608] systemd[1]: Stopped User Manager for UID 60388. machine # [ 67.240575] systemd-homed[450]: test-homed-user: changing state active → deactivating machine # [ 67.249937] systemd-homework[1224]: Successfully acquired home volume key from kernel keyring. machine # [ 67.253728] systemd-homework[1224]: Discarded unused 674.3M. machine # [ 67.262777] systemd-homework[1224]: Syncing completed. machine # [ 67.292201] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 67.314869] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 67.378867] systemd-homework[1224]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 67.425010] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 67.432212] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 67.434183] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 67.523669] systemd-homework[1224]: Successfully re-activated LUKS device. machine # [ 67.526436] systemd-homework[1224]: Discovered used loopback device /dev/loop1. machine # [ 67.527530] systemd-homework[1224]: offset = 1048576, size = 800063488, image = 802160640 machine # [ 67.539543] systemd-homework[1224]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M. machine # [ 67.542907] systemd-homework[1224]: Allocated additional 674.3M. machine # [ 67.564972] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 268435456 machine # [ 67.550020] systemd-homework[1224]: File system resizing from 747M to 256M completed. machine # [ 67.556284] systemd-homework[1224]: Synchronized disk. machine # [ 67.696239] systemd-homework[1224]: LUKS device shrinking completed. machine # [ 67.725098] loop1: detected capacity change from 1562624 to 557056 machine # [ 67.713219] systemd-homework[1224]: Refreshing loop device size completed. machine # [ 67.716000] systemd-homework[1224]: Shrinking of image file completed. machine # [ 67.727131] systemd-homework[1224]: Shrinking of partition completed. machine # [ 67.731329] systemd-homework[1224]: Synchronized disk. machine # [ 67.745470] BTRFS info (device dm-0): last unmount of filesystem ebd9827e-c690-49c0-8649-650ba720b43f machine # [ 67.734753] systemd-homework[1224]: Resizing completed. machine # [ 67.735533] systemd-homework[1224]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 67.737988] systemd-homework[1224]: Unmounting completed. machine # [ 67.738753] systemd-homework[1224]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 67.739876] systemd-homework[1224]: Device home-test-homed-user is not active. machine # [ 67.768762] systemd-homed[450]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 67.773566] systemd-homework[1224]: Everything completed. machine # [ 67.788179] systemd-homed[450]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.94 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.99 seconds) subtest: ssh as homed user sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n' sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.80 seconds) sshClient: waiting for Please enter password for user to appear on tty 1 machine # [ 70.658960] systemd-homed[450]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 70.673818] systemd-homework[1260]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 70.686218] systemd-homed[450]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 70.696522] systemd-homed[450]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 70.703551] sshd-session[1256]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 70.708679] systemd-homed[450]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 70.712568] sshd-session[1256]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 70.717060] systemd-homed[450]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 70.719798] sshd-session[1256]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 43766 ssh2: ED25519 SHA256:1o09W9zGdOtL4re4rbcHeFe12gnpSKexZUe0BY2pCdw machine # [ 70.778708] sshd-session[1256]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 70.796241] systemd-logind[477]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 70.802493] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 70.835407] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 70.845104] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 70.863191] sshd-session[1256]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 70.866428] sshd-session[1256]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 70.886082] systemd-homed[450]: test-homed-user: changing state inactive → activating machine # [ 70.892324] systemd-homework[1266]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 70.899310] systemd-homed[450]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 70.901832] systemd-homed[450]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.25 seconds) sshClient: sending keys 'barfoobar\n' sshClient: (finished: sending keys 'barfoobar\n', in 0.11 seconds) machine: waiting for success: pgrep -u test-homed-user bash machine # [ 71.968276] systemd-homed[450]: test-homed-user: changing state inactive → activating machine # [ 72.027585] systemd-homework[1271]: Provided password unlocks user record. machine # [ 72.033982] systemd-homework[1271]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 72.040678] systemd-homework[1271]: Backing file is fully allocated already. machine # [ 72.045464] systemd-homework[1271]: Setting up loopback device /dev/loop1 completed. machine # [ 72.061194] loop1: detected capacity change from 0 to 557056 machine # [ 72.943134] systemd-homework[1271]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 72.989417] systemd-homework[1271]: Provided password unlocks user record. machine # [ 72.998779] systemd-homework[1271]: Probing file system completed (found btrfs). machine # [ 73.000501] systemd-homework[1271]: No fsck for file system btrfs installed, ignoring. machine # [ 73.019753] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1271) machine # [ 73.023732] BTRFS info (device dm-0): first mount of filesystem ebd9827e-c690-49c0-8649-650ba720b43f machine # [ 73.023789] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 73.015791] systemd-homework[1271]: Mounting file system completed.[ 73.032539] BTRFS info (device dm-0): enabling free space tree machine # [ 73.032562] BTRFS info (device dm-0): use zstd compression, level 1 machine # machine # [ 73.023902] systemd-homework[1271]: Discovered used loopback device /dev/loop1. machine # [ 73.025384] systemd-homework[1271]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 73.040264] systemd-homework[1271]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M. machine # [ 73.042080] systemd-homework[1271]: Growing of image file from 274M to 765M completed. machine # [ 73.051961] systemd-homework[1271]: Refreshing loop device size completed.[ 73.065224] loop1: detected capacity change from 557056 to 1562624 machine # machine # [ 73.066976] systemd-homework[1271]: Growing of partition completed. machine # [ 73.114354] systemd-homework[1271]: LUKS device growing completed. machine # [ 73.122374] systemd-homework[1271]: File system resizing from 256M to 747M completed. machine # [ 73.123590] systemd-homework[1271]: Synchronized disk.[ 73.138094] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 783286272 machine # machine # [ 73.128372] systemd-homework[1271]: Synchronized disk. machine # [ 73.129166] systemd-homework[1271]: Resizing completed. machine # [ 73.129901] systemd-homework[1271]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 73.131527] systemd-homework[1271]: Read embedded .identity file. machine # [ 73.156373] systemd-homework[1271]: Provided password unlocks user record. machine # [ 73.158553] systemd-homework[1271]: Reconciling user identities completed (host and header version were identical). machine # [ 73.159968] systemd-homework[1271]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 73.166790] systemd-homework[1271]: Recursive changing of ownership not necessary, skipped. machine # [ 73.167970] systemd-homework[1271]: Synchronized disk. machine # [ 73.172252] systemd-homework[1271]: Moving to final mount point /home/test-homed-user completed. machine # [ 73.195741] systemd-homework[1271]: Activation completed. machine # [ 73.197419] systemd-homework[1271]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 73.216398] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 73.221992] systemd-homed[450]: test-homed-user: changing state activating → active machine # [ 73.244612] systemd[1]: Starting User Manager for UID 60388... machine # [ 73.295636] (systemd)[1312]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 73.352771] (systemd)[1312]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 73.359277] systemd-logind[477]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 73.387129] systemd[1312]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.513497] systemd[1312]: Queued start job for default target Main User Target. machine # [ 73.521369] systemd[1312]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.525934] systemd[1312]: Created slice User Application Slice. machine # [ 73.526920] systemd[1312]: Started Daily Cleanup of User's Temporary Directories. machine # [ 73.527941] systemd[1312]: Reached target Paths. machine # [ 73.530632] systemd[1312]: Reached target Timers. machine # [ 73.531414] systemd[1312]: Starting D-Bus User Message Bus Socket... machine # [ 73.533576] systemd[1312]: Starting Create User Files and Directories... machine # [ 73.581217] systemd[1312]: Finished Create User Files and Directories. machine # [ 73.585600] systemd[1312]: Listening on D-Bus User Message Bus Socket. machine # [ 73.588934] systemd[1312]: Reached target Sockets. machine # [ 73.589735] systemd[1312]: Reached target Basic System. machine # [ 73.590492] systemd[1]: Started User Manager for UID 60388. machine # [ 73.594666] systemd[1312]: Starting Run user-specific NixOS activation... machine # [ 73.658888] systemd[1312]: Finished Run user-specific NixOS activation. machine # [ 73.665115] systemd[1312]: Reached target Main User Target. machine # [ 73.666000] systemd[1312]: Startup finished in 283ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.32 seconds) sshClient: sending keys 'whoami > /tmp/5\n' sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds) machine: waiting for file '/tmp/5' machine: (finished: waiting for file '/tmp/5', in 1.13 seconds) machine: must succeed: cat /tmp/5 machine: (finished: must succeed: cat /tmp/5, in 0.04 seconds) sshClient: sending keys 'exit\n' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) sshClient: sending keys 'exit\n' machine # [ 75.630190] sshd-session[1264]: Received disconnect from 2001:db8:1::2 port 43766:11: disconnected by user sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.93 seconds) (finished: run the VM test script, in 81.24 seconds) machine # [ 75.645025] sshd-session[1264]: Disconnected from user test-homed-user 2001:db8:1::2 port 43766 machine # [ 75.670562] sshd-session[1256]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 75.679471] sshd-session[1256]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 75.704554] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 75.706647] systemd-logind[477]: Session 3 logged out. Waiting for processes to exit. machine # [ 75.715329] systemd-logind[477]: Removed session 3. sshClient # [ 76.071005] login[647]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 76.095350] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 76.121878] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 76.125680] systemd[1]: session-1.scope: Consumed 221ms CPU time over 36.627s wall clock time, 9.3M memory peak, 12K written to disk, 25K incoming IP traffic, 23.9K outgoing IP traffic. sshClient # [ 76.137252] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 76.153074] systemd-logind[469]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 76.155617] systemd[1]: Started Getty on tty1. sshClient # [ 76.176109] systemd-logind[469]: Removed session 1. test script finished in 81.55s cleanup kill QemuMachine (pid 47) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) machine # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) sshClient # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.41 seconds) additionally exposed symbols: machine, sshClient, 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