tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 47) machine # Formatting '/build/vm-state-machine/tmp.D8e3FpLDGd', 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 sshClient: QEMU running (pid 48) sshClient # Disk image does not exist, creating the virtualisation disk image... machine # Filesystem UUID: 9b2166a4-964f-466b-9519-db572dfb4570 machine # Superblock backups stored on blocks: (finished: start all VMs, in 0.93 seconds) sshClient # Formatting '/build/vm-state-sshClient/tmp.CK0uOHWKoc', fmt=raw size=1073741824 subtest: create systemd-homed user on first boot prompt sshClient # mke2fs 1.47.4 (6-Mar-2025) machine # 32768, 98304, 163840, 229376 machine: waiting for unit systemd-homed.service sshClient # Discarding device blocks: 0/262144 done machine: waiting for the VM to finish booting sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes machine # sshClient # Filesystem UUID: e6a711f9-5e8e-4d29-bd17-5e01004f03d0 machine # Allocating group tables: 0/8 done sshClient # Superblock backups stored on blocks: machine # Writing inode tables: 0/8 done sshClient # 32768, 98304, 163840, 229376 machine # Creating journal (8192 blocks): done sshClient # machine # Writing superblocks and filesystem accounting information: 0/8 done sshClient # Allocating group tables: 0/8 done machine # sshClient # Writing inode tables: 0/8 done machine # Virtualisation disk image created. sshClient # Creating journal (8192 blocks): done machine # Starting virtiofs daemons... sshClient # Writing superblocks and filesystem accounting information: 0/8 done machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # sshClient # Virtualisation disk image created. machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # Starting virtiofs daemons... machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] 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. sshClient # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 sshClient # [ 0.000000] KASLR enabled machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. sshClient # [ 0.000000] random: crng init done machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs sshClient # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] psci: Trusted OS migration not required sshClient # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] psci: SMC Calling Convention v1.1 sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Detected PIPT I-cache on CPU0 sshClient # [ 0.000000] Zone ranges: sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) sshClient # [ 0.000000] DMA32 empty sshClient # [ 0.000000] Normal empty machine # [ 0.000000] CPU features: detected: GICv3 CPU interface sshClient # [ 0.000000] Device empty machine # [ 0.000000] CPU features: detected: Spectre-v4 sshClient # [ 0.000000] Movable zone start for each node machine # [ 0.000000] CPU features: detected: Spectre-BHB sshClient # [ 0.000000] Early memory node ranges machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] alternatives: applying boot alternatives 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 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/j3j1zhi3nlr9yh7n27j8jwmbnawgjk2n-nixos-system-machine-test/init regInfo=/nix/.ro-store/yd9vflyfl4arnvrrsjk8i23iy84671p1-closure-info/registration console=ttyAMA0,115200n8 console=tty0 sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 sshClient # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yd9vflyfl4arnvrrsjk8i23iy84671p1-closure-info/registration", will be passed to user space. machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.000000] CPU features: detected: Spectre-v4 sshClient # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 sshClient # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 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/gmsqir826lmca28d8im6nynpl3zwlpxb-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/i7f1x0ymxffqm3lcm81vimqw2bqb84g2-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 75049 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/i7f1x0ymxffqm3lcm81vimqw2bqb84g2-closure-info/registration", will be passed to user space. machine # [ 0.000000] rcu: Hierarchical RCU implementation. sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB sshClient # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.000000] Fallback order for Node 0: 0 machine # [ 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] Built 1 zonelists, mobility grouping on. Total pages: 262144 sshClient # [ 0.000000] Policy zone: DMA machine # [ 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] 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 machine # [ 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] allocated 2097152 bytes of page_ext machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 sshClient # [ 0.000000] ftrace: allocating 75049 entries in 294 pages machine # [ 0.000000] GICv3: 256 SPIs implemented sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] GICv3: 0 Extended SPIs implemented sshClient # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] Root IRQ handler: gic_handle_irq sshClient # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) 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 machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 sshClient # [ 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] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 sshClient # [ 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: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 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] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 sshClient # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.000034] arm-pv: using stolen time PV sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000496] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000696] Console: colour dummy device 80x25 sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000704] printk: legacy console [tty0] enabled sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000912] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 machine # [ 0.000920] pid_max: default: 32768 minimum: 301 sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 machine # [ 0.001002] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001195] landlock: Up and running. sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.001199] Yama: becoming mindful. machine # [ 0.001722] LSM support for eBPF active sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.001884] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.001906] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.003933] rcu: Hierarchical SRCU implementation. sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.003939] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.005308] fsl-mc MSI: its@8080000 domain created sshClient # [ 0.000031] arm-pv: using stolen time PV machine # [ 0.005434] EFI services will not be available. machine # [ 0.005537] smp: Bringing up secondary CPUs ... sshClient # [ 0.000408] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.005547] smp: Brought up 1 node, 1 CPU sshClient # [ 0.000607] Console: colour dummy device 80x25 machine # [ 0.005551] SMP: Total of 1 processors activated. sshClient # [ 0.000616] printk: legacy console [tty0] enabled machine # [ 0.005553] CPU: All CPU(s) started at EL1 machine # [ 0.005563] CPU features: detected: Branch Target Identification sshClient # [ 0.000806] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.005567] CPU features: detected: ARMv8.4 Translation Table Level sshClient # [ 0.000813] pid_max: default: 32768 minimum: 301 sshClient # [ 0.000889] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.005570] CPU features: detected: Instruction cache invalidation not required for I/D coherence sshClient # [ 0.001064] landlock: Up and running. sshClient # [ 0.001068] Yama: becoming mindful. machine # [ 0.005574] CPU features: detected: Data cache clean to the PoU not required for I/D coherence sshClient # [ 0.001519] LSM support for eBPF active machine # [ 0.005581] CPU features: detected: Common not Private translations sshClient # [ 0.001676] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.005585] CPU features: detected: CRC32 instructions sshClient # [ 0.001697] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.005588] CPU features: detected: Data cache clean to Point of Deep Persistence sshClient # [ 0.003805] rcu: Hierarchical SRCU implementation. machine # [ 0.005591] CPU features: detected: Data cache clean to Point of Persistence sshClient # [ 0.003812] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.005359] fsl-mc MSI: its@8080000 domain created machine # [ 0.005594] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.005472] EFI services will not be available. machine # [ 0.005597] CPU features: detected: E0PD sshClient # [ 0.005550] smp: Bringing up secondary CPUs ... machine # [ 0.005600] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.005560] smp: Brought up 1 node, 1 CPU sshClient # [ 0.005564] SMP: Total of 1 processors activated. machine # [ 0.005603] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) sshClient # [ 0.005566] CPU: All CPU(s) started at EL1 machine # [ 0.005607] CPU features: detected: Enhanced Virtualization Traps sshClient # [ 0.005576] CPU features: detected: Branch Target Identification machine # [ 0.005610] CPU features: detected: Fine Grained Traps sshClient # [ 0.005580] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005614] CPU features: detected: Generic authentication (architected QARMA5 algorithm) sshClient # [ 0.005584] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005619] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005622] CPU features: detected: LSE atomic instructions sshClient # [ 0.005587] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005625] CPU features: detected: Privileged Access Never sshClient # [ 0.005594] CPU features: detected: Common not Private translations machine # [ 0.005628] CPU features: detected: PMUv3 sshClient # [ 0.005597] CPU features: detected: CRC32 instructions machine # [ 0.005631] CPU features: detected: RAS Extension Support sshClient # [ 0.005600] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005634] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005636] CPU features: detected: Random Number Generator sshClient # [ 0.005603] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005641] CPU features: detected: Speculation barrier (SB) sshClient # [ 0.005606] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005644] CPU features: detected: Stage-2 Force Write-Back sshClient # [ 0.005609] CPU features: detected: E0PD machine # [ 0.005647] CPU features: detected: TLB range maintenance instructions sshClient # [ 0.005611] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005652] CPU features: detected: Speculative Store Bypassing Safe (SSBS) sshClient # [ 0.005614] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005691] alternatives: applying system-wide alternatives sshClient # [ 0.005618] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.008859] CPU features: detected: BBM Level 2 without TLB conflict abort sshClient # [ 0.005621] CPU features: detected: Fine Grained Traps sshClient # [ 0.005624] CPU features: detected: Generic authentication (architected QARMA5 algorithm) sshClient # [ 0.005629] CPU features: detected: RCpc load-acquire (LDAPR) sshClient # [ 0.005631] CPU features: detected: LSE atomic instructions machine # [ 0.009060] Memory: 892900K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 114392K reserved, 32768K cma-reserved) sshClient # [ 0.005634] CPU features: detected: Privileged Access Never machine # [ 0.009421] devtmpfs: initialized sshClient # [ 0.005637] CPU features: detected: PMUv3 machine # [ 0.011286] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.005640] CPU features: detected: RAS Extension Support sshClient # [ 0.005642] CPU features: detected: RASv1p1 Extension Support machine # [ 0.011311] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.005645] CPU features: detected: Random Number Generator machine # [ 0.011503] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL sshClient # [ 0.005650] CPU features: detected: Speculation barrier (SB) machine # [ 0.011508] 0 pages in range for non-PLT usage sshClient # [ 0.005653] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.011509] 508192 pages in range for PLT usage machine # [ 0.011629] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.005656] CPU features: detected: TLB range maintenance instructions machine # [ 0.012526] DMI not present or invalid. sshClient # [ 0.005660] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.016123] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.005697] alternatives: applying system-wide alternatives machine # [ 0.018488] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.008867] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.018631] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.018791] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.009069] Memory: 892852K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 114392K reserved, 32768K cma-reserved) sshClient # [ 0.009440] devtmpfs: initialized machine # [ 0.018818] audit: initializing netlink subsys (disabled) machine # [ 0.019494] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.011381] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.019497] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.011408] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.019501] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.011601] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.019504] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.011606] 0 pages in range for non-PLT usage machine # [ 0.019507] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.011607] 508192 pages in range for PLT usage sshClient # [ 0.011729] pinctrl core: initialized pinctrl subsystem machine # [ 0.019535] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.012557] DMI not present or invalid. machine # [ 0.019544] cpuidle: using governor ladder sshClient # [ 0.015920] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.019550] cpuidle: using governor menu sshClient # [ 0.018332] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.019757] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.019776] ASID allocator initialised with 65536 entries sshClient # [ 0.018496] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.021162] Serial: AMBA PL011 UART driver sshClient # [ 0.018659] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.027303] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.018685] audit: initializing netlink subsys (disabled) machine # [ 0.027493] printk: console [ttyAMA0] enabled sshClient # [ 0.019415] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.019417] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.019421] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.019424] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.019427] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.019458] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.019467] cpuidle: using governor ladder sshClient # [ 0.019474] cpuidle: using governor menu sshClient # [ 0.019690] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. sshClient # [ 0.019709] ASID allocator initialised with 65536 entries sshClient # [ 0.021083] Serial: AMBA PL011 UART driver sshClient # [ 0.026738] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.026945] printk: console [ttyAMA0] enabled machine # [ 0.163282] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.163296] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.163300] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.163303] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.163306] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.163308] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.163311] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.163314] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.164654] fbcon: Taking over console machine # [ 0.164666] ACPI: Interpreter disabled. sshClient # [ 0.159705] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.175418] iommu: Default domain type: Translated sshClient # [ 0.159717] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.175426] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.175882] SCSI subsystem initialized sshClient # [ 0.159721] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages sshClient # [ 0.159723] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page sshClient # [ 0.159726] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.159729] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.178085] usbcore: registered new interface driver usbfs machine # [ 0.178120] usbcore: registered new interface driver hub sshClient # [ 0.159732] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.178135] usbcore: registered new device driver usb sshClient # [ 0.159735] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.178473] pps_core: LinuxPPS API ver. 1 registered sshClient # [ 0.161018] fbcon: Taking over console sshClient # [ 0.161028] ACPI: Interpreter disabled. machine # [ 0.178477] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.178486] PTP clock support registered sshClient # [ 0.171144] iommu: Default domain type: Translated machine # [ 0.178525] EDAC MC: Ver: 3.0.0 sshClient # [ 0.171151] iommu: DMA domain TLB invalidation policy: strict mode sshClient # [ 0.171612] SCSI subsystem initialized sshClient # [ 0.173788] usbcore: registered new interface driver usbfs machine # [ 0.184924] scmi_core: SCMI protocol bus registered sshClient # [ 0.173820] usbcore: registered new interface driver hub machine # [ 0.185332] FPGA manager framework machine # [ 0.188444] vgaarb: loaded sshClient # [ 0.173835] usbcore: registered new device driver usb sshClient # [ 0.174157] pps_core: LinuxPPS API ver. 1 registered sshClient # [ 0.174161] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.174169] PTP clock support registered sshClient # [ 0.174208] EDAC MC: Ver: 3.0.0 machine # [ 0.190276] clocksource: Switched to clocksource arch_sys_counter sshClient # [ 0.175665] scmi_core: SCMI protocol bus registered sshClient # [ 0.181504] FPGA manager framework sshClient # [ 0.183857] vgaarb: loaded machine # [ 0.195988] VFS: Disk quotas dquot_6.6.0 machine # [ 0.196024] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.185004] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.200115] netfs: FS-Cache loaded machine # [ 0.200264] pnp: PnP ACPI: disabled machine # [ 0.204868] NET: Registered PF_INET protocol family machine # [ 0.205021] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.193867] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.193899] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.196548] netfs: FS-Cache loaded sshClient # [ 0.196673] pnp: PnP ACPI: disabled sshClient # [ 0.202719] NET: Registered PF_INET protocol family sshClient # [ 0.203109] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.237924] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.237973] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.237999] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.238052] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.238125] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.238223] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.238259] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.234513] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.234559] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.234585] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.234630] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.234703] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.234795] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.245280] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.234828] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.245391] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.245410] NET: Registered PF_XDP protocol family sshClient # [ 0.234939] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.245429] PCI: CLS 0 bytes, default 64 sshClient # [ 0.235023] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.245696] Trying to unpack rootfs image as initramfs... sshClient # [ 0.235041] NET: Registered PF_XDP protocol family machine # [ 0.257929] kvm [1]: HYP mode not available sshClient # [ 0.235057] PCI: CLS 0 bytes, default 64 sshClient # [ 0.235325] Trying to unpack rootfs image as initramfs... sshClient # [ 0.253037] kvm [1]: HYP mode not available sshClient # [ 0.370595] Initialise system trusted keyrings sshClient # [ 0.370746] workingset: timestamp_bits=42 max_order=18 bucket_order=0 sshClient # [ 0.371143] squashfs: version 4.0 (2009/01/31) Phillip Lougher sshClient # [ 0.375503] 9p: Installing v9fs 9p2000 file system support machine # [ 0.387233] Initialise system trusted keyrings machine # [ 0.387396] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.387798] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.387853] 9p: Installing v9fs 9p2000 file system support sshClient # [ 0.396462] Key type asymmetric registered sshClient # [ 0.396478] Asymmetric key parser 'x509' registered machine # [ 0.416098] Key type asymmetric registered sshClient # [ 0.396562] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.416116] Asymmetric key parser 'x509' registered machine # [ 0.416199] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.416345] io scheduler mq-deadline registered machine # [ 0.416351] io scheduler kyber registered sshClient # [ 0.407309] io scheduler mq-deadline registered sshClient # [ 0.407320] io scheduler kyber registered machine # [ 0.429164] pl061_gpio 9030000.pl061: PL061 GPIO chip registered sshClient # [ 0.417183] pl061_gpio 9030000.pl061: PL061 GPIO chip registered sshClient # [ 0.417895] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.431256] ledtrig-cpu: registered to indicate activity on CPUs sshClient # [ 0.418344] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.431737] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: sshClient # [ 0.418361] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.431753] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 sshClient # [ 0.418386] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.431763] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 sshClient # [ 0.418393] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.431769] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 sshClient # [ 0.418412] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.431792] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits sshClient # [ 0.418440] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.431818] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] sshClient # [ 0.418530] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.431922] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 sshClient # [ 0.418538] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.431930] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.418542] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.431933] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] sshClient # [ 0.418545] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.431937] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] sshClient # [ 0.418548] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.431940] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] sshClient # [ 0.418641] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.432000] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.419235] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.432494] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.419435] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.432695] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.419449] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.432707] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.419476] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.432735] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.419488] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.432748] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.419994] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.433259] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.420190] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.433452] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.420202] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.433467] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.420231] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.433494] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.420725] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.434001] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.420930] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.434201] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] sshClient # [ 0.420942] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.434212] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.420968] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.434240] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.453600] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.453829] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.466910] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.453842] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.467149] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.453869] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.467162] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.453886] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.467190] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.454405] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.467202] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.454602] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.467719] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint sshClient # [ 0.454632] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.467916] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.455164] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.467943] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.455363] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.468440] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.455390] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.468636] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.455854] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.468664] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.456047] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.469141] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint sshClient # [ 0.456319] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.469345] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] sshClient # [ 0.456515] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.469618] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.456542] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.469819] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.469847] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.471849] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.472062] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.485383] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.472094] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.485591] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.472639] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.485619] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.472860] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.486144] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.472893] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.485539] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.485847] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.498411] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.485861] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.498449] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.485888] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.499041] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.486405] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.499351] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] sshClient # [ 0.486601] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.499365] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.486613] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.499392] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.486641] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.499898] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.487308] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.500093] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.487315] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.500105] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.487318] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.500132] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.500817] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned sshClient # [ 0.487366] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.500824] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned sshClient # [ 0.487412] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.500827] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned sshClient # [ 0.487460] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.500876] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned sshClient # [ 0.487506] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.500924] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned sshClient # [ 0.487553] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.500971] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned sshClient # [ 0.487598] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.501021] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned sshClient # [ 0.487645] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned sshClient # [ 0.487693] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned sshClient # [ 0.487738] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned sshClient # [ 0.487834] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.501068] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned sshClient # [ 0.487879] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned sshClient # [ 0.487898] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.501116] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned sshClient # [ 0.487915] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.501163] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned sshClient # [ 0.487933] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.501213] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned sshClient # [ 0.487952] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.501259] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned sshClient # [ 0.487971] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.501361] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned sshClient # [ 0.487990] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.501405] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned sshClient # [ 0.488010] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.501423] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned sshClient # [ 0.488029] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.501442] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned sshClient # [ 0.488047] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.488066] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.501460] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned sshClient # [ 0.488086] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.501478] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.488104] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.501496] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.488121] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.501515] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned sshClient # [ 0.488139] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.501535] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned sshClient # [ 0.488158] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.501553] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned sshClient # [ 0.488176] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.501572] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.488194] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.501591] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.488218] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] sshClient # [ 0.488224] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.501609] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.488227] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.501627] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.501645] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned sshClient # [ 0.528484] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.501663] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.501681] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.501699] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.501717] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.501741] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.501746] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.501749] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.519571] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.569006] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) sshClient # [ 0.557400] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) sshClient # [ 0.559662] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.579084] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.581900] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.569703] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.572147] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.591872] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) sshClient # [ 0.582529] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) sshClient # [ 0.584708] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) sshClient # [ 0.588470] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.603042] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.605324] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.609157] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) sshClient # [ 0.598605] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) sshClient # [ 0.600606] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) sshClient # [ 0.604382] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.619528] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.621668] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.625547] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) sshClient # [ 0.614725] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.635984] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) sshClient # [ 0.626473] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.628458] msm_serial: driver initialized sshClient # [ 0.628593] SuperH (H)SCI(F) driver initialized sshClient # [ 0.628651] STM32 USART driver initialized machine # [ 0.647914] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.650067] msm_serial: driver initialized machine # [ 0.650234] SuperH (H)SCI(F) driver initialized machine # [ 0.652372] STM32 USART driver initialized sshClient # [ 0.670811] loop: module loaded sshClient # [ 0.671115] virtio_blk virtio2: 1/0/0 default/read/poll queues sshClient # [ 0.672013] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.689590] loop: module loaded machine # [ 0.689804] virtio_blk virtio2: 1/0/0 default/read/poll queues sshClient # [ 0.677718] megasas: 07.734.00.00-rc1 sshClient # [ 0.678496] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.698883] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 0.694319] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.694345] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.696243] Using buffer write method machine # [ 0.704340] megasas: 07.734.00.00-rc1 sshClient # [ 0.696288] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.705151] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] sshClient # [ 0.701811] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.701831] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.703508] Using buffer write method sshClient # [ 0.703526] Concatenating MTD devices: sshClient # [ 0.703529] (0): "0.flash" sshClient # [ 0.703531] (1): "0.flash" sshClient # [ 0.703533] into device "0.flash" machine # [ 0.719622] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.719653] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.721561] Using buffer write method machine # [ 0.721620] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.735810] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.735842] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.737608] Using buffer write method machine # [ 0.737634] Concatenating MTD devices: machine # [ 0.737637] (0): "0.flash" machine # [ 0.737639] (1): "0.flash" machine # [ 0.737641] into device "0.flash" machine # [ 1.044544] Freeing initrd memory: 27148K sshClient # [ 1.033787] Freeing initrd memory: 27148K sshClient # [ 1.039870] tun: Universal TUN/TAP device driver, 1.6 machine # [ 1.051461] tun: Universal TUN/TAP device driver, 1.6 sshClient # [ 1.044627] thunder_xcv, ver 1.0 sshClient # [ 1.044656] thunder_bgx, ver 1.0 sshClient # [ 1.044675] nicpf, ver 1.0 machine # [ 1.055790] thunder_xcv, ver 1.0 machine # [ 1.055822] thunder_bgx, ver 1.0 machine # [ 1.055842] nicpf, ver 1.0 machine # [ 1.056450] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.056455] e1000: Copyright (c) 1999-2006 Intel Corporation. sshClient # [ 1.046658] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.056483] e1000e: Intel(R) PRO/1000 Network Driver sshClient # [ 1.046663] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.056491] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. sshClient # [ 1.046691] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 1.056516] igb: Intel(R) Gigabit Ethernet Network Driver sshClient # [ 1.046701] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 1.056520] igb: Copyright (c) 2007-2014 Intel Corporation. sshClient # [ 1.046725] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 1.056541] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 1.046728] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 1.056545] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.056691] sky2: driver version 1.30 sshClient # [ 1.046748] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 1.059198] ehci-pci 0000:00:07.0: EHCI Host Controller sshClient # [ 1.046752] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. sshClient # [ 1.047009] sky2: driver version 1.30 machine # [ 1.059231] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 1.059474] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 sshClient # [ 1.048738] usbcore: registered new interface driver usb-storage sshClient # [ 1.048861] usbcore: registered new interface driver usbserial_generic sshClient # [ 1.048874] usbserial: USB Serial support registered for generic machine # [ 1.070080] usbcore: registered new interface driver usb-storage sshClient # [ 1.049698] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 1.070245] usbcore: registered new interface driver usbserial_generic sshClient # [ 1.049722] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 1.070258] usbserial: USB Serial support registered for generic sshClient # [ 1.049905] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.070322] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 1.070659] hub 1-0:1.0: USB hub found machine # [ 1.070683] hub 1-0:1.0: 6 ports detected sshClient # [ 1.061936] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 sshClient # [ 1.062378] hub 1-0:1.0: USB hub found machine # [ 1.076165] hv_vmbus: registering driver hyperv_keyboard sshClient # [ 1.062402] hub 1-0:1.0: 6 ports detected machine # [ 1.077177] rtc-pl031 9010000.pl031: registered as rtc0 sshClient # [ 1.063559] hv_vmbus: registering driver hyperv_keyboard sshClient # [ 1.064525] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.077201] rtc-pl031 9010000.pl031: setting system clock to 2026-10-18T02:43:36 UTC (1792291416) machine # [ 1.077529] i2c_dev: i2c /dev entries driver sshClient # [ 1.064555] rtc-pl031 9010000.pl031: setting system clock to 2026-10-18T02:43:36 UTC (1792291416) sshClient # [ 1.064915] i2c_dev: i2c /dev entries driver machine # [ 1.084362] sdhci: Secure Digital Host Controller Interface driver machine # [ 1.084370] sdhci: Copyright(c) Pierre Ossman machine # [ 1.084671] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.085082] sdhci-pltfm: SDHCI platform and OF driver helper sshClient # [ 1.073762] sdhci: Secure Digital Host Controller Interface driver sshClient # [ 1.073772] sdhci: Copyright(c) Pierre Ossman machine # [ 1.089844] hid: raw HID events driver (C) Jiri Kosina sshClient # [ 1.074082] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.090119] usbcore: registered new interface driver usbhid sshClient # [ 1.074484] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.090123] usbhid: USB HID core driver sshClient # [ 1.076236] hid: raw HID events driver (C) Jiri Kosina sshClient # [ 1.076521] usbcore: registered new interface driver usbhid sshClient # [ 1.076526] usbhid: USB HID core driver machine # [ 1.093513] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available sshClient # [ 1.083074] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.096652] drop_monitor: Initializing network drop monitor service sshClient # [ 1.084797] drop_monitor: Initializing network drop monitor service machine # [ 1.096851] NET: Registered PF_INET6 protocol family sshClient # [ 1.084959] NET: Registered PF_INET6 protocol family machine # [ 1.097431] Segment Routing with IPv6 machine # [ 1.097444] In-situ OAM (IOAM) with IPv6 machine # [ 1.097472] NET: Registered PF_PACKET protocol family sshClient # [ 1.088810] Segment Routing with IPv6 machine # [ 1.097528] 9pnet: Installing 9P2000 support sshClient # [ 1.088852] In-situ OAM (IOAM) with IPv6 machine # [ 1.097572] Key type dns_resolver registered sshClient # [ 1.088906] NET: Registered PF_PACKET protocol family sshClient # [ 1.088982] 9pnet: Installing 9P2000 support sshClient # [ 1.091412] Key type dns_resolver registered machine # [ 1.108399] registered taskstats version 1 machine # [ 1.108585] Loading compiled-in X.509 certificates sshClient # [ 1.098837] registered taskstats version 1 sshClient # [ 1.099037] Loading compiled-in X.509 certificates machine # [ 1.117851] Demotion targets for Node 0: null machine # [ 1.117988] Key type .fscrypt registered machine # [ 1.117992] Key type fscrypt-provisioning registered machine # [ 1.118102] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.118121] ima: Allocated hash algorithm: sha1 sshClient # [ 1.108296] Demotion targets for Node 0: null machine # [ 1.118146] ima: No architecture policies found sshClient # [ 1.108436] Key type .fscrypt registered sshClient # [ 1.108441] Key type fscrypt-provisioning registered sshClient # [ 1.108550] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 1.108569] ima: Allocated hash algorithm: sha1 sshClient # [ 1.108594] ima: No architecture policies found machine # [ 1.122748] input: gpio-keys as /devices/platform/gpio-keys/input/input0 sshClient # [ 1.113395] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.142513] clk: Disabling unused clocks machine # [ 1.142531] PM: genpd: Disabling unused power domains machine # [ 1.145540] Freeing unused kernel memory: 4736K machine # [ 1.145768] Run /init as init process sshClient # [ 1.133313] clk: Disabling unused clocks sshClient # [ 1.133336] PM: genpd: Disabling unused power domains sshClient # [ 1.136549] Freeing unused kernel memory: 4736K sshClient # [ 1.136770] Run /init as init process machine # [ 1.168063] fuse: init (API version 7.45) sshClient # [ 1.158952] fuse: init (API version 7.45) machine # [ 1.171760] virtiofs virtio6: discovered new tag: nix-store machine # [ 1.172580] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 1.162788] virtiofs virtio6: discovered new tag: nix-store sshClient # [ 1.163688] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.188127] virtiofs virtio7: discovered new tag: shared machine # [ 1.188873] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.190232] virtiofs virtio8: discovered new tag: xchg sshClient # [ 1.178754] virtiofs virtio7: discovered new tag: shared sshClient # [ 1.179827] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.193335] virtiofs virtio8: virtio_fs_setup_dax: No cache capability sshClient # [ 1.182925] virtiofs virtio8: discovered new tag: xchg sshClient # [ 1.183719] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 1.205168] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.196131] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.205681] systemd[1]: systemd 263-409be20 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 +EXPERIMENTAL-ENVVAR) machine # [ 1.205696] systemd[1]: Detected virtualization qemu. machine # [ 1.205718] systemd[1]: Detected architecture arm64. machine # [ 1.205724] systemd[1]: Running in initrd. machine # [ 1.206016] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.196684] systemd[1]: systemd 263-409be20 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 +EXPERIMENTAL-ENVVAR) machine # [ 1.206124] systemd[1]: Hostname set to . sshClient # [ 1.196701] systemd[1]: Detected virtualization qemu. sshClient # [ 1.196724] systemd[1]: Detected architecture arm64. sshClient # [ 1.196730] systemd[1]: Running in initrd. sshClient # [ 1.204431] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.204581] systemd[1]: Hostname set to . machine # [ 1.305755] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.298370] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.309111] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.322501] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.363131] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.369604] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.369798] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.369826] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.369848] systemd[1]: Reached target Path Units. machine # [ 1.369860] systemd[1]: Reached target Slice Units. machine # [ 1.369874] systemd[1]: Reached target Swaps. sshClient # [ 1.359038] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.369887] systemd[1]: Reached target Timer Units. machine # [ 1.370019] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.370185] systemd[1]: Listening on Journal Socket (/dev/log). sshClient # [ 1.364312] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.377225] systemd[1]: Listening on Journal Sockets. machine # [ 1.377337] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.377352] systemd[1]: Reached target Socket Units. sshClient # [ 1.364500] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.364529] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.364552] systemd[1]: Reached target Path Units. sshClient # [ 1.364566] systemd[1]: Reached target Slice Units. sshClient # [ 1.364580] systemd[1]: Reached target Swaps. sshClient # [ 1.364594] systemd[1]: Reached target Timer Units. sshClient # [ 1.364730] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.380929] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.364878] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.380988] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 1.371845] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.371953] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.371967] systemd[1]: Reached target Socket Units. sshClient # [ 1.375891] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.375953] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.390541] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.385221] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.394044] systemd[1]: Starting Journal Service... sshClient # [ 1.397938] systemd[1]: Starting Load Kernel Modules... sshClient # [ 1.398063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.414525] systemd[1]: Starting Journal Service... machine # [ 1.426496] systemd[1]: Starting Load Kernel Modules... machine # [ 1.426611] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.430780] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.429708] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.448403] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.449239] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 1.449054] systemd-journald[71]: Collecting audit messages is disabled. sshClient # [ 1.454587] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.467416] systemd-journald[72]: Collecting audit messages is disabled. machine # [ 1.470931] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.469545] 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.488301] 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.495561] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.496362] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.497088] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.532337] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.533291] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.517416] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.526524] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 sshClient # [ 1.526773] [drm] features: -virgl +edid -resource_blob -host_visible sshClient # [ 1.526776] [drm] features: -context_init sshClient # [ 1.527758] [drm] number of scanouts: 1 sshClient # [ 1.527771] [drm] number of cap sets: 0 machine # [ 1.546412] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.551489] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.549254] 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 machine # [ 1.564097] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.564342] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.564345] [drm] features: -context_init machine # [ 1.565164] [drm] number of scanouts: 1 machine # [ 1.565176] [drm] number of cap sets: 0 sshClient # [ 1.555530] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.558490] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic sshClient # [ 1.558502] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 sshClient # [ 1.563433] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.578513] 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 machine # [ 1.590715] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.590734] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 sshClient # [ 1.585806] Console: switching to colour frame buffer device 160x50 machine # [ 1.618630] Console: switching to colour frame buffer device 160x50 sshClient # [ 1.609735] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.627125] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.627583] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.627604] systemd[1]: Reached target Local File Systems. sshClient # [ 1.621674] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.626177] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.642963] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.647116] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device sshClient # [ 1.645316] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.645586] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.645620] systemd[1]: Reached target Local File Systems. machine # [ 1.645509] systemd-modules-load[73]: Inserted module 'dm_mod' machine # [ 1.662683] systemd[1]: Started Journal Service. machine # [ 1.648872] systemd-modules-load[73]: Module 'virtio_balloon' is built in sshClient # [ 1.643233] systemd-modules-load[72]: Inserted module 'dm_mod' machine # [ 1.658886] systemd-modules-load[73]: Module 'virtio_console' is built in machine # [ 1.664461] systemd-modules-load[73]: Inserted module 'virtio_gpu' sshClient # [ 1.651445] systemd-modules-load[72]: Module 'virtio_balloon' is built in[ 1.665534] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.665896] systemd[1]: Started Journal Service. sshClient # [ 1.669536] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.670052] systemd-modules-load[73]: Module 'virtio_rng' is built in machine # [ 1.672328] systemd[1]: Finished Load Kernel Modules. sshClient # sshClient # [ 1.663940] systemd-modules-load[72]: Module 'virtio_console' is built in sshClient # [ 1.668493] systemd-modules-load[72]: Inserted module 'virtio_gpu' sshClient # [ 1.669797] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.698349] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.685341] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.689333] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.681237] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.685275] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.740969] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.730146] systemd[1]: Finished Create System Files and Directories. machine # [ 1.744967] systemd-udevd[79]: Using default interface naming scheme 'v261'. machine # [ 1.752318] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.757540] systemd-udevd[80]: Using default interface naming scheme 'v261'. machine # [ 1.778351] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.786755] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.829873] 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.830155] 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.862031] 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.865256] 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.888142] systemd[1]: Starting Virtual Console Setup... sshClient # [ 1.902204] systemd[1]: Starting Virtual Console Setup... machine # [ 1.964981] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.972420] systemd[1]: Finished Virtual Console Setup. sshClient # [ 1.988911] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 1.992638] systemd[1]: Finished Virtual Console Setup. machine # [ 2.672396] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.700931] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.703974] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.706484] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.720912] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.722683] systemd[1]: Reached target System Initialization. machine # [ 2.725233] systemd[1]: Reached target Basic System. sshClient # [ 2.718586] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 2.720486] systemd[1]: Reached target System Initialization. sshClient # [ 2.722815] systemd[1]: Reached target Basic System. machine # [ 2.747374] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.748564] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.738893] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.749914] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 2.742430] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.743876] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.804616] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 2.796995] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.812218] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 2.804198] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.817248] systemd[1]: Mounting /sysroot... sshClient # [ 2.806925] systemd[1]: Mounting /sysroot... machine # [ 2.871171] EXT4-fs (vda): mounted filesystem 9b2166a4-964f-466b-9519-db572dfb4570 r/w with ordered data mode. Quota mode: none. machine # [ 2.858714] systemd[1]: Mounted /sysroot. machine # [ 2.861295] systemd[1]: Reached target Initrd Root File System. machine # [ 2.868094] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 2.874739] EXT4-fs (vda): mounted filesystem e6a711f9-5e8e-4d29-bd17-5e01004f03d0 r/w with ordered data mode. Quota mode: none. sshClient # [ 2.865098] systemd[1]: Mounted /sysroot. sshClient # [ 2.869793] systemd[1]: Reached target Initrd Root File System. sshClient # [ 2.876134] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.901457] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.907959] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.912658] systemd[1]: Reloading... sshClient # [ 2.912749] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload. sshClient # [ 2.916571] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 2.924134] systemd[1]: Reloading... machine # [ 3.120639] systemd[1]: Reloading finished in 210 ms. sshClient # [ 3.129486] systemd[1]: Reloading finished in 210 ms. machine # [ 3.153827] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace... machine # [ 3.158679] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace... machine # [ 3.168411] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.171201] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.173718] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 3.163854] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.168761] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace... sshClient # [ 3.173451] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 3.177665] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 3.180171] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.419655] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.431020] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.430129] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.451233] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.442448] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.457517] systemd[1]: Mounting /sysroot/tmp/shared... sshClient # [ 3.454209] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.467210] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.490576] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 3.488633] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.527025] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.542283] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.559697] systemd[1]: Mounted /sysroot/run. sshClient # [ 3.549654] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 3.563438] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.576286] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 3.573602] systemd[1]: Mounted /sysroot/run. machine # [ 3.593362] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.597688] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 3.591458] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 3.595981] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 3.610327] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.625169] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.626810] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.637442] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 3.639291] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.057202] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/126w02q3bp3kh7554k53zak86km11gnz-systemd-263-409be20/lib/udev/mtd_probe": No such file or directory machine # [ 4.063563] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory sshClient # [ 4.062741] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/126w02q3bp3kh7554k53zak86km11gnz-systemd-263-409be20/lib/udev/mtd_probe": No such file or directory sshClient # [ 4.070418] (udev-worker)[98]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory machine # [ 4.091514] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.093424] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.096412] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.097466] systemd[1]: Starting Virtual Console Setup... sshClient # [ 4.102163] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.115581] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.117494] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.108202] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.109990] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.121380] systemd[1]: Starting Virtual Console Setup... sshClient # [ 4.111179] systemd[1]: Starting Virtual Console Setup... machine # [ 4.153032] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.157060] systemd[1]: Finished Virtual Console Setup. sshClient # [ 4.149257] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 4.154016] systemd[1]: Finished Virtual Console Setup. machine # [ 4.418145] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 4.430201] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.464892] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.467374] systemd[1]: Reached target Initrd File Systems. machine # [ 4.472201] systemd[1]: Starting Find NixOS closure... machine # [ 4.478055] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.481288] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 4.483994] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.488795] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.500540] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.526747] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.530997] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.545272] systemd[1]: Finished Find NixOS closure. machine # [ 4.548303] systemd[1]: Reached target Initrd Default Target. machine # [ 4.551396] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 4.543094] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 4.547125] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 4.561239] systemd[1]: Finished Find NixOS closure. sshClient # [ 4.564145] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.565716] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.581100] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.584258] systemd[1]: Stopped target Basic System. machine # [ 4.585447] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.588383] systemd[1]: Stopped target Path Units. machine # [ 4.590657] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.592425] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.599857] systemd[1]: Stopped target Slice Units. sshClient # [ 4.591612] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 4.593468] systemd[1]: Stopped target Basic System. machine # [ 4.605408] systemd[1]: Stopped target Socket Units. machine # [ 4.606249] systemd[1]: Stopped target System Initialization. machine # [ 4.607153] systemd[1]: Stopped target Swaps. sshClient # [ 4.596466] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.607876] systemd[1]: Stopped target Timer Units. sshClient # [ 4.597502] systemd[1]: Stopped target Path Units. sshClient # [ 4.599151] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.612973] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.602492] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. sshClient # [ 4.604919] systemd[1]: Stopped target Slice Units. machine # [ 4.617797] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.608195] systemd[1]: Stopped target Socket Units. sshClient # [ 4.609187] systemd[1]: Stopped target System Initialization. machine # [ 4.619060] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.621801] systemd[1]: Stopped Find NixOS closure. sshClient # [ 4.611138] systemd[1]: Stopped target Swaps. machine # [ 4.622626] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.623530] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 4.613196] systemd[1]: Stopped target Timer Units. sshClient # [ 4.621258] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.633412] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.634387] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.635647] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 4.623844] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.629986] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 4.631157] systemd[1]: Stopped Find NixOS closure. machine # [ 4.642226] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 4.631923] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.646239] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.648188] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.637994] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 4.640268] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.652263] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 4.641455] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.653366] systemd[1]: Stopped target Local File Systems. sshClient # [ 4.642618] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 4.643406] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.655434] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.658591] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.659665] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.661808] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.651051] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.663815] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.669589] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.659093] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.660833] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.672218] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.661797] systemd[1]: Stopped target Local File Systems. sshClient # [ 4.662738] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.674442] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.663725] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.669220] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.680291] systemd[1]: systemd-udevd.service: Consumed 1.367s CPU time over 2.996s wall clock time, 24.7M memory peak. machine # [ 4.682025] systemd[1]: Starting Cleanup udev Database... machine # [ 4.682867] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.672204] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.674197] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.676298] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.688153] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.689271] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.679011] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.690475] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.680466] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.691557] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.681485] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.682534] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.683620] systemd[1]: systemd-udevd.service: Consumed 1.394s CPU time over 3.000s wall clock time, 43.1M memory peak. sshClient # [ 4.685396] systemd[1]: Starting Cleanup udev Database... machine # [ 4.696497] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.686354] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.697565] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 4.687491] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.698570] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 4.692302] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.704107] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.705358] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.695077] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.700562] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.702028] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.703021] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.704224] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.728965] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.730846] systemd[1]: Finished Cleanup udev Database. machine # [ 4.734961] systemd[1]: Reached target Switch Root. machine # [ 4.740356] systemd[1]: Starting NixOS Activation... sshClient # [ 4.741403] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. sshClient # [ 4.743310] systemd[1]: Finished Cleanup udev Database. sshClient # [ 4.746230] systemd[1]: Reached target Switch Root. sshClient # [ 4.747485] systemd[1]: Starting NixOS Activation... machine # [ 4.829989] initrd-nixos-activation-start[175]: booting system configuration /nix/store/j3j1zhi3nlr9yh7n27j8jwmbnawgjk2n-nixos-system-machine-test sshClient # [ 4.841680] initrd-nixos-activation-start[174]: booting system configuration /nix/store/gmsqir826lmca28d8im6nynpl3zwlpxb-nixos-system-sshClient-test machine # [ 4.871081] initrd-nixos-activation-start[175]: running activation script... sshClient # [ 4.883558] initrd-nixos-activation-start[174]: running activation script... machine # [ 5.115485] initrd-nixos-activation-start[198]: setting up /etc... sshClient # [ 5.147854] initrd-nixos-activation-start[197]: setting up /etc... machine # [ 5.250556] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.253869] systemd[1]: Finished NixOS Activation. machine # [ 5.255190] systemd[1]: Starting Switch Root... machine # [ 5.282021] systemd[1]: Switching root. sshClient # [ 5.293092] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 5.296239] systemd[1]: Finished NixOS Activation. sshClient # [ 5.298301] systemd[1]: Starting Switch Root... sshClient # [ 5.326152] systemd[1]: Switching root. machine # [ 5.464113] systemd-journald[72]: Received SIGTERM from PID 1 (systemd). sshClient # [ 5.511747] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 5.564334] NET: Registered PF_VSOCK protocol family sshClient # [ 5.613225] NET: Registered PF_VSOCK protocol family machine # [ 5.645992] systemd[1]: systemd 263-409be20 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 +EXPERIMENTAL-ENVVAR) machine # [ 5.653869] systemd[1]: Detected virtualization qemu. machine # [ 5.653932] systemd[1]: Detected architecture arm64. machine # [ 5.654035] systemd[1]: Detected first boot. machine # [ 5.659163] systemd[1]: Initializing machine ID from random generator. sshClient # [ 5.698913] systemd[1]: systemd 263-409be20 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 +EXPERIMENTAL-ENVVAR) sshClient # [ 5.699868] systemd[1]: Detected virtualization qemu. sshClient # [ 5.699929] systemd[1]: Detected architecture arm64. sshClient # [ 5.700020] systemd[1]: Detected first boot. sshClient # [ 5.711350] systemd[1]: Initializing machine ID from random generator. machine # [ 5.779228] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 5.848681] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.931279] systemd[1]: Applying preset policy. sshClient # [ 6.011500] systemd[1]: Applying preset policy. machine # [ 6.160353] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 6.240468] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.390151] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.390640] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.392717] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.398726] systemd[1]: Created slice Slice /system/getty. machine # [ 6.400235] systemd[1]: Created slice User and Session Slice. machine # [ 6.400856] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.401620] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.406589] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.407080] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.407905] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.408612] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.409288] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.409976] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.415862] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.416372] systemd[1]: Reached target Path Units. machine # [ 6.417176] systemd[1]: Reached target Remote File Systems. machine # [ 6.417908] systemd[1]: Reached target Slice Units. machine # [ 6.422186] systemd[1]: Reached target Swaps. machine # [ 6.424681] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.427842] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.429282] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.432604] systemd[1]: Listening on Factory Reset Management. machine # [ 6.433198] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.439368] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.440427] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.444609] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.445555] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.446072] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.450109] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.458556] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.459417] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.460276] systemd[1]: Listening on User Database Manager Socket. machine # [ 6.465808] systemd[1]: Mounting Huge Pages File System... sshClient # [ 6.460774] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.464055] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.475748] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.468009] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.472246] systemd[1]: Created slice Slice /system/getty. sshClient # [ 6.475250] systemd[1]: Created slice User and Session Slice. machine # [ 6.486725] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 6.475888] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.476876] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.481786] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 6.482553] systemd[1]: Expecting device /dev/ttyAMA0... sshClient # [ 6.483326] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 6.483930] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 6.484748] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 6.490719] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.504285] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.491329] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.492146] systemd[1]: Reached target Path Units. sshClient # [ 6.496292] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.496931] systemd[1]: Reached target Slice Units. sshClient # [ 6.499894] systemd[1]: Reached target Swaps. sshClient # [ 6.502681] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 6.506268] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.507856] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.518385] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.520772] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 6.511281] systemd[1]: Listening on Factory Reset Management. sshClient # [ 6.511949] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 6.517710] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 6.519597] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.524006] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 6.527005] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 6.528147] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.532325] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.546538] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.538393] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 6.539292] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 6.540121] systemd[1]: Listening on User Database Manager Socket. machine # [ 6.555089] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.555681] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.546468] systemd[1]: Mounting Huge Pages File System... machine # [ 6.557332] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 6.551619] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.569245] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.588615] systemd[1]: Mounting FUSE Control File System... machine # [ 6.589075] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 6.585777] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.603839] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.609334] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.624547] systemd[1]: Starting Journal Service... machine # [ 6.640513] systemd[1]: Starting Load Kernel Modules... sshClient # [ 6.639713] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.640439] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.648087] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.663296] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 6.653335] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.686500] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.686936] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.680275] systemd[1]: Mounting FUSE Control File System... sshClient # [ 6.680777] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.709947] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.719337] systemd-journald[269]: Collecting audit messages is enabled. sshClient # [ 6.720050] systemd[1]: Starting Journal Service... machine # [ 6.717103] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.735847] systemd[1]: Started Journal Service. sshClient # [ 6.737409] systemd[1]: Starting Load Kernel Modules... sshClient # [ 6.769465] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 6.781244] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 6.781684] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.809491] systemd-journald[268]: Collecting audit messages is enabled. sshClient # [ 6.817163] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 6.820126] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 6.820596] systemd[1]: Mounted Huge Pages File System. sshClient # [ 6.809157] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 6.812133] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0. machine # [ 6.838345] EXT4-fs (vda): re-mounted 9b2166a4-964f-466b-9519-db572dfb4570. sshClient # [ 6.814210] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. sshClient # [ 6.815485] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 6.825977] systemd-modules-load[269]: Module 'atkbd' is built in sshClient # [ 6.827188] systemd-modules-load[269]: Module 'loop' is built in sshClient # [ 6.846264] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 6.848425] systemd[1]: Started Journal Service. sshClient # [ 6.846804] systemd[1]: Mounted Kernel Debug File System. sshClient # [ 6.859872] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 6.863774] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 6.868694] systemd[1]: Finished Load Kernel Modules. sshClient # [ 6.929747] EXT4-fs (vda): re-mounted e6a711f9-5e8e-4d29-bd17-5e01004f03d0. sshClient # [ 6.924313] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 6.925425] systemd[1]: Mounted FUSE Control File System. sshClient # [ 6.947886] systemd[1]: Starting Firewall... sshClient # [ 6.950161] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 6.962708] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 6.976207] systemd[1]: Finished Remount Root and Kernel File Systems. sshClient # [ 6.977472] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 7.024556] systemd-oomd[271]: No swap; memory pressure usage will be degraded sshClient # [ 7.036488] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 7.037714] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 7.054239] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 7.055351] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.073093] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. sshClient # [ 7.149771] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 7.188015] systemd-journald[268]: Received client request to flush runtime journal. sshClient # [ 7.248571] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 7.257071] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 7.273101] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.281695] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.721968] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0. machine # [ 7.364510] systemd-journald[269]: Received client request to flush runtime journal. machine # [ 7.413443] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. machine # [ 7.414926] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 7.405156] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 7.410330] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 7.418697] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.430443] systemd-modules-load[270]: Module 'atkbd' is built in machine # [ 7.431657] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.440174] systemd[1]: Mounted Huge Pages File System. machine # [ 7.441306] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.442346] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.443303] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.457172] systemd-modules-load[270]: Module 'loop' is built in machine # [ 7.458295] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.459360] systemd[1]: Finished Load Kernel Modules. sshClient # [ 7.457654] systemd[1]: Mounting /run/wrappers... machine # [ 7.476364] systemd[1]: Starting Firewall... machine # [ 7.482942] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.490388] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.491753] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.500855] systemd-oomd[271]: No swap; memory pressure usage will be degraded machine # [ 7.502482] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.503570] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.517762] systemd[1]: Mounted FUSE Control File System. machine # [ 7.518639] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.519618] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.532365] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.534406] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.535263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.545401] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.546279] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.547102] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.538151] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.539039] systemd[1]: Reached target Local File Systems. machine # [ 7.552538] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.553607] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 7.548223] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.559809] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 7.552278] systemd[1]: Starting Create SUID/SGID Wrappers... sshClient # [ 7.553319] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.570639] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.571900] systemd[1]: Mounting /run/wrappers... sshClient # [ 7.568314] systemd[1]: Starting Create System Files and Directories... machine # [ 7.580370] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.581846] systemd-udevd[295]: Using default interface naming scheme 'v261'. machine # [ 7.582913] systemd[1]: Starting User Database Manager... machine # [ 7.583760] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.587539] systemd-udevd[309]: Using default interface naming scheme 'v261'. machine # [ 7.599051] systemd[1]: Reached target Local File Systems. machine # [ 7.599976] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.610924] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.611876] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.624319] systemd[1]: Starting Create System Files and Directories... sshClient # [ 7.630432] systemd[1]: Starting User Database Manager... machine # [ 7.727129] systemd[1]: Started User Database Manager. sshClient # [ 7.763279] systemd[1]: Finished Create System Files and Directories. sshClient # [ 7.771945] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 7.786326] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 7.810633] systemd[1]: Finished Create System Files and Directories. machine # [ 7.826317] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 7.825022] systemd[1]: Started User Database Manager. machine # [ 7.841145] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 7.952109] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 7.988874] systemd[1]: Finished Record System Boot/Shutdown in UTMP. sshClient # [ 7.980718] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 7.990732] systemd[1]: Starting Update is Completed... machine # [ 8.032372] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.039302] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.066807] systemd[1]: Starting Update is Completed... sshClient # [ 8.066556] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 8.096593] systemd[1]: Finished Update is Completed. machine # [ 8.159003] systemd[1]: Finished Update is Completed. machine # [ 8.287028] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.354911] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 8.444579] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 8.514283] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 8.648161] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 8.652274] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.712413] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 8.772710] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.812272] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. sshClient # [ 8.814721] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 8.827837] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.831202] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.835274] systemd[1]: Reached target System Initialization. machine # [ 8.838518] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 8.839612] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 8.847457] systemd[1]: Reached target Timer Units. machine # [ 8.849479] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 8.850468] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). sshClient # [ 8.840285] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 8.851776] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 8.846193] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 8.847196] systemd[1]: Reached target System Initialization. machine # [ 8.861105] systemd[1]: Reached target Socket Units. machine # [ 8.861951] systemd[1]: Reached target Basic System. machine # [ 8.862727] systemd[1]: Started backdoor.service. sshClient # [ 8.852538] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 8.853681] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 8.854725] systemd[1]: Reached target Timer Units. sshClient # [ 8.855487] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 8.871514] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 8.875277] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 8.865664] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 8.867000] systemd[1]: Reached target Socket Units. sshClient # [ 8.867842] systemd[1]: Reached target Basic System. machine # [ 8.883414] systemd[1]: Starting Post-Boot Actions... sshClient # [ 8.878116] systemd[1]: Started backdoor.service. sshClient # [ 8.878913] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 8.895354] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.919419] systemd[1]: Started Reset console on configuration changes. sshClient # [ 8.938162] systemd[1]: Starting Post-Boot Actions... machine # [ 8.958427] systemd[1]: Starting resolvconf update... sshClient # [ 8.969581] systemd[1]: Started Reset console on configuration changes. machine # [ 8.989245] (udev-worker)[375]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 8.991635] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.020430] systemd[1]: Starting SSH Host Keys Generation... sshClient # [ 9.024192] systemd[1]: Starting resolvconf update... machine # [ 9.044346] systemd[1]: Starting Home Area Manager... sshClient # [ 9.047317] systemd[1]: Starting Home Area Manager... sshClient # connecting to host... sshClient # [ 9.104888] nsncd[447]: Oct 18 02:43:44.553 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # connecting to host... sshClient # [ 9.122730] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.162300] systemd[1]: Finished Post-Boot Actions. machine # [ 9.171901] nsncd[452]: Oct 18 02:43:44.612 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine: Guest shell says: b'Spawning backdoor root shell...\n' sshClient # [ 9.180547] (udev-worker)[377]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.193120] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.194066] systemd[1]: Finished Post-Boot Actions. sshClient # [ 9.200141] (udev-worker)[377]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 9.209820] systemd[1]: Reached target Host and Network Name Lookups. machine: connected to guest root shell sshClient # [ 9.220171] systemd[1]: Reached target User and Group Name Lookups. machine: (connecting took 9.55 seconds) machine: (finished: waiting for the VM to finish booting, in 9.55 seconds) sshClient # [ 9.236611] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.249368] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.250412] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.281551] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 9.276161] systemd[1]: Starting User Login Management... sshClient # [ 9.312755] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.324234] systemd[1]: Starting User Login Management... sshClient # [ 9.373721] systemd-homed[458]: Watching /home. machine # [ 9.407911] systemd-homed[461]: Watching /home. machine # [ 9.425207] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 9.498782] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 9.511604] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync' sshClient # [ 9.515435] dbus-broker-launch[485]: Invalid user-name in /nix/store/93gyp144815js4024wabbd432qckxf4s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 9.531755] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 9.538824] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 9.539843] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 9.551064] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 9.556279] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 9.557282] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 9.558276] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 9.571163] systemd-logind[491]: New seat seat0. sshClient # [ 9.582138] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 9.583093] systemd[1]: Started User Login Management. machine # [ 9.604880] dbus-broker-launch[495]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 9.600499] systemd[1]: Starting linger-users.service... sshClient # [ 9.614457] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.611641] dbus-broker-launch[495]: NSS returned no entry for 'systemd-timesync' machine # [ 9.627806] dbus-broker-launch[495]: Invalid user-name in /nix/store/mnbsyjaj69qm8xxffv4wsrlv74mp8g36-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 9.639372] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 9.648773] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 9.649770] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 9.650666] systemd[1]: Stopping User and Group Name Lookups... machine # [ 9.651523] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 9.641415] dbus-broker-launch[485]: Ready machine # [ 9.669696] systemd[1]: nscd.service: Deactivated successfully. machine # [ 9.670645] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 9.660434] systemd[1]: Started Home Area Manager. sshClient # [ 9.666393] systemd[1]: Finished Home Area Activation. machine # [ 9.697283] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 9.714577] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 9.728526] systemd-logind[508]: New seat seat0. machine # [ 9.741468] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.742581] systemd[1]: Started User Login Management. machine # [ 9.760624] systemd[1]: Starting linger-users.service... sshClient # [ 9.750411] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 9.762605] dbus-broker-launch[495]: Ready sshClient # [ 9.761826] systemd[1]: Finished linger-users.service. machine # [ 9.776525] systemd[1]: Started Home Area Manager. sshClient # [ 9.798837] systemd[1]: Finished resolvconf update. machine # [ 9.820934] systemd[1]: Finished Home Area Activation. sshClient # [ 9.810461] nsncd[553]: Oct 18 02:43:45.256 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 9.819431] systemd[1]: Starting DHCP Client... sshClient # [ 9.827015] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.841010] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 9.827994] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 9.834325] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 9.854460] systemd[1]: Finished Firewall. sshClient # [ 9.858690] systemd[1]: Finished First Boot Home Area Wizard. sshClient # [ 9.869708] systemd[1]: Reached target First Boot Complete. sshClient # [ 9.874856] systemd[1]: Reached target Preparation for Network. sshClient # [ 9.879194] systemd[1]: Starting Extra networking commands.... sshClient # [ 9.884789] systemd[1]: Starting Save Transient machine-id to Disk... sshClient # [ 9.900253] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 9.901588] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 9.931806] systemd[1]: Finished resolvconf update. sshClient # [ 9.935298] systemd[1]: Starting Address configuration of eth1... machine # [ 9.965018] systemd[1]: Starting DHCP Client... machine # [ 9.970558] nsncd[568]: Oct 18 02:43:45.410 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.988307] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.989328] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 9.990285] systemd[1]: Finished linger-users.service. machine # [ 10.009572] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.012334] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.060204] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 10.069959] systemd[1]: Started Console Output Muting Service (PID 580/UID 0). machine # [ 10.073802] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.075032] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 10.074042] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 10.093752] systemd[1]: Finished Save Transient machine-id to Disk. sshClient # [ 10.108280] systemd-logind[491]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.128552] systemd[1]: Finished Firewall. machine # [ 10.136376] systemd[1]: Reached target Preparation for Network. machine # [ 10.137683] systemd-logind[508]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.160162] systemd[1]: Starting Address configuration of eth1... machine # [ 10.164582] systemd[1]: Starting Extra networking commands.... sshClient # [ 10.173988] network-addresses-eth1-start[595]: adding address 192.168.1.2/24... done sshClient # [ 10.207336] network-addresses-eth1-start[595]: adding address 2001:db8:1::2/64... done sshClient # [ 10.222886] dhcpcd[598]: dhcpcd-10.3.2 starting sshClient # [ 10.243554] dhcpcd[645]: dev: loaded udev sshClient # [ 10.247073] systemd[1]: Finished Address configuration of eth1. sshClient # [ 10.306353] 8021q: 802.1Q VLAN Support v1.8 sshClient # [ 10.306725] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 10.312528] systemd[1]: Finished Extra networking commands.. sshClient # [ 10.316247] systemd[1]: Reached target Network. sshClient # [ 10.321274] systemd[1]: Starting Permit User Sessions... machine # [ 10.438372] network-addresses-eth1-start[611]: adding address 192.168.1.1/24... done sshClient # [ 10.448542] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 10.446608] systemd[1]: Finished Permit User Sessions. sshClient # [ 10.458456] systemd[1]: Started Getty on tty1. sshClient # [ 10.459333] systemd[1]: Reached target Login Prompts. sshClient # [ 10.490337] mousedev: PS/2 mouse device common for all mice machine # [ 10.500408] network-addresses-eth1-start[611]: adding address 2001:db8:1::1/64... done machine # [ 10.502079] dhcpcd[614]: dhcpcd-10.3.2 starting sshClient # [ 10.520809] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 10.522437] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 10.522772] dhcpcd[640]: dev: loaded udev sshClient # [ 10.527718] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 10.528076] cfg80211: failed to load regulatory.db machine # [ 10.554327] systemd[1]: Finished Address configuration of eth1. sshClient # [ 10.578427] systemd-logind[491]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) sshClient # [ 10.602031] dhcpcd[645]: eth0: waiting for carrier sshClient # [ 10.618088] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 10.607327] dhcpcd[645]: libudev: received NULL device sshClient # [ 10.612065] dhcpcd[645]: libudev: received NULL device sshClient # [ 10.612990] dhcpcd[645]: eth0: carrier acquired sshClient # [ 10.622368] dhcpcd[645]: DUID 00:01:00:01:32:66:ee:e2:52:54:00:12:34:56 sshClient # [ 10.623493] dhcpcd[645]: eth0: IAID 00:12:34:56 sshClient # [ 10.627302] dhcpcd[645]: eth0: adding address fe80::5054:ff:fe12:3456 machine: (finished: waiting for unit systemd-homed.service, in 11.07 seconds) machine: waiting for Please enter user name to create to appear on tty 1 machine # [ 10.909081] systemd[1]: Finished Extra networking commands.. machine # [ 10.913647] systemd[1]: Reached target Network. 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' machine # [ 11.052487] systemd-logind[508]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Please enter new password for user test-homed-user: to appear on tty 1 machine # [ 11.257255] dhcpcd[640]: eth0: waiting for carrier machine # [ 11.258176] dhcpcd[640]: eth0: carrier acquired machine # [ 11.277751] dhcpcd[640]: DUID 00:01:00:01:32:66:ee:e2:52:54:00:12:34:56 machine # [ 11.278988] dhcpcd[640]: eth0: IAID 00:12:34:56 machine # [ 11.279718] dhcpcd[640]: eth0: adding address fe80::5054:ff:fe12:3456 machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 0.20 seconds) machine: sending keys 'foobarfoo\n' sshClient # [ 11.368361] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for (repeat) to appear on tty 1 sshClient # [ 11.714675] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 11.718838] systemd[1]: Starting Virtual Console Setup... machine: (finished: waiting for (repeat) to appear on tty 1, in 0.28 seconds) machine: sending keys 'foobarfoo\n' machine # [ 11.745728] dhcpcd[640]: eth0: soliciting a DHCP lease machine # [ 11.753580] dhcpcd[640]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 11.760524] dhcpcd[640]: eth0: probing address 10.0.2.15/24 sshClient # [ 11.750681] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 11.751994] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 11.759477] systemd-logind[491]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 11.764304] systemd[1]: Starting Virtual Console Setup... machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for unit systemd-homed-firstboot.service sshClient # [ 11.909015] systemd-vconsole-setup[692]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 11.914204] systemd[1]: Finished Virtual Console Setup. sshClient # [ 12.118983] dhcpcd[645]: eth0: soliciting a DHCP lease sshClient # [ 12.124893] dhcpcd[645]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 12.128267] dhcpcd[645]: eth0: probing address 10.0.2.15/24 sshClient # [ 12.283101] dhcpcd[645]: eth0: soliciting an IPv6 router sshClient # [ 12.285485] dhcpcd[645]: eth0: Router Advertisement from fe80::2 sshClient # [ 12.287050] dhcpcd[645]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 12.288758] dhcpcd[645]: eth0: adding route to fec0::/64 sshClient # [ 12.290121] dhcpcd[645]: eth0: adding default route via fe80::2 machine # [ 12.499306] sshd-keygen-start[533]: Generating public/private rsa key pair. machine # [ 12.500799] sshd-keygen-start[533]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 12.500854] sshd-keygen-start[533]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine # [ 12.500887] sshd-keygen-start[533]: The key fingerprint is: machine # [ 12.500967] sshd-keygen-start[533]: SHA256:CbldxBD/d3ac4PIF6mH7/d1r2vxCM9+hIr3t/zOtKIk root@machine machine # [ 12.501002] sshd-keygen-start[533]: The key's randomart image is: machine # [ 12.501036] sshd-keygen-start[533]: +---[RSA 4096]----+ machine # [ 12.502248] sshd-keygen-start[533]: | o+. | machine # [ 12.516700] sshd-keygen-start[533]: | . o. | machine # [ 12.517561] sshd-keygen-start[533]: | o o o | machine # [ 12.518329] sshd-keygen-start[533]: | + o .o o..| machine # [ 12.519083] sshd-keygen-start[533]: | . S =...o=| machine # [ 12.519834] sshd-keygen-start[533]: | o =.=+.| machine # [ 12.524811] sshd-keygen-start[533]: | ..+ o.++| machine # [ 12.525689] sshd-keygen-start[533]: | E.oooo=+*| machine # [ 12.526453] sshd-keygen-start[533]: | .o===O&| machine # [ 12.527205] sshd-keygen-start[533]: +----[SHA256]-----+ machine # [ 12.591177] sshd-keygen-start[732]: Generating public/private ed25519 key pair. machine # [ 12.593970] sshd-keygen-start[732]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 12.595345] sshd-keygen-start[732]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 12.602054] sshd-keygen-start[732]: The key fingerprint is: machine # [ 12.602975] sshd-keygen-start[732]: SHA256:mE4ZaVisMdb1CPIg5FSIP7UElt9kRhqXddTFaN6WSQM root@machine machine # [ 12.606711] sshd-keygen-start[732]: The key's randomart image is: machine # [ 12.607631] sshd-keygen-start[732]: +--[ED25519 256]--+ machine # [ 12.613150] sshd-keygen-start[732]: | o*==+=+o.o.E=. | machine # [ 12.614015] sshd-keygen-start[732]: |.+o.*XBo + + + | machine # [ 12.614798] sshd-keygen-start[732]: | ..=oX= . .o o + | machine # [ 12.615552] sshd-keygen-start[732]: | o +..= . = | machine # [ 12.624068] sshd-keygen-start[732]: | . = S . | machine # [ 12.625013] sshd-keygen-start[732]: | o | machine # [ 12.625753] sshd-keygen-start[732]: | . | machine # [ 12.626482] sshd-keygen-start[732]: | | machine # [ 12.627221] sshd-keygen-start[732]: | | machine # [ 12.627952] sshd-keygen-start[732]: +----[SHA256]-----+ machine # [ 12.639355] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 12.641765] systemd[1]: Finished SSH Host Keys Generation. machine # [ 12.642629] systemd[1]: Starting SSH Daemon... machine # [ 12.699608] sshd[734]: Server listening on 0.0.0.0 port 22. machine # [ 12.703021] systemd[1]: Started SSH Daemon. machine # [ 12.703774] sshd[734]: Server listening on :: port 22. machine # [ 13.176744] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.189801] systemd[1]: Starting Virtual Console Setup... machine # [ 13.274988] systemd-logind[508]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 13.360210] systemd-vconsole-setup[744]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.364072] systemd[1]: Finished Virtual Console Setup. machine # [ 13.939191] dhcpcd[640]: eth0: soliciting an IPv6 router machine # [ 13.941550] dhcpcd[640]: eth0: Router Advertisement from fe80::2 machine # [ 13.942975] dhcpcd[640]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.944506] dhcpcd[640]: eth0: adding route to fec0::/64 machine # [ 13.945897] dhcpcd[640]: eth0: adding default route via fe80::2 machine # [ 16.687898] dhcpcd[640]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 16.689809] dhcpcd[640]: eth0: adding route to 10.0.2.0/24 machine # [ 16.691091] dhcpcd[640]: eth0: adding default route via 10.0.2.2 machine # [ 16.804462] systemd[1]: Started DHCP Client. sshClient # [ 17.093678] dhcpcd[645]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 17.095760] dhcpcd[645]: eth0: adding route to 10.0.2.0/24 sshClient # [ 17.098754] dhcpcd[645]: eth0: adding default route via 10.0.2.2 sshClient # [ 17.258673] systemd[1]: Started DHCP Client. sshClient # [ 17.260864] systemd[1]: Reached target Multi-User System. sshClient # [ 17.262306] systemd[1]: Startup finished in 1.126s (kernel) + 4.449s (initrd) + 11.686s (userspace) = 17.262s. machine # [ 22.207859] systemd-homed[461]: Generating key pair for signing local user identity records. machine # [ 22.212706] systemd-homed[461]: Successfully created Ed25519 key pair. machine # [ 22.223861] systemd-homed[461]: test-homed-user: changing state absent → creating machine # [ 22.319052] systemd-homework[839]: Using automatic default storage of 'luks'. machine # [ 22.324723] systemd-homework[839]: Sizing home to 83% of available disk space, which is 744M. machine # [ 22.326329] systemd-homework[839]: Allocating image file completed. machine # [ 22.340941] systemd-homework[839]: Writing of partition table completed. machine # [ 22.342907] systemd-homework[839]: Setting up loopback device /dev/loop0 completed. machine # [ 23.660674] systemd-homework[839]: LUKS formatting completed. machine # [ 25.784506] systemd-homework[839]: Writing password to LUKS keyslot 0 completed. machine # [ 25.997228] systemd-homework[839]: LUKS activation by volume key succeeded. machine # [ 26.013095] systemd-homework[839]: Writing user record as LUKS token completed. machine # [ 26.014390] systemd-homework[839]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 26.015812] systemd-homework[839]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 26.828887] systemd[1]: proc-868-mounts.mount: Deactivated successfully. machine # [ 26.829630] systemd-homework[839]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 5ffd2fb6-a44f-4bb1-9164-bb6223fb6385) machine # [ 26.829735] systemd-homework[839]: Formatting file system completed. machine # [ 26.889180] systemd-homework[839]: Mounting file system completed. machine # [ 26.892538] systemd-homework[839]: Skeleton directory /etc/skel missing, ignoring. machine # [ 26.894574] systemd-homework[839]: Wrote embedded .identity file. machine # [ 26.895905] systemd-homework[839]: Replaced embedded blob dir with contents of system blob dir. machine # [ 26.897754] systemd-homework[839]: Recursive changing of ownership completed. machine # [ 26.899010] systemd-homework[839]: Changed top-level directory access mode to 0700. machine # [ 26.902591] systemd-homework[839]: Synchronized disk. machine # [ 26.903434] systemd-homework[839]: Discarded unused 652.8M. machine # [ 26.914582] systemd-homed[461]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 26.954360] systemd-homed[461]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 26.973286] systemd-homed[461]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 26.989717] systemd-homework[839]: Moved image file into place. machine # [ 26.992120] systemd-homework[839]: Creation completed. machine # [ 26.993121] systemd-homework[839]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M. machine # [ 27.006042] systemd-homed[461]: test-homed-user: changing state creating → inactive machine # [ 27.012644] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 27.016536] systemd[1]: systemd-mute-console@0-1-580_581-0.service: Deactivated successfully. machine # [ 27.022606] systemd[1]: Reached target First Boot Complete. machine # [ 27.028248] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 27.033565] systemd[1]: Starting Permit User Sessions... machine # [ 27.078007] systemd[1]: Finished Permit User Sessions. machine # [ 27.082823] systemd[1]: Started Getty on tty1. machine # [ 27.085082] systemd[1]: Reached target Login Prompts. machine # [ 27.085982] systemd[1]: Reached target Multi-User System. machine # [ 27.193909] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 27.199275] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 27.212980] systemd[1]: Startup finished in 1.133s (kernel) + 4.391s (initrd) + 21.687s (userspace) = 27.212s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.02 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 28.19 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 27.903937] systemd-homed[461]: test-homed-user: changing state inactive → updating machine # [ 27.912623] systemd-homework[914]: Offline update requested. Not touching embedded records. machine # [ 27.916945] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 27.922138] systemd-homed[461]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.06 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.04 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 # [ 28.301678] systemd-homed[461]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 28.310089] systemd-homework[929]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 28.318326] systemd-homed[461]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 28.322307] systemd-homed[461]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 28.325453] systemd-homed[461]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 28.327911] systemd-homed[461]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: to appear on tty 1, in 1.10 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for success: pgrep -u test-homed-user -t tty1 bash machine # [ 29.422683] systemd-homed[461]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 29.475482] systemd-homework[941]: Provided password unlocks user record. machine # [ 29.481568] systemd-homework[941]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 29.489491] systemd-homework[941]: Allocated additional 652.8M. machine # [ 29.493352] systemd-homework[941]: Setting up loopback device /dev/loop1 completed. machine # [ 29.510616] loop1: detected capacity change from 0 to 1519616 machine # [ 30.340943] systemd-homework[941]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 30.379295] systemd-homework[941]: Provided password unlocks user record. machine # [ 30.385405] systemd-homework[941]: Probing file system completed (found btrfs). machine # [ 30.387977] systemd-homework[941]: No fsck for file system btrfs installed, ignoring. machine # [ 30.408660] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (941) machine # [ 30.413483] BTRFS info (device dm-0): first mount of filesystem 5ffd2fb6-a44f-4bb1-9164-bb6223fb6385 machine # [ 30.413532] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 30.402718] systemd-homework[941]: Mounting file system completed.[ 30.420664] BTRFS info (device dm-0): enabling free space tree machine # [ 30.420689] BTRFS info (device dm-0): use zstd compression, level 1 machine # machine # [ 30.408383] systemd-homework[941]: Discovered used loopback device /dev/loop1. machine # [ 30.409662] systemd-homework[941]: offset = 1048576, size = 778043392, image = 780140544 machine # [ 30.427180] systemd-homework[941]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M. machine # [ 30.429376] systemd-homework[941]: Growing of image file from 744M to 897M completed. machine # [ 30.454331] loop1: detected capacity change from 1519616 to 1832960 machine # [ 30.441461] systemd-homework[941]: Refreshing loop device size completed. machine # [ 30.462300] systemd-homework[941]: Growing of partition completed. machine # [ 30.512564] async_tx: api initialized (async) machine # [ 30.549291] systemd-homework[941]: LUKS device growing completed. machine # [ 30.574227] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 921698304 machine # [ 30.560390] systemd-homework[941]: File system resizing from 726M to 879M completed. machine # [ 30.564231] systemd-homework[941]: Synchronized disk. machine # [ 30.565081] systemd-homework[941]: Synchronized disk. machine # [ 30.565860] systemd-homework[941]: Resizing completed. machine # [ 30.566643] systemd-homework[941]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 30.572270] systemd-homework[941]: Read embedded .identity file. machine # [ 30.594327] systemd-homework[941]: Provided password unlocks user record. machine # [ 30.597347] systemd-homework[941]: Reconciling header user identity completed (host version was newer). machine # [ 30.600918] systemd-homework[941]: Reconciling embedded user identity completed (host version was newer). machine # [ 30.611323] systemd-homework[941]: Wrote LUKS header user record. machine # [ 30.613321] systemd-homework[941]: Wrote embedded .identity file. machine # [ 30.614251] systemd-homework[941]: Replaced embedded blob dir with contents of system blob dir. machine # [ 30.615496] systemd-homework[941]: Recursive changing of ownership not necessary, skipped. machine # [ 30.622587] systemd-homework[941]: Synchronized disk. machine # [ 30.623462] systemd-homework[941]: Moving to final mount point /home/test-homed-user completed. machine # [ 30.645321] systemd-homework[941]: Activation completed. machine # [ 30.648257] systemd-homework[941]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 30.660900] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 30.671701] login[905]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 30.678263] systemd-homed[461]: test-homed-user: changing state activating-for-acquire → active machine # [ 30.689871] systemd-homed[461]: test-homed-user: changing state active → resizing-while-active machine # [ 30.703760] systemd-homework[985]: Successfully acquired home volume key from kernel keyring. machine # [ 30.707221] systemd-homework[985]: LUKS volume key from keyring unlocks user record. machine # [ 30.776216] login[905]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 30.809284] systemd-homework[985]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 30.850045] systemd[1]: Created slice Slice /user/60388. machine # [ 30.853263] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 30.879031] systemd-logind[508]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 30.998688] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 31.009235] systemd-homework[985]: Successfully re-activated LUKS device. machine # [ 31.020325] systemd[1]: Starting User Manager for UID 60388... machine # [ 31.021350] systemd-homework[985]: LUKS volume key from keyring unlocks user record. machine # [ 31.022466] systemd-homework[985]: Discovered used loopback device /dev/loop1. machine # [ 31.023553] systemd-homework[985]: Read embedded .identity file. machine # [ 31.034098] systemd-homework[985]: LUKS volume key from keyring unlocks user record. machine # [ 31.039530] systemd-homework[985]: Reconciling header user identity completed (host version was newer). machine # [ 31.044635] systemd-homework[985]: Reconciling embedded user identity completed (host version was newer). machine # [ 31.046018] systemd-homework[985]: offset = 1048576, size = 938475520, image = 940572672 machine # [ 31.047898] systemd-homework[985]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M. machine # [ 31.076693] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 783286272 machine # [ 31.063825] systemd-homework[985]: Wrote embedded .identity file. machine # [ 31.071316] systemd-homework[985]: Replaced embedded blob dir with contents of system blob dir. machine # [ 31.079511] systemd-homework[985]: Backing file is fully allocated already. machine # [ 31.085204] systemd-homework[985]: File system resizing from 879M to 747M completed. machine # [ 31.086516] systemd-homework[985]: Synchronized disk. machine # [ 31.100347] (systemd)[988]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 31.191306] (systemd)[988]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 31.200656] systemd-logind[508]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 31.218176] systemd-homework[985]: LUKS device shrinking completed. machine # [ 31.246400] loop1: detected capacity change from 1832960 to 1562624 machine # [ 31.235346] systemd-homework[985]: Refreshing loop device size completed. machine # [ 31.237428] systemd-homework[985]: Shrinking of image file completed. machine # [ 31.257177] systemd-homework[985]: Shrinking of partition completed. machine # [ 31.258703] systemd[988]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.297084] systemd-homework[985]: Wrote LUKS header user record. machine # [ 31.298267] systemd-homework[985]: Synchronized disk. machine # [ 31.316159] systemd-homework[985]: Resizing completed. machine # [ 31.317178] systemd-homework[985]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 31.333614] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 31.340605] systemd-homed[461]: test-homed-user: changing state resizing-while-active → active machine # [ 31.342616] systemd-homed[461]: Rebalancing complete. machine # [ 31.428611] systemd[988]: Queued start job for default target Main User Target. machine # [ 31.437278] systemd[988]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.441839] systemd[988]: Created slice User Application Slice. machine # [ 31.442946] systemd[988]: Started Daily Cleanup of User's Temporary Directories. machine # [ 31.446289] systemd[988]: Reached target Paths. machine # [ 31.447165] systemd[988]: Reached target Timers. machine # [ 31.447906] systemd[988]: Starting D-Bus User Message Bus Socket... machine # [ 31.451116] systemd[988]: Starting Create User Files and Directories... machine # [ 31.506503] systemd[988]: Finished Create User Files and Directories. machine # [ 31.527278] systemd[988]: Listening on D-Bus User Message Bus Socket. machine # [ 31.533615] systemd[988]: Reached target Sockets. machine # [ 31.534466] systemd[988]: Reached target Basic System. machine # [ 31.535249] systemd[1]: Started User Manager for UID 60388. machine # [ 31.546725] systemd[988]: Starting Run user-specific NixOS activation... machine # [ 31.547911] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 31.599637] systemd[988]: Finished Run user-specific NixOS activation. machine # [ 31.602691] systemd[988]: Reached target Main User Target. machine # [ 31.603573] systemd[988]: Startup finished in 377ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.34 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.06 seconds) machine: must succeed: cat /tmp/2 machine: (finished: must succeed: cat /tmp/2, in 0.02 seconds) (finished: subtest: login as homed user, in 6.00 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.05 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.11 seconds) sshClient: sending keys 'foobarfoo\n' sshClient: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) sshClient: waiting for success: pgrep -u test-normal-user bash sshClient # [ 35.472760] login[664]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 35.512650] systemd[1]: Created slice Slice /user/1000. sshClient # [ 35.518331] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 35.555491] systemd-logind[491]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 35.588815] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 35.596004] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 35.632200] (systemd)[794]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 35.642002] systemd-logind[491]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 35.671865] systemd[794]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 35.809114] systemd[794]: Queued start job for default target Main User Target. sshClient # [ 35.817155] systemd[794]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 35.821796] systemd[794]: Created slice User Application Slice. sshClient # [ 35.823465] systemd[794]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 35.826007] systemd[794]: Reached target Paths. sshClient # [ 35.826761] systemd[794]: Reached target Timers. sshClient # [ 35.827478] systemd[794]: Starting D-Bus User Message Bus Socket... sshClient # [ 35.832269] systemd[794]: Starting Create User Files and Directories... sshClient # [ 35.876910] systemd[794]: Finished Create User Files and Directories. sshClient # [ 35.894116] systemd[794]: Listening on D-Bus User Message Bus Socket. sshClient # [ 35.898192] systemd[794]: Reached target Sockets. sshClient # [ 35.899143] systemd[794]: Reached target Basic System. sshClient # [ 35.899949] systemd[1]: Started User Manager for UID 1000. sshClient # [ 35.905629] systemd[794]: Starting Run user-specific NixOS activation... sshClient # [ 35.912478] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 35.974934] systemd[794]: Finished Run user-specific NixOS activation. sshClient # [ 35.978671] systemd[794]: Reached target Main User Target. sshClient # [ 35.979650] systemd[794]: Startup finished in 317ms. sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.11 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.06 seconds) sshClient: must succeed: cat /tmp/1 sshClient: (finished: must succeed: cat /tmp/1, in 0.02 seconds) (finished: subtest: login as regular user, in 3.80 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.49 seconds) sshClient: waiting for file '/tmp/id_ed25519.pub' sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.06 seconds) sshClient: must succeed: cat /tmp/id_ed25519.pub sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.03 seconds) machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIItbmW+082iQ9XHA6aBPlE4E5Wf+76ccAqt9aCFVNipn test-normal-user@sshClient' machine # [ 39.358901] systemd-homed[461]: test-homed-user: changing state active → updating-while-active machine # [ 39.369916] systemd-homework[1041]: Offline update requested. Not touching embedded records. machine # [ 39.398480] systemd-homed[461]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIItbmW+082iQ9XHA6aBPlE4E5Wf+76ccAqt9aCFVNipn test-normal-user@sshClient', in 0.09 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.04 seconds) (finished: subtest: add homed ssh authorized key, in 1.72 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.12 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.12 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) ??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead. File "/nix/store/hgh6bdm48cnkzdv3ahvrdswdd0azc31a-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 41.303521] systemd-homed[461]: test-homed-user: changing state active → passwd-while-active machine # [ 41.993496] systemd-homework[1074]: No valid password for LUKS superblock. machine # [ 44.465598] systemd-homed[461]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 44.470521] systemd-homed[461]: test-homed-user: changing state passwd-while-active → active machine: (finished: must succeed: sleep 4.0, in 4.03 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for file '/tmp/3' machine # [ 45.431649] systemd-homed[461]: test-homed-user: changing state active → passwd-while-active machine # [ 46.701689] systemd-homework[1080]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 46.830072] systemd-homework[1080]: Successfully re-activated LUKS device. machine # [ 46.892133] systemd-homework[1080]: Provided password unlocks user record. machine # [ 46.893420] systemd-homework[1080]: Discovered used loopback device /dev/loop1. machine # [ 46.894475] systemd-homework[1080]: Read embedded .identity file. machine # [ 46.952255] systemd-homework[1080]: Provided password unlocks user record. machine # [ 46.953568] systemd-homework[1080]: Reconciling header user identity completed (host version was newer). machine # [ 46.954896] systemd-homework[1080]: Reconciling embedded user identity completed (host version was newer). machine # [ 50.237166] systemd-homework[1080]: Added LUKS key slot 1. machine # [ 50.266162] systemd-homework[1080]: Destroyed LUKS key slot 0. machine # [ 50.281310] systemd-homework[1080]: Wrote LUKS header user record. machine # [ 50.284358] systemd-homework[1080]: Wrote embedded .identity file. machine # [ 50.286040] systemd-homework[1080]: Replaced embedded blob dir with contents of system blob dir. machine # [ 50.292264] systemd-homework[1080]: Synchronized disk. machine # [ 50.300197] systemd-homework[1080]: Everything completed. machine # [ 50.321563] systemd-homed[461]: test-homed-user: changing state passwd-while-active → active machine # [ 50.323649] passwd[1054]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine: (finished: waiting for file '/tmp/3', in 5.20 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.09 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 # [ 51.007787] systemd-homed[461]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 51.017080] systemd-homework[1118]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 51.025601] systemd-homed[461]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 51.030097] systemd-homed[461]: 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.11 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for file '/tmp/4' machine: (finished: waiting for file '/tmp/4', in 0.03 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 52.461704] systemd-homed[461]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 52.513590] systemd-homework[1134]: Provided password unlocks user record. machine # [ 53.160709] systemd-homework[1134]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 53.289835] systemd-homework[1134]: Successfully re-activated LUKS device. machine # [ 53.324134] systemd-homework[1134]: Provided password unlocks user record. machine # [ 53.325492] systemd-homework[1134]: Discovered used loopback device /dev/loop1. machine # [ 53.326597] systemd-homework[1134]: Read embedded .identity file. machine # [ 53.352890] systemd-homework[1134]: Provided password unlocks user record. machine # [ 53.354925] systemd-homework[1134]: Reconciling user identities completed (host and header version were identical). machine # [ 53.357069] systemd-homework[1134]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 53.388082] systemd-homework[1134]: Everything completed. machine # [ 53.397645] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 53.713709] sudo[1116]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 53.724932] systemd-homed[461]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 53.737789] sudo[1116]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 53.774650] sudo[1116]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 53.794830] sudo[1116]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 2.11 seconds) (finished: subtest: escalate to root from homed user, in 3.58 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 # [ 54.198319] login[905]: pam_unix(login:session): session closed for user test-homed-user machine # [ 54.213582] login[905]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 54.233577] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 54.246929] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 54.260514] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 54.262524] systemd-logind[508]: Session 1 logged out. Waiting for processes to exit. machine # [ 54.263902] systemd[1]: Started Getty on tty1. machine # [ 54.285001] systemd-logind[508]: Removed session 1. machine # [ 64.413219] systemd[1]: Stopping User Manager for UID 60388... machine # [ 64.421635] systemd[988]: Activating special unit Exit the Session... machine # [ 64.426892] systemd[988]: Stopped target Main User Target. machine # [ 64.432490] systemd[988]: Stopped Run user-specific NixOS activation. machine # [ 64.434031] systemd[988]: Stopped target Basic System. machine # [ 64.435220] systemd[988]: Stopped target Paths. machine # [ 64.437580] systemd[988]: Stopped target Sockets. machine # [ 64.438729] systemd[988]: Stopped target Timers. machine # [ 64.439829] systemd[988]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 64.441820] systemd[988]: Closed D-Bus User Message Bus Socket. machine # [ 64.443203] systemd[988]: Stopped Create User Files and Directories. machine # [ 64.444771] systemd[988]: Removed slice User Application Slice. machine # [ 64.446110] systemd[988]: Reached target Shutdown. machine # [ 64.447243] systemd[988]: Finished Exit the Session. machine # [ 64.450853] systemd[988]: Reached target Exit the Session. machine # [ 64.454535] systemd-logind[508]: Removed session 2. machine # [ 64.465969] systemd-homed[461]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 64.476894] systemd-homed[461]: test-homed-user: changing state active → deactivating machine # [ 64.478860] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 64.488461] systemd[1]: Stopped User Manager for UID 60388. machine # [ 64.490155] systemd-homework[1214]: Successfully acquired home volume key from kernel keyring. machine # [ 64.493234] systemd-homework[1214]: Discarded unused 674.3M. machine # [ 64.500853] systemd-homework[1214]: Syncing completed. machine # [ 64.545509] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 64.555521] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 64.579892] systemd-homework[1214]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 64.719855] systemd-homework[1214]: Successfully re-activated LUKS device. machine # [ 64.722904] systemd-homework[1214]: Discovered used loopback device /dev/loop1. machine # [ 64.724346] systemd-homework[1214]: offset = 1048576, size = 800063488, image = 802160640 machine # [ 64.735415] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 64.743283] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 64.750948] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 64.760469] systemd-homework[1214]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M. machine # [ 64.762361] systemd-homework[1214]: Allocated additional 674.3M. machine # [ 64.771230] systemd-homework[1214]: File system resizing from 747M to 256M completed.[ 64.786721] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 268435456 machine # machine # [ 64.779676] systemd-homework[1214]: Synchronized disk. machine # [ 64.915219] systemd-homework[1214]: LUKS device shrinking completed. machine # [ 64.942507] loop1: detected capacity change from 1562624 to 557056 machine # [ 64.930158] systemd-homework[1214]: Refreshing loop device size completed. machine # [ 64.931389] systemd-homework[1214]: Shrinking of image file completed. machine # [ 64.943098] systemd-homework[1214]: Shrinking of partition completed. machine # [ 64.945485] systemd-homework[1214]: Synchronized disk. machine # [ 64.962960] BTRFS info (device dm-0): last unmount of filesystem 5ffd2fb6-a44f-4bb1-9164-bb6223fb6385 machine # [ 64.950909] systemd-homework[1214]: Resizing completed. machine # [ 64.951826] systemd-homework[1214]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 64.955384] systemd-homework[1214]: Unmounting completed. machine # [ 64.957082] systemd-homework[1214]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 64.958358] systemd-homework[1214]: Device home-test-homed-user is not active. machine # [ 64.993129] systemd-homed[461]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 65.008126] systemd-homework[1214]: Everything completed. machine # [ 65.026480] systemd-homed[461]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.71 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.76 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.78 seconds) sshClient: waiting for Please enter password for user to appear on tty 1 machine # [ 67.684614] systemd-homed[461]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 67.693829] systemd-homework[1248]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 67.715155] systemd-homed[461]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 67.722433] systemd-homed[461]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 67.725398] sshd-session[1244]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 67.728359] systemd-homed[461]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 67.730755] sshd-session[1244]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 67.734330] systemd-homed[461]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 67.738000] sshd-session[1244]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 43002 ssh2: ED25519 SHA256:BTPOOI9gBEHNPEDy0EosZMdehcL9FPuIfxVAvdvWT0c machine # [ 67.789435] sshd-session[1244]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 67.802759] systemd-logind[508]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 67.809500] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 67.842768] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 67.849962] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 67.866383] sshd-session[1244]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 67.870878] sshd-session[1244]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 67.887981] systemd-homed[461]: test-homed-user: changing state inactive → activating machine # [ 67.894776] systemd-homework[1253]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 67.900518] systemd-homed[461]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 67.902957] systemd-homed[461]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.15 seconds) sshClient: sending keys 'barfoobar\n' sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for success: pgrep -u test-homed-user bash machine # [ 69.000382] systemd-homed[461]: test-homed-user: changing state inactive → activating machine # [ 69.052507] systemd-homework[1258]: Provided password unlocks user record. machine # [ 69.058925] systemd-homework[1258]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 69.065780] systemd-homework[1258]: Backing file is fully allocated already. machine # [ 69.070111] systemd-homework[1258]: Setting up loopback device /dev/loop1 completed. machine # [ 69.087355] loop1: detected capacity change from 0 to 557056 machine # [ 69.980235] systemd-homework[1258]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 70.028920] systemd-homework[1258]: Provided password unlocks user record. machine # [ 70.041153] systemd-homework[1258]: Probing file system completed (found btrfs). machine # [ 70.045182] systemd-homework[1258]: No fsck for file system btrfs installed, ignoring. machine # [ 70.066776] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1258) machine # [ 70.068342] BTRFS info (device dm-0): first mount of filesystem 5ffd2fb6-a44f-4bb1-9164-bb6223fb6385 machine # [ 70.068388] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 70.063287] systemd-homework[1258]: Mounting file system completed.[ 70.080881] BTRFS info (device dm-0): enabling free space tree machine # [ 70.080911] BTRFS info (device dm-0): use zstd compression, level 1 machine # machine # [ 70.072432] systemd-homework[1258]: Discovered used loopback device /dev/loop1. machine # [ 70.073524] systemd-homework[1258]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 70.084346] systemd-homework[1258]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M. machine # [ 70.086318] systemd-homework[1258]: Growing of image file from 274M to 765M completed. machine # [ 70.095300] systemd-homework[1258]: Refreshing loop device size completed.[ 70.110576] loop1: detected capacity change from 557056 to 1562624 machine # machine # [ 70.110617] systemd-homework[1258]: Growing of partition completed. machine # [ 70.160969] systemd-homework[1258]: LUKS device growing completed. machine # [ 70.185417] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 783286272 machine # [ 70.169977] systemd-homework[1258]: File system resizing from 256M to 747M completed. machine # [ 70.171206] systemd-homework[1258]: Synchronized disk. machine # [ 70.174718] systemd-homework[1258]: Synchronized disk. machine # [ 70.175562] systemd-homework[1258]: Resizing completed. machine # [ 70.180218] systemd-homework[1258]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 70.182025] systemd-homework[1258]: Read embedded .identity file. machine # [ 70.206390] systemd-homework[1258]: Provided password unlocks user record. machine # [ 70.208973] systemd-homework[1258]: Reconciling user identities completed (host and header version were identical). machine # [ 70.210533] systemd-homework[1258]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 70.215842] systemd-homework[1258]: Recursive changing of ownership not necessary, skipped. machine # [ 70.220251] systemd-homework[1258]: Synchronized disk. machine # [ 70.224411] systemd-homework[1258]: Moving to final mount point /home/test-homed-user completed. machine # [ 70.241065] systemd-homework[1258]: Activation completed. machine # [ 70.242436] systemd-homework[1258]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 70.259883] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 70.265917] systemd-homed[461]: test-homed-user: changing state activating → active machine # [ 70.288745] systemd[1]: Starting User Manager for UID 60388... machine # [ 70.326927] (systemd)[1301]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 70.373192] (systemd)[1301]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 70.380889] systemd-logind[508]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 70.411195] systemd[1301]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.521785] systemd[1301]: Queued start job for default target Main User Target. machine # [ 70.529131] systemd[1301]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.533489] systemd[1301]: Created slice User Application Slice. machine # [ 70.534597] systemd[1301]: Started Daily Cleanup of User's Temporary Directories. machine # [ 70.535648] systemd[1301]: Reached target Paths. machine # [ 70.536700] systemd[1301]: Reached target Timers. machine # [ 70.539344] systemd[1301]: Starting D-Bus User Message Bus Socket... machine # [ 70.541622] systemd[1301]: Starting Create User Files and Directories... machine # [ 70.586920] systemd[1301]: Listening on D-Bus User Message Bus Socket. machine # [ 70.590227] systemd[1301]: Finished Create User Files and Directories. machine # [ 70.591273] systemd[1301]: Reached target Sockets. machine # [ 70.591997] systemd[1301]: Reached target Basic System. machine # [ 70.597500] systemd[1]: Started User Manager for UID 60388. machine # [ 70.598369] systemd[1301]: Starting Run user-specific NixOS activation... machine # [ 70.651182] systemd[1301]: Finished Run user-specific NixOS activation. machine # [ 70.653213] systemd[1301]: Reached target Main User Target. machine # [ 70.656336] systemd[1301]: Startup finished in 257ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.23 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.05 seconds) machine: must succeed: cat /tmp/5 machine: (finished: must succeed: cat /tmp/5, in 0.03 seconds) sshClient: sending keys 'exit\n' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) sshClient: sending keys 'exit\n' machine # [ 72.482569] sshd-session[1251]: Received disconnect from 2001:db8:1::2 port 43002:11: disconnected by user machine # [ 72.485646] sshd-session[1251]: Disconnected from user test-homed-user 2001:db8:1::2 port 43002 machine # [ 72.489873] sshd-session[1244]: pam_unix(sshd:session): session closed for user test-homed-user sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.62 seconds) (finished: run the VM test script, in 73.74 seconds) machine # [ 72.492918] sshd-session[1244]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 72.507338] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 72.514720] systemd-logind[508]: Session 3 logged out. Waiting for processes to exit. machine # [ 72.523381] systemd-logind[508]: Removed session 3. sshClient # [ 72.565500] login[664]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 72.577877] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 72.593059] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 72.608641] systemd[1]: session-1.scope: Consumed 141ms CPU time over 36.686s wall clock time, 10.9M memory peak, 12K written to disk, 25.5K incoming IP traffic, 24.4K outgoing IP traffic. sshClient # [ 72.625340] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 72.627483] systemd-logind[491]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 72.644379] systemd[1]: Started Getty on tty1. sshClient # [ 72.645459] systemd-logind[491]: Removed session 1. test script finished in 73.96s cleanup kill QemuMachine (pid 47) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) machine # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) sshClient # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.30 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