tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.01 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm sshClient: QEMU running (pid 48) machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 47) machine # Formatting '/build/vm-state-machine/tmp.YaBw39dqnF', fmt=raw size=1073741824 sshClient # Disk image does not exist, creating the virtualisation disk image... machine # mke2fs 1.47.4 (6-Mar-2025) sshClient # Formatting '/build/vm-state-sshClient/tmp.t0xnDDOHl8', fmt=raw size=1073741824 machine # Discarding device blocks: 0/262144 done sshClient # mke2fs 1.47.4 (6-Mar-2025) machine # Creating filesystem with 262144 4k blocks and 65536 inodes sshClient # Discarding device blocks: 0/262144 done sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes (finished: start all VMs, in 0.54 seconds) sshClient # Filesystem UUID: cc0b0364-0557-489e-8107-ab2509587be7 subtest: create systemd-homed user on first boot prompt sshClient # Superblock backups stored on blocks: machine: waiting for unit systemd-homed.service sshClient # 32768, 98304, 163840, 229376 machine: waiting for the VM to finish booting sshClient # machine # Filesystem UUID: bfbe21ec-e619-4204-98a0-d71cb902165d sshClient # Allocating group tables: 0/8 done sshClient # Writing inode tables: 0/8 done machine # Superblock backups stored on blocks: sshClient # Creating journal (8192 blocks): done machine # 32768, 98304, 163840, 229376 sshClient # Writing superblocks and filesystem accounting information: 0/8 done machine # sshClient # machine # Allocating group tables: 0/8 done sshClient # Virtualisation disk image created. machine # Writing inode tables: 0/8 done sshClient # Starting virtiofs daemons... machine # Creating journal (8192 blocks): done sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # Writing superblocks and filesystem accounting information: 0/8 done sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # Virtualisation disk image created. sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # Starting virtiofs daemons... sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] 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 sshClient # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 sshClient # [ 0.000000] KASLR enabled machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) sshClient # [ 0.000000] random: crng init done sshClient # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 sshClient # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] Detected PIPT I-cache on CPU0 sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] sshClient # [ 0.000000] Zone ranges: machine # [ 0.000000] CPU features: detected: Spectre-BHB sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 sshClient # [ 0.000000] DMA32 empty sshClient # [ 0.000000] Normal empty machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 sshClient # [ 0.000000] Device empty machine # [ 0.000000] alternatives: applying boot alternatives sshClient # [ 0.000000] Movable zone start for each node sshClient # [ 0.000000] Early memory node ranges sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 sshClient # [ 0.000000] psci: probing for conduit method from DT. 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/w3m7w2a54aa7pd9vcms7g47ph9swqc0k-nixos-system-machine-test/init regInfo=/nix/.ro-store/yxyh9jzy7qkmbd5lfmj3w55gi5dzsrn3-closure-info/registration console=ttyAMA0,115200n8 console=tty0 sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware. sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs sshClient # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yxyh9jzy7qkmbd5lfmj3w55gi5dzsrn3-closure-info/registration", will be passed to user space. sshClient # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 sshClient # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface sshClient # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) sshClient # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] Fallback order for Node 0: 0 sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 sshClient # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74966 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. 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/zf77wrprw8y01r1lp74cyz60kjys14wb-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/9cia8mbip88gv21vxd3c78h7cazwl1y3-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/9cia8mbip88gv21vxd3c78h7cazwl1y3-closure-info/registration", will be passed to user space. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) 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] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB sshClient # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) sshClient # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 sshClient # [ 0.000000] Policy zone: DMA machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] Root IRQ handler: gic_handle_irq sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI sshClient # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 sshClient # [ 0.000000] ftrace: allocating 74966 entries in 294 pages machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] sshClient # [ 0.000000] rcu: Hierarchical RCU implementation. sshClient # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) sshClient # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns sshClient # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000037] arm-pv: using stolen time PV sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000693] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000889] Console: colour dummy device 80x25 sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000897] printk: legacy console [tty0] enabled sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.001125] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.001131] pid_max: default: 32768 minimum: 301 sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.001210] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001391] landlock: Up and running. machine # [ 0.001394] Yama: becoming mindful. sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.002189] LSM support for eBPF active sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 machine # [ 0.002360] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 machine # [ 0.002381] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.004341] rcu: Hierarchical SRCU implementation. machine # [ 0.004347] rcu: Max phase no-delay instances is 1000. sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.005678] fsl-mc MSI: its@8080000 domain created sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.005798] EFI services will not be available. machine # [ 0.005920] smp: Bringing up secondary CPUs ... machine # [ 0.005929] smp: Brought up 1 node, 1 CPU sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.005932] SMP: Total of 1 processors activated. machine # [ 0.005935] CPU: All CPU(s) started at EL1 sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns sshClient # [ 0.000033] arm-pv: using stolen time PV machine # [ 0.005949] CPU features: detected: Branch Target Identification machine # [ 0.005954] CPU features: detected: ARMv8.4 Translation Table Level sshClient # [ 0.000562] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.000752] Console: colour dummy device 80x25 machine # [ 0.005957] CPU features: detected: Instruction cache invalidation not required for I/D coherence sshClient # [ 0.000761] printk: legacy console [tty0] enabled machine # [ 0.005961] CPU features: detected: Data cache clean to the PoU not required for I/D coherence sshClient # [ 0.000976] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.005965] CPU features: detected: Common not Private translations sshClient # [ 0.000983] pid_max: default: 32768 minimum: 301 machine # [ 0.005968] CPU features: detected: CRC32 instructions sshClient # [ 0.001067] LSM: initializing lsm=capability,landlock,yama,bpf,ima sshClient # [ 0.001283] landlock: Up and running. machine # [ 0.005971] CPU features: detected: Data cache clean to Point of Deep Persistence sshClient # [ 0.001286] Yama: becoming mindful. machine # [ 0.005974] CPU features: detected: Data cache clean to Point of Persistence sshClient # [ 0.001866] LSM support for eBPF active machine # [ 0.005977] CPU features: detected: Data independent timing control (DIT) sshClient # [ 0.001997] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.005980] CPU features: detected: E0PD sshClient # [ 0.002018] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.005982] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.003995] rcu: Hierarchical SRCU implementation. machine # [ 0.005985] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) sshClient # [ 0.004028] rcu: Max phase no-delay instances is 1000. machine # [ 0.005989] CPU features: detected: Enhanced Virtualization Traps sshClient # [ 0.005277] fsl-mc MSI: its@8080000 domain created sshClient # [ 0.005389] EFI services will not be available. machine # [ 0.005992] CPU features: detected: Fine Grained Traps sshClient # [ 0.005486] smp: Bringing up secondary CPUs ... sshClient # [ 0.005495] smp: Brought up 1 node, 1 CPU machine # [ 0.005996] CPU features: detected: Generic authentication (architected QARMA5 algorithm) sshClient # [ 0.005498] SMP: Total of 1 processors activated. machine # [ 0.006001] CPU features: detected: RCpc load-acquire (LDAPR) sshClient # [ 0.005501] CPU: All CPU(s) started at EL1 machine # [ 0.006004] CPU features: detected: LSE atomic instructions sshClient # [ 0.005514] CPU features: detected: Branch Target Identification machine # [ 0.006007] CPU features: detected: Privileged Access Never machine # [ 0.006010] CPU features: detected: PMUv3 sshClient # [ 0.005519] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.006012] CPU features: detected: RAS Extension Support sshClient # [ 0.005522] CPU features: detected: Instruction cache invalidation not required for I/D coherence sshClient # [ 0.005525] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.006015] CPU features: detected: RASv1p1 Extension Support machine # [ 0.006018] CPU features: detected: Random Number Generator sshClient # [ 0.005529] CPU features: detected: Common not Private translations sshClient # [ 0.005532] CPU features: detected: CRC32 instructions machine # [ 0.006020] CPU features: detected: Speculation barrier (SB) machine # [ 0.006023] CPU features: detected: Stage-2 Force Write-Back sshClient # [ 0.005535] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.006026] CPU features: detected: TLB range maintenance instructions sshClient # [ 0.005539] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.006031] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.006071] alternatives: applying system-wide alternatives sshClient # [ 0.005542] CPU features: detected: Data independent timing control (DIT) machine # [ 0.009045] CPU features: detected: BBM Level 2 without TLB conflict abort sshClient # [ 0.005545] CPU features: detected: E0PD sshClient # [ 0.005547] CPU features: detected: Enhanced Counter Virtualization sshClient # [ 0.005550] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.009248] Memory: 893748K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 113552K reserved, 32768K cma-reserved) machine # [ 0.009718] devtmpfs: initialized sshClient # [ 0.005554] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.011649] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.005557] CPU features: detected: Fine Grained Traps sshClient # [ 0.005561] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.011672] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.005566] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.011868] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.011876] 0 pages in range for non-PLT usage sshClient # [ 0.005569] CPU features: detected: LSE atomic instructions machine # [ 0.011878] 508224 pages in range for PLT usage sshClient # [ 0.005572] CPU features: detected: Privileged Access Never sshClient # [ 0.005574] CPU features: detected: PMUv3 machine # [ 0.012011] pinctrl core: initialized pinctrl subsystem machine # [ 0.012835] DMI not present or invalid. sshClient # [ 0.005577] CPU features: detected: RAS Extension Support machine # [ 0.016319] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.005580] CPU features: detected: RASv1p1 Extension Support sshClient # [ 0.005583] CPU features: detected: Random Number Generator machine # [ 0.018750] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.005585] CPU features: detected: Speculation barrier (SB) machine # [ 0.018913] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.005588] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.019074] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.005591] CPU features: detected: TLB range maintenance instructions machine # [ 0.019100] audit: initializing netlink subsys (disabled) sshClient # [ 0.005596] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.019707] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.005636] alternatives: applying system-wide alternatives machine # [ 0.019709] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.008637] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.019712] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.019715] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.008827] Memory: 893768K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 113536K reserved, 32768K cma-reserved) machine # [ 0.019718] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.009180] devtmpfs: initialized machine # [ 0.019746] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.011038] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.019754] cpuidle: using governor ladder machine # [ 0.019760] cpuidle: using governor menu sshClient # [ 0.011061] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.019972] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. sshClient # [ 0.011245] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.019989] ASID allocator initialised with 65536 entries sshClient # [ 0.011253] 0 pages in range for non-PLT usage machine # [ 0.021242] Serial: AMBA PL011 UART driver sshClient # [ 0.011254] 508224 pages in range for PLT usage sshClient # [ 0.011360] pinctrl core: initialized pinctrl subsystem machine # [ 0.027170] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 sshClient # [ 0.012214] DMI not present or invalid. machine # [ 0.027316] printk: console [ttyAMA0] enabled sshClient # [ 0.015532] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.017870] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.018022] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.018183] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.018205] audit: initializing netlink subsys (disabled) sshClient # [ 0.018899] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.018902] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.018906] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.018909] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.018912] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.018941] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.018949] cpuidle: using governor ladder sshClient # [ 0.018955] cpuidle: using governor menu sshClient # [ 0.019153] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.158766] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages sshClient # [ 0.019168] ASID allocator initialised with 65536 entries machine # [ 0.158777] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page sshClient # [ 0.020414] Serial: AMBA PL011 UART driver machine # [ 0.158780] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages sshClient # [ 0.026030] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.158783] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page sshClient # [ 0.026200] printk: console [ttyAMA0] enabled machine # [ 0.158786] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.158789] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.158792] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.158795] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.160011] fbcon: Taking over console machine # [ 0.160021] ACPI: Interpreter disabled. machine # [ 0.170345] iommu: Default domain type: Translated machine # [ 0.170352] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.170835] SCSI subsystem initialized machine # [ 0.172981] usbcore: registered new interface driver usbfs machine # [ 0.173010] usbcore: registered new interface driver hub machine # [ 0.173027] usbcore: registered new device driver usb sshClient # [ 0.158018] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.173327] pps_core: LinuxPPS API ver. 1 registered sshClient # [ 0.158031] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.173331] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.158034] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.173339] PTP clock support registered sshClient # [ 0.158037] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.173377] EDAC MC: Ver: 3.0.0 machine # [ 0.174881] scmi_core: SCMI protocol bus registered sshClient # [ 0.158040] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.158043] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.158046] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages sshClient # [ 0.158049] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.180807] FPGA manager framework sshClient # [ 0.159271] fbcon: Taking over console machine # [ 0.183085] vgaarb: loaded sshClient # [ 0.159281] ACPI: Interpreter disabled. sshClient # [ 0.169607] iommu: Default domain type: Translated sshClient # [ 0.169614] iommu: DMA domain TLB invalidation policy: strict mode sshClient # [ 0.170041] SCSI subsystem initialized machine # [ 0.184219] clocksource: Switched to clocksource arch_sys_counter sshClient # [ 0.172225] usbcore: registered new interface driver usbfs sshClient # [ 0.172254] usbcore: registered new interface driver hub sshClient # [ 0.172270] usbcore: registered new device driver usb sshClient # [ 0.172567] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.190708] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.172570] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti sshClient # [ 0.172578] PTP clock support registered machine # [ 0.190741] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.172618] EDAC MC: Ver: 3.0.0 machine # [ 0.191080] netfs: FS-Cache loaded machine # [ 0.191193] pnp: PnP ACPI: disabled sshClient # [ 0.179117] scmi_core: SCMI protocol bus registered sshClient # [ 0.179507] FPGA manager framework sshClient # [ 0.180076] vgaarb: loaded sshClient # [ 0.180360] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.198904] NET: Registered PF_INET protocol family machine # [ 0.199074] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.189879] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.189917] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.193847] netfs: FS-Cache loaded sshClient # [ 0.193990] pnp: PnP ACPI: disabled sshClient # [ 0.198098] NET: Registered PF_INET protocol family sshClient # [ 0.198263] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.229839] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.229880] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.229905] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.229949] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.230022] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.230104] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.230136] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.230184] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.230263] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.230329] NET: Registered PF_XDP protocol family machine # [ 0.230346] PCI: CLS 0 bytes, default 64 machine # [ 0.230706] Trying to unpack rootfs image as initramfs... machine # [ 0.242767] kvm [1]: HYP mode not available sshClient # [ 0.229961] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.230009] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.230037] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.230081] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.230154] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.230240] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) sshClient # [ 0.230297] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.230343] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.230418] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.230440] NET: Registered PF_XDP protocol family sshClient # [ 0.230456] PCI: CLS 0 bytes, default 64 sshClient # [ 0.230746] Trying to unpack rootfs image as initramfs... sshClient # [ 0.248390] kvm [1]: HYP mode not available machine # [ 0.369520] Initialise system trusted keyrings machine # [ 0.369660] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.370049] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.370119] 9p: Installing v9fs 9p2000 file system support sshClient # [ 0.364914] Initialise system trusted keyrings sshClient # [ 0.365061] workingset: timestamp_bits=42 max_order=18 bucket_order=0 sshClient # [ 0.365470] squashfs: version 4.0 (2009/01/31) Phillip Lougher sshClient # [ 0.365526] 9p: Installing v9fs 9p2000 file system support machine # [ 0.398334] Key type asymmetric registered machine # [ 0.398351] Asymmetric key parser 'x509' registered machine # [ 0.398430] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.398589] io scheduler mq-deadline registered machine # [ 0.398593] io scheduler kyber registered sshClient # [ 0.393759] Key type asymmetric registered sshClient # [ 0.393774] Asymmetric key parser 'x509' registered machine # [ 0.411721] pl061_gpio 9030000.pl061: PL061 GPIO chip registered sshClient # [ 0.393849] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) sshClient # [ 0.394006] io scheduler mq-deadline registered sshClient # [ 0.394010] io scheduler kyber registered sshClient # [ 0.406461] pl061_gpio 9030000.pl061: PL061 GPIO chip registered sshClient # [ 0.407074] ledtrig-cpu: registered to indicate activity on CPUs sshClient # [ 0.407448] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: sshClient # [ 0.407462] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 sshClient # [ 0.407473] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 sshClient # [ 0.407478] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 sshClient # [ 0.407511] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits sshClient # [ 0.407531] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] sshClient # [ 0.407604] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.420638] ledtrig-cpu: registered to indicate activity on CPUs sshClient # [ 0.407611] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.407615] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.421121] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: sshClient # [ 0.407618] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.421140] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 sshClient # [ 0.407621] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.421152] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 sshClient # [ 0.407719] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.421158] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 sshClient # [ 0.408174] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.421190] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.421216] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.421315] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.421324] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.421328] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.421331] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.421334] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.421455] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.422005] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.422207] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.422220] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.428477] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.422247] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.428505] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.422261] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.428533] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.428545] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.422767] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.422963] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.429135] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.422976] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.429338] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.429349] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.423003] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.423523] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.429375] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.423721] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] sshClient # [ 0.429872] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.423733] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.430062] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.423761] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.430074] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.430100] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.424308] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.424512] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.430601] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.424525] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.430814] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.424552] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.430825] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.424564] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] sshClient # [ 0.430851] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.425076] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint sshClient # [ 0.430863] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.425275] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.431364] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.425302] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.431563] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.431589] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.425925] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.426129] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.432078] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.426156] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.432273] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.432299] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.426566] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.426752] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.427040] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.427232] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.427258] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.427755] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.427945] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.427971] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.428484] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.428684] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.428711] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.429199] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.429487] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.429499] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.429535] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.452417] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.430025] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.452606] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.430212] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] sshClient # [ 0.452893] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.430224] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.453084] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.430249] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] sshClient # [ 0.453110] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.430858] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned sshClient # [ 0.453583] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.430865] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned sshClient # [ 0.453774] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.430868] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned sshClient # [ 0.453799] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.430916] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned sshClient # [ 0.454272] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.430959] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned sshClient # [ 0.454462] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.454487] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.431002] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned sshClient # [ 0.455038] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.431046] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned sshClient # [ 0.455310] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.431089] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned sshClient # [ 0.455322] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] sshClient # [ 0.455347] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.431133] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned sshClient # [ 0.455825] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.431177] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned sshClient # [ 0.456011] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.431222] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned sshClient # [ 0.456022] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.431265] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned sshClient # [ 0.456047] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.431367] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.431410] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.431431] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.431449] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned sshClient # [ 0.481965] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.431467] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned sshClient # [ 0.481980] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.431485] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.481984] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.431503] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.482034] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.431521] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned sshClient # [ 0.482081] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.431542] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned sshClient # [ 0.482127] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.431565] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned sshClient # [ 0.482174] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.431584] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.431602] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.482218] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.431621] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.482264] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.431639] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned sshClient # [ 0.482311] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.431659] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned sshClient # [ 0.482356] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.431677] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned sshClient # [ 0.482401] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.431694] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.431712] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned sshClient # [ 0.482484] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.431729] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned sshClient # [ 0.482596] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.431750] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] sshClient # [ 0.482615] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.431756] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] sshClient # [ 0.482632] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.431758] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] sshClient # [ 0.482649] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned sshClient # [ 0.482672] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned sshClient # [ 0.482689] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned sshClient # [ 0.482708] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned sshClient # [ 0.482727] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned sshClient # [ 0.482749] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned sshClient # [ 0.482767] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned sshClient # [ 0.482785] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned sshClient # [ 0.482803] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned sshClient # [ 0.482820] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned sshClient # [ 0.482837] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned sshClient # [ 0.482854] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned sshClient # [ 0.482871] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned sshClient # [ 0.482888] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned sshClient # [ 0.482905] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned sshClient # [ 0.482927] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.524792] pci 0000:00:07.0: enabling device (0000 -> 0002) sshClient # [ 0.482932] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] sshClient # [ 0.482935] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] sshClient # [ 0.483810] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.555706] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) sshClient # [ 0.543398] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.559156] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) sshClient # [ 0.546863] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.569769] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.571849] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) sshClient # [ 0.557039] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) sshClient # [ 0.559118] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.576001] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) sshClient # [ 0.563077] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.586056] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.587869] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) sshClient # [ 0.573233] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) sshClient # [ 0.575073] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.591641] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) sshClient # [ 0.578626] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.594397] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) sshClient # [ 0.588657] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) sshClient # [ 0.590838] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.604497] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.606943] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) sshClient # [ 0.594746] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) sshClient # [ 0.605607] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.607579] msm_serial: driver initialized machine # [ 0.621109] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.607711] SuperH (H)SCI(F) driver initialized machine # [ 0.623086] msm_serial: driver initialized sshClient # [ 0.607766] STM32 USART driver initialized machine # [ 0.623238] SuperH (H)SCI(F) driver initialized machine # [ 0.623301] STM32 USART driver initialized sshClient # [ 0.646296] loop: module loaded sshClient # [ 0.646514] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.663708] loop: module loaded sshClient # [ 0.647626] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.663905] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.666172] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 0.653075] megasas: 07.734.00.00-rc1 sshClient # [ 0.653795] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.670130] megasas: 07.734.00.00-rc1 machine # [ 0.670993] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] sshClient # [ 0.669589] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.669615] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.671269] Using buffer write method sshClient # [ 0.671314] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.693480] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.693548] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.695341] Using buffer write method machine # [ 0.695392] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] sshClient # [ 0.685173] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.685199] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.687059] Using buffer write method machine # [ 0.700949] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 sshClient # [ 0.687082] Concatenating MTD devices: sshClient # [ 0.687085] (0): "0.flash" machine # [ 0.700967] Intel/Sharp Extended Query Table at 0x0031 sshClient # [ 0.687088] (1): "0.flash" machine # [ 0.703197] Using buffer write method sshClient # [ 0.687090] into device "0.flash" machine # [ 0.703222] Concatenating MTD devices: machine # [ 0.703224] (0): "0.flash" machine # [ 0.703227] (1): "0.flash" machine # [ 0.703229] into device "0.flash" sshClient # [ 0.988123] Freeing initrd memory: 26424K sshClient # [ 0.994768] tun: Universal TUN/TAP device driver, 1.6 sshClient # [ 0.998813] thunder_xcv, ver 1.0 sshClient # [ 0.998843] thunder_bgx, ver 1.0 sshClient # [ 0.998863] nicpf, ver 1.0 sshClient # [ 0.999444] e1000: Intel(R) PRO/1000 Network Driver sshClient # [ 0.999448] e1000: Copyright (c) 1999-2006 Intel Corporation. sshClient # [ 0.999474] e1000e: Intel(R) PRO/1000 Network Driver sshClient # [ 0.999480] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. sshClient # [ 0.999504] igb: Intel(R) Gigabit Ethernet Network Driver sshClient # [ 0.999508] igb: Copyright (c) 2007-2014 Intel Corporation. sshClient # [ 0.999528] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 0.999531] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. sshClient # [ 0.999673] sky2: driver version 1.30 sshClient # [ 1.009207] usbcore: registered new interface driver usb-storage sshClient # [ 1.009297] usbcore: registered new interface driver usbserial_generic sshClient # [ 1.009307] usbserial: USB Serial support registered for generic sshClient # [ 1.009940] hv_vmbus: registering driver hyperv_keyboard sshClient # [ 1.011379] ehci-pci 0000:00:07.0: EHCI Host Controller sshClient # [ 1.011401] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 sshClient # [ 1.011621] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 sshClient # [ 1.011920] rtc-pl031 9010000.pl031: registered as rtc0 sshClient # [ 1.011945] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T03:39:15 UTC (1790739555) sshClient # [ 1.012298] i2c_dev: i2c /dev entries driver sshClient # [ 1.020389] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 sshClient # [ 1.020765] hub 1-0:1.0: USB hub found sshClient # [ 1.020780] hub 1-0:1.0: 6 ports detected sshClient # [ 1.026370] sdhci: Secure Digital Host Controller Interface driver sshClient # [ 1.026377] sdhci: Copyright(c) Pierre Ossman sshClient # [ 1.026686] Synopsys Designware Multimedia Card Interface Driver sshClient # [ 1.027095] sdhci-pltfm: SDHCI platform and OF driver helper sshClient # [ 1.031694] hid: raw HID events driver (C) Jiri Kosina sshClient # [ 1.031931] usbcore: registered new interface driver usbhid sshClient # [ 1.031940] usbhid: USB HID core driver sshClient # [ 1.035094] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available sshClient # [ 1.037964] drop_monitor: Initializing network drop monitor service sshClient # [ 1.038161] NET: Registered PF_INET6 protocol family sshClient # [ 1.038797] Segment Routing with IPv6 sshClient # [ 1.038814] In-situ OAM (IOAM) with IPv6 sshClient # [ 1.038842] NET: Registered PF_PACKET protocol family machine # [ 1.055959] Freeing initrd memory: 26440K sshClient # [ 1.038902] 9pnet: Installing 9P2000 support sshClient # [ 1.038941] Key type dns_resolver registered machine # [ 1.063494] tun: Universal TUN/TAP device driver, 1.6 sshClient # [ 1.049662] registered taskstats version 1 sshClient # [ 1.049827] Loading compiled-in X.509 certificates machine # [ 1.068018] thunder_xcv, ver 1.0 machine # [ 1.068056] thunder_bgx, ver 1.0 machine # [ 1.068082] nicpf, ver 1.0 machine # [ 1.070295] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.070302] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.070332] e1000e: Intel(R) PRO/1000 Network Driver sshClient # [ 1.058886] Demotion targets for Node 0: null machine # [ 1.070342] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. sshClient # [ 1.059015] Key type .fscrypt registered machine # [ 1.070376] igb: Intel(R) Gigabit Ethernet Network Driver sshClient # [ 1.059023] Key type fscrypt-provisioning registered sshClient # [ 1.059132] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.070380] igb: Copyright (c) 2007-2014 Intel Corporation. sshClient # [ 1.059149] ima: Allocated hash algorithm: sha1 machine # [ 1.070407] igbvf: Intel(R) Gigabit Virtual Function Network Driver sshClient # [ 1.059170] ima: No architecture policies found machine # [ 1.070411] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. sshClient # [ 1.059904] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.070585] sky2: driver version 1.30 machine # [ 1.072946] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 1.072993] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 1.073218] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.083097] usbcore: registered new interface driver usb-storage machine # [ 1.083245] usbcore: registered new interface driver usbserial_generic machine # [ 1.083260] usbserial: USB Serial support registered for generic machine # [ 1.084055] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.087587] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 1.087984] hub 1-0:1.0: USB hub found machine # [ 1.088006] hub 1-0:1.0: 6 ports detected machine # [ 1.091327] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.091355] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T03:39:15 UTC (1790739555) machine # [ 1.091762] i2c_dev: i2c /dev entries driver sshClient # [ 1.083009] clk: Disabling unused clocks sshClient # [ 1.083028] PM: genpd: Disabling unused power domains machine # [ 1.098448] sdhci: Secure Digital Host Controller Interface driver machine # [ 1.098455] sdhci: Copyright(c) Pierre Ossman machine # [ 1.098762] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.099178] sdhci-pltfm: SDHCI platform and OF driver helper sshClient # [ 1.087329] Freeing unused kernel memory: 4736K sshClient # [ 1.087548] Run /init as init process machine # [ 1.103859] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.104120] usbcore: registered new interface driver usbhid machine # [ 1.104129] usbhid: USB HID core driver machine # [ 1.107343] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.110762] drop_monitor: Initializing network drop monitor service machine # [ 1.110976] NET: Registered PF_INET6 protocol family machine # [ 1.111645] Segment Routing with IPv6 machine # [ 1.111659] In-situ OAM (IOAM) with IPv6 machine # [ 1.111690] NET: Registered PF_PACKET protocol family sshClient # [ 1.099033] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.111767] 9pnet: Installing 9P2000 support machine # [ 1.111815] Key type dns_resolver registered sshClient # [ 1.099475] systemd[1]: systemd 263-889bc48 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) sshClient # [ 1.099489] systemd[1]: Detected virtualization qemu. sshClient # [ 1.099528] systemd[1]: Detected architecture arm64. sshClient # [ 1.099533] systemd[1]: Running in initrd. sshClient # [ 1.099815] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.099913] systemd[1]: Hostname set to . machine # [ 1.124063] registered taskstats version 1 machine # [ 1.124839] Loading compiled-in X.509 certificates machine # [ 1.134098] Demotion targets for Node 0: null machine # [ 1.134230] Key type .fscrypt registered machine # [ 1.134238] Key type fscrypt-provisioning registered machine # [ 1.134362] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.134381] ima: Allocated hash algorithm: sha1 machine # [ 1.134408] ima: No architecture policies found machine # [ 1.135254] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.159029] clk: Disabling unused clocks machine # [ 1.159047] PM: genpd: Disabling unused power domains machine # [ 1.163716] Freeing unused kernel memory: 4736K machine # [ 1.163937] Run /init as init process machine # [ 1.183232] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.183772] systemd[1]: systemd 263-889bc48 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.183787] systemd[1]: Detected virtualization qemu. machine # [ 1.183829] systemd[1]: Detected architecture arm64. machine # [ 1.183835] systemd[1]: Running in initrd. machine # [ 1.184158] systemd[1]: Initializing machine ID from random generator. machine # [ 1.192271] systemd[1]: Hostname set to . sshClient # [ 1.207212] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.263761] systemd[1]: Queued start job for default target Initrd Default Target. sshClient # [ 1.267662] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.267839] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.267865] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.267884] systemd[1]: Reached target Path Units. sshClient # [ 1.267895] systemd[1]: Reached target Slice Units. sshClient # [ 1.267908] systemd[1]: Reached target Swaps. sshClient # [ 1.267920] systemd[1]: Reached target Timer Units. sshClient # [ 1.268045] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.268164] systemd[1]: Listening on Journal Socket (/dev/log). sshClient # [ 1.268321] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.268455] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 1.277308] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.277341] systemd[1]: Reached target Socket Units. sshClient # [ 1.279074] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.279136] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.299548] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.291561] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 1.312595] systemd[1]: Starting Journal Service... sshClient # [ 1.323655] systemd[1]: Starting Load Kernel Modules... sshClient # [ 1.323779] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.340459] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 1.332614] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.360526] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.368064] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.348576] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 1.349498] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.369136] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.369171] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.369196] systemd[1]: Reached target Path Units. machine # [ 1.369210] systemd[1]: Reached target Slice Units. sshClient # [ 1.358602] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.369225] systemd[1]: Reached target Swaps. machine # [ 1.369239] systemd[1]: Reached target Timer Units. machine # [ 1.369433] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.369617] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.369775] systemd[1]: Listening on Journal Sockets. machine # [ 1.369878] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.369894] systemd[1]: Reached target Socket Units. machine # [ 1.371791] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.371869] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.392562] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.401689] systemd[1]: Starting Journal Service... sshClient # [ 1.401165] systemd-journald[71]: Collecting audit messages is disabled. sshClient # [ 1.411139] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.433026] systemd[1]: Starting Load Kernel Modules... machine # [ 1.433215] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 1.423587] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.427249] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.430065] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 1.431326] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.460049] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.460587] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.457713] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 sshClient # [ 1.457972] [drm] features: -virgl +edid -resource_blob -host_visible sshClient # [ 1.457975] [drm] features: -context_init sshClient # [ 1.458780] [drm] number of scanouts: 1 sshClient # [ 1.458791] [drm] number of cap sets: 0 machine # [ 1.478515] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 1.473035] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic sshClient # [ 1.473052] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.496603] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.497155] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 1.497218] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.497451] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.497475] systemd[1]: Reached target Local File Systems. sshClient # [ 1.503317] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.505828] Console: switching to colour frame buffer device 160x50 machine # [ 1.522098] 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.524623] 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.525453] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device sshClient # [ 1.540789] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.542587] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.581735] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.593223] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.595442] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.596845] systemd[1]: Started Journal Service. sshClient # [ 1.585098] systemd-modules-load[72]: Inserted module 'dm_mod' sshClient # [ 1.586436] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.608578] 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.612449] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.600463] systemd-modules-load[72]: Module 'virtio_console' is built in sshClient # [ 1.601724] systemd-modules-load[72]: Inserted module 'virtio_gpu' sshClient # [ 1.602870] systemd-modules-load[72]: Module 'virtio_rng' is built in sshClient # [ 1.603891] systemd[1]: Starting Create System Files and Directories... machine # [ 1.640273] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 sshClient # [ 1.613274] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.640633] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.640639] [drm] features: -context_init machine # [ 1.641873] [drm] number of scanouts: 1 machine # [ 1.641890] [drm] number of cap sets: 0 machine # [ 1.644120] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.644137] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 sshClient # [ 1.622193] systemd-udevd[78]: Using default interface naming scheme 'v261'. sshClient # [ 1.648492] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.688966] Console: switching to colour frame buffer device 160x50 sshClient # [ 1.664263] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.670462] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.701725] systemd[1]: Started Journal Service. machine # [ 1.693063] systemd-modules-load[73]: Inserted module 'dm_mod' machine # [ 1.694747] systemd-modules-load[73]: Module 'virtio_balloon' is built in machine # [ 1.715023] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.706069] systemd-modules-load[73]: Module 'virtio_console' is built in machine # [ 1.714124] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.728314] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.721680] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.724666] systemd[1]: Reached target Local File Systems. machine # [ 1.727392] systemd[1]: Starting Create System Files and Directories... machine # [ 1.735215] systemd-modules-load[73]: Inserted module 'virtio_gpu' machine # [ 1.736445] systemd-modules-load[73]: Module 'virtio_rng' is built in machine # [ 1.740673] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.742830] systemd[1]: Finished Load Kernel Modules. machine # [ 1.762476] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 1.764560] systemd[1]: Starting Virtual Console Setup... machine # [ 1.805083] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.818462] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.821951] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.818747] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 machine # [ 1.832751] systemd-udevd[79]: Using default interface naming scheme 'v261'. machine # [ 1.862573] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.850216] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.887019] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 sshClient # [ 1.860410] systemd[1]: Finished Virtual Console Setup. machine # [ 1.887307] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 machine # [ 1.968138] systemd[1]: Starting Virtual Console Setup... machine # [ 2.056577] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.064209] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.525439] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 2.526483] systemd[1]: Reached target System Initialization. sshClient # [ 2.527351] systemd[1]: Reached target Basic System. sshClient # [ 2.678087] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.696228] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.768838] systemd[1]: Found device /dev/disk/by-label/nixos. sshClient # [ 2.772099] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.776245] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.791836] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.792953] systemd[1]: Reached target System Initialization. machine # [ 2.793990] systemd[1]: Reached target Basic System. sshClient # [ 2.833800] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.928069] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.967210] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.972335] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 2.987265] systemd[1]: Mounting /sysroot... machine # [ 3.041473] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.043739] systemd[1]: Reached target Initrd Root Device. machine # [ 3.048222] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.103306] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.175086] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 3.175929] EXT4-fs (vda): mounted filesystem cc0b0364-0557-489e-8107-ab2509587be7 r/w with ordered data mode. Quota mode: none. sshClient # [ 3.165239] systemd[1]: Mounted /sysroot. sshClient # [ 3.167411] systemd[1]: Reached target Initrd Root File System. machine # [ 3.186833] systemd[1]: Mounting /sysroot... sshClient # [ 3.173669] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 3.207965] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.237895] EXT4-fs (vda): mounted filesystem bfbe21ec-e619-4204-98a0-d71cb902165d r/w with ordered data mode. Quota mode: none. machine # [ 3.227039] systemd[1]: Mounted /sysroot. sshClient # [ 3.213447] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.230015] systemd[1]: Reached target Initrd Root File System. sshClient # [ 3.218575] systemd[1]: Reloading... machine # [ 3.235739] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.269131] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.274890] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.280901] systemd[1]: Reloading... sshClient # [ 3.423593] systemd[1]: Reloading finished in 206 ms. sshClient # [ 3.459409] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.463925] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace... sshClient # [ 3.471116] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.493709] systemd[1]: Reloading finished in 215 ms. sshClient # [ 3.491012] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.497585] systemd[1]: Mounting /sysroot/run... machine # [ 3.526400] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.514879] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.532183] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace... machine # [ 3.539209] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.552186] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.546620] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 3.549156] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.567073] systemd[1]: Mounting /sysroot/run... machine # [ 3.584161] systemd[1]: Mounting /sysroot/tmp/shared... sshClient # [ 3.582162] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 3.583332] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 3.612241] fuse: init (API version 7.45) machine # [ 3.614205] systemd[1]: Mounting /sysroot/tmp/xchg... sshClient # [ 3.623371] virtiofs virtio6: discovered new tag: nix-store sshClient # [ 3.624249] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 3.614716] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 3.639366] virtiofs virtio7: discovered new tag: shared sshClient # [ 3.640179] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 3.645622] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 3.650744] virtiofs virtio8: discovered new tag: xchg sshClient # [ 3.651576] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 3.656383] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.657865] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 3.665317] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 3.677985] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 3.681100] systemd[1]: Mounted /sysroot/run. machine # [ 3.699153] systemd[1]: Mounted /sysroot/nix/.rw-store. sshClient # [ 3.684260] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 3.702482] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.721450] systemd[1]: Mounted /sysroot/run. machine # [ 3.742757] fuse: init (API version 7.45) sshClient # [ 3.724220] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.753149] virtiofs virtio6: discovered new tag: nix-store machine # [ 3.754188] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 3.726526] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.728723] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.770182] virtiofs virtio7: discovered new tag: shared machine # [ 3.771094] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 3.778542] virtiofs virtio8: discovered new tag: xchg machine # [ 3.779465] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 3.778042] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.800440] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.802239] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 3.787904] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 3.790337] systemd[1]: Reached target Initrd File Systems. sshClient # [ 3.792172] systemd[1]: Starting Find NixOS closure... machine # [ 3.807624] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.813644] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 3.800296] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.817690] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.846840] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 3.862487] systemd[1]: Finished Find NixOS closure. sshClient # [ 3.866579] systemd[1]: Reached target Initrd Default Target. sshClient # [ 3.874612] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 3.910121] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 3.913957] systemd[1]: Stopped target Basic System. sshClient # [ 3.916793] systemd[1]: Stopped target Initrd Root Device. sshClient # [ 3.918508] systemd[1]: Stopped target Path Units. sshClient # [ 3.921098] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. sshClient # [ 3.925415] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. sshClient # [ 3.929605] systemd[1]: Stopped target Slice Units. sshClient # [ 3.932319] systemd[1]: Stopped target Socket Units. sshClient # [ 3.937815] systemd[1]: Stopped target System Initialization. sshClient # [ 3.939522] systemd[1]: Stopped target Swaps. sshClient # [ 3.944285] systemd[1]: Stopped target Timer Units. sshClient # [ 3.945107] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 3.945996] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 3.947027] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 3.956919] systemd[1]: Stopped Find NixOS closure. sshClient # [ 3.958575] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 3.962111] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 3.965010] systemd[1]: Stopped Apply Kernel Variables. sshClient # [ 3.966988] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 3.979658] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 3.980735] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 3.986231] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 3.990969] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 3.997189] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 3.998202] systemd[1]: Stopped target Local File Systems. sshClient # [ 3.999108] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 4.002897] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.003992] systemd[1]: Stopped Coldplug All udev Devices. sshClient # [ 4.004989] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.006126] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.007235] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.019804] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 4.021349] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 4.022488] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.023584] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.024853] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.025898] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.027153] systemd[1]: systemd-udevd.service: Consumed 1.243s CPU time over 2.524s wall clock time, 17.9M memory peak. sshClient # [ 4.034256] systemd[1]: Starting Cleanup udev Database... sshClient # [ 4.035493] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.040588] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 4.041812] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.043188] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.048274] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.049353] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.073823] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. sshClient # [ 4.076506] systemd[1]: Finished Cleanup udev Database. sshClient # [ 4.078890] systemd[1]: Reached target Switch Root. sshClient # [ 4.080680] systemd[1]: Starting NixOS Activation... sshClient # [ 4.179899] initrd-nixos-activation-start[168]: booting system configuration /nix/store/zf77wrprw8y01r1lp74cyz60kjys14wb-nixos-system-sshClient-test sshClient # [ 4.222383] initrd-nixos-activation-start[168]: running activation script... sshClient # [ 4.487277] initrd-nixos-activation-start[191]: setting up /etc... machine # [ 4.535185] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.580227] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.582166] systemd[1]: Reached target Initrd File Systems. machine # [ 4.584188] systemd[1]: Starting Find NixOS closure... machine # [ 4.592918] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.612382] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 4.615452] systemd[1]: Finished NixOS Activation. sshClient # [ 4.616963] systemd[1]: Starting Switch Root... machine # [ 4.644132] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.649027] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 4.641330] systemd[1]: Switching root. machine # [ 4.670462] systemd[1]: Finished Find NixOS closure. machine # [ 4.672228] systemd[1]: Reached target Initrd Default Target. machine # [ 4.678355] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.711104] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.715644] systemd[1]: Stopped target Basic System. machine # [ 4.720235] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.722937] systemd[1]: Stopped target Path Units. machine # [ 4.724642] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.726748] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.730569] systemd[1]: Stopped target Slice Units. machine # [ 4.733465] systemd[1]: Stopped target Socket Units. machine # [ 4.737480] systemd[1]: Stopped target System Initialization. machine # [ 4.740742] systemd[1]: Stopped target Swaps. machine # [ 4.743879] systemd[1]: Stopped target Timer Units. machine # [ 4.749365] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.750350] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.751282] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.757123] systemd[1]: Stopped Find NixOS closure. machine # [ 4.760749] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.764278] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.768538] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.771065] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.773866] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.782104] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.784917] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.791196] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.796602] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.799743] systemd[1]: Stopped target Local File Systems. machine # [ 4.803237] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.805539] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.808603] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.812416] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.813669] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.815535] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.816712] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.817866] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.818838] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.819906] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.820914] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.821884] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.822913] systemd[1]: systemd-udevd.service: Consumed 1.752s CPU time over 3.073s wall clock time, 20.6M memory peak. machine # [ 4.828322] systemd[1]: Starting Cleanup udev Database... machine # [ 4.829308] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.829944] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 4.832084] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.836295] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.837640] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.840163] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.844209] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.874875] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.877224] systemd[1]: Finished Cleanup udev Database. machine # [ 4.880592] systemd[1]: Reached target Switch Root. machine # [ 4.882646] systemd[1]: Starting NixOS Activation... machine # [ 4.969651] initrd-nixos-activation-start[172]: booting system configuration /nix/store/w3m7w2a54aa7pd9vcms7g47ph9swqc0k-nixos-system-machine-test sshClient # [ 4.991488] systemd[1]: systemd 263-889bc48 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) sshClient # [ 4.999952] systemd[1]: Detected virtualization qemu. sshClient # [ 5.000009] systemd[1]: Detected architecture arm64. sshClient # [ 5.000120] systemd[1]: Detected first boot. machine # [ 5.004147] initrd-nixos-activation-start[172]: running activation script... sshClient # [ 5.004573] systemd[1]: Initializing machine ID from random generator. machine # [ 5.229659] initrd-nixos-activation-start[195]: setting up /etc... sshClient # [ 5.231501] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.356186] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.359398] systemd[1]: Finished NixOS Activation. machine # [ 5.360751] systemd[1]: Starting Switch Root... machine # [ 5.385632] systemd[1]: Switching root. sshClient # [ 5.422742] systemd[1]: Applying preset policy. machine # [ 5.571925] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). sshClient # [ 5.671953] systemd[1]: Populated /etc with preset unit settings. machine # [ 5.746691] systemd[1]: systemd 263-889bc48 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 5.747625] systemd[1]: Detected virtualization qemu. machine # [ 5.757366] systemd[1]: Detected architecture arm64. machine # [ 5.757543] systemd[1]: Detected first boot. machine # [ 5.759410] systemd[1]: Initializing machine ID from random generator. sshClient # [ 5.891979] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 5.894010] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 5.898140] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 5.900110] systemd[1]: Created slice Slice /system/getty. sshClient # [ 5.904840] systemd[1]: Created slice User and Session Slice. sshClient # [ 5.905511] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 5.906224] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 5.906916] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 5.907484] systemd[1]: Expecting device /dev/ttyAMA0... sshClient # [ 5.908098] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 5.914396] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 5.914891] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 5.915638] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 5.916285] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 5.920623] systemd[1]: Reached target Path Units. sshClient # [ 5.921066] systemd[1]: Reached target Remote File Systems. sshClient # [ 5.921749] systemd[1]: Reached target Slice Units. sshClient # [ 5.922397] systemd[1]: Reached target Swaps. sshClient # [ 5.927526] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 5.931402] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 5.934094] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 5.935584] systemd[1]: Listening on Factory Reset Management. sshClient # [ 5.936157] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 5.941946] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 5.943750] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 5.948154] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 5.950989] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 5.953843] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 5.955121] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki sshClient # [ 5.961448] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 5.962096] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 5.962965] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 5.968787] systemd[1]: Mounting Huge Pages File System... sshClient # [ 5.972244] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 5.986494] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 5.996989] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.013461] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.014081] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 6.032723] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.033601] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.040039] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.044861] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.079980] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 6.076633] systemd[1]: Mounting FUSE Control File System... sshClient # [ 6.077127] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 6.116603] systemd[1]: Starting Journal Service... sshClient # [ 6.143831] systemd[1]: Starting Load Kernel Modules... sshClient # [ 6.175740] systemd-journald[263]: Collecting audit messages is enabled. sshClient # [ 6.183947] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 6.167096] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 6.172664] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 6.204030] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 6.215251] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.239834] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 6.263756] systemd[1]: Started Journal Service. machine # [ 6.276325] NET: Registered PF_VSOCK protocol family machine # [ 6.283694] Guest personality initialized and is inactive machine # [ 6.284055] VMCI host device registered (name=vmci, major=10, minor=261) machine # [ 6.287563] Initialized host personality sshClient # [ 6.260726] systemd-modules-load[264]: Module 'atkbd' is built in sshClient # [ 6.266011] systemd-modules-load[264]: Module 'loop' is built in sshClient # [ 6.275747] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 6.278711] systemd[1]: Mounted Huge Pages File System. sshClient # [ 6.279552] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 6.288579] systemd[1]: Mounted Kernel Debug File System. sshClient # [ 6.289492] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 6.290501] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 6.291513] systemd[1]: Finished Load Kernel Modules. machine # [ 6.345140] systemd[1]: Applying preset policy. sshClient # [ 6.315605] systemd[1]: Mounted Kernel Configuration File System.[ 6.330760] EXT4-fs (vda): re-mounted cc0b0364-0557-489e-8107-ab2509587be7. sshClient # sshClient # [ 6.337572] systemd[1]: Starting Firewall... sshClient # [ 6.348203] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 6.362234] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 6.363634] systemd[1]: Mounted FUSE Control File System. sshClient # [ 6.368251] systemd[1]: Finished Remount Root and Kernel File Systems. sshClient # [ 6.376534] systemd-oomd[266]: No swap; memory pressure usage will be degraded sshClient # [ 6.388203] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 6.418050] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 6.420410] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 6.433399] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 6.444292] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.448130] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. sshClient # [ 6.550144] systemd-journald[263]: Received client request to flush runtime journal. machine # [ 6.577587] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 6.605280] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 6.606406] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 6.614032] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 6.652870] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 6.665778] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.806987] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.807823] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.813398] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.815361] systemd[1]: Created slice Slice /system/getty. machine # [ 6.819387] systemd[1]: Created slice User and Session Slice. machine # [ 6.819993] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.823155] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.823613] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.826757] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.827245] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.827911] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.831623] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.832180] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.835371] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.835958] systemd[1]: Reached target Path Units. machine # [ 6.839200] systemd[1]: Reached target Remote File Systems. machine # [ 6.839732] systemd[1]: Reached target Slice Units. machine # [ 6.842686] systemd[1]: Reached target Swaps. machine # [ 6.845875] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.850094] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.852111] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.855828] systemd[1]: Listening on Factory Reset Management. machine # [ 6.858370] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.863728] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 6.837048] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 6.867663] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.840349] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 6.872912] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.875062] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 6.846623] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 6.879090] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.879614] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.889022] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.890123] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.890971] systemd[1]: Listening on User Database Manager Socket. machine # [ 6.898491] systemd[1]: Mounting Huge Pages File System... machine # [ 6.906547] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.890366] systemd[1]: Mounting /run/wrappers... machine # [ 6.924864] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.948027] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.971991] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.978050] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 6.961337] systemd[1]: Mounted /run/wrappers. sshClient # [ 6.962216] systemd[1]: Reached target Local File Systems. sshClient # [ 6.968141] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.002689] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 6.975938] systemd[1]: Starting Create SUID/SGID Wrappers... sshClient # [ 6.980270] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.008803] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.988595] systemd[1]: Starting Create System Files and Directories... machine # [ 7.016741] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.017625] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.050529] systemd[1]: Mounting FUSE Control File System... machine # [ 7.051055] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.111793] systemd[1]: Starting Journal Service... machine # [ 7.138723] systemd[1]: Starting Load Kernel Modules... machine # [ 7.168559] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.187931] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.191111] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.186933] systemd[1]: Finished Create System Files and Directories. sshClient # [ 7.202773] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 7.237306] systemd-journald[268]: Collecting audit messages is enabled. sshClient # [ 7.210472] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 7.211908] systemd-udevd[304]: Using default interface naming scheme 'v261'. machine # [ 7.255859] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.243243] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.275154] systemd[1]: Started Journal Service. sshClient # [ 7.279688] systemd[1]: Starting User Database Manager... machine # [ 7.352396] EXT4-fs (vda): re-mounted bfbe21ec-e619-4204-98a0-d71cb902165d. sshClient # [ 7.366478] systemd[1]: Finished Record System Boot/Shutdown in UTMP. sshClient # [ 7.409800] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 7.428355] systemd[1]: Starting Update is Completed... sshClient # [ 7.492481] systemd[1]: Started User Database Manager. sshClient # [ 7.502599] systemd[1]: Finished Update is Completed. sshClient # [ 7.649435] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.245652] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 7.885958] systemd-journald[268]: Received client request to flush runtime journal. machine # [ 8.091458] systemd-modules-load[269]: Module 'atkbd' is built in machine # [ 8.101402] systemd-modules-load[269]: Module 'loop' is built in machine # [ 8.102586] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.103667] systemd[1]: Mounted Huge Pages File System. machine # [ 8.123144] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.128185] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.129474] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.130444] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.131494] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 8.133066] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.154278] systemd[1]: Finished Load Kernel Modules. machine # [ 8.155283] systemd[1]: Starting Firewall... machine # [ 8.162381] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.163553] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.181103] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.182460] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.183623] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.196590] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.198605] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.199583] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.215129] systemd-oomd[271]: No swap; memory pressure usage will be degraded sshClient # [ 8.203102] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 8.207055] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 8.210423] systemd[1]: Reached target System Initialization. sshClient # [ 8.211425] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 8.228676] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.229892] systemd[1]: Mounted FUSE Control File System. sshClient # [ 8.217425] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 8.218561] systemd[1]: Reached target Timer Units. sshClient # [ 8.219323] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 8.226369] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 8.227648] systemd[1]: Reached target Socket Units. machine # [ 8.245841] systemd[1]: Finished Apply Kernel Variables. machine # [ 8.246879] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 8.234998] systemd[1]: Reached target Basic System. sshClient # [ 8.235838] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 8.245639] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.247949] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 8.252143] systemd[1]: Starting Post-Boot Actions... machine # [ 8.267881] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 8.276394] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 8.277591] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.278708] systemd[1]: Mounting /run/wrappers... machine # [ 8.279450] systemd[1]: Mounted /run/wrappers. sshClient # [ 8.270553] systemd[1]: Started Reset console on configuration changes. machine # [ 8.293075] systemd[1]: Reached target Local File Systems. machine # [ 8.294056] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.295078] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.312548] systemd-udevd[294]: Using default interface naming scheme 'v261'. machine # [ 8.313848] systemd[1]: Starting User Database Manager... machine # [ 8.314717] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 8.315546] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 8.318139] systemd[1]: Starting resolvconf update... machine # [ 8.337664] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 8.338674] systemd[1]: Starting Create System Files and Directories... machine # [ 8.339629] systemd[1]: Started User Database Manager. sshClient # [ 8.347963] systemd[1]: Starting Home Area Manager... sshClient # [ 8.380165] nsncd[436]: Sep 30 03:39:22.880 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 8.396292] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 8.405144] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 8.412764] systemd[1]: Reached target User and Group Name Lookups. machine # [ 8.428479] systemd[1]: Finished Create System Files and Directories. machine # [ 8.444204] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 8.453565] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 8.446880] systemd[1]: Starting D-Bus System Message Bus... machine # [ 8.476380] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 8.502038] systemd[1]: Starting User Login Management... sshClient # [ 8.513347] systemd[1]: Finished Post-Boot Actions. sshClient # [ 8.580444] systemd-homed[445]: Watching /home. sshClient # [ 8.609665] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 8.637885] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.668962] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.684227] systemd[1]: Starting Update is Completed... sshClient # [ 8.751219] systemd-logind[463]: New seat seat0. sshClient # [ 8.759746] systemd[1]: Started User Login Management. sshClient # [ 8.762230] dbus-broker-launch[459]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 8.772429] systemd[1]: Starting linger-users.service... sshClient # [ 8.777566] dbus-broker-launch[459]: NSS returned no entry for 'systemd-timesync' machine # [ 8.793057] systemd[1]: Finished Update is Completed. sshClient # [ 8.778809] dbus-broker-launch[459]: Invalid user-name in /nix/store/k6v9qys37ib5r1kk2svgxps95191fxbz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 8.814734] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 8.859371] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 8.865755] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 8.866909] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 8.867841] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 8.886145] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 8.887187] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 8.899157] dbus-broker-launch[459]: Ready sshClient # [ 8.899934] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 8.916763] systemd[1]: Starting Virtual Console Setup... sshClient # [ 8.907674] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 8.912699] systemd[1]: Finished linger-users.service. sshClient # [ 8.942802] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 8.948925] systemd[1]: Started Home Area Manager. sshClient # [ 8.968473] systemd[1]: Finished Home Area Activation. sshClient # [ 8.979468] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 9.065435] systemd[1]: Finished resolvconf update. sshClient # [ 9.070585] nsncd[540]: Sep 30 03:39:23.574 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 9.081299] systemd[1]: Starting DHCP Client... sshClient # [ 9.084814] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.095204] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 9.098850] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 9.120831] systemd[1]: Finished First Boot Home Area Wizard. sshClient # [ 9.132233] systemd[1]: Reached target First Boot Complete. sshClient # [ 9.139553] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.229881] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.236164] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 9.277073] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 sshClient # [ 9.300626] systemd[1]: Finished Firewall. sshClient # [ 9.307289] systemd[1]: Reached target Preparation for Network. sshClient # [ 9.311909] systemd[1]: Starting Extra networking commands.... sshClient # [ 9.379697] dhcpcd[578]: dhcpcd-10.3.2 starting sshClient # [ 9.397157] dhcpcd[587]: dev: loaded udev sshClient # [ 9.401796] dhcpcd[587]: no valid interfaces found sshClient # [ 9.403352] dhcpcd[587]: no valid interfaces found sshClient # [ 9.408104] dhcpcd[587]: libudev: received NULL device sshClient # [ 9.409084] dhcpcd[587]: libudev: received NULL device machine # [ 9.515120] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.584723] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs sshClient # [ 9.614660] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 9.623379] systemd[1]: Finished Save Transient machine-id to Disk. sshClient # [ 9.714525] systemd[1]: Finished Extra networking commands.. sshClient # [ 9.719195] systemd[1]: Reached target Network. sshClient # [ 9.723490] systemd[1]: Starting Permit User Sessions... machine # [ 9.792524] systemd-vconsole-setup[423]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 9.786871] systemd[1]: Finished Permit User Sessions. machine # [ 9.803329] systemd[1]: Finished Virtual Console Setup. machine # [ 9.805444] systemd[1]: Reached target System Initialization. machine # [ 9.806370] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.807457] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 9.799562] systemd[1]: Started Getty on tty1. machine # [ 9.814896] systemd[1]: Reached target Timer Units. machine # [ 9.815808] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 9.802718] systemd[1]: Reached target Login Prompts. machine # [ 9.823143] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine # [ 9.825939] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.827239] systemd[1]: Reached target Socket Units. machine # [ 9.834171] systemd[1]: Reached target Basic System. machine # [ 9.835641] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 9.825477] systemd[1]: Starting Virtual Console Setup... machine # [ 9.843182] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.851063] systemd[1]: Starting Post-Boot Actions... sshClient # [ 9.856786] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 9.874049] systemd[1]: Started Reset console on configuration changes. sshClient # [ 9.860209] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 9.867534] systemd[1]: Starting Virtual Console Setup... machine # [ 9.899042] systemd[1]: Starting resolvconf update... sshClient # [ 9.888425] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 9.992352] systemd[1]: Starting SSH Host Keys Generation... machine # [ 10.032163] systemd[1]: Starting Home Area Manager... machine # [ 10.066707] systemd[1]: Finished Firewall. machine # [ 10.068591] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.070082] nsncd[485]: Sep 30 03:39:24.489 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 10.061690] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 10.074658] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. sshClient # [ 10.081837] systemd[1]: Started backdoor.service. machine # [ 10.103531] systemd[1]: Finished Post-Boot Actions. sshClient # connecting to host... machine # [ 10.191559] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 10.201191] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. sshClient # [ 10.193239] (udev-worker)[388]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 10.210168] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 10.202207] (udev-worker)[388]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.218389] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.228691] systemd[1]: Started backdoor.service. sshClient # [ 10.244935] 8021q: 802.1Q VLAN Support v1.8 machine # [ 10.249826] systemd[1]: Starting D-Bus System Message Bus... machine # [ 10.261645] systemd-homed[497]: Watching /home. machine # [ 10.281806] systemd[1]: Starting User Login Management... machine # [ 10.303029] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 10.320235] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 10.334742] systemd[1]: Starting Address configuration of eth1... sshClient # [ 10.389873] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 10.423814] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 10.424345] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' sshClient # [ 10.429103] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 10.429457] cfg80211: failed to load regulatory.db sshClient # [ 10.466930] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 10.475005] network-addresses-eth1-start[656]: adding address 192.168.1.2/24... done sshClient # [ 10.491153] network-addresses-eth1-start[656]: adding address 2001:db8:1::2/64... done machine # connecting to host... sshClient # [ 10.529228] systemd[1]: Finished Address configuration of eth1. sshClient # [ 10.550558] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 10.538141] dhcpcd[587]: eth0: waiting for carrier sshClient # [ 10.541438] dhcpcd[587]: eth0: carrier acquired sshClient # [ 10.554466] dhcpcd[587]: DUID 00:01:00:01:32:4f:40:ed:52:54:00:12:34:56 sshClient # [ 10.555542] dhcpcd[587]: eth0: IAID 00:12:34:56 machine # [ 10.570795] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 10.558583] dhcpcd[587]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 10.580458] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 10.565081] systemd-vconsole-setup[642]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 10.581788] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.582767] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.583696] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine: Guest shell says: b'Spawning backdoor root shell...\n' sshClient # [ 10.572744] systemd[1]: Finished Virtual Console Setup. machine # [ 10.605281] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.606225] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine: connected to guest root shell machine: (connecting took 10.98 seconds) machine: (finished: waiting for the VM to finish booting, in 10.98 seconds) machine # [ 10.645556] (udev-worker)[386]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 10.632815] dhcpcd[587]: eth0: soliciting a DHCP lease sshClient # [ 10.640860] dhcpcd[587]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 10.648350] dhcpcd[587]: eth0: probing address 10.0.2.15/24 machine # [ 10.672817] systemd-logind[525]: New seat seat0. machine # [ 10.682747] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.694473] dbus-broker-launch[521]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.696003] systemd[1]: Started User Login Management. machine # [ 10.706991] dbus-broker-launch[521]: NSS returned no entry for 'systemd-timesync' machine # [ 10.716731] dbus-broker-launch[521]: Invalid user-name in /nix/store/cz5bxfy0cscnwcfsqdp63q0saf0bayj2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.719283] systemd[1]: Starting linger-users.service... machine # [ 10.738805] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.782937] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 10.783477] systemd-logind[463]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.857605] dbus-broker-launch[521]: Ready sshClient # [ 10.901414] mousedev: PS/2 mouse device common for all mice machine # [ 10.909477] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.910971] nsncd[580]: Sep 30 03:39:25.330 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.933541] systemd[1]: Started Home Area Manager. machine # [ 10.934457] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.935468] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.950079] systemd[1]: Finished Home Area Activation. machine # [ 10.951100] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 10.956710] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.957920] systemd[1]: Finished linger-users.service. sshClient # [ 10.959772] systemd-logind[463]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 11.020514] systemd[1]: Finished resolvconf update. machine # [ 11.021508] systemd[1]: Reached target Preparation for Network. machine # [ 11.044167] systemd[1]: Starting DHCP Client... machine # [ 11.052205] systemd[1]: Starting Extra networking commands.... machine # [ 11.101519] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 11.140908] systemd[1]: Starting Address configuration of eth1... machine # [ 11.207099] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 11.224795] systemd[1]: Started Console Output Muting Service (PID 607/UID 0). machine # [ 11.450104] network-addresses-eth1-start[614]: adding address 192.168.1.1/24... done machine # [ 11.516123] network-addresses-eth1-start[614]: adding address 2001:db8:1::1/64... done machine # [ 11.596809] systemd[1]: Finished Address configuration of eth1. machine # [ 11.643567] dhcpcd[621]: dhcpcd-10.3.2 starting machine # [ 11.659575] dhcpcd[680]: dev: loaded udev machine # [ 11.772737] systemd-logind[525]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 11.779775] systemd[1]: Finished Extra networking commands.. machine # [ 11.784744] systemd[1]: Reached target Network. sshClient # [ 12.018545] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 12.023123] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 12.025266] systemd[1]: Stopping Virtual Console Setup... sshClient # [ 12.028429] systemd[1]: Starting Virtual Console Setup... sshClient # [ 12.061575] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 12.083560] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 12.090506] systemd[1]: Stopped Virtual Console Setup. machine: (finished: waiting for unit systemd-homed.service, in 12.47 seconds) machine: waiting for Please enter user name to create to appear on tty 1 sshClient # [ 12.095954] systemd[1]: Starting Virtual Console Setup... sshClient # [ 12.139419] systemd-logind[463]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 12.190799] dhcpcd[587]: eth0: soliciting an IPv6 router sshClient # [ 12.191815] dhcpcd[587]: eth0: Router Advertisement from fe80::2 sshClient # [ 12.193140] dhcpcd[587]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 12.194153] dhcpcd[587]: eth0: adding route to fec0::/64 sshClient # [ 12.194978] dhcpcd[587]: eth0: adding default route via fe80::2 sshClient # [ 12.229187] systemd-vconsole-setup[696]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 12.233481] systemd[1]: Finished Virtual Console Setup. machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.18 seconds) machine: sending keys 'test-homed-user\n' machine # [ 12.333385] systemd-logind[525]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.436230] dhcpcd[680]: eth0: waiting for carrier machine # [ 12.437128] dhcpcd[680]: libudev: received NULL device machine # [ 12.438092] dhcpcd[680]: libudev: received NULL device machine # [ 12.439059] dhcpcd[680]: eth0: carrier acquired machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Please enter new password for user test-homed-user: to appear on tty 1 machine # [ 12.472102] dhcpcd[680]: DUID 00:01:00:01:32:4f:40:ee:52:54:00:12:34:56 machine # [ 12.473306] dhcpcd[680]: eth0: IAID 00:12:34:56 machine # [ 12.474105] dhcpcd[680]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 13.049736] dhcpcd[680]: eth0: soliciting a DHCP lease machine # [ 13.059189] dhcpcd[680]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 13.064337] dhcpcd[680]: eth0: probing address 10.0.2.15/24 machine # [ 13.115917] sshd-keygen-start[538]: Generating public/private rsa key pair. machine # [ 13.117298] sshd-keygen-start[538]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 13.118581] sshd-keygen-start[538]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine # [ 13.119853] sshd-keygen-start[538]: The key fingerprint is: machine # [ 13.123741] sshd-keygen-start[538]: SHA256:RdjykSgJwwazvBmDADl/9qGfGmVtM6x7LFAWYrjxfWc root@machine machine # [ 13.126385] sshd-keygen-start[538]: The key's randomart image is: machine # [ 13.127300] sshd-keygen-start[538]: +---[RSA 4096]----+ machine # [ 13.130718] sshd-keygen-start[538]: |+.o+o. . +.. | machine # [ 13.131564] sshd-keygen-start[538]: |+ooo=.+ +.+ | machine # [ 13.134888] sshd-keygen-start[538]: |.o=* o o o.. | machine # [ 13.135701] sshd-keygen-start[538]: | o=+ =o..E | machine # [ 13.138853] sshd-keygen-start[538]: | oo =ooSo | machine # [ 13.139666] sshd-keygen-start[538]: | oo.o o | machine # [ 13.142807] sshd-keygen-start[538]: | .o.o | machine # [ 13.143626] sshd-keygen-start[538]: | .+.o | machine # [ 13.146123] sshd-keygen-start[538]: | ...o | machine # [ 13.146924] sshd-keygen-start[538]: +----[SHA256]-----+ machine # [ 13.206438] sshd-keygen-start[727]: Generating public/private ed25519 key pair. machine # [ 13.207596] sshd-keygen-start[727]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 13.211837] sshd-keygen-start[727]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 13.215581] sshd-keygen-start[727]: The key fingerprint is: machine # [ 13.219106] sshd-keygen-start[727]: SHA256:g9Po2p3zzbXbm2WWcJPAArMbfksrlExoYFLxIKPHa/s root@machine machine # [ 13.222546] sshd-keygen-start[727]: The key's randomart image is: machine # [ 13.223491] sshd-keygen-start[727]: +--[ED25519 256]--+ machine # [ 13.226990] sshd-keygen-start[727]: | +.*. o | machine # [ 13.227826] sshd-keygen-start[727]: | o = + . + . | machine # [ 13.232813] sshd-keygen-start[727]: | . o + + . o | machine # [ 13.233808] sshd-keygen-start[727]: | . . .++ + . . .| machine # [ 13.234569] sshd-keygen-start[727]: | o + S* o . + | machine # [ 13.235324] sshd-keygen-start[727]: | . .. ...o o o o| machine # [ 13.243158] sshd-keygen-start[727]: | . . . o ..+| machine # [ 13.249807] sshd-keygen-start[727]: | .o ....o . =o| machine # [ 13.250707] sshd-keygen-start[727]: | .E. oo. o o+o| machine # [ 13.251471] sshd-keygen-start[727]: +----[SHA256]-----+ machine # [ 13.255763] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 13.258394] systemd[1]: Finished SSH Host Keys Generation. machine # [ 13.259273] systemd[1]: Starting SSH Daemon... machine # [ 13.327938] sshd[729]: Server listening on 0.0.0.0 port 22. machine # [ 13.331050] sshd[729]: Server listening on :: port 22. machine # [ 13.331916] systemd[1]: Started SSH Daemon. machine # [ 13.664721] dhcpcd[680]: eth0: soliciting an IPv6 router machine # [ 13.668128] dhcpcd[680]: eth0: Router Advertisement from fe80::2 machine # [ 13.669156] dhcpcd[680]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.670209] dhcpcd[680]: eth0: adding route to fec0::/64 machine # [ 13.671029] dhcpcd[680]: eth0: adding default route via fe80::2 machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.30 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds) machine: waiting for (repeat) to appear on tty 1 machine: (finished: waiting for (repeat) to appear on tty 1, in 0.12 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 # [ 14.397030] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 14.404875] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 14.409695] systemd[1]: Stopped Virtual Console Setup. machine # [ 14.412378] systemd[1]: Stopping Virtual Console Setup... machine # [ 14.416820] systemd[1]: Starting Virtual Console Setup... machine # [ 14.500907] systemd-logind[525]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 14.578389] systemd-vconsole-setup[763]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 14.581908] systemd[1]: Finished Virtual Console Setup. sshClient # [ 15.604587] dhcpcd[587]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 15.606994] dhcpcd[587]: eth0: adding route to 10.0.2.0/24 sshClient # [ 15.609740] dhcpcd[587]: eth0: adding default route via 10.0.2.2 sshClient # [ 15.724982] systemd[1]: Started DHCP Client. sshClient # [ 15.728775] systemd[1]: Reached target Multi-User System. sshClient # [ 15.730743] systemd[1]: Startup finished in 1.076s (kernel) + 3.813s (initrd) + 10.840s (userspace) = 15.730s. machine # [ 17.559501] dhcpcd[680]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 17.561436] dhcpcd[680]: eth0: adding route to 10.0.2.0/24 machine # [ 17.562872] dhcpcd[680]: eth0: adding default route via 10.0.2.2 machine # [ 17.698124] systemd[1]: Started DHCP Client. machine # [ 24.376190] systemd-homed[497]: Generating key pair for signing local user identity records. machine # [ 24.376485] systemd-homed[497]: Successfully created Ed25519 key pair. machine # [ 24.390804] systemd-homed[497]: test-homed-user: changing state absent → creating machine # [ 24.494461] systemd-homework[862]: Using automatic default storage of 'luks'. machine # [ 24.502257] systemd-homework[862]: Sizing home to 83% of available disk space, which is 744M. machine # [ 24.504148] systemd-homework[862]: Allocating image file completed. machine # [ 24.523799] systemd-homework[862]: Writing of partition table completed. machine # [ 24.528629] systemd-homework[862]: Setting up loopback device /dev/loop0 completed. machine # [ 25.864174] systemd-homework[862]: LUKS formatting completed. machine # [ 28.120430] systemd-homework[862]: Writing password to LUKS keyslot 0 completed. machine # [ 28.340732] systemd-homework[862]: LUKS activation by volume key succeeded. machine # [ 28.360669] systemd-homework[862]: Writing user record as LUKS token completed. machine # [ 28.361895] systemd-homework[862]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 28.363199] systemd-homework[862]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 29.181487] systemd[1]: proc-898-mounts.mount: Deactivated successfully. machine # [ 29.183452] systemd-homework[862]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 30172656-be20-43bb-a0a2-e378254e9fe5) machine # [ 29.189798] systemd-homework[862]: Formatting file system completed. machine # [ 29.257247] systemd-homework[862]: Mounting file system completed. machine # [ 29.260788] systemd-homework[862]: Skeleton directory /etc/skel missing, ignoring. machine # [ 29.261997] systemd-homework[862]: Wrote embedded .identity file. machine # [ 29.262874] systemd-homework[862]: Replaced embedded blob dir with contents of system blob dir. machine # [ 29.265550] systemd-homework[862]: Recursive changing of ownership completed. machine # [ 29.267086] systemd-homework[862]: Changed top-level directory access mode to 0700. machine # [ 29.274615] systemd-homework[862]: Synchronized disk. machine # [ 29.275500] systemd-homework[862]: Discarded unused 652.8M. machine # [ 29.288299] systemd-homed[497]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 29.325465] systemd-homed[497]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 29.341735] systemd-homed[497]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 29.358298] systemd-homework[862]: Moved image file into place. machine # [ 29.363999] systemd-homework[862]: Creation completed. machine # [ 29.365255] systemd-homework[862]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M. machine # [ 29.375617] systemd-homed[497]: test-homed-user: changing state creating → inactive machine # [ 29.384774] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 29.388907] systemd[1]: systemd-mute-console@0-1-607_608-0.service: Deactivated successfully. machine # [ 29.395422] systemd[1]: Reached target First Boot Complete. machine # [ 29.400502] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 29.412677] systemd[1]: Starting Permit User Sessions... machine # [ 29.451850] systemd[1]: Finished Permit User Sessions. machine # [ 29.458517] systemd[1]: Started Getty on tty1. machine # [ 29.461874] systemd[1]: Reached target Login Prompts. machine # [ 29.462783] systemd[1]: Reached target Multi-User System. machine # [ 29.466544] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 29.473757] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 29.477200] systemd[1]: Startup finished in 1.152s (kernel) + 4.490s (initrd) + 23.832s (userspace) = 29.475s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.18 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 30.63 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 30.312519] systemd-homed[497]: test-homed-user: changing state inactive → updating machine # [ 30.321529] systemd-homework[945]: Offline update requested. Not touching embedded records. machine # [ 30.325516] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 30.330644] systemd-homed[497]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.06 seconds) subtest: login as homed user machine: waiting for login: to appear on tty 1 machine: (finished: waiting for login: to appear on tty 1, in 0.04 seconds) machine: sending keys 'test-homed-user\n' machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Password: to appear on tty 1 machine # [ 30.719384] systemd-homed[497]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 30.730583] systemd-homework[960]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 30.739755] systemd-homed[497]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 30.744518] systemd-homed[497]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 30.747460] systemd-homed[497]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 30.750342] systemd-homed[497]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: 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 success: pgrep -u test-homed-user -t tty1 bash machine # [ 31.835614] systemd-homed[497]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 31.883825] systemd-homework[972]: Provided password unlocks user record. machine # [ 31.890427] systemd-homework[972]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 31.897440] systemd-homework[972]: Allocated additional 652.8M. machine # [ 31.903057] systemd-homework[972]: Setting up loopback device /dev/loop1 completed.[ 31.917728] loop1: detected capacity change from 0 to 1519616 machine # machine # [ 32.824848] systemd-homework[972]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 32.873023] systemd-homework[972]: Provided password unlocks user record. machine # [ 32.881043] systemd-homework[972]: Probing file system completed (found btrfs). machine # [ 32.884155] systemd-homework[972]: No fsck for file system btrfs installed, ignoring. machine # [ 32.903645] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (972) machine # [ 32.907817] BTRFS info (device dm-0): first mount of filesystem 30172656-be20-43bb-a0a2-e378254e9fe5 machine # [ 32.907869] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 32.906671] systemd-homework[972]: Mounting file system completed.[ 32.922418] BTRFS info (device dm-0): enabling free space tree machine # [ 32.922449] BTRFS info (device dm-0): use zstd compression, level 1 machine # machine # [ 32.913767] systemd-homework[972]: Discovered used loopback device /dev/loop1. machine # [ 32.917898] systemd-homework[972]: offset = 1048576, size = 778043392, image = 780140544 machine # [ 32.925647] systemd-homework[972]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M. machine # [ 32.927580] systemd-homework[972]: Growing of image file from 744M to 897M completed. machine # [ 32.952336] loop1: detected capacity change from 1519616 to 1832960 machine # [ 32.941180] systemd-homework[972]: Refreshing loop device size completed. machine # [ 32.952835] systemd-homework[972]: Growing of partition completed. machine # [ 32.996686] async_tx: api initialized (async) machine # [ 33.036653] systemd-homework[972]: LUKS device growing completed. machine # [ 33.060142] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 921698304 machine # [ 33.048438] systemd-homework[972]: File system resizing from 726M to 879M completed. machine # [ 33.052258] systemd-homework[972]: Synchronized disk. machine # [ 33.053123] systemd-homework[972]: Synchronized disk. machine # [ 33.053892] systemd-homework[972]: Resizing completed. machine # [ 33.054660] systemd-homework[972]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 33.060225] systemd-homework[972]: Read embedded .identity file. machine # [ 33.081589] systemd-homework[972]: Provided password unlocks user record. machine # [ 33.082729] systemd-homework[972]: Reconciling header user identity completed (host version was newer). machine # [ 33.085175] systemd-homework[972]: Reconciling embedded user identity completed (host version was newer). machine # [ 33.097825] systemd-homework[972]: Wrote LUKS header user record. machine # [ 33.099393] systemd-homework[972]: Wrote embedded .identity file. machine # [ 33.101183] systemd-homework[972]: Replaced embedded blob dir with contents of system blob dir. machine # [ 33.102455] systemd-homework[972]: Recursive changing of ownership not necessary, skipped. machine # [ 33.107707] systemd-homework[972]: Synchronized disk. machine # [ 33.111915] systemd-homework[972]: Moving to final mount point /home/test-homed-user completed. machine # [ 33.137412] systemd-homework[972]: Activation completed. machine # [ 33.140243] systemd-homework[972]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M. machine # [ 33.164598] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 33.170841] systemd-homed[497]: test-homed-user: changing state activating-for-acquire → active machine # [ 33.174102] login[935]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 33.179479] systemd-homed[497]: test-homed-user: changing state active → resizing-while-active machine # [ 33.198661] systemd-homework[1016]: Successfully acquired home volume key from kernel keyring. machine # [ 33.204156] systemd-homework[1016]: LUKS volume key from keyring unlocks user record. machine # [ 33.270030] login[935]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 33.316307] systemd-homework[1016]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 33.364497] systemd[1]: Created slice Slice /user/60388. machine # [ 33.374686] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 33.400541] systemd-logind[525]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 33.518196] systemd-homework[1016]: Successfully re-activated LUKS device. machine # [ 33.527136] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 33.531109] systemd-homework[1016]: LUKS volume key from keyring unlocks user record. machine # [ 33.533660] systemd-homework[1016]: Discovered used loopback device /dev/loop1. machine # [ 33.534879] systemd-homework[1016]: Read embedded .identity file. machine # [ 33.535869] systemd-homework[1016]: LUKS volume key from keyring unlocks user record. machine # [ 33.540339] systemd-homework[1016]: Reconciling header user identity completed (host version was newer). machine # [ 33.541894] systemd-homework[1016]: Reconciling embedded user identity completed (host version was newer). machine # [ 33.543493] systemd-homework[1016]: offset = 1048576, size = 938475520, image = 940572672 machine # [ 33.564574] systemd[1]: Starting User Manager for UID 60388... machine # [ 33.579960] systemd-homework[1016]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M. machine # [ 33.583117] systemd-homework[1016]: Wrote embedded .identity file. machine # [ 33.584547] systemd-homework[1016]: Replaced embedded blob dir with contents of system blob dir. machine # [ 33.585983] systemd-homework[1016]: Backing file is fully allocated already. machine # [ 33.608165] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 783286272 machine # [ 33.593734] systemd-homework[1016]: File system resizing from 879M to 747M completed. machine # [ 33.600494] systemd-homework[1016]: Synchronized disk. machine # [ 33.627913] (systemd)[1019]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 33.701812] (systemd)[1019]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 33.711169] systemd-logind[525]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 33.722662] systemd-homework[1016]: LUKS device shrinking completed. machine # [ 33.748327] loop1: detected capacity change from 1832960 to 1562624 machine # [ 33.737968] systemd-homework[1016]: Refreshing loop device size completed. machine # [ 33.739190] systemd-homework[1016]: Shrinking of image file completed. machine # [ 33.762499] systemd-homework[1016]: Shrinking of partition completed. machine # [ 33.774424] systemd[1019]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.812577] systemd-homework[1016]: Wrote LUKS header user record. machine # [ 33.813733] systemd-homework[1016]: Synchronized disk. machine # [ 33.814491] systemd-homework[1016]: Resizing completed. machine # [ 33.815258] 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 # [ 33.822220] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 33.823554] systemd-homed[497]: test-homed-user: changing state resizing-while-active → active machine # [ 33.828176] systemd-homed[497]: Rebalancing complete. machine # [ 33.923895] systemd[1019]: Queued start job for default target Main User Target. machine # [ 33.933363] systemd[1019]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.941764] systemd[1019]: Created slice User Application Slice. machine # [ 33.942754] systemd[1019]: Started Daily Cleanup of User's Temporary Directories. machine # [ 33.943895] systemd[1019]: Reached target Paths. machine # [ 33.952704] systemd[1019]: Reached target Timers. machine # [ 33.953628] systemd[1019]: Starting D-Bus User Message Bus Socket... machine # [ 33.954596] systemd[1019]: Starting Create User Files and Directories... machine # [ 34.017457] systemd[1019]: Finished Create User Files and Directories. machine # [ 34.034143] systemd[1019]: Listening on D-Bus User Message Bus Socket. machine # [ 34.035237] systemd[1019]: Reached target Sockets. machine # [ 34.035962] systemd[1019]: Reached target Basic System. machine # [ 34.041940] systemd[1]: Started User Manager for UID 60388. machine # [ 34.042914] systemd[1019]: Starting Run user-specific NixOS activation... machine # [ 34.050014] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 34.093829] systemd[1019]: Finished Run user-specific NixOS activation. machine # [ 34.096803] systemd[1019]: Reached target Main User Target. machine # [ 34.097739] systemd[1019]: Startup finished in 344ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.33 seconds) machine: sending keys 'whoami > /tmp/2\n' machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds) machine: waiting for file '/tmp/2' machine: (finished: waiting for file '/tmp/2', in 1.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.05 seconds) sshClient: sending keys 'test-normal-user\n' sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds) sshClient: waiting for Password: to appear on tty 1 sshClient: (finished: waiting for Password: to appear on tty 1, in 1.09 seconds) sshClient: sending keys 'foobarfoo\n' sshClient: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) sshClient: waiting for success: pgrep -u test-normal-user bash sshClient # [ 37.844652] login[640]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 37.884517] systemd[1]: Created slice Slice /user/1000. sshClient # [ 37.889464] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 37.933296] systemd-logind[463]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 37.955055] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 37.968723] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 37.994521] (systemd)[800]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 38.002159] systemd-logind[463]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 38.022751] systemd[800]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 38.139738] systemd[800]: Queued start job for default target Main User Target. sshClient # [ 38.148951] systemd[800]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 38.152830] systemd[800]: Created slice User Application Slice. sshClient # [ 38.153740] systemd[800]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 38.154854] systemd[800]: Reached target Paths. sshClient # [ 38.155516] systemd[800]: Reached target Timers. sshClient # [ 38.159543] systemd[800]: Starting D-Bus User Message Bus Socket... sshClient # [ 38.164223] systemd[800]: Starting Create User Files and Directories... sshClient # [ 38.199932] systemd[800]: Finished Create User Files and Directories. sshClient # [ 38.211187] systemd[800]: Listening on D-Bus User Message Bus Socket. sshClient # [ 38.216384] systemd[800]: Reached target Sockets. sshClient # [ 38.217125] systemd[800]: Reached target Basic System. sshClient # [ 38.217861] systemd[1]: Started User Manager for UID 1000. sshClient # [ 38.219777] systemd[800]: Starting Run user-specific NixOS activation... sshClient # [ 38.227660] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 38.270277] systemd[800]: Finished Run user-specific NixOS activation. sshClient # [ 38.271357] systemd[800]: Reached target Main User Target. sshClient # [ 38.276176] systemd[800]: Startup finished in 254ms. sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.09 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.05 seconds) sshClient: must succeed: cat /tmp/1 sshClient: (finished: must succeed: cat /tmp/1, in 0.02 seconds) (finished: subtest: login as regular user, in 3.75 seconds) subtest: add homed ssh authorized key sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n' sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.49 seconds) sshClient: waiting for file '/tmp/id_ed25519.pub' sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.05 seconds) sshClient: must succeed: cat /tmp/id_ed25519.pub sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.02 seconds) machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKYJTeORB1j1D217JCqYUL2nzIlyVkTOAY9qoRgl+ICv test-normal-user@sshClient' machine # [ 41.686022] systemd-homed[497]: test-homed-user: changing state active → updating-while-active machine # [ 41.694081] systemd-homework[1072]: Offline update requested. Not touching embedded records. machine # [ 41.702687] systemd-homed[497]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKYJTeORB1j1D217JCqYUL2nzIlyVkTOAY9qoRgl+ICv 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.66 seconds) subtest: change homed user password machine: sending keys 'passwd; echo $? > /tmp/3\n' machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.26 seconds) machine: waiting for New password: to appear on tty 1 machine: (finished: waiting for New password: to appear on tty 1, in 1.12 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for Retype new password: to appear on tty 1 machine: (finished: waiting for Retype new password: to appear on tty 1, in 1.09 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) ??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead. File "/nix/store/iiab4a2pmymr5lc22a6yc0d0fyf3g00n-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 44.548254] systemd-homed[497]: test-homed-user: changing state active → passwd-while-active machine # [ 45.248830] systemd-homework[1111]: No valid password for LUKS superblock. machine # [ 47.564715] systemd-homed[497]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 47.568624] systemd-homed[497]: test-homed-user: changing state passwd-while-active → active machine: (finished: must succeed: sleep 4.0, in 4.03 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for file '/tmp/3' machine # [ 48.679339] systemd-homed[497]: test-homed-user: changing state active → passwd-while-active machine # [ 49.947370] systemd-homework[1117]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 50.075114] systemd-homework[1117]: Successfully re-activated LUKS device. machine # [ 50.130300] systemd-homework[1117]: Provided password unlocks user record. machine # [ 50.136095] systemd-homework[1117]: Discovered used loopback device /dev/loop1. machine # [ 50.137362] systemd-homework[1117]: Read embedded .identity file. machine # [ 50.186203] systemd-homework[1117]: Provided password unlocks user record. machine # [ 50.187475] systemd-homework[1117]: Reconciling header user identity completed (host version was newer). machine # [ 50.192169] systemd-homework[1117]: Reconciling embedded user identity completed (host version was newer). machine # [ 53.376003] systemd-homework[1117]: Added LUKS key slot 1. machine # [ 53.394235] systemd-homework[1117]: Destroyed LUKS key slot 0. machine # [ 53.403249] systemd-homework[1117]: Wrote LUKS header user record. machine # [ 53.404915] systemd-homework[1117]: Wrote embedded .identity file. machine # [ 53.405849] systemd-homework[1117]: Replaced embedded blob dir with contents of system blob dir. machine # [ 53.410217] systemd-homework[1117]: Synchronized disk. machine # [ 53.416174] systemd-homework[1117]: Everything completed. machine # [ 53.439585] systemd-homed[497]: test-homed-user: changing state passwd-while-active → active machine # [ 53.443171] passwd[1086]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine: (finished: waiting for file '/tmp/3', in 5.18 seconds) machine: must succeed: cat /tmp/3 machine: (finished: must succeed: cat /tmp/3, in 0.02 seconds) (finished: subtest: change homed user password, in 12.01 seconds) subtest: escalate to root from homed user machine: sending keys 'sudo id | tee /tmp/4\n' machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds) machine: waiting for password for test-homed-user to appear on tty 1 machine # [ 54.214780] systemd-homed[497]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 54.222422] systemd-homework[1155]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 54.229103] systemd-homed[497]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 54.232813] systemd-homed[497]: test-homed-user: changing state authenticating-for-acquire → active machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.10 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for file '/tmp/4' machine: (finished: waiting for file '/tmp/4', in 0.03 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 55.263256] systemd-homed[497]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 55.312581] systemd-homework[1171]: Provided password unlocks user record. machine # [ 55.956079] systemd-homework[1171]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 56.080133] systemd-homework[1171]: Successfully re-activated LUKS device. machine # [ 56.109057] systemd-homework[1171]: Provided password unlocks user record. machine # [ 56.110948] systemd-homework[1171]: Discovered used loopback device /dev/loop1. machine # [ 56.112215] systemd-homework[1171]: Read embedded .identity file. machine # [ 56.137769] systemd-homework[1171]: Provided password unlocks user record. machine # [ 56.139798] systemd-homework[1171]: Reconciling user identities completed (host and header version were identical). machine # [ 56.141893] systemd-homework[1171]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 56.152160] systemd-homework[1171]: Everything completed. machine # [ 56.163880] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 56.169082] systemd-homed[497]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 56.170772] sudo[1153]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 56.176601] sudo[1153]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 56.187855] sudo[1153]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 56.198800] sudo[1153]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.06 seconds) (finished: subtest: escalate to root from homed user, in 2.52 seconds) subtest: log out and deactivate homed user's home area machine: sending keys 'exit\n' machine: (finished: sending keys 'exit\n', in 0.05 seconds) machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive' machine # [ 56.365119] login[935]: pam_unix(login:session): session closed for user test-homed-user machine # [ 56.367112] login[935]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 56.385095] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 56.391560] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 56.396946] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 56.411536] systemd-logind[525]: Session 1 logged out. Waiting for processes to exit. machine # [ 56.422178] systemd[1]: Started Getty on tty1. machine # [ 56.423056] systemd-logind[525]: Removed session 1. machine # [ 66.592289] systemd[1]: Stopping User Manager for UID 60388... machine # [ 66.599534] systemd[1019]: Activating special unit Exit the Session... machine # [ 66.605752] systemd[1019]: Stopped target Main User Target. machine # [ 66.611343] systemd[1019]: Stopped Run user-specific NixOS activation. machine # [ 66.613875] systemd[1019]: Stopped target Basic System. machine # [ 66.615162] systemd[1019]: Stopped target Paths. machine # [ 66.616531] systemd[1019]: Stopped target Sockets. machine # [ 66.617823] systemd[1019]: Stopped target Timers. machine # [ 66.618990] systemd[1019]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 66.620859] systemd[1019]: Closed D-Bus User Message Bus Socket. machine # [ 66.622233] systemd[1019]: Stopped Create User Files and Directories. machine # [ 66.623685] systemd[1019]: Removed slice User Application Slice. machine # [ 66.627472] systemd[1019]: Reached target Shutdown. machine # [ 66.628946] systemd[1019]: Finished Exit the Session. machine # [ 66.630166] systemd[1019]: Reached target Exit the Session. machine # [ 66.633869] systemd-logind[525]: Removed session 2. machine # [ 66.643737] systemd-homed[497]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 66.655878] systemd-homed[497]: test-homed-user: changing state active → deactivating machine # [ 66.658339] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 66.659649] systemd[1]: Stopped User Manager for UID 60388. machine # [ 66.665007] systemd-homework[1246]: Successfully acquired home volume key from kernel keyring. machine # [ 66.668927] systemd-homework[1246]: Discarded unused 674.3M. machine # [ 66.675402] systemd-homework[1246]: Syncing completed. machine # [ 66.718361] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 66.732126] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 66.772165] systemd-homework[1246]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 66.842665] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 66.851003] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 66.855976] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 66.953353] systemd-homework[1246]: Successfully re-activated LUKS device. machine # [ 66.955970] systemd-homework[1246]: Discovered used loopback device /dev/loop1. machine # [ 66.959094] systemd-homework[1246]: offset = 1048576, size = 800063488, image = 802160640 machine # [ 66.966074] systemd-homework[1246]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M. machine # [ 66.969459] systemd-homework[1246]: Allocated additional 674.3M. machine # [ 66.975293] systemd-homework[1246]: File system resizing from 747M to 256M completed.[ 66.989296] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 268435456 machine # machine # [ 66.978868] systemd-homework[1246]: Synchronized disk. machine # [ 67.098892] systemd-homework[1246]: LUKS device shrinking completed. machine # [ 67.124353] loop1: detected capacity change from 1562624 to 557056 machine # [ 67.113742] systemd-homework[1246]: Refreshing loop device size completed. machine # [ 67.114890] systemd-homework[1246]: Shrinking of image file completed. machine # [ 67.126820] systemd-homework[1246]: Shrinking of partition completed. machine # [ 67.130858] systemd-homework[1246]: Synchronized disk. machine # [ 67.131756] systemd-homework[1246]: Resizing completed.[ 67.144537] BTRFS info (device dm-0): last unmount of filesystem 30172656-be20-43bb-a0a2-e378254e9fe5 machine # machine # [ 67.135970] systemd-homework[1246]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 67.138008] systemd-homework[1246]: Unmounting completed. machine # [ 67.138858] systemd-homework[1246]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 67.140999] systemd-homework[1246]: Device home-test-homed-user is not active. machine # [ 67.169524] systemd-homed[497]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 67.172909] systemd-homework[1246]: Everything completed. machine # [ 67.187236] systemd-homed[497]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.66 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.71 seconds) subtest: ssh as homed user sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n' sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.78 seconds) sshClient: waiting for Please enter password for user to appear on tty 1 machine # [ 69.802288] systemd-homed[497]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 69.811003] systemd-homework[1280]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 69.818564] systemd-homed[497]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 69.824271] systemd-homed[497]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 69.827015] sshd-session[1276]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 69.831421] systemd-homed[497]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 69.834262] sshd-session[1276]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 69.837629] systemd-homed[497]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 69.839766] sshd-session[1276]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 59596 ssh2: ED25519 SHA256:SHtDcnYC3ATvelDlZUOWwv0Yz+c/5at/16lm0TDR7vw machine # [ 69.892608] sshd-session[1276]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 69.908056] systemd-logind[525]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 69.911922] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 69.945069] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 69.957214] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 69.977474] sshd-session[1276]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 69.984621] sshd-session[1276]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 70.004937] systemd-homed[497]: test-homed-user: changing state inactive → activating machine # [ 70.010147] systemd-homework[1287]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 70.015199] systemd-homed[497]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 70.017740] systemd-homed[497]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.14 seconds) sshClient: sending keys 'barfoobar\n' sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for success: pgrep -u test-homed-user bash machine # [ 71.097768] systemd-homed[497]: test-homed-user: changing state inactive → activating machine # [ 71.148386] systemd-homework[1292]: Provided password unlocks user record. machine # [ 71.154375] systemd-homework[1292]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 71.159827] systemd-homework[1292]: Backing file is fully allocated already. machine # [ 71.179111] loop1: detected capacity change from 0 to 557056 machine # [ 71.165447] systemd-homework[1292]: Setting up loopback device /dev/loop1 completed. machine # [ 72.052147] systemd-homework[1292]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 72.100198] systemd-homework[1292]: Provided password unlocks user record. machine # [ 72.108066] systemd-homework[1292]: Probing file system completed (found btrfs). machine # [ 72.110716] systemd-homework[1292]: No fsck for file system btrfs installed, ignoring. machine # [ 72.130434] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1292) machine # [ 72.132162] BTRFS info (device dm-0): first mount of filesystem 30172656-be20-43bb-a0a2-e378254e9fe5 machine # [ 72.132208] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 72.125229] systemd-homework[1292]: Mounting file system completed. machine # [ 72.141163] BTRFS info (device dm-0): enabling free space tree machine # [ 72.141188] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 72.129833] systemd-homework[1292]: Discovered used loopback device /dev/loop1. machine # [ 72.130974] systemd-homework[1292]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 72.143703] systemd-homework[1292]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M. machine # [ 72.148501] systemd-homework[1292]: Growing of image file from 274M to 765M completed. machine # [ 72.155901] systemd-homework[1292]: Refreshing loop device size completed.[ 72.168271] loop1: detected capacity change from 557056 to 1562624 machine # machine # [ 72.173514] systemd-homework[1292]: Growing of partition completed. machine # [ 72.225154] systemd-homework[1292]: LUKS device growing completed. machine # [ 72.247851] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 783286272 machine # [ 72.237733] systemd-homework[1292]: File system resizing from 256M to 747M completed. machine # [ 72.239884] systemd-homework[1292]: Synchronized disk. machine # [ 72.240852] systemd-homework[1292]: Synchronized disk. machine # [ 72.241630] systemd-homework[1292]: Resizing completed. machine # [ 72.242407] systemd-homework[1292]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 72.248185] systemd-homework[1292]: Read embedded .identity file. machine # [ 72.267680] systemd-homework[1292]: Provided password unlocks user record. machine # [ 72.270807] systemd-homework[1292]: Reconciling user identities completed (host and header version were identical). machine # [ 72.275264] systemd-homework[1292]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 72.284154] systemd-homework[1292]: Recursive changing of ownership not necessary, skipped. machine # [ 72.285524] systemd-homework[1292]: Synchronized disk. machine # [ 72.286306] systemd-homework[1292]: Moving to final mount point /home/test-homed-user completed. machine # [ 72.303186] systemd-homework[1292]: Activation completed. machine # [ 72.304553] systemd-homework[1292]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M. machine # [ 72.321747] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 72.326180] systemd-homed[497]: test-homed-user: changing state activating → active machine # [ 72.344371] systemd[1]: Starting User Manager for UID 60388... machine # [ 72.382538] (systemd)[1336]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 72.424914] (systemd)[1336]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 72.431886] systemd-logind[525]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 72.455316] systemd[1336]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.569613] systemd[1336]: Queued start job for default target Main User Target. machine # [ 72.573073] systemd[1336]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.577192] systemd[1336]: Created slice User Application Slice. machine # [ 72.578221] systemd[1336]: Started Daily Cleanup of User's Temporary Directories. machine # [ 72.581610] systemd[1336]: Reached target Paths. machine # [ 72.582389] systemd[1336]: Reached target Timers. machine # [ 72.583128] systemd[1336]: Starting D-Bus User Message Bus Socket... machine # [ 72.588224] systemd[1336]: Starting Create User Files and Directories... machine # [ 72.624253] systemd[1336]: Finished Create User Files and Directories. machine # [ 72.626812] systemd[1336]: Listening on D-Bus User Message Bus Socket. machine # [ 72.631398] systemd[1336]: Reached target Sockets. machine # [ 72.633790] systemd[1336]: Reached target Basic System. machine # [ 72.634618] systemd[1]: Started User Manager for UID 60388. machine # [ 72.635479] systemd[1336]: Starting Run user-specific NixOS activation... machine # [ 72.685836] systemd[1336]: Finished Run user-specific NixOS activation. machine # [ 72.691362] systemd[1336]: Reached target Main User Target. machine # [ 72.692393] systemd[1336]: Startup finished in 240ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.22 seconds) sshClient: sending keys 'whoami > /tmp/5\n' sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds) machine: waiting for file '/tmp/5' machine: (finished: waiting for file '/tmp/5', in 1.05 seconds) machine: must succeed: cat /tmp/5 machine: (finished: must succeed: cat /tmp/5, in 0.02 seconds) sshClient: sending keys 'exit\n' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) sshClient: sending keys 'exit\n' machine # [ 74.571271] sshd-session[1283]: Received disconnect from 2001:db8:1::2 port 59596:11: disconnected by user machine # [ 74.574703] sshd-session[1283]: Disconnected from user test-homed-user 2001:db8:1::2 port 59596 sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.60 seconds) (finished: run the VM test script, in 75.48 seconds) machine # [ 74.578018] sshd-session[1276]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 74.582653] sshd-session[1276]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 74.592728] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 74.605320] systemd-logind[525]: Session 3 logged out. Waiting for processes to exit. machine # [ 74.607196] systemd-logind[525]: Removed session 3. sshClient # [ 74.647810] login[640]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 74.663309] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 74.678189] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 74.683194] systemd[1]: session-1.scope: Consumed 112ms CPU time over 36.455s wall clock time, 9.3M memory peak, 12K written to disk, 24.6K incoming IP traffic, 23.7K outgoing IP traffic. sshClient # [ 74.702557] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 74.713570] systemd-logind[463]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 74.715124] systemd[1]: Started Getty on tty1. sshClient # [ 74.715998] systemd-logind[463]: Removed session 1. test script finished in 75.67s cleanup kill QemuMachine (pid 47) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) machine # [2026-09-30T03:40:28Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-30T03:40:28Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-30T03:40:28Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) sshClient # [2026-09-30T03:40:29Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-09-30T03:40:29Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-09-30T03:40:29Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.30 seconds) additionally exposed symbols: machine, sshClient, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh