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... sshClient # Disk image does not exist, creating the virtualisation disk image... sshClient: QEMU running (pid 48) machine: QEMU running (pid 47) machine # Formatting '/build/vm-state-machine/tmp.RrKoxSsSrL', fmt=raw size=1073741824 sshClient # Formatting '/build/vm-state-sshClient/tmp.sRKmDFmMC0', fmt=raw size=1073741824 machine # mke2fs 1.47.4 (6-Mar-2025) sshClient # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done sshClient # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: d7841fcb-efde-4384-a049-60aa363e73d9 sshClient # Filesystem UUID: 28c9ebd2-6f8c-431a-a998-66016d819d6c machine # Superblock backups stored on blocks: sshClient # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 sshClient # 32768, 98304, 163840, 229376 machine # sshClient # machine # Allocating group tables: 0/8 done sshClient # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done sshClient # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done sshClient # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done sshClient # Writing superblocks and filesystem accounting information: 0/8 done machine # sshClient # machine # Virtualisation disk image created. sshClient # Virtualisation disk image created. machine # Starting virtiofs daemons... sshClient # Starting virtiofs daemons... machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests (finished: start all VMs, in 0.56 seconds) subtest: create systemd-homed user on first boot prompt machine: waiting for unit systemd-homed.service machine: waiting for the VM to finish booting machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1792058300 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] 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] sshClient # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1792058300 machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] sshClient # [ 0.000000] KASLR enabled machine # [ 0.000000] Zone ranges: sshClient # [ 0.000000] random: crng init done machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] DMA32 empty sshClient # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] sshClient # [ 0.000000] Zone ranges: machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 sshClient # [ 0.000000] DMA32 empty sshClient # [ 0.000000] Normal empty machine # [ 0.000000] psci: probing for conduit method from DT. sshClient # [ 0.000000] Device empty machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. sshClient # [ 0.000000] Movable zone start for each node machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs sshClient # [ 0.000000] Early memory node ranges machine # [ 0.000000] psci: Trusted OS migration not required sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] psci: SMC Calling Convention v1.1 sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 sshClient # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296 sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] Detected PIPT I-cache on CPU0 sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) sshClient # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] CPU features: detected: GICv3 CPU interface sshClient # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296 sshClient # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface sshClient # [ 0.000000] CPU features: detected: Spectre-v4 sshClient # [ 0.000000] CPU features: detected: Spectre-BHB sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 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/8ayak4q3g5jkd2lbri6jg1vyq03hs2gm-nixos-system-machine-test/init regInfo=/nix/.ro-store/j1jrigfa6k7c2dj3g3z6wckk1m6bn092-closure-info/registration console=ttyAMA0,115200n8 console=tty0 sshClient # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/j1jrigfa6k7c2dj3g3z6wckk1m6bn092-closure-info/registration", will be passed to user space. machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) 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/bwks7k3pfs60grb8wmcagz85qsrls588-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/g9bc1s9i3i8fi927f95n1ikm32q4lmrr-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/g9bc1s9i3i8fi927f95n1ikm32q4lmrr-closure-info/registration", will be passed to user space. machine # [ 0.000000] software IO TLB: area num 1. sshClient # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off 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] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] ftrace: allocating 75301 entries in 296 pages sshClient # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] ftrace: allocated 296 pages with 3 groups sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 sshClient # [ 0.000000] Policy zone: DMA machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] Rude variant of Tasks RCU enabled. sshClient # [ 0.000000] ftrace: allocating 75301 entries in 296 pages machine # [ 0.000000] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] ftrace: allocated 296 pages with 3 groups sshClient # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. sshClient # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 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] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] Rude variant of Tasks RCU enabled. 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] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented 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] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI 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] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 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] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] sshClient # [ 0.000000] GICv3: 256 SPIs implemented sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000 sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000 machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000045110000 sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000 machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.000059] arm-pv: using stolen time PV sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.000580] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000800] Console: colour dummy device 80x25 machine # [ 0.000812] printk: legacy console [tty0] enabled sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.001016] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.000034] arm-pv: using stolen time PV machine # [ 0.001024] pid_max: default: 32768 minimum: 301 machine # [ 0.001144] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima sshClient # [ 0.000445] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.001273] landlock: Up and running. sshClient # [ 0.000628] Console: colour dummy device 80x25 machine # [ 0.001277] Yama: becoming mindful. sshClient # [ 0.000636] printk: legacy console [tty0] enabled machine # [ 0.001765] LSM support for eBPF active machine # [ 0.001903] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.000834] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.000841] pid_max: default: 32768 minimum: 301 machine # [ 0.001932] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.004332] rcu: Hierarchical SRCU implementation. sshClient # [ 0.000929] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima sshClient # [ 0.001080] landlock: Up and running. machine # [ 0.004340] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.001084] Yama: becoming mindful. machine # [ 0.005716] fsl-mc MSI: its@8080000 domain created sshClient # [ 0.001587] LSM support for eBPF active machine # [ 0.005843] EFI services will not be available. sshClient # [ 0.001721] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.005952] smp: Bringing up secondary CPUs ... sshClient # [ 0.001750] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.005964] smp: Brought up 1 node, 1 CPU sshClient # [ 0.003898] rcu: Hierarchical SRCU implementation. machine # [ 0.005967] SMP: Total of 1 processors activated. sshClient # [ 0.003905] rcu: Max phase no-delay instances is 1000. machine # [ 0.005970] CPU: All CPU(s) started at EL1 sshClient # [ 0.005232] fsl-mc MSI: its@8080000 domain created machine # [ 0.005981] CPU features: detected: Branch Target Identification sshClient # [ 0.005355] EFI services will not be available. sshClient # [ 0.005433] smp: Bringing up secondary CPUs ... machine # [ 0.005986] CPU features: detected: ARMv8.4 Translation Table Level sshClient # [ 0.005443] smp: Brought up 1 node, 1 CPU sshClient # [ 0.005446] SMP: Total of 1 processors activated. machine # [ 0.005989] CPU features: detected: Instruction cache invalidation not required for I/D coherence sshClient # [ 0.005449] CPU: All CPU(s) started at EL1 machine # [ 0.005993] CPU features: detected: Data cache clean to the PoU not required for I/D coherence sshClient # [ 0.005460] CPU features: detected: Branch Target Identification machine # [ 0.006002] CPU features: detected: Common not Private translations sshClient # [ 0.005465] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.006006] CPU features: detected: CRC32 instructions sshClient # [ 0.005468] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.006008] CPU features: detected: Data cache clean to Point of Deep Persistence sshClient # [ 0.005472] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.006012] CPU features: detected: Data cache clean to Point of Persistence sshClient # [ 0.005480] CPU features: detected: Common not Private translations machine # [ 0.006015] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.005484] CPU features: detected: CRC32 instructions machine # [ 0.006018] CPU features: detected: E0PD sshClient # [ 0.005487] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.006021] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.005490] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.006023] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.006027] CPU features: detected: Enhanced Virtualization Traps sshClient # [ 0.005493] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.005496] CPU features: detected: E0PD machine # [ 0.006030] CPU features: detected: Fine Grained Traps sshClient # [ 0.005499] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.006033] CPU features: detected: Generic authentication (architected QARMA5 algorithm) sshClient # [ 0.005502] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.006038] CPU features: detected: RCpc load-acquire (LDAPR) sshClient # [ 0.005505] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.006041] CPU features: detected: LSE atomic instructions sshClient # [ 0.005509] CPU features: detected: Fine Grained Traps machine # [ 0.006044] CPU features: detected: Privileged Access Never machine # [ 0.006046] CPU features: detected: PMUv3 sshClient # [ 0.005513] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.006049] CPU features: detected: RAS Extension Support sshClient # [ 0.005518] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.006052] CPU features: detected: RASv1p1 Extension Support sshClient # [ 0.005522] CPU features: detected: LSE atomic instructions machine # [ 0.006055] CPU features: detected: Random Number Generator sshClient # [ 0.005525] CPU features: detected: Privileged Access Never machine # [ 0.006060] CPU features: detected: Speculation barrier (SB) sshClient # [ 0.005527] CPU features: detected: PMUv3 machine # [ 0.006063] CPU features: detected: Stage-2 Force Write-Back sshClient # [ 0.005530] CPU features: detected: RAS Extension Support sshClient # [ 0.005533] CPU features: detected: RASv1p1 Extension Support machine # [ 0.006066] CPU features: detected: TLB range maintenance instructions sshClient # [ 0.005536] CPU features: detected: Random Number Generator machine # [ 0.006071] CPU features: detected: Speculative Store Bypassing Safe (SSBS) sshClient # [ 0.005546] CPU features: detected: Speculation barrier (SB) machine # [ 0.006109] alternatives: applying system-wide alternatives sshClient # [ 0.005549] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.009259] CPU features: detected: BBM Level 2 without TLB conflict abort sshClient # [ 0.005552] CPU features: detected: TLB range maintenance instructions sshClient # [ 0.005556] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.009474] Memory: 886676K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved) sshClient # [ 0.005595] alternatives: applying system-wide alternatives machine # [ 0.009900] devtmpfs: initialized sshClient # [ 0.009070] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.012475] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.012503] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.009246] Memory: 886664K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved) machine # [ 0.012719] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL sshClient # [ 0.009706] devtmpfs: initialized machine # [ 0.012724] 0 pages in range for non-PLT usage sshClient # [ 0.011630] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.012726] 506368 pages in range for PLT usage machine # [ 0.012875] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.011657] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.013887] DMI not present or invalid. sshClient # [ 0.011886] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.017651] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.011892] 0 pages in range for non-PLT usage sshClient # [ 0.011893] 506368 pages in range for PLT usage machine # [ 0.020177] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.012012] pinctrl core: initialized pinctrl subsystem machine # [ 0.020326] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.012939] DMI not present or invalid. sshClient # [ 0.016551] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.020497] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.020524] audit: initializing netlink subsys (disabled) sshClient # [ 0.019463] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.021198] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.019682] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.021201] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.019850] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.021204] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.019881] audit: initializing netlink subsys (disabled) machine # [ 0.021208] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.020541] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.021210] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.020544] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.021247] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.020548] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.021258] cpuidle: using governor ladder sshClient # [ 0.020551] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.021265] cpuidle: using governor menu sshClient # [ 0.020554] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.021487] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.021508] ASID allocator initialised with 65536 entries sshClient # [ 0.020595] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.022836] Serial: AMBA PL011 UART driver sshClient # [ 0.020605] cpuidle: using governor ladder sshClient # [ 0.020612] cpuidle: using governor menu machine # [ 0.028963] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.020840] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.029138] printk: console [ttyAMA0] enabled sshClient # [ 0.020859] ASID allocator initialised with 65536 entries sshClient # [ 0.022272] Serial: AMBA PL011 UART driver sshClient # [ 0.028235] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.028429] printk: console [ttyAMA0] enabled machine # [ 0.159917] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.159931] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.159934] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.159937] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.159940] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.158341] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.159942] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.158355] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.159945] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages sshClient # [ 0.158359] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.159948] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page sshClient # [ 0.158362] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.161314] fbcon: Taking over console machine # [ 0.161326] ACPI: Interpreter disabled. sshClient # [ 0.158364] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.158367] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.171686] iommu: Default domain type: Translated sshClient # [ 0.158370] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.171693] iommu: DMA domain TLB invalidation policy: strict mode sshClient # [ 0.158373] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.172230] SCSI subsystem initialized sshClient # [ 0.159787] fbcon: Taking over console sshClient # [ 0.159799] ACPI: Interpreter disabled. sshClient # [ 0.169718] iommu: Default domain type: Translated machine # [ 0.174387] usbcore: registered new interface driver usbfs sshClient # [ 0.169725] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.174421] usbcore: registered new interface driver hub sshClient # [ 0.170176] SCSI subsystem initialized machine # [ 0.174437] usbcore: registered new device driver usb machine # [ 0.174763] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.174767] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.172434] usbcore: registered new interface driver usbfs machine # [ 0.174789] PTP clock support registered machine # [ 0.174833] EDAC MC: Ver: 3.0.0 sshClient # [ 0.172467] usbcore: registered new interface driver hub sshClient # [ 0.172491] usbcore: registered new device driver usb sshClient # [ 0.172820] pps_core: LinuxPPS API ver. 1 registered sshClient # [ 0.172823] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.172840] PTP clock support registered sshClient # [ 0.172882] EDAC MC: Ver: 3.0.0 machine # [ 0.181108] scmi_core: SCMI protocol bus registered machine # [ 0.181542] FPGA manager framework machine # [ 0.184629] vgaarb: loaded sshClient # [ 0.179155] scmi_core: SCMI protocol bus registered sshClient # [ 0.179539] FPGA manager framework machine # [ 0.186392] clocksource: Switched to clocksource arch_sys_counter sshClient # [ 0.182692] vgaarb: loaded sshClient # [ 0.184475] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.193528] VFS: Disk quotas dquot_6.6.0 machine # [ 0.193566] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.191036] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.191077] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.196164] netfs: FS-Cache loaded machine # [ 0.196305] pnp: PnP ACPI: disabled sshClient # [ 0.194223] netfs: FS-Cache loaded sshClient # [ 0.194372] pnp: PnP ACPI: disabled sshClient # [ 0.199704] NET: Registered PF_INET protocol family machine # [ 0.202438] NET: Registered PF_INET protocol family sshClient # [ 0.199891] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.202619] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.233771] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.233819] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.233845] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.233893] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.233966] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.237490] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.234062] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.237538] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.234127] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.234204] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.237564] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.234284] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.237617] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.234310] NET: Registered PF_XDP protocol family machine # [ 0.237690] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.234327] PCI: CLS 0 bytes, default 64 sshClient # [ 0.234775] Trying to unpack rootfs image as initramfs... machine # [ 0.237787] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.237854] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.237878] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.246899] kvm [1]: HYP mode not available machine # [ 0.238012] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.238033] NET: Registered PF_XDP protocol family machine # [ 0.238050] PCI: CLS 0 bytes, default 64 machine # [ 0.238318] Trying to unpack rootfs image as initramfs... machine # [ 0.257048] kvm [1]: HYP mode not available sshClient # [ 0.422866] Initialise system trusted keyrings sshClient # [ 0.422907] Key type blacklist registered sshClient # [ 0.425713] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.431027] Initialise system trusted keyrings sshClient # [ 0.426195] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.431067] Key type blacklist registered sshClient # [ 0.426248] 9p: Installing v9fs 9p2000 file system support sshClient # [ 0.426349] integrity: Platform Keyring initialized sshClient # [ 0.426360] integrity: Machine keyring initialized machine # [ 0.435629] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.436281] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.436335] 9p: Installing v9fs 9p2000 file system support machine # [ 0.436433] integrity: Platform Keyring initialized machine # [ 0.436446] integrity: Machine keyring initialized sshClient # [ 0.454346] Key type asymmetric registered machine # [ 0.460108] Key type asymmetric registered sshClient # [ 0.454363] Asymmetric key parser 'x509' registered machine # [ 0.460125] Asymmetric key parser 'x509' registered sshClient # [ 0.454448] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) sshClient # [ 0.454584] io scheduler mq-deadline registered machine # [ 0.460213] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) sshClient # [ 0.454589] io scheduler kyber registered machine # [ 0.464770] io scheduler mq-deadline registered machine # [ 0.464782] io scheduler kyber registered sshClient # [ 0.467460] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.477533] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.478139] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.480331] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.480361] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.480376] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 sshClient # [ 0.469317] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.480383] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 sshClient # [ 0.469746] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.480402] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits sshClient # [ 0.469762] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.480425] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] sshClient # [ 0.469777] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.480509] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.480516] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.469783] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.480521] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] sshClient # [ 0.469804] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.480524] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] sshClient # [ 0.469835] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.480527] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] sshClient # [ 0.469942] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.480588] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.469950] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.481077] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.469954] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.481271] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.469958] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.481283] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.469961] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.481309] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.470032] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.481321] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.470492] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.481804] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.470722] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.481997] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.470734] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.482010] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.470763] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.482038] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.470776] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.471260] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.471457] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.471468] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.471495] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.471970] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.472157] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] sshClient # [ 0.472169] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.472195] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.504697] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.510658] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.504904] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.510868] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.510882] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.510908] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.504917] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.504945] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.504957] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.511433] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.505446] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.511632] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.505637] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.511644] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.505663] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.511671] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.506142] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.511684] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.506345] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.512224] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint sshClient # [ 0.506377] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.512425] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.506895] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.512454] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.507085] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.512940] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.507356] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.513150] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.507548] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.513179] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.507575] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.513614] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint sshClient # [ 0.508085] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.513805] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] sshClient # [ 0.508284] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.514074] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.508312] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.514269] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.514295] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.525859] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.532675] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.526060] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.532872] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.526087] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.532899] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.526561] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.533373] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.526857] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.533567] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.526874] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.533595] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.526900] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.534081] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.527372] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.534383] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] sshClient # [ 0.527563] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.527574] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.541732] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.527600] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.541759] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.528258] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.542254] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.528265] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned sshClient # [ 0.528269] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned sshClient # [ 0.528319] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned sshClient # [ 0.528367] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned sshClient # [ 0.528413] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned sshClient # [ 0.528460] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.550516] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.550538] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.550564] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.551295] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.551302] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.551306] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.551353] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.551398] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned sshClient # [ 0.552605] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.551445] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned sshClient # [ 0.552668] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.551490] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned sshClient # [ 0.552715] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.551540] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.551587] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned sshClient # [ 0.552766] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.551633] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned sshClient # [ 0.552812] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.551678] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned sshClient # [ 0.552956] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned sshClient # [ 0.553039] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.551724] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned sshClient # [ 0.553065] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.551858] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned sshClient # [ 0.553084] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.551903] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned sshClient # [ 0.553104] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.551921] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned sshClient # [ 0.553124] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.551940] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned sshClient # [ 0.553142] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.551958] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned sshClient # [ 0.553160] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.551976] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.553180] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.551994] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.553199] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.552012] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned sshClient # [ 0.553217] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.552047] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned sshClient # [ 0.553238] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.552066] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned sshClient # [ 0.553256] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.552084] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.553274] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.552103] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.553292] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.552122] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.553309] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.552140] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned sshClient # [ 0.553327] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.552158] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned sshClient # [ 0.553344] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.552175] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned sshClient # [ 0.553362] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.552193] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned sshClient # [ 0.553392] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.552211] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned sshClient # [ 0.553397] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.552229] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned sshClient # [ 0.553400] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.552254] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] sshClient # [ 0.554287] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.552260] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.552262] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.553158] pci 0000:00:07.0: enabling device (0000 -> 0002) sshClient # [ 0.600955] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.608838] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) sshClient # [ 0.603213] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.619187] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.622040] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.621231] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.623493] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) sshClient # [ 0.627426] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.631891] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) sshClient # [ 0.630791] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.642496] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.644830] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) sshClient # [ 0.641656] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.648526] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) sshClient # [ 0.643749] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) sshClient # [ 0.647747] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.658542] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.660985] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) sshClient # [ 0.658108] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.664876] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) sshClient # [ 0.661716] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.668886] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) sshClient # [ 0.675665] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.678758] msm_serial: driver initialized sshClient # [ 0.678906] SuperH (H)SCI(F) driver initialized sshClient # [ 0.678976] STM32 USART driver initialized machine # [ 0.691949] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.694096] msm_serial: driver initialized machine # [ 0.694257] SuperH (H)SCI(F) driver initialized machine # [ 0.694317] STM32 USART driver initialized sshClient # [ 0.719143] loop: module loaded sshClient # [ 0.719372] virtio_blk virtio2: 1/0/0 default/read/poll queues sshClient # [ 0.720391] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.736000] loop: module loaded machine # [ 0.736276] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.737144] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 0.732646] megasas: 07.734.00.00-rc1 sshClient # [ 0.733758] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] sshClient # [ 0.736014] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.736041] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.743216] megasas: 07.734.00.00-rc1 machine # [ 0.744007] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.746229] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.741446] Using buffer write method machine # [ 0.746252] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.741499] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] sshClient # [ 0.743441] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.743464] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.759671] Using buffer write method machine # [ 0.759758] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.761732] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.761755] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.758586] Using buffer write method sshClient # [ 0.758614] Concatenating MTD devices: sshClient # [ 0.758616] (0): "0.flash" sshClient # [ 0.758747] (1): "0.flash" sshClient # [ 0.758749] into device "0.flash" machine # [ 0.767090] Using buffer write method machine # [ 0.767116] Concatenating MTD devices: machine # [ 0.767118] (0): "0.flash" machine # [ 0.767121] (1): "0.flash" machine # [ 0.767123] into device "0.flash" sshClient # [ 1.067760] Freeing initrd memory: 26060K machine # [ 1.072595] Freeing initrd memory: 26060K sshClient # [ 1.074813] tun: Universal TUN/TAP device driver, 1.6 machine # [ 1.079822] tun: Universal TUN/TAP device driver, 1.6 sshClient # [ 1.079464] thunder_xcv, ver 1.0 sshClient # [ 1.079526] thunder_bgx, ver 1.0 machine # [ 1.084845] thunder_xcv, ver 1.0 sshClient # [ 1.079548] nicpf, ver 1.0 machine # [ 1.084882] thunder_bgx, ver 1.0 machine # [ 1.084903] nicpf, ver 1.0 sshClient # [ 1.080141] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.085497] e1000: Intel(R) PRO/1000 Network Driver sshClient # [ 1.080147] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.085502] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.085529] e1000e: Intel(R) PRO/1000 Network Driver sshClient # [ 1.080175] e1000e: Intel(R) PRO/1000 Network Driver sshClient # [ 1.080183] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 1.085540] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. sshClient # [ 1.080210] igb: Intel(R) Gigabit Ethernet Network Driver sshClient # [ 1.080214] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 1.085566] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 1.085569] igb: Copyright (c) 2007-2014 Intel Corporation. sshClient # [ 1.080236] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 1.080240] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.085590] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 1.080396] sky2: driver version 1.30 machine # [ 1.085594] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.085736] sky2: driver version 1.30 sshClient # [ 1.089816] usbcore: registered new interface driver usb-storage machine # [ 1.095320] usbcore: registered new interface driver usb-storage sshClient # [ 1.089878] usbcore: registered new interface driver usbserial_generic machine # [ 1.095446] usbcore: registered new interface driver usbserial_generic sshClient # [ 1.089890] usbserial: USB Serial support registered for generic machine # [ 1.095459] usbserial: USB Serial support registered for generic sshClient # [ 1.090547] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.096280] hv_vmbus: registering driver hyperv_keyboard sshClient # [ 1.092157] ehci-pci 0000:00:07.0: EHCI Host Controller sshClient # [ 1.092187] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 1.097832] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 1.097877] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 sshClient # [ 1.092429] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.098134] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.104192] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.104225] rtc-pl031 9010000.pl031: setting system clock to 2026-10-24T03:01:49 UTC (1792810909) machine # [ 1.104715] i2c_dev: i2c /dev entries driver machine # [ 1.106483] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 sshClient # [ 1.098522] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.106891] hub 1-0:1.0: USB hub found machine # [ 1.106910] hub 1-0:1.0: 6 ports detected sshClient # [ 1.098549] rtc-pl031 9010000.pl031: setting system clock to 2026-10-24T03:01:49 UTC (1792810909) sshClient # [ 1.099032] i2c_dev: i2c /dev entries driver sshClient # [ 1.102551] sdhci: Secure Digital Host Controller Interface driver sshClient # [ 1.102559] sdhci: Copyright(c) Pierre Ossman sshClient # [ 1.102970] Synopsys Designware Multimedia Card Interface Driver sshClient # [ 1.103366] sdhci-pltfm: SDHCI platform and OF driver helper sshClient # [ 1.104546] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 sshClient # [ 1.104897] hub 1-0:1.0: USB hub found sshClient # [ 1.104915] hub 1-0:1.0: 6 ports detected machine # [ 1.114593] sdhci: Secure Digital Host Controller Interface driver machine # [ 1.114607] sdhci: Copyright(c) Pierre Ossman machine # [ 1.114914] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.115320] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.117167] hid: raw HID events driver (C) Jiri Kosina sshClient # [ 1.113343] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.117452] usbcore: registered new interface driver usbhid sshClient # [ 1.113613] usbcore: registered new interface driver usbhid sshClient # [ 1.113617] usbhid: USB HID core driver machine # [ 1.117457] usbhid: USB HID core driver sshClient # [ 1.114933] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available sshClient # [ 1.119791] drop_monitor: Initializing network drop monitor service sshClient # [ 1.120020] NET: Registered PF_INET6 protocol family machine # [ 1.123915] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.125809] drop_monitor: Initializing network drop monitor service machine # [ 1.125981] NET: Registered PF_INET6 protocol family sshClient # [ 1.122118] Segment Routing with IPv6 sshClient # [ 1.122146] In-situ OAM (IOAM) with IPv6 machine # [ 1.129444] Segment Routing with IPv6 machine # [ 1.129461] In-situ OAM (IOAM) with IPv6 sshClient # [ 1.122175] NET: Registered PF_PACKET protocol family sshClient # [ 1.122243] 9pnet: Installing 9P2000 support machine # [ 1.129494] NET: Registered PF_PACKET protocol family sshClient # [ 1.122292] Key type dns_resolver registered machine # [ 1.129582] 9pnet: Installing 9P2000 support machine # [ 1.129634] Key type dns_resolver registered sshClient # [ 1.131951] registered taskstats version 1 sshClient # [ 1.132104] Loading compiled-in X.509 certificates machine # [ 1.139278] registered taskstats version 1 machine # [ 1.139434] Loading compiled-in X.509 certificates sshClient # [ 1.141380] Demotion targets for Node 0: null machine # [ 1.148912] Demotion targets for Node 0: null sshClient # [ 1.141502] Key type .fscrypt registered machine # [ 1.149051] Key type .fscrypt registered sshClient # [ 1.141506] Key type fscrypt-provisioning registered machine # [ 1.149055] Key type fscrypt-provisioning registered sshClient # [ 1.141618] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.149168] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 1.141641] ima: Allocated hash algorithm: sha1 machine # [ 1.149193] ima: Allocated hash algorithm: sha1 sshClient # [ 1.141664] ima: No architecture policies found machine # [ 1.149226] ima: No architecture policies found sshClient # [ 1.142429] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.150071] input: gpio-keys as /devices/platform/gpio-keys/input/input0 sshClient # [ 1.166828] clk: Disabling unused clocks sshClient # [ 1.166848] PM: genpd: Disabling unused power domains machine # [ 1.174919] clk: Disabling unused clocks machine # [ 1.174942] PM: genpd: Disabling unused power domains sshClient # [ 1.172852] Freeing unused kernel memory: 7808K sshClient # [ 1.173096] Run /init as init process machine # [ 1.181163] Freeing unused kernel memory: 7808K machine # [ 1.181390] Run /init as init process sshClient # [ 1.191040] fuse: module verification failed: signature and/or required key missing - tainting kernel sshClient # [ 1.195034] fuse: init (API version 7.45) machine # [ 1.199588] fuse: module verification failed: signature and/or required key missing - tainting kernel sshClient # [ 1.198850] virtiofs virtio6: discovered new tag: nix-store machine # [ 1.203845] fuse: init (API version 7.45) sshClient # [ 1.199683] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.207762] virtiofs virtio6: discovered new tag: nix-store machine # [ 1.208611] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 1.214244] virtiofs virtio7: discovered new tag: shared sshClient # [ 1.215110] virtiofs virtio7: virtio_fs_setup_dax: No cache capability sshClient # [ 1.216472] virtiofs virtio8: discovered new tag: xchg sshClient # [ 1.219532] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 1.224401] virtiofs virtio7: discovered new tag: shared machine # [ 1.225305] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.228290] virtiofs virtio8: discovered new tag: xchg machine # [ 1.229113] virtiofs virtio8: virtio_fs_setup_dax: No cache capability sshClient # [ 1.231403] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.231893] systemd[1]: systemd 263-0f0682b 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.241558] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.231908] systemd[1]: Detected virtualization qemu. sshClient # [ 1.231930] systemd[1]: Detected architecture arm64. sshClient # [ 1.231936] systemd[1]: Running in initrd. sshClient # [ 1.232251] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.232349] systemd[1]: Hostname set to . machine # [ 1.242158] systemd[1]: systemd 263-0f0682b 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.242175] systemd[1]: Detected virtualization qemu. machine # [ 1.242196] systemd[1]: Detected architecture arm64. machine # [ 1.242202] systemd[1]: Running in initrd. machine # [ 1.249703] systemd[1]: Initializing machine ID from random generator. machine # [ 1.249838] systemd[1]: Hostname set to . sshClient # [ 1.341932] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.351730] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.358549] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 1.360589] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 1.408241] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.416644] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.421945] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.422173] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.422203] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.422227] systemd[1]: Reached target Path Units. machine # [ 1.422242] systemd[1]: Reached target Slice Units. machine # [ 1.422255] systemd[1]: Reached target Swaps. machine # [ 1.422270] systemd[1]: Reached target Timer Units. sshClient # [ 1.418845] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.419051] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.419082] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.419111] systemd[1]: Reached target Path Units. sshClient # [ 1.419127] systemd[1]: Reached target Slice Units. sshClient # [ 1.419144] systemd[1]: Reached target Swaps. sshClient # [ 1.419160] systemd[1]: Reached target Timer Units. machine # [ 1.427910] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.419308] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.428239] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.428373] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.419478] systemd[1]: Listening on Journal Socket (/dev/log). sshClient # [ 1.419604] systemd[1]: Listening on Journal Sockets. machine # [ 1.428450] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.419679] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.428653] systemd[1]: Listening on udev Varlink Socket. machine # [ 1.428672] systemd[1]: Reached target Socket Units. sshClient # [ 1.419871] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 1.419889] systemd[1]: Reached target Socket Units. machine # [ 1.430234] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.430344] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 1.431184] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.431296] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.445392] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.440712] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.448752] systemd[1]: Starting Journal Service... sshClient # [ 1.456752] systemd[1]: Starting Load Kernel Modules... sshClient # [ 1.456922] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.470683] systemd[1]: Starting Journal Service... machine # [ 1.479124] systemd[1]: Starting Load Kernel Modules... machine # [ 1.479279] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.494684] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.493294] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.508929] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.510164] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 1.511007] systemd-journald[71]: Collecting audit messages is disabled. sshClient # [ 1.514187] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 1.526282] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.526791] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.535264] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.541222] 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.538107] 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.555774] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.586744] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.589454] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.602651] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.604676] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.622085] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.622329] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.622332] [drm] features: -context_init machine # [ 1.625774] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.626786] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 sshClient # [ 1.617084] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.626555] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.635051] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 1.632974] 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.643402] [drm] number of scanouts: 1 machine # [ 1.643423] [drm] number of cap sets: 0 machine # [ 1.644191] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.644205] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 sshClient # [ 1.641221] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 sshClient # [ 1.641499] [drm] features: -virgl +edid -resource_blob -host_visible sshClient # [ 1.641504] [drm] features: -context_init sshClient # [ 1.642584] [drm] number of scanouts: 1 sshClient # [ 1.642601] [drm] number of cap sets: 0 sshClient # [ 1.652778] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic sshClient # [ 1.652806] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.678830] Console: switching to colour frame buffer device 160x50 machine # [ 1.695241] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device sshClient # [ 1.689045] Console: switching to colour frame buffer device 160x50 machine # [ 1.710458] systemd[1]: Finished Load Kernel Modules. machine # [ 1.715415] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 1.709234] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device sshClient # [ 1.712328] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.720779] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.721215] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.722019] systemd[1]: Reached target Local File Systems. machine # [ 1.730696] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.731025] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.731058] systemd[1]: Reached target Local File Systems. sshClient # [ 1.729668] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.737385] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.725926] systemd-modules-load[72]: Inserted module 'dm_mod' sshClient # [ 1.728256] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.746505] usb 1-2: new high-speed USB device number 3 using ehci-pci sshClient # [ 1.733171] systemd-modules-load[72]: Module 'virtio_console' is built in sshClient # [ 1.751223] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.751721] systemd[1]: Started Journal Service. sshClient # [ 1.738922] systemd-modules-load[72]: Inserted module 'virtio_gpu' sshClient # [ 1.756547] usb 1-2: new high-speed USB device number 3 using ehci-pci sshClient # [ 1.748839] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.752547] systemd-modules-load[72]: Inserted module 'dm_mod' machine # [ 1.756330] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.757702] systemd-modules-load[72]: Module 'virtio_console' is built in machine # [ 1.758885] systemd-modules-load[72]: Inserted module 'virtio_gpu' machine # [ 1.778824] systemd[1]: Started Journal Service. machine # [ 1.776223] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.794056] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.800114] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.805989] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.810042] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.848581] systemd-udevd[79]: Using default interface naming scheme 'v261'. machine # [ 1.856841] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.857988] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.873813] systemd-udevd[79]: Using default interface naming scheme 'v261'. machine # [ 1.900603] 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.900918] 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.898750] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.911201] 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.911735] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 sshClient # [ 1.923068] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.020547] systemd[1]: Starting Virtual Console Setup... sshClient # [ 2.034813] systemd[1]: Starting Virtual Console Setup... machine # [ 2.104620] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.116158] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.129037] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 2.144964] systemd[1]: Finished Virtual Console Setup. machine # [ 2.780327] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.792173] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.842837] (udev-worker)[103]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.850936] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.852567] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.862529] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.865165] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.869389] systemd[1]: Reached target System Initialization. machine # [ 2.872859] systemd[1]: Reached target Basic System. sshClient # [ 2.876480] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 2.878345] systemd[1]: Reached target System Initialization. sshClient # [ 2.880196] systemd[1]: Reached target Basic System. machine # [ 2.903990] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.909862] systemd[1]: Reached target Initrd Root Device. machine # [ 2.916148] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 2.919151] systemd[1]: Found device /dev/disk/by-label/nixos. sshClient # [ 2.920734] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.926286] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.973469] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 2.977813] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.123763] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 3.124166] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 3.128612] systemd[1]: Mounting /sysroot... machine # [ 3.140327] systemd[1]: Mounting /sysroot... sshClient # [ 3.297351] EXT4-fs (vda): mounted filesystem 28c9ebd2-6f8c-431a-a998-66016d819d6c r/w with ordered data mode. Quota mode: none. machine # [ 3.303406] EXT4-fs (vda): mounted filesystem d7841fcb-efde-4384-a049-60aa363e73d9 r/w with ordered data mode. Quota mode: none. sshClient # [ 3.286815] systemd[1]: Mounted /sysroot. machine # [ 3.291777] systemd[1]: Mounted /sysroot. sshClient # [ 3.289505] systemd[1]: Reached target Initrd Root File System. machine # [ 3.294877] systemd[1]: Reached target Initrd Root File System. sshClient # [ 3.295147] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.299393] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 3.327856] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.331457] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.336976] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 3.334611] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.341210] systemd[1]: Reloading... sshClient # [ 3.339838] systemd[1]: Reloading... machine # [ 3.566114] systemd[1]: Reloading finished in 224 ms. sshClient # [ 3.563628] systemd[1]: Reloading finished in 225 ms. machine # [ 3.601190] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.597584] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... machine # [ 3.606185] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... sshClient # [ 3.603010] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... machine # [ 3.613078] systemd[1]: Mounting /sysroot/nix/.ro-store... sshClient # [ 3.611431] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.626707] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.624138] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.644184] systemd[1]: Mounting /sysroot/run... machine # [ 3.647951] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.669710] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.677955] systemd[1]: Mounting /sysroot/tmp/shared... sshClient # [ 3.695596] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.703199] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 3.709669] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.724924] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.737829] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 3.738252] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.741576] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 3.740748] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.761580] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 3.763636] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.771140] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 3.775870] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 3.778581] systemd[1]: Mounted /sysroot/run. machine # [ 3.806111] systemd[1]: Mounted /sysroot/run. machine # [ 3.808771] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 3.813518] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.822992] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.827646] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 3.827649] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 3.831860] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.865162] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.868395] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.866357] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 3.868283] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.341267] (udev-worker)[104]: mtd0ro: Failed to find and pin callout binary "/nix/store/2g8v5yf619hqwad8g5jcynn5sz166dvl-systemd-263-0f0682b/lib/udev/mtd_probe": No such file or directory machine # [ 4.349279] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/2g8v5yf619hqwad8g5jcynn5sz166dvl-systemd-263-0f0682b/lib/udev/mtd_probe": No such file or directory sshClient # [ 4.347646] (udev-worker)[104]: 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.356226] (udev-worker)[93]: 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.383217] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.386772] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.393132] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.392311] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.396089] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.393326] systemd[1]: Starting Virtual Console Setup... machine # [ 4.397536] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.400340] systemd[1]: Starting Virtual Console Setup... sshClient # [ 4.425225] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 4.429279] systemd[1]: Finished Virtual Console Setup. machine # [ 4.432918] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.437062] systemd[1]: Finished Virtual Console Setup. machine # [ 4.606933] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 4.604896] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.649243] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.651969] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.650672] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.656183] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.653230] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.657770] systemd[1]: Starting Find NixOS closure... machine # [ 4.661940] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.665700] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.710108] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.711555] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.718415] systemd[1]: Finished Find NixOS closure. machine # [ 4.720712] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.717186] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.724163] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 4.724130] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 4.733093] systemd[1]: Finished Find NixOS closure. sshClient # [ 4.736286] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.737968] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.752879] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.754672] systemd[1]: Stopped target Basic System. machine # [ 4.756630] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.760455] systemd[1]: Stopped target Path Units. machine # [ 4.761380] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.763381] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.766100] systemd[1]: Stopped target Slice Units. machine # [ 4.768189] systemd[1]: Stopped target Socket Units. machine # [ 4.769743] systemd[1]: Stopped target System Initialization. machine # [ 4.773452] systemd[1]: Stopped target Swaps. sshClient # [ 4.771111] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.776204] systemd[1]: Stopped target Timer Units. sshClient # [ 4.772960] systemd[1]: Stopped target Basic System. machine # [ 4.778802] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.776440] systemd[1]: Stopped target Initrd Root Device. sshClient # [ 4.777571] systemd[1]: Stopped target Path Units. sshClient # [ 4.779328] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.786054] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.783288] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.788146] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 4.785578] systemd[1]: Stopped target Slice Units. machine # [ 4.789305] systemd[1]: Stopped Find NixOS closure. machine # [ 4.790109] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.790997] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 4.788178] systemd[1]: Stopped target Socket Units. sshClient # [ 4.789766] systemd[1]: Stopped target System Initialization. sshClient # [ 4.792173] systemd[1]: Stopped target Swaps. machine # [ 4.791980] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.798672] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 4.796219] systemd[1]: Stopped target Timer Units. sshClient # [ 4.797135] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.798063] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.800129] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.808500] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.809541] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 4.809490] systemd[1]: Stopped Find NixOS closure. sshClient # [ 4.811430] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.815885] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 4.813782] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.820636] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.817490] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.821825] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.822767] systemd[1]: Stopped target Local File Systems. machine # [ 4.823597] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.828711] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.825828] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 4.828414] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.832196] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.833210] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.831125] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.836251] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.840243] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.841138] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.842386] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.843682] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.840533] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.844912] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.841769] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.842917] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.846010] systemd[1]: systemd-udevd.service: Consumed 1.502s CPU time over 3.098s wall clock time, 25.1M memory peak. sshClient # [ 4.843869] systemd[1]: Stopped target Local File Systems. machine # [ 4.847524] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.846456] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.852317] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.853329] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully. machine # [ 4.854868] systemd[1]: Closed udev Varlink Socket. sshClient # [ 4.852332] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.853597] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.857799] systemd[1]: Starting Cleanup udev Database... sshClient # [ 4.854575] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.856257] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.860253] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.861503] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 4.858911] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.860656] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.864134] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.863306] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.868518] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.865079] systemd[1]: systemd-udevd.service: Consumed 1.487s CPU time over 3.095s wall clock time, 23.9M memory peak. machine # [ 4.869721] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.866677] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 4.867698] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.872115] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.869022] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully. sshClient # [ 4.870107] systemd[1]: Closed udev Varlink Socket. sshClient # [ 4.870906] systemd[1]: Starting Cleanup udev Database... sshClient # [ 4.871776] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.876408] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 4.879620] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.884598] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.885734] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.886923] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.887854] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.892420] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.907458] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.910550] systemd[1]: Finished Cleanup udev Database. machine # [ 4.913484] systemd[1]: Reached target Switch Root. machine # [ 4.915004] systemd[1]: Starting NixOS Activation... sshClient # [ 4.931300] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. sshClient # [ 4.935061] systemd[1]: Finished Cleanup udev Database. sshClient # [ 4.935928] systemd[1]: Reached target Switch Root. sshClient # [ 4.938180] systemd[1]: Starting NixOS Activation... machine # [ 5.014219] initrd-nixos-activation-start[173]: booting system configuration /nix/store/8ayak4q3g5jkd2lbri6jg1vyq03hs2gm-nixos-system-machine-test sshClient # [ 5.026553] initrd-nixos-activation-start[174]: booting system configuration /nix/store/bwks7k3pfs60grb8wmcagz85qsrls588-nixos-system-sshClient-test machine # [ 5.050388] initrd-nixos-activation-start[173]: running activation script... sshClient # [ 5.060424] initrd-nixos-activation-start[174]: running activation script... machine # [ 5.284935] initrd-nixos-activation-start[196]: setting up /etc... sshClient # [ 5.299868] initrd-nixos-activation-start[197]: setting up /etc... machine # [ 5.419117] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.422509] systemd[1]: Finished NixOS Activation. machine # [ 5.423862] systemd[1]: Starting Switch Root... sshClient # [ 5.425589] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 5.428228] systemd[1]: Finished NixOS Activation. sshClient # [ 5.429969] systemd[1]: Starting Switch Root... machine # [ 5.450026] systemd[1]: Switching root. sshClient # [ 5.453104] systemd[1]: Switching root. machine # [ 5.640304] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). sshClient # [ 5.638281] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 5.738164] NET: Registered PF_VSOCK protocol family sshClient # [ 5.733338] NET: Registered PF_VSOCK protocol family sshClient # [ 5.804130] systemd[1]: systemd 263-0f0682b 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.812651] systemd[1]: Detected virtualization qemu. sshClient # [ 5.812726] systemd[1]: Detected architecture arm64. sshClient # [ 5.812840] systemd[1]: Detected first boot. sshClient # [ 5.814372] systemd[1]: Initializing machine ID from random generator. machine # [ 5.818290] systemd[1]: systemd 263-0f0682b 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.826870] systemd[1]: Detected virtualization qemu. machine # [ 5.826933] systemd[1]: Detected architecture arm64. machine # [ 5.827036] systemd[1]: Detected first boot. machine # [ 5.828501] systemd[1]: Initializing machine ID from random generator. sshClient # [ 6.039621] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 6.044923] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 6.198322] systemd[1]: Applying preset policy. machine # [ 6.208716] systemd[1]: Applying preset policy. sshClient # [ 6.430117] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.446221] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 6.643576] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.646254] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 6.649921] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.651920] systemd[1]: Created slice Slice /system/getty. sshClient # [ 6.656138] systemd[1]: Created slice User and Session Slice. sshClient # [ 6.658918] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.659557] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.663501] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 6.664036] systemd[1]: Expecting device /dev/ttyAMA0... sshClient # [ 6.667181] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 6.667725] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.674061] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.670844] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.677561] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 6.671348] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 6.672289] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.676251] systemd[1]: Reached target Path Units. machine # [ 6.682076] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.678366] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.678884] systemd[1]: Reached target Slice Units. sshClient # [ 6.679805] systemd[1]: Reached target Swaps. machine # [ 6.687575] systemd[1]: Created slice Slice /system/getty. machine # [ 6.689181] systemd[1]: Created slice User and Session Slice. machine # [ 6.689838] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.684986] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 6.687155] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.694051] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.690707] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 6.692251] systemd[1]: Listening on Factory Reset Management. machine # [ 6.696877] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.697379] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.698340] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 6.694613] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.702204] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 6.699447] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.704446] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.704900] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.705838] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.703097] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.709883] systemd[1]: Reached target Path Units. machine # [ 6.710348] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.706522] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 6.707599] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.713745] systemd[1]: Reached target Slice Units. machine # [ 6.714254] systemd[1]: Reached target Swaps. sshClient # [ 6.708182] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.712261] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.718939] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.721755] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.720803] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.726539] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 6.721564] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.728234] systemd[1]: Listening on Factory Reset Management. sshClient # [ 6.722519] systemd[1]: Listening on User Database Manager Socket. machine # [ 6.728844] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 6.728966] systemd[1]: Mounting Huge Pages File System... machine # [ 6.735068] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.737301] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.733665] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.741747] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.745484] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.749725] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.750151] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.756689] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.757445] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.758254] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 6.752691] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.764113] systemd[1]: Mounting Huge Pages File System... machine # [ 6.769718] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.765848] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.779540] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.786614] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 6.788699] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.803432] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.803475] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.809247] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.820714] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.815310] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.820747] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.826898] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.846844] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.847516] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.851366] systemd[1]: Mounting FUSE Control File System... machine # [ 6.856373] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.851843] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.856989] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.881421] systemd[1]: Mounting FUSE Control File System... machine # [ 6.881908] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 6.882758] systemd[1]: Starting Journal Service... sshClient # [ 6.912584] systemd[1]: Starting Load Kernel Modules... machine # [ 6.925310] systemd[1]: Starting Journal Service... machine # [ 6.943535] systemd[1]: Starting Load Kernel Modules... sshClient # [ 6.940933] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 6.952755] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 6.953221] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.970859] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.978756] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.988703] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.989140] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.003303] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 7.003914] systemd[1]: Mounted Huge Pages File System. sshClient # [ 7.004442] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 7.010848] systemd-journald[268]: Collecting audit messages is enabled. sshClient # [ 7.024207] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.027424] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 7.028219] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.050124] systemd-journald[267]: Collecting audit messages is enabled. sshClient # [ 7.033456] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 7.052034] systemd[1]: Started Journal Service. sshClient # [ 7.037769] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. sshClient # [ 7.039355] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0. machine # [ 7.059415] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.060153] systemd[1]: Mounted Huge Pages File System. machine # [ 7.060700] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.061149] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.050778] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.056238] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. machine # [ 7.059031] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.[ 7.076589] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 7.062865] systemd[1]: systemd-journald.service: Deactivated successfully. machine # sshClient # [ 7.068601] systemd-modules-load[269]: Module 'atkbd' is built in machine # [ 7.072841] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 7.069828] systemd-modules-load[269]: Module 'loop' is built in machine # [ 7.074509] systemd-modules-load[268]: Module 'atkbd' is built in sshClient # [ 7.071264] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.075629] systemd-modules-load[268]: Module 'loop' is built in machine # [ 7.099301] systemd[1]: Started Journal Service. sshClient # [ 7.083466] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.088872] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 7.086006] systemd[1]: Finished Load Kernel Modules. machine # [ 7.090119] systemd[1]: Finished Load Kernel Modules. sshClient # [ 7.095238] systemd[1]: Starting Firewall... sshClient # [ 7.103373] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 7.112591] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.137520] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.145536] systemd-oomd[270]: No swap; memory pressure usage will be degraded sshClient # [ 7.156693] EXT4-fs (vda): re-mounted 28c9ebd2-6f8c-431a-a998-66016d819d6c. machine # [ 7.154233] systemd[1]: Starting Firewall... machine # [ 7.160295] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 7.181931] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.187134] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.196508] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.226502] EXT4-fs (vda): re-mounted d7841fcb-efde-4384-a049-60aa363e73d9. sshClient # [ 7.209693] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 7.219884] systemd-oomd[271]: No swap; memory pressure usage will be degraded machine # [ 7.232122] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.233289] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 7.236541] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 7.237639] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.248448] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 7.253943] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 7.255126] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.259240] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 7.265758] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. sshClient # [ 7.267055] systemd[1]: Mounted FUSE Control File System. machine # [ 7.275023] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.277064] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.299754] systemd[1]: Mounted FUSE Control File System. sshClient # [ 7.374492] systemd-journald[268]: Received client request to flush runtime journal. machine # [ 7.403578] systemd-journald[267]: Received client request to flush runtime journal. sshClient # [ 7.469368] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 7.472375] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.482970] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 7.488937] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 7.653999] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 7.663594] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 7.667837] systemd[1]: Mounting /run/wrappers... sshClient # [ 7.673736] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 7.730372] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 7.778696] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.779582] systemd[1]: Reached target Local File Systems. machine # [ 7.784918] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 7.786825] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.794800] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.799170] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.803432] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 7.800694] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 7.806295] systemd[1]: Starting Create System Files and Directories... machine # [ 7.812569] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.813684] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.814695] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.815762] systemd[1]: Mounting /run/wrappers... machine # [ 7.825164] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.826202] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.827194] systemd[1]: Mounted /run/wrappers. machine # [ 7.827905] systemd[1]: Reached target Local File Systems. machine # [ 7.844513] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.845632] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.846511] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.847795] systemd[1]: Starting Create System Files and Directories... machine # [ 7.863381] systemd-udevd[303]: Using default interface naming scheme 'v261'. sshClient # [ 7.880402] systemd-udevd[308]: Using default interface naming scheme 'v261'. machine # [ 7.947349] systemd[1]: Starting User Database Manager... sshClient # [ 7.972253] systemd[1]: Starting User Database Manager... sshClient # [ 8.009237] systemd[1]: Finished Create System Files and Directories. sshClient # [ 8.023584] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 8.037554] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.096449] systemd[1]: Finished Create System Files and Directories. machine # [ 8.111707] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 8.118870] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.158277] systemd[1]: Started User Database Manager. sshClient # [ 8.197793] systemd[1]: Started User Database Manager. sshClient # [ 8.204855] systemd[1]: Finished Record System Boot/Shutdown in UTMP. sshClient # [ 8.258449] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 8.270803] systemd[1]: Starting Update is Completed... machine # [ 8.282370] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.314779] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.325067] systemd[1]: Starting Update is Completed... sshClient # [ 8.404302] systemd[1]: Finished Update is Completed. machine # [ 8.432715] systemd[1]: Finished Update is Completed. sshClient # [ 8.444339] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.452708] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 8.676805] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 8.745568] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 8.766200] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.823776] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 9.035000] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 9.041756] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 9.120297] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 9.132388] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 9.141076] systemd[1]: Reached target System Initialization. sshClient # [ 9.142195] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 9.143261] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 9.151377] systemd[1]: Reached target Timer Units. sshClient # [ 9.153411] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 9.154506] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 9.164352] systemd[1]: Reached target Socket Units. sshClient # [ 9.165295] systemd[1]: Reached target Basic System. sshClient # [ 9.166239] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 9.167304] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 9.178644] systemd[1]: Starting Post-Boot Actions... machine # [ 9.191966] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.193249] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 9.197015] systemd[1]: Started Reset console on configuration changes. machine # [ 9.244942] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 9.248643] systemd[1]: Starting resolvconf update... machine # [ 9.256474] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.260450] systemd[1]: Reached target System Initialization. machine # [ 9.261491] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.262591] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.263551] systemd[1]: Reached target Timer Units. machine # [ 9.274123] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.275261] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). sshClient # [ 9.274589] systemd[1]: Starting Home Area Manager... machine # [ 9.283669] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.289753] systemd[1]: Reached target Socket Units. machine # [ 9.290579] systemd[1]: Reached target Basic System. machine # [ 9.291376] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 9.295484] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 9.301879] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.315127] systemd[1]: Starting Post-Boot Actions... machine # [ 9.327497] systemd[1]: Started Reset console on configuration changes. sshClient # [ 9.355435] systemd[1]: Started backdoor.service. machine # [ 9.370132] systemd[1]: Starting resolvconf update... sshClient # [ 9.392533] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.420323] systemd[1]: Starting SSH Host Keys Generation... sshClient # [ 9.441362] systemd[1]: Finished Post-Boot Actions. sshClient # [ 9.442404] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.480341] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 9.489435] nsncd[440]: Oct 24 03:01:57.905 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.505478] systemd[1]: Starting Home Area Manager... machine # [ 9.549258] systemd[1]: Finished Post-Boot Actions. sshClient # [ 9.545829] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 9.569159] systemd-homed[448]: Watching /home. machine # [ 9.580534] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.582136] nsncd[449]: Oct 24 03:01:57.982 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 9.591182] systemd[1]: Starting User Login Management... machine # [ 9.601260] (udev-worker)[384]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.626097] (udev-worker)[384]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.636199] (udev-worker)[379]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.648440] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 9.659382] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.670434] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 9.680442] (udev-worker)[387]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.690432] systemd[1]: Started backdoor.service. sshClient # [ 9.695100] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.704365] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 9.738626] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line. sshClient # connecting to host... machine # [ 9.759330] systemd-homed[459]: Watching /home. sshClient # [ 9.756849] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.766913] systemd[1]: Starting User Login Management... sshClient # [ 9.784609] dbus-broker-launch[453]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 9.801987] dbus-broker-launch[453]: NSS returned no entry for 'systemd-timesync' sshClient # [ 9.803259] dbus-broker-launch[453]: Invalid user-name in /nix/store/vsfq21l6ig5hfphri57fsrnlmi94bl5v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 9.810132] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 9.851193] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 9.904095] dbus-broker-launch[453]: Ready sshClient # [ 9.919624] systemd[1]: Started Home Area Manager. sshClient # [ 9.926624] systemd[1]: Finished Home Area Activation. sshClient # [ 9.942736] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 9.953547] systemd-logind[473]: New seat seat0. sshClient # [ 9.970794] systemd[1]: Started User Login Management. machine # connecting to host... sshClient # [ 10.002619] systemd[1]: Starting linger-users.service... sshClient # [ 10.067261] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 10.074294] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 10.075393] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 10.088899] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 10.089894] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 10.090846] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 10.107682] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 10.128313] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.143635] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync' machine # [ 10.150164] dbus-broker-launch[488]: Invalid user-name in /nix/store/vf2vfspyf9pk8mm10gmj0pyh9kcqn8bi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine: connected to guest root shell machine: (connecting took 10.60 seconds) machine: (finished: waiting for the VM to finish booting, in 10.60 seconds) sshClient # [ 10.164257] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 10.175030] systemd[1]: Finished First Boot Home Area Wizard. sshClient # [ 10.191829] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.199196] systemd-logind[491]: New seat seat0. machine # [ 10.208560] systemd[1]: Started User Login Management. sshClient # [ 10.206902] systemd[1]: Finished linger-users.service. sshClient # [ 10.230650] systemd[1]: Reached target First Boot Complete. machine # [ 10.237960] systemd[1]: Starting linger-users.service... sshClient # [ 10.260160] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 10.276978] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 10.321538] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 10.330406] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 10.331485] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.334991] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.344578] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.345839] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 10.340939] nsncd[550]: Oct 24 03:01:58.738 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.346810] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.347645] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.372821] dbus-broker-launch[488]: Ready machine # [ 10.373674] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 10.376264] systemd[1]: Finished resolvconf update. machine # [ 10.388327] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 10.400488] systemd[1]: Starting DHCP Client... sshClient # [ 10.488943] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 10.490382] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.495354] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.500304] systemd[1]: Finished linger-users.service. machine # [ 10.519102] systemd[1]: Started Home Area Manager. machine # [ 10.536198] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 10.539555] systemd-logind[473]: Watching system buttons on /dev/input/event0 (gpio-keys) sshClient # [ 10.544126] systemd[1]: Finished Firewall. machine # [ 10.548943] systemd[1]: Finished Home Area Activation. sshClient # [ 10.547965] systemd[1]: Reached target Preparation for Network. machine # [ 10.561704] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 10.561279] systemd[1]: Starting Address configuration of eth1... sshClient # [ 10.584512] systemd[1]: Starting Extra networking commands.... machine # [ 10.635632] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.646534] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.688399] systemd[1]: Finished resolvconf update. machine # [ 10.722150] systemd[1]: Starting DHCP Client... machine # [ 10.723026] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.733286] systemd[1]: Finished Firewall. machine # [ 10.740975] nsncd[582]: Oct 24 03:01:59.128 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.757486] systemd[1]: Reached target Preparation for Network. machine # [ 10.768566] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.769719] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.791144] systemd[1]: Starting Address configuration of eth1... machine # [ 10.799094] systemd[1]: Starting Extra networking commands.... sshClient # [ 10.810734] dhcpcd[594]: dhcpcd-10.3.2 starting sshClient # [ 10.821679] dhcpcd[610]: dev: loaded udev sshClient # [ 10.830739] network-addresses-eth1-start[596]: adding address 192.168.1.2/24... done machine # [ 10.851199] systemd-logind[491]: Watching system buttons on /dev/input/event0 (gpio-keys) sshClient # [ 10.852323] network-addresses-eth1-start[596]: adding address 2001:db8:1::2/64... done machine # [ 10.877255] systemd[1]: Created slice Slice /system/systemd-mute-console. sshClient # [ 10.897675] mousedev: PS/2 mouse device common for all mice sshClient # [ 10.908622] 8021q: 802.1Q VLAN Support v1.8 machine # [ 10.900310] systemd[1]: Started Console Output Muting Service (PID 585/UID 0). sshClient # [ 10.909011] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 10.908261] systemd[1]: Finished Address configuration of eth1. sshClient # [ 11.048794] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 11.051799] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 11.052309] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' sshClient # [ 11.060457] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 11.060908] cfg80211: failed to load regulatory.db sshClient # [ 11.107643] systemd[1]: Finished Extra networking commands.. sshClient # [ 11.109467] systemd[1]: Reached target Network. sshClient # [ 11.114030] systemd[1]: Starting Permit User Sessions... machine # [ 11.123234] network-addresses-eth1-start[606]: adding address 192.168.1.1/24... done machine # [ 11.159271] network-addresses-eth1-start[606]: adding address 2001:db8:1::1/64... done sshClient # [ 11.181699] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 11.195200] systemd[1]: Finished Save Transient machine-id to Disk. sshClient # [ 11.198215] dhcpcd[610]: eth0: waiting for carrier sshClient # [ 11.199436] dhcpcd[610]: eth0: carrier acquired[ 11.213034] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # sshClient # [ 11.210240] systemd[1]: Finished Permit User Sessions. sshClient # [ 11.225144] systemd[1]: Started Getty on tty1. machine # [ 11.229916] systemd[1]: Finished Address configuration of eth1. sshClient # [ 11.229969] systemd[1]: Reached target Login Prompts. sshClient # [ 11.236146] dhcpcd[610]: DUID 00:01:00:01:32:6e:dc:27:52:54:00:12:34:56 sshClient # [ 11.237421] dhcpcd[610]: eth0: IAID 00:12:34:56 sshClient # [ 11.238214] dhcpcd[610]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 11.285617] systemd-logind[473]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 11.301793] dhcpcd[616]: dhcpcd-10.3.2 starting machine # [ 11.318486] dhcpcd[655]: dev: loaded udev machine # [ 11.498105] systemd[1]: Finished Extra networking commands.. machine # [ 11.502326] systemd[1]: Reached target Network. machine: (finished: waiting for unit systemd-homed.service, in 12.20 seconds) machine: waiting for Please enter user name to create to appear on tty 1 machine # [ 11.960713] dhcpcd[655]: eth0: waiting for carrier machine # [ 11.962659] systemd-logind[491]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 11.971032] dhcpcd[655]: eth0: carrier acquired machine # [ 12.028569] dhcpcd[655]: DUID 00:01:00:01:32:6e:dc:28:52:54:00:12:34:56 machine # [ 12.029757] dhcpcd[655]: eth0: IAID 00:12:34:56 machine # [ 12.030459] dhcpcd[655]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 12.041026] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.27 seconds) machine: sending keys 'test-homed-user\n' 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 sshClient # [ 12.373626] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 12.413929] systemd[1]: Starting Virtual Console Setup... sshClient # [ 12.471348] systemd-logind[473]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 12.565980] systemd-vconsole-setup[688]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 12.571710] systemd[1]: Finished Virtual Console Setup. sshClient # [ 12.876887] dhcpcd[610]: eth0: soliciting a DHCP lease sshClient # [ 12.881004] dhcpcd[610]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 12.888357] dhcpcd[610]: eth0: probing address 10.0.2.15/24 machine # [ 12.921853] sshd-keygen-start[503]: Generating public/private rsa key pair. machine # [ 12.923710] sshd-keygen-start[503]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 12.928270] sshd-keygen-start[503]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine # [ 12.929837] sshd-keygen-start[503]: The key fingerprint is: machine # [ 12.930679] sshd-keygen-start[503]: SHA256:GCYtutT4doSgEVLlo3p0VX6GM0ihLkQHKoOwuB4ZsnU root@machine machine # [ 12.937840] sshd-keygen-start[503]: The key's randomart image is: machine # [ 12.938796] sshd-keygen-start[503]: +---[RSA 4096]----+ machine # [ 12.939501] sshd-keygen-start[503]: |+.+oo o.. | machine # [ 12.943023] sshd-keygen-start[503]: |== o + + . | machine # [ 12.943893] sshd-keygen-start[503]: |X.+ E * = o | machine # [ 12.948962] sshd-keygen-start[503]: |oOoO B o = | machine # [ 12.949995] sshd-keygen-start[503]: |+oB = o S | machine # [ 12.950739] sshd-keygen-start[503]: |.+.= . | machine # [ 12.951478] sshd-keygen-start[503]: |..o o . | machine # [ 12.956116] sshd-keygen-start[503]: | . . . | machine # [ 12.956960] sshd-keygen-start[503]: | | machine # [ 12.957757] sshd-keygen-start[503]: +----[SHA256]-----+ machine # [ 13.023539] sshd-keygen-start[718]: Generating public/private ed25519 key pair. machine # [ 13.026648] sshd-keygen-start[718]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 13.030204] sshd-keygen-start[718]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 13.031585] sshd-keygen-start[718]: The key fingerprint is: machine # [ 13.036702] sshd-keygen-start[718]: SHA256:s5mfsHtA4ybU2jUPeDpnK96rXz7+iwQYG3Xnt7J2pJk root@machine machine # [ 13.038081] sshd-keygen-start[718]: The key's randomart image is: machine # [ 13.038937] sshd-keygen-start[718]: +--[ED25519 256]--+ machine # [ 13.039678] sshd-keygen-start[718]: | . . . | machine # [ 13.047328] sshd-keygen-start[718]: | . . o | machine # [ 13.049942] sshd-keygen-start[718]: | .o. . .| machine # [ 13.050808] sshd-keygen-start[718]: | . === ..| machine # [ 13.051568] sshd-keygen-start[718]: | . =S=.+ . o | machine # [ 13.060217] sshd-keygen-start[718]: | o B=o.. B | machine # [ 13.061066] sshd-keygen-start[718]: | o== .oE . | machine # [ 13.061849] sshd-keygen-start[718]: | .=o=o.. | machine # [ 13.062605] sshd-keygen-start[718]: | .=**o+oo. | machine # [ 13.063339] sshd-keygen-start[718]: +----[SHA256]-----+ machine # [ 13.072440] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 13.073682] systemd[1]: Finished SSH Host Keys Generation. machine # [ 13.074497] systemd[1]: Starting SSH Daemon... machine # [ 13.169781] sshd[720]: Server listening on 0.0.0.0 port 22. machine # [ 13.170790] systemd[1]: Started SSH Daemon. machine # [ 13.171563] sshd[720]: Server listening on :: port 22. machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.35 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for (repeat) to appear on tty 1 machine # [ 13.663501] dhcpcd[655]: eth0: soliciting an IPv6 router machine # [ 13.668835] dhcpcd[655]: eth0: Router Advertisement from fe80::2 machine # [ 13.669945] dhcpcd[655]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.670962] dhcpcd[655]: eth0: adding route to fec0::/64 machine # [ 13.671741] dhcpcd[655]: eth0: adding default route via fe80::2 sshClient # [ 13.668694] dhcpcd[610]: eth0: soliciting an IPv6 router sshClient # [ 13.671043] dhcpcd[610]: eth0: Router Advertisement from fe80::2 sshClient # [ 13.673225] dhcpcd[610]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 13.674816] dhcpcd[610]: eth0: adding route to fec0::/64 sshClient # [ 13.676115] dhcpcd[610]: eth0: adding default route via fe80::2 machine: (finished: waiting for (repeat) to appear on tty 1, in 0.16 seconds) machine: sending keys 'foobarfoo\n' machine # [ 13.827810] dhcpcd[655]: eth0: soliciting a DHCP lease machine # [ 13.832685] dhcpcd[655]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 13.840366] dhcpcd[655]: eth0: probing address 10.0.2.15/24 machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for unit systemd-homed-firstboot.service machine # [ 14.191215] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 14.232490] systemd[1]: Starting Virtual Console Setup... machine # [ 14.263884] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 14.267203] systemd[1]: Stopped Virtual Console Setup. machine # [ 14.277928] systemd[1]: Starting Virtual Console Setup... machine # [ 14.311095] systemd-logind[491]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 14.411680] systemd-vconsole-setup[755]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 14.416287] systemd[1]: Finished Virtual Console Setup. sshClient # [ 17.629871] dhcpcd[610]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 17.632214] dhcpcd[610]: eth0: adding route to 10.0.2.0/24 sshClient # [ 17.633599] dhcpcd[610]: eth0: adding default route via 10.0.2.2 sshClient # [ 17.832234] systemd[1]: Started DHCP Client. sshClient # [ 17.834843] systemd[1]: Reached target Multi-User System. sshClient # [ 17.836711] systemd[1]: Startup finished in 1.161s (kernel) + 4.536s (initrd) + 12.137s (userspace) = 17.835s. machine # [ 18.620566] dhcpcd[655]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 18.620879] dhcpcd[655]: eth0: adding route to 10.0.2.0/24 machine # [ 18.620964] dhcpcd[655]: eth0: adding default route via 10.0.2.2 machine # [ 18.752685] systemd[1]: Started DHCP Client. machine # [ 24.229862] systemd-homed[459]: Generating key pair for signing local user identity records. machine # [ 24.234328] systemd-homed[459]: Successfully created Ed25519 key pair. machine # [ 24.250298] systemd-homed[459]: test-homed-user: changing state absent → creating machine # [ 24.360273] systemd-homework[854]: Using automatic default storage of 'luks'. machine # [ 24.367213] systemd-homework[854]: Sizing home to 83% of available disk space, which is 744M. machine # [ 24.368998] systemd-homework[854]: Allocating image file completed. machine # [ 24.388244] systemd-homework[854]: Writing of partition table completed. machine # [ 24.396298] systemd-homework[854]: Setting up loopback device /dev/loop0 completed. machine # [ 25.824584] systemd-homework[854]: LUKS formatting completed. machine # [ 28.204499] systemd-homework[854]: Writing password to LUKS keyslot 0 completed. machine # [ 28.421831] systemd-homework[854]: LUKS activation by volume key succeeded. machine # [ 28.447865] systemd-homework[854]: Writing user record as LUKS token completed. machine # [ 28.450349] systemd-homework[854]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 28.451824] systemd-homework[854]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 29.315932] systemd-homework[854]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid c84feec6-7304-42fa-812c-340d4c015a52) machine # [ 29.316160] systemd-homework[854]: Formatting file system completed. machine # [ 29.317655] systemd[1]: proc-886-mounts.mount: Deactivated successfully. machine # [ 29.404112] systemd-homework[854]: Mounting file system completed. machine # [ 29.406808] systemd-homework[854]: Skeleton directory /etc/skel missing, ignoring. machine # [ 29.410062] systemd-homework[854]: Wrote embedded .identity file. machine # [ 29.412496] systemd-homework[854]: Replaced embedded blob dir with contents of system blob dir. machine # [ 29.414330] systemd-homework[854]: Recursive changing of ownership completed. machine # [ 29.415502] systemd-homework[854]: Changed top-level directory access mode to 0700. machine # [ 29.422539] systemd-homework[854]: Synchronized disk. machine # [ 29.423464] systemd-homework[854]: Discarded unused 652.8M. machine # [ 29.438203] systemd-homed[459]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 29.469353] systemd-homed[459]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 29.503337] (udev-worker)[916]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 29.508414] (udev-worker)[916]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 29.511574] systemd-homed[459]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 29.524906] systemd-homework[854]: Moved image file into place. machine # [ 29.526195] systemd-homework[854]: Creation completed. machine # [ 29.527032] systemd-homework[854]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M. machine # [ 29.537824] systemd-homed[459]: test-homed-user: changing state creating → inactive machine # [ 29.550095] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 29.554854] systemd[1]: systemd-mute-console@0-1-585_586-0.service: Deactivated successfully. machine # [ 29.562541] systemd[1]: Reached target First Boot Complete. machine # [ 29.568529] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 29.578474] systemd[1]: Starting Permit User Sessions... machine # [ 29.622939] systemd[1]: Finished Permit User Sessions. machine # [ 29.630373] systemd[1]: Started Getty on tty1. machine # [ 29.632998] systemd[1]: Reached target Login Prompts. machine # [ 29.640255] systemd[1]: Reached target Multi-User System. machine # [ 29.822381] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 29.828675] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 29.846227] systemd[1]: Startup finished in 1.168s (kernel) + 4.531s (initrd) + 24.144s (userspace) = 29.844s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.48 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 30.84 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 30.449893] systemd-homed[459]: test-homed-user: changing state inactive → updating machine # [ 30.459567] systemd-homework[933]: Offline update requested. Not touching embedded records. machine # [ 30.464223] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 30.469926] systemd-homed[459]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.07 seconds) subtest: login as homed user machine: waiting for login: to appear on tty 1 machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds) machine: sending keys 'test-homed-user\n' machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Password: to appear on tty 1 machine # [ 30.862615] systemd-homed[459]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 30.871176] systemd-homework[948]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 30.878746] systemd-homed[459]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 30.881983] systemd-homed[459]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 30.884456] systemd-homed[459]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 30.886173] systemd-homed[459]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: to appear on tty 1, in 1.15 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for success: pgrep -u test-homed-user -t tty1 bash machine # [ 32.027396] systemd-homed[459]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 32.083651] systemd-homework[961]: Provided password unlocks user record. machine # [ 32.089662] systemd-homework[961]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 32.098170] systemd-homework[961]: Allocated additional 652.8M. machine # [ 32.103533] systemd-homework[961]: Setting up loopback device /dev/loop1 completed.[ 32.119853] loop1: detected capacity change from 0 to 1519616 machine # machine # [ 32.949257] systemd-homework[961]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 32.991129] systemd-homework[961]: Provided password unlocks user record. machine # [ 32.997531] systemd-homework[961]: Probing file system completed (found btrfs). machine # [ 33.000268] systemd-homework[961]: No fsck for file system btrfs installed, ignoring. machine # [ 33.021600] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (961) machine # [ 33.027255] BTRFS info (device dm-0): first mount of filesystem c84feec6-7304-42fa-812c-340d4c015a52 machine # [ 33.027322] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 33.039072] BTRFS info (device dm-0): enabling free space tree machine # [ 33.039111] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 33.026003] systemd-homework[961]: Mounting file system completed. machine # [ 33.027083] systemd-homework[961]: Discovered used loopback device /dev/loop1. machine # [ 33.034098] systemd-homework[961]: offset = 1048576, size = 778043392, image = 780140544 machine # [ 33.045386] systemd-homework[961]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M. machine # [ 33.047413] systemd-homework[961]: Growing of image file from 744M to 897M completed. machine # [ 33.074464] loop1: detected capacity change from 1519616 to 1832960 machine # [ 33.062162] systemd-homework[961]: Refreshing loop device size completed. machine # [ 33.085280] systemd-homework[961]: Growing of partition completed. machine # [ 33.137275] async_tx: api initialized (async) machine # [ 33.180633] systemd-homework[961]: LUKS device growing completed. machine # [ 33.205976] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 921698304 machine # [ 33.192345] systemd-homework[961]: File system resizing from 726M to 879M completed. machine # [ 33.196408] systemd-homework[961]: Synchronized disk. machine # [ 33.197281] systemd-homework[961]: Synchronized disk. machine # [ 33.198196] systemd-homework[961]: Resizing completed. machine # [ 33.198969] systemd-homework[961]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 33.208393] systemd-homework[961]: Read embedded .identity file. machine # [ 33.228404] systemd-homework[961]: Provided password unlocks user record. machine # [ 33.229796] systemd-homework[961]: Reconciling header user identity completed (host version was newer). machine # [ 33.231997] systemd-homework[961]: Reconciling embedded user identity completed (host version was newer). machine # [ 33.248829] systemd-homework[961]: Wrote LUKS header user record. machine # [ 33.249982] systemd-homework[961]: Wrote embedded .identity file. machine # [ 33.250879] systemd-homework[961]: Replaced embedded blob dir with contents of system blob dir. machine # [ 33.253000] systemd-homework[961]: Recursive changing of ownership not necessary, skipped. machine # [ 33.258806] systemd-homework[961]: Synchronized disk. machine # [ 33.259713] systemd-homework[961]: Moving to final mount point /home/test-homed-user completed. machine # [ 33.286045] systemd-homework[961]: Activation completed. machine # [ 33.288776] systemd-homework[961]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 33.308346] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 33.317671] systemd-homed[459]: test-homed-user: changing state activating-for-acquire → active machine # [ 33.321552] login[924]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 33.329141] systemd-homed[459]: test-homed-user: changing state active → resizing-while-active machine # [ 33.339772] systemd-homework[1003]: Successfully acquired home volume key from kernel keyring. machine # [ 33.348369] systemd-homework[1003]: LUKS volume key from keyring unlocks user record. machine # [ 33.460261] systemd-homework[1003]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 33.465229] login[924]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 33.578852] systemd[1]: Created slice Slice /user/60388. machine # [ 33.586307] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 33.615677] systemd-logind[491]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 33.649296] systemd-homework[1003]: Successfully re-activated LUKS device. machine # [ 33.654587] systemd-homework[1003]: LUKS volume key from keyring unlocks user record. machine # [ 33.655870] systemd-homework[1003]: Discovered used loopback device /dev/loop1. machine # [ 33.659234] systemd-homework[1003]: Read embedded .identity file. machine # [ 33.660505] systemd-homework[1003]: LUKS volume key from keyring unlocks user record. machine # [ 33.661775] systemd-homework[1003]: Reconciling header user identity completed (host version was newer). machine # [ 33.663090] systemd-homework[1003]: Reconciling embedded user identity completed (host version was newer). machine # [ 33.668521] systemd-homework[1003]: offset = 1048576, size = 938475520, image = 940572672 machine # [ 33.678972] systemd-homework[1003]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M. machine # [ 33.682942] systemd-homework[1003]: Wrote embedded .identity file. machine # [ 33.686508] systemd-homework[1003]: Replaced embedded blob dir with contents of system blob dir. machine # [ 33.687823] systemd-homework[1003]: Backing file is fully allocated already. machine # [ 33.708261] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 783286272 machine # [ 33.698071] systemd-homework[1003]: File system resizing from 879M to 747M completed. machine # [ 33.699398] systemd-homework[1003]: Synchronized disk. machine # [ 33.716488] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 33.734177] systemd[1]: Starting User Manager for UID 60388... machine # [ 33.799200] systemd-homework[1003]: LUKS device shrinking completed. machine # [ 33.807704] (systemd)[1010]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 33.828456] loop1: detected capacity change from 1832960 to 1562624 machine # [ 33.813912] systemd-homework[1003]: Refreshing loop device size completed. machine # [ 33.822704] systemd-homework[1003]: Shrinking of image file completed. machine # [ 33.841671] systemd-homework[1003]: Shrinking of partition completed. machine # [ 33.879149] systemd-homework[1003]: Wrote LUKS header user record. machine # [ 33.888481] systemd-homework[1003]: Synchronized disk. machine # [ 33.889516] systemd-homework[1003]: Resizing completed. machine # [ 33.890345] systemd-homework[1003]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 33.906153] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 33.915441] systemd-homed[459]: test-homed-user: changing state resizing-while-active → active machine # [ 33.922466] systemd-homed[459]: Rebalancing complete. machine # [ 33.932812] (systemd)[1010]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 33.938925] systemd-logind[491]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 33.971511] systemd[1010]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.108611] systemd[1010]: Queued start job for default target Main User Target. machine # [ 34.118011] systemd[1010]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.123207] systemd[1010]: Created slice User Application Slice. machine # [ 34.127942] systemd[1010]: Started Daily Cleanup of User's Temporary Directories. machine # [ 34.136725] systemd[1010]: Reached target Paths. machine # [ 34.137577] systemd[1010]: Reached target Timers. machine # [ 34.138310] systemd[1010]: Starting D-Bus User Message Bus Socket... machine # [ 34.139275] systemd[1010]: Starting Create User Files and Directories... machine # [ 34.199918] systemd[1010]: Finished Create User Files and Directories. machine # [ 34.220231] systemd[1010]: Listening on D-Bus User Message Bus Socket. machine # [ 34.221614] systemd[1010]: Reached target Sockets. machine # [ 34.222381] systemd[1010]: Reached target Basic System. machine # [ 34.231166] systemd[1]: Started User Manager for UID 60388. machine # [ 34.236377] systemd[1010]: Starting Run user-specific NixOS activation... machine # [ 34.240604] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 34.292191] systemd[1010]: Finished Run user-specific NixOS activation. machine # [ 34.296312] systemd[1010]: Reached target Main User Target. machine # [ 34.297494] systemd[1010]: Startup finished in 337ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.31 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.03 seconds) (finished: subtest: login as homed user, in 6.03 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.06 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 # [ 38.072204] login[682]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 38.120665] systemd[1]: Created slice Slice /user/1000. sshClient # [ 38.125778] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 38.172563] systemd-logind[473]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 38.201969] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 38.208679] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 38.240891] (systemd)[791]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 38.249508] systemd-logind[473]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 38.279942] systemd[791]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 38.409846] systemd[791]: Queued start job for default target Main User Target. sshClient # [ 38.417192] systemd[791]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 38.421678] systemd[791]: Created slice User Application Slice. sshClient # [ 38.422780] systemd[791]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 38.423876] systemd[791]: Reached target Paths. sshClient # [ 38.424971] systemd[791]: Reached target Timers. sshClient # [ 38.427705] systemd[791]: Starting D-Bus User Message Bus Socket... sshClient # [ 38.430169] systemd[791]: Starting Create User Files and Directories... sshClient # [ 38.479985] systemd[791]: Finished Create User Files and Directories. sshClient # [ 38.500273] systemd[791]: Listening on D-Bus User Message Bus Socket. sshClient # [ 38.505419] systemd[791]: Reached target Sockets. sshClient # [ 38.506388] systemd[791]: Reached target Basic System. sshClient # [ 38.507218] systemd[1]: Started User Manager for UID 1000. sshClient # [ 38.510717] systemd[791]: Starting Run user-specific NixOS activation... sshClient # [ 38.516202] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 38.581252] systemd[791]: Finished Run user-specific NixOS activation. sshClient # [ 38.582600] systemd[791]: Reached target Main User Target. sshClient # [ 38.583445] systemd[791]: Startup finished in 314ms. 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.03 seconds) (finished: subtest: login as regular user, in 3.82 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 AAAAC3NzaC1lZDI1NTE5AAAAIAxNmMX70zsoSR57RHEMefz0Maruk4xNr3jzLsZplqGi test-normal-user@sshClient' machine # [ 41.952773] systemd-homed[459]: test-homed-user: changing state active → updating-while-active machine # [ 41.962490] systemd-homework[1061]: Offline update requested. Not touching embedded records. machine # [ 41.971957] systemd-homed[459]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAxNmMX70zsoSR57RHEMefz0Maruk4xNr3jzLsZplqGi test-normal-user@sshClient', in 0.07 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.03 seconds) (finished: subtest: add homed ssh authorized key, in 1.69 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.26 seconds) machine: waiting for New password: to appear on tty 1 machine: (finished: waiting for New password: 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 Retype new password: to appear on tty 1 machine: (finished: waiting for Retype new password: to appear on tty 1, in 1.11 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds) ??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead. File "/nix/store/0yd36fpwpnbc3yrryy7qi9hd645vflhd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 44.844665] systemd-homed[459]: test-homed-user: changing state active → passwd-while-active machine # [ 45.628140] systemd-homework[1099]: No valid password for LUKS superblock. machine # [ 47.862617] systemd-homed[459]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 47.866760] systemd-homed[459]: test-homed-user: changing state passwd-while-active → active machine: (finished: must succeed: sleep 4.0, in 4.04 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for file '/tmp/3' machine # [ 48.976677] systemd-homed[459]: test-homed-user: changing state active → passwd-while-active machine # [ 50.245786] systemd-homework[1105]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 50.373389] systemd-homework[1105]: Successfully re-activated LUKS device. machine # [ 50.430685] systemd-homework[1105]: Provided password unlocks user record. machine # [ 50.436123] systemd-homework[1105]: Discovered used loopback device /dev/loop1. machine # [ 50.437360] systemd-homework[1105]: Read embedded .identity file. machine # [ 50.490465] systemd-homework[1105]: Provided password unlocks user record. machine # [ 50.496157] systemd-homework[1105]: Reconciling header user identity completed (host version was newer). machine # [ 50.497646] systemd-homework[1105]: Reconciling embedded user identity completed (host version was newer). machine # [ 53.627291] systemd-homework[1105]: Added LUKS key slot 1. machine # [ 53.651911] systemd-homework[1105]: Destroyed LUKS key slot 0. machine # [ 53.663842] systemd-homework[1105]: Wrote LUKS header user record. machine # [ 53.666008] systemd-homework[1105]: Wrote embedded .identity file. machine # [ 53.668331] systemd-homework[1105]: Replaced embedded blob dir with contents of system blob dir. machine # [ 53.672611] systemd-homework[1105]: Synchronized disk. machine # [ 53.678512] systemd-homework[1105]: Everything completed. machine # [ 53.690600] passwd[1074]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine # [ 53.698745] systemd-homed[459]: test-homed-user: changing state passwd-while-active → active machine: (finished: waiting for file '/tmp/3', in 5.19 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 12.05 seconds) subtest: escalate to root from homed user machine: sending keys 'sudo id | tee /tmp/4\n' machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds) machine: waiting for password for test-homed-user to appear on tty 1 machine # [ 54.534516] systemd-homed[459]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 54.543444] systemd-homework[1142]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 54.551783] systemd-homed[459]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 54.555995] systemd-homed[459]: 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.10 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 # [ 55.580434] systemd-homed[459]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 55.628518] systemd-homework[1158]: Provided password unlocks user record. machine # [ 56.275054] systemd-homework[1158]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 56.404067] systemd-homework[1158]: Successfully re-activated LUKS device. machine # [ 56.434691] systemd-homework[1158]: Provided password unlocks user record. machine # [ 56.436674] systemd-homework[1158]: Discovered used loopback device /dev/loop1. machine # [ 56.440101] systemd-homework[1158]: Read embedded .identity file. machine # [ 56.466041] systemd-homework[1158]: Provided password unlocks user record. machine # [ 56.468280] systemd-homework[1158]: Reconciling user identities completed (host and header version were identical). machine # [ 56.470382] systemd-homework[1158]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 56.478392] systemd-homework[1158]: Everything completed. machine # [ 56.485751] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 56.494813] systemd-homed[459]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 56.497376] sudo[1140]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 56.503103] sudo[1140]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 56.518167] sudo[1140]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 56.528237] sudo[1140]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.06 seconds) (finished: subtest: escalate to root from homed user, in 2.52 seconds) subtest: log out and deactivate homed user's home area machine: sending keys 'exit\n' machine: (finished: sending keys 'exit\n', in 0.05 seconds) machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive' machine # [ 56.673923] login[924]: pam_unix(login:session): session closed for user test-homed-user machine # [ 56.691174] login[924]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 56.717413] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 56.718765] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 56.719905] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 56.745262] systemd[1]: Started Getty on tty1. machine # [ 56.746465] systemd-logind[491]: Session 1 logged out. Waiting for processes to exit. machine # [ 56.747693] systemd-logind[491]: Removed session 1. machine # [ 66.896682] systemd[1]: Stopping User Manager for UID 60388... machine # [ 66.905556] systemd[1010]: Activating special unit Exit the Session... machine # [ 66.912220] systemd[1010]: Stopped target Main User Target. machine # [ 66.913850] systemd[1010]: Stopped Run user-specific NixOS activation. machine # [ 66.915340] systemd[1010]: Stopped target Basic System. machine # [ 66.920543] systemd[1010]: Stopped target Paths. machine # [ 66.921860] systemd[1010]: Stopped target Sockets. machine # [ 66.922985] systemd[1010]: Stopped target Timers. machine # [ 66.924198] systemd[1010]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 66.925872] systemd[1010]: Closed D-Bus User Message Bus Socket. machine # [ 66.927208] systemd[1010]: Stopped Create User Files and Directories. machine # [ 66.928840] systemd[1010]: Removed slice User Application Slice. machine # [ 66.930281] systemd[1010]: Reached target Shutdown. machine # [ 66.931418] systemd[1010]: Finished Exit the Session. machine # [ 66.932861] systemd[1010]: Reached target Exit the Session. machine # [ 66.956777] systemd-homed[459]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 66.973821] systemd-logind[491]: Removed session 2. machine # [ 66.975163] systemd-homed[459]: test-homed-user: changing state active → deactivating machine # [ 66.979500] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 66.983319] systemd[1]: Stopped User Manager for UID 60388. machine # [ 66.988582] systemd-homework[1232]: Successfully acquired home volume key from kernel keyring. machine # [ 66.990483] systemd-homework[1232]: Discarded unused 674.3M. machine # [ 66.995583] systemd-homework[1232]: Syncing completed. machine # [ 67.042902] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 67.054321] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 67.092126] systemd-homework[1232]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 67.205382] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 67.215215] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 67.221774] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 67.262204] systemd-homework[1232]: Successfully re-activated LUKS device. machine # [ 67.263368] systemd-homework[1232]: Discovered used loopback device /dev/loop1. machine # [ 67.267763] systemd-homework[1232]: offset = 1048576, size = 800063488, image = 802160640 machine # [ 67.279629] systemd-homework[1232]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M. machine # [ 67.284467] systemd-homework[1232]: Allocated additional 674.3M. machine # [ 67.290647] systemd-homework[1232]: File system resizing from 747M to 256M completed.[ 67.307587] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 268435456 machine # machine # [ 67.299278] systemd-homework[1232]: Synchronized disk. machine # [ 67.428923] systemd-homework[1232]: LUKS device shrinking completed. machine # [ 67.458489] loop1: detected capacity change from 1562624 to 557056 machine # [ 67.446046] systemd-homework[1232]: Refreshing loop device size completed. machine # [ 67.447260] systemd-homework[1232]: Shrinking of image file completed. machine # [ 67.460469] systemd-homework[1232]: Shrinking of partition completed. machine # [ 67.462795] systemd-homework[1232]: Synchronized disk. machine # [ 67.480524] BTRFS info (device dm-0): last unmount of filesystem c84feec6-7304-42fa-812c-340d4c015a52 machine # [ 67.468628] systemd-homework[1232]: Resizing completed. machine # [ 67.469675] systemd-homework[1232]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 67.471412] systemd-homework[1232]: Unmounting completed. machine # [ 67.473350] systemd-homework[1232]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 67.474658] systemd-homework[1232]: Device home-test-homed-user is not active. machine # [ 67.513084] systemd-homed[459]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 67.517807] systemd-homework[1232]: Everything completed. machine # [ 67.535771] systemd-homed[459]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.73 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.79 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 # [ 70.190465] systemd-homed[459]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 70.199033] systemd-homework[1266]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 70.207037] systemd-homed[459]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 70.212465] systemd-homed[459]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 70.215517] sshd-session[1262]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 70.220885] systemd-homed[459]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 70.223590] sshd-session[1262]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 70.227027] systemd-homed[459]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 70.229349] sshd-session[1262]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 36038 ssh2: ED25519 SHA256:zD6owfigmJuVr9sTJohdLFU7PV/Q3PBHXLz5owGLiQs machine # [ 70.278086] sshd-session[1262]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 70.292737] systemd-logind[491]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 70.299730] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 70.334348] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 70.342167] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 70.360591] sshd-session[1262]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 70.365312] sshd-session[1262]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 70.387153] systemd-homed[459]: test-homed-user: changing state inactive → activating machine # [ 70.394830] systemd-homework[1272]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 70.402127] systemd-homed[459]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 70.404818] systemd-homed[459]: 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 # [ 71.504142] systemd-homed[459]: test-homed-user: changing state inactive → activating machine # [ 71.559327] systemd-homework[1278]: Provided password unlocks user record. machine # [ 71.565374] systemd-homework[1278]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 71.570901] systemd-homework[1278]: Backing file is fully allocated already. machine # [ 71.575118] systemd-homework[1278]: Setting up loopback device /dev/loop1 completed.[ 71.592448] loop1: detected capacity change from 0 to 557056 machine # machine # [ 72.448122] systemd-homework[1278]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 72.498484] systemd-homework[1278]: Provided password unlocks user record. machine # [ 72.510251] systemd-homework[1278]: Probing file system completed (found btrfs). machine # [ 72.512139] systemd-homework[1278]: No fsck for file system btrfs installed, ignoring. machine # [ 72.534339] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1278) machine # [ 72.539261] BTRFS info (device dm-0): first mount of filesystem c84feec6-7304-42fa-812c-340d4c015a52 machine # [ 72.539324] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 72.530523] systemd-homework[1278]: Mounting file system completed. machine # [ 72.548304] BTRFS info (device dm-0): enabling free space tree machine # [ 72.548331] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 72.538571] systemd-homework[1278]: Discovered used loopback device /dev/loop1. machine # [ 72.539700] systemd-homework[1278]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 72.554582] systemd-homework[1278]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M. machine # [ 72.556895] systemd-homework[1278]: Growing of image file from 274M to 765M completed. machine # [ 72.582536] loop1: detected capacity change from 557056 to 1562624 machine # [ 72.569746] systemd-homework[1278]: Refreshing loop device size completed. machine # [ 72.581236] systemd-homework[1278]: Growing of partition completed. machine # [ 72.627613] systemd-homework[1278]: LUKS device growing completed. machine # [ 72.634262] systemd-homework[1278]: File system resizing from 256M to 747M completed. machine # [ 72.635508] systemd-homework[1278]: Synchronized disk.[ 72.651504] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 783286272 machine # machine # [ 72.639401] systemd-homework[1278]: Synchronized disk. machine # [ 72.644265] systemd-homework[1278]: Resizing completed. machine # [ 72.645164] systemd-homework[1278]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 72.646873] systemd-homework[1278]: Read embedded .identity file. machine # [ 72.669259] systemd-homework[1278]: Provided password unlocks user record. machine # [ 72.674080] systemd-homework[1278]: Reconciling user identities completed (host and header version were identical). machine # [ 72.675706] systemd-homework[1278]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 72.681469] systemd-homework[1278]: Recursive changing of ownership not necessary, skipped. machine # [ 72.682769] systemd-homework[1278]: Synchronized disk. machine # [ 72.684155] systemd-homework[1278]: Moving to final mount point /home/test-homed-user completed. machine # [ 72.705334] systemd-homework[1278]: Activation completed. machine # [ 72.706403] systemd-homework[1278]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 72.721847] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 72.726775] systemd-homed[459]: test-homed-user: changing state activating → active machine # [ 72.747198] systemd[1]: Starting User Manager for UID 60388... machine # [ 72.790904] (systemd)[1318]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 72.830963] (systemd)[1318]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 72.841102] systemd-logind[491]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 72.871299] systemd[1318]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.981032] systemd[1318]: Queued start job for default target Main User Target. machine # [ 72.985087] systemd[1318]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.989388] systemd[1318]: Created slice User Application Slice. machine # [ 72.990441] systemd[1318]: Started Daily Cleanup of User's Temporary Directories. machine # [ 72.991473] systemd[1318]: Reached target Paths. machine # [ 72.994127] systemd[1318]: Reached target Timers. machine # [ 72.994899] systemd[1318]: Starting D-Bus User Message Bus Socket... machine # [ 72.997348] systemd[1318]: Starting Create User Files and Directories... machine # [ 73.041753] systemd[1318]: Finished Create User Files and Directories. machine # [ 73.044054] systemd[1318]: Listening on D-Bus User Message Bus Socket. machine # [ 73.049275] systemd[1318]: Reached target Sockets. machine # [ 73.050162] systemd[1318]: Reached target Basic System. machine # [ 73.050935] systemd[1]: Started User Manager for UID 60388. machine # [ 73.056319] systemd[1318]: Starting Run user-specific NixOS activation... machine # [ 73.108385] systemd[1318]: Finished Run user-specific NixOS activation. machine # [ 73.112661] systemd[1318]: Reached target Main User Target. machine # [ 73.113721] systemd[1318]: Startup finished in 252ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.20 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' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.60 seconds) (finished: run the VM test script, in 75.96 seconds) machine # [ 74.964835] sshd-session[1269]: Received disconnect from 2001:db8:1::2 port 36038:11: disconnected by user machine # [ 74.968702] sshd-session[1269]: Disconnected from user test-homed-user 2001:db8:1::2 port 36038 machine # [ 74.983696] sshd-session[1262]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 74.994451] sshd-session[1262]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 75.008909] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 75.011054] systemd-logind[491]: Session 3 logged out. Waiting for processes to exit. machine # [ 75.015839] systemd-logind[491]: Removed session 3. test script finished in 76.62s cleanup kill QemuMachine (pid 47) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14) machine # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14) sshClient # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.34 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