tribuchet: building on jamie Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 48) machine # Formatting '/build/vm-state-machine/tmp.N8k4AHae0L', fmt=raw size=1073741824 machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: 822a07e4-b8c3-44bc-b81c-99c69b605b00 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-13T02:54:10Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-13T02:54:10Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-13T02:54:10Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-13T02:54:10Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-13T02:54:10Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-13T02:54:10Z INFO virtiofsd] Client connected, servicing requests sshClient # Disk image does not exist, creating the virtualisation disk image... sshClient: QEMU running (pid 47) sshClient # Formatting '/build/vm-state-sshClient/tmp.XRHL5bxsxu', fmt=raw size=1073741824 sshClient # mke2fs 1.47.4 (6-Mar-2025) sshClient # Discarding device blocks: 0/262144 done (finished: start all VMs, in 0.47 seconds) sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes subtest: create systemd-homed user on first boot prompt sshClient # Filesystem UUID: 77f9855c-fdbf-4e2f-823c-53dc04bbf010 machine: waiting for unit systemd-homed.service sshClient # Superblock backups stored on blocks: machine: waiting for the VM to finish booting sshClient # 32768, 98304, 163840, 229376 sshClient # sshClient # Allocating group tables: 0/8 done sshClient # Writing inode tables: 0/8 done sshClient # Creating journal (8192 blocks): done sshClient # Writing superblocks and filesystem accounting information: 0/8 done sshClient # sshClient # Virtualisation disk image created. sshClient # Starting virtiofs daemons... sshClient # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-13T02:54:10Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-13T02:54:10Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-13T02:54:10Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-13T02:54:10Z INFO virtiofsd] Client connected, servicing requests sshClient # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) sshClient # [2026-09-13T02:54:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether sshClient # [2026-09-13T02:54:10Z INFO virtiofsd] Waiting for vhost-user socket connection... sshClient # [2026-09-13T02:54:10Z INFO virtiofsd] Client connected, servicing requests machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) sshClient # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 sshClient # sshClient # sshClient # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)... machine # machine # sshClient # Press Ctrl-B to configure iPXE (PCI 00:02.0)... sshClient # sshClient # sshClient # sshClient # sshClient # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 sshClient # Press Ctrl-B to configure iPXE (PCI 00:05.0)... sshClient # sshClient # machine # Booting from ROM... sshClient # Booting from ROM... machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026 machine # [ 0.000000] Command line: console=ttyS0 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/66lgp1lkiaiyvwdyhaqxk83ml3s8jvxp-nixos-system-machine-test/init regInfo=/nix/store/fy5r9laxkc5a927bnc6pgwndchrhbqay-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000000] APIC: Static calls initialized machine # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.000001] kvm-clock: using sched offset of 448798525 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000005] tsc: Detected 2400.012 MHz processor machine # [ 0.000820] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000847] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000850] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002748] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002760] Using GB pages for direct mapping machine # [ 0.002880] RAMDISK: [mem 0x3e41d000-0x3ffcffff] machine # [ 0.002888] ACPI: Early table checksum verification disabled machine # [ 0.002891] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002895] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002899] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002907] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002909] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002910] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002912] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002914] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002915] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002916] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002917] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002918] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002918] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002919] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002919] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002920] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003147] No NUMA configuration found machine # [ 0.003148] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003150] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005540] Zone ranges: machine # [ 0.005541] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005543] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005545] Normal empty machine # [ 0.005546] Device empty machine # [ 0.005547] Movable zone start for each node machine # [ 0.005548] Early memory node ranges machine # [ 0.005548] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005549] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005551] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005575] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005874] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.024307] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.024761] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.024772] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.024797] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.024799] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.024801] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.024802] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.024803] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.024804] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.024806] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.024807] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.024810] TSC deadline timer available machine # [ 0.024814] CPU topo: Max. logical packages: 1 machine # [ 0.024815] CPU topo: Max. logical dies: 1 machine # [ 0.024815] CPU topo: Max. dies per package: 1 machine # [ 0.024819] CPU topo: Max. threads per core: 1 machine # [ 0.024819] CPU topo: Num. cores per package: 1 machine # [ 0.024820] CPU topo: Num. threads per package: 1 machine # [ 0.024820] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.024837] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.024866] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.024868] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.024869] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.024870] Booting paravirtualized kernel on KVM machine # [ 0.024873] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.029380] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.031901] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.031945] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.031947] Kernel command line: console=ttyS0 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/66lgp1lkiaiyvwdyhaqxk83ml3s8jvxp-nixos-system-machine-test/init regInfo=/nix/store/fy5r9laxkc5a927bnc6pgwndchrhbqay-closure-info/registration console=ttyS0,115200n8 console=tty0 sshClient # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026 machine # [ 0.032039] Unknown kernel command line parameters "regInfo=/nix/store/fy5r9laxkc5a927bnc6pgwndchrhbqay-closure-info/registration", will be passed to user space. machine # [ 0.032052] random: crng init done machine # [ 0.032053] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.032074] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.000000] Command line: console=ttyS0 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/xvd6dma7ynrqg6wrblri5g38f94l1fii-nixos-system-sshClient-test/init regInfo=/nix/store/zx91i70372xjsjggdv2kg2yrq1bp9rfr-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.032087] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.032117] Fallback order for Node 0: 0 sshClient # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.032119] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.032120] Policy zone: DMA32 sshClient # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.034646] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.037231] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.039536] allocated 2097152 bytes of page_ext machine # [ 0.049764] ftrace: allocating 48736 entries in 192 pages sshClient # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.049766] ftrace: allocated 192 pages with 2 groups sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.050613] Dynamic Preempt: lazy sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.050738] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.050739] rcu: RCU event tracing is enabled. sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.050740] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.050741] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.050742] Rude variant of Tasks RCU enabled. sshClient # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.050742] Tracing variant of Tasks RCU enabled. sshClient # [ 0.000000] APIC: Static calls initialized sshClient # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.050742] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.050743] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 sshClient # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.050800] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] Hypervisor detected: KVM sshClient # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.050802] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.050802] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.000001] kvm-clock: using sched offset of 516053089 cycles machine # [ 0.055145] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 sshClient # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.055414] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.000005] tsc: Detected 2400.012 MHz processor sshClient # [ 0.000804] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.055421] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns sshClient # [ 0.000831] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.055527] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.059006] Console: colour VGA+ 80x25 sshClient # [ 0.000834] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.059009] printk: legacy console [tty0] enabled sshClient # [ 0.002742] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.087986] printk: legacy console [ttyS0] enabled sshClient # [ 0.002754] Using GB pages for direct mapping sshClient # [ 0.002820] RAMDISK: [mem 0x3e41d000-0x3ffcffff] machine # [ 0.191815] ACPI: Core revision 20250807 sshClient # [ 0.002827] ACPI: Early table checksum verification disabled sshClient # [ 0.002830] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.192748] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns sshClient # [ 0.002834] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.194289] APIC: Switch to symmetric I/O mode setup machine # [ 0.195246] x2apic enabled sshClient # [ 0.002839] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.002845] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.196095] APIC: Switched APIC routing to: physical x2apic sshClient # [ 0.002847] ACPI: FACS 0x000000003FFE0000 000040 sshClient # [ 0.002849] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.198040] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 sshClient # [ 0.002850] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.199040] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns sshClient # [ 0.002852] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.002853] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.200742] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) sshClient # [ 0.002855] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.201825] x86/cpu: User Mode Instruction Prevention (UMIP) activated sshClient # [ 0.002856] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.202860] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 sshClient # [ 0.002856] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] sshClient # [ 0.002857] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.203740] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 sshClient # [ 0.002857] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] sshClient # [ 0.002858] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.204744] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto sshClient # [ 0.002858] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] sshClient # [ 0.003087] No NUMA configuration found machine # [ 0.205739] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl sshClient # [ 0.003088] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.206739] Transient Scheduler Attacks: Vulnerable: No microcode sshClient # [ 0.003090] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] sshClient # [ 0.005405] Zone ranges: machine # [ 0.207739] Spectre V2 : Mitigation: Enhanced / Automatic IBRS sshClient # [ 0.005406] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.208740] Speculative Return Stack Overflow: Mitigation: Safe RET sshClient # [ 0.005408] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] sshClient # [ 0.005409] Normal empty sshClient # [ 0.005410] Device empty sshClient # [ 0.005410] Movable zone start for each node sshClient # [ 0.005411] Early memory node ranges sshClient # [ 0.005411] node 0: [mem 0x0000000000001000-0x000000000009efff] sshClient # [ 0.005412] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] sshClient # [ 0.005414] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.209739] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.211748] Spectre V2 : Enabling IBPB for BPF sshClient # [ 0.005433] On node 0, zone DMA: 1 pages in unavailable ranges sshClient # [ 0.005704] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.213262] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier sshClient # [ 0.024002] On node 0, zone DMA32: 40 pages in unavailable ranges sshClient # [ 0.024449] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.213740] active return thunk: srso_alias_return_thunk sshClient # [ 0.024461] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) sshClient # [ 0.024489] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.215427] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' sshClient # [ 0.024492] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.216740] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' sshClient # [ 0.024493] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.217739] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' sshClient # [ 0.024494] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) sshClient # [ 0.024496] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.218739] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' sshClient # [ 0.024496] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.219740] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' sshClient # [ 0.024499] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.220739] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' sshClient # [ 0.024500] ACPI: HPET id: 0x8086a201 base: 0xfed00000 sshClient # [ 0.024503] TSC deadline timer available sshClient # [ 0.024507] CPU topo: Max. logical packages: 1 machine # [ 0.221739] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' sshClient # [ 0.024507] CPU topo: Max. logical dies: 1 sshClient # [ 0.024508] CPU topo: Max. dies per package: 1 machine # [ 0.222739] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 sshClient # [ 0.024511] CPU topo: Max. threads per core: 1 machine # [ 0.223739] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 sshClient # [ 0.024512] CPU topo: Num. cores per package: 1 sshClient # [ 0.024512] CPU topo: Num. threads per package: 1 machine # [ 0.224739] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 sshClient # [ 0.024512] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.225739] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 sshClient # [ 0.024530] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.227494] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 sshClient # [ 0.024562] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] sshClient # [ 0.024563] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.228464] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. sshClient # [ 0.024565] [mem 0x40000000-0xafffffff] available for PCI devices sshClient # [ 0.024567] Booting paravirtualized kernel on KVM sshClient # [ 0.024570] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns sshClient # [ 0.029127] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 sshClient # [ 0.031420] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 sshClient # [ 0.031463] kvm-guest: PV spinlocks disabled, single CPU sshClient # [ 0.031465] Kernel command line: console=ttyS0 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/xvd6dma7ynrqg6wrblri5g38f94l1fii-nixos-system-sshClient-test/init regInfo=/nix/store/zx91i70372xjsjggdv2kg2yrq1bp9rfr-closure-info/registration console=ttyS0,115200n8 console=tty0 sshClient # [ 0.031561] Unknown kernel command line parameters "regInfo=/nix/store/zx91i70372xjsjggdv2kg2yrq1bp9rfr-closure-info/registration", will be passed to user space. sshClient # [ 0.031573] random: crng init done sshClient # [ 0.031574] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes sshClient # [ 0.031595] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.031608] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) sshClient # [ 0.031639] Fallback order for Node 0: 0 sshClient # [ 0.031642] Built 1 zonelists, mobility grouping on. Total pages: 262006 sshClient # [ 0.031643] Policy zone: DMA32 sshClient # [ 0.033940] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.036575] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.038695] allocated 2097152 bytes of page_ext sshClient # [ 0.049081] ftrace: allocating 48736 entries in 192 pages sshClient # [ 0.049083] ftrace: allocated 192 pages with 2 groups sshClient # [ 0.049999] Dynamic Preempt: lazy sshClient # [ 0.050160] rcu: Preemptible hierarchical RCU implementation. sshClient # [ 0.050160] rcu: RCU event tracing is enabled. sshClient # [ 0.050161] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.050163] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.050164] Rude variant of Tasks RCU enabled. sshClient # [ 0.050164] Tracing variant of Tasks RCU enabled. sshClient # [ 0.050165] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. sshClient # [ 0.050166] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.050175] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.050176] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.050177] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.054682] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 sshClient # [ 0.054955] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.054962] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns sshClient # [ 0.055085] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.058771] Console: colour VGA+ 80x25 sshClient # [ 0.058774] printk: legacy console [tty0] enabled sshClient # [ 0.090325] printk: legacy console [ttyS0] enabled sshClient # [ 0.193399] ACPI: Core revision 20250807 machine # [ 0.265105] Freeing SMP alternatives memory: 44K machine # [ 0.265742] pid_max: default: 32768 minimum: 301 sshClient # [ 0.194290] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.266774] LSM: initializing lsm=capability,landlock,yama,bpf,ima sshClient # [ 0.195859] APIC: Switch to symmetric I/O mode setup machine # [ 0.267850] landlock: Up and running. sshClient # [ 0.196907] x2apic enabled machine # [ 0.268509] Yama: becoming mindful. machine # [ 0.268961] LSM support for eBPF active sshClient # [ 0.197639] APIC: Switched APIC routing to: physical x2apic machine # [ 0.269843] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.199538] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.270759] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.200548] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.273715] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) sshClient # [ 0.202238] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) sshClient # [ 0.203325] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.274301] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.274744] ... version: 2 sshClient # [ 0.205346] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.275426] ... bit width: 48 machine # [ 0.275741] ... generic counters: 6 sshClient # [ 0.206235] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.276424] ... generic bitmap: 000000000000003f machine # [ 0.276785] ... fixed-purpose counters: 0 sshClient # [ 0.207239] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.277477] ... fixed-purpose bitmap: 0000000000000000 sshClient # [ 0.208235] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.277741] ... value mask: 0000ffffffffffff machine # [ 0.278583] ... max period: 00007fffffffffff sshClient # [ 0.210235] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.279379] ... global_ctrl mask: 000000000000003f sshClient # [ 0.211235] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.279854] signal: max sigframe size: 3376 sshClient # [ 0.212235] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.280713] rcu: Hierarchical SRCU implementation. machine # [ 0.281288] rcu: Max phase no-delay instances is 400. sshClient # [ 0.213234] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization sshClient # [ 0.215244] Spectre V2 : Enabling IBPB for BPF sshClient # [ 0.215947] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.286394] smp: Bringing up secondary CPUs ... sshClient # [ 0.217235] active return thunk: srso_alias_return_thunk machine # [ 0.286756] smp: Brought up 1 node, 1 CPU sshClient # [ 0.218258] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.287435] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) sshClient # [ 0.219235] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' sshClient # [ 0.220234] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.287894] Memory: 941772K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 98888K reserved, 0K cma-reserved) sshClient # [ 0.221235] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.288982] devtmpfs: initialized sshClient # [ 0.222235] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.289734] x86/mm: Memory block size: 128MB sshClient # [ 0.223235] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.291482] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.224237] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' sshClient # [ 0.225235] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.292624] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.226953] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.293834] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.227963] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.294896] PM: RTC time: 02:54:10, date: 2026-09-13 sshClient # [ 0.228999] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 sshClient # [ 0.229946] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 sshClient # [ 0.230954] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.298327] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.299104] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.299758] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.300883] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.301751] audit: initializing netlink subsys (disabled) machine # [ 0.302743] audit: type=2000 audit(1789268051.291:1): state=initialized audit_enabled=0 res=1 machine # [ 0.303843] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.303846] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.304743] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.305656] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.306425] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.306756] cpuidle: using governor menu machine # [ 0.308746] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.309972] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.310744] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.311741] PCI: Using configuration type 1 for base access machine # [ 0.312798] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.319772] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.320742] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.325743] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.326742] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.269174] Freeing SMP alternatives memory: 44K sshClient # [ 0.269805] pid_max: default: 32768 minimum: 301 sshClient # [ 0.270326] LSM: initializing lsm=capability,landlock,yama,bpf,ima sshClient # [ 0.271345] landlock: Up and running. sshClient # [ 0.272235] Yama: becoming mindful. sshClient # [ 0.273418] LSM support for eBPF active sshClient # [ 0.274167] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.337978] ACPI: Added _OSI(Module Device) sshClient # [ 0.275264] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.338661] ACPI: Added _OSI(Processor Device) machine # [ 0.339272] ACPI: Added _OSI(Processor Aggregator Device) sshClient # [ 0.277692] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) sshClient # [ 0.278848] Performance Events: Fam17h+ core perfctr, AMD PMU driver. sshClient # [ 0.279245] ... version: 2 sshClient # [ 0.279938] ... bit width: 48 machine # [ 0.343723] ACPI: 1 ACPI AML tables successfully acquired and loaded sshClient # [ 0.280238] ... generic counters: 6 sshClient # [ 0.280948] ... generic bitmap: 000000000000003f sshClient # [ 0.281239] ... fixed-purpose counters: 0 sshClient # [ 0.282019] ... fixed-purpose bitmap: 0000000000000000 sshClient # [ 0.282239] ... value mask: 0000ffffffffffff sshClient # [ 0.283238] ... max period: 00007fffffffffff sshClient # [ 0.284204] ... global_ctrl mask: 000000000000003f machine # [ 0.350084] ACPI: Interpreter enabled sshClient # [ 0.285109] signal: max sigframe size: 3376 machine # [ 0.350763] ACPI: PM: (supports S0 S3 S4 S5) sshClient # [ 0.285848] rcu: Hierarchical SRCU implementation. machine # [ 0.351564] ACPI: Using IOAPIC for interrupt routing sshClient # [ 0.286243] rcu: Max phase no-delay instances is 400. machine # [ 0.356816] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug sshClient # [ 0.292082] smp: Bringing up secondary CPUs ... machine # [ 0.357742] PCI: Using E820 reservations for host bridge windows sshClient # [ 0.292891] smp: Brought up 1 node, 1 CPU sshClient # [ 0.293241] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.360801] ACPI: Enabled 2 GPEs in block 00 to 3F sshClient # [ 0.294522] Memory: 941760K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 98888K reserved, 0K cma-reserved) sshClient # [ 0.295599] devtmpfs: initialized sshClient # [ 0.296458] x86/mm: Memory block size: 128MB sshClient # [ 0.298332] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.366767] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) sshClient # [ 0.299284] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.367748] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] sshClient # [ 0.300342] pinctrl core: initialized pinctrl subsystem machine # [ 0.368833] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] sshClient # [ 0.301526] PM: RTC time: 02:54:10, date: 2026-09-13 machine # [ 0.369898] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.371127] PCI host bridge to bus 0000:00 machine # [ 0.371749] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] sshClient # [ 0.305146] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.372744] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] sshClient # [ 0.306567] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.373741] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] sshClient # [ 0.307265] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.374743] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.375744] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.308417] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.309256] audit: initializing netlink subsys (disabled) machine # [ 0.376744] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.377743] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.310508] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.310510] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.378873] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.311243] audit: type=2000 audit(1789268051.373:1): state=initialized audit_enabled=0 res=1 machine # [ 0.380217] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint sshClient # [ 0.313241] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.313243] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.314239] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.315276] cpuidle: using governor menu sshClient # [ 0.317551] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 sshClient # [ 0.318526] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] sshClient # [ 0.319243] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.382802] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] sshClient # [ 0.320257] PCI: Using configuration type 1 for base access machine # [ 0.383756] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.384716] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] sshClient # [ 0.321468] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.386122] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.387467] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.326375] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.390749] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] sshClient # [ 0.327239] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.391615] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.392562] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.393553] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] sshClient # [ 0.332241] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.395547] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.333238] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.398749] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 0.399614] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.400515] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] sshClient # [ 0.339401] ACPI: Added _OSI(Module Device) machine # [ 0.402094] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.340099] ACPI: Added _OSI(Processor Device) sshClient # [ 0.340770] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.404755] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.405590] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.406455] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.407317] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.409599] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.410491] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.410769] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.411752] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] sshClient # [ 0.353846] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.413339] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.355323] ACPI: Interpreter enabled machine # [ 0.414670] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] sshClient # [ 0.355954] ACPI: PM: (supports S0 S3 S4 S5) sshClient # [ 0.356241] ACPI: Using IOAPIC for interrupt routing sshClient # [ 0.357122] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.415552] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.417215] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.419013] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] sshClient # [ 0.362242] PCI: Using E820 reservations for host bridge windows machine # [ 0.419764] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] sshClient # [ 0.363437] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.421343] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.422623] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.423455] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.424341] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.425755] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.426764] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.428278] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.374979] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.429752] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] sshClient # [ 0.375246] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.430611] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.431467] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] sshClient # [ 0.377703] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.432289] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.378363] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] sshClient # [ 0.379641] PCI host bridge to bus 0000:00 machine # [ 0.434235] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] sshClient # [ 0.380244] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.434748] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] sshClient # [ 0.381243] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.435689] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] sshClient # [ 0.382237] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] sshClient # [ 0.383269] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.437120] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.384238] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.438265] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] sshClient # [ 0.385238] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.438954] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.386238] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.440298] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] sshClient # [ 0.387239] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.440952] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.388698] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.442266] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] sshClient # [ 0.391310] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] sshClient # [ 0.392250] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] sshClient # [ 0.393175] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] sshClient # [ 0.394139] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.442978] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint sshClient # [ 0.396031] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.445207] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.446011] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.447033] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO sshClient # [ 0.399261] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] sshClient # [ 0.400123] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.448003] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint sshClient # [ 0.400931] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.449616] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] sshClient # [ 0.401243] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.450360] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] sshClient # [ 0.403152] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.451082] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.452283] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] sshClient # [ 0.406258] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] sshClient # [ 0.407202] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] sshClient # [ 0.408048] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.455686] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.456554] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 sshClient # [ 0.409701] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.457866] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.458855] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.459852] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.460869] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 sshClient # [ 0.413019] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] sshClient # [ 0.413888] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.461866] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 sshClient # [ 0.414260] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.462860] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.463792] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 sshClient # [ 0.415846] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.464762] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.465714] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.466766] ACPI: PCI: Interrupt link GSID configured for IRQ 19 sshClient # [ 0.418247] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.467758] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.468731] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 sshClient # [ 0.419279] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.469528] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.470484] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 sshClient # [ 0.420291] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] sshClient # [ 0.421245] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.471883] iommu: Default domain type: Translated sshClient # [ 0.423246] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.472705] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.473773] ACPI: bus type USB registered machine # [ 0.474505] usbcore: registered new interface driver usbfs machine # [ 0.474769] usbcore: registered new interface driver hub sshClient # [ 0.425252] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.475632] usbcore: registered new device driver usb sshClient # [ 0.426261] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.477410] NetLabel: Initializing machine # [ 0.477742] NetLabel: domain hash size = 128 machine # [ 0.478430] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO sshClient # [ 0.427796] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.478797] NetLabel: unlabeled traffic allowed by default machine # [ 0.479643] PCI: Using ACPI for IRQ routing sshClient # [ 0.430251] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] sshClient # [ 0.431198] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] sshClient # [ 0.432656] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.434250] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] sshClient # [ 0.435194] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] sshClient # [ 0.436612] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint sshClient # [ 0.439259] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] sshClient # [ 0.440243] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] sshClient # [ 0.441803] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.443275] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] sshClient # [ 0.444121] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] sshClient # [ 0.445016] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] sshClient # [ 0.446928] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.450006] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] sshClient # [ 0.450245] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] sshClient # [ 0.451180] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] sshClient # [ 0.452675] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.454073] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] sshClient # [ 0.455478] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.457053] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] sshClient # [ 0.458062] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.459916] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] machine # [ 0.523621] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.523737] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.523737] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.523748] vgaarb: loaded machine # [ 0.524488] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 sshClient # [ 0.460453] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.524742] hpet0: 3 comparators, 64-bit 100.000000 MHz counter sshClient # [ 0.462928] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.526823] clocksource: Switched to clocksource kvm-clock sshClient # [ 0.463514] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint sshClient # [ 0.464550] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.530607] VFS: Disk quotas dquot_6.6.0 machine # [ 0.531309] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.465579] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.532882] pnp: PnP ACPI init machine # [ 0.533698] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.534658] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.536165] pnp: PnP ACPI: found 5 devices sshClient # [ 0.468239] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] sshClient # [ 0.469286] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] sshClient # [ 0.470576] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint sshClient # [ 0.472115] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.544298] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns sshClient # [ 0.476375] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.545702] clocksource: Switched to clocksource acpi_pm machine # [ 0.546721] NET: Registered PF_INET protocol family sshClient # [ 0.477342] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 sshClient # [ 0.478355] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.547734] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.479346] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 sshClient # [ 0.480334] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 sshClient # [ 0.481332] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 sshClient # [ 0.482338] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 sshClient # [ 0.483354] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 sshClient # [ 0.484278] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 sshClient # [ 0.485317] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 sshClient # [ 0.486439] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 sshClient # [ 0.487259] ACPI: PCI: Interrupt link GSID configured for IRQ 19 sshClient # [ 0.488249] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 sshClient # [ 0.489252] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 sshClient # [ 0.490250] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 sshClient # [ 0.491220] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 sshClient # [ 0.493316] iommu: Default domain type: Translated sshClient # [ 0.494185] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.565555] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.495320] ACPI: bus type USB registered sshClient # [ 0.496068] usbcore: registered new interface driver usbfs machine # [ 0.567112] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.497096] usbcore: registered new interface driver hub machine # [ 0.568475] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.498027] usbcore: registered new device driver usb machine # [ 0.569796] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.499318] NetLabel: Initializing machine # [ 0.571253] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.499900] NetLabel: domain hash size = 128 machine # [ 0.572305] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) sshClient # [ 0.500237] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO sshClient # [ 0.501219] NetLabel: unlabeled traffic allowed by default machine # [ 0.573567] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.501961] PCI: Using ACPI for IRQ routing machine # [ 0.574712] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.575956] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.576979] NET: Registered PF_XDP protocol family machine # [ 0.577886] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.578923] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.579895] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.580988] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.582086] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.583214] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.584930] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.587611] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.589724] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.591723] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.593719] PCI: CLS 0 bytes, default 64 machine # [ 0.594591] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.596379] Trying to unpack rootfs image as initramfs... sshClient # [ 0.545197] pci 0000:00:01.0: vgaarb: setting as boot VGA device sshClient # [ 0.545233] pci 0000:00:01.0: vgaarb: bridge control possible sshClient # [ 0.545233] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none sshClient # [ 0.545242] vgaarb: loaded sshClient # [ 0.545993] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 sshClient # [ 0.546237] hpet0: 3 comparators, 64-bit 100.000000 MHz counter sshClient # [ 0.550326] clocksource: Switched to clocksource kvm-clock sshClient # [ 0.552127] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.554891] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.556340] pnp: PnP ACPI init sshClient # [ 0.557172] ACPI: IRQ 4 override to edge(!), high(!) sshClient # [ 0.558173] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved sshClient # [ 0.559795] pnp: PnP ACPI: found 5 devices machine # [ 0.642785] Initialise system trusted keyrings machine # [ 0.645413] workingset: timestamp_bits=40 max_order=18 bucket_order=0 sshClient # [ 0.567394] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns sshClient # [ 0.568925] clocksource: Switched to clocksource acpi_pm sshClient # [ 0.569944] NET: Registered PF_INET protocol family sshClient # [ 0.571060] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.588643] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.666968] Key type asymmetric registered machine # [ 0.667716] Asymmetric key parser 'x509' registered sshClient # [ 0.590216] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.591554] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.670424] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) sshClient # [ 0.592932] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.594129] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.595323] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.673402] io scheduler mq-deadline registered machine # [ 0.674146] io scheduler kyber registered sshClient # [ 0.596541] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.597623] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.598788] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.599741] NET: Registered PF_XDP protocol family sshClient # [ 0.600541] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.678111] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.601513] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.679508] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A sshClient # [ 0.602463] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] sshClient # [ 0.603490] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] sshClient # [ 0.604640] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.605751] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.684983] Linux agpgart interface v0.103 sshClient # [ 0.607439] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.685735] ACPI: bus type drm_connector registered sshClient # [ 0.609808] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.688755] usbcore: registered new interface driver usbserial_generic sshClient # [ 0.611956] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.689819] usbserial: USB Serial support registered for generic sshClient # [ 0.614039] ACPI: \_SB_.GSID: Enabled at IRQ 19 sshClient # [ 0.616040] PCI: CLS 0 bytes, default 64 machine # [ 0.693364] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.693364] Please enable it if your BIOS has the CPPC option. sshClient # [ 0.616977] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.695535] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled sshClient # [ 0.618678] Trying to unpack rootfs image as initramfs... machine # [ 0.696901] drop_monitor: Initializing network drop monitor service machine # [ 0.698094] NET: Registered PF_INET6 protocol family machine # [ 0.701835] Segment Routing with IPv6 machine # [ 0.705386] In-situ OAM (IOAM) with IPv6 machine # [ 0.706460] IPI shorthand broadcast: enabled machine # [ 0.714751] sched_clock: Marking stable (574014926, 140316733)->(784289759, -69958100) machine # [ 0.719475] registered taskstats version 1 machine # [ 0.720433] Loading compiled-in X.509 certificates machine # [ 0.738501] Demotion targets for Node 0: null machine # [ 0.740410] Key type .fscrypt registered machine # [ 0.741110] Key type fscrypt-provisioning registered sshClient # [ 0.663690] Initialise system trusted keyrings machine # [ 0.742058] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 0.666346] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.746385] ima: Allocated hash algorithm: sha1 machine # [ 0.747121] ima: No architecture policies found machine # [ 0.749368] PM: Magic number: 10:96:915 machine # [ 0.751098] RAS: Correctable Errors collector initialized. machine # [ 0.762303] clk: Disabling unused clocks machine # [ 0.765373] PM: genpd: Disabling unused power domains sshClient # [ 0.689513] Key type asymmetric registered sshClient # [ 0.690224] Asymmetric key parser 'x509' registered sshClient # [ 0.691063] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) sshClient # [ 0.694490] io scheduler mq-deadline registered sshClient # [ 0.695225] io scheduler kyber registered sshClient # [ 0.698841] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.700058] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A sshClient # [ 0.703228] Linux agpgart interface v0.103 sshClient # [ 0.703978] ACPI: bus type drm_connector registered sshClient # [ 0.708728] usbcore: registered new interface driver usbserial_generic sshClient # [ 0.709783] usbserial: USB Serial support registered for generic sshClient # [ 0.713306] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. sshClient # [ 0.713306] Please enable it if your BIOS has the CPPC option. sshClient # [ 0.715423] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled sshClient # [ 0.716755] drop_monitor: Initializing network drop monitor service sshClient # [ 0.717888] NET: Registered PF_INET6 protocol family sshClient # [ 0.722601] Segment Routing with IPv6 sshClient # [ 0.723220] In-situ OAM (IOAM) with IPv6 sshClient # [ 0.724262] IPI shorthand broadcast: enabled sshClient # [ 0.734660] sched_clock: Marking stable (592061220, 142271524)->(806604627, -72271883) sshClient # [ 0.736241] registered taskstats version 1 sshClient # [ 0.737203] Loading compiled-in X.509 certificates sshClient # [ 0.755496] Demotion targets for Node 0: null sshClient # [ 0.758338] Key type .fscrypt registered sshClient # [ 0.759053] Key type fscrypt-provisioning registered sshClient # [ 0.760056] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 0.764313] ima: Allocated hash algorithm: sha1 sshClient # [ 0.765091] ima: No architecture policies found sshClient # [ 0.767303] PM: Magic number: 10:96:915 sshClient # [ 0.769018] RAS: Correctable Errors collector initialized. sshClient # [ 0.779118] clk: Disabling unused clocks sshClient # [ 0.781305] PM: genpd: Disabling unused power domains machine # [ 0.925484] Freeing initrd memory: 28364K machine # [ 0.928714] Freeing unused decrypted memory: 2028K machine # [ 0.931460] Freeing unused kernel image (initmem) memory: 3644K machine # [ 0.932529] Write protecting the kernel read-only data: 32768k machine # [ 0.934427] Freeing unused kernel image (text/rodata gap) memory: 1200K machine # [ 0.935934] Freeing unused kernel image (rodata/data gap) memory: 736K machine # [ 0.988548] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 0.989613] Run /init as init process machine # [ 1.000607] systemd[1]: Inserted module 'autofs4' machine # [ 1.021531] fuse: init (API version 7.45) machine # [ 1.029015] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.031662] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.035431] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.038263] ACPI: \_SB_.GSIF: Enabled at IRQ 21 sshClient # [ 0.962910] Freeing initrd memory: 28364K sshClient # [ 0.966243] Freeing unused decrypted memory: 2028K machine # [ 1.045029] virtiofs virtio5: discovered new tag: nix-store machine # [ 1.046767] virtiofs virtio5: virtio_fs_setup_dax: No cache capability sshClient # [ 0.969421] Freeing unused kernel image (initmem) memory: 3644K sshClient # [ 0.970549] Write protecting the kernel read-only data: 32768k sshClient # [ 0.972636] Freeing unused kernel image (text/rodata gap) memory: 1200K sshClient # [ 0.974262] Freeing unused kernel image (rodata/data gap) memory: 736K machine # [ 1.055306] virtiofs virtio6: discovered new tag: shared machine # [ 1.056914] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.062513] virtiofs virtio7: discovered new tag: xchg machine # [ 1.064149] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.086533] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.027729] x86/mm: Checked W+X mappings: passed, no W+X pages found. sshClient # [ 1.028872] Run /init as init process sshClient # [ 1.041413] systemd[1]: Inserted module 'autofs4' sshClient # [ 1.062723] fuse: init (API version 7.45) sshClient # [ 1.071305] ACPI: \_SB_.GSIG: Enabled at IRQ 22 sshClient # [ 1.073789] ACPI: \_SB_.GSIH: Enabled at IRQ 23 sshClient # [ 1.077525] ACPI: \_SB_.GSIE: Enabled at IRQ 20 sshClient # [ 1.080478] ACPI: \_SB_.GSIF: Enabled at IRQ 21 sshClient # [ 1.086956] virtiofs virtio5: discovered new tag: nix-store sshClient # [ 1.088932] virtiofs virtio5: virtio_fs_setup_dax: No cache capability sshClient # [ 1.096251] virtiofs virtio6: discovered new tag: shared sshClient # [ 1.097851] virtiofs virtio6: virtio_fs_setup_dax: No cache capability sshClient # [ 1.101245] virtiofs virtio7: discovered new tag: xchg sshClient # [ 1.102813] virtiofs virtio7: virtio_fs_setup_dax: No cache capability sshClient # [ 1.129663] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.423068] systemd[1]: systemd 262-b504aa0 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.434773] systemd[1]: Detected virtualization kvm. machine # [ 1.436859] systemd[1]: Detected architecture x86-64. machine # [ 1.438969] systemd[1]: Running in initrd. machine # [ 1.441481] systemd[1]: Initializing machine ID from random generator. machine # [ 1.444298] systemd[1]: Hostname set to . machine # [ 1.537510] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.466476] systemd[1]: systemd 262-b504aa0 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.479855] systemd[1]: Detected virtualization kvm. sshClient # [ 1.482367] systemd[1]: Detected architecture x86-64. sshClient # [ 1.484751] systemd[1]: Running in initrd. sshClient # [ 1.487492] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.490770] systemd[1]: Hostname set to . machine # [ 1.575436] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.578666] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.579819] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.581185] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.582295] systemd[1]: Reached target Path Units. machine # [ 1.583191] systemd[1]: Reached target Slice Units. machine # [ 1.584065] systemd[1]: Reached target Swaps. machine # [ 1.584928] systemd[1]: Reached target Timer Units. machine # [ 1.585866] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.587085] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.588251] systemd[1]: Listening on Journal Sockets. machine # [ 1.589233] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.590182] systemd[1]: Reached target Socket Units. machine # [ 1.592025] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.596429] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.605990] systemd[1]: Starting Journal Service... machine # [ 1.617485] systemd[1]: Starting Load Kernel Modules... machine # [ 1.632218] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.637566] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.654410] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.658084] systemd[1]: modprobe@configfs.service: Deactivated successfully. sshClient # [ 1.581743] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.665868] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.671858] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.676087] systemd-journald[66]: Collecting audit messages is disabled. machine # [ 1.684466] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.691459] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.619724] systemd[1]: Queued start job for default target Initrd Default Target. sshClient # [ 1.623610] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.624910] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.703557] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.626424] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.627667] systemd[1]: Reached target Path Units. sshClient # [ 1.628634] systemd[1]: Reached target Slice Units. sshClient # [ 1.629629] systemd[1]: Reached target Swaps. sshClient # [ 1.630521] systemd[1]: Reached target Timer Units. sshClient # [ 1.631557] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.632957] systemd[1]: Listening on Journal Socket (/dev/log). sshClient # [ 1.634299] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.635376] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.636424] systemd[1]: Reached target Socket Units. sshClient # [ 1.638386] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.642362] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.728422] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.653394] systemd[1]: Starting Journal Service... machine # [ 1.738642] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.749442] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.663090] systemd[1]: Starting Load Kernel Modules... sshClient # [ 1.679489] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.762435] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 1.688386] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.701630] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 1.709033] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.787565] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.793636] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.716689] systemd[1]: Finished Load Kernel Module configfs. sshClient # [ 1.718374] systemd-journald[66]: Collecting audit messages is disabled. machine # [ 1.799433] systemd[1]: Reached target Local File Systems. machine # [ 1.655909] systemd-modules-load[67]: Inserted module 'dm_mod' machine # [ 1.661051] systemd-modules-load[67]: Inserted module 'virtio_balloon' sshClient # [ 1.722763] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.664118] systemd-modules-load[67]: Inserted module 'virtio_gpu' machine # [ 1.806167] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.812601] systemd[1]: Started Journal Service. sshClient # [ 1.735416] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.738745] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.680108] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.748316] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.694100] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.778877] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.724190] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.787527] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.729269] systemd-udevd[74]: Using default interface naming scheme 'v261'. sshClient # [ 1.798887] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.809387] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.753148] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.831638] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.836556] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.842840] systemd[1]: Reached target Local File Systems. sshClient # [ 1.701605] systemd-modules-load[67]: Inserted module 'dm_mod' sshClient # [ 1.702948] systemd-modules-load[67]: Inserted module 'virtio_balloon' sshClient # [ 1.705289] systemd-modules-load[67]: Inserted module 'virtio_gpu' sshClient # [ 1.852508] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.857611] systemd[1]: Started Journal Service. sshClient # [ 1.723187] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.807219] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.808430] systemd[1]: Reached target System Initialization. machine # [ 1.810464] systemd[1]: Reached target Basic System. sshClient # [ 1.740085] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.769208] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.777295] systemd-udevd[74]: Using default interface naming scheme 'v261'. sshClient # [ 1.802078] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.857103] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 1.857980] systemd[1]: Reached target System Initialization. sshClient # [ 1.858867] systemd[1]: Reached target Basic System. machine # [ 2.130836] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 2.141178] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.154609] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.157129] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.157992] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.159767] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.166696] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.167466] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.168345] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.170033] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.171116] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.174358] usb usb1: Product: EHCI Host Controller machine # [ 2.174999] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd machine # [ 2.177090] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.179081] hub 1-0:1.0: USB hub found machine # [ 2.180796] hub 1-0:1.0: 6 ports detected machine # [ 2.185196] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.185976] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.200601] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.206728] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 2.215164] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.216306] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.230026] usb usb2: Product: UHCI Host Controller machine # [ 2.240602] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.103320] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.248256] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.109434] systemd[1]: Starting Virtual Console Setup... machine # [ 2.113264] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.259467] hub 2-0:1.0: USB hub found machine # [ 2.120993] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.185760] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 2.264132] hub 2-0:1.0: 2 ports detected sshClient # [ 2.188482] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 sshClient # [ 2.195474] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 2.200243] serio: i8042 KBD port at 0x60,0x64 irq 1 sshClient # [ 2.203595] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.281921] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.148973] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 2.214462] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.152508] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.215199] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.295246] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 sshClient # [ 2.218256] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 sshClient # [ 2.225313] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 sshClient # [ 2.226610] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 sshClient # [ 2.227736] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.230560] usb usb1: Product: EHCI Host Controller machine # [ 2.308715] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 sshClient # [ 2.232436] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd sshClient # [ 2.233142] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.171326] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.172173] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.235345] hub 1-0:1.0: USB hub found sshClient # [ 2.236877] hub 1-0:1.0: 6 ports detected machine # [ 2.174289] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 2.244036] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.321985] uhci_hcd 0000:00:1d.1: detected 2 ports sshClient # [ 2.244778] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.326492] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 machine # [ 2.330570] SCSI subsystem initialized sshClient # [ 2.256038] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.334871] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.335978] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.261024] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 2.340392] usb usb3: Product: UHCI Host Controller machine # [ 2.341101] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.343414] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.346425] hub 3-0:1.0: USB hub found sshClient # [ 2.270385] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.349491] hub 3-0:1.0: 2 ports detected sshClient # [ 2.271516] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.213577] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.358057] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 2.358912] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 sshClient # [ 2.284433] SCSI subsystem initialized machine # [ 2.362424] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 2.363160] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 sshClient # [ 2.287470] usb usb2: Product: UHCI Host Controller sshClient # [ 2.288170] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.366463] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.367734] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.370475] usb usb4: Product: UHCI Host Controller machine # [ 2.371165] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.373341] usb usb4: SerialNumber: 0000:00:1d.2 sshClient # [ 2.296192] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.376439] hub 4-0:1.0: USB hub found machine # [ 2.377229] hub 4-0:1.0: 2 ports detected sshClient # [ 2.300464] hub 2-0:1.0: USB hub found sshClient # [ 2.303484] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 sshClient # [ 2.307292] hub 2-0:1.0: 2 ports detected sshClient # [ 2.169451] systemd[1]: Starting Virtual Console Setup... sshClient # [ 2.183286] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.408593] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.409918] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.411232] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.275704] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.419470] scsi host0: ahci machine # [ 2.421249] scsi host1: ahci sshClient # [ 2.200373] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.421757] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.423809] scsi host2: ahci sshClient # [ 2.204188] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.425927] scsi host3: ahci machine # [ 2.429235] scsi host4: ahci machine # [ 2.431102] scsi host5: ahci sshClient # [ 2.353430] uhci_hcd 0000:00:1d.1: UHCI Host Controller sshClient # [ 2.354146] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 2.433613] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 machine # [ 2.434698] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 sshClient # [ 2.214979] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.436572] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 sshClient # [ 2.218065] systemd[1]: Finished Virtual Console Setup. machine # [ 2.437853] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 machine # [ 2.439190] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 machine # [ 2.442103] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 sshClient # [ 2.365487] uhci_hcd 0000:00:1d.1: detected 2 ports sshClient # [ 2.371312] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 sshClient # [ 2.377959] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.379069] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.245081] systemd[1]: Found device /dev/disk/by-label/nixos. sshClient # [ 2.245929] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.248071] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 2.391883] usb usb3: Product: UHCI Host Controller sshClient # [ 2.396882] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd sshClient # [ 2.397840] usb usb3: SerialNumber: 0000:00:1d.1 sshClient # [ 2.398919] hub 3-0:1.0: USB hub found sshClient # [ 2.405391] hub 3-0:1.0: 2 ports detected sshClient # [ 2.408982] uhci_hcd 0000:00:1d.2: UHCI Host Controller sshClient # [ 2.415342] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 sshClient # [ 2.418337] uhci_hcd 0000:00:1d.2: detected 2 ports sshClient # [ 2.419221] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 sshClient # [ 2.422533] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.425355] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.428066] usb usb4: Product: UHCI Host Controller sshClient # [ 2.292346] systemd-fsck[106]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 2.437345] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd sshClient # [ 2.438111] usb usb4: SerialNumber: 0000:00:1d.2 sshClient # [ 2.441478] hub 4-0:1.0: USB hub found sshClient # [ 2.442918] hub 4-0:1.0: 2 ports detected sshClient # [ 2.308510] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 2.467933] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode sshClient # [ 2.474307] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) sshClient # [ 2.475158] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.550974] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 sshClient # [ 2.477313] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.553812] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.556983] usb 1-1: Product: QEMU USB Tablet machine # [ 2.558789] usb 1-1: Manufacturer: QEMU machine # [ 2.560420] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 sshClient # [ 2.484536] scsi host0: ahci sshClient # [ 2.485854] scsi host1: ahci sshClient # [ 2.486623] scsi host2: ahci sshClient # [ 2.488558] scsi host3: ahci sshClient # [ 2.493370] scsi host4: ahci sshClient # [ 2.496408] scsi host5: ahci sshClient # [ 2.497012] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 sshClient # [ 2.499037] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 sshClient # [ 2.501343] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 sshClient # [ 2.506589] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 sshClient # [ 2.508220] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 sshClient # [ 2.509389] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 machine # [ 2.595728] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.510959] systemd[1]: Mounting /sysroot... sshClient # [ 2.606767] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 sshClient # [ 2.609681] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 sshClient # [ 2.612904] usb 1-1: Product: QEMU USB Tablet sshClient # [ 2.614681] usb 1-1: Manufacturer: QEMU sshClient # [ 2.616447] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 sshClient # [ 2.649174] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.756848] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.757833] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.758815] ata3.00: applying bridge limits machine # [ 2.759519] ata3.00: configured for UDMA/100 machine # [ 2.760283] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.761190] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.762060] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.762926] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.763987] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.766288] ata6: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.560988] systemd[1]: Mounting /sysroot... machine # [ 2.816427] usbcore: registered new interface driver usbhid machine # [ 2.817182] usbhid: USB HID core driver machine # [ 2.839017] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 machine # [ 2.842590] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 machine # [ 2.870498] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.881500] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.883080] EXT4-fs (vda): mounted filesystem 822a07e4-b8c3-44bc-b81c-99c69b605b00 r/w with ordered data mode. Quota mode: none. machine # [ 2.746958] systemd[1]: Mounted /sysroot. machine # [ 2.750419] systemd[1]: Reached target Initrd Root File System. machine # [ 2.753195] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 2.822207] ata6: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.823161] ata1: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.824373] ata4: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.825218] ata5: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.826199] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) sshClient # [ 2.827164] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 sshClient # [ 2.828144] ata3.00: applying bridge limits sshClient # [ 2.828882] ata2: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.829826] ata3.00: configured for UDMA/100 sshClient # [ 2.830880] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.771262] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.774086] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.775582] systemd[1]: Reloading... sshClient # [ 2.893517] usbcore: registered new interface driver usbhid sshClient # [ 2.901716] usbhid: USB HID core driver machine # [ 2.850799] systemd[1]: Reloading finished in 77 ms. machine # [ 2.861456] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace... sshClient # [ 2.924857] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 sshClient # [ 2.926618] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 machine # [ 2.865164] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace... machine # [ 2.869834] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 2.933153] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.871190] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.872183] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 2.943406] cdrom: Uniform CD-ROM driver Revision: 3.20 sshClient # [ 2.946927] EXT4-fs (vda): mounted filesystem 77f9855c-fdbf-4e2f-823c-53dc04bbf010 r/w with ordered data mode. Quota mode: none. sshClient # [ 2.811262] systemd[1]: Mounted /sysroot. sshClient # [ 2.813384] systemd[1]: Reached target Initrd Root File System. sshClient # [ 2.816236] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 2.833245] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload. sshClient # [ 2.836240] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 2.837736] systemd[1]: Reloading... sshClient # [ 2.912840] systemd[1]: Reloading finished in 74 ms. sshClient # [ 2.920720] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace... sshClient # [ 2.924151] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace... sshClient # [ 2.928365] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 2.929696] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 2.930761] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.512207] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.518224] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.528185] systemd[1]: Mounting /sysroot/run... machine # [ 3.537190] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.549534] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.582068] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.586072] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.586806] systemd[1]: Mounted /sysroot/run. machine # [ 3.592537] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.595002] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.597793] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.608581] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.610095] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.612283] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.634519] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.635610] systemd[1]: Reached target Initrd File Systems. machine # [ 3.637236] systemd[1]: Starting Find NixOS closure... machine # [ 3.639764] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 3.562219] systemd[1]: Mounting /sysroot/nix/.ro-store... sshClient # [ 3.569150] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.578175] systemd[1]: Mounting /sysroot/run... machine # [ 3.658760] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.667272] systemd[1]: Finished Find NixOS closure. sshClient # [ 3.587177] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.668619] systemd[1]: Reached target Initrd Default Target. machine # [ 3.670659] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 3.597651] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.685277] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.686608] systemd[1]: Stopped target Basic System. machine # [ 3.688235] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.688993] systemd[1]: Stopped target Path Units. machine # [ 3.689668] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.690812] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.691806] systemd[1]: Stopped target Slice Units. machine # [ 3.692780] systemd[1]: Stopped target Socket Units. machine # [ 3.693741] systemd[1]: Stopped target System Initialization. machine # [ 3.694760] systemd[1]: Stopped target Swaps. machine # [ 3.696162] systemd[1]: Stopped target Timer Units. machine # [ 3.696909] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.697685] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.698465] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.699625] systemd[1]: Stopped Find NixOS closure. machine # [ 3.701245] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.702116] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.704297] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.705274] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.706421] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.707880] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.708949] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.710858] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.711901] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.714516] systemd[1]: Stopped target Local File Systems. machine # [ 3.715301] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 3.635993] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.716269] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.717278] systemd[1]: Stopped Coldplug All udev Devices. sshClient # [ 3.639333] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.719411] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.720477] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.722153] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 3.646524] systemd[1]: Mounted /sysroot/run. sshClient # [ 3.647989] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 3.649614] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.731097] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 3.653190] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.733655] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.736611] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.737829] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.743949] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 3.664973] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.745401] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.666705] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.747954] systemd[1]: Starting Cleanup udev Database... machine # [ 3.749168] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.751150] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.751956] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.752992] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.753916] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.754814] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.770828] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.772655] systemd[1]: Finished Cleanup udev Database. machine # [ 3.774218] systemd[1]: Reached target Switch Root. machine # [ 3.775687] systemd[1]: Starting NixOS Activation... machine # [ 3.838390] initrd-nixos-activation-start[189]: booting system configuration /nix/store/66lgp1lkiaiyvwdyhaqxk83ml3s8jvxp-nixos-system-machine-test machine # [ 3.871231] initrd-nixos-activation-start[189]: running activation script... machine # [ 4.059489] initrd-nixos-activation-start[212]: setting up /etc... machine # [ 4.167239] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.170164] systemd[1]: Finished NixOS Activation. machine # [ 4.171251] systemd[1]: Starting Switch Root... machine # [ 4.186462] systemd[1]: Switching root. machine # [ 4.456154] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). machine # [ 4.509882] NET: Registered PF_VSOCK protocol family sshClient # [ 4.560274] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 4.583348] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 4.584672] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.587075] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.589518] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.609224] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 4.611099] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 4.618915] systemd[1]: Finished Find NixOS closure. sshClient # [ 4.620151] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.621780] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 4.636082] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 4.637041] systemd[1]: Stopped target Basic System. sshClient # [ 4.638026] systemd[1]: Stopped target Initrd Root Device. sshClient # [ 4.638888] systemd[1]: Stopped target Path Units. sshClient # [ 4.640101] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. sshClient # [ 4.641181] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. sshClient # [ 4.642367] systemd[1]: Stopped target Slice Units. sshClient # [ 4.643311] systemd[1]: Stopped target Socket Units. sshClient # [ 4.645119] systemd[1]: Stopped target System Initialization. sshClient # [ 4.645962] systemd[1]: Stopped target Swaps. sshClient # [ 4.646641] systemd[1]: Stopped target Timer Units. sshClient # [ 4.647509] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.648517] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.649314] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 4.650500] systemd[1]: Stopped Find NixOS closure. machine # [ 4.867874] systemd[1]: systemd 262-b504aa0 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 # [ 4.871776] systemd[1]: Detected virtualization kvm. sshClient # [ 4.651982] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.872559] systemd[1]: Detected architecture x86-64. sshClient # [ 4.652960] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.873452] systemd[1]: Detected first boot. sshClient # [ 4.654318] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.875633] systemd[1]: Initializing machine ID from random generator. sshClient # [ 4.656357] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 4.657676] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 4.659074] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 4.661104] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 4.662182] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.663187] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 4.664031] systemd[1]: Stopped target Local File Systems. sshClient # [ 4.664744] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 4.667140] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.668170] systemd[1]: Stopped Coldplug All udev Devices. sshClient # [ 4.670121] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.671194] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.672691] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.682636] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 4.686057] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 4.687384] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 4.690180] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 4.697088] systemd[1]: Starting Cleanup udev Database... sshClient # [ 4.697857] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 4.700125] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 4.700945] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 4.702119] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 4.703596] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 4.704610] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 4.706066] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 4.708133] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 4.725478] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. sshClient # [ 4.727145] systemd[1]: Finished Cleanup udev Database. sshClient # [ 4.728285] systemd[1]: Reached target Switch Root. sshClient # [ 4.730069] systemd[1]: Starting NixOS Activation... sshClient # [ 4.786897] initrd-nixos-activation-start[189]: booting system configuration /nix/store/xvd6dma7ynrqg6wrblri5g38f94l1fii-nixos-system-sshClient-test sshClient # [ 4.814683] initrd-nixos-activation-start[189]: running activation script... machine # [ 5.076568] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.143917] systemd[1]: Applying preset policy. sshClient # [ 4.998487] initrd-nixos-activation-start[212]: setting up /etc... machine # [ 5.300971] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 5.103089] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 5.104214] systemd[1]: Finished NixOS Activation. sshClient # [ 5.105745] systemd[1]: Starting Switch Root... sshClient # [ 5.119337] systemd[1]: Switching root. machine # [ 5.465803] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 5.467090] systemd[1]: Stopped initrd-switch-root.service. machine # [ 5.469709] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 5.471731] systemd[1]: Created slice Slice /system/getty. sshClient # [ 5.393915] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). machine # [ 5.473007] systemd[1]: Created slice User and Session Slice. machine # [ 5.473888] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 5.475046] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 5.476119] systemd[1]: Expecting device /dev/hvc0... machine # [ 5.476836] systemd[1]: Expecting device /dev/ttyS0... machine # [ 5.477578] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 5.478375] systemd[1]: Stopped target initrd-fs.target. machine # [ 5.479083] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 5.479869] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 5.480704] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 5.481594] systemd[1]: Reached target Path Units. machine # [ 5.482277] systemd[1]: Reached target Remote File Systems. machine # [ 5.483116] systemd[1]: Reached target Slice Units. machine # [ 5.483817] systemd[1]: Reached target Swaps. machine # [ 5.485235] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 5.498484] systemd[1]: Listening on Process Core Dump Socket. machine # [ 5.500091] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 5.501806] systemd[1]: Listening on Factory Reset Management. machine # [ 5.502702] systemd[1]: Listening on Hostname Service Socket. machine # [ 5.505160] systemd[1]: Starting Journal Log Access Socket... machine # [ 5.506520] systemd[1]: Listening on Journal Audit Socket. machine # [ 5.508819] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 5.510254] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 5.511386] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.512630] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 5.516161] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 5.517175] systemd[1]: Listening on udev Varlink Socket. machine # [ 5.518032] systemd[1]: Listening on User Database Manager Socket. machine # [ 5.520211] systemd[1]: Mounting Huge Pages File System... machine # [ 5.523515] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 5.451572] NET: Registered PF_VSOCK protocol family machine # [ 5.530595] systemd[1]: Mounting Kernel Debug File System... machine # [ 5.536100] systemd[1]: Mounting Kernel Trace File System... machine # [ 5.547738] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 5.563442] systemd[1]: Starting Load Kernel Module configfs... machine # [ 5.567902] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 5.577615] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 5.590425] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 5.606611] systemd[1]: Mounting FUSE Control File System... machine # [ 5.607659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 5.615006] systemd[1]: Starting Journal Service... machine # [ 5.622071] systemd[1]: Starting Load Kernel Modules... machine # [ 5.632799] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 5.651531] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 5.654520] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.666631] systemd[1]: Starting Coldplug All udev Devices... machine # [ 5.676908] systemd[1]: Listening on Journal Log Access Socket. machine # [ 5.680215] systemd-journald[283]: Collecting audit messages is enabled. machine # [ 5.682242] systemd[1]: Mounted Huge Pages File System. machine # [ 5.689094] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 5.697580] systemd[1]: Mounted Kernel Debug File System. machine # [ 5.561843] systemd[1]: Queued start job for default target Multi-User System. machine # [ 5.705614] systemd[1]: Started Journal Service. machine # [ 5.567413] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 5.711752] loop: module loaded machine # [ 5.572408] systemd[1]: Mounted Kernel Trace File System. machine # [ 5.574472] systemd-modules-load[284]: Inserted module 'loop' machine # [ 5.578839] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 5.581290] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 5.583949] systemd[1]: Finished Load Kernel Module configfs. machine # [ 5.588913] systemd[1]: Finished Load Kernel Modules. machine # [ 5.731470] EXT4-fs (vda): re-mounted 822a07e4-b8c3-44bc-b81c-99c69b605b00. machine # [ 5.602159] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 5.608085] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 5.615094] systemd[1]: Mounting Kernel Configuration File System... machine # [ 5.618139] systemd[1]: Starting Firewall... machine # [ 5.623619] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 5.625161] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 5.639628] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 5.650257] systemd-oomd[285]: No swap; memory pressure usage will be degraded machine # [ 5.665120] systemd[1]: Starting Apply Kernel Variables... machine # [ 5.672199] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 5.673349] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.681640] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 5.689886] systemd[1]: Mounted FUSE Control File System. machine # [ 5.882509] systemd-journald[283]: Received client request to flush runtime journal. sshClient # [ 5.807860] systemd[1]: systemd 262-b504aa0 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) sshClient # [ 5.811904] systemd[1]: Detected virtualization kvm. sshClient # [ 5.812721] systemd[1]: Detected architecture x86-64. sshClient # [ 5.813634] systemd[1]: Detected first boot. sshClient # [ 5.815665] systemd[1]: Initializing machine ID from random generator. machine # [ 5.915462] systemd[1]: Mounted Kernel Configuration File System. machine # [ 5.916750] systemd[1]: Finished Apply Kernel Variables. machine # [ 5.919767] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 5.920822] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 5.922737] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 5.988464] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.926128] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 5.958872] systemd[1]: Finished Coldplug All udev Devices. machine # [ 5.973855] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 5.975122] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 5.977615] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 6.077777] systemd[1]: Applying preset policy. machine # [ 6.022997] systemd-udevd[327]: Using default interface naming scheme 'v261'. machine # [ 6.041508] systemd[1]: Starting User Database Manager... machine # [ 6.080438] systemd[1]: Started User Database Manager. machine # [ 6.136640] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 6.246744] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.254545] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.329982] systemd[1]: Mounting /run/wrappers... machine # [ 6.339383] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 6.423930] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.425254] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 6.427723] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.429750] systemd[1]: Created slice Slice /system/getty. sshClient # [ 6.431030] systemd[1]: Created slice User and Session Slice. sshClient # [ 6.431978] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.433205] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.434393] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.372334] systemd[1]: Mounted /run/wrappers. sshClient # [ 6.435092] systemd[1]: Expecting device /dev/ttyS0... sshClient # [ 6.435864] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.373908] systemd[1]: Reached target Local File Systems. sshClient # [ 6.436710] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 6.437502] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 6.438319] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 6.439214] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.440165] systemd[1]: Reached target Path Units. sshClient # [ 6.440901] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.441744] systemd[1]: Reached target Slice Units. machine # [ 6.379128] systemd[1]: Listening on Boot Loader Control Service Socket. sshClient # [ 6.442429] systemd[1]: Reached target Swaps. machine # [ 6.384059] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 6.384833] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 6.388575] systemd[1]: Starting Create System Files and Directories... sshClient # [ 6.444135] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 6.457905] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.459658] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 6.461390] systemd[1]: Listening on Factory Reset Management. sshClient # [ 6.462306] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 6.464943] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.402329] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. sshClient # [ 6.466517] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.469173] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 6.470364] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 6.471466] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.472746] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki sshClient # [ 6.476453] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 6.477483] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 6.478348] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 6.480650] systemd[1]: Mounting Huge Pages File System... sshClient # [ 6.482875] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.424254] (udev-worker)[367]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 6.489356] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.429590] (udev-worker)[361]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 6.432185] (udev-worker)[361]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 6.497358] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.504727] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.515991] systemd[1]: Starting Load Kernel Module configfs... sshClient # [ 6.522748] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.531563] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.543130] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 6.559836] systemd[1]: Mounting FUSE Control File System... sshClient # [ 6.561491] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 6.572867] systemd[1]: Starting Journal Service... machine # [ 6.517256] systemd[1]: Finished Create System Files and Directories. sshClient # [ 6.582829] systemd[1]: Starting Load Kernel Modules... machine # [ 6.527405] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 6.593574] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.534190] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 6.605232] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 6.613892] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.627374] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 6.643872] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 6.650239] systemd[1]: Mounted Huge Pages File System. sshClient # [ 6.654593] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 6.661101] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.605329] systemd[1]: Finished Record System Boot/Shutdown in UTMP. sshClient # [ 6.669439] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 6.677776] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 6.683325] systemd-journald[283]: Collecting audit messages is enabled. sshClient # [ 6.684760] systemd[1]: modprobe@configfs.service: Deactivated successfully. sshClient # [ 6.689640] EXT4-fs (vda): re-mounted 77f9855c-fdbf-4e2f-823c-53dc04bbf010. sshClient # [ 6.691529] systemd[1]: Finished Load Kernel Module configfs. sshClient # [ 6.699121] systemd[1]: Mounted FUSE Control File System. sshClient # [ 6.700349] loop: module loaded machine # [ 6.637578] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 6.643447] systemd[1]: Starting Update is Completed... sshClient # [ 6.708399] systemd[1]: Finished Remount Root and Kernel File Systems. sshClient # [ 6.712703] systemd[1]: Started Journal Service. sshClient # [ 6.572127] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 6.573678] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 6.576465] systemd-modules-load[284]: Inserted module 'loop' sshClient # [ 6.582378] systemd[1]: Finished Load Kernel Modules. machine # [ 6.680371] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 6.603207] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.683056] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.684391] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 6.685500] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.687332] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 6.609103] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.688691] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.690715] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.612465] systemd[1]: Starting Firewall... sshClient # [ 6.617126] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 6.618037] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.704449] systemd[1]: Finished Update is Completed. sshClient # [ 6.626271] systemd-oomd[285]: No swap; memory pressure usage will be degraded sshClient # [ 6.632227] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 6.642102] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 6.663181] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 6.664477] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 6.669086] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. sshClient # [ 6.874229] systemd-journald[283]: Received client request to flush runtime journal. machine # [ 6.990181] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 6.857155] systemd[1]: Finished Firewall. machine # [ 7.027444] ACPI: button: Power Button [PWRF] machine # [ 6.901472] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 6.903985] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 6.905518] systemd[1]: Reached target System Initialization. machine # [ 7.047211] mousedev: PS/2 mouse device common for all mice machine # [ 6.908417] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 6.909619] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 6.910582] systemd[1]: Reached target Timer Units. machine # [ 6.912792] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 6.914505] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine # [ 6.915653] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 6.916736] systemd[1]: Reached target Socket Units. machine # [ 6.917798] systemd[1]: Reached target Basic System. machine # [ 6.920114] systemd[1]: Started backdoor.service. sshClient # [ 6.843481] systemd[1]: Mounted Kernel Configuration File System. machine # [ 6.923401] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 6.844487] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 6.846246] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 6.850389] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 6.936079] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 6.951046] systemd[1]: Starting Post-Boot Actions... machine # [ 6.963794] systemd[1]: Started Reset console on configuration changes. machine # [ 6.972575] systemd[1]: Starting resolvconf update... sshClient # [ 6.907732] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 6.990463] systemd[1]: Starting SSH Host Keys Generation... sshClient # [ 6.912987] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 6.942162] systemd[1]: Finished Coldplug All udev Devices. machine # connecting to host... machine # [ 7.034697] systemd[1]: Starting Home Area Manager... sshClient # [ 6.969768] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 6.971422] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.193121] rtc_cmos PNP0B00:00: RTC can wake from S4 sshClient # [ 6.974696] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.063936] nsncd[510]: Sep 13 02:54:18.193 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.070170] systemd[1]: Started Name Service Cache Daemon (nsncd). machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 7.223101] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine: connected to guest root shell sshClient # [ 7.018546] systemd-udevd[326]: Using default interface naming scheme 'v261'. machine: (connecting took 7.75 seconds) machine: (finished: waiting for the VM to finish booting, in 7.75 seconds) machine # [ 7.106285] systemd[1]: Finished Post-Boot Actions. sshClient # [ 7.037197] systemd[1]: Starting User Database Manager... machine # [ 7.121407] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.137477] systemd[1]: Reached target User and Group Name Lookups. machine # [ 7.157740] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 7.082167] systemd[1]: Started User Database Manager. sshClient # [ 7.137108] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.234941] systemd[1]: Starting User Login Management... machine # [ 7.246659] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 7.250652] systemd-homed[518]: Watching /home. sshClient # [ 7.253830] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 7.286318] systemd[1]: Mounting /run/wrappers... machine # [ 7.377340] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 7.378382] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 7.329917] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.330782] systemd[1]: Reached target Local File Systems. sshClient # [ 7.334168] systemd[1]: Listening on Boot Loader Control Service Socket. sshClient # [ 7.339107] systemd[1]: Starting Create SUID/SGID Wrappers... sshClient # [ 7.339905] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 7.344097] systemd[1]: Starting Create System Files and Directories... sshClient # [ 7.378263] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 7.300030] rtc_cmos PNP0B00:00: registered as rtc0 machine # [ 7.300091] rtc_cmos PNP0B00:00: setting system clock to 2026-09-13T02:54:17 UTC (1789268057) machine # [ 7.300172] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs sshClient # [ 7.422067] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 7.304474] systemd-journald[283]: Time jumped backwards, rotating. machine # [ 7.378262] parport_pc 00:02: reported by Plug and Play ACPI sshClient # [ 7.456343] systemd[1]: Finished Create System Files and Directories. sshClient # [ 7.469149] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 7.480890] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 7.486269] (udev-worker)[362]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 7.489221] (udev-worker)[362]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.393191] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 machine # [ 7.408155] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 7.435930] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] sshClient # [ 7.498180] (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.626399] Console: switching to colour dummy device 80x25 machine # [ 7.654248] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 7.654392] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD sshClient # [ 7.573262] systemd[1]: Finished Record System Boot/Shutdown in UTMP. sshClient # [ 7.603206] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 7.607787] systemd[1]: Starting Update is Completed... machine # [ 7.819139] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 7.819470] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 sshClient # [ 7.651773] systemd[1]: Finished Update is Completed. machine # [ 7.920799] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 7.920801] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 7.380051] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 7.783949] dbus-broker-launch[543]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 7.784975] dbus-broker-launch[543]: NSS returned no entry for 'systemd-timesync' machine # [ 7.930997] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 7.931675] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 7.793155] dbus-broker-launch[543]: Invalid user-name in /nix/store/im04kr52nr8x0nh1gncffni6zlx264nn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 7.795496] systemd[1]: Stopping User and Group Name Lookups... machine # [ 7.804743] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 7.809759] network-addresses-eth1-start[627]: adding address 192.168.1.1/24... done machine # [ 7.816188] network-addresses-eth1-start[627]: adding address 2001:db8:1::1/64... done machine # [ 7.817434] nsncd[599]: Sep 13 02:54:17.822 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.822195] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 7.744768] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 7.746148] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.826795] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 7.747591] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.827768] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 7.748754] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.830337] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 7.751589] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 7.753489] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.755139] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.836622] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.837761] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.844286] systemd[1]: Reached target User and Group Name Lookups. machine # [ 7.845333] dbus-broker-launch[543]: Ready machine # [ 7.845936] systemd[1]: Started Home Area Manager. machine # [ 7.853081] systemd[1]: Finished Home Area Activation. machine # [ 7.853784] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 7.855171] systemd[1]: Finished resolvconf update. machine # [ 7.858282] systemd[1]: Reached target Preparation for Network. machine # [ 7.859247] systemd[1]: Starting DHCP Client... machine # [ 7.859889] systemd[1]: Starting Address configuration of eth1... machine # [ 7.862145] systemd[1]: Starting Extra networking commands.... machine # [ 7.862931] systemd[1]: Starting Virtual Console Setup... machine # [ 7.865329] systemd-logind[547]: New seat seat0. machine # [ 7.869604] systemd[1]: Started User Login Management. machine # [ 7.870405] systemd[1]: Starting linger-users.service... machine # [ 7.871160] systemd[1]: Finished Address configuration of eth1. machine # [ 7.899300] systemd-logind[547]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 7.914765] systemd-logind[547]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 7.921124] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 7.929508] systemd[1]: Started Console Output Muting Service (PID 622/UID 0). sshClient # [ 7.864079] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 7.865820] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 7.870848] systemd[1]: Reached target System Initialization. sshClient # [ 7.872251] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 7.876811] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 7.877831] systemd[1]: Reached target Timer Units. sshClient # [ 7.878866] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 7.880112] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 7.883515] systemd[1]: Reached target Socket Units. sshClient # [ 7.884230] systemd[1]: Reached target Basic System. sshClient # [ 7.888527] systemd[1]: Started backdoor.service. sshClient # [ 7.893118] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 7.974813] dhcpcd[636]: dhcpcd-10.3.2 starting machine # [ 7.977978] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 7.983433] systemd[1]: Finished linger-users.service. sshClient # [ 7.907649] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.996722] dhcpcd[696]: dev: loaded udev sshClient # [ 7.923243] systemd[1]: Starting Post-Boot Actions... sshClient # [ 7.943383] systemd[1]: Started Reset console on configuration changes. sshClient # [ 7.968915] systemd[1]: Starting resolvconf update... sshClient # [ 8.000313] systemd[1]: Starting Home Area Manager... sshClient # [ 8.146004] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console sshClient # [ 8.008403] systemd[1]: Finished Firewall. sshClient # [ 8.153998] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 sshClient # connecting to host... machine # [ 8.032989] systemd[1]: Finished Extra networking commands.. machine # [ 8.114167] systemd[1]: Reached target Network. machine # [ 8.114820] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 8.117134] systemd[1]: Stopped Virtual Console Setup. machine # [ 8.119380] systemd[1]: Starting Virtual Console Setup... sshClient # [ 8.052088] systemd[1]: Finished Post-Boot Actions. sshClient # [ 8.053174] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 8.198430] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 sshClient # [ 8.058500] nsncd[503]: Sep 13 02:54:19.263 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 8.071351] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 8.072911] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 8.221945] ACPI: button: Power Button [PWRF] machine # [ 8.172138] systemd-logind[547]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 8.096930] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 8.113311] systemd[1]: Starting User Login Management... sshClient # [ 8.146524] systemd-homed[510]: Watching /home. sshClient # [ 8.162095] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 8.325681] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 8.272552] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 8.273502] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 8.274982] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 8.276317] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 8.277095] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 8.357605] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 8.360161] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 8.245382] mousedev: PS/2 mouse device common for all mice sshClient # [ 8.285152] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 8.285936] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 8.374738] systemd[1]: Starting Virtual Console Setup... sshClient # [ 8.407975] rtc_cmos PNP0B00:00: RTC can wake from S4 sshClient # [ 8.425100] Console: switching to colour dummy device 80x25 sshClient # [ 8.449671] rtc_cmos PNP0B00:00: registered as rtc0 sshClient # [ 8.449746] rtc_cmos PNP0B00:00: setting system clock to 2026-09-13T02:54:19 UTC (1789268059) machine: (finished: waiting for unit systemd-homed.service, in 9.11 seconds) machine: waiting for Please enter user name to create to appear on tty 1 sshClient # [ 8.449821] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs sshClient # [ 8.498083] parport_pc 00:02: reported by Plug and Play ACPI sshClient # [ 8.498193] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] sshClient # [ 8.517091] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized sshClient # [ 8.576193] [drm] Found bochs VGA, ID 0xb0c5. sshClient # [ 8.576195] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. sshClient # [ 8.295489] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 8.440612] dbus-broker-launch[530]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 8.447341] dbus-broker-launch[530]: NSS returned no entry for 'systemd-timesync' sshClient # [ 8.452356] dbus-broker-launch[530]: Invalid user-name in /nix/store/bi4yrjsf13g0qz9xxkgganzka7gv1piv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 8.454368] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 8.455675] dbus-broker-launch[530]: Ready sshClient # [ 8.462836] nsncd[577]: Sep 13 02:54:19.551 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.543834] dhcpcd[696]: libudev: received NULL device machine # [ 8.544852] dhcpcd[696]: eth0: waiting for carrier machine # [ 8.545558] dhcpcd[696]: libudev: received NULL device machine # [ 8.548115] dhcpcd[696]: eth0: carrier acquired sshClient # [ 8.468961] systemd[1]: Started Home Area Manager. sshClient # [ 8.470584] systemd[1]: Finished Home Area Activation. sshClient # [ 8.476459] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 8.477517] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 8.479717] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 8.624466] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic sshClient # [ 8.625164] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 sshClient # [ 8.484144] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 8.485254] systemd[1]: Finished resolvconf update. sshClient # [ 8.485970] systemd[1]: Reached target Preparation for Network. sshClient # [ 8.489748] systemd[1]: Starting DHCP Client... sshClient # [ 8.490455] systemd[1]: Starting Address configuration of eth1... sshClient # [ 8.492201] systemd[1]: Starting Extra networking commands.... sshClient # [ 8.494587] systemd-logind[531]: New seat seat0. sshClient # [ 8.497360] systemd[1]: Started User Login Management. machine # [ 8.577550] dhcpcd[696]: DUID 00:01:00:01:32:38:cc:da:52:54:00:12:34:56 machine # [ 8.578465] dhcpcd[696]: eth0: IAID 00:12:34:56 machine # [ 8.579093] dhcpcd[696]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 8.499980] systemd[1]: Starting linger-users.service... machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.15 seconds) machine: sending keys 'test-homed-user\n' sshClient # [ 8.544489] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 8.628538] sshd-keygen-start[555]: Generating public/private rsa key pair. machine # [ 8.629476] sshd-keygen-start[555]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 8.630541] sshd-keygen-start[555]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub sshClient # [ 8.551744] systemd[1]: Reached target First Boot Complete. machine # [ 8.631593] sshd-keygen-start[555]: The key fingerprint is: machine # [ 8.632878] sshd-keygen-start[555]: SHA256:PLODELP0Tnf3PDBlpKNBNBk3l6UCCiKZatVQphw63qo root@machine machine # [ 8.634418] sshd-keygen-start[555]: The key's randomart image is: machine # [ 8.635311] sshd-keygen-start[555]: +---[RSA 4096]----+ machine # [ 8.635935] sshd-keygen-start[555]: | .==o. .*oo oo.| machine # [ 8.636632] sshd-keygen-start[555]: | =o+o . o.+ =.. | machine # [ 8.637285] sshd-keygen-start[555]: | +.o+ . . + + | sshClient # [ 8.557585] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 8.637909] sshd-keygen-start[555]: |o.o. = . o = | machine # [ 8.638550] sshd-keygen-start[555]: |.. .o o S o + | machine # [ 8.641101] sshd-keygen-start[555]: | . + o = . = | machine # [ 8.641736] sshd-keygen-start[555]: | . o o + | machine # [ 8.642464] sshd-keygen-start[555]: |. . . | machine # [ 8.645857] sshd-keygen-start[555]: |E | machine # [ 8.646549] sshd-keygen-start[555]: +----[SHA256]-----+ sshClient # [ 8.570146] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 8.576672] systemd[1]: Finished linger-users.service. machine # [ 8.673747] sshd-keygen-start[739]: Generating public/private ed25519 key pair. machine # [ 8.674690] sshd-keygen-start[739]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 8.676088] sshd-keygen-start[739]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub sshClient # [ 8.596608] network-addresses-eth1-start[607]: adding address 192.168.1.2/24... done machine # [ 8.677176] sshd-keygen-start[739]: The key fingerprint is: machine # [ 8.677820] sshd-keygen-start[739]: SHA256:q3qBAyB17uz3/nAZY3dK4+Bi+/Q2PqmMRFX2dy/8Wb8 root@machine machine # [ 8.679105] sshd-keygen-start[739]: The key's randomart image is: machine # [ 8.679823] sshd-keygen-start[739]: +--[ED25519 256]--+ machine # [ 8.680478] sshd-keygen-start[739]: | .. . o | machine # [ 8.681118] sshd-keygen-start[739]: |o o o . | machine # [ 8.681759] sshd-keygen-start[739]: |.. . . . o| machine # [ 8.682437] sshd-keygen-start[739]: | .o . . .o| machine # [ 8.683179] sshd-keygen-start[739]: | .o. S = + + o| machine # [ 8.683800] sshd-keygen-start[739]: | .o .. + O + o+| machine # [ 8.684914] sshd-keygen-start[739]: | ....* = o. .o| machine # [ 8.685696] sshd-keygen-start[739]: | ..= O .= .| machine # [ 8.686720] sshd-keygen-start[739]: | .o..=o=+oo E | machine # [ 8.687393] sshd-keygen-start[739]: +----[SHA256]-----+ machine # [ 8.690227] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 8.691789] systemd[1]: Finished SSH Host Keys Generation. machine # [ 8.695746] systemd[1]: Starting SSH Daemon... sshClient # [ 8.623071] network-addresses-eth1-start[607]: adding address 2001:db8:1::2/64... done machine # [ 8.721801] sshd[741]: Server listening on 0.0.0.0 port 22. machine # [ 8.723567] sshd[741]: Server listening on :: port 22. machine # [ 8.724574] systemd[1]: Started SSH Daemon. sshClient # [ 8.656150] systemd[1]: Finished Address configuration of eth1. machine # [ 8.761793] systemd-vconsole-setup[714]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 8.764991] systemd[1]: Finished Virtual Console Setup. machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Please enter new password for user test-homed-user: to appear on tty 1 sshClient # [ 8.728833] dhcpcd[619]: dhcpcd-10.3.2 starting sshClient # [ 8.736627] dhcpcd[675]: dev: loaded udev sshClient # [ 8.746064] systemd[1]: Finished Extra networking commands.. sshClient # [ 8.750274] systemd[1]: Reached target Network. sshClient # [ 8.753452] systemd[1]: Starting Permit User Sessions... sshClient # [ 8.834221] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt sshClient # [ 8.834385] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD sshClient # [ 8.897732] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 sshClient # [ 8.899306] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 sshClient # [ 8.905460] 8021q: 802.1Q VLAN Support v1.8 sshClient # [ 8.813567] systemd[1]: Starting Virtual Console Setup... sshClient # [ 8.818870] systemd[1]: Finished Permit User Sessions. sshClient # [ 8.905479] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 8.909882] Console: switching to colour frame buffer device 160x50 sshClient # [ 9.011529] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device sshClient # [ 8.871058] systemd[1]: Started Getty on tty1. sshClient # [ 8.876207] systemd[1]: Reached target Login Prompts. sshClient # [ 8.877044] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 8.886153] systemd[1]: Finished Save Transient machine-id to Disk. sshClient # [ 8.898278] systemd-logind[531]: Watching system buttons on /dev/input/event2 (Power Button) sshClient # [ 8.915125] systemd-logind[531]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) sshClient # [ 8.949506] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 8.954174] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 9.109777] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 8.969240] systemd[1]: Starting Virtual Console Setup... sshClient # [ 8.993308] systemd-logind[531]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 9.153865] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 9.154594] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' sshClient # [ 9.157848] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 9.158757] cfg80211: failed to load regulatory.db sshClient # [ 9.222757] ppdev: user-space parallel port driver sshClient # [ 9.246734] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) sshClient # [ 9.249060] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 9.109157] dhcpcd[675]: libudev: received NULL device sshClient # [ 9.109924] dhcpcd[675]: eth0: waiting for carrier sshClient # [ 9.110615] dhcpcd[675]: libudev: received NULL device sshClient # [ 9.111657] dhcpcd[675]: eth0: carrier acquired sshClient # [ 9.126205] dhcpcd[675]: DUID 00:01:00:01:32:38:cc:dc:52:54:00:12:34:56 sshClient # [ 9.127264] dhcpcd[675]: eth0: IAID 00:12:34:56 sshClient # [ 9.127883] dhcpcd[675]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 9.279046] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) sshClient # [ 9.150374] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 9.171392] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 9.176082] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 9.179200] systemd[1]: Starting Virtual Console Setup... sshClient # [ 9.412489] kvm_amd: TSC scaling supported sshClient # [ 9.413059] kvm_amd: Nested Virtualization enabled sshClient # [ 9.414249] kvm_amd: Nested Paging enabled sshClient # [ 9.414897] kvm_amd: LBR virtualization supported sshClient # [ 9.415875] kvm_amd: Virtual VMLOAD VMSAVE supported sshClient # [ 9.418854] kvm_amd: Virtual GIF supported sshClient # [ 9.419596] kvm_amd: Virtual NMI enabled sshClient # [ 9.464680] EDAC MC: Ver: 3.0.0 sshClient # [ 9.378624] systemd-vconsole-setup[706]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 9.382338] systemd[1]: Finished Virtual Console Setup. machine # [ 9.661761] dhcpcd[696]: eth0: soliciting a DHCP lease machine # [ 9.674621] dhcpcd[696]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 9.676191] dhcpcd[696]: eth0: probing address 10.0.2.15/24 machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.08 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for (repeat) to appear on tty 1 sshClient # [ 9.982796] dhcpcd[675]: eth0: soliciting a DHCP lease sshClient # [ 10.133105] NET: Registered PF_PACKET protocol family sshClient # [ 9.995523] dhcpcd[675]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 9.997164] dhcpcd[675]: eth0: probing address 10.0.2.15/24 machine # [ 11.017559] dhcpcd[696]: eth0: soliciting an IPv6 router machine # [ 11.020431] dhcpcd[696]: eth0: Router Advertisement from fe80::2 machine # [ 11.022594] dhcpcd[696]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 11.024739] dhcpcd[696]: eth0: adding route to fec0::/64 machine # [ 11.026479] dhcpcd[696]: eth0: adding default route via fe80::2 machine: (finished: waiting for (repeat) to appear on tty 1, in 1.10 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for unit systemd-homed-firstboot.service sshClient # [ 11.288307] dhcpcd[675]: eth0: soliciting an IPv6 router sshClient # [ 11.291159] dhcpcd[675]: eth0: Router Advertisement from fe80::2 sshClient # [ 11.293355] dhcpcd[675]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 11.295515] dhcpcd[675]: eth0: adding route to fec0::/64 sshClient # [ 11.297324] dhcpcd[675]: eth0: adding default route via fe80::2 sshClient # [ 14.818062] dhcpcd[675]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 14.818968] dhcpcd[675]: eth0: adding route to 10.0.2.0/24 sshClient # [ 14.819726] dhcpcd[675]: eth0: adding default route via 10.0.2.2 sshClient # [ 14.876319] systemd[1]: Started DHCP Client. sshClient # [ 14.878258] systemd[1]: Reached target Multi-User System. sshClient # [ 14.879095] systemd[1]: Startup finished in 890ms (kernel) + 4.401s (initrd) + 9.586s (userspace) = 14.879s. machine # [ 15.129966] dhcpcd[696]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 15.132809] dhcpcd[696]: eth0: adding route to 10.0.2.0/24 machine # [ 15.135318] dhcpcd[696]: eth0: adding default route via 10.0.2.2 machine # [ 15.218936] systemd[1]: Started DHCP Client. machine # [ 21.337512] systemd-homed[518]: Generating key pair for signing local user identity records. machine # [ 21.341582] systemd-homed[518]: Successfully created Ed25519 key pair. machine # [ 21.355553] systemd-homed[518]: test-homed-user: changing state absent → creating machine # [ 21.408478] systemd-homework[873]: Using automatic default storage of 'luks'. machine # [ 21.412823] systemd-homework[873]: Sizing home to 83% of available disk space, which is 743M. machine # [ 21.413903] systemd-homework[873]: Allocating image file completed. machine # [ 21.430638] systemd-homework[873]: Writing of partition table completed. machine # [ 21.434082] systemd-homework[873]: Setting up loopback device /dev/loop0 completed. machine # [ 22.782344] systemd-homework[873]: LUKS formatting completed. machine # [ 25.130932] systemd-homework[873]: Writing password to LUKS keyslot 0 completed. machine # [ 25.355273] systemd-homework[873]: LUKS activation by volume key succeeded. machine # [ 25.380582] systemd-homework[873]: Writing user record as LUKS token completed. machine # [ 25.381587] systemd-homework[873]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 25.382660] systemd-homework[873]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 25.716362] systemd-homework[873]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid a645659a-c74f-4e26-a0c6-4c5cb2b003f2) machine # [ 25.718288] systemd-homework[873]: Formatting file system completed. machine # [ 25.719961] systemd[1]: proc-913-mounts.mount: Deactivated successfully. machine # [ 25.753903] systemd-homework[873]: Mounting file system completed. machine # [ 25.755807] systemd-homework[873]: Skeleton directory /etc/skel missing, ignoring. machine # [ 25.756977] systemd-homework[873]: Wrote embedded .identity file. machine # [ 25.757884] systemd-homework[873]: Replaced embedded blob dir with contents of system blob dir. machine # [ 25.759085] systemd-homework[873]: Recursive changing of ownership completed. machine # [ 25.760292] systemd-homework[873]: Changed top-level directory access mode to 0700. machine # [ 25.768753] systemd-homework[873]: Synchronized disk. machine # [ 25.769864] systemd-homework[873]: Discarded unused 651.8M. machine # [ 25.778442] systemd-homed[518]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 25.785938] systemd-homed[518]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 25.808061] systemd-homed[518]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 25.842361] systemd-homework[873]: Moved image file into place. machine # [ 25.844071] systemd-homework[873]: Creation completed. machine # [ 25.844731] systemd-homework[873]: Image size is 743M, file system size is 725M, file system payload size is 725M, file system free is 718.9M. machine # [ 25.852538] systemd-homed[518]: test-homed-user: changing state creating → inactive machine # [ 25.858883] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 25.861512] systemd[1]: systemd-mute-console@0-1-622_623-0.service: Deactivated successfully. machine # [ 25.865391] systemd[1]: Reached target First Boot Complete. machine # [ 25.867944] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 25.871388] systemd[1]: Starting Permit User Sessions... machine # [ 25.905086] systemd[1]: Finished Permit User Sessions. machine # [ 25.911529] systemd[1]: Started Getty on tty1. machine # [ 25.913165] systemd[1]: Reached target Login Prompts. machine # [ 25.914430] systemd[1]: Reached target Multi-User System. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 14.74 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 26.57 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 25.940233] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 25.945497] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 25.947107] systemd[1]: Startup finished in 852ms (kernel) + 3.498s (initrd) + 21.595s (userspace) = 25.946s. machine # [ 25.954547] systemd-homed[518]: test-homed-user: changing state inactive → updating machine # [ 25.960353] systemd-homework[955]: Offline update requested. Not touching embedded records. machine # [ 25.962591] systemd-homed[518]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 25.969483] systemd-homed[518]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.05 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.03 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 # [ 26.363146] systemd-homed[518]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 26.372636] systemd-homework[970]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 26.383388] systemd-homed[518]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 26.387866] systemd-homed[518]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 26.390652] systemd-homed[518]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 26.393850] systemd-homed[518]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: to appear on tty 1, in 1.08 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 # [ 27.436960] systemd-homed[518]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 27.461362] systemd-homework[982]: Provided password unlocks user record. machine # [ 27.464822] systemd-homework[982]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 27.470079] systemd-homework[982]: Allocated additional 651.8M. machine # [ 27.613873] loop1: detected capacity change from 0 to 1517568 machine # [ 27.474575] systemd-homework[982]: Setting up loopback device /dev/loop1 completed. machine # [ 28.274105] systemd-homework[982]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 28.315289] systemd-homework[982]: Provided password unlocks user record. machine # [ 28.324530] systemd-homework[982]: Probing file system completed (found btrfs). machine # [ 28.328697] systemd-homework[982]: No fsck for file system btrfs installed, ignoring. machine # [ 28.471952] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (254:0) scanned by systemd-homewor (982) machine # [ 28.474646] BTRFS info (device dm-0): first mount of filesystem a645659a-c74f-4e26-a0c6-4c5cb2b003f2 machine # [ 28.475502] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 28.480573] BTRFS info (device dm-0): enabling free space tree machine # [ 28.481107] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 28.341834] systemd-homework[982]: Mounting file system completed. machine # [ 28.345901] systemd-homework[982]: Discovered used loopback device /dev/loop1. machine # [ 28.346868] systemd-homework[982]: offset = 1048576, size = 776994816, image = 779091968 machine # [ 28.355533] systemd-homework[982]: Ready to resize image size 743M → 896M, partition size 741M → 894M, file system size 725M → 878M. machine # [ 28.357038] systemd-homework[982]: Growing of image file from 743M to 896M completed. machine # [ 28.499406] loop1: detected capacity change from 1517568 to 1830912 machine # [ 28.361087] systemd-homework[982]: Refreshing loop device size completed. machine # [ 28.375817] systemd-homework[982]: Growing of partition completed. machine # [ 28.528971] async_tx: api initialized (async) machine # [ 28.438776] systemd-homework[982]: LUKS device growing completed. machine # [ 28.588423] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 760217600 to 920649728 machine # [ 28.449330] systemd-homework[982]: File system resizing from 725M to 878M completed. machine # [ 28.452061] systemd-homework[982]: Synchronized disk. machine # [ 28.452730] systemd-homework[982]: Synchronized disk. machine # [ 28.453398] systemd-homework[982]: Resizing completed. machine # [ 28.454070] systemd-homework[982]: Image size is 896M, file system size is 878M, file system payload size is 878M, file system free is 871.9M. machine # [ 28.455573] systemd-homework[982]: Read embedded .identity file. machine # [ 28.473390] systemd-homework[982]: Provided password unlocks user record. machine # [ 28.474376] systemd-homework[982]: Reconciling header user identity completed (host version was newer). machine # [ 28.475540] systemd-homework[982]: Reconciling embedded user identity completed (host version was newer). machine # [ 28.748082] systemd-homework[982]: Wrote LUKS header user record. machine # [ 28.751231] systemd-homework[982]: Wrote embedded .identity file. machine # [ 28.753657] systemd-homework[982]: Replaced embedded blob dir with contents of system blob dir. machine # [ 28.756909] systemd-homework[982]: Recursive changing of ownership not necessary, skipped. machine # [ 28.797923] systemd-homework[982]: Synchronized disk. machine # [ 28.802281] systemd-homework[982]: Moving to final mount point /home/test-homed-user completed. machine # [ 28.845213] systemd-homework[982]: Activation completed. machine # [ 28.846780] systemd-homework[982]: Image size is 894M, file system size is 878M, file system payload size is 878M, file system free is 871.9M. machine # [ 28.860993] systemd-homed[518]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 28.868464] login[950]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 28.871071] systemd-homed[518]: test-homed-user: changing state activating-for-acquire → active machine # [ 28.880880] systemd-homed[518]: test-homed-user: changing state active → resizing-while-active machine # [ 28.888899] systemd-homework[1026]: Successfully acquired home volume key from kernel keyring. machine # [ 28.891700] systemd-homework[1026]: LUKS volume key from keyring unlocks user record. machine # [ 28.939330] login[950]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 29.023392] systemd-homework[1026]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 29.061287] systemd[1]: Created slice Slice /user/60388. machine # [ 29.063486] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 29.079264] systemd-logind[547]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 29.127202] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 29.138444] systemd[1]: Starting User Manager for UID 60388... machine # [ 29.191364] (systemd)[1031]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 29.248456] (systemd)[1031]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 29.254572] systemd-logind[547]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 29.304321] systemd-homework[1026]: Successfully re-activated LUKS device. machine # [ 29.305881] systemd-homework[1026]: LUKS volume key from keyring unlocks user record. machine # [ 29.307393] systemd-homework[1026]: Discovered used loopback device /dev/loop1. machine # [ 29.308559] systemd-homework[1026]: Read embedded .identity file. machine # [ 29.309515] systemd-homework[1026]: LUKS volume key from keyring unlocks user record. machine # [ 29.310620] systemd-homework[1026]: Reconciling header user identity completed (host version was newer). machine # [ 29.312250] systemd-homework[1026]: Reconciling embedded user identity completed (host version was newer). machine # [ 29.313433] systemd-homework[1026]: offset = 1048576, size = 937426944, image = 939524096 machine # [ 29.316589] systemd[1031]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.334550] systemd-homework[1026]: Ready to resize image size 896M → 764M, partition size 894M → 762M, file system size 878M → 746M. machine # [ 29.337612] systemd-homework[1026]: Wrote embedded .identity file. machine # [ 29.338571] systemd-homework[1026]: Replaced embedded blob dir with contents of system blob dir. machine # [ 29.339649] systemd-homework[1026]: Backing file is fully allocated already. machine # [ 29.411052] systemd[1031]: Queued start job for default target Main User Target. machine # [ 29.413838] systemd[1031]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.416102] systemd[1031]: Created slice User Application Slice. machine # [ 29.416991] systemd[1031]: Started Daily Cleanup of User's Temporary Directories. machine # [ 29.418059] systemd[1031]: Reached target Paths. machine # [ 29.419491] systemd[1031]: Reached target Timers. machine # [ 29.421919] systemd[1031]: Starting D-Bus User Message Bus Socket... machine # [ 29.423142] systemd[1031]: Starting Create User Files and Directories... machine # [ 29.450477] systemd[1031]: Finished Create User Files and Directories. machine # [ 29.469087] systemd[1031]: Listening on D-Bus User Message Bus Socket. machine # [ 29.469947] systemd[1031]: Reached target Sockets. machine # [ 29.470598] systemd[1031]: Reached target Basic System. machine # [ 29.471320] systemd[1]: Started User Manager for UID 60388. machine # [ 29.474617] systemd[1031]: Starting Run user-specific NixOS activation... machine # [ 29.477730] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 29.633429] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 920649728 to 782237696 machine # [ 29.503345] systemd-homework[1026]: File system resizing from 878M to 746M completed. machine # [ 29.504360] systemd-homework[1026]: Synchronized disk. machine # [ 29.523867] systemd[1031]: Finished Run user-specific NixOS activation. machine # [ 29.525417] systemd[1031]: Reached target Main User Target. machine # [ 29.527650] systemd[1031]: Startup finished in 223ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 2.20 seconds) machine: sending keys 'whoami > /tmp/2\n' machine # [ 29.568679] systemd-homework[1026]: LUKS device shrinking completed. machine # [ 29.712319] loop1: detected capacity change from 1830912 to 1560576 machine # [ 29.574758] systemd-homework[1026]: Refreshing loop device size completed. machine # [ 29.575728] systemd-homework[1026]: Shrinking of image file completed. machine # [ 29.593353] systemd-homework[1026]: Shrinking of partition completed. machine # [ 29.606777] systemd-homework[1026]: Wrote LUKS header user record. machine # [ 29.608396] systemd-homework[1026]: Synchronized disk. machine # [ 29.611561] systemd-homework[1026]: Resizing completed. machine # [ 29.612332] systemd-homework[1026]: Image size is 764M, file system size is 746M, file system payload size is 746M, file system free is 739.9M. machine # [ 29.617893] systemd-homed[518]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 29.626046] systemd-homed[518]: test-homed-user: changing state resizing-while-active → active machine # [ 29.628114] systemd-homed[518]: Rebalancing complete. 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.04 seconds) machine: must succeed: cat /tmp/2 machine: (finished: must succeed: cat /tmp/2, in 0.01 seconds) (finished: subtest: login as homed user, in 4.80 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.03 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.07 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 # [ 32.186600] login[685]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 32.222159] systemd[1]: Created slice Slice /user/1000. sshClient # [ 32.224801] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 32.244571] systemd-logind[531]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 32.267584] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 32.271305] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 32.300536] (systemd)[813]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 32.306304] systemd-logind[531]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 32.323215] systemd[813]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 32.404617] systemd[813]: Queued start job for default target Main User Target. sshClient # [ 32.407678] systemd[813]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 32.410137] systemd[813]: Created slice User Application Slice. sshClient # [ 32.411107] systemd[813]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 32.413113] systemd[813]: Reached target Paths. sshClient # [ 32.413856] systemd[813]: Reached target Timers. sshClient # [ 32.416070] systemd[813]: Starting D-Bus User Message Bus Socket... sshClient # [ 32.417951] systemd[813]: Starting Create User Files and Directories... sshClient # [ 32.443917] systemd[813]: Finished Create User Files and Directories. sshClient # [ 32.455512] systemd[813]: Listening on D-Bus User Message Bus Socket. sshClient # [ 32.456385] systemd[813]: Reached target Sockets. sshClient # [ 32.457025] systemd[813]: Reached target Basic System. sshClient # [ 32.457736] systemd[1]: Started User Manager for UID 1000. sshClient # [ 32.461262] systemd[813]: Starting Run user-specific NixOS activation... sshClient # [ 32.464847] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 32.494923] systemd[813]: Finished Run user-specific NixOS activation. sshClient # [ 32.496398] systemd[813]: Reached target Main User Target. sshClient # [ 32.498144] systemd[813]: Startup finished in 178ms. sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.11 seconds) sshClient: sending keys 'whoami > /tmp/1\n' sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds) sshClient: waiting for file '/tmp/1' sshClient: (finished: waiting for file '/tmp/1', in 1.05 seconds) sshClient: must succeed: cat /tmp/1 sshClient: (finished: must succeed: cat /tmp/1, in 0.03 seconds) (finished: subtest: login as regular user, in 3.73 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.04 seconds) sshClient: must succeed: cat /tmp/id_ed25519.pub sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.01 seconds) machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHLF6M3LkHgbGXBvC/oCdVl0JloD8n0KRkwkDcTJCa/K test-normal-user@sshClient' machine # [ 36.093230] systemd-homed[518]: test-homed-user: changing state active → updating-while-active machine # [ 36.102320] systemd-homework[1081]: Offline update requested. Not touching embedded records. machine # [ 36.114253] systemd-homed[518]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHLF6M3LkHgbGXBvC/oCdVl0JloD8n0KRkwkDcTJCa/K test-normal-user@sshClient', in 0.07 seconds) machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519 machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.03 seconds) (finished: subtest: add homed ssh authorized key, in 1.64 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.10 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.10 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/q8bka5k1zvss9dd482k8ivsflp9lkmxw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 38.948474] systemd-homed[518]: test-homed-user: changing state active → passwd-while-active machine # [ 39.624962] systemd-homework[1120]: No valid password for LUKS superblock. machine # [ 41.964253] systemd-homed[518]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 41.969748] systemd-homed[518]: 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.11 seconds) machine: waiting for file '/tmp/3' machine # [ 43.083414] systemd-homed[518]: test-homed-user: changing state active → passwd-while-active machine # [ 44.361323] systemd-homework[1126]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 44.490109] systemd-homework[1126]: Successfully re-activated LUKS device. machine # [ 44.527758] systemd-homework[1126]: Provided password unlocks user record. machine # [ 44.529174] systemd-homework[1126]: Discovered used loopback device /dev/loop1. machine # [ 44.530249] systemd-homework[1126]: Read embedded .identity file. machine # [ 44.566168] systemd-homework[1126]: Provided password unlocks user record. machine # [ 44.568167] systemd-homework[1126]: Reconciling header user identity completed (host version was newer). machine # [ 44.569336] systemd-homework[1126]: Reconciling embedded user identity completed (host version was newer). machine # [ 47.749801] systemd-homework[1126]: Added LUKS key slot 1. machine # [ 47.782692] systemd-homework[1126]: Destroyed LUKS key slot 0. machine # [ 47.795790] systemd-homework[1126]: Wrote LUKS header user record. machine # [ 47.796935] systemd-homework[1126]: Wrote embedded .identity file. machine # [ 47.798140] systemd-homework[1126]: Replaced embedded blob dir with contents of system blob dir. machine # [ 47.805216] systemd-homework[1126]: Synchronized disk. machine # [ 47.809929] systemd-homework[1126]: Everything completed. machine # [ 47.819733] passwd[1094]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine # [ 47.821789] systemd-homed[518]: test-homed-user: changing state passwd-while-active → active machine: (finished: waiting for file '/tmp/3', in 5.14 seconds) machine: must succeed: cat /tmp/3 machine: (finished: must succeed: cat /tmp/3, in 0.03 seconds) (finished: subtest: change homed user password, in 11.97 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 # [ 48.597924] systemd-homed[518]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 48.606611] systemd-homework[1163]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 48.616650] systemd-homed[518]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 48.620637] systemd-homed[518]: 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.02 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 49.629261] systemd-homed[518]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 49.666562] systemd-homework[1179]: Provided password unlocks user record. machine # [ 50.333234] systemd-homework[1179]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 50.460875] systemd-homework[1179]: Successfully re-activated LUKS device. machine # [ 50.481381] systemd-homework[1179]: Provided password unlocks user record. machine # [ 50.483135] systemd-homework[1179]: Discovered used loopback device /dev/loop1. machine # [ 50.484095] systemd-homework[1179]: Read embedded .identity file. machine # [ 50.502078] systemd-homework[1179]: Provided password unlocks user record. machine # [ 50.503139] systemd-homework[1179]: Reconciling user identities completed (host and header version were identical). machine # [ 50.504573] systemd-homework[1179]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 50.515299] systemd-homework[1179]: Everything completed. machine # [ 50.519345] systemd-homed[518]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 50.525559] systemd-homed[518]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 50.527262] sudo[1161]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 50.531994] sudo[1161]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 50.541839] sudo[1161]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 50.548496] sudo[1161]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.03 seconds) (finished: subtest: escalate to root from homed user, in 2.47 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 # [ 50.682427] login[950]: pam_unix(login:session): session closed for user test-homed-user machine # [ 50.684501] login[950]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 50.689272] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 50.696779] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 50.699935] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 50.702243] systemd-logind[547]: Session 1 logged out. Waiting for processes to exit. machine # [ 50.707084] systemd[1]: Started Getty on tty1. machine # [ 50.710081] systemd-logind[547]: Removed session 1. machine # [ 60.773566] systemd[1]: Stopping User Manager for UID 60388... machine # [ 60.784206] systemd[1031]: Activating special unit Exit the Session... machine # [ 60.786560] systemd[1031]: Stopped target Main User Target. machine # [ 60.789968] systemd[1031]: Stopped Run user-specific NixOS activation. machine # [ 60.793853] systemd[1031]: Stopped target Basic System. machine # [ 60.796447] systemd[1031]: Stopped target Paths. machine # [ 60.799421] systemd[1031]: Stopped target Sockets. machine # [ 60.802556] systemd[1031]: Stopped target Timers. machine # [ 60.806589] systemd[1031]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 60.809903] systemd[1031]: Closed D-Bus User Message Bus Socket. machine # [ 60.812858] systemd[1031]: Stopped Create User Files and Directories. machine # [ 60.817107] systemd[1031]: Removed slice User Application Slice. machine # [ 60.819711] systemd[1031]: Reached target Shutdown. machine # [ 60.822258] systemd[1031]: Finished Exit the Session. machine # [ 60.823962] systemd[1031]: Reached target Exit the Session. machine # [ 60.828550] systemd-logind[547]: Removed session 2. machine # [ 60.835416] systemd-homed[518]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 60.841679] systemd-homed[518]: test-homed-user: changing state active → deactivating machine # [ 60.842728] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 60.846947] systemd[1]: Stopped User Manager for UID 60388. machine # [ 60.847976] systemd-homework[1256]: Successfully acquired home volume key from kernel keyring. machine # [ 60.850954] systemd-homework[1256]: Discarded unused 673.3M. machine # [ 60.860378] systemd-homework[1256]: Syncing completed. machine # [ 60.863676] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 60.871513] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 60.896087] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 60.901442] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 60.902822] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 60.926751] systemd-homework[1256]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 61.054312] systemd-homework[1256]: Successfully re-activated LUKS device. machine # [ 61.056084] systemd-homework[1256]: Discovered used loopback device /dev/loop1. machine # [ 61.057072] systemd-homework[1256]: offset = 1048576, size = 799014912, image = 801112064 machine # [ 61.067591] systemd-homework[1256]: Ready to resize image size 764M → 274M, partition size 762M → 272M, file system size 746M → 256M. machine # [ 61.069696] systemd-homework[1256]: Allocated additional 673.3M. machine # [ 61.215978] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 782237696 to 268435456 machine # [ 61.076941] systemd-homework[1256]: File system resizing from 746M to 256M completed. machine # [ 61.078170] systemd-homework[1256]: Synchronized disk. machine # [ 61.195607] systemd-homework[1256]: LUKS device shrinking completed. machine # [ 61.338420] loop1: detected capacity change from 1560576 to 557056 machine # [ 61.201120] systemd-homework[1256]: Refreshing loop device size completed. machine # [ 61.202126] systemd-homework[1256]: Shrinking of image file completed. machine # [ 61.218789] systemd-homework[1256]: Shrinking of partition completed. machine # [ 61.219668] systemd-homework[1256]: Synchronized disk. machine # [ 61.220378] systemd-homework[1256]: Resizing completed. machine # [ 61.221129] systemd-homework[1256]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 61.362923] BTRFS info (device dm-0): last unmount of filesystem a645659a-c74f-4e26-a0c6-4c5cb2b003f2 machine # [ 61.225485] systemd-homework[1256]: Unmounting completed. machine # [ 61.226549] systemd-homework[1256]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 61.228505] systemd-homework[1256]: Device home-test-homed-user is not active. machine # [ 61.238533] systemd-homed[518]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 61.244780] systemd-homework[1256]: Everything completed. machine # [ 61.253413] systemd-homed[518]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.56 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.61 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 # [ 63.970784] systemd-homed[518]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 63.974848] systemd-homework[1293]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 63.982715] systemd-homed[518]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 63.984631] systemd-homed[518]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 63.985931] sshd-session[1289]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 63.987446] systemd-homed[518]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 63.990208] sshd-session[1289]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 63.991901] sshd-session[1289]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 37170 ssh2: ED25519 SHA256:EqfcihfEuXARVn5RBMUTO7VoFtsuPR1jVbuziX+ykAU machine # [ 64.030920] sshd-session[1289]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 64.042837] systemd-logind[547]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 64.046083] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 64.068670] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 64.073804] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 64.091586] sshd-session[1289]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 64.094536] sshd-session[1289]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 64.110204] systemd-homed[518]: test-homed-user: changing state inactive → activating machine # [ 64.114429] systemd-homework[1298]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 64.121138] systemd-homed[518]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 64.122931] systemd-homed[518]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.13 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 # [ 65.322775] systemd-homed[518]: test-homed-user: changing state inactive → activating machine # [ 65.347604] systemd-homework[1303]: Provided password unlocks user record. machine # [ 65.351757] systemd-homework[1303]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 65.356362] systemd-homework[1303]: Backing file is fully allocated already. machine # [ 65.500888] loop1: detected capacity change from 0 to 557056 machine # [ 65.361758] systemd-homework[1303]: Setting up loopback device /dev/loop1 completed. machine # [ 66.336289] systemd-homework[1303]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 66.363284] systemd-homework[1303]: Provided password unlocks user record. machine # [ 66.368460] systemd-homework[1303]: Probing file system completed (found btrfs). machine # [ 66.369876] systemd-homework[1303]: No fsck for file system btrfs installed, ignoring. machine # [ 66.512304] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (254:0) scanned by systemd-homewor (1303) machine # [ 66.515245] BTRFS info (device dm-0): first mount of filesystem a645659a-c74f-4e26-a0c6-4c5cb2b003f2 machine # [ 66.516108] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 66.521056] BTRFS info (device dm-0): enabling free space tree machine # [ 66.521689] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 66.382408] systemd-homework[1303]: Mounting file system completed. machine # [ 66.384213] systemd-homework[1303]: Discovered used loopback device /dev/loop1. machine # [ 66.385211] systemd-homework[1303]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 66.394685] systemd-homework[1303]: Ready to resize image size 274M → 764M, partition size 272M → 762M, file system size 256M → 746M. machine # [ 66.396685] systemd-homework[1303]: Growing of image file from 274M to 764M completed. machine # [ 66.539222] loop1: detected capacity change from 557056 to 1560576 machine # [ 66.400137] systemd-homework[1303]: Refreshing loop device size completed. machine # [ 66.415964] systemd-homework[1303]: Growing of partition completed. machine # [ 66.448593] systemd-homework[1303]: LUKS device growing completed. machine # [ 66.594369] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 782237696 machine # [ 66.455393] systemd-homework[1303]: File system resizing from 256M to 746M completed. machine # [ 66.457416] systemd-homework[1303]: Synchronized disk. machine # [ 66.458179] systemd-homework[1303]: Synchronized disk. machine # [ 66.458859] systemd-homework[1303]: Resizing completed. machine # [ 66.459549] systemd-homework[1303]: Image size is 764M, file system size is 746M, file system payload size is 746M, file system free is 739.9M. machine # [ 66.461094] systemd-homework[1303]: Read embedded .identity file. machine # [ 66.481997] systemd-homework[1303]: Provided password unlocks user record. machine # [ 66.483036] systemd-homework[1303]: Reconciling user identities completed (host and header version were identical). machine # [ 66.484301] systemd-homework[1303]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 66.486065] systemd-homework[1303]: Recursive changing of ownership not necessary, skipped. machine # [ 66.487573] systemd-homework[1303]: Synchronized disk. machine # [ 66.489544] systemd-homework[1303]: Moving to final mount point /home/test-homed-user completed. machine # [ 66.512709] systemd-homework[1303]: Activation completed. machine # [ 66.513683] systemd-homework[1303]: Image size is 762M, file system size is 746M, file system payload size is 746M, file system free is 739.9M. machine # [ 66.527813] systemd-homed[518]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 66.535913] systemd-homed[518]: test-homed-user: changing state activating → active machine # [ 66.547411] systemd[1]: Starting User Manager for UID 60388... machine # [ 66.572423] (systemd)[1344]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 66.616508] (systemd)[1344]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 66.622364] systemd-logind[547]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 66.640241] systemd[1344]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.716450] systemd[1344]: Queued start job for default target Main User Target. machine # [ 66.718760] systemd[1344]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.721362] systemd[1344]: Created slice User Application Slice. machine # [ 66.723203] systemd[1344]: Started Daily Cleanup of User's Temporary Directories. machine # [ 66.724179] systemd[1344]: Reached target Paths. machine # [ 66.724832] systemd[1344]: Reached target Timers. machine # [ 66.727077] systemd[1344]: Starting D-Bus User Message Bus Socket... machine # [ 66.728919] systemd[1344]: Starting Create User Files and Directories... machine # [ 66.757495] systemd[1344]: Listening on D-Bus User Message Bus Socket. machine # [ 66.759264] systemd[1344]: Reached target Sockets. machine # [ 66.762093] systemd[1344]: Finished Create User Files and Directories. machine # [ 66.762960] systemd[1344]: Reached target Basic System. machine # [ 66.764549] systemd[1]: Started User Manager for UID 60388. machine # [ 66.767152] systemd[1344]: Starting Run user-specific NixOS activation... machine # [ 66.803107] systemd[1344]: Finished Run user-specific NixOS activation. machine # [ 66.804434] systemd[1344]: Reached target Main User Target. machine # [ 66.805373] systemd[1344]: Startup finished in 170ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.15 seconds) sshClient: sending keys 'whoami > /tmp/5\n' sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds) machine: waiting for file '/tmp/5' machine: (finished: waiting for file '/tmp/5', in 1.06 seconds) machine: must succeed: cat /tmp/5 machine: (finished: must succeed: cat /tmp/5, in 0.02 seconds) sshClient: sending keys 'exit\n' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) sshClient: sending keys 'exit\n' machine # [ 68.722721] sshd-session[1296]: Received disconnect from 2001:db8:1::2 port 37170:11: disconnected by user machine # [ 68.729820] sshd-session[1296]: Disconnected from user test-homed-user 2001:db8:1::2 port 37170 sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.53 seconds) (finished: run the VM test script, in 69.85 seconds) machine # [ 68.735640] sshd-session[1289]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 68.741700] sshd-session[1289]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 68.754547] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 68.763454] systemd-logind[547]: Session 3 logged out. Waiting for processes to exit. machine # [ 68.768318] systemd-logind[547]: Removed session 3. sshClient # [ 68.737966] login[685]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 68.748992] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 68.770819] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 68.777224] systemd[1]: session-1.scope: Consumed 147ms CPU time over 36.305s wall clock time, 9.4M memory peak, 12K written to disk, 25.3K incoming IP traffic, 24.1K outgoing IP traffic. sshClient # [ 68.784513] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 68.793748] systemd-logind[531]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 68.801567] systemd[1]: Started Getty on tty1. sshClient # [ 68.804127] systemd-logind[531]: Removed session 1. test script finished in 70.04s cleanup kill QemuMachine (pid 48) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) machine # [2026-09-13T02:55:20Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-13T02:55:20Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-13T02:55:20Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 47) sshClient # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) sshClient # [2026-09-13T02:55:20Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-09-13T02:55:20Z INFO virtiofsd] Client disconnected, shutting down sshClient # [2026-09-13T02:55:20Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.35 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