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.09 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm sshClient # Disk image does not exist, creating the virtualisation disk image... sshClient: QEMU running (pid 48) sshClient # Formatting '/build/vm-state-sshClient/tmp.yM2oppDkZ2', fmt=raw size=1073741824 sshClient # mke2fs 1.47.4 (6-Mar-2025) sshClient # Discarding device blocks: 0/262144 done sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes sshClient # Filesystem UUID: a3bd74eb-1caa-4f31-98e8-ebce3a7cff78 sshClient # Superblock backups stored on blocks: sshClient # 32768, 98304, 163840, 229376 sshClient # sshClient # Allocating group tables: 0/8 done sshClient # Writing inode tables: 0/8 done sshClient # Creating journal (8192 blocks): done sshClient # Writing superblocks and filesystem accounting information: 0/8 done sshClient # sshClient # Virtualisation disk image created. sshClient # Starting virtiofs daemons... sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.cq5iUQ6xrj', fmt=raw size=1073741824 machine: QEMU running (pid 47) machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: a6ea0050-3b8b-40a7-b5e4-d8af0a783265 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 (finished: start all VMs, in 2.39 seconds) machine # machine # Allocating group tables: 0/8 done subtest: create systemd-homed user on first boot prompt machine # Writing inode tables: 0/8 done machine: waiting for unit systemd-homed.service machine # Creating journal (8192 blocks): done machine: waiting for the VM to finish booting machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests 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 machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] 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 sshClient # [ 0.000000] KASLR enabled sshClient # [ 0.000000] random: crng init done 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/g3z59v7k5qdjgb547p7wxg0gzwvkcvn8-nixos-system-machine-test/init regInfo=/nix/.ro-store/fcf77dspcc5w4hzhklj1a9zjb4lwqj26-closure-info/registration console=ttyAMA0,115200n8 console=tty0 sshClient # [ 0.000000] Machine model: linux,dummy-virt sshClient # [ 0.000000] efi: UEFI not found. sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/fcf77dspcc5w4hzhklj1a9zjb4lwqj26-closure-info/registration", will be passed to user space. sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] sshClient # [ 0.000000] Zone ranges: machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] DMA32 empty machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.000000] Normal empty sshClient # [ 0.000000] Device empty machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB sshClient # [ 0.000000] Movable zone start for each node machine # [ 0.000000] software IO TLB: area num 1. sshClient # [ 0.000000] Early memory node ranges machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Fallback order for Node 0: 0 sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] Policy zone: DMA sshClient # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] allocated 2097152 bytes of page_ext sshClient # [ 0.000000] psci: Trusted OS migration not required sshClient # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] ftrace: allocating 75301 entries in 296 pages machine # [ 0.000000] ftrace: allocated 296 pages with 3 groups sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296 sshClient # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] Rude variant of Tasks RCU enabled. sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] CPU features: detected: Spectre-v4 sshClient # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 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] alternatives: applying boot alternatives machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented 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/5fy3hcm4l7bcy4gn68s4mcazymyrkqa6-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/422maq87rahdpjh07nks4g9570jp2l9q-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/422maq87rahdpjh07nks4g9570jp2l9q-closure-info/registration", will be passed to user space. machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000 sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000 sshClient # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 sshClient # [ 0.000000] Policy zone: DMA machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] ftrace: allocating 75301 entries in 296 pages sshClient # [ 0.000000] ftrace: allocated 296 pages with 3 groups machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns sshClient # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000035] arm-pv: using stolen time PV sshClient # [ 0.000000] rcu: RCU event tracing is enabled. sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. machine # [ 0.000462] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000605] Console: colour dummy device 80x25 sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000614] printk: legacy console [tty0] enabled sshClient # [ 0.000000] Rude variant of Tasks RCU enabled. sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000816] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000823] pid_max: default: 32768 minimum: 301 sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000917] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima machine # [ 0.001050] landlock: Up and running. 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.001053] Yama: becoming mindful. machine # [ 0.001559] LSM support for eBPF active 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.002387] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) 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.002409] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.004865] rcu: Hierarchical SRCU implementation. sshClient # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.004872] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.006222] fsl-mc MSI: its@8080000 domain created sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.006361] EFI services will not be available. sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.006462] smp: Bringing up secondary CPUs ... sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.006473] smp: Brought up 1 node, 1 CPU sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.006477] SMP: Total of 1 processors activated. sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.006483] CPU: All CPU(s) started at EL1 machine # [ 0.006493] CPU features: detected: Branch Target Identification sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450e0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.006497] CPU features: detected: ARMv8.4 Translation Table Level sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @450f0000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.006500] CPU features: detected: Instruction cache invalidation not required for I/D coherence sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000045110000 machine # [ 0.006504] CPU features: detected: Data cache clean to the PoU not required for I/D coherence sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000 machine # [ 0.006511] CPU features: detected: Common not Private translations sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.006514] CPU features: detected: CRC32 instructions sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.006517] CPU features: detected: Data cache clean to Point of Deep Persistence sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.006521] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.006524] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.006527] CPU features: detected: E0PD machine # [ 0.006529] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns sshClient # [ 0.000032] arm-pv: using stolen time PV machine # [ 0.006532] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.006536] CPU features: detected: Enhanced Virtualization Traps sshClient # [ 0.000398] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.006539] CPU features: detected: Fine Grained Traps sshClient # [ 0.000589] Console: colour dummy device 80x25 sshClient # [ 0.000599] printk: legacy console [tty0] enabled machine # [ 0.006542] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.006547] CPU features: detected: RCpc load-acquire (LDAPR) sshClient # [ 0.000787] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.006550] CPU features: detected: LSE atomic instructions sshClient # [ 0.000795] pid_max: default: 32768 minimum: 301 machine # [ 0.006553] CPU features: detected: Privileged Access Never sshClient # [ 0.000902] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima machine # [ 0.006555] CPU features: detected: PMUv3 sshClient # [ 0.001032] landlock: Up and running. machine # [ 0.006558] CPU features: detected: RAS Extension Support sshClient # [ 0.001035] Yama: becoming mindful. sshClient # [ 0.001497] LSM support for eBPF active machine # [ 0.006561] CPU features: detected: RASv1p1 Extension Support machine # [ 0.006563] CPU features: detected: Random Number Generator sshClient # [ 0.002616] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.006571] CPU features: detected: Speculation barrier (SB) sshClient # [ 0.002637] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.006574] CPU features: detected: Stage-2 Force Write-Back sshClient # [ 0.005212] rcu: Hierarchical SRCU implementation. machine # [ 0.006576] CPU features: detected: TLB range maintenance instructions sshClient # [ 0.005220] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.006491] fsl-mc MSI: its@8080000 domain created machine # [ 0.006581] CPU features: detected: Speculative Store Bypassing Safe (SSBS) sshClient # [ 0.006611] EFI services will not be available. machine # [ 0.006621] alternatives: applying system-wide alternatives sshClient # [ 0.006689] smp: Bringing up secondary CPUs ... sshClient # [ 0.006700] smp: Brought up 1 node, 1 CPU machine # [ 0.009747] CPU features: detected: BBM Level 2 without TLB conflict abort sshClient # [ 0.006703] SMP: Total of 1 processors activated. sshClient # [ 0.006706] CPU: All CPU(s) started at EL1 sshClient # [ 0.006718] CPU features: detected: Branch Target Identification machine # [ 0.010126] Memory: 886720K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120596K reserved, 32768K cma-reserved) machine # [ 0.010557] devtmpfs: initialized sshClient # [ 0.006722] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.012485] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.006726] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.012510] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.006729] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.012705] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL sshClient # [ 0.006737] CPU features: detected: Common not Private translations machine # [ 0.012710] 0 pages in range for non-PLT usage machine # [ 0.012712] 506368 pages in range for PLT usage sshClient # [ 0.006740] CPU features: detected: CRC32 instructions machine # [ 0.012836] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.006743] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.013726] DMI not present or invalid. sshClient # [ 0.006746] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.017048] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.006750] CPU features: detected: Data independent timing control (DIT) machine # [ 0.021126] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.006753] CPU features: detected: E0PD machine # [ 0.021306] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.006755] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.006758] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.021474] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.021501] audit: initializing netlink subsys (disabled) sshClient # [ 0.006762] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.022578] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.006765] CPU features: detected: Fine Grained Traps machine # [ 0.022581] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.006769] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.022585] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.006774] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.022588] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.006777] CPU features: detected: LSE atomic instructions machine # [ 0.022590] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.006780] CPU features: detected: Privileged Access Never sshClient # [ 0.006782] CPU features: detected: PMUv3 machine # [ 0.022631] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.006785] CPU features: detected: RAS Extension Support machine # [ 0.022640] cpuidle: using governor ladder machine # [ 0.022647] cpuidle: using governor menu sshClient # [ 0.006788] CPU features: detected: RASv1p1 Extension Support machine # [ 0.022852] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. sshClient # [ 0.006791] CPU features: detected: Random Number Generator machine # [ 0.022873] ASID allocator initialised with 65536 entries sshClient # [ 0.006796] CPU features: detected: Speculation barrier (SB) sshClient # [ 0.006799] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.024143] Serial: AMBA PL011 UART driver sshClient # [ 0.006802] CPU features: detected: TLB range maintenance instructions machine # [ 0.029883] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.030173] printk: console [ttyAMA0] enabled sshClient # [ 0.006806] CPU features: detected: Speculative Store Bypassing Safe (SSBS) sshClient # [ 0.006844] alternatives: applying system-wide alternatives sshClient # [ 0.009871] CPU features: detected: BBM Level 2 without TLB conflict abort sshClient # [ 0.010081] Memory: 886680K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved) sshClient # [ 0.010457] devtmpfs: initialized sshClient # [ 0.012288] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.012312] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.012507] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL sshClient # [ 0.012512] 0 pages in range for non-PLT usage sshClient # [ 0.012513] 506368 pages in range for PLT usage sshClient # [ 0.012616] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.013613] DMI not present or invalid. sshClient # [ 0.016997] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.020145] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.020295] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.160256] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages sshClient # [ 0.020453] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.160295] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page sshClient # [ 0.020477] audit: initializing netlink subsys (disabled) machine # [ 0.160299] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages sshClient # [ 0.021786] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.160309] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page sshClient # [ 0.021789] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.160313] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.021793] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.160316] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.021796] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.160319] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages sshClient # [ 0.021799] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.160321] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page sshClient # [ 0.021830] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.161590] fbcon: Taking over console sshClient # [ 0.021839] cpuidle: using governor ladder machine # [ 0.161601] ACPI: Interpreter disabled. sshClient # [ 0.021846] cpuidle: using governor menu machine # [ 0.171635] iommu: Default domain type: Translated sshClient # [ 0.022073] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.171641] iommu: DMA domain TLB invalidation policy: strict mode sshClient # [ 0.022093] ASID allocator initialised with 65536 entries machine # [ 0.172062] SCSI subsystem initialized sshClient # [ 0.023403] Serial: AMBA PL011 UART driver sshClient # [ 0.028978] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.029125] printk: console [ttyAMA0] enabled machine # [ 0.174188] usbcore: registered new interface driver usbfs machine # [ 0.174218] usbcore: registered new interface driver hub machine # [ 0.174233] usbcore: registered new device driver usb machine # [ 0.174987] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.174992] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.175001] PTP clock support registered machine # [ 0.175050] EDAC MC: Ver: 3.0.0 machine # [ 0.181017] scmi_core: SCMI protocol bus registered machine # [ 0.184685] FPGA manager framework machine # [ 0.186422] vgaarb: loaded machine # [ 0.186737] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.193709] VFS: Disk quotas dquot_6.6.0 machine # [ 0.193745] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.159393] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages sshClient # [ 0.159407] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page sshClient # [ 0.159411] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages sshClient # [ 0.159413] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.196442] netfs: FS-Cache loaded sshClient # [ 0.159416] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.196571] pnp: PnP ACPI: disabled sshClient # [ 0.159419] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.159422] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages sshClient # [ 0.159424] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page sshClient # [ 0.160765] fbcon: Taking over console sshClient # [ 0.160779] ACPI: Interpreter disabled. sshClient # [ 0.178692] iommu: Default domain type: Translated machine # [ 0.203274] NET: Registered PF_INET protocol family sshClient # [ 0.178708] iommu: DMA domain TLB invalidation policy: strict mode sshClient # [ 0.180026] SCSI subsystem initialized machine # [ 0.203433] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.183396] usbcore: registered new interface driver usbfs sshClient # [ 0.183426] usbcore: registered new interface driver hub sshClient # [ 0.183441] usbcore: registered new device driver usb sshClient # [ 0.183784] pps_core: LinuxPPS API ver. 1 registered sshClient # [ 0.183788] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.183797] PTP clock support registered sshClient # [ 0.183834] EDAC MC: Ver: 3.0.0 sshClient # [ 0.188723] scmi_core: SCMI protocol bus registered sshClient # [ 0.189123] FPGA manager framework sshClient # [ 0.189723] vgaarb: loaded sshClient # [ 0.189984] clocksource: Switched to clocksource arch_sys_counter sshClient # [ 0.191441] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.191472] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.197470] netfs: FS-Cache loaded sshClient # [ 0.197609] pnp: PnP ACPI: disabled sshClient # [ 0.203673] NET: Registered PF_INET protocol family sshClient # [ 0.203847] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.235766] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.235813] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.235839] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.235886] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.235959] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.236049] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.237296] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.237322] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.237405] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.237451] NET: Registered PF_XDP protocol family machine # [ 0.237468] PCI: CLS 0 bytes, default 64 machine # [ 0.237701] Trying to unpack rootfs image as initramfs... machine # [ 0.255688] kvm [1]: HYP mode not available sshClient # [ 0.237631] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.237677] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.237704] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.237750] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.237823] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.237923] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) sshClient # [ 0.237983] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.244737] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.244834] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.244887] NET: Registered PF_XDP protocol family sshClient # [ 0.244905] PCI: CLS 0 bytes, default 64 sshClient # [ 0.245167] Trying to unpack rootfs image as initramfs... sshClient # [ 0.254016] kvm [1]: HYP mode not available machine # [ 0.383087] Initialise system trusted keyrings machine # [ 0.383126] Key type blacklist registered machine # [ 0.383256] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.383647] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.383709] 9p: Installing v9fs 9p2000 file system support machine # [ 0.383811] integrity: Platform Keyring initialized machine # [ 0.383820] integrity: Machine keyring initialized sshClient # [ 0.378691] Initialise system trusted keyrings sshClient # [ 0.378730] Key type blacklist registered sshClient # [ 0.378958] workingset: timestamp_bits=42 max_order=18 bucket_order=0 sshClient # [ 0.379418] squashfs: version 4.0 (2009/01/31) Phillip Lougher sshClient # [ 0.379472] 9p: Installing v9fs 9p2000 file system support sshClient # [ 0.379572] integrity: Platform Keyring initialized sshClient # [ 0.379581] integrity: Machine keyring initialized machine # [ 0.415780] Key type asymmetric registered machine # [ 0.415799] Asymmetric key parser 'x509' registered machine # [ 0.415881] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.416013] io scheduler mq-deadline registered machine # [ 0.416018] io scheduler kyber registered sshClient # [ 0.404365] Key type asymmetric registered sshClient # [ 0.404382] Asymmetric key parser 'x509' registered sshClient # [ 0.404499] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.430943] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.431660] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.432061] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.432078] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.432115] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.432123] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.432142] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.432169] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.432260] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 sshClient # [ 0.414197] io scheduler mq-deadline registered sshClient # [ 0.414212] io scheduler kyber registered machine # [ 0.432268] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.432272] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.432275] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.432279] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] sshClient # [ 0.419568] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.432372] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.420147] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.432827] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.420511] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.433017] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.433029] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.420526] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.433056] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.420539] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.433068] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.420546] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.433533] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.433718] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.420564] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.433729] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.420584] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.433756] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.420656] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.434231] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.420663] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.434417] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] sshClient # [ 0.420667] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.434429] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.420670] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.434455] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.420673] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] sshClient # [ 0.420734] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.421217] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.421409] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.421421] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.459566] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.421447] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.459758] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.421459] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.459769] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.421935] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.459796] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.459809] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.460274] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.460465] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.460491] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.460951] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.461142] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.446179] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.446201] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.446228] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.446758] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.446950] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.461169] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.446961] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.446987] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.461566] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.461754] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] sshClient # [ 0.447478] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.447669] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.462030] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.447681] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.462223] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.447707] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.462249] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.447719] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.462711] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.448205] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint sshClient # [ 0.448395] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.485497] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.448421] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.485535] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.448898] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.449090] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.486590] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.449116] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.449532] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.489508] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.449717] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.489537] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.449978] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.490055] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.490329] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.490345] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.490372] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.495787] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.495970] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.495982] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.496008] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.496640] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.496648] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned sshClient # [ 0.474254] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.474297] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.496651] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned sshClient # [ 0.474831] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.496696] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned sshClient # [ 0.475032] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.496741] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned sshClient # [ 0.475058] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.496786] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned sshClient # [ 0.475526] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.496831] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned sshClient # [ 0.475716] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.475742] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.496879] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned sshClient # [ 0.476217] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.496928] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned sshClient # [ 0.476533] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.496972] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned sshClient # [ 0.476551] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.497015] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned sshClient # [ 0.476577] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.497059] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned sshClient # [ 0.477067] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.477256] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.497138] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned sshClient # [ 0.477268] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.497182] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned sshClient # [ 0.477294] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.497200] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned sshClient # [ 0.477915] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.497218] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned sshClient # [ 0.477922] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.497236] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.497254] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.477926] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.497273] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.477972] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.497292] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.497312] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.497330] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.497349] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.495619] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.497368] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.495665] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.497387] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.495713] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.497405] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned sshClient # [ 0.495764] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.497422] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.497440] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned sshClient # [ 0.495809] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.497458] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned sshClient # [ 0.495854] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.497476] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned sshClient # [ 0.495897] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.497493] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned sshClient # [ 0.495942] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.497516] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.497521] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] sshClient # [ 0.496032] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.497525] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] sshClient # [ 0.496079] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.498470] pci 0000:00:07.0: enabling device (0000 -> 0002) sshClient # [ 0.496098] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned sshClient # [ 0.496115] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned sshClient # [ 0.496133] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned sshClient # [ 0.496151] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.496169] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.496187] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned sshClient # [ 0.496207] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned sshClient # [ 0.496225] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned sshClient # [ 0.496244] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.496263] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.496281] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.496299] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.538871] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 39457 usecs sshClient # [ 0.496317] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned sshClient # [ 0.496334] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned sshClient # [ 0.496352] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned sshClient # [ 0.496370] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned sshClient # [ 0.496388] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned sshClient # [ 0.496413] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] sshClient # [ 0.496419] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] sshClient # [ 0.496422] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] sshClient # [ 0.497361] pci 0000:00:07.0: enabling device (0000 -> 0002) sshClient # [ 0.530110] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 31985 usecs sshClient # [ 0.558968] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.569032] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) sshClient # [ 0.582933] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.632786] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) sshClient # [ 0.607599] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.621406] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) sshClient # [ 0.623555] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) sshClient # [ 0.670297] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) sshClient # [ 0.672328] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) sshClient # [ 0.675160] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) sshClient # [ 0.707577] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.643674] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.646184] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.784403] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.787467] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.789348] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.807409] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.809299] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.819409] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) sshClient # [ 0.710875] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) sshClient # [ 0.713541] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.832265] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) sshClient # [ 0.855825] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.857873] msm_serial: driver initialized sshClient # [ 0.866244] SuperH (H)SCI(F) driver initialized sshClient # [ 0.866315] STM32 USART driver initialized machine # [ 0.916748] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.918799] msm_serial: driver initialized machine # [ 0.918989] SuperH (H)SCI(F) driver initialized machine # [ 0.919053] STM32 USART driver initialized sshClient # [ 0.909670] loop: module loaded sshClient # [ 0.909883] virtio_blk virtio2: 1/0/0 default/read/poll queues sshClient # [ 0.912154] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.968899] loop: module loaded machine # [ 0.969112] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.970182] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 0.942954] megasas: 07.734.00.00-rc1 sshClient # [ 0.945448] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] sshClient # [ 0.981284] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.981328] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.986377] Using buffer write method machine # [ 1.000827] megasas: 07.734.00.00-rc1 sshClient # [ 0.986458] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 1.001749] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 1.005926] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 1.005951] Intel/Sharp Extended Query Table at 0x0031 machine # [ 1.007885] Using buffer write method machine # [ 1.007933] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 1.023576] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 1.023614] Intel/Sharp Extended Query Table at 0x0031 machine # [ 1.025202] Using buffer write method machine # [ 1.025224] Concatenating MTD devices: machine # [ 1.025226] (0): "0.flash" machine # [ 1.025229] (1): "0.flash" machine # [ 1.025231] into device "0.flash" sshClient # [ 1.007000] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 1.007034] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 1.009029] Using buffer write method sshClient # [ 1.009058] Concatenating MTD devices: sshClient # [ 1.009061] (0): "0.flash" sshClient # [ 1.009064] (1): "0.flash" sshClient # [ 1.009066] into device "0.flash" machine # [ 1.266324] Freeing initrd memory: 26056K machine # [ 1.272965] tun: Universal TUN/TAP device driver, 1.6 sshClient # [ 1.248525] Freeing initrd memory: 26060K machine # [ 1.277235] thunder_xcv, ver 1.0 machine # [ 1.277266] thunder_bgx, ver 1.0 machine # [ 1.277292] nicpf, ver 1.0 machine # [ 1.277906] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.277911] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.277939] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 1.277945] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 1.278070] igb: Intel(R) Gigabit Ethernet Network Driver sshClient # [ 1.255227] tun: Universal TUN/TAP device driver, 1.6 machine # [ 1.278074] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 1.278096] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 1.278101] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.278242] sky2: driver version 1.30 sshClient # [ 1.259587] thunder_xcv, ver 1.0 sshClient # [ 1.259620] thunder_bgx, ver 1.0 sshClient # [ 1.259639] nicpf, ver 1.0 sshClient # [ 1.260253] e1000: Intel(R) PRO/1000 Network Driver sshClient # [ 1.260258] e1000: Copyright (c) 1999-2006 Intel Corporation. sshClient # [ 1.260284] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 1.287542] usbcore: registered new interface driver usb-storage sshClient # [ 1.260291] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 1.287641] usbcore: registered new interface driver usbserial_generic sshClient # [ 1.260315] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 1.287653] usbserial: USB Serial support registered for generic sshClient # [ 1.260319] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 1.288287] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.289640] ehci-pci 0000:00:07.0: EHCI Host Controller sshClient # [ 1.260339] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 1.260343] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.289685] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 sshClient # [ 1.260486] sky2: driver version 1.30 machine # [ 1.289896] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 sshClient # [ 1.262736] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 1.290306] rtc-pl031 9010000.pl031: registered as rtc0 sshClient # [ 1.262762] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 1.290334] rtc-pl031 9010000.pl031: setting system clock to 2026-10-23T03:05:56 UTC (1792724756) sshClient # [ 1.262978] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.290700] i2c_dev: i2c /dev entries driver sshClient # [ 1.273698] usbcore: registered new interface driver usb-storage sshClient # [ 1.273911] usbcore: registered new interface driver usbserial_generic sshClient # [ 1.273923] usbserial: USB Serial support registered for generic machine # [ 1.299516] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 sshClient # [ 1.276460] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 1.299823] hub 1-0:1.0: USB hub found sshClient # [ 1.276960] hub 1-0:1.0: USB hub found machine # [ 1.299840] hub 1-0:1.0: 6 ports detected sshClient # [ 1.276981] hub 1-0:1.0: 6 ports detected machine # [ 1.304632] sdhci: Secure Digital Host Controller Interface driver sshClient # [ 1.279861] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.304642] sdhci: Copyright(c) Pierre Ossman sshClient # [ 1.280890] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.304933] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.305322] sdhci-pltfm: SDHCI platform and OF driver helper sshClient # [ 1.280923] rtc-pl031 9010000.pl031: setting system clock to 2026-10-23T03:05:56 UTC (1792724756) sshClient # [ 1.281274] i2c_dev: i2c /dev entries driver machine # [ 1.309916] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.310256] usbcore: registered new interface driver usbhid machine # [ 1.310260] usbhid: USB HID core driver machine # [ 1.313373] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available sshClient # [ 1.288118] sdhci: Secure Digital Host Controller Interface driver sshClient # [ 1.288126] sdhci: Copyright(c) Pierre Ossman sshClient # [ 1.288428] Synopsys Designware Multimedia Card Interface Driver sshClient # [ 1.288861] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.316211] drop_monitor: Initializing network drop monitor service sshClient # [ 1.293652] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.316380] NET: Registered PF_INET6 protocol family sshClient # [ 1.293897] usbcore: registered new interface driver usbhid machine # [ 1.317030] Segment Routing with IPv6 sshClient # [ 1.293901] usbhid: USB HID core driver machine # [ 1.317047] In-situ OAM (IOAM) with IPv6 machine # [ 1.317072] NET: Registered PF_PACKET protocol family machine # [ 1.317128] 9pnet: Installing 9P2000 support machine # [ 1.317166] Key type dns_resolver registered sshClient # [ 1.297148] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available sshClient # [ 1.300313] drop_monitor: Initializing network drop monitor service sshClient # [ 1.300502] NET: Registered PF_INET6 protocol family sshClient # [ 1.301199] Segment Routing with IPv6 sshClient # [ 1.301214] In-situ OAM (IOAM) with IPv6 sshClient # [ 1.301243] NET: Registered PF_PACKET protocol family sshClient # [ 1.301307] 9pnet: Installing 9P2000 support sshClient # [ 1.301354] Key type dns_resolver registered machine # [ 1.327812] registered taskstats version 1 machine # [ 1.327979] Loading compiled-in X.509 certificates machine # [ 1.337054] Demotion targets for Node 0: null sshClient # [ 1.311846] registered taskstats version 1 machine # [ 1.337185] Key type .fscrypt registered sshClient # [ 1.312006] Loading compiled-in X.509 certificates machine # [ 1.337189] Key type fscrypt-provisioning registered machine # [ 1.337289] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.337312] ima: Allocated hash algorithm: sha1 machine # [ 1.337334] ima: No architecture policies found machine # [ 1.338119] input: gpio-keys as /devices/platform/gpio-keys/input/input0 sshClient # [ 1.321103] Demotion targets for Node 0: null sshClient # [ 1.321231] Key type .fscrypt registered sshClient # [ 1.321235] Key type fscrypt-provisioning registered sshClient # [ 1.321352] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 1.321376] ima: Allocated hash algorithm: sha1 sshClient # [ 1.321398] ima: No architecture policies found sshClient # [ 1.326111] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.361364] clk: Disabling unused clocks machine # [ 1.361387] PM: genpd: Disabling unused power domains machine # [ 1.367549] Freeing unused kernel memory: 7808K machine # [ 1.367760] Run /init as init process sshClient # [ 1.346249] clk: Disabling unused clocks sshClient # [ 1.346271] PM: genpd: Disabling unused power domains sshClient # [ 1.352502] Freeing unused kernel memory: 7808K sshClient # [ 1.352701] Run /init as init process machine # [ 1.385553] fuse: module verification failed: signature and/or required key missing - tainting kernel machine # [ 1.389562] fuse: init (API version 7.45) machine # [ 1.393277] virtiofs virtio6: discovered new tag: nix-store machine # [ 1.394110] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 1.371040] fuse: module verification failed: signature and/or required key missing - tainting kernel sshClient # [ 1.375410] fuse: init (API version 7.45) sshClient # [ 1.379210] virtiofs virtio6: discovered new tag: nix-store sshClient # [ 1.380049] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.408408] virtiofs virtio7: discovered new tag: shared machine # [ 1.409167] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.410434] virtiofs virtio8: discovered new tag: xchg machine # [ 1.413324] virtiofs virtio8: virtio_fs_setup_dax: No cache capability sshClient # [ 1.395658] virtiofs virtio7: discovered new tag: shared sshClient # [ 1.396468] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.425457] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.400465] virtiofs virtio8: discovered new tag: xchg sshClient # [ 1.401319] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 1.425954] systemd[1]: systemd 263-c676df8 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.425969] systemd[1]: Detected virtualization qemu. machine # [ 1.426075] systemd[1]: Detected architecture arm64. machine # [ 1.426081] systemd[1]: Running in initrd. machine # [ 1.426410] systemd[1]: Initializing machine ID from random generator. machine # [ 1.426508] systemd[1]: Hostname set to . sshClient # [ 1.416829] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.417523] systemd[1]: systemd 263-c676df8 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 # [ 1.417539] systemd[1]: Detected virtualization qemu. sshClient # [ 1.417563] systemd[1]: Detected architecture arm64. sshClient # [ 1.417569] systemd[1]: Running in initrd. sshClient # [ 1.417891] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.425655] systemd[1]: Hostname set to . machine # [ 1.528038] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.520037] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.526143] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.554812] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.588432] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.594123] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.594327] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.594355] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.594377] systemd[1]: Reached target Path Units. machine # [ 1.594391] systemd[1]: Reached target Slice Units. machine # [ 1.594403] systemd[1]: Reached target Swaps. machine # [ 1.594417] systemd[1]: Reached target Timer Units. machine # [ 1.594554] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.594722] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.601474] systemd[1]: Listening on Journal Sockets. machine # [ 1.601565] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.601768] systemd[1]: Listening on udev Varlink Socket. machine # [ 1.601785] systemd[1]: Reached target Socket Units. machine # [ 1.606187] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.581956] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.606279] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.611830] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.593096] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.593313] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.593343] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.593367] systemd[1]: Reached target Path Units. sshClient # [ 1.593381] systemd[1]: Reached target Slice Units. sshClient # [ 1.593395] systemd[1]: Reached target Swaps. sshClient # [ 1.593408] systemd[1]: Reached target Timer Units. sshClient # [ 1.593549] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.593709] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.625654] systemd[1]: Starting Journal Service... sshClient # [ 1.593830] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.593908] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.598645] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 1.598678] systemd[1]: Reached target Socket Units. sshClient # [ 1.605264] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.605369] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.633855] systemd[1]: Starting Load Kernel Modules... machine # [ 1.633963] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 1.611038] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.643446] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.630229] systemd[1]: Starting Journal Service... sshClient # [ 1.646555] systemd[1]: Starting Load Kernel Modules... sshClient # [ 1.646690] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.679242] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.684203] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.685350] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 1.666204] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.695206] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.671257] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 1.691104] 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.697731] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.699675] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.726873] 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.700108] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.700349] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.740783] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.735891] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 sshClient # [ 1.736138] [drm] features: -virgl +edid -resource_blob -host_visible sshClient # [ 1.736141] [drm] features: -context_init sshClient # [ 1.736940] [drm] number of scanouts: 1 sshClient # [ 1.736955] [drm] number of cap sets: 0 machine # [ 1.762807] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.742196] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.775516] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.777339] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 1.754351] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic sshClient # [ 1.754371] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.785844] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.786177] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.786179] [drm] features: -context_init sshClient # [ 1.770761] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.800859] [drm] number of scanouts: 1 machine # [ 1.800882] [drm] number of cap sets: 0 sshClient # [ 1.775450] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.801473] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.801483] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 sshClient # [ 1.780617] Console: switching to colour frame buffer device 160x50 sshClient # [ 1.787630] 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.811674] 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.810713] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device sshClient # [ 1.823468] systemd[1]: Finished Load Kernel Modules. machine # [ 1.850856] Console: switching to colour frame buffer device 160x50 machine # [ 1.851557] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.853142] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.853423] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.853453] systemd[1]: Reached target Local File Systems. machine # [ 1.860967] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.833963] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.875656] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.851222] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.851474] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.862424] systemd-modules-load[72]: Inserted module 'dm_mod' sshClient # [ 1.851507] systemd[1]: Reached target Local File Systems. sshClient # [ 1.853585] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.870268] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.871637] systemd-modules-load[72]: Module 'virtio_console' is built in machine # [ 1.880559] systemd-modules-load[72]: Inserted module 'virtio_gpu' machine # [ 1.881678] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.901837] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.902323] systemd[1]: Started Journal Service. sshClient # [ 1.902461] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.906058] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.930874] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.919376] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.924824] systemd[1]: Started Journal Service. sshClient # [ 1.913087] systemd-modules-load[72]: Inserted module 'dm_mod' sshClient # [ 1.919972] systemd-modules-load[72]: Module 'virtio_balloon' is built in sshClient # [ 1.921291] systemd-modules-load[72]: Module 'virtio_console' is built in sshClient # [ 1.922451] systemd-modules-load[72]: Inserted module 'virtio_gpu' sshClient # [ 1.923557] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.961188] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.940585] systemd[1]: Starting Create System Files and Directories... machine # [ 1.965831] systemd-udevd[78]: Using default interface naming scheme 'v261'. machine # [ 1.988788] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.968517] systemd-udevd[80]: Using default interface naming scheme 'v261'. machine # [ 2.013860] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.990983] systemd[1]: Finished Create System Files and Directories. sshClient # [ 2.006565] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 2.062067] 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 # [ 2.062381] 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 # [ 2.091181] 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 # [ 2.091509] 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 # [ 2.121359] systemd[1]: Starting Virtual Console Setup... sshClient # [ 2.120132] systemd[1]: Starting Virtual Console Setup... machine # [ 2.213074] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.217676] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.208358] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 2.213741] systemd[1]: Finished Virtual Console Setup. machine # [ 3.008448] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.024873] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 3.038935] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 3.043117] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 3.055626] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 3.071106] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 3.076235] systemd[1]: Reached target System Initialization. sshClient # [ 3.080391] systemd[1]: Reached target Basic System. sshClient # [ 3.084589] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.114801] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.121858] systemd[1]: Reached target Initrd Root Device. machine # [ 3.130617] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.134293] systemd[1]: Finished Coldplug All udev Devices. machine # [ 3.150586] systemd[1]: Reached target System Initialization. machine # [ 3.152631] systemd[1]: Reached target Basic System. sshClient # [ 3.129784] systemd[1]: Found device /dev/disk/by-label/nixos. sshClient # [ 3.134226] systemd[1]: Reached target Initrd Root Device. sshClient # [ 3.139739] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.224795] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 3.220247] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 3.238485] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.292085] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 3.278550] systemd[1]: Mounting /sysroot... machine # [ 3.336626] systemd[1]: Mounting /sysroot... sshClient # [ 3.497655] EXT4-fs (vda): mounted filesystem a3bd74eb-1caa-4f31-98e8-ebce3a7cff78 r/w with ordered data mode. Quota mode: none. sshClient # [ 3.513995] systemd[1]: Mounted /sysroot. sshClient # [ 3.524435] systemd[1]: Reached target Initrd Root File System. sshClient # [ 3.552156] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 3.603625] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload. sshClient # [ 3.613977] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 3.620605] systemd[1]: Reloading... machine # [ 3.850640] EXT4-fs (vda): mounted filesystem a6ea0050-3b8b-40a7-b5e4-d8af0a783265 r/w with ordered data mode. Quota mode: none. machine # [ 3.837914] systemd[1]: Mounted /sysroot. machine # [ 3.839105] systemd[1]: Reached target Initrd Root File System. machine # [ 3.846652] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.859818] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.842624] systemd[1]: Reloading finished in 225 ms. machine # [ 3.874115] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.880170] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.884202] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace... machine # [ 3.909101] systemd[1]: Mounting /sysroot/tmp/shared... sshClient # [ 3.893827] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.918778] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 3.913912] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.917377] systemd[1]: Mounting /sysroot/run... machine # [ 3.954215] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 3.931941] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.978217] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 4.003012] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 4.013212] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 4.021191] systemd[1]: Mounted /sysroot/run. sshClient # [ 3.999482] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 4.002606] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 4.013188] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 4.038897] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 4.014303] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 4.020873] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 4.049949] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 4.054781] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 4.061204] systemd[1]: Reloading... sshClient # [ 4.036959] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 4.041402] systemd[1]: Mounted /sysroot/run. sshClient # [ 4.042475] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 4.071528] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.077634] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 4.103989] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.107264] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.109358] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 4.165002] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 4.167123] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.169373] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.179730] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.217988] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 4.239827] systemd[1]: Finished Find NixOS closure. sshClient # [ 4.241000] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.243376] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.278648] systemd[1]: Reloading finished in 219 ms. sshClient # [ 4.286196] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 4.290520] systemd[1]: Stopped target Basic System. sshClient # [ 4.292419] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.319752] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... sshClient # [ 4.296191] systemd[1]: Stopped target Path Units. sshClient # [ 4.297999] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. sshClient # [ 4.302451] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.327507] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... sshClient # [ 4.308230] systemd[1]: Stopped target Slice Units. machine # [ 4.332726] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.310972] systemd[1]: Stopped target Socket Units. sshClient # [ 4.312464] systemd[1]: Stopped target System Initialization. machine # [ 4.337108] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 4.313972] systemd[1]: Stopped target Swaps. sshClient # [ 4.316405] systemd[1]: Stopped target Timer Units. machine # [ 4.340831] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 4.343318] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 4.321490] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.323581] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.327795] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 4.330957] systemd[1]: Stopped Find NixOS closure. sshClient # [ 4.335388] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.341415] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 4.342602] systemd[1]: Stopped Apply Kernel Variables. sshClient # [ 4.343412] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.370500] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.372779] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.358147] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 4.359068] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 4.362282] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 4.368196] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.369385] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 4.370312] systemd[1]: Stopped target Local File Systems. sshClient # [ 4.371145] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 4.374405] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.376169] systemd[1]: Stopped Coldplug All udev Devices. sshClient # [ 4.380208] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.381398] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.383979] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.384907] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 4.385913] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 4.386983] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.391479] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.394515] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.395863] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.400605] systemd[1]: systemd-udevd.service: Consumed 1.009s CPU time over 2.555s wall clock time, 17.1M memory peak. sshClient # [ 4.402204] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully. sshClient # [ 4.404301] systemd[1]: Closed udev Varlink Socket. sshClient # [ 4.406602] systemd[1]: Starting Cleanup udev Database... sshClient # [ 4.407614] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.408930] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 4.409839] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.411105] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.416281] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.417368] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.440440] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. sshClient # [ 4.443449] systemd[1]: Finished Cleanup udev Database. sshClient # [ 4.444817] systemd[1]: Reached target Switch Root. sshClient # [ 4.448547] systemd[1]: Starting NixOS Activation... sshClient # [ 4.546575] initrd-nixos-activation-start[167]: booting system configuration /nix/store/5fy3hcm4l7bcy4gn68s4mcazymyrkqa6-nixos-system-sshClient-test sshClient # [ 4.583202] initrd-nixos-activation-start[167]: running activation script... machine # [ 4.840639] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 4.815427] initrd-nixos-activation-start[190]: setting up /etc... machine # [ 4.900130] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.901020] systemd[1]: Reached target Initrd File Systems. machine # [ 4.905546] systemd[1]: Starting Find NixOS closure... machine # [ 4.918893] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.969943] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.972298] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 4.951868] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 4.955451] systemd[1]: Finished NixOS Activation. sshClient # [ 4.956837] systemd[1]: Starting Switch Root... machine # [ 4.983601] systemd[1]: Finished Find NixOS closure. machine # [ 4.987382] systemd[1]: Reached target Initrd Default Target. machine # [ 4.998226] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 4.982807] systemd[1]: Switching root. machine # [ 5.030533] systemd[1]: Stopped target Initrd Default Target. machine # [ 5.036107] systemd[1]: Stopped target Basic System. machine # [ 5.037332] systemd[1]: Stopped target Initrd Root Device. machine # [ 5.039084] systemd[1]: Stopped target Path Units. machine # [ 5.043507] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 5.045466] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 5.053188] systemd[1]: Stopped target Slice Units. machine # [ 5.056169] systemd[1]: Stopped target Socket Units. machine # [ 5.057031] systemd[1]: Stopped target System Initialization. machine # [ 5.057879] systemd[1]: Stopped target Swaps. machine # [ 5.060154] systemd[1]: Stopped target Timer Units. machine # [ 5.064823] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 5.066886] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 5.069792] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 5.074121] systemd[1]: Stopped Find NixOS closure. machine # [ 5.076115] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 5.084192] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 5.088434] systemd[1]: Stopped Apply Kernel Variables. machine # [ 5.092511] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 5.102127] systemd[1]: Stopped Load Kernel Modules. machine # [ 5.104251] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 5.108259] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 5.109464] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 5.112186] systemd[1]: Stopped Create System Files and Directories. machine # [ 5.113598] systemd[1]: Stopped target Local File Systems. machine # [ 5.115604] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 5.119255] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 5.120805] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 5.122986] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 5.125841] systemd[1]: Stopped Virtual Console Setup. machine # [ 5.127954] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 5.129734] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 5.131678] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 5.134401] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 5.147181] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 5.151361] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 5.152545] systemd[1]: systemd-udevd.service: Consumed 1.719s CPU time over 3.306s wall clock time, 24.4M memory peak. machine # [ 5.154978] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully. machine # [ 5.160436] systemd[1]: Closed udev Varlink Socket. machine # [ 5.161219] systemd[1]: Starting Cleanup udev Database... machine # [ 5.162003] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 5.163083] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 5.165196] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 5.166517] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 5.167619] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 5.168745] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 5.168954] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 5.216183] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.218109] systemd[1]: Finished Cleanup udev Database. machine # [ 5.219809] systemd[1]: Reached target Switch Root. machine # [ 5.223652] systemd[1]: Starting NixOS Activation... sshClient # [ 5.272912] NET: Registered PF_VSOCK protocol family machine # [ 5.326393] initrd-nixos-activation-start[179]: booting system configuration /nix/store/g3z59v7k5qdjgb547p7wxg0gzwvkcvn8-nixos-system-machine-test sshClient # [ 5.346544] systemd[1]: systemd 263-c676df8 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.346621] systemd[1]: Detected virtualization qemu. sshClient # [ 5.346661] systemd[1]: Detected architecture arm64. sshClient # [ 5.346781] systemd[1]: Detected first boot. machine # [ 5.366474] initrd-nixos-activation-start[179]: running activation script... sshClient # [ 5.348753] systemd[1]: Initializing machine ID from random generator. sshClient # [ 5.695180] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 5.876148] systemd[1]: Applying preset policy. machine # [ 5.886712] initrd-nixos-activation-start[202]: setting up /etc... machine # [ 6.012609] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 6.015817] systemd[1]: Finished NixOS Activation. machine # [ 6.017249] systemd[1]: Starting Switch Root... machine # [ 6.042157] systemd[1]: Switching root. sshClient # [ 6.201938] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.229184] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 6.328421] NET: Registered PF_VSOCK protocol family machine # [ 6.412131] systemd[1]: systemd 263-c676df8 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 # [ 6.412786] systemd[1]: Detected virtualization qemu. machine # [ 6.413490] systemd[1]: Detected architecture arm64. machine # [ 6.413595] systemd[1]: Detected first boot. machine # [ 6.425263] systemd[1]: Initializing machine ID from random generator. sshClient # [ 6.412654] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.413639] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 6.419144] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.421002] systemd[1]: Created slice Slice /system/getty. sshClient # [ 6.424955] systemd[1]: Created slice User and Session Slice. sshClient # [ 6.425578] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.428515] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.428978] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 6.429579] systemd[1]: Expecting device /dev/ttyAMA0... sshClient # [ 6.432985] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 6.433399] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 6.435936] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 6.436333] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 6.437068] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.437645] systemd[1]: Reached target Path Units. sshClient # [ 6.441652] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.443474] systemd[1]: Reached target Slice Units. sshClient # [ 6.443889] systemd[1]: Reached target Swaps. sshClient # [ 6.445875] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 6.450080] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.451380] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 6.452656] systemd[1]: Listening on Factory Reset Management. sshClient # [ 6.453288] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 6.460467] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 6.463492] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.467697] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 6.468378] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 6.468976] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.469521] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki sshClient # [ 6.477813] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 6.479322] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 6.480026] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 6.484395] systemd[1]: Mounting Huge Pages File System... sshClient # [ 6.489459] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.506705] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 6.514646] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.529222] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.529770] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.568276] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 6.549870] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.558319] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.558875] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.565932] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 6.604401] systemd[1]: Mounting FUSE Control File System... sshClient # [ 6.604986] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 6.642661] systemd[1]: Starting Journal Service... sshClient # [ 6.662504] systemd[1]: Starting Load Kernel Modules... sshClient # [ 6.689294] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 6.703299] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 6.703728] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.724215] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 6.739379] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 6.739907] systemd[1]: Mounted Huge Pages File System. sshClient # [ 6.740312] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 6.740693] systemd[1]: Mounted Kernel Debug File System. sshClient # [ 6.741196] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 6.761966] systemd-journald[262]: Collecting audit messages is enabled. sshClient # [ 6.766199] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 6.778603] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 6.779298] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 6.779700] systemd[1]: Mounted FUSE Control File System. machine # [ 6.812100] systemd[1]: Applying preset policy. sshClient # [ 6.778459] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0. sshClient # [ 6.802966] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 6.803645] systemd[1]: Started Journal Service. sshClient # [ 6.796602] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. sshClient # [ 6.797966] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 6.799428] systemd-modules-load[263]: Module 'atkbd' is built in sshClient # [ 6.816553] systemd-modules-load[263]: Module 'loop' is built in sshClient # [ 6.817660] systemd[1]: Finished Load Kernel Modules. sshClient # [ 6.847185] systemd[1]: Starting Firewall... sshClient # [ 6.853436] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 6.892038] EXT4-fs (vda): re-mounted a3bd74eb-1caa-4f31-98e8-ebce3a7cff78. sshClient # [ 6.971107] systemd[1]: Finished Remount Root and Kernel File Systems. sshClient # [ 6.990216] systemd-oomd[264]: No swap; memory pressure usage will be degraded sshClient # [ 7.092629] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 7.093910] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 7.094992] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 7.117115] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 7.118066] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.165220] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.242701] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 7.214278] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.228680] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 7.255112] systemd-journald[262]: Received client request to flush runtime journal. sshClient # [ 7.325060] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 7.326119] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 7.360104] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 7.421076] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 7.428154] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 7.436875] systemd[1]: Mounting /run/wrappers... sshClient # [ 7.446563] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.490925] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 7.493267] systemd[1]: Stopped initrd-switch-root.service. machine # [ 7.499714] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 7.502437] systemd[1]: Created slice Slice /system/getty. machine # [ 7.507301] systemd[1]: Created slice User and Session Slice. machine # [ 7.508066] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 7.508878] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 7.509462] systemd[1]: Expecting device /dev/hvc0... machine # [ 7.510163] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 7.515458] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 7.516348] systemd[1]: Stopped target initrd-fs.target. machine # [ 7.517098] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 7.518619] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 7.522635] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 7.524667] systemd[1]: Reached target Path Units. machine # [ 7.525110] systemd[1]: Reached target Remote File Systems. machine # [ 7.525749] systemd[1]: Reached target Slice Units. machine # [ 7.526387] systemd[1]: Reached target Swaps. machine # [ 7.531409] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 7.533484] systemd[1]: Listening on Process Core Dump Socket. machine # [ 7.535090] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 7.540441] systemd[1]: Listening on Factory Reset Management. machine # [ 7.541128] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.547845] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.550175] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.555721] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.556732] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.557228] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.557699] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.571376] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.572255] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.573120] systemd[1]: Listening on User Database Manager Socket. machine # [ 7.581630] systemd[1]: Mounting Huge Pages File System... machine # [ 7.587539] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 7.562063] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.563181] systemd[1]: Reached target Local File Systems. machine # [ 7.603822] systemd[1]: Mounting Kernel Debug File System... machine # [ 7.621291] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 7.580001] systemd[1]: Listening on Boot Loader Control Service Socket. sshClient # [ 7.584346] systemd[1]: Starting Create SUID/SGID Wrappers... sshClient # [ 7.585370] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 7.599044] systemd[1]: Starting Create System Files and Directories... machine # [ 7.639820] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 7.641367] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 7.664170] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.671865] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.672460] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.681728] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.735132] systemd[1]: Mounting FUSE Control File System... machine # [ 7.737730] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.789382] systemd[1]: Starting Journal Service... sshClient # [ 7.764868] systemd-udevd[300]: Using default interface naming scheme 'v261'. machine # [ 7.819446] systemd[1]: Starting Load Kernel Modules... machine # [ 7.934057] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.948387] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.948947] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.974584] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 7.942248] systemd[1]: Starting User Database Manager... machine # [ 7.986070] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.986661] systemd[1]: Mounted Huge Pages File System. sshClient # [ 7.949114] systemd[1]: Finished Create System Files and Directories. machine # [ 8.001423] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.002188] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.002687] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 7.963422] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 7.968619] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.019206] systemd-journald[274]: Collecting audit messages is enabled. machine # [ 8.033234] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.034095] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.034644] systemd[1]: Mounted FUSE Control File System. machine # [ 8.022101] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.043372] systemd[1]: Started Journal Service. machine # [ 8.029236] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0. machine # [ 8.031210] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. machine # [ 8.044700] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.046040] systemd-modules-load[275]: Module 'atkbd' is built in machine # [ 8.047092] systemd-modules-load[275]: Module 'loop' is built in machine # [ 8.053794] systemd[1]: Finished Load Kernel Modules. machine # [ 8.081032] systemd[1]: Starting Firewall... machine # [ 8.091548] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.101697] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.110330] systemd-oomd[277]: No swap; memory pressure usage will be degraded machine # [ 8.123736] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.151651] EXT4-fs (vda): re-mounted a6ea0050-3b8b-40a7-b5e4-d8af0a783265. sshClient # [ 8.134141] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.172141] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.173244] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.198766] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.199915] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 8.177946] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 8.187562] systemd[1]: Started User Database Manager. machine # [ 8.212823] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.213727] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 8.191280] systemd[1]: Starting Update is Completed... sshClient # [ 8.295368] systemd[1]: Finished Update is Completed. machine # [ 8.340338] systemd-journald[274]: Received client request to flush runtime journal. machine # [ 8.384978] systemd[1]: Finished Apply Kernel Variables. machine # [ 8.385902] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 8.375375] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.408141] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 8.410083] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 8.429038] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 8.565348] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 8.585823] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 8.596494] systemd[1]: Mounting /run/wrappers... machine # [ 8.622794] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.755876] systemd[1]: Mounted /run/wrappers. machine # [ 8.756868] systemd[1]: Reached target Local File Systems. machine # [ 8.761416] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.772271] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.773291] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.781478] systemd[1]: Starting Create System Files and Directories... machine # [ 8.841719] systemd-udevd[315]: Using default interface naming scheme 'v261'. machine # [ 8.933314] systemd[1]: Starting User Database Manager... sshClient # [ 8.950896] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 8.957019] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.997716] systemd[1]: Finished Create System Files and Directories. machine # [ 9.022598] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.033584] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.226217] systemd[1]: Started User Database Manager. machine # [ 9.284105] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.315599] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 9.331554] systemd[1]: Starting Update is Completed... machine # [ 9.446759] systemd[1]: Finished Update is Completed. sshClient # [ 9.423721] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 9.425870] systemd[1]: Reached target System Initialization. sshClient # [ 9.426940] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 9.431311] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 9.437188] systemd[1]: Reached target Timer Units. sshClient # [ 9.438005] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 9.438985] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 9.446926] systemd[1]: Reached target Socket Units. sshClient # [ 9.447776] systemd[1]: Reached target Basic System. machine # [ 9.475436] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 9.452617] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 9.453698] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 9.468175] systemd[1]: Starting Post-Boot Actions... sshClient # [ 9.500653] systemd[1]: Started Reset console on configuration changes. sshClient # [ 9.538031] systemd[1]: Starting resolvconf update... sshClient # [ 9.583352] systemd[1]: Starting Home Area Manager... sshClient # [ 9.633748] systemd[1]: Finished Post-Boot Actions. sshClient # [ 9.671627] nsncd[440]: Oct 23 03:06:04.907 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 9.687328] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.724436] systemd[1]: Starting Virtual Console Setup... sshClient # [ 9.724110] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 9.725236] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 9.736515] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 9.774226] systemd[1]: Starting User Login Management... sshClient # [ 9.802661] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 9.848236] systemd-homed[445]: Watching /home. sshClient # [ 10.036595] dbus-broker-launch[463]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.072169] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 10.047954] systemd-logind[470]: New seat seat0. sshClient # [ 10.055672] dbus-broker-launch[463]: NSS returned no entry for 'systemd-timesync' machine # [ 10.084590] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 10.061225] dbus-broker-launch[463]: Invalid user-name in /nix/store/zay1cm11xv2kiq6m7ram48k5y8k4fn4r-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 10.063904] systemd[1]: Started User Login Management. machine # [ 10.088383] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 10.084059] systemd[1]: Starting linger-users.service... sshClient # [ 10.104171] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.136234] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 10.113752] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 10.124215] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 10.125343] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 10.126292] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 10.127197] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 10.145040] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 10.154622] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 10.181194] dbus-broker-launch[463]: Ready sshClient # [ 10.186587] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 10.195474] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 10.198703] systemd[1]: Finished linger-users.service. sshClient # [ 10.216451] systemd[1]: Started Home Area Manager. sshClient # [ 10.235197] systemd[1]: Finished Home Area Activation. sshClient # [ 10.255040] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 10.318407] systemd[1]: Finished resolvconf update. sshClient # [ 10.341302] systemd[1]: Starting DHCP Client... sshClient # [ 10.348604] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 10.360725] nsncd[536]: Oct 23 03:06:05.577 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 10.368135] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 10.373527] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 10.407303] systemd[1]: Finished First Boot Home Area Wizard. sshClient # [ 10.414758] systemd[1]: Reached target First Boot Complete. machine # [ 10.441531] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 10.433056] systemd[1]: Starting Save Transient machine-id to Disk... sshClient # [ 10.473269] systemd[1]: Finished Firewall. sshClient # [ 10.481330] systemd[1]: Reached target Preparation for Network. sshClient # [ 10.487535] systemd[1]: Starting Extra networking commands.... machine # [ 10.516896] systemd[1]: Finished Coldplug All udev Devices. machine # [ 10.567498] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. sshClient # [ 10.598397] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 10.610471] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 10.638503] systemd-vconsole-setup[420]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 10.647348] systemd[1]: Finished Virtual Console Setup. machine # [ 10.650265] systemd[1]: Reached target System Initialization. machine # [ 10.651155] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.658679] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.659878] systemd[1]: Reached target Timer Units. machine # [ 10.664965] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.665962] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine # [ 10.667295] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.677844] systemd[1]: Reached target Socket Units. machine # [ 10.678647] systemd[1]: Reached target Basic System. machine # [ 10.679419] systemd[1]: Started backdoor.service. machine # [ 10.689222] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.693219] (udev-worker)[393]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 10.704235] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 10.680568] dhcpcd[581]: dhcpcd-10.3.2 starting machine # [ 10.705654] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.707001] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 10.691133] dhcpcd[591]: dev: loaded udev machine # [ 10.719489] systemd[1]: Starting Post-Boot Actions... sshClient # [ 10.696703] dhcpcd[591]: no valid interfaces found sshClient # [ 10.699638] dhcpcd[591]: no valid interfaces found machine # [ 10.760945] systemd[1]: Started Reset console on configuration changes. machine # [ 10.799832] systemd[1]: Starting resolvconf update... sshClient # [ 10.809299] systemd[1]: Finished Extra networking commands.. sshClient # [ 10.811671] systemd[1]: Reached target Network. sshClient # [ 10.816504] systemd[1]: Starting Permit User Sessions... machine # [ 10.847995] systemd[1]: Starting SSH Host Keys Generation... machine # connecting to host... machine # [ 10.898279] systemd[1]: Starting Home Area Manager... sshClient # [ 10.880222] systemd[1]: Finished Permit User Sessions. sshClient # [ 10.887622] systemd[1]: Started Getty on tty1. sshClient # [ 10.890620] systemd[1]: Reached target Login Prompts. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 10.928340] systemd[1]: Finished Firewall. machine # [ 10.932187] systemd[1]: Started Name Service Cache Daemon (nsncd). machine: connected to guest root shell machine: (connecting took 11.23 seconds) machine: (finished: waiting for the VM to finish booting, in 11.23 seconds) machine # [ 10.978265] systemd[1]: Finished Post-Boot Actions. sshClient # [ 10.973059] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine # [ 10.997558] nsncd[492]: Oct 23 03:06:06.158 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.036489] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.067854] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.100350] systemd[1]: Starting D-Bus System Message Bus... machine # [ 11.133828] systemd-homed[508]: Watching /home. machine # [ 11.134661] systemd[1]: Starting User Login Management... machine # [ 11.270982] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 11.288686] systemd[1]: Starting Virtual Console Setup... sshClient # [ 11.303211] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 11.307888] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 11.315340] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 11.323927] systemd[1]: Starting Virtual Console Setup... sshClient # [ 11.361804] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 11.390554] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 11.391684] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 11.402162] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 11.403341] systemd[1]: Stopping User and Group Name Lookups... machine # [ 11.414791] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 11.415817] systemd[1]: nscd.service: Deactivated successfully. machine # [ 11.421493] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 11.463813] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.479929] systemd-logind[542]: New seat seat0. machine # [ 11.488785] dbus-broker-launch[535]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 11.489962] dbus-broker-launch[535]: NSS returned no entry for 'systemd-timesync' machine # [ 11.490997] dbus-broker-launch[535]: Invalid user-name in /nix/store/fq3kwf03knlx507q24vjcqcfyhn4x8xr-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 11.475556] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 11.517020] systemd[1]: Started User Login Management. machine # [ 11.517891] systemd[1]: Starting linger-users.service... sshClient # [ 11.515079] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. sshClient # [ 11.521978] systemd[1]: Started backdoor.service. machine # [ 11.546598] systemd[1]: Started D-Bus System Message Bus. machine # [ 11.570501] systemd-logind[542]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 11.609683] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.611115] nsncd[590]: Oct 23 03:06:06.837 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # connecting to host... machine # [ 11.634601] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.635716] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.646962] dbus-broker-launch[535]: Ready machine # [ 11.647720] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 11.664207] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 11.665525] systemd[1]: Started Home Area Manager. machine # [ 11.666224] systemd[1]: Finished Home Area Activation. machine # [ 11.666953] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 11.680358] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 11.681470] systemd[1]: Finished linger-users.service. sshClient # [ 11.672294] 8021q: 802.1Q VLAN Support v1.8 sshClient # [ 11.666402] (udev-worker)[636]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 11.680569] (udev-worker)[641]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 11.708172] systemd[1]: Finished resolvconf update. machine # [ 11.709087] systemd[1]: Reached target Preparation for Network. sshClient # [ 11.695317] (udev-worker)[641]: Network interface NamePolicy= disabled on kernel command line. machine # [ 11.727536] systemd[1]: Starting DHCP Client... machine # [ 11.733953] systemd[1]: Starting Address configuration of eth1... machine # [ 11.745825] systemd[1]: Starting Extra networking commands.... sshClient # [ 11.803690] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 11.805889] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 11.809169] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' sshClient # [ 11.812548] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 11.813008] cfg80211: failed to load regulatory.db machine # [ 11.871979] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 11.895648] systemd[1]: Started Console Output Muting Service (PID 614/UID 0). sshClient # [ 11.890099] systemd-logind[470]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 11.940584] mousedev: PS/2 mouse device common for all mice machine # [ 11.959563] sshd-keygen-start[546]: Generating public/private rsa key pair. machine # [ 11.969434] sshd-keygen-start[546]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 11.970738] sshd-keygen-start[546]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub sshClient # [ 11.963661] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 11.990752] sshd-keygen-start[546]: The key fingerprint is: machine # [ 11.991670] sshd-keygen-start[546]: SHA256:ma+y82d2mxKJs6C/jEGFgDNlmdxqKgrmqJYjvBQi7kw root@machine sshClient # [ 11.971525] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 12.000434] sshd-keygen-start[546]: The key's randomart image is: machine # [ 12.001396] sshd-keygen-start[546]: +---[RSA 4096]----+ machine # [ 12.002134] sshd-keygen-start[546]: | +++ | machine # [ 12.002844] sshd-keygen-start[546]: |+.+... | sshClient # [ 11.979565] systemd[1]: Starting Address configuration of eth1... machine # [ 12.003676] sshd-keygen-start[546]: | o .. . | machine # [ 12.026245] sshd-keygen-start[546]: | o . o | machine # [ 12.027000] sshd-keygen-start[546]: |o+ . S. . | machine # [ 12.027876] sshd-keygen-start[546]: |B... . o.o | sshClient # [ 12.011201] dhcpcd[591]: eth0: waiting for carrier sshClient # [ 12.028134] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 12.016936] dhcpcd[591]: eth0: carrier acquired machine # [ 12.049299] sshd-keygen-start[546]: |OE. .. . o.. | machine # [ 12.050123] sshd-keygen-start[546]: |O* .+o ..= .. | machine # [ 12.050869] sshd-keygen-start[546]: |=+o ..=*o+ oo. | machine # [ 12.051777] sshd-keygen-start[546]: +----[SHA256]-----+ sshClient # [ 12.036518] dhcpcd[591]: DUID 00:01:00:01:32:6d:8b:9f:52:54:00:12:34:56 sshClient # [ 12.037623] dhcpcd[591]: eth0: IAID 00:12:34:56 sshClient # [ 12.038420] dhcpcd[591]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.073247] network-addresses-eth1-start[619]: adding address 192.168.1.1/24... done sshClient # [ 12.074326] systemd-vconsole-setup[652]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 12.100156] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 12.109745] network-addresses-eth1-start[619]: adding address 2001:db8:1::1/64... done sshClient # [ 12.087533] systemd[1]: Finished Virtual Console Setup. machine # [ 12.119607] sshd-keygen-start[645]: Generating public/private ed25519 key pair. machine # [ 12.128587] sshd-keygen-start[645]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 12.129943] sshd-keygen-start[645]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 12.131287] sshd-keygen-start[645]: The key fingerprint is: sshClient # [ 12.106651] network-addresses-eth1-start[672]: adding address 192.168.1.2/24... done sshClient # [ 12.121276] network-addresses-eth1-start[672]: adding address 2001:db8:1::2/64... done machine # [ 12.148237] sshd-keygen-start[645]: SHA256:ipvwH74p5jjbrWnd6T0nPVhpDsyZRfglSxA9nqR0cso root@machine machine # [ 12.149566] sshd-keygen-start[645]: The key's randomart image is: machine # [ 12.150423] sshd-keygen-start[645]: +--[ED25519 256]--+ machine # [ 12.151176] sshd-keygen-start[645]: | o= | machine # [ 12.151938] sshd-keygen-start[645]: | + X . | machine # [ 12.165267] sshd-keygen-start[645]: | o % * | machine # [ 12.166095] sshd-keygen-start[645]: | E * | machine # [ 12.166841] sshd-keygen-start[645]: | So + . | machine # [ 12.167602] sshd-keygen-start[645]: | . . * + | sshClient # [ 12.144231] systemd[1]: Finished Address configuration of eth1. machine # [ 12.173280] sshd-keygen-start[645]: | . ..o. . B | machine # [ 12.174079] sshd-keygen-start[645]: | .=+*.oo.+ = | machine # [ 12.174805] sshd-keygen-start[645]: | o*O+*o. .+ . | machine # [ 12.175569] sshd-keygen-start[645]: +----[SHA256]-----+ sshClient # [ 12.171421] mousedev: PS/2 mouse device common for all mice machine # [ 12.189013] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 12.190246] systemd[1]: Finished SSH Host Keys Generation. machine # [ 12.191084] systemd[1]: Finished Address configuration of eth1. sshClient # [ 12.187950] systemd-logind[470]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.224746] dhcpcd[628]: dhcpcd-10.3.2 starting machine # [ 12.237207] dhcpcd[681]: dev: loaded udev machine # [ 12.320157] systemd[1]: Finished Extra networking commands.. machine # [ 12.322760] systemd[1]: Reached target Network. machine # [ 12.345297] systemd[1]: Starting SSH Daemon... machine: (finished: waiting for unit systemd-homed.service, in 12.71 seconds) machine: waiting for Please enter user name to create to appear on tty 1 machine # [ 12.447613] systemd-logind[542]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.516491] sshd[709]: Server listening on 0.0.0.0 port 22. machine # [ 12.517508] sshd[709]: Server listening on :: port 22. machine # [ 12.526886] systemd[1]: Started SSH Daemon. machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.14 seconds) machine: sending keys 'test-homed-user\n' machine # [ 12.597195] dhcpcd[681]: eth0: waiting for carrier machine # [ 12.598114] dhcpcd[681]: eth0: carrier acquired machine # [ 12.610091] dhcpcd[681]: DUID 00:01:00:01:32:6d:8b:9f:52:54:00:12:34:56 machine # [ 12.611374] dhcpcd[681]: eth0: IAID 00:12:34:56 machine # [ 12.613988] dhcpcd[681]: eth0: adding address fe80::5054:ff:fe12:3456 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 # [ 13.236460] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 13.236626] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 13.236677] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 13.236765] systemd[1]: Stopping Virtual Console Setup... sshClient # [ 13.247314] systemd[1]: Starting Virtual Console Setup... sshClient # [ 13.303461] systemd-logind[470]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 13.387177] systemd-vconsole-setup[692]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.415051] dhcpcd[681]: eth0: soliciting a DHCP lease sshClient # [ 13.392176] systemd[1]: Finished Virtual Console Setup. machine # [ 13.420637] dhcpcd[681]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 13.428294] dhcpcd[681]: eth0: probing address 10.0.2.15/24 sshClient # [ 13.428155] dhcpcd[591]: eth0: soliciting a DHCP lease sshClient # [ 13.435518] dhcpcd[591]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 13.440321] dhcpcd[591]: eth0: probing address 10.0.2.15/24 machine # [ 13.641747] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.650829] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.652423] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.653194] systemd[1]: Stopping Virtual Console Setup... machine # [ 13.657640] systemd[1]: Starting Virtual Console Setup... machine # [ 13.701745] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.704436] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.716926] systemd[1]: Starting Virtual Console Setup... machine # [ 13.755244] systemd-logind[542]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 13.837074] systemd-vconsole-setup[740]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.846253] systemd[1]: Finished Virtual Console Setup. machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.14 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 sshClient # [ 14.885108] dhcpcd[591]: eth0: soliciting an IPv6 router sshClient # [ 14.887779] dhcpcd[591]: eth0: Router Advertisement from fe80::2 sshClient # [ 14.890899] dhcpcd[591]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 14.892584] dhcpcd[591]: eth0: adding route to fec0::/64 sshClient # [ 14.893937] dhcpcd[591]: eth0: adding default route via fe80::2 machine # [ 15.017780] dhcpcd[681]: eth0: soliciting an IPv6 router machine # [ 15.019398] dhcpcd[681]: eth0: Router Advertisement from fe80::2 machine # [ 15.021004] dhcpcd[681]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 15.022476] dhcpcd[681]: eth0: adding route to fec0::/64 machine # [ 15.023734] dhcpcd[681]: eth0: adding default route via fe80::2 machine: (finished: waiting for (repeat) to appear on tty 1, in 1.11 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for unit systemd-homed-firstboot.service machine # [ 18.482184] dhcpcd[681]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 18.483919] dhcpcd[681]: eth0: adding route to 10.0.2.0/24 machine # [ 18.485540] dhcpcd[681]: eth0: adding default route via 10.0.2.2 machine # [ 18.599870] systemd[1]: Started DHCP Client. sshClient # [ 18.802347] dhcpcd[591]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 18.804799] dhcpcd[591]: eth0: adding route to 10.0.2.0/24 sshClient # [ 18.806190] dhcpcd[591]: eth0: adding default route via 10.0.2.2 sshClient # [ 18.921487] systemd[1]: Started DHCP Client. sshClient # [ 18.925279] systemd[1]: Reached target Multi-User System. sshClient # [ 18.926785] systemd[1]: Startup finished in 1.339s (kernel) + 3.893s (initrd) + 13.692s (userspace) = 18.926s. machine # [ 25.411312] systemd-homed[508]: Generating key pair for signing local user identity records. machine # [ 25.416319] systemd-homed[508]: Successfully created Ed25519 key pair. machine # [ 25.429081] systemd-homed[508]: test-homed-user: changing state absent → creating machine # [ 25.523467] systemd-homework[864]: Using automatic default storage of 'luks'. machine # [ 25.528512] systemd-homework[864]: Sizing home to 83% of available disk space, which is 744M. machine # [ 25.530086] systemd-homework[864]: Allocating image file completed. machine # [ 25.572143] systemd-homework[864]: Writing of partition table completed. machine # [ 25.576124] systemd-homework[864]: Setting up loopback device /dev/loop0 completed. machine # [ 26.859882] systemd-homework[864]: LUKS formatting completed. machine # [ 29.988123] systemd-homework[864]: Writing password to LUKS keyslot 0 completed. machine # [ 30.244220] systemd-homework[864]: LUKS activation by volume key succeeded. machine # [ 30.262379] systemd-homework[864]: Writing user record as LUKS token completed. machine # [ 30.263670] systemd-homework[864]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 30.268135] systemd-homework[864]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 31.389787] systemd[1]: proc-896-mounts.mount: Deactivated successfully. machine # [ 31.390818] systemd-homework[864]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 64ea3196-bd7f-4137-ba47-873ddcb82844) machine # [ 31.390915] systemd-homework[864]: Formatting file system completed. machine # [ 31.461798] systemd-homework[864]: Mounting file system completed. machine # [ 31.464705] systemd-homework[864]: Skeleton directory /etc/skel missing, ignoring. machine # [ 31.466673] systemd-homework[864]: Wrote embedded .identity file. machine # [ 31.467792] systemd-homework[864]: Replaced embedded blob dir with contents of system blob dir. machine # [ 31.470573] systemd-homework[864]: Recursive changing of ownership completed. machine # [ 31.472300] systemd-homework[864]: Changed top-level directory access mode to 0700. machine # [ 31.475884] systemd-homework[864]: Synchronized disk. machine # [ 31.478114] systemd-homework[864]: Discarded unused 652.8M. machine # [ 31.491547] systemd-homed[508]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 31.548826] systemd-homed[508]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 31.554696] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 31.561485] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 31.569447] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 31.571423] systemd-homework[864]: Moved image file into place. machine # [ 31.581261] systemd-homework[864]: Creation completed. machine # [ 31.582166] systemd-homework[864]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M. machine # [ 31.583994] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 31.586153] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory machine # [ 31.587979] systemd-homed[508]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 31.597188] systemd-homed[508]: test-homed-user: changing state creating → inactive machine # [ 31.618314] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 31.625576] systemd[1]: systemd-mute-console@0-1-614_615-0.service: Deactivated successfully. machine # [ 31.634932] systemd[1]: Reached target First Boot Complete. machine # [ 31.640166] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 31.645703] systemd[1]: Starting Permit User Sessions... machine # [ 31.701740] systemd[1]: Finished Permit User Sessions. machine # [ 31.708567] systemd[1]: Started Getty on tty1. machine # [ 31.710925] systemd[1]: Reached target Login Prompts. machine # [ 31.717158] systemd[1]: Reached target Multi-User System. machine # [ 32.314668] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 32.323563] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 32.329650] systemd[1]: Startup finished in 1.353s (kernel) + 4.934s (initrd) + 26.038s (userspace) = 32.327s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 17.46 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 32.95 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 32.722602] systemd-homed[508]: test-homed-user: changing state inactive → updating machine # [ 32.746839] systemd-homework[947]: Offline update requested. Not touching embedded records. machine # [ 32.751180] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 32.758083] systemd-homed[508]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.09 seconds) subtest: login as homed user machine: waiting for login: to appear on tty 1 machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds) machine: sending keys 'test-homed-user\n' machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Password: to appear on tty 1 machine # [ 33.153353] systemd-homed[508]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 33.163238] systemd-homework[962]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 33.173067] systemd-homed[508]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 33.177273] systemd-homed[508]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 33.180426] systemd-homed[508]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 33.182987] systemd-homed[508]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: to appear on tty 1, in 1.13 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for success: pgrep -u test-homed-user -t tty1 bash machine # [ 34.300270] systemd-homed[508]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 34.341375] systemd-homework[974]: Provided password unlocks user record. machine # [ 34.346920] systemd-homework[974]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 34.354129] systemd-homework[974]: Allocated additional 652.8M. machine # [ 34.377918] loop1: detected capacity change from 0 to 1519616 machine # [ 34.361260] systemd-homework[974]: Setting up loopback device /dev/loop1 completed. machine # [ 35.045108] systemd-homework[974]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 35.085797] systemd-homework[974]: Provided password unlocks user record. machine # [ 35.093163] systemd-homework[974]: Probing file system completed (found btrfs). machine # [ 35.095661] systemd-homework[974]: No fsck for file system btrfs installed, ignoring. machine # [ 35.117337] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (974) machine # [ 35.122216] BTRFS info (device dm-0): first mount of filesystem 64ea3196-bd7f-4137-ba47-873ddcb82844 machine # [ 35.122276] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 35.109835] systemd-homework[974]: Mounting file system completed. machine # [ 35.128213] BTRFS info (device dm-0): enabling free space tree machine # [ 35.128240] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 35.115901] systemd-homework[974]: Discovered used loopback device /dev/loop1. machine # [ 35.119356] systemd-homework[974]: offset = 1048576, size = 778043392, image = 780140544 machine # [ 35.127679] systemd-homework[974]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M. machine # [ 35.130398] systemd-homework[974]: Growing of image file from 744M to 897M completed. machine # [ 35.142498] systemd-homework[974]: Refreshing loop device size completed. machine # [ 35.158792] loop1: detected capacity change from 1519616 to 1832960 machine # [ 35.159271] systemd-homework[974]: Growing of partition completed. machine # [ 35.209875] async_tx: api initialized (async) machine # [ 35.253801] systemd-homework[974]: LUKS device growing completed. machine # [ 35.281374] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 921698304 machine # [ 35.266481] systemd-homework[974]: File system resizing from 726M to 879M completed. machine # [ 35.267992] systemd-homework[974]: Synchronized disk. machine # [ 35.273538] systemd-homework[974]: Synchronized disk. machine # [ 35.274383] systemd-homework[974]: Resizing completed. machine # [ 35.275358] systemd-homework[974]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 35.288325] systemd-homework[974]: Read embedded .identity file. machine # [ 35.316344] systemd-homework[974]: Provided password unlocks user record. machine # [ 35.318803] systemd-homework[974]: Reconciling header user identity completed (host version was newer). machine # [ 35.322501] systemd-homework[974]: Reconciling embedded user identity completed (host version was newer). machine # [ 35.352611] systemd-homework[974]: Wrote LUKS header user record. machine # [ 35.353778] systemd-homework[974]: Wrote embedded .identity file. machine # [ 35.354709] systemd-homework[974]: Replaced embedded blob dir with contents of system blob dir. machine # [ 35.361200] systemd-homework[974]: Recursive changing of ownership not necessary, skipped. machine # [ 35.372153] systemd-homework[974]: Synchronized disk. machine # [ 35.373096] systemd-homework[974]: Moving to final mount point /home/test-homed-user completed. machine # [ 35.409980] systemd-homework[974]: Activation completed. machine # [ 35.411070] systemd-homework[974]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 35.436649] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 35.446845] login[937]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 35.451331] systemd-homed[508]: test-homed-user: changing state activating-for-acquire → active machine # [ 35.457060] systemd-homed[508]: test-homed-user: changing state active → resizing-while-active machine # [ 35.467964] systemd-homework[1016]: Successfully acquired home volume key from kernel keyring. machine # [ 35.473434] systemd-homework[1016]: LUKS volume key from keyring unlocks user record. machine # [ 35.564668] login[937]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 35.580650] systemd-homework[1016]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 35.709838] systemd-homework[1016]: Successfully re-activated LUKS device. machine # [ 35.712956] systemd-homework[1016]: LUKS volume key from keyring unlocks user record. machine # [ 35.716271] systemd-homework[1016]: Discovered used loopback device /dev/loop1. machine # [ 35.718966] systemd-homework[1016]: Read embedded .identity file. machine # [ 35.721141] systemd-homework[1016]: LUKS volume key from keyring unlocks user record. machine # [ 35.722307] systemd-homework[1016]: Reconciling header user identity completed (host version was newer). machine # [ 35.723817] systemd-homework[1016]: Reconciling embedded user identity completed (host version was newer). machine # [ 35.728748] systemd-homework[1016]: offset = 1048576, size = 938475520, image = 940572672 machine # [ 35.864540] systemd-homework[1016]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M. machine # [ 35.868988] systemd-homework[1016]: Wrote embedded .identity file. machine # [ 35.870803] systemd-homework[1016]: Replaced embedded blob dir with contents of system blob dir.[ 35.893552] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 783286272 machine # machine # [ 35.879351] systemd-homework[1016]: Backing file is fully allocated already. machine # [ 35.885066] systemd-homework[1016]: File system resizing from 879M to 747M completed. machine # [ 35.886389] systemd-homework[1016]: Synchronized disk. machine # [ 35.927473] systemd[1]: Created slice Slice /user/60388. machine # [ 35.944735] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 35.979809] systemd-logind[542]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 36.037484] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 36.055806] systemd[1]: Starting User Manager for UID 60388... machine # [ 36.094205] systemd-homework[1016]: LUKS device shrinking completed. machine # [ 36.122866] loop1: detected capacity change from 1832960 to 1562624 machine # [ 36.109333] systemd-homework[1016]: Refreshing loop device size completed. machine # [ 36.111228] systemd-homework[1016]: Shrinking of image file completed. machine # [ 36.126496] (systemd)[1030]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 36.148326] systemd-homework[1016]: Shrinking of partition completed. machine # [ 36.197950] systemd-homework[1016]: Wrote LUKS header user record. machine # [ 36.199091] systemd-homework[1016]: Synchronized disk. machine # [ 36.222199] systemd-homework[1016]: Resizing completed. machine # [ 36.223173] systemd-homework[1016]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 36.232201] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 36.246523] systemd-homed[508]: test-homed-user: changing state resizing-while-active → active machine # [ 36.251234] systemd-homed[508]: Rebalancing complete. machine # [ 36.258026] (systemd)[1030]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 36.264965] systemd-logind[542]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 36.295806] systemd[1030]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.473034] systemd[1030]: Queued start job for default target Main User Target. machine # [ 36.481152] systemd[1030]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.485421] systemd[1030]: Created slice User Application Slice. machine # [ 36.486483] systemd[1030]: Started Daily Cleanup of User's Temporary Directories. machine # [ 36.487705] systemd[1030]: Reached target Paths. machine # [ 36.488794] systemd[1030]: Reached target Timers. machine # [ 36.491477] systemd[1030]: Starting D-Bus User Message Bus Socket... machine # [ 36.494027] systemd[1030]: Starting Create User Files and Directories... machine # [ 36.542473] systemd[1030]: Finished Create User Files and Directories. machine # [ 36.553444] systemd[1030]: Listening on D-Bus User Message Bus Socket. machine # [ 36.554637] systemd[1030]: Reached target Sockets. machine # [ 36.562365] systemd[1030]: Reached target Basic System. machine # [ 36.563436] systemd[1]: Started User Manager for UID 60388. machine # [ 36.565431] systemd[1030]: Starting Run user-specific NixOS activation... machine # [ 36.576522] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 36.616138] systemd[1030]: Finished Run user-specific NixOS activation. machine # [ 36.617299] systemd[1030]: Reached target Main User Target. machine # [ 36.619658] systemd[1030]: Startup finished in 334ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.30 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.00 seconds) subtest: login as regular user sshClient: waiting for login: to appear on tty 1 sshClient: waiting for the VM to finish booting sshClient: Guest shell says: b'Spawning backdoor root shell...\n' sshClient: connected to guest root shell sshClient: (connecting took 0.00 seconds) sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds) sshClient: (finished: waiting for login: to appear on tty 1, in 0.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.12 seconds) sshClient: sending keys 'foobarfoo\n' sshClient: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) sshClient: waiting for success: pgrep -u test-normal-user bash sshClient # [ 40.320697] login[645]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 40.360128] systemd-logind[470]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 40.383262] systemd[1]: Created slice Slice /user/1000. sshClient # [ 40.397431] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 40.431147] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 40.439001] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 40.474490] (systemd)[795]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 40.483235] systemd-logind[470]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 40.519549] systemd[795]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 40.657888] systemd[795]: Queued start job for default target Main User Target. sshClient # [ 40.665155] systemd[795]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 40.669621] systemd[795]: Created slice User Application Slice. sshClient # [ 40.670738] systemd[795]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 40.671837] systemd[795]: Reached target Paths. sshClient # [ 40.673079] systemd[795]: Reached target Timers. sshClient # [ 40.675591] systemd[795]: Starting D-Bus User Message Bus Socket... sshClient # [ 40.677776] systemd[795]: Starting Create User Files and Directories... sshClient # [ 40.731762] systemd[795]: Finished Create User Files and Directories. sshClient # [ 40.752120] systemd[795]: Listening on D-Bus User Message Bus Socket. sshClient # [ 40.753217] systemd[795]: Reached target Sockets. sshClient # [ 40.753986] systemd[795]: Reached target Basic System. sshClient # [ 40.760999] systemd[1]: Started User Manager for UID 1000. sshClient # [ 40.761991] systemd[795]: Starting Run user-specific NixOS activation... sshClient # [ 40.770489] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 40.830008] systemd[795]: Finished Run user-specific NixOS activation. sshClient # [ 40.836406] systemd[795]: Reached target Main User Target. sshClient # [ 40.837465] systemd[795]: Startup finished in 323ms. 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.08 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.86 seconds) subtest: add homed ssh authorized key sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n' sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.50 seconds) sshClient: waiting for file '/tmp/id_ed25519.pub' sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.07 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 AAAAC3NzaC1lZDI1NTE5AAAAIK+UHmjak5nHrmY+RJ9r3DGFB9Nk1OnAyY7ISBk8IWjJ test-normal-user@sshClient' machine # [ 44.260003] systemd-homed[508]: test-homed-user: changing state active → updating-while-active machine # [ 44.266776] systemd-homework[1078]: Offline update requested. Not touching embedded records. machine # [ 44.276551] systemd-homed[508]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIK+UHmjak5nHrmY+RJ9r3DGFB9Nk1OnAyY7ISBk8IWjJ test-normal-user@sshClient', in 0.06 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.68 seconds) subtest: change homed user password machine: sending keys 'passwd; echo $? > /tmp/3\n' machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.27 seconds) machine: waiting for New password: to appear on tty 1 machine: (finished: waiting for New password: to appear on tty 1, in 1.08 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds) machine: waiting for Retype new password: to appear on tty 1 machine: (finished: waiting for Retype new password: to appear on tty 1, in 0.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 # [ 46.130190] systemd-homed[508]: test-homed-user: changing state active → passwd-while-active machine # [ 46.707669] systemd-homework[1110]: No valid password for LUKS superblock. machine # [ 49.185416] systemd-homed[508]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 49.189689] systemd-homed[508]: 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 # [ 50.300829] systemd-homed[508]: test-homed-user: changing state active → passwd-while-active machine # [ 51.585464] systemd-homework[1115]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 51.713426] systemd-homework[1115]: Successfully re-activated LUKS device. machine # [ 51.771722] systemd-homework[1115]: Provided password unlocks user record. machine # [ 51.776324] systemd-homework[1115]: Discovered used loopback device /dev/loop1. machine # [ 51.777499] systemd-homework[1115]: Read embedded .identity file. machine # [ 51.830202] systemd-homework[1115]: Provided password unlocks user record. machine # [ 51.836209] systemd-homework[1115]: Reconciling header user identity completed (host version was newer). machine # [ 51.837736] systemd-homework[1115]: Reconciling embedded user identity completed (host version was newer). machine # [ 55.393406] systemd-homework[1115]: Added LUKS key slot 1. machine # [ 55.418605] systemd-homework[1115]: Destroyed LUKS key slot 0. machine # [ 55.445715] systemd-homework[1115]: Wrote LUKS header user record. machine # [ 55.447739] systemd-homework[1115]: Wrote embedded .identity file. machine # [ 55.451164] systemd-homework[1115]: Replaced embedded blob dir with contents of system blob dir. machine # [ 55.484850] systemd-homework[1115]: Synchronized disk. machine # [ 55.491147] systemd-homework[1115]: Everything completed. machine # [ 55.517022] passwd[1091]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine # [ 55.521146] systemd-homed[508]: test-homed-user: changing state passwd-while-active → active machine: (finished: waiting for file '/tmp/3', in 6.24 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.09 seconds) subtest: escalate to root from homed user machine: sending keys 'sudo id | tee /tmp/4\n' machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds) machine: waiting for password for test-homed-user to appear on tty 1 machine # [ 56.884607] systemd-homed[508]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 56.893216] systemd-homework[1157]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 56.903236] systemd-homed[508]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 56.906652] systemd-homed[508]: test-homed-user: changing state authenticating-for-acquire → active machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.11 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for file '/tmp/4' machine: (finished: waiting for file '/tmp/4', in 0.02 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 58.155933] systemd-homed[508]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 58.214981] systemd-homework[1173]: Provided password unlocks user record. machine # [ 58.952136] systemd-homework[1173]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 59.076684] systemd-homework[1173]: Successfully re-activated LUKS device. machine # [ 59.107474] systemd-homework[1173]: Provided password unlocks user record. machine # [ 59.109499] systemd-homework[1173]: Discovered used loopback device /dev/loop1. machine # [ 59.110867] systemd-homework[1173]: Read embedded .identity file. machine # [ 59.138570] systemd-homework[1173]: Provided password unlocks user record. machine # [ 59.140830] systemd-homework[1173]: Reconciling user identities completed (host and header version were identical). machine # [ 59.143217] systemd-homework[1173]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 59.151603] systemd-homework[1173]: Everything completed. machine # [ 59.160898] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 59.639993] sudo[1154]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 59.646281] systemd-homed[508]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 59.657176] sudo[1154]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 59.698191] sudo[1154]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 59.721064] sudo[1154]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 2.11 seconds) (finished: subtest: escalate to root from homed user, in 3.57 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 # [ 60.058360] login[937]: pam_unix(login:session): session closed for user test-homed-user machine # [ 60.067677] login[937]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 60.076957] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 60.087956] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 60.093075] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 60.099165] systemd-logind[542]: Session 1 logged out. Waiting for processes to exit. machine # [ 60.102472] systemd[1]: Started Getty on tty1. machine # [ 60.106645] systemd-logind[542]: Removed session 1. machine # [ 70.234714] systemd[1]: Stopping User Manager for UID 60388... machine # [ 70.243708] systemd[1030]: Activating special unit Exit the Session... machine # [ 70.251709] systemd[1030]: Stopped target Main User Target. machine # [ 70.257053] systemd[1030]: Stopped Run user-specific NixOS activation. machine # [ 70.258858] systemd[1030]: Stopped target Basic System. machine # [ 70.260500] systemd[1030]: Stopped target Paths. machine # [ 70.261698] systemd[1030]: Stopped target Sockets. machine # [ 70.262940] systemd[1030]: Stopped target Timers. machine # [ 70.264270] systemd[1030]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 70.266037] systemd[1030]: Closed D-Bus User Message Bus Socket. machine # [ 70.267588] systemd[1030]: Stopped Create User Files and Directories. machine # [ 70.269475] systemd[1030]: Removed slice User Application Slice. machine # [ 70.270993] systemd[1030]: Reached target Shutdown. machine # [ 70.272486] systemd[1030]: Finished Exit the Session. machine # [ 70.273803] systemd[1030]: Reached target Exit the Session. machine # [ 70.292002] systemd-homed[508]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 70.313375] systemd-logind[542]: Removed session 2. machine # [ 70.314815] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 70.324964] systemd-homed[508]: test-homed-user: changing state active → deactivating machine # [ 70.326594] systemd[1]: Stopped User Manager for UID 60388. machine # [ 70.331620] systemd-homework[1250]: Successfully acquired home volume key from kernel keyring. machine # [ 70.333794] systemd-homework[1250]: Discarded unused 674.3M. machine # [ 70.338368] systemd-homework[1250]: Syncing completed. machine # [ 70.369838] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 70.379783] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 70.432183] systemd-homework[1250]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 70.529213] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 70.538812] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 70.545836] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 70.583932] systemd-homework[1250]: Successfully re-activated LUKS device. machine # [ 70.587166] systemd-homework[1250]: Discovered used loopback device /dev/loop1. machine # [ 70.590503] systemd-homework[1250]: offset = 1048576, size = 800063488, image = 802160640 machine # [ 70.602981] systemd-homework[1250]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M. machine # [ 70.606507] systemd-homework[1250]: Allocated additional 674.3M. machine # [ 70.612782] systemd-homework[1250]: File system resizing from 747M to 256M completed. machine # [ 70.631182] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 268435456 machine # [ 70.619786] systemd-homework[1250]: Synchronized disk. machine # [ 70.790338] systemd-homework[1250]: LUKS device shrinking completed. machine # [ 70.818859] loop1: detected capacity change from 1562624 to 557056 machine # [ 70.806056] systemd-homework[1250]: Refreshing loop device size completed. machine # [ 70.807290] systemd-homework[1250]: Shrinking of image file completed. machine # [ 70.823845] systemd-homework[1250]: Shrinking of partition completed. machine # [ 70.825529] systemd-homework[1250]: Synchronized disk. machine # [ 70.845670] BTRFS info (device dm-0): last unmount of filesystem 64ea3196-bd7f-4137-ba47-873ddcb82844 machine # [ 70.832072] systemd-homework[1250]: Resizing completed. machine # [ 70.833009] systemd-homework[1250]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 70.834780] systemd-homework[1250]: Unmounting completed. machine # [ 70.835586] systemd-homework[1250]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 70.838135] systemd-homework[1250]: Device home-test-homed-user is not active. machine # [ 70.868520] systemd-homed[508]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 70.876447] systemd-homework[1250]: Everything completed. machine # [ 70.888884] systemd-homed[508]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.82 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.87 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.79 seconds) sshClient: waiting for Please enter password for user to appear on tty 1 machine # [ 73.692631] systemd-homed[508]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 73.702933] systemd-homework[1286]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 73.717170] systemd-homed[508]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 73.724396] systemd-homed[508]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 73.727082] sshd-session[1282]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 73.730435] systemd-homed[508]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 73.733203] sshd-session[1282]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 73.736443] systemd-homed[508]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 73.738412] sshd-session[1282]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 37286 ssh2: ED25519 SHA256:DqVlABoZ1gFOBvaMVk6uzrJEbNnsZez/zEQ2ZX+euOU machine # [ 73.791391] sshd-session[1282]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 73.808751] systemd-logind[542]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 73.814428] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 73.852447] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 73.862164] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 73.879860] sshd-session[1282]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 73.884625] sshd-session[1282]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 73.906386] systemd-homed[508]: test-homed-user: changing state inactive → activating machine # [ 73.913103] systemd-homework[1291]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 73.941787] systemd-homed[508]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 73.944625] systemd-homed[508]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.18 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 # [ 75.006390] systemd-homed[508]: test-homed-user: changing state inactive → activating machine # [ 75.061791] systemd-homework[1296]: Provided password unlocks user record. machine # [ 75.067608] systemd-homework[1296]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 75.085250] systemd-homework[1296]: Backing file is fully allocated already. machine # [ 75.093968] systemd-homework[1296]: Setting up loopback device /dev/loop1 completed. machine # [ 75.111171] loop1: detected capacity change from 0 to 557056 machine # [ 76.036134] systemd-homework[1296]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 76.076188] systemd-homework[1296]: Provided password unlocks user record. machine # [ 76.082224] systemd-homework[1296]: Probing file system completed (found btrfs). machine # [ 76.084143] systemd-homework[1296]: No fsck for file system btrfs installed, ignoring. machine # [ 76.105131] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1296) machine # [ 76.109584] BTRFS info (device dm-0): first mount of filesystem 64ea3196-bd7f-4137-ba47-873ddcb82844 machine # [ 76.109664] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 76.097473] systemd-homework[1296]: Mounting file system completed. machine # [ 76.115867] BTRFS info (device dm-0): enabling free space tree machine # [ 76.115903] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 76.104239] systemd-homework[1296]: Discovered used loopback device /dev/loop1. machine # [ 76.105371] systemd-homework[1296]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 76.114320] systemd-homework[1296]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M. machine # [ 76.117279] systemd-homework[1296]: Growing of image file from 274M to 765M completed. machine # [ 76.146802] loop1: detected capacity change from 557056 to 1562624 machine # [ 76.133308] systemd-homework[1296]: Refreshing loop device size completed. machine # [ 76.145500] systemd-homework[1296]: Growing of partition completed. machine # [ 76.192806] systemd-homework[1296]: LUKS device growing completed. machine # [ 76.217913] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 783286272 machine # [ 76.202359] systemd-homework[1296]: File system resizing from 256M to 747M completed. machine # [ 76.203691] systemd-homework[1296]: Synchronized disk. machine # [ 76.208278] systemd-homework[1296]: Synchronized disk. machine # [ 76.209131] systemd-homework[1296]: Resizing completed. machine # [ 76.209906] systemd-homework[1296]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 76.211760] systemd-homework[1296]: Read embedded .identity file. machine # [ 76.237017] systemd-homework[1296]: Provided password unlocks user record. machine # [ 76.239291] systemd-homework[1296]: Reconciling user identities completed (host and header version were identical). machine # [ 76.243367] systemd-homework[1296]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 76.248331] systemd-homework[1296]: Recursive changing of ownership not necessary, skipped. machine # [ 76.249663] systemd-homework[1296]: Synchronized disk. machine # [ 76.250418] systemd-homework[1296]: Moving to final mount point /home/test-homed-user completed. machine # [ 76.272987] systemd-homework[1296]: Activation completed. machine # [ 76.274301] systemd-homework[1296]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 76.291708] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 76.301507] systemd-homed[508]: test-homed-user: changing state activating → active machine # [ 76.320523] systemd[1]: Starting User Manager for UID 60388... machine # [ 76.362769] (systemd)[1339]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 76.403709] (systemd)[1339]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 76.409903] systemd-logind[542]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 76.439308] systemd[1339]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.553079] systemd[1339]: Queued start job for default target Main User Target. machine # [ 76.557091] systemd[1339]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.561326] systemd[1339]: Created slice User Application Slice. machine # [ 76.564251] systemd[1339]: Started Daily Cleanup of User's Temporary Directories. machine # [ 76.565395] systemd[1339]: Reached target Paths. machine # [ 76.566098] systemd[1339]: Reached target Timers. machine # [ 76.566934] systemd[1339]: Starting D-Bus User Message Bus Socket... machine # [ 76.572128] systemd[1339]: Starting Create User Files and Directories... machine # [ 76.619090] systemd[1339]: Finished Create User Files and Directories. machine # [ 76.623808] systemd[1339]: Listening on D-Bus User Message Bus Socket. machine # [ 76.627188] systemd[1339]: Reached target Sockets. machine # [ 76.627994] systemd[1339]: Reached target Basic System. machine # [ 76.633134] systemd[1]: Started User Manager for UID 60388. machine # [ 76.634031] systemd[1339]: Starting Run user-specific NixOS activation... machine # [ 76.692514] systemd[1339]: Finished Run user-specific NixOS activation. machine # [ 76.693740] systemd[1339]: Reached target Main User Target. machine # [ 76.694660] systemd[1339]: Startup finished in 265ms. 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.06 seconds) machine: must succeed: cat /tmp/5 machine: (finished: must succeed: cat /tmp/5, in 0.03 seconds) sshClient: sending keys 'exit\n' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) sshClient: sending keys 'exit\n' machine # [ 78.466826] sshd-session[1289]: Received disconnect from 2001:db8:1::2 port 37286:11: disconnected by user sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.63 seconds) (finished: run the VM test script, in 81.14 seconds) machine # [ 78.474432] sshd-session[1289]: Disconnected from user test-homed-user 2001:db8:1::2 port 37286 machine # [ 78.483759] sshd-session[1282]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 78.489680] sshd-session[1282]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 78.507542] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 78.512886] systemd-logind[542]: Session 3 logged out. Waiting for processes to exit. machine # [ 78.514832] systemd-logind[542]: Removed session 3. sshClient # [ 78.529644] login[645]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 78.540723] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 78.556291] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 78.559319] systemd[1]: session-1.scope: Consumed 155ms CPU time over 37.790s wall clock time, 11M memory peak, 12K written to disk, 25.9K incoming IP traffic, 24.3K outgoing IP traffic. sshClient # [ 78.569453] systemd-logind[470]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 78.576848] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 78.581684] systemd[1]: Started Getty on tty1. sshClient # [ 78.588777] systemd-logind[470]: Removed session 1. test script finished in 81.37s 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-23T03:07:13Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-23T03:07:13Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-23T03:07:13Z 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-23T03:07:14Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-10-23T03:07:14Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-10-23T03:07:14Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 1.15 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