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.01 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm sshClient: QEMU running (pid 48) sshClient # Disk image does not exist, creating the virtualisation disk image... sshClient # Formatting '/build/vm-state-sshClient/tmp.GNdkVPh0U3', fmt=raw size=1073741824 sshClient # mke2fs 1.47.4 (6-Mar-2025) sshClient # Discarding device blocks: 0/262144 done sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes sshClient # Filesystem UUID: 51872491-804b-47ff-9689-9e95a3f0563e sshClient # Superblock backups stored on blocks: sshClient # 32768, 98304, 163840, 229376 sshClient # sshClient # Allocating group tables: 0/8 done sshClient # Writing inode tables: 0/8 done sshClient # Creating journal (8192 blocks): done sshClient # Writing superblocks and filesystem accounting information: 0/8 done sshClient # sshClient # Virtualisation disk image created. machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 47) machine # Formatting '/build/vm-state-machine/tmp.yn6akPMP1C', 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: a14a70c9-7e91-4905-9fc6-c2e2cd9c68c8 (finished: start all VMs, in 0.32 seconds) machine # Superblock backups stored on blocks: subtest: create systemd-homed user on first boot prompt machine # 32768, 98304, 163840, 229376 machine: waiting for unit systemd-homed.service machine # machine: waiting for the VM to finish booting 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. sshClient # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) sshClient # sshClient # sshClient # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 sshClient # Press Ctrl-B to configure iPXE (PCI 00:02.0)... sshClient # sshClient # sshClient # sshClient # sshClient # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 sshClient # Press Ctrl-B to configure iPXE (PCI 00:08.0)... sshClient # sshClient # 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:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)... machine # machine # machine # Booting from ROM... sshClient # Booting from ROM... sshClient # Probing EDD (edd=off to disable)... ok machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 7 15:23:00 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/snf179hyv8fqssx2q1z1jg1hzbx0n949-nixos-system-machine-test/init regInfo=/nix/store/97qnv4m2p05xsarj6fl35d1ldmcik3a4-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 sshClient # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 7 15:23:00 UTC 2026 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 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/hxdxb8q25kfcw5jhacl5knylm0vmjzzv-nixos-system-sshClient-test/init regInfo=/nix/store/wgn40m61g0vvnqp1ywjz6dip8qla8y7v-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] NX (Execute Disable) protection: active sshClient # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] APIC: Static calls initialized sshClient # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] SMBIOS 2.8 present. sshClient # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 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] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM sshClient # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 sshClient # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.000000] kvm-clock: using sched offset of 528642447 cycles sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000005] tsc: Detected 2400.010 MHz processor sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000812] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 sshClient # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000850] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs sshClient # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000853] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT sshClient # [ 0.000000] APIC: Static calls initialized sshClient # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.002765] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002776] Using GB pages for direct mapping sshClient # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.002843] RAMDISK: [mem 0x3e425000-0x3ffcffff] sshClient # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.002850] ACPI: Early table checksum verification disabled sshClient # [ 0.000000] Hypervisor detected: KVM machine # [ 0.002853] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) sshClient # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 sshClient # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.002857] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.000001] kvm-clock: using sched offset of 576945885 cycles machine # [ 0.002862] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.002869] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.000005] tsc: Detected 2400.010 MHz processor machine # [ 0.002870] ACPI: FACS 0x000000003FFE0000 000040 sshClient # [ 0.000817] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.002872] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.000854] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.002873] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.000857] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT sshClient # [ 0.002777] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002875] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.002788] Using GB pages for direct mapping sshClient # [ 0.002892] RAMDISK: [mem 0x3e426000-0x3ffcffff] machine # [ 0.002876] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.002900] ACPI: Early table checksum verification disabled machine # [ 0.002878] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] sshClient # [ 0.002902] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002879] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] sshClient # [ 0.002906] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002879] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] sshClient # [ 0.002910] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002880] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002880] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] sshClient # [ 0.002917] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) sshClient # [ 0.002919] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002881] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] sshClient # [ 0.002920] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002881] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003366] No NUMA configuration found sshClient # [ 0.002922] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003367] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] sshClient # [ 0.002923] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003370] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005879] Zone ranges: sshClient # [ 0.002925] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.005880] DMA [mem 0x0000000000001000-0x0000000000ffffff] sshClient # [ 0.002926] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.005882] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005883] Normal empty sshClient # [ 0.002927] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.005884] Device empty machine # [ 0.005885] Movable zone start for each node sshClient # [ 0.002928] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.005885] Early memory node ranges sshClient # [ 0.002928] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.005886] node 0: [mem 0x0000000000001000-0x000000000009efff] sshClient # [ 0.002929] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.005887] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] sshClient # [ 0.002929] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.005888] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] sshClient # [ 0.002930] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.005907] On node 0, zone DMA: 1 pages in unavailable ranges sshClient # [ 0.003422] No NUMA configuration found machine # [ 0.006177] On node 0, zone DMA: 97 pages in unavailable ranges sshClient # [ 0.003423] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.024358] On node 0, zone DMA32: 40 pages in unavailable ranges sshClient # [ 0.003426] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.025498] ACPI: PM-Timer IO Port: 0x608 sshClient # [ 0.005940] Zone ranges: machine # [ 0.025511] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) sshClient # [ 0.005941] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.025544] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 sshClient # [ 0.005943] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] sshClient # [ 0.005944] Normal empty machine # [ 0.025547] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) sshClient # [ 0.005944] Device empty sshClient # [ 0.005945] Movable zone start for each node machine # [ 0.025548] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) sshClient # [ 0.005946] Early memory node ranges machine # [ 0.025549] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) sshClient # [ 0.005946] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.025550] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) sshClient # [ 0.005947] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.025551] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) sshClient # [ 0.005948] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.025553] ACPI: Using ACPI (MADT) for SMP configuration information sshClient # [ 0.005967] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.025554] ACPI: HPET id: 0x8086a201 base: 0xfed00000 sshClient # [ 0.006240] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.025558] TSC deadline timer available machine # [ 0.025562] CPU topo: Max. logical packages: 1 sshClient # [ 0.024625] On node 0, zone DMA32: 40 pages in unavailable ranges sshClient # [ 0.025750] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.025563] CPU topo: Max. logical dies: 1 machine # [ 0.025563] CPU topo: Max. dies per package: 1 sshClient # [ 0.025763] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.025566] CPU topo: Max. threads per core: 1 sshClient # [ 0.025794] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.025571] CPU topo: Num. cores per package: 1 sshClient # [ 0.025797] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.025572] CPU topo: Num. threads per package: 1 machine # [ 0.025572] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs sshClient # [ 0.025799] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) sshClient # [ 0.025800] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.025590] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() sshClient # [ 0.025801] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.025625] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] sshClient # [ 0.025802] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.025627] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] sshClient # [ 0.025804] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.025628] [mem 0x40000000-0xafffffff] available for PCI devices sshClient # [ 0.025805] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.025629] Booting paravirtualized kernel on KVM sshClient # [ 0.025810] TSC deadline timer available sshClient # [ 0.025814] CPU topo: Max. logical packages: 1 machine # [ 0.025632] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns sshClient # [ 0.025814] CPU topo: Max. logical dies: 1 sshClient # [ 0.025815] CPU topo: Max. dies per package: 1 machine # [ 0.030073] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 sshClient # [ 0.025818] CPU topo: Max. threads per core: 1 machine # [ 0.032360] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 sshClient # [ 0.025818] CPU topo: Num. cores per package: 1 machine # [ 0.032405] kvm-guest: PV spinlocks disabled, single CPU sshClient # [ 0.025819] CPU topo: Num. threads per package: 1 sshClient # [ 0.025819] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs sshClient # [ 0.025836] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() sshClient # [ 0.025872] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] sshClient # [ 0.025873] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.032407] 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/snf179hyv8fqssx2q1z1jg1hzbx0n949-nixos-system-machine-test/init regInfo=/nix/store/97qnv4m2p05xsarj6fl35d1ldmcik3a4-closure-info/registration console=ttyS0,115200n8 console=tty0 sshClient # [ 0.025874] [mem 0x40000000-0xafffffff] available for PCI devices sshClient # [ 0.025876] Booting paravirtualized kernel on KVM machine # [ 0.032503] Unknown kernel command line parameters "regInfo=/nix/store/97qnv4m2p05xsarj6fl35d1ldmcik3a4-closure-info/registration", will be passed to user space. machine # [ 0.032517] random: crng init done sshClient # [ 0.025879] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.032517] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes sshClient # [ 0.030443] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.032538] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) sshClient # [ 0.032692] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 sshClient # [ 0.032736] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.032550] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.032582] Fallback order for Node 0: 0 machine # [ 0.032585] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.032586] Policy zone: DMA32 machine # [ 0.035161] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.037612] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.032737] 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/hxdxb8q25kfcw5jhacl5knylm0vmjzzv-nixos-system-sshClient-test/init regInfo=/nix/store/wgn40m61g0vvnqp1ywjz6dip8qla8y7v-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.040122] allocated 2097152 bytes of page_ext machine # [ 0.050038] ftrace: allocating 48731 entries in 192 pages machine # [ 0.050040] ftrace: allocated 192 pages with 2 groups sshClient # [ 0.032827] Unknown kernel command line parameters "regInfo=/nix/store/wgn40m61g0vvnqp1ywjz6dip8qla8y7v-closure-info/registration", will be passed to user space. machine # [ 0.050886] Dynamic Preempt: lazy sshClient # [ 0.032840] random: crng init done machine # [ 0.051061] rcu: Preemptible hierarchical RCU implementation. sshClient # [ 0.032841] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.051061] rcu: RCU event tracing is enabled. machine # [ 0.051062] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.032861] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.051063] Trampoline variant of Tasks RCU enabled. sshClient # [ 0.032874] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.051064] Rude variant of Tasks RCU enabled. sshClient # [ 0.032904] Fallback order for Node 0: 0 machine # [ 0.051064] Tracing variant of Tasks RCU enabled. sshClient # [ 0.032907] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.051065] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. sshClient # [ 0.032908] Policy zone: DMA32 machine # [ 0.051066] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 sshClient # [ 0.035461] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.037929] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.051081] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.040253] allocated 2097152 bytes of page_ext sshClient # [ 0.050255] ftrace: allocating 48731 entries in 192 pages machine # [ 0.051083] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.050257] ftrace: allocated 192 pages with 2 groups sshClient # [ 0.051098] Dynamic Preempt: lazy machine # [ 0.051083] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.051267] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.055570] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 sshClient # [ 0.051268] rcu: RCU event tracing is enabled. machine # [ 0.055845] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.051268] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. sshClient # [ 0.051269] Trampoline variant of Tasks RCU enabled. machine # [ 0.055852] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns sshClient # [ 0.051270] Rude variant of Tasks RCU enabled. sshClient # [ 0.051270] Tracing variant of Tasks RCU enabled. machine # [ 0.055959] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.063276] Console: colour VGA+ 80x25 sshClient # [ 0.051271] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.063280] printk: legacy console [tty0] enabled sshClient # [ 0.051272] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.106693] printk: legacy console [ttyS0] enabled machine # [ 0.307557] ACPI: Core revision 20250807 sshClient # [ 0.051297] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. sshClient # [ 0.051299] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.309082] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.311957] APIC: Switch to symmetric I/O mode setup sshClient # [ 0.051300] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.313711] x2apic enabled sshClient # [ 0.055675] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.314962] APIC: Switched APIC routing to: physical x2apic sshClient # [ 0.055949] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.055955] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.317875] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 sshClient # [ 0.056058] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.063371] Console: colour VGA+ 80x25 machine # [ 0.319741] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns sshClient # [ 0.063374] printk: legacy console [tty0] enabled sshClient # [ 0.106498] printk: legacy console [ttyS0] enabled machine # [ 0.322855] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010) sshClient # [ 0.306597] ACPI: Core revision 20250807 machine # [ 0.325097] x86/cpu: User Mode Instruction Prevention (UMIP) activated sshClient # [ 0.308275] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.326011] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 sshClient # [ 0.311148] APIC: Switch to symmetric I/O mode setup machine # [ 0.327853] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 sshClient # [ 0.312907] x2apic enabled sshClient # [ 0.314151] APIC: Switched APIC routing to: physical x2apic machine # [ 0.328858] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto sshClient # [ 0.317012] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.330853] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.332854] Transient Scheduler Attacks: Mitigation: Clear CPU buffers sshClient # [ 0.318881] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns machine # [ 0.333854] Spectre V2 : Mitigation: Enhanced / Automatic IBRS sshClient # [ 0.322003] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010) machine # [ 0.334853] Speculative Return Stack Overflow: Mitigation: Safe RET sshClient # [ 0.324120] x86/cpu: User Mode Instruction Prevention (UMIP) activated sshClient # [ 0.325147] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 sshClient # [ 0.326001] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.335853] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization sshClient # [ 0.327005] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.337863] Spectre V2 : Enabling IBPB for BPF sshClient # [ 0.328001] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.339855] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier sshClient # [ 0.330001] Transient Scheduler Attacks: Mitigation: Clear CPU buffers machine # [ 0.340854] active return thunk: srso_alias_return_thunk sshClient # [ 0.331001] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.342883] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' sshClient # [ 0.332001] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.344853] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.346853] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.348853] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.350853] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' sshClient # [ 0.334001] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.352853] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' sshClient # [ 0.336010] Spectre V2 : Enabling IBPB for BPF machine # [ 0.353853] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' sshClient # [ 0.338003] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.355854] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' sshClient # [ 0.339002] active return thunk: srso_alias_return_thunk machine # [ 0.357854] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)' sshClient # [ 0.341030] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.358854] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 sshClient # [ 0.342001] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.360853] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 sshClient # [ 0.344001] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.362853] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 sshClient # [ 0.345001] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.363853] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 sshClient # [ 0.346001] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.364853] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 sshClient # [ 0.347001] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.365853] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16 sshClient # [ 0.348001] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.366854] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24 sshClient # [ 0.349001] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' machine # [ 0.367854] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format. sshClient # [ 0.351001] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)' sshClient # [ 0.352001] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 sshClient # [ 0.354001] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 sshClient # [ 0.355001] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 sshClient # [ 0.356001] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 sshClient # [ 0.357001] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 sshClient # [ 0.358001] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16 sshClient # [ 0.359001] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24 sshClient # [ 0.360003] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format. machine # [ 0.402620] Freeing SMP alternatives memory: 44K machine # [ 0.403855] pid_max: default: 32768 minimum: 301 machine # [ 0.404945] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.406954] landlock: Up and running. machine # [ 0.408853] Yama: becoming mindful. machine # [ 0.410067] LSM support for eBPF active machine # [ 0.410995] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.412880] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.415402] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.416391] Performance Events: Fam17h+ core perfctr, AMD PMU driver. sshClient # [ 0.396246] Freeing SMP alternatives memory: 44K machine # [ 0.416859] ... version: 2 sshClient # [ 0.397009] pid_max: default: 32768 minimum: 301 machine # [ 0.417855] ... bit width: 48 sshClient # [ 0.398104] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.418869] ... generic counters: 6 sshClient # [ 0.399109] landlock: Up and running. machine # [ 0.419855] ... generic bitmap: 000000000000003f sshClient # [ 0.400001] Yama: becoming mindful. machine # [ 0.420855] ... fixed-purpose counters: 0 sshClient # [ 0.401225] LSM support for eBPF active machine # [ 0.421855] ... fixed-purpose bitmap: 0000000000000000 sshClient # [ 0.403118] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.422855] ... value mask: 0000ffffffffffff machine # [ 0.423855] ... max period: 00007fffffffffff sshClient # [ 0.404029] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.424855] ... global_ctrl mask: 000000000000003f machine # [ 0.425969] signal: max sigframe size: 3376 sshClient # [ 0.406966] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.426999] rcu: Hierarchical SRCU implementation. machine # [ 0.427860] rcu: Max phase no-delay instances is 400. sshClient # [ 0.407595] Performance Events: Fam17h+ core perfctr, AMD PMU driver. sshClient # [ 0.408006] ... version: 2 sshClient # [ 0.409005] ... bit width: 48 sshClient # [ 0.410017] ... generic counters: 6 machine # [ 0.433544] smp: Bringing up secondary CPUs ... machine # [ 0.433871] smp: Brought up 1 node, 1 CPU sshClient # [ 0.411003] ... generic bitmap: 000000000000003f sshClient # [ 0.412003] ... fixed-purpose counters: 0 machine # [ 0.434858] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) sshClient # [ 0.413003] ... fixed-purpose bitmap: 0000000000000000 sshClient # [ 0.414003] ... value mask: 0000ffffffffffff machine # [ 0.436023] Memory: 941804K/1048024K available (17223K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98856K reserved, 0K cma-reserved) sshClient # [ 0.415014] ... max period: 00007fffffffffff machine # [ 0.437093] devtmpfs: initialized sshClient # [ 0.416003] ... global_ctrl mask: 000000000000003f machine # [ 0.438070] x86/mm: Memory block size: 128MB sshClient # [ 0.417134] signal: max sigframe size: 3376 sshClient # [ 0.418106] rcu: Hierarchical SRCU implementation. machine # [ 0.439808] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.419007] rcu: Max phase no-delay instances is 400. machine # [ 0.440895] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.441946] pinctrl core: initialized pinctrl subsystem machine # [ 0.443203] PM: RTC time: 02:43:22, date: 2026-09-11 sshClient # [ 0.424591] smp: Bringing up secondary CPUs ... sshClient # [ 0.425028] smp: Brought up 1 node, 1 CPU sshClient # [ 0.426006] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.446781] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.448244] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.427170] Memory: 941828K/1048024K available (17223K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98852K reserved, 0K cma-reserved) sshClient # [ 0.428247] devtmpfs: initialized machine # [ 0.448876] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.429252] x86/mm: Memory block size: 128MB machine # [ 0.450016] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.430996] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.450868] audit: initializing netlink subsys (disabled) sshClient # [ 0.432041] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.452155] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.433250] pinctrl core: initialized pinctrl subsystem machine # [ 0.452157] thermal_sys: Registered thermal governor 'bang_bang' sshClient # [ 0.434411] PM: RTC time: 02:43:22, date: 2026-09-11 machine # [ 0.452856] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.453860] audit: type=2000 audit(1789094602.544:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.437887] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.455858] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.455860] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.439362] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.456875] cpuidle: using governor menu sshClient # [ 0.440024] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.460066] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 sshClient # [ 0.441157] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.442014] audit: initializing netlink subsys (disabled) machine # [ 0.461231] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] sshClient # [ 0.443308] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.461859] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry sshClient # [ 0.443311] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.462868] PCI: Using configuration type 1 for base access sshClient # [ 0.444004] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.464068] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. sshClient # [ 0.445009] audit: type=2000 audit(1789094602.555:1): state=initialized audit_enabled=0 res=1 sshClient # [ 0.447006] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.447008] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.448047] cpuidle: using governor menu machine # [ 0.469148] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.469857] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page sshClient # [ 0.451286] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 sshClient # [ 0.452312] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] sshClient # [ 0.453006] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.474856] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.454017] PCI: Using configuration type 1 for base access machine # [ 0.475856] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page sshClient # [ 0.455216] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. sshClient # [ 0.462296] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.486358] ACPI: Added _OSI(Module Device) sshClient # [ 0.463004] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.486857] ACPI: Added _OSI(Processor Device) machine # [ 0.490816] ACPI: Added _OSI(Processor Aggregator Device) sshClient # [ 0.468003] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages sshClient # [ 0.469004] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.496414] ACPI: 1 ACPI AML tables successfully acquired and loaded sshClient # [ 0.478535] ACPI: Added _OSI(Module Device) machine # [ 0.502294] ACPI: Interpreter enabled sshClient # [ 0.479004] ACPI: Added _OSI(Processor Device) machine # [ 0.502872] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.503858] ACPI: Using IOAPIC for interrupt routing sshClient # [ 0.482008] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.505015] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug sshClient # [ 0.487624] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.510854] PCI: Using E820 reservations for host bridge windows machine # [ 0.512017] ACPI: Enabled 2 GPEs in block 00 to 3F sshClient # [ 0.493447] ACPI: Interpreter enabled sshClient # [ 0.494021] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.519201] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) sshClient # [ 0.497002] ACPI: Using IOAPIC for interrupt routing machine # [ 0.519861] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] sshClient # [ 0.498058] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.520950] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.521985] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] sshClient # [ 0.501002] PCI: Using E820 reservations for host bridge windows sshClient # [ 0.502160] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.523491] PCI host bridge to bus 0000:00 machine # [ 0.523867] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.524856] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.525881] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] sshClient # [ 0.509061] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.526856] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] sshClient # [ 0.510009] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.527857] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.511085] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.528856] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.529857] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.512135] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] sshClient # [ 0.513634] PCI host bridge to bus 0000:00 machine # [ 0.530994] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.514009] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.532849] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint sshClient # [ 0.515009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] sshClient # [ 0.516004] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] sshClient # [ 0.517032] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] sshClient # [ 0.518004] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.535954] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.536883] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] sshClient # [ 0.519004] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] sshClient # [ 0.520005] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.537912] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] sshClient # [ 0.521149] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.539093] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] sshClient # [ 0.522969] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.540872] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.543889] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.544870] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] sshClient # [ 0.526084] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.545909] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] sshClient # [ 0.527032] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.546870] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] sshClient # [ 0.528058] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.549097] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.529239] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] sshClient # [ 0.531085] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.552038] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.552871] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] sshClient # [ 0.534040] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.553909] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] sshClient # [ 0.535018] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] sshClient # [ 0.536058] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.556104] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint sshClient # [ 0.537044] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.558406] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.558871] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] sshClient # [ 0.539370] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.559908] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] sshClient # [ 0.542040] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.562081] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint sshClient # [ 0.543018] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] sshClient # [ 0.544058] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.564870] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f] machine # [ 0.565870] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.566909] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] sshClient # [ 0.546272] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.569152] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint sshClient # [ 0.549038] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf] sshClient # [ 0.550019] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.571610] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf] sshClient # [ 0.551059] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.572870] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.573909] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] sshClient # [ 0.553617] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.576103] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.556912] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f] sshClient # [ 0.558018] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.578673] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f] sshClient # [ 0.559062] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.579870] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.580909] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] sshClient # [ 0.561253] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.583096] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.563837] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf] sshClient # [ 0.565018] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.585704] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df] machine # [ 0.586870] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] sshClient # [ 0.566057] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.587909] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.588870] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref] sshClient # [ 0.568225] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.570707] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.591385] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.572018] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.592903] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] sshClient # [ 0.573057] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.593909] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] sshClient # [ 0.575341] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.596080] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.577607] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df] machine # [ 0.598479] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff] sshClient # [ 0.578018] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.598870] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] sshClient # [ 0.579057] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.599913] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] sshClient # [ 0.580018] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.602104] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.582288] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.603889] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff] sshClient # [ 0.584317] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.604870] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] sshClient # [ 0.585057] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.605908] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] sshClient # [ 0.587268] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.589546] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff] sshClient # [ 0.590018] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] sshClient # [ 0.591056] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] sshClient # [ 0.593256] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.608478] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.595019] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff] sshClient # [ 0.596019] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.610939] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f] sshClient # [ 0.597057] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.612255] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.613942] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f] machine # [ 0.615288] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.616916] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f] machine # [ 0.618274] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint sshClient # [ 0.599307] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.619870] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] sshClient # [ 0.602577] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f] machine # [ 0.621594] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint sshClient # [ 0.603412] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.623565] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO sshClient # [ 0.605102] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f] machine # [ 0.625341] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint sshClient # [ 0.606356] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.627057] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f] sshClient # [ 0.607976] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f] machine # [ 0.627939] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] sshClient # [ 0.609411] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.629745] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint sshClient # [ 0.611018] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.631833] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] sshClient # [ 0.612686] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint sshClient # [ 0.614716] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.639404] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 sshClient # [ 0.616516] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.640030] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.641008] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 sshClient # [ 0.618354] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f] sshClient # [ 0.619091] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.642020] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.643012] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 sshClient # [ 0.620939] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.644016] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.645012] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 sshClient # [ 0.622942] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.646013] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.646920] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.647878] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.648878] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 sshClient # [ 0.630566] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.649873] ACPI: PCI: Interrupt link GSID configured for IRQ 19 sshClient # [ 0.631168] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.650878] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 sshClient # [ 0.632163] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.651885] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 sshClient # [ 0.633162] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.652872] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 sshClient # [ 0.634169] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.653879] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 sshClient # [ 0.635158] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.656011] iommu: Default domain type: Translated sshClient # [ 0.636163] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.656866] iommu: DMA domain TLB invalidation policy: lazy mode sshClient # [ 0.637157] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.658185] ACPI: bus type USB registered machine # [ 0.658929] usbcore: registered new interface driver usbfs sshClient # [ 0.638077] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.659886] usbcore: registered new interface driver hub sshClient # [ 0.639027] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.660883] usbcore: registered new device driver usb sshClient # [ 0.640022] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.662766] NetLabel: Initializing sshClient # [ 0.641026] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.663683] NetLabel: domain hash size = 128 sshClient # [ 0.642025] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.664855] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO sshClient # [ 0.643022] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.665901] NetLabel: unlabeled traffic allowed by default sshClient # [ 0.644027] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.666872] PCI: Using ACPI for IRQ routing sshClient # [ 0.645022] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 sshClient # [ 0.647186] iommu: Default domain type: Translated sshClient # [ 0.648014] iommu: DMA domain TLB invalidation policy: lazy mode sshClient # [ 0.649276] ACPI: bus type USB registered sshClient # [ 0.650084] usbcore: registered new interface driver usbfs sshClient # [ 0.651021] usbcore: registered new interface driver hub sshClient # [ 0.652033] usbcore: registered new device driver usb sshClient # [ 0.654045] NetLabel: Initializing sshClient # [ 0.655003] NetLabel: domain hash size = 128 sshClient # [ 0.656003] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO sshClient # [ 0.657073] NetLabel: unlabeled traffic allowed by default sshClient # [ 0.658021] PCI: Using ACPI for IRQ routing machine # [ 0.775137] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.775850] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.775850] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.775859] vgaarb: loaded machine # [ 0.776998] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.777855] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.782951] clocksource: Switched to clocksource kvm-clock machine # [ 0.787252] VFS: Disk quotas dquot_6.6.0 machine # [ 0.788539] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.766924] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.790827] pnp: PnP ACPI init sshClient # [ 0.766998] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.792152] ACPI: IRQ 4 override to edge(!), high(!) sshClient # [ 0.766998] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.793868] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved sshClient # [ 0.767007] vgaarb: loaded machine # [ 0.796508] pnp: PnP ACPI: found 6 devices sshClient # [ 0.768187] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 sshClient # [ 0.769006] hpet0: 3 comparators, 64-bit 100.000000 MHz counter sshClient # [ 0.774103] clocksource: Switched to clocksource kvm-clock machine # [ 0.804753] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.807401] clocksource: Switched to clocksource acpi_pm sshClient # [ 0.778466] VFS: Disk quotas dquot_6.6.0 machine # [ 0.809116] NET: Registered PF_INET protocol family sshClient # [ 0.779776] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.810864] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.782130] pnp: PnP ACPI init sshClient # [ 0.783444] ACPI: IRQ 4 override to edge(!), high(!) sshClient # [ 0.785170] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved sshClient # [ 0.787880] pnp: PnP ACPI: found 6 devices sshClient # [ 0.796184] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns sshClient # [ 0.798840] clocksource: Switched to clocksource acpi_pm machine # [ 0.829252] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.800680] NET: Registered PF_INET protocol family machine # [ 0.831918] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.802497] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.834375] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.836744] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.839055] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.841156] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.843430] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.845517] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.847692] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.849467] NET: Registered PF_XDP protocol family machine # [ 0.851046] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] sshClient # [ 0.821300] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.852899] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] sshClient # [ 0.823925] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.854813] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.856903] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] sshClient # [ 0.826341] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.858975] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.828702] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.861047] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] sshClient # [ 0.830960] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.863945] ACPI: \_SB_.GSIA: Enabled at IRQ 16 sshClient # [ 0.833227] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) sshClient # [ 0.835569] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.866820] ACPI: \_SB_.GSIB: Enabled at IRQ 17 sshClient # [ 0.837599] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.869614] ACPI: \_SB_.GSIC: Enabled at IRQ 18 sshClient # [ 0.839792] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.841611] NET: Registered PF_XDP protocol family machine # [ 0.872353] ACPI: \_SB_.GSID: Enabled at IRQ 19 sshClient # [ 0.843236] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.874892] PCI: CLS 0 bytes, default 64 sshClient # [ 0.845136] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.876502] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns sshClient # [ 0.846992] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.879577] Trying to unpack rootfs image as initramfs... sshClient # [ 0.849084] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] sshClient # [ 0.851141] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.853237] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] sshClient # [ 0.856127] ACPI: \_SB_.GSIA: Enabled at IRQ 16 sshClient # [ 0.858990] ACPI: \_SB_.GSIB: Enabled at IRQ 17 sshClient # [ 0.861821] ACPI: \_SB_.GSIC: Enabled at IRQ 18 sshClient # [ 0.864711] ACPI: \_SB_.GSID: Enabled at IRQ 19 sshClient # [ 0.867463] PCI: CLS 0 bytes, default 64 sshClient # [ 0.869007] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns sshClient # [ 0.872199] Trying to unpack rootfs image as initramfs... machine # [ 0.928592] Initialise system trusted keyrings machine # [ 0.933094] workingset: timestamp_bits=40 max_order=18 bucket_order=0 sshClient # [ 0.921881] Initialise system trusted keyrings sshClient # [ 0.926291] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.959458] Key type asymmetric registered machine # [ 0.960811] Asymmetric key parser 'x509' registered machine # [ 0.965112] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.971084] io scheduler mq-deadline registered machine # [ 0.972570] io scheduler kyber registered machine # [ 0.976656] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.978959] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A sshClient # [ 0.952253] Key type asymmetric registered machine # [ 0.988223] Linux agpgart interface v0.103 sshClient # [ 0.958108] Asymmetric key parser 'x509' registered machine # [ 0.989614] ACPI: bus type drm_connector registered sshClient # [ 0.959847] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.995539] usbcore: registered new interface driver usbserial_generic sshClient # [ 0.966258] io scheduler mq-deadline registered machine # [ 0.997531] usbserial: USB Serial support registered for generic sshClient # [ 0.971116] io scheduler kyber registered machine # [ 1.002045] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 1.002045] Please enable it if your BIOS has the CPPC option. sshClient # [ 0.975673] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 1.006142] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled sshClient # [ 0.977988] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A sshClient # [ 0.985301] Linux agpgart interface v0.103 machine # [ 1.015181] drop_monitor: Initializing network drop monitor service sshClient # [ 0.986693] ACPI: bus type drm_connector registered machine # [ 1.017237] NET: Registered PF_INET6 protocol family machine # [ 1.023502] Segment Routing with IPv6 sshClient # [ 0.993256] usbcore: registered new interface driver usbserial_generic sshClient # [ 0.995321] usbserial: USB Serial support registered for generic machine # [ 1.028073] In-situ OAM (IOAM) with IPv6 machine # [ 1.029760] IPI shorthand broadcast: enabled sshClient # [ 1.000089] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. sshClient # [ 1.000089] Please enable it if your BIOS has the CPPC option. sshClient # [ 1.004243] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 1.038527] sched_clock: Marking stable (778029982, 259998627)->(1250848667, -212820058) sshClient # [ 1.013275] drop_monitor: Initializing network drop monitor service sshClient # [ 1.015346] NET: Registered PF_INET6 protocol family machine # [ 1.046247] registered taskstats version 1 machine # [ 1.047830] Loading compiled-in X.509 certificates sshClient # [ 1.021789] Segment Routing with IPv6 sshClient # [ 1.025125] In-situ OAM (IOAM) with IPv6 sshClient # [ 1.028414] IPI shorthand broadcast: enabled sshClient # [ 1.037398] sched_clock: Marking stable (778029847, 259043030)->(1262088046, -225015169) machine # [ 1.070332] Demotion targets for Node 0: null machine # [ 1.074087] Key type .fscrypt registered machine # [ 1.075384] Key type fscrypt-provisioning registered sshClient # [ 1.045389] registered taskstats version 1 sshClient # [ 1.049191] Loading compiled-in X.509 certificates machine # [ 1.079173] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.086046] ima: Allocated hash algorithm: sha1 machine # [ 1.087535] ima: No architecture policies found machine # [ 1.091235] PM: Magic number: 10:737:712 machine # [ 1.095644] RAS: Correctable Errors collector initialized. sshClient # [ 1.069262] Demotion targets for Node 0: null sshClient # [ 1.073145] Key type .fscrypt registered sshClient # [ 1.074440] Key type fscrypt-provisioning registered machine # [ 1.107337] clk: Disabling unused clocks machine # [ 1.110054] PM: genpd: Disabling unused power domains sshClient # [ 1.080222] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 1.081922] ima: Allocated hash algorithm: sha1 sshClient # [ 1.085178] ima: No architecture policies found sshClient # [ 1.090104] PM: Magic number: 10:737:712 sshClient # [ 1.094510] RAS: Correctable Errors collector initialized. sshClient # [ 1.104255] clk: Disabling unused clocks sshClient # [ 1.109114] PM: genpd: Disabling unused power domains machine # [ 1.250312] Freeing initrd memory: 28332K machine # [ 1.254241] Freeing unused decrypted memory: 2028K machine # [ 1.257640] Freeing unused kernel image (initmem) memory: 3644K machine # [ 1.259575] Write protecting the kernel read-only data: 32768k machine # [ 1.262348] Freeing unused kernel image (text/rodata gap) memory: 1208K machine # [ 1.264927] Freeing unused kernel image (rodata/data gap) memory: 752K sshClient # [ 1.245214] Freeing initrd memory: 28328K sshClient # [ 1.249149] Freeing unused decrypted memory: 2028K sshClient # [ 1.252656] Freeing unused kernel image (initmem) memory: 3644K sshClient # [ 1.254593] Write protecting the kernel read-only data: 32768k sshClient # [ 1.257410] Freeing unused kernel image (text/rodata gap) memory: 1208K sshClient # [ 1.259848] Freeing unused kernel image (rodata/data gap) memory: 752K machine # [ 1.320824] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 1.322934] Run /init as init process machine # [ 1.334535] systemd[1]: Inserted module 'autofs4' sshClient # [ 1.313602] x86/mm: Checked W+X mappings: passed, no W+X pages found. sshClient # [ 1.315590] Run /init as init process machine # [ 1.352212] fuse: init (API version 7.45) sshClient # [ 1.327114] systemd[1]: Inserted module 'autofs4' machine # [ 1.359057] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.362904] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.367555] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.371316] ACPI: \_SB_.GSIF: Enabled at IRQ 21 sshClient # [ 1.345037] fuse: init (API version 7.45) sshClient # [ 1.351796] ACPI: \_SB_.GSIG: Enabled at IRQ 22 sshClient # [ 1.355667] ACPI: \_SB_.GSIH: Enabled at IRQ 23 sshClient # [ 1.360311] ACPI: \_SB_.GSIE: Enabled at IRQ 20 sshClient # [ 1.365468] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.406958] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 1.402106] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.746479] systemd[1]: systemd 262-aaab1f1 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.768987] systemd[1]: Detected virtualization kvm. machine # [ 1.773187] systemd[1]: Detected architecture x86-64. machine # [ 1.777190] systemd[1]: Running in initrd. machine # [ 1.781514] systemd[1]: Initializing machine ID from random generator. machine # [ 1.786981] systemd[1]: Hostname set to . sshClient # [ 1.741620] systemd[1]: systemd 262-aaab1f1 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.764623] systemd[1]: Detected virtualization kvm. sshClient # [ 1.769243] systemd[1]: Detected architecture x86-64. sshClient # [ 1.773839] systemd[1]: Running in initrd. sshClient # [ 1.778745] systemd[1]: Initializing machine ID from random generator. sshClient # [ 1.784651] systemd[1]: Hostname set to . machine # [ 1.877422] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 1.875626] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.921063] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.925694] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.927831] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.930396] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.932444] systemd[1]: Reached target Path Units. machine # [ 1.934079] systemd[1]: Reached target Slice Units. machine # [ 1.935721] systemd[1]: Reached target Swaps. machine # [ 1.937260] systemd[1]: Reached target Timer Units. machine # [ 1.939044] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.941270] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.943328] systemd[1]: Listening on Journal Sockets. machine # [ 1.945201] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.946923] systemd[1]: Reached target Socket Units. sshClient # [ 1.918586] systemd[1]: Queued start job for default target Initrd Default Target. sshClient # [ 1.923821] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.925947] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.928510] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.930546] systemd[1]: Reached target Path Units. sshClient # [ 1.932172] systemd[1]: Reached target Slice Units. machine # [ 1.949730] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.933837] systemd[1]: Reached target Swaps. sshClient # [ 1.935385] systemd[1]: Reached target Timer Units. sshClient # [ 1.937162] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.939370] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.971289] systemd[1]: Starting Load Kernel Module 9pnet_virtio... sshClient # [ 1.941461] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.943247] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.945000] systemd[1]: Reached target Socket Units. machine # [ 1.982914] systemd[1]: Starting Load Kernel Module configfs... sshClient # [ 1.947754] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.966321] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.997871] systemd[1]: Starting Journal Service... sshClient # [ 1.978875] systemd[1]: Starting Load Kernel Module configfs... machine # [ 2.014148] systemd[1]: Starting Load Kernel Modules... machine # [ 2.022591] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 1.995223] systemd[1]: Starting Journal Service... machine # [ 2.027159] netfs: FS-Cache loaded sshClient # [ 2.009178] systemd[1]: Starting Load Kernel Modules... machine # [ 2.040151] systemd[1]: Starting Coldplug All udev Devices... machine # [ 2.044278] 9pnet: Installing 9P2000 support sshClient # [ 2.019671] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 2.023398] netfs: FS-Cache loaded machine # [ 2.061116] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 2.035310] systemd[1]: Starting Coldplug All udev Devices... machine # [ 2.067929] systemd-journald[67]: Collecting audit messages is disabled. sshClient # [ 2.042155] 9pnet: Installing 9P2000 support machine # [ 2.074925] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 2.088728] systemd[1]: Finished Load Kernel Module 9pnet_virtio. sshClient # [ 2.058379] systemd-journald[67]: Collecting audit messages is disabled. sshClient # [ 2.062167] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 2.092553] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 2.101733] systemd[1]: modprobe@configfs.service: Deactivated successfully. sshClient # [ 2.072823] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 2.111183] systemd[1]: Finished Load Kernel Module configfs. machine # [ 2.114199] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 2.085215] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 2.088931] systemd[1]: Finished Load Kernel Module configfs. machine # [ 2.121876] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config sshClient # [ 2.095803] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. sshClient # [ 2.102117] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 2.107839] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 2.141310] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 2.117928] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 2.162216] systemd[1]: Finished Load Kernel Modules. sshClient # [ 2.137702] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 2.173498] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 2.159245] systemd[1]: Finished Load Kernel Modules. machine # [ 2.194199] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 2.170439] systemd[1]: Starting Apply Kernel Variables... machine # [ 2.209253] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 2.189181] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 2.228228] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 2.203325] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 2.252221] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 2.224652] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.259434] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 2.266157] systemd[1]: Reached target Local File Systems. machine # [ 2.275436] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 2.246212] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 2.252536] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 2.020520] systemd-modules-load[68]: Inserted module 'dm_mod' machine # [ 2.025523] systemd-modules-load[68]: Inserted module 'virtio_balloon' machine # [ 2.027548] systemd-modules-load[68]: Inserted module 'virtio_gpu' sshClient # [ 2.259231] systemd[1]: Reached target Local File Systems. machine # [ 2.296397] systemd[1]: Started Journal Service. sshClient # [ 2.268561] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 2.014663] systemd-modules-load[68]: Inserted module 'dm_mod' sshClient # [ 2.019481] systemd-modules-load[68]: Inserted module 'virtio_balloon' sshClient # [ 2.021510] systemd-modules-load[68]: Inserted module 'virtio_gpu' sshClient # [ 2.289455] systemd[1]: Started Journal Service. machine # [ 2.060230] systemd[1]: Starting Create System Files and Directories... sshClient # [ 2.055118] systemd[1]: Starting Create System Files and Directories... machine # [ 2.092798] systemd[1]: Finished Create System Files and Directories. machine # [ 2.097302] systemd-udevd[74]: Using default interface naming scheme 'v261'. sshClient # [ 2.086336] systemd[1]: Finished Create System Files and Directories. sshClient # [ 2.092489] systemd-udevd[74]: Using default interface naming scheme 'v261'. machine # [ 2.133110] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 2.128859] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.175291] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.179106] systemd[1]: Reached target System Initialization. machine # [ 2.180683] systemd[1]: Reached target Basic System. sshClient # [ 2.172176] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 2.173836] systemd[1]: Reached target System Initialization. sshClient # [ 2.176220] systemd[1]: Reached target Basic System. machine # [ 2.687480] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 2.709469] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.740464] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 sshClient # [ 2.722978] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 sshClient # [ 2.734186] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 2.778697] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.785149] serio: i8042 AUX port at 0x60,0x64 irq 12 sshClient # [ 2.762950] serio: i8042 KBD port at 0x60,0x64 irq 1 sshClient # [ 2.764573] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) sshClient # [ 2.771097] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.804915] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.806263] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 sshClient # [ 2.778090] ehci-pci 0000:00:1d.7: EHCI Host Controller sshClient # [ 2.779415] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.810411] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 sshClient # [ 2.783426] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.819074] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.822575] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 sshClient # [ 2.793142] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.826522] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.797149] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 sshClient # [ 2.799075] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.830502] usb usb1: Product: EHCI Host Controller machine # [ 2.833438] usb usb1: Manufacturer: Linux 6.18.50 ehci_hcd sshClient # [ 2.804087] usb usb1: Product: EHCI Host Controller machine # [ 2.836206] usb usb1: SerialNumber: 0000:00:1d.7 sshClient # [ 2.806742] usb usb1: Manufacturer: Linux 6.18.50 ehci_hcd machine # [ 2.838966] hub 1-0:1.0: USB hub found sshClient # [ 2.810082] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.840792] hub 1-0:1.0: 6 ports detected sshClient # [ 2.814280] hub 1-0:1.0: USB hub found sshClient # [ 2.815689] hub 1-0:1.0: 6 ports detected machine # [ 2.846128] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.847418] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 sshClient # [ 2.820530] uhci_hcd 0000:00:1d.0: UHCI Host Controller sshClient # [ 2.821839] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 sshClient # [ 2.824942] SCSI subsystem initialized machine # [ 2.865264] uhci_hcd 0000:00:1d.0: detected 2 ports sshClient # [ 2.837018] uhci_hcd 0000:00:1d.0: detected 2 ports sshClient # [ 2.844583] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200 machine # [ 2.876142] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200 sshClient # [ 2.859262] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.861285] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.891979] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.893988] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.897161] SCSI subsystem initialized machine # [ 2.645237] systemd[1]: Starting Virtual Console Setup... sshClient # [ 2.890162] usb usb2: Product: UHCI Host Controller machine # [ 2.667210] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.901331] usb usb2: Manufacturer: Linux 6.18.50 uhci_hcd machine # [ 2.679754] (udev-worker)[87]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.653709] systemd[1]: Starting Virtual Console Setup... machine # [ 2.684298] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.660196] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.922134] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.951872] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 2.695741] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.702737] systemd[1]: Finished Virtual Console Setup. machine # [ 2.968058] usb usb2: Product: UHCI Host Controller machine # [ 2.977752] usb usb2: Manufacturer: Linux 6.18.50 uhci_hcd sshClient # [ 2.687982] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.954137] hub 2-0:1.0: USB hub found machine # [ 2.988073] usb usb2: SerialNumber: 0000:00:1d.0 sshClient # [ 2.697985] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.705867] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.997813] hub 2-0:1.0: USB hub found machine # [ 2.999742] hub 2-0:1.0: 2 ports detected sshClient # [ 2.969919] hub 2-0:1.0: 2 ports detected sshClient # [ 2.713714] systemd[1]: Finished Virtual Console Setup. machine # [ 3.006383] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.749862] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.751479] systemd[1]: Reached target Initrd Root Device. machine # [ 2.754109] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 2.989107] uhci_hcd 0000:00:1d.1: UHCI Host Controller sshClient # [ 2.993322] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 3.028053] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 3.038071] uhci_hcd 0000:00:1d.1: detected 2 ports sshClient # [ 3.007889] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 3.040260] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220 machine # [ 3.046868] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.762123] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.792748] systemd-fsck[106]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 2.764914] systemd[1]: Reached target Initrd Root Device. sshClient # [ 3.026180] uhci_hcd 0000:00:1d.1: detected 2 ports sshClient # [ 2.770433] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.803319] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 3.036152] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220 machine # [ 3.066492] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.809257] systemd[1]: Mounting /sysroot... sshClient # [ 3.040761] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode sshClient # [ 3.046338] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 3.048320] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 3.082057] usb usb3: Product: UHCI Host Controller machine # [ 3.084280] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 3.054357] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) sshClient # [ 3.056566] usb usb3: Product: UHCI Host Controller sshClient # [ 3.058364] ahci 0000:00:1f.2: flags: 64bit ncq only sshClient # [ 3.060097] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 3.092656] usb usb3: Manufacturer: Linux 6.18.50 uhci_hcd sshClient # [ 3.062413] usb usb3: Manufacturer: Linux 6.18.50 uhci_hcd sshClient # [ 3.065146] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 3.100717] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode sshClient # [ 3.072485] scsi host0: ahci machine # [ 3.104818] usb usb3: SerialNumber: 0000:00:1d.1 sshClient # [ 2.816093] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 3.077834] hub 3-0:1.0: USB hub found machine # [ 3.112931] hub 3-0:1.0: USB hub found sshClient # [ 2.824415] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.115504] hub 3-0:1.0: 2 ports detected sshClient # [ 3.086253] scsi host1: ahci sshClient # [ 3.087878] hub 3-0:1.0: 2 ports detected machine # [ 3.119092] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) sshClient # [ 2.832108] systemd[1]: Mounting /sysroot... sshClient # [ 3.092513] scsi host2: ahci machine # [ 3.127110] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 3.130177] uhci_hcd 0000:00:1d.2: UHCI Host Controller sshClient # [ 3.102246] scsi host3: ahci sshClient # [ 3.104156] uhci_hcd 0000:00:1d.2: UHCI Host Controller sshClient # [ 3.105452] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 3.136135] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 sshClient # [ 3.113473] scsi host4: ahci machine # [ 3.154146] uhci_hcd 0000:00:1d.2: detected 2 ports sshClient # [ 3.129449] scsi host5: ahci machine # [ 3.162081] scsi host0: ahci sshClient # [ 3.130480] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1 machine # [ 3.163387] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240 sshClient # [ 3.138657] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 3.170948] scsi host1: ahci sshClient # [ 3.140030] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240 machine # [ 3.174540] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 3.180794] scsi host2: ahci sshClient # [ 3.150696] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1 machine # [ 3.185808] scsi host3: ahci sshClient # [ 3.156570] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 3.191039] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 3.161300] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1 machine # [ 3.197274] usb usb4: Product: UHCI Host Controller machine # [ 3.199123] scsi host4: ahci sshClient # [ 3.168757] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 3.173665] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1 machine # [ 3.205081] usb usb4: Manufacturer: Linux 6.18.50 uhci_hcd machine # [ 3.208125] scsi host5: ahci machine # [ 3.210122] usb usb4: SerialNumber: 0000:00:1d.2 sshClient # [ 3.180454] usb usb4: Product: UHCI Host Controller sshClient # [ 3.182153] usb usb4: Manufacturer: Linux 6.18.50 uhci_hcd machine # [ 3.213236] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1 machine # [ 3.217249] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 sshClient # [ 3.187164] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1 machine # [ 3.219154] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 sshClient # [ 3.191158] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 3.221894] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1 machine # [ 3.224146] usb 1-1: Product: QEMU USB Tablet sshClient # [ 3.192884] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1 machine # [ 3.225611] hub 4-0:1.0: USB hub found machine # [ 3.226686] usb 1-1: Manufacturer: QEMU machine # [ 3.228423] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 sshClient # [ 3.198524] hub 4-0:1.0: USB hub found machine # [ 3.230602] hub 4-0:1.0: 2 ports detected sshClient # [ 3.200082] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 sshClient # [ 3.202021] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 3.235285] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1 sshClient # [ 3.206663] hub 4-0:1.0: 2 ports detected sshClient # [ 3.208168] usb 1-1: Product: QEMU USB Tablet machine # [ 3.239672] EXT4-fs (vda): mounted filesystem a14a70c9-7e91-4905-9fc6-c2e2cd9c68c8 r/w with ordered data mode. Quota mode: none. sshClient # [ 3.211911] usb 1-1: Manufacturer: QEMU sshClient # [ 3.213073] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.987534] systemd[1]: Mounted /sysroot. machine # [ 2.990074] systemd[1]: Reached target Initrd Root File System. machine # [ 2.995625] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.261070] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1 machine # [ 3.005201] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.011236] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.250704] hid: raw HID events driver (C) Jiri Kosina machine # [ 3.021421] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.034211] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.296137] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1 machine # [ 3.313384] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1 machine # [ 3.058207] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.343875] 9p: Installing v9fs 9p2000 file system support machine # [ 3.358563] hid: raw HID events driver (C) Jiri Kosina machine # [ 3.107237] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.115773] systemd-sysroot-fstab-check[134]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.119229] systemd[1]: Mounted /sysroot/run. machine # [ 3.123700] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.128176] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.135258] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.137736] systemd[1]: Reload requested from client PID 134 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.141141] systemd[1]: Reloading... machine # [ 3.214955] systemd[1]: Reloading finished in 72 ms. machine # [ 3.224970] systemd-sysroot-fstab-check[134]: Requesting initrd-fs.target/start/replace... machine # [ 3.229181] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.231884] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.235638] systemd-sysroot-fstab-check[134]: Requesting swap.target/start/replace... machine # [ 3.239796] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.243124] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.244952] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.248761] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.252527] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.264850] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.267802] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.532896] ata6: SATA link down (SStatus 0 SControl 300) sshClient # [ 3.537007] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) sshClient # [ 3.541821] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 sshClient # [ 3.545666] ata3.00: applying bridge limits sshClient # [ 3.549377] ata5: SATA link down (SStatus 0 SControl 300) sshClient # [ 3.553704] ata4: SATA link down (SStatus 0 SControl 300) sshClient # [ 3.557773] ata1: SATA link down (SStatus 0 SControl 300) sshClient # [ 3.562417] ata2: SATA link down (SStatus 0 SControl 300) sshClient # [ 3.566761] ata3.00: configured for UDMA/100 sshClient # [ 3.570967] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 sshClient # [ 3.602988] EXT4-fs (vda): mounted filesystem 51872491-804b-47ff-9689-9e95a3f0563e r/w with ordered data mode. Quota mode: none. sshClient # [ 3.353116] systemd[1]: Mounted /sysroot. sshClient # [ 3.355815] systemd[1]: Reached target Initrd Root File System. machine # [ 3.643902] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 3.647939] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) sshClient # [ 3.361551] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.652783] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 3.656543] ata3.00: applying bridge limits machine # [ 3.660301] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 3.664643] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 3.669089] ata5: SATA link down (SStatus 0 SControl 300) sshClient # [ 3.383559] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.672969] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 3.676821] ata3.00: configured for UDMA/100 sshClient # [ 3.390639] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 3.393464] systemd[1]: Reloading... machine # [ 3.680357] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 3.441284] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 3.676559] usbcore: registered new interface driver usbhid sshClient # [ 3.698095] usbhid: USB HID core driver machine # [ 3.730709] usbcore: registered new interface driver usbhid machine # [ 3.735456] usbhid: USB HID core driver machine # [ 3.743571] 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 # [ 3.746595] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 3.748402] 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 # [ 3.759246] cdrom: Uniform CD-ROM driver Revision: 3.20 sshClient # [ 3.731366] 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 # [ 3.747156] 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 sshClient # [ 3.754894] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray sshClient # [ 3.774287] cdrom: Uniform CD-ROM driver Revision: 3.20 sshClient # [ 3.533132] systemd[1]: Reloading finished in 143 ms. sshClient # [ 3.546691] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace... sshClient # [ 3.555944] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio sshClient # [ 3.558974] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace... sshClient # [ 3.562652] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 3.565254] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 3.568258] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.799624] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 3.794600] systemd[1]: Mounting /sysroot/nix/.ro-store... sshClient # [ 3.799218] systemd[1]: Mounting /sysroot/nix/.rw-store... sshClient # [ 3.804974] systemd[1]: Mounting /sysroot/run... sshClient # [ 3.811118] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.851111] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 3.822121] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.852571] systemd[1]: Reached target Initrd File Systems. machine # [ 3.856068] systemd[1]: Starting Find NixOS closure... machine # [ 3.860211] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.107526] 9p: Installing v9fs 9p2000 file system support sshClient # [ 3.850951] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.881589] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.885633] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 3.862131] systemd[1]: Mounted /sysroot/run. machine # [ 3.895209] systemd[1]: Finished Find NixOS closure. sshClient # [ 3.866933] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.897544] systemd[1]: Reached target Initrd Default Target. sshClient # [ 3.870857] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.900411] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 3.873378] systemd[1]: Mounted /sysroot/tmp/xchg. sshClient # [ 3.878956] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.919265] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.920849] systemd[1]: Stopped target Basic System. sshClient # [ 3.891441] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.922316] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.923844] systemd[1]: Stopped target Path Units. sshClient # [ 3.894793] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.925565] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.928244] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.930671] systemd[1]: Stopped target Slice Units. machine # [ 3.933245] systemd[1]: Stopped target Socket Units. machine # [ 3.934674] systemd[1]: Stopped target System Initialization. machine # [ 3.936505] systemd[1]: Stopped target Swaps. machine # [ 3.938199] systemd[1]: Stopped target Timer Units. machine # [ 3.939931] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.942395] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.945227] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.947339] systemd[1]: Stopped Find NixOS closure. machine # [ 3.948732] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.952216] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.953837] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.955616] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.958198] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.960105] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.961546] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.963902] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.966874] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.968894] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.971255] systemd[1]: Stopped target Local File Systems. machine # [ 3.974238] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.976069] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.978297] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.981364] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.983407] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.985770] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.998938] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.003218] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.006825] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.009511] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.012296] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.014868] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.020739] systemd[1]: Starting Cleanup udev Database... machine # [ 4.023253] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.025311] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.027053] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.030186] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.032074] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.033957] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.050505] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.053350] systemd[1]: Finished Cleanup udev Database. machine # [ 4.055681] systemd[1]: Reached target Switch Root. machine # [ 4.058475] systemd[1]: Starting NixOS Activation... machine # [ 4.240732] initrd-nixos-activation-start[195]: booting system configuration /nix/store/snf179hyv8fqssx2q1z1jg1hzbx0n949-nixos-system-machine-test machine # [ 4.311065] initrd-nixos-activation-start[195]: running activation script... machine # [ 4.774850] initrd-nixos-activation-start[218]: setting up /etc... sshClient # [ 4.796479] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 4.847901] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 4.850255] systemd[1]: Reached target Initrd File Systems. sshClient # [ 4.853400] systemd[1]: Starting Find NixOS closure... sshClient # [ 4.857585] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 4.880081] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 4.883618] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. sshClient # [ 4.893968] systemd[1]: Finished Find NixOS closure. sshClient # [ 4.896375] systemd[1]: Reached target Initrd Default Target. sshClient # [ 4.899120] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 4.916639] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 4.918590] systemd[1]: Stopped target Basic System. sshClient # [ 4.921217] systemd[1]: Stopped target Initrd Root Device. sshClient # [ 4.922747] systemd[1]: Stopped target Path Units. sshClient # [ 4.924331] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. sshClient # [ 4.926296] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. sshClient # [ 4.928718] systemd[1]: Stopped target Slice Units. sshClient # [ 4.930578] systemd[1]: Stopped target Socket Units. sshClient # [ 4.932609] systemd[1]: Stopped target System Initialization. sshClient # [ 4.935247] systemd[1]: Stopped target Swaps. sshClient # [ 4.936649] systemd[1]: Stopped target Timer Units. sshClient # [ 4.938097] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 4.940209] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 4.942137] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 4.944594] systemd[1]: Stopped Find NixOS closure. sshClient # [ 4.946285] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio sshClient # [ 4.950253] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 4.951883] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 4.953772] systemd[1]: Stopped Apply Kernel Variables. sshClient # [ 4.955725] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 4.957978] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 4.960623] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 4.962679] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 4.966207] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 4.968126] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 4.969769] systemd[1]: Stopped target Local File Systems. sshClient # [ 4.971308] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 4.973423] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 4.975720] systemd[1]: Stopped Coldplug All udev Devices. sshClient # [ 4.978244] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 4.981500] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 4.983462] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 4.996362] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 5.000262] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 5.006116] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 5.009110] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 5.011733] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 5.014434] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 5.019993] systemd[1]: Starting Cleanup udev Database... sshClient # [ 5.022479] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 5.024581] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 5.026360] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 5.029255] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 5.031149] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 5.034187] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.078156] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 5.049528] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.081130] systemd[1]: Finished NixOS Activation. sshClient # [ 5.053101] systemd[1]: Finished Cleanup udev Database. machine # [ 5.083986] systemd[1]: Starting Switch Root... sshClient # [ 5.054617] systemd[1]: Reached target Switch Root. sshClient # [ 5.057321] systemd[1]: Starting NixOS Activation... machine # [ 5.099474] systemd[1]: Switching root. machine # [ 5.494847] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). sshClient # [ 5.215540] initrd-nixos-activation-start[195]: booting system configuration /nix/store/hxdxb8q25kfcw5jhacl5knylm0vmjzzv-nixos-system-sshClient-test sshClient # [ 5.284529] initrd-nixos-activation-start[195]: running activation script... machine # [ 5.654676] NET: Registered PF_VSOCK protocol family sshClient # [ 5.726936] initrd-nixos-activation-start[218]: setting up /etc... machine # [ 6.048425] systemd[1]: systemd 262-aaab1f1 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 # [ 6.065140] systemd[1]: Detected virtualization kvm. machine # [ 6.068285] systemd[1]: Detected architecture x86-64. machine # [ 6.071621] systemd[1]: Detected first boot. machine # [ 6.080246] systemd[1]: Initializing machine ID from random generator. machine # [ 6.206838] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 6.022982] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 6.025355] systemd[1]: Finished NixOS Activation. sshClient # [ 6.028354] systemd[1]: Starting Switch Root... sshClient # [ 6.044589] systemd[1]: Switching root. machine # [ 6.346474] systemd[1]: Applying preset policy. sshClient # [ 6.432184] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). sshClient # [ 6.564434] NET: Registered PF_VSOCK protocol family machine # [ 6.929838] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 6.953516] systemd[1]: systemd 262-aaab1f1 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 # [ 6.970219] systemd[1]: Detected virtualization kvm. sshClient # [ 6.973473] systemd[1]: Detected architecture x86-64. sshClient # [ 6.976880] systemd[1]: Detected first boot. sshClient # [ 6.985386] systemd[1]: Initializing machine ID from random generator. sshClient # [ 7.118689] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 7.243138] systemd[1]: Applying preset policy. machine # [ 7.507615] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 7.510218] systemd[1]: Stopped initrd-switch-root.service. machine # [ 7.513950] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 7.517512] systemd[1]: Created slice Slice /system/getty. machine # [ 7.519668] systemd[1]: Created slice User and Session Slice. machine # [ 7.521413] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 7.523506] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 7.525405] systemd[1]: Expecting device /dev/hvc0... machine # [ 7.539136] systemd[1]: Expecting device /dev/ttyS0... machine # [ 7.540497] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 7.542027] systemd[1]: Stopped target initrd-fs.target. machine # [ 7.543403] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 7.544847] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 7.546454] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 7.548108] systemd[1]: Reached target Path Units. machine # [ 7.549430] systemd[1]: Reached target Remote File Systems. machine # [ 7.550869] systemd[1]: Reached target Slice Units. machine # [ 7.552205] systemd[1]: Reached target Swaps. machine # [ 7.556262] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 7.561267] systemd[1]: Listening on Process Core Dump Socket. machine # [ 7.565076] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 7.568636] systemd[1]: Listening on Factory Reset Management. machine # [ 7.570272] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.575421] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.577768] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.581571] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.583542] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.585875] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.588185] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.601370] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.603259] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.604895] systemd[1]: Listening on User Database Manager Socket. machine # [ 7.610243] systemd[1]: Mounting Huge Pages File System... machine # [ 7.614771] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 7.622482] systemd[1]: Mounting Kernel Debug File System... machine # [ 7.630448] systemd[1]: Mounting Kernel Trace File System... machine # [ 7.637392] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 7.642602] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 7.652453] systemd[1]: Starting Load Kernel Module configfs... machine # [ 7.654849] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.657684] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.660662] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.673111] systemd[1]: Mounting FUSE Control File System... machine # [ 7.675371] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.717331] systemd[1]: Starting Journal Service... machine # [ 7.740224] systemd[1]: Starting Load Kernel Modules... machine # [ 7.766713] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.782224] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 7.758451] systemd[1]: Populated /etc with preset unit settings. machine # [ 7.788160] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.801551] systemd-journald[289]: Collecting audit messages is enabled. machine # [ 7.806541] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.832976] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.843483] systemd[1]: Mounted Huge Pages File System. machine # [ 7.853325] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.595566] systemd[1]: Queued start job for default target Multi-User System.[ 7.859860] loop: module loaded machine # machine # [ 7.603966] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 7.868360] systemd[1]: Started Journal Service. machine # [ 7.611703] systemd-modules-load[290]: Inserted module 'loop' machine # [ 7.620824] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.882264] EXT4-fs (vda): re-mounted a14a70c9-7e91-4905-9fc6-c2e2cd9c68c8. machine # [ 7.626753] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.631805] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.637500] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 7.640910] systemd[1]: Finished Load Kernel Module configfs. machine # [ 7.644471] systemd[1]: Mounted FUSE Control File System. machine # [ 7.647608] systemd[1]: Finished Load Kernel Modules. machine # [ 7.654216] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.677105] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.684346] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.689616] systemd[1]: Starting Firewall... machine # [ 7.698546] systemd-oomd[292]: No swap; memory pressure usage will be degraded machine # [ 7.706111] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.707935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.729186] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.737160] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.754553] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.759683] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.766853] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.047081] systemd-journald[289]: Received client request to flush runtime journal. machine # [ 8.056311] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.062961] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 8.067485] systemd[1]: Finished Apply Kernel Variables. machine # [ 8.069471] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 8.073888] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 8.075941] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 8.079328] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 8.310113] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 8.312624] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.086380] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 8.316246] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 8.319640] systemd[1]: Created slice Slice /system/getty. sshClient # [ 8.321724] systemd[1]: Created slice User and Session Slice. machine # [ 8.093874] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 8.323545] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 8.325567] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 8.327411] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 8.340983] systemd[1]: Expecting device /dev/ttyS0... sshClient # [ 8.342443] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 8.344173] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 8.345617] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 8.347096] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 8.348674] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 8.350341] systemd[1]: Reached target Path Units. sshClient # [ 8.351662] systemd[1]: Reached target Remote File Systems. sshClient # [ 8.353161] systemd[1]: Reached target Slice Units. sshClient # [ 8.354504] systemd[1]: Reached target Swaps. sshClient # [ 8.358154] systemd[1]: Listening on Query the User Interactively for a Password. sshClient # [ 8.362859] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 8.366404] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 8.369745] systemd[1]: Listening on Factory Reset Management. sshClient # [ 8.371428] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 8.376460] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 8.378849] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 8.384801] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 8.386886] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 8.389283] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 8.391706] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki sshClient # [ 8.401236] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 8.403320] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 8.405014] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 8.409865] systemd[1]: Mounting Huge Pages File System... sshClient # [ 8.414294] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 8.422614] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.196365] systemd-udevd[333]: Using default interface naming scheme 'v261'. sshClient # [ 8.431339] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.203551] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 8.437253] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 8.441934] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio sshClient # [ 8.453416] systemd[1]: Starting Load Kernel Module configfs... sshClient # [ 8.455732] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 8.458598] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 8.462017] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 8.474164] systemd[1]: Mounting FUSE Control File System... sshClient # [ 8.476828] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.252128] systemd[1]: Mounting /run/wrappers... machine # [ 8.268257] systemd[1]: Starting User Database Manager... machine # [ 8.292958] systemd[1]: Mounted /run/wrappers. machine # [ 8.295409] systemd[1]: Reached target Local File Systems. sshClient # [ 8.530666] systemd[1]: Starting Journal Service... machine # [ 8.302136] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.308159] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.309740] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.315127] systemd[1]: Starting Create System Files and Directories... sshClient # [ 8.546333] systemd[1]: Starting Load Kernel Modules... sshClient # [ 8.561673] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 8.575666] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 8.584154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.371442] systemd[1]: Started User Database Manager. sshClient # [ 8.602227] systemd-journald[289]: Collecting audit messages is enabled. sshClient # [ 8.605483] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 8.636105] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 8.645568] systemd[1]: Mounted Huge Pages File System. sshClient # [ 8.652304] loop: module loaded sshClient # [ 8.653690] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 8.399826] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 8.406412] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 8.669335] systemd[1]: Started Journal Service. sshClient # [ 8.412630] systemd-modules-load[290]: Inserted module 'loop'[ 8.674179] EXT4-fs (vda): re-mounted 51872491-804b-47ff-9689-9e95a3f0563e. sshClient # machine # [ 8.447357] systemd[1]: Finished Create System Files and Directories. sshClient # [ 8.420398] systemd[1]: Mounted Kernel Debug File System. sshClient # [ 8.427202] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.458532] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 8.434179] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 8.436583] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 8.467489] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 8.443516] systemd[1]: Finished Load Kernel Module configfs. sshClient # [ 8.445451] systemd[1]: Mounted FUSE Control File System. sshClient # [ 8.448672] systemd[1]: Finished Load Kernel Modules. sshClient # [ 8.454135] systemd[1]: Finished Remount Root and Kernel File Systems. sshClient # [ 8.475775] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 8.483418] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 8.492110] systemd[1]: Starting Firewall... machine # [ 8.520939] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 8.497567] systemd-oomd[292]: No swap; memory pressure usage will be degraded sshClient # [ 8.511602] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 8.514106] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.548141] systemd[1]: Finished Record System Boot/Shutdown in UTMP. sshClient # [ 8.529524] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 8.544552] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.592246] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 8.571163] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.601624] systemd[1]: Starting Update is Completed... sshClient # [ 8.576377] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 8.587210] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. sshClient # [ 8.859668] systemd-journald[289]: Received client request to flush runtime journal. machine # [ 8.658694] systemd[1]: Finished Update is Completed. machine # [ 8.858673] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 8.883397] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 8.887342] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 8.892293] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 8.895500] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 8.899351] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 8.902334] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 8.905622] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 8.911777] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 8.918653] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.999338] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 9.013510] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.016330] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.020124] systemd[1]: Reached target System Initialization. machine # [ 9.024079] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.027067] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.029784] systemd[1]: Reached target Timer Units. machine # [ 9.031700] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.036276] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine # [ 9.040107] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.042285] systemd[1]: Reached target Socket Units. machine # [ 9.044786] systemd[1]: Reached target Basic System. sshClient # [ 9.019778] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.049718] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 9.029622] systemd-udevd[333]: Using default interface naming scheme 'v261'. machine # [ 9.059728] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.075085] systemd[1]: Starting Post-Boot Actions... sshClient # [ 9.056273] systemd[1]: Mounting /run/wrappers... machine # [ 9.089396] systemd[1]: Started Reset console on configuration changes. machine # [ 9.116402] systemd[1]: Starting resolvconf update... sshClient # [ 9.096399] systemd[1]: Mounted /run/wrappers. sshClient # [ 9.099967] systemd[1]: Reached target Local File Systems. sshClient # [ 9.105162] systemd[1]: Listening on Boot Loader Control Service Socket. sshClient # [ 9.108981] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.141830] systemd[1]: Starting SSH Host Keys Generation... sshClient # [ 9.112082] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 9.115828] systemd[1]: Starting Create System Files and Directories... sshClient # [ 9.128355] systemd[1]: Starting User Database Manager... machine # [ 9.184826] systemd[1]: Starting Home Area Manager... machine # [ 9.203197] (udev-worker)[428]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.213421] (udev-worker)[433]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.217358] (udev-worker)[428]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.230950] systemd[1]: Finished Post-Boot Actions. machine # [ 9.245476] nsncd[486]: Sep 11 02:43:31.591 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.252522] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.229788] systemd[1]: Started User Database Manager. sshClient # [ 9.235932] systemd[1]: Finished Create System Files and Directories. sshClient # [ 9.249645] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 9.256780] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.296908] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 9.304406] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.307384] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.319620] systemd[1]: Started backdoor.service. machine # [ 9.336489] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 9.341260] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.373478] systemd-homed[500]: Watching /home. machine # [ 9.388128] systemd[1]: Starting User Login Management... machine # [ 9.407200] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 9.391725] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 9.398939] systemd[1]: Starting Update is Completed... sshClient # [ 9.422151] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 9.459510] systemd[1]: Finished Update is Completed. machine # connecting to host... machine # [ 9.545336] systemd[1]: Finished Firewall. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 9.579298] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 9.580951] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 9.582502] systemd[1]: Stopped target User and Group Name Lookups. machine: connected to guest root shell machine # [ 9.587576] systemd[1]: Stopping User and Group Name Lookups... machine: (connecting took 10.60 seconds) machine # [ 9.594645] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine: (finished: waiting for the VM to finish booting, in 10.60 seconds) machine # [ 9.596274] systemd[1]: nscd.service: Deactivated successfully. machine # [ 9.603541] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 9.613795] dbus-broker-launch[532]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 9.637516] dbus-broker-launch[532]: NSS returned no entry for 'systemd-timesync' machine # [ 9.639466] dbus-broker-launch[532]: Invalid user-name in /nix/store/q8qwqdnxcwxffczxzr90ylxrb9csvabg-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 9.650996] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.686994] systemd[1]: Started D-Bus System Message Bus. machine # [ 9.702388] systemd-logind[543]: New seat seat0. machine # [ 9.706829] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 9.714138] systemd[1]: Started User Login Management. machine # [ 9.730175] systemd[1]: Starting linger-users.service... machine # [ 9.769216] dbus-broker-launch[532]: Ready sshClient # [ 9.740403] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 9.782153] nsncd[599]: Sep 11 02:43:32.132 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.806108] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.777639] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. sshClient # [ 9.783193] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 9.787270] systemd[1]: Reached target System Initialization. sshClient # [ 9.789985] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 9.792612] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 9.796138] systemd[1]: Reached target Timer Units. sshClient # [ 9.798259] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 9.800539] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 9.803409] systemd[1]: Reached target Socket Units. sshClient # [ 9.806295] systemd[1]: Reached target Basic System. machine # [ 9.841325] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 9.812605] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 9.848929] systemd[1]: Finished linger-users.service. sshClient # [ 9.820198] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.856130] systemd[1]: Started Home Area Manager. sshClient # [ 9.835503] systemd[1]: Starting Post-Boot Actions... machine # [ 9.866624] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.868383] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.872169] systemd[1]: Finished Home Area Activation. machine # [ 9.880992] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 9.856776] systemd[1]: Started Reset console on configuration changes. machine # [ 9.887097] systemd[1]: Finished resolvconf update. machine # [ 9.897179] systemd[1]: Reached target Preparation for Network. sshClient # [ 9.870917] systemd[1]: Starting resolvconf update... machine # [ 9.914468] systemd[1]: Starting DHCP Client... machine # [ 9.925189] systemd[1]: Starting Address configuration of eth1... machine # [ 9.943992] systemd[1]: Starting Extra networking commands.... sshClient # [ 9.915445] systemd[1]: Starting Home Area Manager... sshClient # [ 9.966633] nsncd[475]: Sep 11 02:43:32.334 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 9.973133] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.986780] systemd[1]: Finished Post-Boot Actions. machine # [ 10.019283] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 10.033306] systemd[1]: Started Console Output Muting Service (PID 629/UID 0). sshClient # [ 10.030984] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 10.034522] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 10.049546] systemd[1]: Starting D-Bus System Message Bus... sshClient # [ 10.075245] systemd[1]: Starting User Login Management... sshClient # [ 10.087568] systemd[1]: Finished Import lastlog data into lastlog2 database. sshClient # [ 10.099435] systemd-homed[485]: Watching /home. sshClient # [ 10.108388] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 10.145822] network-addresses-eth1-start[632]: adding address 192.168.1.1/24... done machine # [ 10.188078] network-addresses-eth1-start[632]: adding address 2001:db8:1::1/64... done sshClient # [ 10.194939] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 10.235224] systemd[1]: Finished Address configuration of eth1. sshClient # [ 10.210539] systemd[1]: Started backdoor.service. sshClient # [ 10.268340] (udev-worker)[435]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 10.281386] (udev-worker)[440]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 10.288059] (udev-worker)[435]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 10.291179] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 10.296463] dbus-broker-launch[509]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 10.302951] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 10.305458] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 10.308545] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 10.314186] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 10.319635] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 10.322355] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 10.326393] dbus-broker-launch[509]: NSS returned no entry for 'systemd-timesync' machine # [ 10.361782] dhcpcd[643]: dhcpcd-10.3.2 starting sshClient # [ 10.330676] dbus-broker-launch[509]: Invalid user-name in /nix/store/czb5a9cc8yywl0092fqqahcm7h5lc6wq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.378136] dhcpcd[696]: dev: loaded udev sshClient # [ 10.345693] systemd-logind[514]: New seat seat0. sshClient # [ 10.359327] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 10.361151] systemd[1]: Started User Login Management. machine # [ 10.398683] systemd[1]: Finished Extra networking commands.. machine # [ 10.403606] systemd[1]: Reached target Network. sshClient # [ 10.380631] systemd[1]: Finished Firewall. sshClient # connecting to host... sshClient # [ 10.390297] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 10.415909] systemd[1]: Starting linger-users.service... sshClient # [ 10.437130] dbus-broker-launch[509]: Ready sshClient # [ 10.459080] systemd[1]: Started Home Area Manager. sshClient # [ 10.472422] systemd[1]: Finished Home Area Activation. sshClient # [ 10.482095] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 10.483784] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 10.488375] nsncd[577]: Sep 11 02:43:32.858 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 10.493443] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 10.498650] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 10.526115] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 10.531124] systemd[1]: Finished linger-users.service. sshClient # [ 10.537241] systemd[1]: Finished resolvconf update. sshClient # [ 10.546285] systemd[1]: Reached target Preparation for Network. sshClient # [ 10.557190] systemd[1]: Starting DHCP Client... sshClient # [ 10.565240] systemd[1]: Starting Extra networking commands.... sshClient # [ 10.594115] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 10.609106] systemd[1]: Starting Address configuration of eth1... sshClient # [ 10.613658] systemd[1]: Finished First Boot Home Area Wizard. sshClient # [ 10.629816] systemd[1]: Reached target First Boot Complete. sshClient # [ 10.641245] systemd[1]: Starting Save Transient machine-id to Disk... sshClient # [ 10.734185] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 10.747373] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 10.786686] dhcpcd[696]: eth0: waiting for carrier machine # [ 10.790056] dhcpcd[696]: libudev: received NULL device machine # [ 10.793380] dhcpcd[696]: libudev: received NULL device machine # [ 10.795628] dhcpcd[696]: eth0: carrier acquired machine # [ 10.815868] dhcpcd[696]: DUID 00:01:00:01:32:36:27:54:52:54:00:12:34:56 machine # [ 10.817623] dhcpcd[696]: eth0: IAID 00:12:34:56 machine # [ 10.820818] dhcpcd[696]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 10.793436] network-addresses-eth1-start[616]: adding address 192.168.1.2/24... done sshClient # [ 10.818118] network-addresses-eth1-start[616]: adding address 2001:db8:1::2/64... done sshClient # [ 10.856813] systemd[1]: Finished Address configuration of eth1. machine: (finished: waiting for unit systemd-homed.service, in 11.90 seconds) machine: waiting for Please enter user name to create to appear on tty 1 sshClient # [ 10.922920] systemd[1]: Finished Extra networking commands.. sshClient # [ 10.929116] systemd[1]: Reached target Network. sshClient # [ 10.936900] systemd[1]: Starting Permit User Sessions... sshClient # [ 10.940853] dhcpcd[628]: dhcpcd-10.3.2 starting sshClient # [ 10.952983] dhcpcd[683]: dev: loaded udev machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.10 seconds) machine: sending keys 'test-homed-user\n' sshClient # [ 11.241732] 8021q: 802.1Q VLAN Support v1.8 sshClient # [ 11.248736] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 10.993081] systemd[1]: Finished Permit User Sessions. sshClient # [ 10.999890] systemd[1]: Started Getty on tty1. sshClient # [ 11.003621] systemd[1]: Reached target Login Prompts. sshClient # [ 11.279508] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 sshClient # [ 11.309677] mousedev: PS/2 mouse device common for all mice sshClient # [ 11.326135] ACPI: button: Power Button [PWRF] machine # [ 11.098258] systemd[1]: Starting 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 # [ 11.407431] cfg80211: Loading compiled-in X.509 certificates for regulatory database sshClient # [ 11.410313] rtc_cmos 00:04: RTC can wake from S4 sshClient # [ 11.429732] parport_pc 00:02: reported by Plug and Play ACPI sshClient # [ 11.433925] rtc_cmos 00:04: registered as rtc0 sshClient # [ 11.438872] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console sshClient # [ 11.446965] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.220281] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 11.451654] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' sshClient # [ 11.453724] rtc_cmos 00:04: setting system clock to 2026-09-11T02:43:33 UTC (1789094613) machine # [ 11.225401] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 11.455937] systemd-journald[289]: Time jumped backwards, rotating. sshClient # [ 11.460087] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 sshClient # [ 11.462180] cfg80211: failed to load regulatory.db machine # [ 11.233780] systemd-logind[543]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) sshClient # [ 11.465272] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 11.251108] systemd-logind[543]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 11.258205] systemd[1]: Starting Virtual Console Setup... sshClient # [ 11.304512] dhcpcd[683]: eth0: waiting for carrier sshClient # [ 11.307519] dhcpcd[683]: libudev: received NULL device sshClient # [ 11.309256] dhcpcd[683]: libudev: received NULL device sshClient # [ 11.483577] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4 sshClient # [ 11.499683] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs sshClient # [ 11.543861] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized sshClient # [ 11.563147] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 11.588180] Console: switching to colour dummy device 80x25 sshClient # [ 11.619470] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt sshClient # [ 11.619602] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD sshClient # [ 11.654086] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 sshClient # [ 11.654485] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 sshClient # [ 11.688543] [drm] Found bochs VGA, ID 0xb0c5. sshClient # [ 11.688546] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. sshClient # [ 11.691440] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic sshClient # [ 11.692789] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 sshClient # [ 11.459598] systemd[1]: Starting Virtual Console Setup... sshClient # [ 11.512156] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 11.555088] systemd-logind[514]: Watching system buttons on /dev/input/event2 (Power Button) sshClient # [ 11.559125] systemd-logind[514]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 11.605447] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 11.859846] Console: switching to colour frame buffer device 160x50 sshClient # [ 11.894211] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device sshClient # [ 11.641488] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 11.645116] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 11.913822] ppdev: user-space parallel port driver sshClient # [ 11.657344] systemd[1]: Starting Virtual Console Setup... sshClient # [ 11.715400] systemd-logind[514]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 11.985753] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) sshClient # [ 11.755559] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 12.017635] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) sshClient # [ 11.761235] systemd[1]: Stopped Virtual Console Setup. machine # [ 11.792599] systemd-logind[543]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 11.768396] dhcpcd[683]: eth0: carrier acquired machine # [ 11.798934] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 11.772634] systemd[1]: Starting Virtual Console Setup... machine # [ 11.806138] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 11.792674] dhcpcd[683]: DUID 00:01:00:01:32:36:27:56:52:54:00:12:34:56 sshClient # [ 11.794543] dhcpcd[683]: eth0: IAID 00:12:34:56 machine # [ 11.824716] systemd[1]: Starting Virtual Console Setup... sshClient # [ 11.795922] dhcpcd[683]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 11.889258] sshd-keygen-start[539]: Generating public/private rsa key pair. machine # [ 11.891532] sshd-keygen-start[539]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 11.894761] sshd-keygen-start[539]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine # [ 11.897164] sshd-keygen-start[539]: The key fingerprint is: machine # [ 11.898914] sshd-keygen-start[539]: SHA256:VG+XwEGWMzLA+dl4M2QlC9dVLPEXXtpJUhiT3uZw6UE root@machine sshClient # [ 12.130552] kvm_amd: TSC scaling supported sshClient # [ 12.131473] kvm_amd: Nested Virtualization enabled machine # [ 11.902172] sshd-keygen-start[539]: The key's randomart image is: sshClient # [ 12.132898] kvm_amd: Nested Paging enabled machine # [ 11.903967] sshd-keygen-start[539]: +---[RSA 4096]----+ sshClient # [ 12.134131] kvm_amd: LBR virtualization supported sshClient # [ 12.135313] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 11.905759] sshd-keygen-start[539]: | ..oooBOBB=| sshClient # [ 12.136568] kvm_amd: Virtual GIF supported sshClient # [ 12.137658] kvm_amd: Virtual NMI enabled machine # [ 11.907571] sshd-keygen-start[539]: | o.o=B*BE=| machine # [ 11.909403] sshd-keygen-start[539]: | .. O=o*+=| machine # [ 11.911152] sshd-keygen-start[539]: | . +.=+ *.| machine # [ 11.913563] sshd-keygen-start[539]: | S . o* .| machine # [ 11.915512] sshd-keygen-start[539]: | o | machine # [ 11.917394] sshd-keygen-start[539]: | | machine # [ 11.919227] sshd-keygen-start[539]: | | machine # [ 11.921371] sshd-keygen-start[539]: | | machine # [ 11.923371] sshd-keygen-start[539]: +----[SHA256]-----+ sshClient # [ 12.180944] EDAC MC: Ver: 3.0.0 machine # [ 11.966133] sshd-keygen-start[761]: Generating public/private ed25519 key pair. machine # [ 11.968434] sshd-keygen-start[761]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 11.970880] sshd-keygen-start[761]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 11.973276] sshd-keygen-start[761]: The key fingerprint is: machine # [ 11.974814] sshd-keygen-start[761]: SHA256:QSWtuVvRz3CDiVSaIffeovtYShpQZotwZRYOciUbLcY root@machine machine # [ 11.977720] sshd-keygen-start[761]: The key's randomart image is: machine # [ 11.979722] sshd-keygen-start[761]: +--[ED25519 256]--+ machine # [ 11.981556] sshd-keygen-start[761]: | ..=oX++.. | machine # [ 11.983304] sshd-keygen-start[761]: | oE@.+o= | machine # [ 11.985187] sshd-keygen-start[761]: | ..o.*+oo.o | machine # [ 11.986824] sshd-keygen-start[761]: | o =ooo.=.o | machine # [ 11.988862] sshd-keygen-start[761]: | o S. .o=.. | machine # [ 11.990379] sshd-keygen-start[761]: | .. .. .o | machine # [ 11.992259] sshd-keygen-start[761]: | .oo . | machine # [ 11.994481] sshd-keygen-start[761]: | .+ = | machine # [ 11.996479] sshd-keygen-start[761]: | . +.. | machine # [ 11.998510] sshd-keygen-start[761]: +----[SHA256]-----+ machine # [ 12.001113] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 12.002833] systemd[1]: Finished SSH Host Keys Generation. machine # [ 12.006588] systemd[1]: Starting SSH Daemon... machine # [ 12.060951] sshd[763]: Server listening on 0.0.0.0 port 22. machine # [ 12.062631] sshd[763]: Server listening on :: port 22. machine # [ 12.065188] systemd[1]: Started SSH Daemon. machine # [ 12.212245] dhcpcd[696]: eth0: soliciting an IPv6 router machine # [ 12.214413] dhcpcd[696]: eth0: Router Advertisement from fe80::2 machine # [ 12.216128] dhcpcd[696]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 12.217952] dhcpcd[696]: eth0: adding route to fec0::/64 machine # [ 12.219477] dhcpcd[696]: eth0: adding default route via fe80::2 machine # [ 12.301757] systemd-vconsole-setup[754]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 12.305996] dhcpcd[696]: eth0: soliciting a DHCP lease machine # [ 12.310231] systemd[1]: Finished Virtual Console Setup. sshClient # [ 12.291184] systemd-vconsole-setup[711]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 12.296681] systemd[1]: Finished Virtual Console Setup. machine # [ 12.336514] dhcpcd[696]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.339222] 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.19 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 # [ 12.885625] dhcpcd[683]: eth0: soliciting an IPv6 router sshClient # [ 12.890724] dhcpcd[683]: eth0: Router Advertisement from fe80::2 sshClient # [ 12.894842] dhcpcd[683]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 12.899123] dhcpcd[683]: eth0: adding route to fec0::/64 sshClient # [ 12.902702] dhcpcd[683]: eth0: adding default route via fe80::2 sshClient # [ 12.975484] dhcpcd[683]: eth0: soliciting a DHCP lease sshClient # [ 13.267561] NET: Registered PF_PACKET protocol family sshClient # [ 13.016712] dhcpcd[683]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 13.023590] dhcpcd[683]: eth0: probing address 10.0.2.15/24 machine: (finished: waiting for (repeat) to appear on tty 1, in 1.14 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for unit systemd-homed-firstboot.service machine # [ 17.402677] dhcpcd[696]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 17.407506] dhcpcd[696]: eth0: adding route to 10.0.2.0/24 machine # [ 17.411484] dhcpcd[696]: eth0: adding default route via 10.0.2.2 machine # [ 17.553168] systemd[1]: Started DHCP Client. sshClient # [ 18.436749] dhcpcd[683]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 18.442391] dhcpcd[683]: eth0: adding route to 10.0.2.0/24 sshClient # [ 18.446760] dhcpcd[683]: eth0: adding default route via 10.0.2.2 sshClient # [ 18.583344] systemd[1]: Started DHCP Client. sshClient # [ 18.587326] systemd[1]: Reached target Multi-User System. sshClient # [ 18.589280] systemd[1]: Startup finished in 1.060s (kernel) + 5.177s (initrd) + 12.350s (userspace) = 18.588s. machine # [ 23.861519] systemd-homed[500]: Generating key pair for signing local user identity records. machine # [ 23.863776] systemd-homed[500]: Successfully created Ed25519 key pair. machine # [ 23.879710] systemd-homed[500]: test-homed-user: changing state absent → creating machine # [ 23.950328] systemd-homework[887]: Using automatic default storage of 'luks'. machine # [ 23.956923] systemd-homework[887]: Sizing home to 83% of available disk space, which is 743M. machine # [ 23.959752] systemd-homework[887]: Allocating image file completed. machine # [ 23.980876] systemd-homework[887]: Writing of partition table completed. machine # [ 23.985106] systemd-homework[887]: Setting up loopback device /dev/loop0 completed. machine # [ 25.359281] systemd-homework[887]: LUKS formatting completed. machine # [ 27.084702] systemd-homework[887]: Writing password to LUKS keyslot 0 completed. machine # [ 27.380907] systemd-homework[887]: LUKS activation by volume key succeeded. machine # [ 27.397678] systemd-homework[887]: Writing user record as LUKS token completed. machine # [ 27.400096] systemd-homework[887]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 27.402416] systemd-homework[887]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 27.795888] systemd-homework[887]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 0a1736b6-6fb5-4b16-a31c-29fecaa60769) machine # [ 27.799498] systemd-homework[887]: Formatting file system completed. machine # [ 27.802823] systemd[1]: proc-923-mounts.mount: Deactivated successfully. machine # [ 27.847265] systemd-homework[887]: Mounting file system completed. machine # [ 27.850955] systemd-homework[887]: Skeleton directory /etc/skel missing, ignoring. machine # [ 27.852961] systemd-homework[887]: Wrote embedded .identity file. machine # [ 27.855511] systemd-homework[887]: Replaced embedded blob dir with contents of system blob dir. machine # [ 27.857882] systemd-homework[887]: Recursive changing of ownership completed. machine # [ 27.859941] systemd-homework[887]: Changed top-level directory access mode to 0700. machine # [ 27.865463] systemd-homework[887]: Synchronized disk. machine # [ 27.868176] systemd-homework[887]: Discarded unused 651.8M. machine # [ 27.881374] systemd-homed[500]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 27.893085] systemd-homed[500]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 27.926763] systemd-homed[500]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 27.964197] systemd-homework[887]: Moved image file into place. machine # [ 27.966155] systemd-homework[887]: Creation completed. machine # [ 27.969529] systemd-homework[887]: Image size is 743M, file system size is 725M, file system payload size is 725M, file system free is 718.9M. machine # [ 27.979845] systemd-homed[500]: test-homed-user: changing state creating → inactive machine # [ 27.987610] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 27.991617] systemd[1]: systemd-mute-console@0-1-629_630-0.service: Deactivated successfully. machine # [ 27.998681] systemd[1]: Reached target First Boot Complete. machine # [ 28.003240] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 28.008577] systemd[1]: Starting Permit User Sessions... machine # [ 28.044395] systemd[1]: Finished Permit User Sessions. machine # [ 28.051409] systemd[1]: Started Getty on tty1. machine # [ 28.054340] systemd[1]: Reached target Login Prompts. machine # [ 28.056549] systemd[1]: Reached target Multi-User System. machine # [ 28.061334] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 28.065835] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 28.070123] systemd[1]: Startup finished in 1.067s (kernel) + 4.259s (initrd) + 22.741s (userspace) = 28.069s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 15.07 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 29.77 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 28.808937] systemd-homed[500]: test-homed-user: changing state inactive → updating machine # [ 28.815966] systemd-homework[969]: Offline update requested. Not touching embedded records. machine # [ 28.818636] systemd-homed[500]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 28.825223] systemd-homed[500]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.06 seconds) subtest: login as homed user machine: waiting for login: to appear on tty 1 machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds) machine: sending keys 'test-homed-user\n' machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Password: to appear on tty 1 machine # [ 29.258296] systemd-homed[500]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 29.265671] systemd-homework[984]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 29.272354] systemd-homed[500]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 29.275694] systemd-homed[500]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 29.277913] systemd-homed[500]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 29.280635] systemd-homed[500]: Home test-homed-user already deactivated, no automatic deactivation needed. machine: (finished: waiting for Password: to appear on tty 1, in 1.15 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for success: pgrep -u test-homed-user -t tty1 bash machine # [ 30.390331] systemd-homed[500]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 30.421870] systemd-homework[996]: Provided password unlocks user record. machine # [ 30.432947] systemd-homework[996]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 30.440095] systemd-homework[996]: Allocated additional 651.8M. machine # [ 30.704041] loop1: detected capacity change from 0 to 1517568 machine # [ 30.445744] systemd-homework[996]: Setting up loopback device /dev/loop1 completed. machine # [ 31.333127] systemd-homework[996]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 31.361991] systemd-homework[996]: Provided password unlocks user record. machine # [ 31.368514] systemd-homework[996]: Probing file system completed (found btrfs). machine # [ 31.374549] systemd-homework[996]: No fsck for file system btrfs installed, ignoring. machine # [ 31.637743] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (254:0) scanned by systemd-homewor (996) machine # [ 31.640944] BTRFS info (device dm-0): first mount of filesystem 0a1736b6-6fb5-4b16-a31c-29fecaa60769 machine # [ 31.643713] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 31.648383] BTRFS info (device dm-0): enabling free space tree machine # [ 31.649560] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 31.391239] systemd-homework[996]: Mounting file system completed. machine # [ 31.394143] systemd-homework[996]: Discovered used loopback device /dev/loop1. machine # [ 31.396045] systemd-homework[996]: offset = 1048576, size = 776994816, image = 779091968 machine # [ 31.408482] systemd-homework[996]: Ready to resize image size 743M → 896M, partition size 741M → 894M, file system size 725M → 878M. machine # [ 31.411760] systemd-homework[996]: Growing of image file from 743M to 896M completed. machine # [ 31.678026] loop1: detected capacity change from 1517568 to 1830912 machine # [ 31.421377] systemd-homework[996]: Refreshing loop device size completed. machine # [ 31.438937] systemd-homework[996]: Growing of partition completed. machine # [ 31.734300] async_tx: api initialized (async) machine # [ 31.520228] systemd-homework[996]: LUKS device growing completed. machine # [ 31.785898] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 760217600 to 920649728 machine # [ 31.529253] systemd-homework[996]: File system resizing from 725M to 878M completed. machine # [ 31.534101] systemd-homework[996]: Synchronized disk. machine # [ 31.535578] systemd-homework[996]: Synchronized disk. machine # [ 31.538081] systemd-homework[996]: Resizing completed. machine # [ 31.539646] systemd-homework[996]: Image size is 896M, file system size is 878M, file system payload size is 878M, file system free is 871.9M. machine # [ 31.542727] systemd-homework[996]: Read embedded .identity file. machine # [ 31.563869] systemd-homework[996]: Provided password unlocks user record. machine # [ 31.566289] systemd-homework[996]: Reconciling header user identity completed (host version was newer). machine # [ 31.568568] systemd-homework[996]: Reconciling embedded user identity completed (host version was newer). machine # [ 31.586609] systemd-homework[996]: Wrote LUKS header user record. machine # [ 31.589126] systemd-homework[996]: Wrote embedded .identity file. machine # [ 31.590906] systemd-homework[996]: Replaced embedded blob dir with contents of system blob dir. machine # [ 31.593319] systemd-homework[996]: Recursive changing of ownership not necessary, skipped. machine # [ 31.598575] systemd-homework[996]: Synchronized disk. machine # [ 31.599961] systemd-homework[996]: Moving to final mount point /home/test-homed-user completed. machine # [ 31.620376] systemd-homework[996]: Activation completed. machine # [ 31.621883] systemd-homework[996]: Image size is 894M, file system size is 878M, file system payload size is 878M, file system free is 871.9M. machine # [ 31.635719] systemd-homed[500]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 31.642342] login[960]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 31.645121] systemd-homed[500]: test-homed-user: changing state activating-for-acquire → active machine # [ 31.654341] systemd-homed[500]: test-homed-user: changing state active → resizing-while-active machine # [ 31.668502] systemd-homework[1041]: Successfully acquired home volume key from kernel keyring. machine # [ 31.672506] systemd-homework[1041]: LUKS volume key from keyring unlocks user record. machine # [ 31.765345] login[960]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 31.793480] systemd-homework[1041]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 31.828358] systemd[1]: Created slice Slice /user/60388. machine # [ 31.834836] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 31.854832] systemd-logind[543]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 31.913233] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 31.936136] systemd[1]: Starting User Manager for UID 60388... machine # [ 31.990189] (systemd)[1046]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 32.079455] (systemd)[1046]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 32.095446] systemd-logind[543]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 32.140465] systemd[1046]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.161341] systemd-homework[1041]: Successfully re-activated LUKS device. machine # [ 32.165856] systemd-homework[1041]: LUKS volume key from keyring unlocks user record. machine # [ 32.168938] systemd-homework[1041]: Discovered used loopback device /dev/loop1. machine # [ 32.171214] systemd-homework[1041]: Read embedded .identity file. machine # [ 32.173733] systemd-homework[1041]: LUKS volume key from keyring unlocks user record. machine # [ 32.178375] systemd-homework[1041]: Reconciling header user identity completed (host version was newer). machine # [ 32.180694] systemd-homework[1041]: Reconciling embedded user identity completed (host version was newer). machine # [ 32.183221] systemd-homework[1041]: offset = 1048576, size = 937426944, image = 939524096 machine # [ 32.197152] systemd-homework[1041]: Ready to resize image size 896M → 764M, partition size 894M → 762M, file system size 878M → 746M. machine # [ 32.201735] systemd-homework[1041]: Wrote embedded .identity file. machine # [ 32.204195] systemd-homework[1041]: Replaced embedded blob dir with contents of system blob dir. machine # [ 32.206408] systemd-homework[1041]: Backing file is fully allocated already. machine # [ 32.471324] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 920649728 to 782237696 machine # [ 32.216313] systemd-homework[1041]: File system resizing from 878M to 746M completed. machine # [ 32.218860] systemd-homework[1041]: Synchronized disk. machine # [ 32.268700] systemd-homework[1041]: LUKS device shrinking completed. machine # [ 32.532412] loop1: detected capacity change from 1830912 to 1560576 machine # [ 32.277143] systemd-homework[1041]: Refreshing loop device size completed. machine # [ 32.279923] systemd-homework[1041]: Shrinking of image file completed. machine # [ 32.303635] systemd-homework[1041]: Shrinking of partition completed. machine # [ 32.323531] systemd-homework[1041]: Wrote LUKS header user record. machine # [ 32.327796] systemd-homework[1041]: Synchronized disk. machine # [ 32.332631] systemd-homework[1041]: Resizing completed. machine # [ 32.334477] systemd-homework[1041]: Image size is 764M, file system size is 746M, file system payload size is 746M, file system free is 739.9M. machine # [ 32.346187] systemd-homed[500]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 32.356804] systemd-homed[500]: test-homed-user: changing state resizing-while-active → active machine # [ 32.361315] systemd-homed[500]: Rebalancing complete. machine # [ 32.459857] systemd[1046]: Queued start job for default target Main User Target. machine # [ 32.464424] systemd[1046]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.475158] systemd[1046]: Created slice User Application Slice. machine # [ 32.478320] systemd[1046]: Started Daily Cleanup of User's Temporary Directories. machine # [ 32.480340] systemd[1046]: Reached target Paths. machine # [ 32.481879] systemd[1046]: Reached target Timers. machine # [ 32.486731] systemd[1046]: Starting D-Bus User Message Bus Socket... machine # [ 32.491363] systemd[1046]: Starting Create User Files and Directories... machine # [ 32.540170] systemd[1046]: Finished Create User Files and Directories. machine # [ 32.587813] systemd[1046]: Listening on D-Bus User Message Bus Socket. machine # [ 32.590091] systemd[1046]: Reached target Sockets. machine # [ 32.593199] systemd[1046]: Reached target Basic System. machine # [ 32.595548] systemd[1]: Started User Manager for UID 60388. machine # [ 32.600528] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 32.604158] systemd[1046]: Starting Run user-specific NixOS activation... machine # [ 32.659416] systemd[1046]: Finished Run user-specific NixOS activation. machine # [ 32.662935] systemd[1046]: Reached target Main User Target. machine # [ 32.664649] systemd[1046]: Startup finished in 537ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.34 seconds) machine: sending keys 'whoami > /tmp/2\n' machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds) machine: waiting for file '/tmp/2' machine: (finished: waiting for file '/tmp/2', in 1.10 seconds) machine: must succeed: cat /tmp/2 machine: (finished: must succeed: cat /tmp/2, in 0.04 seconds) (finished: subtest: login as homed user, in 6.12 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.09 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.13 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 # [ 36.523816] login[686]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 36.566354] systemd[1]: Created slice Slice /user/1000. sshClient # [ 36.570744] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 36.584604] systemd-logind[514]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 36.624184] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 36.634326] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 36.693795] (systemd)[814]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 36.704579] systemd-logind[514]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 36.747107] systemd[814]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 36.975884] systemd[814]: Queued start job for default target Main User Target. sshClient # [ 36.980926] systemd[814]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 36.993636] systemd[814]: Created slice User Application Slice. sshClient # [ 36.995671] systemd[814]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 36.998915] systemd[814]: Reached target Paths. sshClient # [ 37.000594] systemd[814]: Reached target Timers. sshClient # [ 37.004117] systemd[814]: Starting D-Bus User Message Bus Socket... sshClient # [ 37.008652] systemd[814]: Starting Create User Files and Directories... sshClient # [ 37.048383] systemd[814]: Finished Create User Files and Directories. sshClient # [ 37.090848] systemd[814]: Listening on D-Bus User Message Bus Socket. sshClient # [ 37.093182] systemd[814]: Reached target Sockets. sshClient # [ 37.094849] systemd[814]: Reached target Basic System. sshClient # [ 37.098166] systemd[1]: Started User Manager for UID 1000. sshClient # [ 37.103710] systemd[814]: Starting Run user-specific NixOS activation... sshClient # [ 37.106302] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 37.155902] systemd[814]: Finished Run user-specific NixOS activation. sshClient # [ 37.158354] systemd[814]: Reached target Main User Target. sshClient # [ 37.160590] systemd[814]: Startup finished in 428ms. sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.16 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.07 seconds) sshClient: must succeed: cat /tmp/1 sshClient: (finished: must succeed: cat /tmp/1, in 0.04 seconds) (finished: subtest: login as regular user, in 3.95 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.08 seconds) sshClient: must succeed: cat /tmp/id_ed25519.pub sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.02 seconds) machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILPZXhy2Agbee7GNtZYag2Mw5z/6Um9dgV44Z8ThCD1d test-normal-user@sshClient' machine # [ 40.549320] systemd-homed[500]: test-homed-user: changing state active → updating-while-active machine # [ 40.560990] systemd-homework[1100]: Offline update requested. Not touching embedded records. machine # [ 40.568910] systemd-homed[500]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILPZXhy2Agbee7GNtZYag2Mw5z/6Um9dgV44Z8ThCD1d test-normal-user@sshClient', in 0.09 seconds) machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519 machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.03 seconds) (finished: subtest: add homed ssh authorized key, in 1.71 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.17 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.16 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/3gycnalwgpix76jjky7ljgh88gwgdb0l-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 43.576392] systemd-homed[500]: test-homed-user: changing state active → passwd-while-active machine # [ 44.290401] systemd-homework[1139]: No valid password for LUKS superblock. machine # [ 46.592530] systemd-homed[500]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 46.595800] systemd-homed[500]: test-homed-user: changing state passwd-while-active → active machine: (finished: must succeed: sleep 4.0, in 4.05 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for file '/tmp/3' machine # [ 47.698098] systemd-homed[500]: test-homed-user: changing state active → passwd-while-active machine # [ 49.053076] systemd-homework[1144]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 49.190362] systemd-homework[1144]: Successfully re-activated LUKS device. machine # [ 49.234615] systemd-homework[1144]: Provided password unlocks user record. machine # [ 49.236923] systemd-homework[1144]: Discovered used loopback device /dev/loop1. machine # [ 49.238936] systemd-homework[1144]: Read embedded .identity file. machine # [ 49.281138] systemd-homework[1144]: Provided password unlocks user record. machine # [ 49.283076] systemd-homework[1144]: Reconciling header user identity completed (host version was newer). machine # [ 49.285340] systemd-homework[1144]: Reconciling embedded user identity completed (host version was newer). machine # [ 52.338071] systemd-homework[1144]: Added LUKS key slot 1. machine # [ 52.363907] systemd-homework[1144]: Destroyed LUKS key slot 0. machine # [ 52.375887] systemd-homework[1144]: Wrote LUKS header user record. machine # [ 52.377887] systemd-homework[1144]: Wrote embedded .identity file. machine # [ 52.379757] systemd-homework[1144]: Replaced embedded blob dir with contents of system blob dir. machine # [ 52.386699] systemd-homework[1144]: Synchronized disk. machine # [ 52.391115] systemd-homework[1144]: Everything completed. machine # [ 52.403400] systemd-homed[500]: test-homed-user: changing state passwd-while-active → active machine # [ 52.405784] passwd[1113]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine: (finished: waiting for file '/tmp/3', in 5.21 seconds) machine: must succeed: cat /tmp/3 machine: (finished: must succeed: cat /tmp/3, in 0.04 seconds) (finished: subtest: change homed user password, in 12.21 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 # [ 53.317209] systemd-homed[500]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 53.324338] systemd-homework[1180]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 53.367626] systemd-homed[500]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 53.371427] systemd-homed[500]: 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.16 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.05 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 54.383714] systemd-homed[500]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 54.415847] systemd-homework[1196]: Provided password unlocks user record. machine # [ 55.036919] systemd-homework[1196]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 55.169616] systemd-homework[1196]: Successfully re-activated LUKS device. machine # [ 55.193435] systemd-homework[1196]: Provided password unlocks user record. machine # [ 55.195720] systemd-homework[1196]: Discovered used loopback device /dev/loop1. machine # [ 55.197646] systemd-homework[1196]: Read embedded .identity file. machine # [ 55.218813] systemd-homework[1196]: Provided password unlocks user record. machine # [ 55.221388] systemd-homework[1196]: Reconciling user identities completed (host and header version were identical). machine # [ 55.224219] systemd-homework[1196]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 55.234934] systemd-homework[1196]: Everything completed. machine # [ 55.241148] systemd-homed[500]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 55.249094] systemd-homed[500]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 55.252052] sudo[1178]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 55.261521] sudo[1178]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 55.281068] sudo[1178]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 55.297622] sudo[1178]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.09 seconds) (finished: subtest: escalate to root from homed user, in 2.62 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 # [ 55.533641] login[960]: pam_unix(login:session): session closed for user test-homed-user machine # [ 55.540103] login[960]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 55.551481] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 55.562186] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 55.571110] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 55.573572] systemd-logind[543]: Session 1 logged out. Waiting for processes to exit. machine # [ 55.580832] systemd[1]: Started Getty on tty1. machine # [ 55.584601] systemd-logind[543]: Removed session 1. machine # [ 65.659350] systemd[1]: Stopping User Manager for UID 60388... machine # [ 65.676731] systemd[1046]: Activating special unit Exit the Session... machine # [ 65.683992] systemd[1046]: Stopped target Main User Target. machine # [ 65.692532] systemd[1046]: Stopped Run user-specific NixOS activation. machine # [ 65.701595] systemd[1046]: Stopped target Basic System. machine # [ 65.705872] systemd[1046]: Stopped target Paths. machine # [ 65.711703] systemd[1046]: Stopped target Sockets. machine # [ 65.716509] systemd[1046]: Stopped target Timers. machine # [ 65.720593] systemd[1046]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 65.727227] systemd[1046]: Closed D-Bus User Message Bus Socket. machine # [ 65.729111] systemd[1046]: Stopped Create User Files and Directories. machine # [ 65.730864] systemd[1046]: Removed slice User Application Slice. machine # [ 65.732570] systemd[1046]: Reached target Shutdown. machine # [ 65.734175] systemd[1046]: Finished Exit the Session. machine # [ 65.735706] systemd[1046]: Reached target Exit the Session. machine # [ 65.741470] systemd-logind[543]: Removed session 2. machine # [ 65.744534] systemd-homed[500]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 65.748558] systemd-homed[500]: test-homed-user: changing state active → deactivating machine # [ 65.750717] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 65.753445] systemd[1]: Stopped User Manager for UID 60388. machine # [ 65.760742] systemd-homework[1276]: Successfully acquired home volume key from kernel keyring. machine # [ 65.765227] systemd-homework[1276]: Discarded unused 673.3M. machine # [ 65.773411] systemd-homework[1276]: Syncing completed. machine # [ 65.781623] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 65.793483] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 65.844158] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 65.847094] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 65.852271] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 65.856806] systemd-homework[1276]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 65.992397] systemd-homework[1276]: Successfully re-activated LUKS device. machine # [ 65.994389] systemd-homework[1276]: Discovered used loopback device /dev/loop1. machine # [ 65.996870] systemd-homework[1276]: offset = 1048576, size = 799014912, image = 801112064 machine # [ 66.008322] systemd-homework[1276]: Ready to resize image size 764M → 274M, partition size 762M → 272M, file system size 746M → 256M. machine # [ 66.012041] systemd-homework[1276]: Allocated additional 673.3M. machine # [ 66.277270] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 782237696 to 268435456 machine # [ 66.019510] systemd-homework[1276]: File system resizing from 746M to 256M completed. machine # [ 66.021626] systemd-homework[1276]: Synchronized disk. machine # [ 66.198411] systemd-homework[1276]: LUKS device shrinking completed. machine # [ 66.460963] loop1: detected capacity change from 1560576 to 557056 machine # [ 66.203673] systemd-homework[1276]: Refreshing loop device size completed. machine # [ 66.206700] systemd-homework[1276]: Shrinking of image file completed. machine # [ 66.225265] systemd-homework[1276]: Shrinking of partition completed. machine # [ 66.487086] BTRFS info (device dm-0): last unmount of filesystem 0a1736b6-6fb5-4b16-a31c-29fecaa60769 machine # [ 66.229136] systemd-homework[1276]: Synchronized disk. machine # [ 66.230503] systemd-homework[1276]: Resizing completed. machine # [ 66.232088] systemd-homework[1276]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 66.239360] systemd-homework[1276]: Unmounting completed. machine # [ 66.240892] systemd-homework[1276]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 66.244284] systemd-homework[1276]: Device home-test-homed-user is not active. machine # [ 66.257548] systemd-homed[500]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 66.264494] systemd-homework[1276]: Everything completed. machine # [ 66.274309] systemd-homed[500]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 10.80 seconds) (finished: subtest: log out and deactivate homed user's home area, in 10.85 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 # [ 68.195321] systemd-homed[500]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 68.203173] systemd-homework[1304]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 68.210859] systemd-homed[500]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 68.214580] systemd-homed[500]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 68.217289] systemd-homed[500]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 68.220806] sshd-session[1300]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 68.223816] systemd-homed[500]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 68.227252] sshd-session[1300]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 68.231195] sshd-session[1300]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 50030 ssh2: ED25519 SHA256:PQCSrI0rEsRXexZ+2pOUpAdwBIm3BUfrhH4R2nJUI+w machine # [ 68.305184] sshd-session[1300]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 68.327382] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 68.332471] systemd-logind[543]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 68.373268] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 68.380868] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 68.414116] sshd-session[1300]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 68.419907] sshd-session[1300]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 68.444201] systemd-homed[500]: test-homed-user: changing state inactive → activating machine # [ 68.452751] systemd-homework[1309]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 68.460114] systemd-homed[500]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 68.463827] systemd-homed[500]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.24 seconds) sshClient: sending keys 'barfoobar\n' sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for success: pgrep -u test-homed-user bash machine # [ 69.505462] systemd-homed[500]: test-homed-user: changing state inactive → activating machine # [ 69.534262] systemd-homework[1314]: Provided password unlocks user record. machine # [ 69.544606] systemd-homework[1314]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 69.551911] systemd-homework[1314]: Backing file is fully allocated already. machine # [ 69.816278] loop1: detected capacity change from 0 to 557056 machine # [ 69.557667] systemd-homework[1314]: Setting up loopback device /dev/loop1 completed. machine # [ 70.443127] systemd-homework[1314]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 70.471904] systemd-homework[1314]: Provided password unlocks user record. machine # [ 70.478305] systemd-homework[1314]: Probing file system completed (found btrfs). machine # [ 70.480856] systemd-homework[1314]: No fsck for file system btrfs installed, ignoring. machine # [ 70.744329] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (254:0) scanned by systemd-homewor (1314) machine # [ 70.747816] BTRFS info (device dm-0): first mount of filesystem 0a1736b6-6fb5-4b16-a31c-29fecaa60769 machine # [ 70.750682] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 70.755835] BTRFS info (device dm-0): enabling free space tree machine # [ 70.757005] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 70.498696] systemd-homework[1314]: Mounting file system completed. machine # [ 70.501617] systemd-homework[1314]: Discovered used loopback device /dev/loop1. machine # [ 70.504119] systemd-homework[1314]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 70.513763] systemd-homework[1314]: Ready to resize image size 274M → 764M, partition size 272M → 762M, file system size 256M → 746M. machine # [ 70.516742] systemd-homework[1314]: Growing of image file from 274M to 764M completed. machine # [ 70.779961] loop1: detected capacity change from 557056 to 1560576 machine # [ 70.525107] systemd-homework[1314]: Refreshing loop device size completed. machine # [ 70.542445] systemd-homework[1314]: Growing of partition completed. machine # [ 70.607841] systemd-homework[1314]: LUKS device growing completed. machine # [ 70.876139] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 782237696 machine # [ 70.618442] systemd-homework[1314]: File system resizing from 256M to 746M completed. machine # [ 70.621212] systemd-homework[1314]: Synchronized disk. machine # [ 70.622741] systemd-homework[1314]: Synchronized disk. machine # [ 70.625183] systemd-homework[1314]: Resizing completed. machine # [ 70.626620] systemd-homework[1314]: Image size is 764M, file system size is 746M, file system payload size is 746M, file system free is 739.9M. machine # [ 70.629599] systemd-homework[1314]: Read embedded .identity file. machine # [ 70.650369] systemd-homework[1314]: Provided password unlocks user record. machine # [ 70.652841] systemd-homework[1314]: Reconciling user identities completed (host and header version were identical). machine # [ 70.655366] systemd-homework[1314]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 70.658352] systemd-homework[1314]: Recursive changing of ownership not necessary, skipped. machine # [ 70.660799] systemd-homework[1314]: Synchronized disk. machine # [ 70.663683] systemd-homework[1314]: Moving to final mount point /home/test-homed-user completed. machine # [ 70.685882] systemd-homework[1314]: Activation completed. machine # [ 70.687555] systemd-homework[1314]: Image size is 762M, file system size is 746M, file system payload size is 746M, file system free is 739.9M. machine # [ 70.700308] systemd-homed[500]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 70.708213] systemd-homed[500]: test-homed-user: changing state activating → active machine # [ 70.726385] systemd[1]: Starting User Manager for UID 60388... machine # [ 70.765732] (systemd)[1359]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 70.834604] (systemd)[1359]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 70.842927] systemd-logind[543]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 70.881681] systemd[1359]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.062706] systemd[1359]: Queued start job for default target Main User Target. machine # [ 71.067056] systemd[1359]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.071609] systemd[1359]: Created slice User Application Slice. machine # [ 71.073529] systemd[1359]: Started Daily Cleanup of User's Temporary Directories. machine # [ 71.075612] systemd[1359]: Reached target Paths. machine # [ 71.078281] systemd[1359]: Reached target Timers. machine # [ 71.083103] systemd[1359]: Starting D-Bus User Message Bus Socket... machine # [ 71.086867] systemd[1359]: Starting Create User Files and Directories... machine # [ 71.131043] systemd[1359]: Finished Create User Files and Directories. machine # [ 71.135555] systemd[1359]: Listening on D-Bus User Message Bus Socket. machine # [ 71.137714] systemd[1359]: Reached target Sockets. machine # [ 71.139305] systemd[1359]: Reached target Basic System. machine # [ 71.142803] systemd[1]: Started User Manager for UID 60388. machine # [ 71.148211] systemd[1359]: Starting Run user-specific NixOS activation... machine # [ 71.196405] systemd[1359]: Finished Run user-specific NixOS activation. machine # [ 71.198930] systemd[1359]: Reached target Main User Target. machine # [ 71.201503] systemd[1359]: Startup finished in 329ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.26 seconds) sshClient: sending keys 'whoami > /tmp/5\n' sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds) machine: waiting for file '/tmp/5' machine: (finished: waiting for file '/tmp/5', in 1.06 seconds) machine: must succeed: cat /tmp/5 machine: (finished: must succeed: cat /tmp/5, in 0.03 seconds) sshClient: sending keys 'exit\n' sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) sshClient: sending keys 'exit\n' machine # [ 73.035850] sshd-session[1307]: Received disconnect from 2001:db8:1::2 port 50030:11: disconnected by user sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.75 seconds) (finished: run the VM test script, in 74.37 seconds) machine # [ 73.046747] sshd-session[1307]: Disconnected from user test-homed-user 2001:db8:1::2 port 50030 machine # [ 73.052862] sshd-session[1300]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 73.057179] sshd-session[1300]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 73.064882] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 73.070290] systemd-logind[543]: Session 3 logged out. Waiting for processes to exit. machine # [ 73.074169] systemd-logind[543]: Removed session 3. sshClient # [ 73.104795] login[686]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 73.121692] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 73.141213] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 73.146314] systemd[1]: session-1.scope: Consumed 205ms CPU time over 36.034s wall clock time, 7.5M memory peak, 4K written to disk, 26.6K incoming IP traffic, 25.5K outgoing IP traffic. sshClient # [ 73.152280] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 73.156361] systemd-logind[514]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 73.160427] systemd[1]: Started Getty on tty1. sshClient # [ 73.163276] systemd-logind[514]: Removed session 1. test script finished in 74.54s cleanup kill QemuMachine (pid 47) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14) kill QemuMachine (pid 48) sshClient # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.29 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