tribuchet: building on jamie Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm sshClient: starting vm machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 47) machine # Formatting '/build/vm-state-machine/tmp.q8yX2nfmO8', 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: 168131a3-1f4c-4a1e-8640-9050b7268a1f machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. sshClient: QEMU running (pid 48) sshClient # Disk image does not exist, creating the virtualisation disk image... sshClient # Formatting '/build/vm-state-sshClient/tmp.ecefeARJLt', fmt=raw size=1073741824 (finished: start all VMs, in 0.40 seconds) sshClient # mke2fs 1.47.4 (6-Mar-2025) subtest: create systemd-homed user on first boot prompt sshClient # Discarding device blocks: 0/262144 done machine: waiting for unit systemd-homed.service sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes machine: waiting for the VM to finish booting sshClient # Filesystem UUID: 3537cf17-4b34-43ae-8078-52e125675163 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 # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) sshClient # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00: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 # 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 # sshClient # Booting from ROM... machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 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/4168p9mq10fvfqil04hb12s3knajsd7f-nixos-system-machine-test/init regInfo=/nix/store/80vipjz8zv0k98i46jsr0j7vf2gcjyxa-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000000] APIC: Static calls initialized machine # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.000000] kvm-clock: using sched offset of 439797131 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000005] tsc: Detected 2400.012 MHz processor machine # [ 0.000812] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000838] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000841] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002751] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002762] Using GB pages for direct mapping machine # [ 0.002821] RAMDISK: [mem 0x3e42b000-0x3ffcffff] machine # [ 0.002829] ACPI: Early table checksum verification disabled machine # [ 0.002832] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002835] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002839] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002845] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002847] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002849] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002850] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002852] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002853] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002855] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002856] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002856] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002857] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002857] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002858] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002858] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003082] No NUMA configuration found machine # [ 0.003083] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003085] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005321] Zone ranges: machine # [ 0.005322] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005323] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005325] Normal empty machine # [ 0.005325] Device empty machine # [ 0.005326] Movable zone start for each node machine # [ 0.005327] Early memory node ranges machine # [ 0.005327] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005328] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005329] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005347] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005641] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.022513] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.022960] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.022971] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.022994] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.022996] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.022998] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.022999] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.023000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.023000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.023003] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.023004] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.023007] TSC deadline timer available machine # [ 0.023011] CPU topo: Max. logical packages: 1 machine # [ 0.023012] CPU topo: Max. logical dies: 1 machine # [ 0.023012] CPU topo: Max. dies per package: 1 machine # [ 0.023015] CPU topo: Max. threads per core: 1 machine # [ 0.023016] CPU topo: Num. cores per package: 1 machine # [ 0.023016] CPU topo: Num. threads per package: 1 machine # [ 0.023017] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.023032] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.023060] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.023061] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.023063] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.023064] Booting paravirtualized kernel on KVM machine # [ 0.023067] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.027532] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.029664] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.029703] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.029705] 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/4168p9mq10fvfqil04hb12s3knajsd7f-nixos-system-machine-test/init regInfo=/nix/store/80vipjz8zv0k98i46jsr0j7vf2gcjyxa-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.029793] Unknown kernel command line parameters "regInfo=/nix/store/80vipjz8zv0k98i46jsr0j7vf2gcjyxa-closure-info/registration", will be passed to user space. machine # [ 0.029805] random: crng init done machine # [ 0.029806] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.029826] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.029838] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.029867] Fallback order for Node 0: 0 machine # [ 0.029870] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.029871] Policy zone: DMA32 machine # [ 0.032151] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.034427] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.036658] allocated 2097152 bytes of page_ext machine # [ 0.045974] ftrace: allocating 48732 entries in 192 pages machine # [ 0.045976] ftrace: allocated 192 pages with 2 groups machine # [ 0.046760] Dynamic Preempt: lazy machine # [ 0.046932] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.046932] rcu: RCU event tracing is enabled. machine # [ 0.046933] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.046934] Trampoline variant of Tasks RCU enabled. machine # [ 0.046935] Rude variant of Tasks RCU enabled. machine # [ 0.046935] Tracing variant of Tasks RCU enabled. machine # [ 0.046936] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.046936] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.046951] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.046953] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.046954] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051321] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.051587] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.051593] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns sshClient # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 2026 machine # [ 0.051693] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.055185] Console: colour VGA+ 80x25 machine # [ 0.055188] printk: legacy console [tty0] enabled machine # [ 0.084218] printk: legacy console [ttyS0] enabled machine # [ 0.189371] ACPI: Core revision 20250807 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/z3p2i51n122a9yp2zicdscms49y7mqgf-nixos-system-sshClient-test/init regInfo=/nix/store/28wrw0dw4555hhk6vcs3iv7xy4sz8qg6-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.190266] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns sshClient # [ 0.000000] BIOS-provided physical RAM map: sshClient # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.191823] APIC: Switch to symmetric I/O mode setup machine # [ 0.192816] x2apic enabled sshClient # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.193608] APIC: Switched APIC routing to: physical x2apic sshClient # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable sshClient # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.195626] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.196624] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved sshClient # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.198311] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) sshClient # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.199391] x86/cpu: User Mode Instruction Prevention (UMIP) activated sshClient # [ 0.000000] NX (Execute Disable) protection: active sshClient # [ 0.000000] APIC: Static calls initialized sshClient # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.201374] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.202307] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 sshClient # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 sshClient # [ 0.000000] DMI: Memory slots populated: 1/1 sshClient # [ 0.000000] Hypervisor detected: KVM machine # [ 0.203283] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto sshClient # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 sshClient # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.204307] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl sshClient # [ 0.000001] kvm-clock: using sched offset of 554933546 cycles machine # [ 0.206308] Transient Scheduler Attacks: Vulnerable: No microcode sshClient # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.207307] Spectre V2 : Mitigation: Enhanced / Automatic IBRS sshClient # [ 0.000005] tsc: Detected 2400.012 MHz processor sshClient # [ 0.000811] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.208307] Speculative Return Stack Overflow: Mitigation: Safe RET sshClient # [ 0.000838] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs sshClient # [ 0.000840] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT sshClient # [ 0.002743] found SMP MP-table at [mem 0x000f5450-0x000f545f] sshClient # [ 0.002753] Using GB pages for direct mapping sshClient # [ 0.002830] RAMDISK: [mem 0x3e42b000-0x3ffcffff] machine # [ 0.209307] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization sshClient # [ 0.002838] ACPI: Early table checksum verification disabled machine # [ 0.211314] Spectre V2 : Enabling IBPB for BPF sshClient # [ 0.002841] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) sshClient # [ 0.002845] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.212039] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier sshClient # [ 0.002849] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.213308] active return thunk: srso_alias_return_thunk sshClient # [ 0.002856] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.214328] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' sshClient # [ 0.002858] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.215307] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' sshClient # [ 0.002859] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.216307] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' sshClient # [ 0.002861] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.217307] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' sshClient # [ 0.002863] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.218307] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' sshClient # [ 0.002864] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.219307] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' sshClient # [ 0.002865] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.220307] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' sshClient # [ 0.002866] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] sshClient # [ 0.002867] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.221307] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 sshClient # [ 0.002867] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.222307] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 sshClient # [ 0.002868] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.223307] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 sshClient # [ 0.002868] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.224307] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 sshClient # [ 0.002869] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] sshClient # [ 0.003083] No NUMA configuration found machine # [ 0.225307] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 sshClient # [ 0.003084] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] sshClient # [ 0.003086] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.226307] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. sshClient # [ 0.005148] Zone ranges: sshClient # [ 0.005149] DMA [mem 0x0000000000001000-0x0000000000ffffff] sshClient # [ 0.005150] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] sshClient # [ 0.005152] Normal empty sshClient # [ 0.005153] Device empty sshClient # [ 0.005153] Movable zone start for each node sshClient # [ 0.005154] Early memory node ranges sshClient # [ 0.005154] node 0: [mem 0x0000000000001000-0x000000000009efff] sshClient # [ 0.005155] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] sshClient # [ 0.005156] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] sshClient # [ 0.005176] On node 0, zone DMA: 1 pages in unavailable ranges sshClient # [ 0.005419] On node 0, zone DMA: 97 pages in unavailable ranges sshClient # [ 0.021858] On node 0, zone DMA32: 40 pages in unavailable ranges sshClient # [ 0.022297] ACPI: PM-Timer IO Port: 0x608 sshClient # [ 0.022307] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) sshClient # [ 0.022330] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 sshClient # [ 0.022333] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) sshClient # [ 0.022335] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) sshClient # [ 0.022336] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) sshClient # [ 0.022337] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) sshClient # [ 0.022338] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) sshClient # [ 0.022340] ACPI: Using ACPI (MADT) for SMP configuration information sshClient # [ 0.022341] ACPI: HPET id: 0x8086a201 base: 0xfed00000 sshClient # [ 0.022344] TSC deadline timer available sshClient # [ 0.022348] CPU topo: Max. logical packages: 1 sshClient # [ 0.022349] CPU topo: Max. logical dies: 1 sshClient # [ 0.022350] CPU topo: Max. dies per package: 1 sshClient # [ 0.022353] CPU topo: Max. threads per core: 1 sshClient # [ 0.022353] CPU topo: Num. cores per package: 1 sshClient # [ 0.022354] CPU topo: Num. threads per package: 1 sshClient # [ 0.022354] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs sshClient # [ 0.022370] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() sshClient # [ 0.022398] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] sshClient # [ 0.022400] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] sshClient # [ 0.022401] [mem 0x40000000-0xafffffff] available for PCI devices sshClient # [ 0.022403] Booting paravirtualized kernel on KVM sshClient # [ 0.022406] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns sshClient # [ 0.027002] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 sshClient # [ 0.029154] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 sshClient # [ 0.029191] kvm-guest: PV spinlocks disabled, single CPU sshClient # [ 0.029193] 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/z3p2i51n122a9yp2zicdscms49y7mqgf-nixos-system-sshClient-test/init regInfo=/nix/store/28wrw0dw4555hhk6vcs3iv7xy4sz8qg6-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.260829] Freeing SMP alternatives memory: 44K machine # [ 0.261310] pid_max: default: 32768 minimum: 301 sshClient # [ 0.029284] Unknown kernel command line parameters "regInfo=/nix/store/28wrw0dw4555hhk6vcs3iv7xy4sz8qg6-closure-info/registration", will be passed to user space. machine # [ 0.262403] LSM: initializing lsm=capability,landlock,yama,bpf,ima sshClient # [ 0.029297] random: crng init done machine # [ 0.263398] landlock: Up and running. sshClient # [ 0.029298] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.264020] Yama: becoming mindful. sshClient # [ 0.029318] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.264927] LSM support for eBPF active sshClient # [ 0.029331] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.265396] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.029362] Fallback order for Node 0: 0 sshClient # [ 0.029364] Built 1 zonelists, mobility grouping on. Total pages: 262006 sshClient # [ 0.029364] Policy zone: DMA32 machine # [ 0.266326] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.031643] mem auto-init: stack:all(zero), heap alloc:on, heap free:off sshClient # [ 0.033983] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 sshClient # [ 0.036057] allocated 2097152 bytes of page_ext machine # [ 0.269130] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) sshClient # [ 0.045531] ftrace: allocating 48732 entries in 192 pages sshClient # [ 0.045534] ftrace: allocated 192 pages with 2 groups machine # [ 0.269828] Performance Events: Fam17h+ core perfctr, AMD PMU driver. sshClient # [ 0.046333] Dynamic Preempt: lazy sshClient # [ 0.046450] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.270313] ... version: 2 sshClient # [ 0.046451] rcu: RCU event tracing is enabled. machine # [ 0.271018] ... bit width: 48 sshClient # [ 0.046452] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.271310] ... generic counters: 6 sshClient # [ 0.046453] Trampoline variant of Tasks RCU enabled. machine # [ 0.272011] ... generic bitmap: 000000000000003f sshClient # [ 0.046454] Rude variant of Tasks RCU enabled. machine # [ 0.272360] ... fixed-purpose counters: 0 sshClient # [ 0.046454] Tracing variant of Tasks RCU enabled. machine # [ 0.273048] ... fixed-purpose bitmap: 0000000000000000 sshClient # [ 0.046455] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.273310] ... value mask: 0000ffffffffffff sshClient # [ 0.046455] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.274206] ... max period: 00007fffffffffff sshClient # [ 0.046476] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.275006] ... global_ctrl mask: 000000000000003f sshClient # [ 0.046477] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.275432] signal: max sigframe size: 3376 sshClient # [ 0.046478] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.276219] rcu: Hierarchical SRCU implementation. sshClient # [ 0.050794] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.276901] rcu: Max phase no-delay instances is 400. sshClient # [ 0.051078] rcu: srcu_init: Setting srcu_struct sizes based on contention. sshClient # [ 0.051083] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns sshClient # [ 0.051186] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) sshClient # [ 0.054649] Console: colour VGA+ 80x25 sshClient # [ 0.054652] printk: legacy console [tty0] enabled sshClient # [ 0.083628] printk: legacy console [ttyS0] enabled machine # [ 0.281768] smp: Bringing up secondary CPUs ... sshClient # [ 0.186557] ACPI: Core revision 20250807 machine # [ 0.282323] smp: Brought up 1 node, 1 CPU machine # [ 0.282973] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) sshClient # [ 0.187451] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns sshClient # [ 0.189017] APIC: Switch to symmetric I/O mode setup sshClient # [ 0.190013] x2apic enabled machine # [ 0.283515] Memory: 941804K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98832K reserved, 0K cma-reserved) sshClient # [ 0.190794] APIC: Switched APIC routing to: physical x2apic machine # [ 0.284521] devtmpfs: initialized machine # [ 0.285268] x86/mm: Memory block size: 128MB sshClient # [ 0.192599] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.286971] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.193647] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.287340] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.195385] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) machine # [ 0.288398] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.196468] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.289494] PM: RTC time: 18:03:20, date: 2026-09-06 sshClient # [ 0.198434] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 sshClient # [ 0.199378] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 sshClient # [ 0.200387] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.292918] NET: Registered PF_NETLINK/PF_ROUTE protocol family sshClient # [ 0.201382] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.293655] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations sshClient # [ 0.203383] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.294325] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations sshClient # [ 0.204382] Spectre V2 : Mitigation: Enhanced / Automatic IBRS sshClient # [ 0.205382] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.295451] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.296320] audit: initializing netlink subsys (disabled) machine # [ 0.297311] audit: type=2000 audit(1788717800.821:1): state=initialized audit_enabled=0 res=1 machine # [ 0.298498] thermal_sys: Registered thermal governor 'fair_share' sshClient # [ 0.206382] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization sshClient # [ 0.208390] Spectre V2 : Enabling IBPB for BPF machine # [ 0.298500] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.299311] thermal_sys: Registered thermal governor 'step_wise' sshClient # [ 0.209089] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.300279] thermal_sys: Registered thermal governor 'user_space' sshClient # [ 0.210382] active return thunk: srso_alias_return_thunk machine # [ 0.301041] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.211397] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.301323] cpuidle: using governor menu sshClient # [ 0.212382] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.303537] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 sshClient # [ 0.213382] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' sshClient # [ 0.214382] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.304532] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] sshClient # [ 0.215382] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.305313] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry sshClient # [ 0.216382] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.306321] PCI: Using configuration type 1 for base access sshClient # [ 0.217382] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.307393] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. sshClient # [ 0.218383] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 sshClient # [ 0.219382] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 sshClient # [ 0.220382] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 sshClient # [ 0.221382] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 sshClient # [ 0.222382] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 sshClient # [ 0.223382] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.315335] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.316311] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.321312] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.322309] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.328413] ACPI: Added _OSI(Module Device) machine # [ 0.329127] ACPI: Added _OSI(Processor Device) machine # [ 0.329886] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.339873] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.343707] ACPI: Interpreter enabled machine # [ 0.344325] ACPI: PM: (supports S0 S3 S4 S5) sshClient # [ 0.256903] Freeing SMP alternatives memory: 44K machine # [ 0.345078] ACPI: Using IOAPIC for interrupt routing sshClient # [ 0.257385] pid_max: default: 32768 minimum: 301 sshClient # [ 0.258460] LSM: initializing lsm=capability,landlock,yama,bpf,ima sshClient # [ 0.259483] landlock: Up and running. sshClient # [ 0.260111] Yama: becoming mindful. machine # [ 0.347369] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug sshClient # [ 0.261002] LSM support for eBPF active machine # [ 0.348311] PCI: Using E820 reservations for host bridge windows sshClient # [ 0.261493] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) sshClient # [ 0.262401] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.351430] ACPI: Enabled 2 GPEs in block 00 to 3F sshClient # [ 0.265437] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) sshClient # [ 0.266917] Performance Events: Fam17h+ core perfctr, AMD PMU driver. sshClient # [ 0.267392] ... version: 2 sshClient # [ 0.268123] ... bit width: 48 sshClient # [ 0.268384] ... generic counters: 6 sshClient # [ 0.269146] ... generic bitmap: 000000000000003f sshClient # [ 0.269384] ... fixed-purpose counters: 0 sshClient # [ 0.270140] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.359565] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) sshClient # [ 0.270384] ... value mask: 0000ffffffffffff sshClient # [ 0.271282] ... max period: 00007fffffffffff machine # [ 0.360322] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] sshClient # [ 0.272084] ... global_ctrl mask: 000000000000003f machine # [ 0.361402] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] sshClient # [ 0.272481] signal: max sigframe size: 3376 sshClient # [ 0.273269] rcu: Hierarchical SRCU implementation. machine # [ 0.362434] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] sshClient # [ 0.273991] rcu: Max phase no-delay instances is 400. machine # [ 0.363709] PCI host bridge to bus 0000:00 machine # [ 0.364316] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.365310] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.366310] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.367311] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] sshClient # [ 0.278895] smp: Bringing up secondary CPUs ... sshClient # [ 0.279399] smp: Brought up 1 node, 1 CPU machine # [ 0.368310] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.280094] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.369310] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.370310] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.280584] Memory: 941860K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98832K reserved, 0K cma-reserved) machine # [ 0.371311] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.281603] devtmpfs: initialized sshClient # [ 0.282375] x86/mm: Memory block size: 128MB machine # [ 0.372764] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint sshClient # [ 0.283822] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.284408] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). sshClient # [ 0.285468] pinctrl core: initialized pinctrl subsystem sshClient # [ 0.286683] PM: RTC time: 18:03:20, date: 2026-09-06 machine # [ 0.375371] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.376322] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] sshClient # [ 0.289998] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.377400] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] sshClient # [ 0.290732] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.378467] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] sshClient # [ 0.291400] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.380290] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.292517] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations sshClient # [ 0.293394] audit: initializing netlink subsys (disabled) sshClient # [ 0.294582] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.382381] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f] sshClient # [ 0.294584] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.383201] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] sshClient # [ 0.295386] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.384007] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] sshClient # [ 0.296344] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.384316] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] sshClient # [ 0.297134] audit: type=2000 audit(1788717800.922:1): state=initialized audit_enabled=0 res=1 machine # [ 0.385822] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.298388] thermal_sys: Registered thermal governor 'power_allocator' sshClient # [ 0.298401] cpuidle: using governor menu sshClient # [ 0.300633] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.388345] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f] sshClient # [ 0.301602] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.389197] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] sshClient # [ 0.302388] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.390092] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] sshClient # [ 0.303397] PCI: Using configuration type 1 for base access machine # [ 0.391701] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint sshClient # [ 0.304561] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.394348] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.395263] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.396131] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] sshClient # [ 0.309680] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages sshClient # [ 0.310386] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.397786] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.399339] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f] machine # [ 0.400203] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] sshClient # [ 0.315385] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.401108] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] sshClient # [ 0.316385] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.402739] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.405279] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf] machine # [ 0.406031] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.406331] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.407846] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint sshClient # [ 0.326737] ACPI: Added _OSI(Module Device) sshClient # [ 0.327386] ACPI: Added _OSI(Processor Device) machine # [ 0.409345] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f] sshClient # [ 0.328080] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.410194] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.411077] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.411928] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.413345] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df] machine # [ 0.414259] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.415120] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.416221] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref] sshClient # [ 0.336889] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.417630] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.340757] ACPI: Interpreter enabled machine # [ 0.419830] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] sshClient # [ 0.341400] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.420333] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] sshClient # [ 0.342120] ACPI: Using IOAPIC for interrupt routing sshClient # [ 0.342427] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.421864] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.343385] PCI: Using E820 reservations for host bridge windows machine # [ 0.423352] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff] machine # [ 0.424253] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] sshClient # [ 0.346526] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.425125] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.426714] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.428317] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff] machine # [ 0.429197] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.430103] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] sshClient # [ 0.355175] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.431763] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.356183] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.432978] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f] sshClient # [ 0.357888] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.433510] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.358507] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] sshClient # [ 0.359767] PCI host bridge to bus 0000:00 machine # [ 0.435011] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f] sshClient # [ 0.360389] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] sshClient # [ 0.361385] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] sshClient # [ 0.362384] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] sshClient # [ 0.363384] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] sshClient # [ 0.364384] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.365385] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] sshClient # [ 0.366385] pci_bus 0000:00: root bus resource [bus 00-ff] sshClient # [ 0.367412] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint sshClient # [ 0.368862] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.435509] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.437994] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f] machine # [ 0.438558] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.439926] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] sshClient # [ 0.371416] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] sshClient # [ 0.372396] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.440589] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint sshClient # [ 0.373364] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.441590] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO sshClient # [ 0.374334] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.442566] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint sshClient # [ 0.376103] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.444338] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f] machine # [ 0.445244] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] sshClient # [ 0.378427] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.446416] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint sshClient # [ 0.379277] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] sshClient # [ 0.380123] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.447885] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] sshClient # [ 0.380391] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] sshClient # [ 0.381911] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.451611] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.452414] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 sshClient # [ 0.384417] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.453431] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 sshClient # [ 0.385266] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.454411] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 sshClient # [ 0.386151] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.455411] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 sshClient # [ 0.386927] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.456408] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.457412] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.458414] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 sshClient # [ 0.388392] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf] sshClient # [ 0.389216] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.459351] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.460325] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 sshClient # [ 0.390158] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.461274] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.462084] ACPI: PCI: Interrupt link GSID configured for IRQ 19 sshClient # [ 0.392161] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.463047] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.463322] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 sshClient # [ 0.394419] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f] machine # [ 0.464322] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 sshClient # [ 0.395291] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.465293] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 sshClient # [ 0.396176] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.467129] iommu: Default domain type: Translated sshClient # [ 0.397804] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.467907] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.468570] ACPI: bus type USB registered machine # [ 0.469319] usbcore: registered new interface driver usbfs machine # [ 0.470281] usbcore: registered new interface driver hub sshClient # [ 0.399417] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf] machine # [ 0.471011] usbcore: registered new device driver usb sshClient # [ 0.400392] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.472182] NetLabel: Initializing sshClient # [ 0.401384] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.472746] NetLabel: domain hash size = 128 machine # [ 0.473309] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO sshClient # [ 0.403007] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.474288] NetLabel: unlabeled traffic allowed by default machine # [ 0.475014] PCI: Using ACPI for IRQ routing sshClient # [ 0.404418] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f] sshClient # [ 0.405325] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] sshClient # [ 0.406196] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] sshClient # [ 0.407862] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint sshClient # [ 0.409392] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df] sshClient # [ 0.410333] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] sshClient # [ 0.411188] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] sshClient # [ 0.412304] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref] sshClient # [ 0.413740] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint sshClient # [ 0.415329] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] sshClient # [ 0.416139] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] sshClient # [ 0.417792] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint sshClient # [ 0.419392] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff] sshClient # [ 0.420297] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] sshClient # [ 0.421200] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] sshClient # [ 0.422824] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint sshClient # [ 0.424392] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff] sshClient # [ 0.425392] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] sshClient # [ 0.426406] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] sshClient # [ 0.427968] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.428947] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f] sshClient # [ 0.429585] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint sshClient # [ 0.430925] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f] machine # [ 0.517622] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.518306] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.518306] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.518314] vgaarb: loaded machine # [ 0.519040] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.519310] hpet0: 3 comparators, 64-bit 100.000000 MHz counter sshClient # [ 0.431577] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.522395] clocksource: Switched to clocksource kvm-clock sshClient # [ 0.434964] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f] sshClient # [ 0.435699] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.525893] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.437385] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] sshClient # [ 0.438587] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.526636] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.529609] pnp: PnP ACPI init sshClient # [ 0.439666] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.530453] ACPI: IRQ 4 override to edge(!), high(!) sshClient # [ 0.440658] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.531397] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.533062] pnp: PnP ACPI: found 6 devices sshClient # [ 0.442937] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f] sshClient # [ 0.443391] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] sshClient # [ 0.444690] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint sshClient # [ 0.446146] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.540696] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.542128] clocksource: Switched to clocksource acpi_pm machine # [ 0.543129] NET: Registered PF_INET protocol family sshClient # [ 0.450182] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.544194] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) sshClient # [ 0.451242] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 sshClient # [ 0.452224] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 sshClient # [ 0.453237] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 sshClient # [ 0.454272] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 sshClient # [ 0.455288] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 sshClient # [ 0.456295] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 sshClient # [ 0.457289] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 sshClient # [ 0.458208] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 sshClient # [ 0.459178] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 sshClient # [ 0.460396] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 sshClient # [ 0.461397] ACPI: PCI: Interrupt link GSID configured for IRQ 19 sshClient # [ 0.462394] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 sshClient # [ 0.463388] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 sshClient # [ 0.464397] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 sshClient # [ 0.465398] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.560849] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.467467] iommu: Default domain type: Translated machine # [ 0.562277] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.468274] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.563575] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) sshClient # [ 0.469447] ACPI: bus type USB registered machine # [ 0.564906] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.470194] usbcore: registered new interface driver usbfs sshClient # [ 0.471059] usbcore: registered new interface driver hub machine # [ 0.566134] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.471420] usbcore: registered new device driver usb machine # [ 0.567280] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) sshClient # [ 0.473283] NetLabel: Initializing machine # [ 0.568591] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.473824] NetLabel: domain hash size = 128 machine # [ 0.569680] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.474384] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.570927] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.475333] NetLabel: unlabeled traffic allowed by default machine # [ 0.571910] NET: Registered PF_XDP protocol family sshClient # [ 0.476116] PCI: Using ACPI for IRQ routing machine # [ 0.572761] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.573816] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.574870] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.576013] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.577209] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.578340] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.580353] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.582950] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.584919] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.586976] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.588660] PCI: CLS 0 bytes, default 64 machine # [ 0.589558] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.591349] Trying to unpack rootfs image as initramfs... sshClient # [ 0.518597] pci 0000:00:01.0: vgaarb: setting as boot VGA device sshClient # [ 0.519381] pci 0000:00:01.0: vgaarb: bridge control possible sshClient # [ 0.519381] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none sshClient # [ 0.519389] vgaarb: loaded sshClient # [ 0.520108] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 sshClient # [ 0.520386] hpet0: 3 comparators, 64-bit 100.000000 MHz counter sshClient # [ 0.525467] clocksource: Switched to clocksource kvm-clock sshClient # [ 0.527132] VFS: Disk quotas dquot_6.6.0 sshClient # [ 0.529586] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) sshClient # [ 0.530969] pnp: PnP ACPI init sshClient # [ 0.531805] ACPI: IRQ 4 override to edge(!), high(!) sshClient # [ 0.532801] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved sshClient # [ 0.534397] pnp: PnP ACPI: found 6 devices machine # [ 0.636840] Initialise system trusted keyrings machine # [ 0.639784] workingset: timestamp_bits=40 max_order=18 bucket_order=0 sshClient # [ 0.542105] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns sshClient # [ 0.543695] clocksource: Switched to clocksource acpi_pm sshClient # [ 0.544696] NET: Registered PF_INET protocol family sshClient # [ 0.545762] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.660859] Key type asymmetric registered machine # [ 0.661579] Asymmetric key parser 'x509' registered machine # [ 0.662399] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) sshClient # [ 0.562277] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) sshClient # [ 0.563800] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) sshClient # [ 0.565139] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.665886] io scheduler mq-deadline registered machine # [ 0.666640] io scheduler kyber registered sshClient # [ 0.566474] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) sshClient # [ 0.567686] TCP: Hash tables configured (established 8192 bind 8192) sshClient # [ 0.568827] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.669294] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.570120] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.670513] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A sshClient # [ 0.571230] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) sshClient # [ 0.572462] NET: Registered PF_UNIX/PF_LOCAL protocol family sshClient # [ 0.573478] NET: Registered PF_XDP protocol family sshClient # [ 0.574335] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] sshClient # [ 0.575365] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.676390] Linux agpgart interface v0.103 sshClient # [ 0.576391] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.677165] ACPI: bus type drm_connector registered sshClient # [ 0.577459] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] sshClient # [ 0.578583] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] sshClient # [ 0.579730] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.680236] usbcore: registered new interface driver usbserial_generic machine # [ 0.681290] usbserial: USB Serial support registered for generic sshClient # [ 0.581546] ACPI: \_SB_.GSIA: Enabled at IRQ 16 sshClient # [ 0.583617] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.684745] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. sshClient # [ 0.585564] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.684745] Please enable it if your BIOS has the CPPC option. sshClient # [ 0.587420] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.687021] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled sshClient # [ 0.589036] PCI: CLS 0 bytes, default 64 sshClient # [ 0.589954] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.690881] drop_monitor: Initializing network drop monitor service sshClient # [ 0.591721] Trying to unpack rootfs image as initramfs... machine # [ 0.692006] NET: Registered PF_INET6 protocol family machine # [ 0.695221] Segment Routing with IPv6 machine # [ 0.697762] In-situ OAM (IOAM) with IPv6 machine # [ 0.698762] IPI shorthand broadcast: enabled machine # [ 0.707021] sched_clock: Marking stable (565014101, 141713827)->(779119034, -72391106) machine # [ 0.711949] registered taskstats version 1 machine # [ 0.712896] Loading compiled-in X.509 certificates machine # [ 0.731736] Demotion targets for Node 0: null machine # [ 0.732640] Key type .fscrypt registered machine # [ 0.734739] Key type fscrypt-provisioning registered machine # [ 0.735701] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 0.636159] Initialise system trusted keyrings machine # [ 0.736672] ima: Allocated hash algorithm: sha1 sshClient # [ 0.637110] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.740819] ima: No architecture policies found machine # [ 0.741756] PM: Magic number: 10:890:88 machine # [ 0.743322] RAS: Correctable Errors collector initialized. machine # [ 0.752453] clk: Disabling unused clocks machine # [ 0.755747] PM: genpd: Disabling unused power domains sshClient # [ 0.659372] Key type asymmetric registered sshClient # [ 0.660087] Asymmetric key parser 'x509' registered sshClient # [ 0.660972] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) sshClient # [ 0.664467] io scheduler mq-deadline registered sshClient # [ 0.665235] io scheduler kyber registered sshClient # [ 0.669345] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled sshClient # [ 0.670631] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A sshClient # [ 0.676097] Linux agpgart interface v0.103 sshClient # [ 0.676896] ACPI: bus type drm_connector registered sshClient # [ 0.679835] usbcore: registered new interface driver usbserial_generic sshClient # [ 0.680912] usbserial: USB Serial support registered for generic sshClient # [ 0.684336] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. sshClient # [ 0.684336] Please enable it if your BIOS has the CPPC option. sshClient # [ 0.686657] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled sshClient # [ 0.690459] drop_monitor: Initializing network drop monitor service sshClient # [ 0.691677] NET: Registered PF_INET6 protocol family sshClient # [ 0.694809] Segment Routing with IPv6 sshClient # [ 0.697354] In-situ OAM (IOAM) with IPv6 sshClient # [ 0.698391] IPI shorthand broadcast: enabled sshClient # [ 0.706641] sched_clock: Marking stable (566014347, 140297858)->(776853196, -70540991) sshClient # [ 0.710431] registered taskstats version 1 sshClient # [ 0.711438] Loading compiled-in X.509 certificates sshClient # [ 0.731319] Demotion targets for Node 0: null sshClient # [ 0.732245] Key type .fscrypt registered sshClient # [ 0.735328] Key type fscrypt-provisioning registered sshClient # [ 0.736289] ima: No TPM chip found, activating TPM-bypass! sshClient # [ 0.737229] ima: Allocated hash algorithm: sha1 sshClient # [ 0.739370] ima: No architecture policies found sshClient # [ 0.741332] PM: Magic number: 10:890:88 sshClient # [ 0.742955] RAS: Correctable Errors collector initialized. sshClient # [ 0.751962] clk: Disabling unused clocks sshClient # [ 0.755334] PM: genpd: Disabling unused power domains machine # [ 0.912418] Freeing initrd memory: 28308K machine # [ 0.915622] Freeing unused decrypted memory: 2028K machine # [ 0.918298] Freeing unused kernel image (initmem) memory: 3644K machine # [ 0.919411] Write protecting the kernel read-only data: 32768k machine # [ 0.921341] Freeing unused kernel image (text/rodata gap) memory: 1216K machine # [ 0.922916] Freeing unused kernel image (rodata/data gap) memory: 752K machine # [ 0.974942] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 0.976066] Run /init as init process machine # [ 0.985639] systemd[1]: Inserted module 'autofs4' machine # [ 1.002334] fuse: init (API version 7.45) machine # [ 1.008341] ACPI: \_SB_.GSIG: Enabled at IRQ 22 sshClient # [ 0.909692] Freeing initrd memory: 28308K machine # [ 1.011037] ACPI: \_SB_.GSIH: Enabled at IRQ 23 sshClient # [ 0.912773] Freeing unused decrypted memory: 2028K machine # [ 1.014206] ACPI: \_SB_.GSIE: Enabled at IRQ 20 sshClient # [ 0.915318] Freeing unused kernel image (initmem) memory: 3644K machine # [ 1.016613] ACPI: \_SB_.GSIF: Enabled at IRQ 21 sshClient # [ 0.916433] Write protecting the kernel read-only data: 32768k sshClient # [ 0.918247] Freeing unused kernel image (text/rodata gap) memory: 1216K sshClient # [ 0.919786] Freeing unused kernel image (rodata/data gap) memory: 752K machine # [ 1.044453] systemd[1]: Successfully made /usr/ read-only. sshClient # [ 0.970907] x86/mm: Checked W+X mappings: passed, no W+X pages found. sshClient # [ 0.972010] Run /init as init process sshClient # [ 0.982014] systemd[1]: Inserted module 'autofs4' sshClient # [ 0.998163] fuse: init (API version 7.45) sshClient # [ 1.004073] ACPI: \_SB_.GSIG: Enabled at IRQ 22 sshClient # [ 1.006501] ACPI: \_SB_.GSIH: Enabled at IRQ 23 sshClient # [ 1.010013] ACPI: \_SB_.GSIE: Enabled at IRQ 20 sshClient # [ 1.012955] ACPI: \_SB_.GSIF: Enabled at IRQ 21 sshClient # [ 1.047450] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.379599] systemd[1]: systemd 262-9457f81 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.384673] systemd[1]: Detected virtualization kvm. machine # [ 1.385585] systemd[1]: Detected architecture x86-64. machine # [ 1.386506] systemd[1]: Running in initrd. machine # [ 1.387576] systemd[1]: Initializing machine ID from random generator. machine # [ 1.388826] systemd[1]: Hostname set to . machine # [ 1.460192] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.495600] systemd[1]: Queued start job for default target Initrd Default Target. sshClient # [ 1.384526] systemd[1]: systemd 262-9457f81 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.397778] systemd[1]: Detected virtualization kvm. machine # [ 1.499927] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.501125] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.400168] systemd[1]: Detected architecture x86-64. machine # [ 1.502521] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.503661] systemd[1]: Reached target Path Units. sshClient # [ 1.402542] systemd[1]: Running in initrd. machine # [ 1.504538] systemd[1]: Reached target Slice Units. machine # [ 1.505434] systemd[1]: Reached target Swaps. machine # [ 1.506251] systemd[1]: Reached target Timer Units. machine # [ 1.507232] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.405463] systemd[1]: Initializing machine ID from random generator. machine # [ 1.508424] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.509587] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.408699] systemd[1]: Hostname set to . machine # [ 1.510528] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.511463] systemd[1]: Reached target Socket Units. machine # [ 1.513190] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.516805] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.525271] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.535920] systemd[1]: Starting Journal Service... machine # [ 1.546602] systemd[1]: Starting Load Kernel Modules... machine # [ 1.552811] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.572153] netfs: FS-Cache loaded machine # [ 1.576836] 9pnet: Installing 9P2000 support machine # [ 1.588389] systemd[1]: Starting Coldplug All udev Devices... sshClient # [ 1.504681] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.605879] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.611532] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.620187] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.624573] systemd-journald[67]: Collecting audit messages is disabled. machine # [ 1.628277] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.634115] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.639230] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.642364] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. sshClient # [ 1.543264] systemd[1]: Queued start job for default target Initrd Default Target. sshClient # [ 1.547752] systemd[1]: Created slice Slice /system/modprobe. sshClient # [ 1.548990] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 1.550443] systemd[1]: Expecting device /dev/disk/by-label/nixos... sshClient # [ 1.551624] systemd[1]: Reached target Path Units. machine # [ 1.651823] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.552541] systemd[1]: Reached target Slice Units. sshClient # [ 1.553401] systemd[1]: Reached target Swaps. machine # [ 1.653349] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev sshClient # [ 1.554163] systemd[1]: Reached target Timer Units. sshClient # [ 1.555158] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 1.556448] systemd[1]: Listening on Journal Socket (/dev/log). sshClient # [ 1.557658] systemd[1]: Listening on Journal Sockets. sshClient # [ 1.558747] systemd[1]: Listening on udev Kernel Socket. sshClient # [ 1.559736] systemd[1]: Reached target Socket Units. sshClient # [ 1.561659] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 1.565388] systemd[1]: Starting Load Kernel Module 9pnet_virtio... sshClient # [ 1.574384] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.684356] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.589401] systemd[1]: Starting Journal Service... machine # [ 1.692947] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 1.600425] systemd[1]: Starting Load Kernel Modules... machine # [ 1.705825] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.711992] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 1.608427] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 1.626714] netfs: FS-Cache loaded sshClient # [ 1.631599] 9pnet: Installing 9P2000 support machine # [ 1.736298] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.641206] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.744248] systemd[1]: Started Journal Service. machine # [ 1.603606] systemd-modules-load[68]: Inserted module 'dm_mod' machine # [ 1.607321] systemd-modules-load[68]: Inserted module 'virtio_balloon' machine # [ 1.608401] systemd-modules-load[68]: Inserted module 'virtio_gpu' machine # [ 1.615085] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.657405] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.619988] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.663022] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.623086] systemd[1]: Reached target Local File Systems. machine # [ 1.625928] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.671759] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.633070] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.676826] systemd-journald[67]: Collecting audit messages is disabled. sshClient # [ 1.678528] systemd[1]: modprobe@configfs.service: Deactivated successfully. sshClient # [ 1.685155] systemd[1]: Finished Load Kernel Module configfs. sshClient # [ 1.690781] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config sshClient # [ 1.696037] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.659166] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.703434] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 1.704978] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.682344] systemd-udevd[83]: Using default interface naming scheme 'v261'. sshClient # [ 1.736893] systemd[1]: Finished Load Kernel Modules. sshClient # [ 1.745515] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.708408] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.758423] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 1.768536] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 1.782433] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 1.794098] systemd[1]: Started Journal Service. sshClient # [ 1.654825] systemd-modules-load[68]: Inserted module 'dm_mod' sshClient # [ 1.657983] systemd-modules-load[68]: Inserted module 'virtio_balloon' machine # [ 1.759765] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 1.661783] systemd-modules-load[68]: Inserted module 'virtio_gpu' machine # [ 1.760734] systemd[1]: Reached target System Initialization. machine # [ 1.762137] systemd[1]: Reached target Basic System. sshClient # [ 1.673117] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 1.676107] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 1.677100] systemd[1]: Reached target Local File Systems. sshClient # [ 1.680072] systemd[1]: Starting Create System Files and Directories... sshClient # [ 1.684536] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 1.713169] systemd[1]: Finished Create System Files and Directories. sshClient # [ 1.735262] systemd-udevd[83]: Using default interface naming scheme 'v261'. sshClient # [ 1.760279] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 1.801144] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 1.803148] systemd[1]: Reached target System Initialization. sshClient # [ 1.803906] systemd[1]: Reached target Basic System. machine # [ 2.088918] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.097109] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 2.112558] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.116270] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.118076] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.128199] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.128990] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.131751] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.137757] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.140541] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.141657] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.144818] usb usb1: Product: EHCI Host Controller machine # [ 2.145610] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd machine # [ 2.148208] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.151108] hub 1-0:1.0: USB hub found machine # [ 2.152747] hub 1-0:1.0: 6 ports detected machine # [ 2.156049] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.156825] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.168055] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.173365] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200 machine # [ 2.180980] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.182116] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.200752] usb usb2: Product: UHCI Host Controller machine # [ 2.201476] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd machine # [ 2.204526] SCSI subsystem initialized machine # [ 2.212857] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.218067] hub 2-0:1.0: USB hub found machine # [ 2.224321] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 2.226808] hub 2-0:1.0: 2 ports detected machine # [ 2.087345] systemd[1]: Starting Virtual Console Setup... machine # [ 2.090115] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.253317] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.254242] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 sshClient # [ 2.156991] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.117536] (udev-worker)[92]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.121365] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.164109] virtio_blk virtio5: 1/0/0 default/read/poll queues sshClient # [ 2.166826] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.125993] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.128635] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.177248] ehci-pci 0000:00:1d.7: EHCI Host Controller sshClient # [ 2.177993] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 sshClient # [ 2.179822] serio: i8042 AUX port at 0x60,0x64 irq 12 sshClient # [ 2.180885] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 sshClient # [ 2.182700] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.286686] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 2.287502] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220 sshClient # [ 2.189345] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 sshClient # [ 2.190473] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 sshClient # [ 2.191600] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.194105] usb usb1: Product: EHCI Host Controller sshClient # [ 2.195326] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd machine # [ 2.295251] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.196035] usb usb1: SerialNumber: 0000:00:1d.7 sshClient # [ 2.198505] hub 1-0:1.0: USB hub found sshClient # [ 2.199867] hub 1-0:1.0: 6 ports detected machine # [ 2.300237] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.160067] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.160923] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.204194] uhci_hcd 0000:00:1d.0: UHCI Host Controller sshClient # [ 2.204954] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.165067] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.308748] usb usb3: Product: UHCI Host Controller machine # [ 2.309397] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd machine # [ 2.312798] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.315964] hub 3-0:1.0: USB hub found machine # [ 2.317758] hub 3-0:1.0: 2 ports detected sshClient # [ 2.217829] uhci_hcd 0000:00:1d.0: detected 2 ports sshClient # [ 2.225851] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200 machine # [ 2.326261] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 2.326960] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 2.330863] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 2.331639] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240 sshClient # [ 2.233586] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.234735] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.197521] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.341896] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.204538] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.348611] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.350758] usb usb4: Product: UHCI Host Controller sshClient # [ 2.251241] usb usb2: Product: UHCI Host Controller machine # [ 2.351446] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd sshClient # [ 2.254534] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd machine # [ 2.354914] usb usb4: SerialNumber: 0000:00:1d.2 sshClient # [ 2.255249] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.356241] hub 4-0:1.0: USB hub found machine # [ 2.357283] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.358432] hub 4-0:1.0: 2 ports detected sshClient # [ 2.118691] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 2.264063] SCSI subsystem initialized machine # [ 2.367752] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.368612] ahci 0000:00:1f.2: flags: 64bit ncq only sshClient # [ 2.269414] hub 2-0:1.0: USB hub found sshClient # [ 2.133389] systemd[1]: Starting Virtual Console Setup... sshClient # [ 2.274489] hub 2-0:1.0: 2 ports detected sshClient # [ 2.277912] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 sshClient # [ 2.140476] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 2.142437] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.383645] scsi host0: ahci machine # [ 2.386060] scsi host1: ahci machine # [ 2.388206] scsi host2: ahci machine # [ 2.393755] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.395182] scsi host3: ahci machine # [ 2.399782] scsi host4: ahci machine # [ 2.402610] scsi host5: ahci machine # [ 2.404186] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1 machine # [ 2.408435] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1 machine # [ 2.409624] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1 machine # [ 2.410806] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1 machine # [ 2.411946] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1 machine # [ 2.413109] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1 sshClient # [ 2.176328] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 2.178906] systemd[1]: Finished Virtual Console Setup. sshClient # [ 2.322004] uhci_hcd 0000:00:1d.1: UHCI Host Controller sshClient # [ 2.328475] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 sshClient # [ 2.336802] uhci_hcd 0000:00:1d.1: detected 2 ports sshClient # [ 2.337639] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220 sshClient # [ 2.342755] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.346409] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.350390] usb usb3: Product: UHCI Host Controller sshClient # [ 2.351056] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd sshClient # [ 2.214266] systemd[1]: Found device /dev/disk/by-label/nixos. sshClient # [ 2.217869] systemd[1]: Reached target Initrd Root Device. sshClient # [ 2.220071] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... sshClient # [ 2.361371] usb usb3: SerialNumber: 0000:00:1d.1 sshClient # [ 2.363554] hub 3-0:1.0: USB hub found sshClient # [ 2.365466] hub 3-0:1.0: 2 ports detected sshClient # [ 2.370652] uhci_hcd 0000:00:1d.2: UHCI Host Controller sshClient # [ 2.378362] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 sshClient # [ 2.383369] uhci_hcd 0000:00:1d.2: detected 2 ports sshClient # [ 2.384165] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240 sshClient # [ 2.387593] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 sshClient # [ 2.396159] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 sshClient # [ 2.397715] usb usb4: Product: UHCI Host Controller sshClient # [ 2.258901] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks sshClient # [ 2.401516] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd sshClient # [ 2.402274] usb usb4: SerialNumber: 0000:00:1d.2 sshClient # [ 2.266866] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. sshClient # [ 2.408600] hub 4-0:1.0: USB hub found sshClient # [ 2.412335] hub 4-0:1.0: 2 ports detected sshClient # [ 2.418641] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode sshClient # [ 2.419790] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.523065] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.525941] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.529070] usb 1-1: Product: QEMU USB Tablet machine # [ 2.530822] usb 1-1: Manufacturer: QEMU sshClient # [ 2.432526] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.532436] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 sshClient # [ 2.440329] usb 1-1: new high-speed USB device number 2 using ehci-pci sshClient # [ 2.444365] scsi host0: ahci sshClient # [ 2.445691] scsi host1: ahci sshClient # [ 2.447939] scsi host2: ahci sshClient # [ 2.452850] scsi host3: ahci sshClient # [ 2.457867] scsi host4: ahci sshClient # [ 2.459894] scsi host5: ahci machine # [ 2.561609] hid: raw HID events driver (C) Jiri Kosina sshClient # [ 2.462760] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1 sshClient # [ 2.464523] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1 sshClient # [ 2.465632] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1 sshClient # [ 2.466878] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1 sshClient # [ 2.468051] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1 sshClient # [ 2.469226] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1 machine # [ 2.458560] systemd[1]: Mounting /sysroot... sshClient # [ 2.570577] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 sshClient # [ 2.573378] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 sshClient # [ 2.576514] usb 1-1: Product: QEMU USB Tablet sshClient # [ 2.578245] usb 1-1: Manufacturer: QEMU sshClient # [ 2.579981] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 sshClient # [ 2.609265] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.721303] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.723545] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.726105] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.728350] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.730651] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.733265] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.735334] ata3.00: applying bridge limits machine # [ 2.737229] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.739381] ata3.00: configured for UDMA/100 machine # [ 2.741847] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 sshClient # [ 2.511927] systemd[1]: Mounting /sysroot... machine # [ 2.810371] usbcore: registered new interface driver usbhid machine # [ 2.816531] usbhid: USB HID core driver machine # [ 2.836029] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 machine # [ 2.837781] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 machine # [ 2.849255] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.857858] EXT4-fs (vda): mounted filesystem 168131a3-1f4c-4a1e-8640-9050b7268a1f r/w with ordered data mode. Quota mode: none. machine # [ 2.862395] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.722624] systemd[1]: Mounted /sysroot. machine # [ 2.725035] systemd[1]: Reached target Initrd Root File System. machine # [ 2.729025] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.741865] systemd-sysroot-fstab-check[139]: /sysroot should be mounted in the initrd, will request daemon-reload. sshClient # [ 2.783983] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.744585] systemd[1]: Reload requested from client PID 139 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 2.786235] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.746065] systemd[1]: Reloading... sshClient # [ 2.788857] ata6: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.791210] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) sshClient # [ 2.793797] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 sshClient # [ 2.795904] ata3.00: applying bridge limits sshClient # [ 2.797774] ata4: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.800063] ata5: SATA link down (SStatus 0 SControl 300) sshClient # [ 2.802220] ata3.00: configured for UDMA/100 sshClient # [ 2.804708] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.822035] systemd[1]: Reloading finished in 75 ms. machine # [ 2.831063] systemd-sysroot-fstab-check[139]: Requesting initrd-fs.target/start/replace... machine # [ 2.833067] systemd-sysroot-fstab-check[139]: Requesting swap.target/start/replace... sshClient # [ 2.876113] usbcore: registered new interface driver usbhid sshClient # [ 2.880654] usbhid: USB HID core driver machine # [ 2.839107] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.840170] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.841264] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.843336] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio sshClient # [ 2.891503] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 sshClient # [ 2.897638] 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 # [ 2.916711] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray sshClient # [ 2.927439] cdrom: Uniform CD-ROM driver Revision: 3.20 sshClient # [ 2.930626] EXT4-fs (vda): mounted filesystem 3537cf17-4b34-43ae-8078-52e125675163 r/w with ordered data mode. Quota mode: none. sshClient # [ 2.797304] systemd[1]: Mounted /sysroot. sshClient # [ 2.797949] systemd[1]: Reached target Initrd Root File System. sshClient # [ 2.801243] systemd[1]: Starting Mountpoints Configured in the Real Root... sshClient # [ 2.815808] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload. sshClient # [ 2.819066] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)... sshClient # [ 2.820571] systemd[1]: Reloading... sshClient # [ 2.896889] systemd[1]: Reloading finished in 77 ms. sshClient # [ 2.905157] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace... sshClient # [ 2.907218] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace... sshClient # [ 2.912479] systemd[1]: initrd-parse-etc.service: Deactivated successfully. sshClient # [ 2.913855] systemd[1]: Finished Mountpoints Configured in the Real Root. sshClient # [ 2.915141] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. sshClient # [ 2.917711] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.460169] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.466152] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.474151] systemd[1]: Mounting /sysroot/run... machine # [ 3.482160] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.491666] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.679175] 9p: Installing v9fs 9p2000 file system support machine # [ 3.541074] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.544088] systemd[1]: Mounted /sysroot/run. machine # [ 3.549411] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.556610] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.558301] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.559274] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.568771] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.569828] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.571955] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.590384] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.591893] systemd[1]: Reached target Initrd File Systems. machine # [ 3.592859] systemd[1]: Starting Find NixOS closure... machine # [ 3.595366] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 3.514192] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.614120] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 3.519958] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.622247] systemd[1]: Finished Find NixOS closure. machine # [ 3.623982] systemd[1]: Reached target Initrd Default Target. machine # [ 3.625130] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 3.530308] systemd[1]: Mounting /sysroot/run... machine # [ 3.638391] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.640056] systemd[1]: Stopped target Basic System. sshClient # [ 3.540348] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.640757] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.641504] systemd[1]: Stopped target Path Units. machine # [ 3.642227] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.643206] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.644210] systemd[1]: Stopped target Slice Units. machine # [ 3.645540] systemd[1]: Stopped target Socket Units. machine # [ 3.646481] systemd[1]: Stopped target System Initialization. machine # [ 3.647582] systemd[1]: Stopped target Swaps. machine # [ 3.648417] systemd[1]: Stopped target Timer Units. machine # [ 3.649492] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.650759] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.651635] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.653134] systemd[1]: Stopped Find NixOS closure. sshClient # [ 3.553694] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.653848] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.655786] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.657172] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.658245] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.659395] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.660594] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.662132] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.663199] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.664354] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.665320] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.667105] systemd[1]: Stopped target Local File Systems. machine # [ 3.667850] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.668728] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.669832] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.672620] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.673649] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.674611] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.682586] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.685126] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.691100] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.694079] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.696122] systemd[1]: Starting Cleanup udev Database... machine # [ 3.696916] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 3.598902] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.698105] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 3.741360] 9p: Installing v9fs 9p2000 file system support machine # [ 3.700952] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 3.603571] systemd[1]: Mounted /sysroot/run. machine # [ 3.702065] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.702994] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.703980] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.704866] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.707121] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.612446] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 3.615268] systemd[1]: Mounted /sysroot/nix/.ro-store. sshClient # [ 3.616203] systemd[1]: Mounted /sysroot/tmp/shared. sshClient # [ 3.617118] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.720709] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.723198] systemd[1]: Finished Cleanup udev Database. machine # [ 3.723935] systemd[1]: Reached target Switch Root. machine # [ 3.725683] systemd[1]: Starting NixOS Activation... sshClient # [ 3.627931] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 3.629458] systemd[1]: Finished rw-sysroot-nix-store.service. sshClient # [ 3.631634] systemd[1]: Mounting /sysroot/nix/store... sshClient # [ 3.651154] systemd[1]: Mounted /sysroot/nix/store. sshClient # [ 3.652263] systemd[1]: Reached target Initrd File Systems. sshClient # [ 3.653965] systemd[1]: Starting Find NixOS closure... sshClient # [ 3.656641] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... sshClient # [ 3.675573] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. sshClient # [ 3.684211] systemd[1]: Finished Find NixOS closure. sshClient # [ 3.685562] systemd[1]: Reached target Initrd Default Target. sshClient # [ 3.687884] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... sshClient # [ 3.700865] systemd[1]: Stopped target Initrd Default Target. sshClient # [ 3.702272] systemd[1]: Stopped target Basic System. sshClient # [ 3.703209] systemd[1]: Stopped target Initrd Root Device. sshClient # [ 3.704171] systemd[1]: Stopped target Path Units. sshClient # [ 3.706057] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. sshClient # [ 3.707124] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. sshClient # [ 3.708204] systemd[1]: Stopped target Slice Units. sshClient # [ 3.708953] systemd[1]: Stopped target Socket Units. sshClient # [ 3.709947] systemd[1]: Stopped target System Initialization. sshClient # [ 3.710815] systemd[1]: Stopped target Swaps. sshClient # [ 3.711495] systemd[1]: Stopped target Timer Units. sshClient # [ 3.712344] systemd[1]: dbus.socket: Deactivated successfully. sshClient # [ 3.713361] systemd[1]: Closed D-Bus System Message Bus Socket. sshClient # [ 3.714460] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. sshClient # [ 3.716140] systemd[1]: Stopped Find NixOS closure. sshClient # [ 3.716807] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio sshClient # [ 3.719117] systemd[1]: Starting rw-sysroot-nix-store.service... sshClient # [ 3.720180] systemd[1]: systemd-sysctl.service: Deactivated successfully. sshClient # [ 3.722243] systemd[1]: Stopped Apply Kernel Variables. sshClient # [ 3.723303] systemd[1]: systemd-modules-load.service: Deactivated successfully. sshClient # [ 3.724481] systemd[1]: Stopped Load Kernel Modules. sshClient # [ 3.725535] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. sshClient # [ 3.726764] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. sshClient # [ 3.728176] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. sshClient # [ 3.729278] systemd[1]: Stopped Create System Files and Directories. sshClient # [ 3.731151] systemd[1]: Stopped target Local File Systems. sshClient # [ 3.732201] systemd[1]: Stopped target Preparation for Local File Systems. sshClient # [ 3.734118] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. sshClient # [ 3.735091] systemd[1]: Stopped Coldplug All udev Devices. sshClient # [ 3.736151] systemd[1]: Stopping Rule-based Manager for Device Events and Files... sshClient # [ 3.737883] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 3.739372] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 3.746140] systemd[1]: initrd-cleanup.service: Deactivated successfully. sshClient # [ 3.748103] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. sshClient # [ 3.756061] systemd[1]: systemd-udevd.service: Deactivated successfully. sshClient # [ 3.759120] systemd[1]: Stopped Rule-based Manager for Device Events and Files. sshClient # [ 3.764070] systemd[1]: Starting Cleanup udev Database... sshClient # [ 3.764884] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. sshClient # [ 3.765978] systemd[1]: Stopped Create Static Device Nodes in /dev. sshClient # [ 3.766844] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. sshClient # [ 3.767916] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. sshClient # [ 3.768965] systemd[1]: kmod-static-nodes.service: Deactivated successfully. sshClient # [ 3.770124] systemd[1]: Stopped Create List of Static Device Nodes. sshClient # [ 3.770974] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. sshClient # [ 3.773644] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.880574] initrd-nixos-activation-start[194]: booting system configuration /nix/store/4168p9mq10fvfqil04hb12s3knajsd7f-nixos-system-machine-test sshClient # [ 3.789869] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. sshClient # [ 3.791570] systemd[1]: Finished Cleanup udev Database. sshClient # [ 3.792735] systemd[1]: Reached target Switch Root. sshClient # [ 3.794476] systemd[1]: Starting NixOS Activation... machine # [ 3.942235] initrd-nixos-activation-start[194]: running activation script... sshClient # [ 3.956070] initrd-nixos-activation-start[194]: booting system configuration /nix/store/z3p2i51n122a9yp2zicdscms49y7mqgf-nixos-system-sshClient-test sshClient # [ 4.018829] initrd-nixos-activation-start[194]: running activation script... machine # [ 4.337959] initrd-nixos-activation-start[217]: setting up /etc... sshClient # [ 4.428937] initrd-nixos-activation-start[217]: setting up /etc... machine # [ 4.592675] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.595083] systemd[1]: Finished NixOS Activation. machine # [ 4.595978] systemd[1]: Starting Switch Root... machine # [ 4.609225] systemd[1]: Switching root. machine # [ 4.882685] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). sshClient # [ 4.689789] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. sshClient # [ 4.692063] systemd[1]: Finished NixOS Activation. sshClient # [ 4.693104] systemd[1]: Starting Switch Root... sshClient # [ 4.706844] systemd[1]: Switching root. machine # [ 5.028772] NET: Registered PF_VSOCK protocol family sshClient # [ 4.986426] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). sshClient # [ 5.130272] NET: Registered PF_VSOCK protocol family machine # [ 5.412117] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 5.421067] systemd[1]: Detected virtualization kvm. machine # [ 5.422821] systemd[1]: Detected architecture x86-64. machine # [ 5.424696] systemd[1]: Detected first boot. machine # [ 5.431403] systemd[1]: Initializing machine ID from random generator. machine # [ 5.551847] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 5.510620] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) sshClient # [ 5.520029] systemd[1]: Detected virtualization kvm. sshClient # [ 5.521806] systemd[1]: Detected architecture x86-64. sshClient # [ 5.523771] systemd[1]: Detected first boot. sshClient # [ 5.530223] systemd[1]: Initializing machine ID from random generator. machine # [ 5.674996] systemd[1]: Applying preset policy. sshClient # [ 5.654082] systemd[1]: bpf-restrict-fs: LSM BPF program attached sshClient # [ 5.769110] systemd[1]: Applying preset policy. machine # [ 6.157150] systemd[1]: Populated /etc with preset unit settings. sshClient # [ 6.278658] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.628596] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.630087] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.632507] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.634664] systemd[1]: Created slice Slice /system/getty. machine # [ 6.636031] systemd[1]: Created slice User and Session Slice. machine # [ 6.637110] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.638312] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.639349] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.640049] systemd[1]: Expecting device /dev/ttyS0... machine # [ 6.640804] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.641607] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.642338] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.643089] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.643938] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.644831] systemd[1]: Reached target Path Units. machine # [ 6.645528] systemd[1]: Reached target Remote File Systems. machine # [ 6.646285] systemd[1]: Reached target Slice Units. machine # [ 6.647002] systemd[1]: Reached target Swaps. machine # [ 6.649654] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.664824] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.667396] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.669871] systemd[1]: Listening on Factory Reset Management. machine # [ 6.670793] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.674269] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.676069] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.678862] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.680385] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.681887] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.683177] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.691821] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.692871] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.693782] systemd[1]: Listening on User Database Manager Socket. machine # [ 6.697664] systemd[1]: Mounting Huge Pages File System... machine # [ 6.700795] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.706065] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.712566] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.718997] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.721207] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 6.732694] systemd[1]: Starting Load Kernel Module configfs... machine # [ 6.733980] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.735592] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.737527] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.747405] systemd[1]: Mounting FUSE Control File System... machine # [ 6.748794] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.782260] systemd[1]: Starting Journal Service... machine # [ 6.802578] systemd[1]: Starting Load Kernel Modules... machine # [ 6.824544] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.830906] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.836805] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.856565] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.873650] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.875986] systemd-journald[288]: Collecting audit messages is enabled. machine # [ 6.877434] systemd[1]: Mounted Huge Pages File System. machine # [ 6.882295] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.888354] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.892693] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.900535] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.905687] loop: module loaded machine # [ 6.911019] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 6.911960] EXT4-fs (vda): re-mounted 168131a3-1f4c-4a1e-8640-9050b7268a1f. machine # [ 6.916608] systemd[1]: Finished Load Kernel Module configfs. machine # [ 6.775712] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.777862] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.779520] systemd-modules-load[289]: Inserted module 'loop' machine # [ 6.924974] systemd[1]: Started Journal Service. machine # [ 6.788220] systemd[1]: Mounted FUSE Control File System. machine # [ 6.790290] systemd[1]: Finished Load Kernel Modules. machine # [ 6.794168] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.808145] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.816065] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.824067] systemd[1]: Starting Firewall... sshClient # [ 6.867486] systemd[1]: initrd-switch-root.service: Deactivated successfully. sshClient # [ 6.869028] systemd[1]: Stopped initrd-switch-root.service. sshClient # [ 6.871573] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. sshClient # [ 6.873768] systemd[1]: Created slice Slice /system/getty. machine # [ 6.832749] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 6.875055] systemd[1]: Created slice User and Session Slice. machine # [ 6.834078] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 6.876219] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. sshClient # [ 6.877414] systemd[1]: Started Forward Password Requests to Wall Directory Watch. sshClient # [ 6.878496] systemd[1]: Expecting device /dev/hvc0... sshClient # [ 6.879159] systemd[1]: Expecting device /dev/ttyS0... sshClient # [ 6.879916] systemd[1]: Reached target Local Encrypted Volumes. sshClient # [ 6.880761] systemd[1]: Stopped target initrd-fs.target. sshClient # [ 6.881480] systemd[1]: Stopped target initrd-root-fs.target. sshClient # [ 6.882228] systemd[1]: Stopped target initrd-switch-root.target. sshClient # [ 6.883065] systemd[1]: Reached target Virtual Machines and Containers. sshClient # [ 6.883957] systemd[1]: Reached target Path Units. sshClient # [ 6.884662] systemd[1]: Reached target Remote File Systems. sshClient # [ 6.885409] systemd[1]: Reached target Slice Units. sshClient # [ 6.886095] systemd[1]: Reached target Swaps. machine # [ 6.844854] systemd-oomd[291]: No swap; memory pressure usage will be degraded machine # [ 6.847410] systemd[1]: Starting Load/Save OS Random Seed... sshClient # [ 6.889286] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.859453] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 6.904495] systemd[1]: Listening on Process Core Dump Socket. sshClient # [ 6.907470] systemd[1]: Listening on Credential Encryption/Decryption. sshClient # [ 6.910250] systemd[1]: Listening on Factory Reset Management. sshClient # [ 6.911172] systemd[1]: Listening on Hostname Service Socket. sshClient # [ 6.914960] systemd[1]: Starting Journal Log Access Socket... sshClient # [ 6.916818] systemd[1]: Listening on Journal Audit Socket. sshClient # [ 6.919824] systemd[1]: Listening on Console Output Muting Service Socket. sshClient # [ 6.921072] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. sshClient # [ 6.922815] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.881676] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 6.924148] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.885062] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.886960] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. sshClient # [ 6.933944] systemd[1]: Listening on Disk Repartitioning Service Socket. sshClient # [ 6.935029] systemd[1]: Listening on udev Varlink Socket. sshClient # [ 6.935971] systemd[1]: Listening on User Database Manager Socket. sshClient # [ 6.940498] systemd[1]: Mounting Huge Pages File System... sshClient # [ 6.943651] systemd[1]: Mounting POSIX Message Queue File System... sshClient # [ 6.948592] systemd[1]: Mounting Kernel Debug File System... sshClient # [ 6.955419] systemd[1]: Mounting Kernel Trace File System... sshClient # [ 6.960874] systemd[1]: Starting Create List of Static Device Nodes... sshClient # [ 6.963221] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 7.066403] systemd-journald[288]: Received client request to flush runtime journal. sshClient # [ 6.973057] systemd[1]: Starting Load Kernel Module configfs... sshClient # [ 6.974653] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm sshClient # [ 6.976234] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 6.978378] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 6.986942] systemd[1]: Mounting FUSE Control File System... sshClient # [ 6.988324] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 sshClient # [ 7.027927] systemd[1]: Starting Journal Service... sshClient # [ 7.048936] systemd[1]: Starting Load Kernel Modules... sshClient # [ 7.074445] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... sshClient # [ 7.086907] systemd[1]: Starting Remount Root and Kernel File Systems... sshClient # [ 7.091741] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.051423] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.057049] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.058116] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.058858] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.064443] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 7.107273] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.065726] systemd[1]: Finished Flush Journal to Persistent Storage. sshClient # [ 7.109837] systemd-journald[288]: Collecting audit messages is enabled. sshClient # [ 7.123195] systemd[1]: Listening on Journal Log Access Socket. sshClient # [ 7.128514] systemd[1]: Mounted Huge Pages File System. sshClient # [ 7.133170] systemd[1]: Mounted POSIX Message Queue File System. sshClient # [ 7.139767] systemd[1]: Mounted Kernel Debug File System. sshClient # [ 7.002511] systemd[1]: Queued start job for default target Multi-User System. sshClient # [ 7.006429] systemd[1]: systemd-journald.service: Deactivated successfully. sshClient # [ 7.149542] systemd[1]: Started Journal Service. sshClient # [ 7.016351] systemd[1]: Mounted Kernel Trace File System. sshClient # [ 7.017679] systemd[1]: Finished Create List of Static Device Nodes. sshClient # [ 7.020353] systemd[1]: modprobe@configfs.service: Deactivated successfully. sshClient # [ 7.024074] systemd[1]: Finished Load Kernel Module configfs. sshClient # [ 7.165391] EXT4-fs (vda): re-mounted 3537cf17-4b34-43ae-8078-52e125675163. sshClient # [ 7.166557] loop: module loaded sshClient # [ 7.028211] systemd[1]: Mounted FUSE Control File System. sshClient # [ 7.030811] systemd-modules-load[289]: Inserted module 'loop' sshClient # [ 7.039451] systemd[1]: Finished Load Kernel Modules. sshClient # [ 7.042181] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.141762] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.145756] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.148066] systemd[1]: Starting Rule-based Manager for Device Events and Files... sshClient # [ 7.051971] systemd[1]: Listening on Disk Image Download Service Socket. sshClient # [ 7.058227] systemd[1]: Mounting Kernel Configuration File System... sshClient # [ 7.065079] systemd[1]: Starting Firewall... sshClient # [ 7.072163] systemd[1]: Starting Flush Journal to Persistent Storage... sshClient # [ 7.073099] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 7.087780] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.193463] systemd[1]: Finished Coldplug All udev Devices. sshClient # [ 7.096247] systemd-oomd[291]: No swap; memory pressure usage will be degraded sshClient # [ 7.106196] systemd[1]: Starting Apply Kernel Variables... sshClient # [ 7.115318] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... sshClient # [ 7.117282] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 7.122159] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.232222] systemd-udevd[330]: Using default interface naming scheme 'v261'. sshClient # [ 7.298010] systemd-journald[288]: Received client request to flush runtime journal. machine # [ 7.291969] systemd[1]: Starting User Database Manager... machine # [ 7.342384] systemd[1]: Started User Database Manager. sshClient # [ 7.299272] systemd[1]: Finished Load/Save OS Random Seed. sshClient # [ 7.300311] systemd[1]: Mounted Kernel Configuration File System. sshClient # [ 7.302182] systemd[1]: Finished Apply Kernel Variables. sshClient # [ 7.302882] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. sshClient # [ 7.304150] systemd[1]: Starting Create Static Device Nodes in /dev... sshClient # [ 7.310768] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.416225] systemd[1]: Started Rule-based Manager for Device Events and Files. sshClient # [ 7.375194] systemd[1]: Finished Create Static Device Nodes in /dev. sshClient # [ 7.376962] systemd[1]: Reached target Preparation for Local File Systems. sshClient # [ 7.382211] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.491807] systemd[1]: Mounting /run/wrappers... machine # [ 7.533308] systemd[1]: Mounted /run/wrappers. machine # [ 7.541364] systemd[1]: Reached target Local File Systems. machine # [ 7.545752] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.550362] systemd[1]: Starting register-nix-paths.service... sshClient # [ 7.453492] systemd[1]: Finished Coldplug All udev Devices. machine # [ 7.553681] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.555110] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.569682] systemd[1]: Starting Create System Files and Directories... sshClient # [ 7.472475] systemd-udevd[329]: Using default interface naming scheme 'v261'. machine # [ 7.587169] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse sshClient # [ 7.529387] systemd[1]: Starting User Database Manager... sshClient # [ 7.579812] systemd[1]: Started User Database Manager. machine # [ 7.699295] systemd[1]: Finished Create System Files and Directories. machine # [ 7.710610] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 7.717951] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 7.649550] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.808374] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 7.821098] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 7.732678] systemd[1]: Mounting /run/wrappers... machine # [ 7.858219] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 7.866687] systemd[1]: Starting Update is Completed... sshClient # [ 7.783366] systemd[1]: Mounted /run/wrappers. sshClient # [ 7.785381] systemd[1]: Reached target Local File Systems. sshClient # [ 7.789213] systemd[1]: Listening on Boot Loader Control Service Socket. sshClient # [ 7.793158] systemd[1]: Starting register-nix-paths.service... sshClient # [ 7.798676] systemd[1]: Starting Create SUID/SGID Wrappers... sshClient # [ 7.800286] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.903438] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. sshClient # [ 7.813721] systemd[1]: Starting Create System Files and Directories... sshClient # [ 7.814680] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.928780] systemd[1]: Finished Update is Completed. machine # [ 7.974562] (udev-worker)[378]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 7.981353] (udev-worker)[374]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.982681] (udev-worker)[378]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 7.942784] systemd[1]: Finished Create System Files and Directories. sshClient # [ 7.954721] systemd[1]: Starting Rebuild Journal Catalog... sshClient # [ 7.969656] systemd[1]: Starting Record System Boot/Shutdown in UTMP... sshClient # [ 8.020689] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. sshClient # [ 8.068174] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.188752] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 8.190643] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.192324] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.194596] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.198204] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.200569] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.203123] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 8.112506] systemd[1]: Finished Rebuild Journal Catalog. sshClient # [ 8.116838] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. sshClient # [ 8.122883] systemd[1]: Starting Update is Completed... machine # [ 8.258251] systemd[1]: Finished Firewall. machine # [ 8.266878] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.269737] systemd[1]: Finished Create SUID/SGID Wrappers. sshClient # [ 8.182634] systemd[1]: Finished Update is Completed. sshClient # [ 8.189376] (udev-worker)[379]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. sshClient # [ 8.192754] (udev-worker)[379]: Network interface NamePolicy= disabled on kernel command line. sshClient # [ 8.198849] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line. machine # [ 8.478982] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 8.490221] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 8.458103] systemd[1]: Finished register-nix-paths.service. machine # [ 8.459226] systemd[1]: Reached target System Initialization. machine # [ 8.461541] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 8.463165] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 8.464350] systemd[1]: Reached target Timer Units. machine # [ 8.466481] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 8.468347] systemd[1]: Listening on Nix Daemon Socket. machine # [ 8.470062] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine # [ 8.471220] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 8.473289] systemd[1]: Reached target Socket Units. machine # [ 8.474061] systemd[1]: Reached target Basic System. machine # [ 8.477162] systemd[1]: Started backdoor.service. machine # [ 8.483540] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 8.491460] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.502420] systemd[1]: Starting Post-Boot Actions... sshClient # [ 8.410543] systemd[1]: Condition check resulted in Virtio network device being skipped. sshClient # [ 8.413891] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore sshClient # [ 8.415497] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. sshClient # [ 8.417530] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.517875] systemd[1]: Started Reset console on configuration changes. sshClient # [ 8.420826] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore sshClient # [ 8.423098] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os sshClient # [ 8.425139] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.532239] systemd[1]: Starting resolvconf update... sshClient # [ 8.444855] systemd[1]: Finished Firewall. sshClient # [ 8.460251] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.559669] systemd[1]: Starting SSH Host Keys Generation... sshClient # [ 8.461761] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.507758] mousedev: PS/2 mouse device common for all mice machine # [ 8.582353] systemd[1]: Starting Home Area Manager... machine # connecting to host... machine # [ 8.529779] ACPI: button: Power Button [PWRF] machine # [ 8.586344] rtc_cmos 00:04: RTC can wake from S4 machine # [ 8.604263] rtc_cmos 00:04: registered as rtc0 machine # [ 8.610846] systemd[1]: Finished Post-Boot Actions. machine # [ 8.615738] nsncd[520]: Sep 06 18:03:29.650 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.627717] systemd[1]: Started Name Service Cache Daemon (nsncd). machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine # [ 8.604322] rtc_cmos 00:04: setting system clock to 2026-09-06T18:03:29 UTC (1788717809) sshClient # [ 8.694608] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 8.654061] systemd[1]: Reached target Host and Network Name Lookups. machine: (connecting took 9.29 seconds) machine: (finished: waiting for the VM to finish booting, in 9.29 seconds) machine # [ 8.655665] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 8.699706] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 8.667689] systemd[1]: Starting D-Bus System Message Bus... machine # [ 8.700338] systemd[1]: Starting User Login Management... machine # [ 8.604396] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 8.624655] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 8.725468] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 8.762985] systemd-homed[529]: Watching /home. sshClient # [ 8.678154] systemd[1]: Finished register-nix-paths.service. sshClient # [ 8.680464] systemd[1]: Reached target System Initialization. sshClient # [ 8.684069] systemd[1]: Started Discard unused filesystem blocks once a week. sshClient # [ 8.685118] systemd[1]: Started Daily Cleanup of Temporary Directories. sshClient # [ 8.685971] systemd[1]: Reached target Timer Units. sshClient # [ 8.686824] systemd[1]: Listening on D-Bus System Message Bus Socket. sshClient # [ 8.688170] systemd[1]: Listening on Nix Daemon Socket. machine # [ 8.624766] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 8.677664] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4 sshClient # [ 8.690220] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. sshClient # [ 8.691348] systemd[1]: Reached target Socket Units. sshClient # [ 8.692051] systemd[1]: Reached target Basic System. sshClient # [ 8.694840] systemd[1]: Started backdoor.service. sshClient # [ 8.699128] systemd[1]: Starting Import lastlog data into lastlog2 database... sshClient # [ 8.703775] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 8.732382] mousedev: PS/2 mouse device common for all mice sshClient # [ 8.732522] ACPI: button: Power Button [PWRF] sshClient # [ 8.807382] rtc_cmos 00:04: RTC can wake from S4 sshClient # [ 8.820535] rtc_cmos 00:04: registered as rtc0 sshClient # [ 8.820600] rtc_cmos 00:04: setting system clock to 2026-09-06T18:03:29 UTC (1788717809) sshClient # [ 8.820676] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs sshClient # [ 8.739730] systemd[1]: Starting Post-Boot Actions... machine # [ 8.710049] Console: switching to colour dummy device 80x25 machine # [ 8.727231] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 8.991674] [drm] Found bochs VGA, ID 0xb0c5. sshClient # [ 8.751089] systemd[1]: Started Reset console on configuration changes. machine # [ 8.991676] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. sshClient # [ 8.766112] systemd[1]: Starting resolvconf update... machine # [ 8.873129] dbus-broker-launch[551]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 8.884710] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 8.885584] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 8.886389] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 8.887193] systemd[1]: Stopping User and Group Name Lookups... machine # [ 8.890607] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 8.895119] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 8.795524] nsncd[514]: Sep 06 18:03:29.614 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.898110] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 8.801283] systemd[1]: Starting Home Area Manager... sshClient # [ 8.804207] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 8.903550] dbus-broker-launch[551]: NSS returned no entry for 'systemd-timesync' machine # [ 8.904682] dbus-broker-launch[551]: Invalid user-name in /nix/store/hp24j6j0ry4x23m51na359cmps2gyhky-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 8.847128] parport_pc 00:02: reported by Plug and Play ACPI sshClient # [ 8.847210] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] sshClient # [ 8.852466] Console: switching to colour dummy device 80x25 sshClient # connecting to host... machine # [ 9.062684] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 9.063353] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 8.935540] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.083396] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic sshClient # [ 8.843335] systemd[1]: Finished Post-Boot Actions. machine # [ 8.956921] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 8.858329] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 8.859223] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.107091] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 sshClient # [ 8.867657] systemd[1]: Starting D-Bus System Message Bus... machine # [ 8.971957] systemd-logind[554]: New seat seat0. machine # [ 8.974974] systemd[1]: Started User Login Management. machine # [ 8.989064] systemd[1]: Starting linger-users.service... sshClient # [ 8.864912] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized sshClient # [ 8.908890] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4 sshClient # [ 8.897919] systemd[1]: Starting User Login Management... sshClient # [ 8.904652] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.016777] dbus-broker-launch[551]: Ready machine # [ 9.029069] systemd[1]: Started Home Area Manager. machine # [ 9.035612] systemd[1]: Finished Home Area Activation. machine # [ 9.039607] nsncd[610]: Sep 06 18:03:30.074 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.048534] systemd[1]: Starting First Boot Home Area Wizard... machine # [ 9.049460] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.051839] systemd[1]: Reached target Host and Network Name Lookups. sshClient # [ 8.955262] systemd-homed[523]: Watching /home. sshClient # [ 9.097941] [drm] Found bochs VGA, ID 0xb0c5. sshClient # [ 9.097944] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 9.201058] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 sshClient # [ 9.101133] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 9.062197] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.204988] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 sshClient # [ 9.126689] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 sshClient # [ 9.177725] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt sshClient # [ 9.179223] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD sshClient # [ 9.045090] systemd[1]: Stopped target Host and Network Name Lookups. sshClient # [ 9.045984] systemd[1]: Stopping Host and Network Name Lookups... sshClient # [ 9.048176] systemd[1]: Stopped target User and Group Name Lookups. sshClient # [ 9.049764] systemd[1]: Stopping User and Group Name Lookups... sshClient # [ 9.051438] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... sshClient # [ 9.054176] systemd[1]: nscd.service: Deactivated successfully. sshClient # [ 9.057470] systemd[1]: Stopped Name Service Cache Daemon (nsncd). sshClient # [ 9.062568] dbus-broker-launch[542]: Looking up NSS user entry for 'systemd-timesync'... sshClient # [ 9.073170] systemd-logind[543]: New seat seat0. sshClient # [ 9.078707] dbus-broker-launch[542]: NSS returned no entry for 'systemd-timesync' sshClient # [ 9.079775] dbus-broker-launch[542]: Invalid user-name in /nix/store/f91lyyhpac5x2ivn9mbs7fzczmgk8lwb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" sshClient # [ 9.087512] systemd[1]: Starting Name Service Cache Daemon (nsncd)... sshClient # [ 9.088450] systemd[1]: Started User Login Management. sshClient # [ 9.101135] systemd[1]: Starting linger-users.service... sshClient # [ 9.128566] systemd[1]: Started D-Bus System Message Bus. sshClient # [ 9.166084] systemd[1]: Started Name Service Cache Daemon (nsncd). sshClient # [ 9.167491] nsncd[593]: Sep 06 18:03:29.983 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" sshClient # [ 9.172518] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.324540] Console: switching to colour frame buffer device 160x50 sshClient # [ 9.173445] systemd[1]: Reached target User and Group Name Lookups. sshClient # [ 9.177808] dbus-broker-launch[542]: Ready sshClient # [ 9.179870] systemd[1]: linger-users.service: Deactivated successfully. sshClient # [ 9.183538] systemd[1]: Finished linger-users.service. sshClient # [ 9.189237] systemd[1]: Started Home Area Manager. sshClient # [ 9.196080] systemd[1]: Finished Home Area Activation. sshClient # [ 9.201223] systemd[1]: Starting First Boot Home Area Wizard... sshClient # [ 9.204223] systemd[1]: Finished resolvconf update. sshClient # [ 9.206870] systemd[1]: Reached target Preparation for Network. sshClient # [ 9.217565] systemd[1]: Starting DHCP Client... sshClient # [ 9.262468] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 9.472952] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 9.087382] systemd[1]: Finished resolvconf update. machine # [ 9.333044] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 9.334144] network-addresses-eth1-start[638]: adding address 192.168.1.1/24... done machine # [ 9.335396] network-addresses-eth1-start[638]: adding address 2001:db8:1::1/64... done machine # [ 9.340817] systemd[1]: Finished linger-users.service. machine # [ 9.343364] systemd[1]: Reached target Preparation for Network. machine # [ 9.347260] systemd[1]: Starting DHCP Client... machine # [ 9.350618] systemd[1]: Starting Address configuration of eth1... machine # [ 9.351860] systemd[1]: Starting Extra networking commands.... machine # [ 9.354810] systemd[1]: Starting Virtual Console Setup... machine # [ 9.372252] systemd[1]: Finished Address configuration of eth1. sshClient # [ 9.262707] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 sshClient # [ 9.363778] Console: switching to colour frame buffer device 160x50 sshClient # [ 9.464803] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device sshClient # [ 9.222233] systemd[1]: Starting Address configuration of eth1... sshClient # [ 9.332418] systemd[1]: Starting Extra networking commands.... sshClient # [ 9.333295] systemd[1]: Starting Virtual Console Setup... machine # [ 9.436238] systemd[1]: Created slice Slice /system/systemd-mute-console. machine # [ 9.443880] systemd[1]: Started Console Output Muting Service (PID 634/UID 0). machine # [ 9.462167] systemd-logind[554]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) sshClient # [ 9.370354] network-addresses-eth1-start[622]: adding address 192.168.1.2/24... done machine # [ 9.475487] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 9.478255] systemd[1]: Stopped Virtual Console Setup. machine # [ 9.485741] systemd-logind[554]: Watching system buttons on /dev/input/event2 (Power Button) sshClient # [ 9.395725] network-addresses-eth1-start[622]: adding address 2001:db8:1::2/64... done machine # [ 9.496161] systemd[1]: Starting Virtual Console Setup... sshClient # [ 9.411572] systemd-logind[543]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 9.512757] dhcpcd[647]: dhcpcd-10.3.2 starting machine # [ 9.518222] systemd[1]: Finished Extra networking commands.. machine # [ 9.531145] dhcpcd[709]: dev: loaded udev sshClient # [ 9.432394] systemd[1]: Finished Address configuration of eth1. sshClient # [ 9.438217] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 9.538762] systemd[1]: Reached target Network. sshClient # [ 9.444218] systemd[1]: Reached target First Boot Complete. sshClient # [ 9.451957] systemd[1]: Starting Save Transient machine-id to Disk... sshClient # [ 9.477692] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 9.481698] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 9.495976] systemd-logind[543]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) sshClient # [ 9.504938] systemd[1]: Starting Virtual Console Setup... sshClient # [ 9.571194] systemd[1]: Finished Save Transient machine-id to Disk. sshClient # [ 9.578065] dhcpcd[632]: dhcpcd-10.3.2 starting sshClient # [ 9.582406] systemd[1]: Finished Extra networking commands.. sshClient # [ 9.583971] systemd[1]: Reached target Network. sshClient # [ 9.588696] systemd[1]: Starting Permit User Sessions... sshClient # [ 9.596179] dhcpcd[695]: dev: loaded udev sshClient # [ 9.752642] ppdev: user-space parallel port driver machine # [ 9.719590] systemd-logind[554]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) sshClient # [ 9.772171] 8021q: 802.1Q VLAN Support v1.8 sshClient # [ 9.775046] 8021q: adding VLAN 0 to HW filter on device eth1 sshClient # [ 9.637093] systemd[1]: Finished Permit User Sessions. sshClient # [ 9.644543] systemd[1]: Started Getty on tty1. sshClient # [ 9.647286] systemd[1]: Reached target Login Prompts. sshClient # [ 9.799949] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) sshClient # [ 9.823579] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) sshClient # [ 9.704973] systemd-logind[543]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 9.813668] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. sshClient # [ 9.750559] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 9.867414] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 9.873152] systemd[1]: Stopped Virtual Console Setup. sshClient # [ 9.780400] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. sshClient # [ 9.782083] systemd[1]: Stopped Virtual Console Setup. machine # [ 9.885819] systemd[1]: Starting Virtual Console Setup... sshClient # [ 9.814321] systemd[1]: Starting Virtual Console Setup... sshClient # [ 9.957870] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine: (finished: waiting for unit systemd-homed.service, in 10.58 seconds) machine: waiting for Please enter user name to create to appear on tty 1 machine # [ 9.958036] dhcpcd[709]: eth0: waiting for carrier machine # [ 9.961989] dhcpcd[709]: libudev: received NULL device machine # [ 9.965781] dhcpcd[709]: libudev: received NULL device machine # [ 9.966615] dhcpcd[709]: eth0: carrier acquired sshClient # [ 10.022582] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' sshClient # [ 10.023238] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 9.983500] dhcpcd[709]: DUID 00:01:00:01:32:30:67:73:52:54:00:12:34:56 sshClient # [ 10.026231] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 9.984830] dhcpcd[709]: eth0: IAID 00:12:34:56 sshClient # [ 10.027064] cfg80211: failed to load regulatory.db machine # [ 9.986383] dhcpcd[709]: eth0: adding address fe80::5054:ff:fe12:3456 sshClient # [ 10.035134] kvm_amd: TSC scaling supported sshClient # [ 10.035862] kvm_amd: Nested Virtualization enabled sshClient # [ 10.036711] kvm_amd: Nested Paging enabled sshClient # [ 10.037172] kvm_amd: LBR virtualization supported sshClient # [ 10.037927] kvm_amd: Virtual VMLOAD VMSAVE supported sshClient # [ 10.038802] kvm_amd: Virtual GIF supported sshClient # [ 10.039274] kvm_amd: Virtual NMI enabled sshClient # [ 10.085233] EDAC MC: Ver: 3.0.0 machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.11 seconds) machine: sending keys 'test-homed-user\n' sshClient # [ 10.104660] 8021q: adding VLAN 0 to HW filter on device eth0 sshClient # [ 9.965508] dhcpcd[695]: eth0: waiting for carrier sshClient # [ 9.966332] dhcpcd[695]: eth0: carrier acquired sshClient # [ 9.976360] dhcpcd[695]: DUID 00:01:00:01:32:30:67:72:52:54:00:12:34:56 sshClient # [ 9.977405] dhcpcd[695]: eth0: IAID 00:12:34:56 sshClient # [ 9.978307] dhcpcd[695]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 10.079288] sshd-keygen-start[577]: Generating public/private rsa key pair. machine # [ 10.081085] sshd-keygen-start[577]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine # [ 10.082345] sshd-keygen-start[577]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine # [ 10.083521] sshd-keygen-start[577]: The key fingerprint is: machine # [ 10.084357] sshd-keygen-start[577]: SHA256:lhr7K7WXFSNlcY12oOM/SXThHQ5ktVxByfUXtxPTGvs root@machine machine # [ 10.086081] sshd-keygen-start[577]: The key's randomart image is: machine # [ 10.087493] sshd-keygen-start[577]: +---[RSA 4096]----+ machine # [ 10.088274] sshd-keygen-start[577]: | o=*&O| machine # [ 10.089105] sshd-keygen-start[577]: | +oOo^| machine # [ 10.090080] sshd-keygen-start[577]: | oo..%=| machine # [ 10.091080] sshd-keygen-start[577]: | ...ooo.o| machine # [ 10.092323] sshd-keygen-start[577]: | . S ..o.. | machine # [ 10.094122] sshd-keygen-start[577]: | =. .o .E| machine # [ 10.095483] sshd-keygen-start[577]: | o. . o + | machine # [ 10.096703] sshd-keygen-start[577]: | ... o . | machine # [ 10.097579] sshd-keygen-start[577]: | .oo | machine # [ 10.098993] sshd-keygen-start[577]: +----[SHA256]-----+ machine # [ 10.142056] sshd-keygen-start[746]: Generating public/private ed25519 key pair. machine # [ 10.144133] sshd-keygen-start[746]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine # [ 10.145263] sshd-keygen-start[746]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine # [ 10.146351] sshd-keygen-start[746]: The key fingerprint is: machine # [ 10.147069] sshd-keygen-start[746]: SHA256:rz9kyut0DAVEAe9pX73J8l9plRVNV04645jcq6+bG0Y root@machine machine # [ 10.148202] sshd-keygen-start[746]: The key's randomart image is: machine # [ 10.149122] sshd-keygen-start[746]: +--[ED25519 256]--+ machine # [ 10.149900] sshd-keygen-start[746]: | .+=. oB| machine # [ 10.151853] sshd-keygen-start[746]: | . . ++| machine # [ 10.152907] sshd-keygen-start[746]: | . . + o| machine # [ 10.154292] sshd-keygen-start[746]: | . o . * oo| machine # [ 10.155168] sshd-keygen-start[746]: | S E +..| machine # [ 10.157141] sshd-keygen-start[746]: | . =oo . +o| machine # [ 10.157864] sshd-keygen-start[746]: | ..+= + =o.| machine # [ 10.158521] sshd-keygen-start[746]: | .oo.. *. .| machine # [ 10.159241] sshd-keygen-start[746]: | .+o..*=o..| machine # [ 10.159959] sshd-keygen-start[746]: +----[SHA256]-----+ machine # [ 10.161690] systemd[1]: sshd-keygen.service: Deactivated successfully. machine # [ 10.163099] systemd[1]: Finished SSH Host Keys Generation. machine # [ 10.168974] systemd[1]: Starting SSH Daemon... machine # [ 10.212986] sshd[749]: Server listening on 0.0.0.0 port 22. machine # [ 10.214665] sshd[749]: Server listening on :: port 22. machine # [ 10.215937] systemd[1]: Started SSH Daemon. machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds) machine: waiting for Please enter new password for user test-homed-user: to appear on tty 1 machine # [ 10.345909] systemd-vconsole-setup[727]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 10.349142] systemd[1]: Finished Virtual Console Setup. sshClient # [ 10.251514] systemd-vconsole-setup[704]: Configuration of first virtual console was skipped, ignoring remaining ones. sshClient # [ 10.255206] systemd[1]: Finished Virtual Console Setup. sshClient # [ 10.328712] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. sshClient # [ 10.421742] dhcpcd[695]: eth0: soliciting a DHCP lease sshClient # [ 10.592360] NET: Registered PF_PACKET protocol family sshClient # [ 10.459805] dhcpcd[695]: eth0: offered 10.0.2.15 from 10.0.2.2 sshClient # [ 10.464341] dhcpcd[695]: eth0: probing address 10.0.2.15/24 machine # [ 10.740198] dhcpcd[709]: eth0: soliciting a DHCP lease machine # [ 10.771907] dhcpcd[709]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 10.775364] dhcpcd[709]: 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.11 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 machine # [ 11.949794] dhcpcd[709]: eth0: soliciting an IPv6 router machine # [ 11.952899] dhcpcd[709]: eth0: Router Advertisement from fe80::2 machine # [ 11.955191] dhcpcd[709]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 11.957396] dhcpcd[709]: eth0: adding route to fec0::/64 machine # [ 11.959343] dhcpcd[709]: eth0: adding default route via fe80::2 sshClient # [ 12.167269] dhcpcd[695]: eth0: soliciting an IPv6 router sshClient # [ 12.170182] dhcpcd[695]: eth0: Router Advertisement from fe80::2 sshClient # [ 12.172290] dhcpcd[695]: eth0: adding address fec0::5054:ff:fe12:3456/64 sshClient # [ 12.174432] dhcpcd[695]: eth0: adding route to fec0::/64 sshClient # [ 12.176302] dhcpcd[695]: eth0: adding default route via fe80::2 machine: (finished: waiting for (repeat) to appear on tty 1, in 1.12 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for unit systemd-homed-firstboot.service machine # [ 15.248361] dhcpcd[709]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 15.249300] dhcpcd[709]: eth0: adding route to 10.0.2.0/24 machine # [ 15.250022] dhcpcd[709]: eth0: adding default route via 10.0.2.2 machine # [ 15.343540] systemd[1]: Started DHCP Client. sshClient # [ 15.370990] dhcpcd[695]: eth0: leased 10.0.2.15 for 86400 seconds sshClient # [ 15.374226] dhcpcd[695]: eth0: adding route to 10.0.2.0/24 sshClient # [ 15.376523] dhcpcd[695]: eth0: adding default route via 10.0.2.2 sshClient # [ 15.500888] systemd[1]: Started DHCP Client. sshClient # [ 15.502654] systemd[1]: Reached target Multi-User System. sshClient # [ 15.503670] systemd[1]: Startup finished in 835ms (kernel) + 4.094s (initrd) + 10.574s (userspace) = 15.503s. machine # [ 22.809634] systemd-homed[529]: Generating key pair for signing local user identity records. machine # [ 22.812789] systemd-homed[529]: Successfully created Ed25519 key pair. machine # [ 22.835619] systemd-homed[529]: test-homed-user: changing state absent → creating machine # [ 22.907465] systemd-homework[881]: Using automatic default storage of 'luks'. machine # [ 22.914962] systemd-homework[881]: Sizing home to 83% of available disk space, which is 737M. machine # [ 22.917539] systemd-homework[881]: Allocating image file completed. machine # [ 22.938062] systemd-homework[881]: Writing of partition table completed. machine # [ 22.941072] systemd-homework[881]: Setting up loopback device /dev/loop0 completed. machine # [ 24.308876] systemd-homework[881]: LUKS formatting completed. machine # [ 25.989445] systemd-homework[881]: Writing password to LUKS keyslot 0 completed. machine # [ 26.242895] systemd-homework[881]: LUKS activation by volume key succeeded. machine # [ 26.258590] systemd-homework[881]: Writing user record as LUKS token completed. machine # [ 26.259709] systemd-homework[881]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 26.261317] systemd-homework[881]: Formatting /dev/mapper/home-test-homed-user as btrfs machine # [ 26.646349] systemd[1]: proc-917-mounts.mount: Deactivated successfully. machine # [ 26.647936] systemd-homework[881]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 06a00fbf-4222-4c55-abbd-ea41cc49c2ac) machine # [ 26.649661] systemd-homework[881]: Formatting file system completed. machine # [ 26.691979] systemd-homework[881]: Mounting file system completed. machine # [ 26.694105] systemd-homework[881]: Skeleton directory /etc/skel missing, ignoring. machine # [ 26.695452] systemd-homework[881]: Wrote embedded .identity file. machine # [ 26.696362] systemd-homework[881]: Replaced embedded blob dir with contents of system blob dir. machine # [ 26.697863] systemd-homework[881]: Recursive changing of ownership completed. machine # [ 26.699694] systemd-homework[881]: Changed top-level directory access mode to 0700. machine # [ 26.705088] systemd-homework[881]: Synchronized disk. machine # [ 26.706496] systemd-homework[881]: Discarded unused 646.4M. machine # [ 26.717340] systemd-homed[529]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 26.725455] systemd-homed[529]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 26.744493] systemd-homework[881]: Moved image file into place. machine # [ 26.745683] systemd-homework[881]: Creation completed. machine # [ 26.746611] systemd-homed[529]: block device /sys/devices/virtual/block/loop0 has been removed. machine # [ 26.748112] systemd-homework[881]: Image size is 737M, file system size is 719M, file system payload size is 719M, file system free is 712.9M. machine # [ 26.757766] systemd-homed[529]: test-homed-user: changing state creating → inactive machine # [ 26.765297] systemd[1]: systemd-mute-console@0-1-634_635-0.service: Deactivated successfully. machine # [ 26.768227] systemd[1]: Finished First Boot Home Area Wizard. machine # [ 26.772092] systemd[1]: Reached target First Boot Complete. machine # [ 26.775262] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 26.778497] systemd[1]: Starting Permit User Sessions... machine # [ 26.810553] systemd[1]: Finished Permit User Sessions. machine # [ 26.815835] systemd[1]: Started Getty on tty1. machine # [ 26.817353] systemd[1]: Reached target Login Prompts. machine # [ 26.818774] systemd[1]: Reached target Multi-User System. machine # [ 26.829269] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 26.831675] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 26.833813] systemd[1]: Startup finished in 837ms (kernel) + 3.989s (initrd) + 22.005s (userspace) = 26.833s. machine: (finished: waiting for unit systemd-homed-firstboot.service, in 14.94 seconds) (finished: subtest: create systemd-homed user on first boot prompt, in 28.24 seconds) machine: must succeed: homectl update test-homed-user --offline -G wheel machine # [ 27.635252] systemd-homed[529]: test-homed-user: changing state inactive → updating machine # [ 27.640098] systemd-homework[963]: Offline update requested. Not touching embedded records. machine # [ 27.642233] systemd-homed[529]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 27.647485] systemd-homed[529]: test-homed-user: changing state updating → inactive machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.05 seconds) subtest: login as homed user machine: waiting for login: to appear on tty 1 machine: (finished: waiting for login: to appear on tty 1, in 0.04 seconds) machine: sending keys 'test-homed-user\n' machine: (finished: sending keys 'test-homed-user\n', in 0.16 seconds) machine: waiting for Password: to appear on tty 1 machine # [ 28.080291] systemd-homed[529]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 28.089909] systemd-homework[978]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 28.097435] systemd-homed[529]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 28.099241] systemd-homed[529]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 28.100595] systemd-homed[529]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 28.101990] systemd-homed[529]: 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 # [ 29.187218] systemd-homed[529]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 29.212973] systemd-homework[990]: Provided password unlocks user record. machine # [ 29.222630] systemd-homework[990]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 29.228069] systemd-homework[990]: Allocated additional 646.4M. machine # [ 29.372923] loop1: detected capacity change from 0 to 1505280 machine # [ 29.232329] systemd-homework[990]: Setting up loopback device /dev/loop1 completed. machine # [ 30.054154] systemd-homework[990]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 30.077406] systemd-homework[990]: Provided password unlocks user record. machine # [ 30.082458] systemd-homework[990]: Probing file system completed (found btrfs). machine # [ 30.088049] systemd-homework[990]: No fsck for file system btrfs installed, ignoring. machine # [ 30.232097] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (254:0) scanned by systemd-homewor (990) machine # [ 30.234259] BTRFS info (device dm-0): first mount of filesystem 06a00fbf-4222-4c55-abbd-ea41cc49c2ac machine # [ 30.235575] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 30.239826] BTRFS info (device dm-0): enabling free space tree machine # [ 30.240483] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 30.099650] systemd-homework[990]: Mounting file system completed. machine # [ 30.102252] systemd-homework[990]: Discovered used loopback device /dev/loop1. machine # [ 30.103274] systemd-homework[990]: offset = 1048576, size = 770703360, image = 772800512 machine # [ 30.113903] systemd-homework[990]: Ready to resize image size 737M → 888M, partition size 735M → 886M, file system size 719M → 870M. machine # [ 30.115756] systemd-homework[990]: Growing of image file from 737M to 888M completed. machine # [ 30.260720] loop1: detected capacity change from 1505280 to 1814528 machine # [ 30.120924] systemd-homework[990]: Refreshing loop device size completed. machine # [ 30.138366] systemd-homework[990]: Growing of partition completed. machine # [ 30.309258] async_tx: api initialized (async) machine # [ 30.215340] systemd-homework[990]: LUKS device growing completed. machine # [ 30.362458] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 753926144 to 912261120 machine # [ 30.222155] systemd-homework[990]: File system resizing from 719M to 870M completed. machine # [ 30.223743] systemd-homework[990]: Synchronized disk. machine # [ 30.224506] systemd-homework[990]: Synchronized disk. machine # [ 30.225337] systemd-homework[990]: Resizing completed. machine # [ 30.227130] systemd-homework[990]: Image size is 888M, file system size is 870M, file system payload size is 870M, file system free is 863.9M. machine # [ 30.229073] systemd-homework[990]: Read embedded .identity file. machine # [ 30.258129] systemd-homework[990]: Provided password unlocks user record. machine # [ 30.259558] systemd-homework[990]: Reconciling header user identity completed (host version was newer). machine # [ 30.260772] systemd-homework[990]: Reconciling embedded user identity completed (host version was newer). machine # [ 30.277276] systemd-homework[990]: Wrote LUKS header user record. machine # [ 30.278564] systemd-homework[990]: Wrote embedded .identity file. machine # [ 30.279391] systemd-homework[990]: Replaced embedded blob dir with contents of system blob dir. machine # [ 30.280667] systemd-homework[990]: Recursive changing of ownership not necessary, skipped. machine # [ 30.286069] systemd-homework[990]: Synchronized disk. machine # [ 30.288249] systemd-homework[990]: Moving to final mount point /home/test-homed-user completed. machine # [ 30.310304] systemd-homework[990]: Activation completed. machine # [ 30.311828] systemd-homework[990]: Image size is 886M, file system size is 870M, file system payload size is 870M, file system free is 863.9M. machine # [ 30.322973] systemd-homed[529]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 30.329439] login[954]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired. machine # [ 30.331427] systemd-homed[529]: test-homed-user: changing state activating-for-acquire → active machine # [ 30.338687] systemd-homed[529]: test-homed-user: changing state active → resizing-while-active machine # [ 30.348471] systemd-homework[1034]: Successfully acquired home volume key from kernel keyring. machine # [ 30.349856] systemd-homework[1034]: LUKS volume key from keyring unlocks user record. machine # [ 30.408286] login[954]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0) machine # [ 30.441251] systemd-homework[1034]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 30.453064] systemd[1]: Created slice Slice /user/60388. machine # [ 30.456729] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 30.472575] systemd-logind[554]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'. machine # [ 30.519053] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 30.535146] systemd[1]: Starting User Manager for UID 60388... machine # [ 30.578596] (systemd)[1039]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 30.629232] (systemd)[1039]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 30.635801] systemd-logind[554]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 30.675955] systemd[1039]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.730910] systemd-homework[1034]: Successfully re-activated LUKS device. machine # [ 30.733656] systemd-homework[1034]: LUKS volume key from keyring unlocks user record. machine # [ 30.735781] systemd-homework[1034]: Discovered used loopback device /dev/loop1. machine # [ 30.736804] systemd-homework[1034]: Read embedded .identity file. machine # [ 30.737655] systemd-homework[1034]: LUKS volume key from keyring unlocks user record. machine # [ 30.738701] systemd-homework[1034]: Reconciling header user identity completed (host version was newer). machine # [ 30.739895] systemd-homework[1034]: Reconciling embedded user identity completed (host version was newer). machine # [ 30.741468] systemd-homework[1034]: offset = 1048576, size = 929038336, image = 931135488 machine # [ 30.755476] systemd-homework[1034]: Ready to resize image size 888M → 758M, partition size 886M → 756M, file system size 870M → 740M. machine # [ 30.758441] systemd-homework[1034]: Wrote embedded .identity file. machine # [ 30.759404] systemd-homework[1034]: Replaced embedded blob dir with contents of system blob dir. machine # [ 30.760799] systemd-homework[1034]: Backing file is fully allocated already. machine # [ 30.906489] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 912261120 to 775946240 machine # [ 30.766834] systemd-homework[1034]: File system resizing from 870M to 740M completed. machine # [ 30.771827] systemd-homework[1034]: Synchronized disk. machine # [ 30.814737] systemd-homework[1034]: LUKS device shrinking completed. machine # [ 30.959782] loop1: detected capacity change from 1814528 to 1548288 machine # [ 30.820867] systemd-homework[1034]: Refreshing loop device size completed. machine # [ 30.822179] systemd-homework[1034]: Shrinking of image file completed. machine # [ 30.842473] systemd-homework[1034]: Shrinking of partition completed. machine # [ 30.859065] systemd-homework[1034]: Wrote LUKS header user record. machine # [ 30.859976] systemd-homework[1034]: Synchronized disk. machine # [ 30.862760] systemd-homework[1034]: Resizing completed. machine # [ 30.863859] systemd-homework[1034]: Image size is 758M, file system size is 740M, file system payload size is 740M, file system free is 733.9M. machine # [ 30.874044] systemd-homed[529]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 30.881712] systemd-homed[529]: test-homed-user: changing state resizing-while-active → active machine # [ 30.884958] systemd-homed[529]: Rebalancing complete. machine # [ 30.938744] systemd[1039]: Queued start job for default target Main User Target. machine # [ 30.941368] systemd[1039]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.948626] systemd[1039]: Created slice User Application Slice. machine # [ 30.949714] systemd[1039]: Started Daily Cleanup of User's Temporary Directories. machine # [ 30.951396] systemd[1039]: Reached target Paths. machine # [ 30.952169] systemd[1039]: Reached target Timers. machine # [ 30.955871] systemd[1039]: Starting D-Bus User Message Bus Socket... machine # [ 30.958069] systemd[1039]: Starting Create User Files and Directories... machine # [ 30.990097] systemd[1039]: Finished Create User Files and Directories. machine # [ 31.033952] systemd[1039]: Listening on D-Bus User Message Bus Socket. machine # [ 31.035345] systemd[1039]: Reached target Sockets. machine # [ 31.037054] systemd[1039]: Reached target Basic System. machine # [ 31.039064] systemd[1]: Started User Manager for UID 60388. machine # [ 31.041740] systemd[1039]: Starting Run user-specific NixOS activation... machine # [ 31.043606] systemd[1]: Started Session 1 of User test-homed-user. machine # [ 31.087332] systemd[1039]: Finished Run user-specific NixOS activation. machine # [ 31.088649] systemd[1039]: Reached target Main User Target. machine # [ 31.090522] systemd[1039]: Startup finished in 427ms. machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 2.22 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.07 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 4.96 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.14 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 # [ 34.124046] login[700]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0) sshClient # [ 34.159297] systemd[1]: Created slice Slice /user/1000. sshClient # [ 34.162404] systemd[1]: Starting User Runtime Directory /run/user/1000... sshClient # [ 34.173725] systemd-logind[543]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'. sshClient # [ 34.213617] systemd[1]: Finished User Runtime Directory /run/user/1000. sshClient # [ 34.224715] systemd[1]: Starting User Manager for UID 1000... sshClient # [ 34.264085] (systemd)[822]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0) sshClient # [ 34.269962] systemd-logind[543]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'. sshClient # [ 34.305933] systemd[822]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 34.483769] systemd[822]: Queued start job for default target Main User Target. sshClient # [ 34.486844] systemd[822]: Failed to adjust io pressure threshold, ignoring: Device or resource busy sshClient # [ 34.489169] systemd[822]: Created slice User Application Slice. sshClient # [ 34.490669] systemd[822]: Started Daily Cleanup of User's Temporary Directories. sshClient # [ 34.491707] systemd[822]: Reached target Paths. sshClient # [ 34.492443] systemd[822]: Reached target Timers. sshClient # [ 34.495817] systemd[822]: Starting D-Bus User Message Bus Socket... sshClient # [ 34.497910] systemd[822]: Starting Create User Files and Directories... sshClient # [ 34.534927] systemd[822]: Finished Create User Files and Directories. sshClient # [ 34.574071] systemd[822]: Listening on D-Bus User Message Bus Socket. sshClient # [ 34.574981] systemd[822]: Reached target Sockets. sshClient # [ 34.575646] systemd[822]: Reached target Basic System. sshClient # [ 34.577066] systemd[1]: Started User Manager for UID 1000. sshClient # [ 34.580714] systemd[1]: Started Session 1 of User test-normal-user. sshClient # [ 34.582485] systemd[822]: Starting Run user-specific NixOS activation... sshClient # [ 34.627569] systemd[822]: Finished Run user-specific NixOS activation. sshClient # [ 34.628813] systemd[822]: Reached target Main User Target. sshClient # [ 34.630232] systemd[822]: Startup finished in 336ms. sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.14 seconds) sshClient: sending keys 'whoami > /tmp/1\n' sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds) sshClient: waiting for file '/tmp/1' sshClient: (finished: waiting for file '/tmp/1', in 1.08 seconds) sshClient: must succeed: cat /tmp/1 sshClient: (finished: must succeed: cat /tmp/1, in 0.04 seconds) (finished: subtest: login as regular user, in 3.94 seconds) subtest: add homed ssh authorized key sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n' sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.49 seconds) sshClient: waiting for file '/tmp/id_ed25519.pub' sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.06 seconds) sshClient: must succeed: cat /tmp/id_ed25519.pub sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.03 seconds) machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIMThWzC48kHbNNc9OfBgwH5gXTnS37d4wAkQ2Ya84eaJ test-normal-user@sshClient' machine # [ 38.152598] systemd-homed[529]: test-homed-user: changing state active → updating-while-active machine # [ 38.160164] systemd-homework[1088]: Offline update requested. Not touching embedded records. machine # [ 38.166899] systemd-homed[529]: test-homed-user: changing state updating-while-active → active machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIMThWzC48kHbNNc9OfBgwH5gXTnS37d4wAkQ2Ya84eaJ test-normal-user@sshClient', in 0.05 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.65 seconds) subtest: change homed user password machine: sending keys 'passwd; echo $? > /tmp/3\n' machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.26 seconds) machine: waiting for New password: to appear on tty 1 machine: (finished: waiting for New password: to appear on tty 1, in 1.12 seconds) machine: sending keys 'barfoobar\n' machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds) machine: waiting for Retype new password: to appear on tty 1 machine: (finished: waiting for Retype new password: to appear on tty 1, in 1.14 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/wqwvv2w9ih8r1f0lsa0h3fqf91nblq7g-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 573 machine: must succeed: sleep 4.0 machine # [ 41.051763] systemd-homed[529]: test-homed-user: changing state active → passwd-while-active machine # [ 41.747682] systemd-homework[1127]: No valid password for LUKS superblock. machine # [ 44.068738] systemd-homed[529]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 44.073637] systemd-homed[529]: test-homed-user: changing state passwd-while-active → active machine: (finished: must succeed: sleep 4.0, in 4.04 seconds) machine: sending keys 'foobarfoo\n' machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds) machine: waiting for file '/tmp/3' machine # [ 45.195956] systemd-homed[529]: test-homed-user: changing state active → passwd-while-active machine # [ 46.485397] systemd-homework[1133]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 46.612059] systemd-homework[1133]: Successfully re-activated LUKS device. machine # [ 46.650459] systemd-homework[1133]: Provided password unlocks user record. machine # [ 46.651843] systemd-homework[1133]: Discovered used loopback device /dev/loop1. machine # [ 46.654112] systemd-homework[1133]: Read embedded .identity file. machine # [ 46.688955] systemd-homework[1133]: Provided password unlocks user record. machine # [ 46.689942] systemd-homework[1133]: Reconciling header user identity completed (host version was newer). machine # [ 46.691149] systemd-homework[1133]: Reconciling embedded user identity completed (host version was newer). machine # [ 49.639944] systemd-homework[1133]: Added LUKS key slot 1. machine # [ 49.667672] systemd-homework[1133]: Destroyed LUKS key slot 0. machine # [ 49.677049] systemd-homework[1133]: Wrote LUKS header user record. machine # [ 49.678277] systemd-homework[1133]: Wrote embedded .identity file. machine # [ 49.679285] systemd-homework[1133]: Replaced embedded blob dir with contents of system blob dir. machine # [ 49.685476] systemd-homework[1133]: Synchronized disk. machine # [ 49.691115] systemd-homework[1133]: Everything completed. machine # [ 49.701846] systemd-homed[529]: test-homed-user: changing state passwd-while-active → active machine # [ 49.703207] passwd[1102]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user. machine: (finished: waiting for file '/tmp/3', in 5.19 seconds) machine: must succeed: cat /tmp/3 machine: (finished: must succeed: cat /tmp/3, in 0.02 seconds) (finished: subtest: change homed user password, in 12.08 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 # [ 50.771690] systemd-homed[529]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 50.777423] systemd-homework[1170]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 50.782630] systemd-homed[529]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 50.784472] systemd-homed[529]: 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.13 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.04 seconds) machine: waiting for success: grep uid=0 /tmp/4 machine # [ 51.816397] systemd-homed[529]: test-homed-user: changing state active → authenticating-for-acquire machine # [ 51.844886] systemd-homework[1186]: Provided password unlocks user record. machine # [ 52.575119] systemd-homework[1186]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 52.704996] systemd-homework[1186]: Successfully re-activated LUKS device. machine # [ 52.729065] systemd-homework[1186]: Provided password unlocks user record. machine # [ 52.730748] systemd-homework[1186]: Discovered used loopback device /dev/loop1. machine # [ 52.731807] systemd-homework[1186]: Read embedded .identity file. machine # [ 52.753113] systemd-homework[1186]: Provided password unlocks user record. machine # [ 52.754488] systemd-homework[1186]: Reconciling user identities completed (host and header version were identical). machine # [ 52.755894] systemd-homework[1186]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 52.820437] systemd-homework[1186]: Everything completed. machine # [ 52.828069] systemd-homed[529]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 52.861768] sudo[1168]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired. machine # [ 52.864299] systemd-homed[529]: test-homed-user: changing state authenticating-for-acquire → active machine # [ 52.874554] sudo[1168]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id machine # [ 52.901445] sudo[1168]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388) machine # [ 52.919326] sudo[1168]: pam_unix(sudo:session): session closed for user root machine: (finished: waiting for success: grep uid=0 /tmp/4, in 2.15 seconds) (finished: subtest: escalate to root from homed user, in 3.65 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 # [ 54.017316] login[954]: pam_unix(login:session): session closed for user test-homed-user machine # [ 54.022258] login[954]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 54.035861] systemd[1]: getty@tty1.service: Deactivated successfully. machine # [ 54.050352] systemd[1]: session-1.scope: Deactivated successfully. machine # [ 54.058721] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. machine # [ 54.060158] systemd-logind[554]: Session 1 logged out. Waiting for processes to exit. machine # [ 54.064062] systemd[1]: Started Getty on tty1. machine # [ 54.066761] systemd-logind[554]: Removed session 1. machine # [ 64.192934] systemd[1]: Stopping User Manager for UID 60388... machine # [ 64.204238] systemd[1039]: Activating special unit Exit the Session... machine # [ 64.206542] systemd[1039]: Stopped target Main User Target. machine # [ 64.210877] systemd[1039]: Stopped Run user-specific NixOS activation. machine # [ 64.214475] systemd[1039]: Stopped target Basic System. machine # [ 64.217647] systemd[1039]: Stopped target Paths. machine # [ 64.219682] systemd[1039]: Stopped target Sockets. machine # [ 64.223751] systemd[1039]: Stopped target Timers. machine # [ 64.227629] systemd[1039]: Stopped Daily Cleanup of User's Temporary Directories. machine # [ 64.230655] systemd[1039]: Closed D-Bus User Message Bus Socket. machine # [ 64.235278] systemd[1039]: Stopped Create User Files and Directories. machine # [ 64.238168] systemd[1039]: Removed slice User Application Slice. machine # [ 64.240402] systemd[1039]: Reached target Shutdown. machine # [ 64.242461] systemd[1039]: Finished Exit the Session. machine # [ 64.250150] systemd[1039]: Reached target Exit the Session. machine # [ 64.253997] systemd-logind[554]: Removed session 2. machine # [ 64.258365] systemd-homed[529]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 64.263360] systemd-homed[529]: test-homed-user: changing state active → deactivating machine # [ 64.265056] systemd[1]: user@60388.service: Deactivated successfully. machine # [ 64.266069] systemd[1]: Stopped User Manager for UID 60388. machine # [ 64.272623] systemd-homework[1269]: Successfully acquired home volume key from kernel keyring. machine # [ 64.275152] systemd-homework[1269]: Discarded unused 668.1M. machine # [ 64.282479] systemd-homework[1269]: Syncing completed. machine # [ 64.284928] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully. machine # [ 64.294645] systemd[1]: Stopping User Runtime Directory /run/user/60388... machine # [ 64.339253] systemd[1]: run-user-60388.mount: Deactivated successfully. machine # [ 64.341565] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully. machine # [ 64.345351] systemd[1]: Stopped User Runtime Directory /run/user/60388. machine # [ 64.353733] systemd-homework[1269]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password. machine # [ 64.482266] systemd-homework[1269]: Successfully re-activated LUKS device. machine # [ 64.484200] systemd-homework[1269]: Discovered used loopback device /dev/loop1. machine # [ 64.485228] systemd-homework[1269]: offset = 1048576, size = 792723456, image = 794820608 machine # [ 64.502077] systemd-homework[1269]: Ready to resize image size 758M → 274M, partition size 756M → 272M, file system size 740M → 256M. machine # [ 64.503932] systemd-homework[1269]: Allocated additional 668.1M. machine # [ 64.650959] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 775946240 to 268435456 machine # [ 64.510537] systemd-homework[1269]: File system resizing from 740M to 256M completed. machine # [ 64.512460] systemd-homework[1269]: Synchronized disk. machine # [ 64.661438] systemd-homework[1269]: LUKS device shrinking completed. machine # [ 64.806559] loop1: detected capacity change from 1548288 to 557056 machine # [ 64.666659] systemd-homework[1269]: Refreshing loop device size completed. machine # [ 64.668183] systemd-homework[1269]: Shrinking of image file completed. machine # [ 64.683212] systemd-homework[1269]: Shrinking of partition completed. machine # [ 64.826000] BTRFS info (device dm-0): last unmount of filesystem 06a00fbf-4222-4c55-abbd-ea41cc49c2ac machine # [ 64.685316] systemd-homework[1269]: Synchronized disk. machine # [ 64.686033] systemd-homework[1269]: Resizing completed. machine # [ 64.686686] systemd-homework[1269]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M. machine # [ 64.690142] systemd-homework[1269]: Unmounting completed. machine # [ 64.690900] systemd-homework[1269]: Discovered used LUKS device /dev/mapper/home-test-homed-user. machine # [ 64.693608] systemd-homework[1269]: Device home-test-homed-user is not active. machine # [ 64.703719] systemd-homed[529]: block device /sys/devices/virtual/block/dm-0 has been removed. machine # [ 64.708287] systemd-homework[1269]: Everything completed. machine # [ 64.717197] systemd-homed[529]: test-homed-user: changing state deactivating → inactive machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.72 seconds) (finished: subtest: log out and deactivate homed user's home area, in 11.78 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 # [ 67.596939] systemd-homed[529]: test-homed-user: changing state inactive → activating-for-acquire machine # [ 67.605901] systemd-homework[1302]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 67.614776] systemd-homed[529]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 67.618691] systemd-homed[529]: test-homed-user: changing state activating-for-acquire → inactive machine # [ 67.621438] systemd-homed[529]: Got notification that all sessions of user test-homed-user ended, deactivating automatically. machine # [ 67.625190] sshd-session[1298]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error machine # [ 67.628172] systemd-homed[529]: Home test-homed-user already deactivated, no automatic deactivation needed. machine # [ 67.629579] sshd-session[1298]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation. machine # [ 67.631314] sshd-session[1298]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 49998 ssh2: ED25519 SHA256:NzMDVi7gX3RSjF5+PmbI5sesN51qHwY7hax7UNdHPuc machine # [ 67.674173] sshd-session[1298]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 67.689234] systemd-logind[554]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'. machine # [ 67.693988] systemd[1]: Starting User Runtime Directory /run/user/60388... machine # [ 67.725072] systemd[1]: Finished User Runtime Directory /run/user/60388. machine # [ 67.730456] systemd[1]: Started Session 3 of User test-homed-user. machine # [ 67.748230] sshd-session[1298]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 67.751184] sshd-session[1298]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory machine # [ 67.769119] systemd-homed[529]: test-homed-user: changing state inactive → activating machine # [ 67.774251] systemd-homework[1308]: None of the supplied plaintext passwords unlock the user record's hashed passwords. machine # [ 67.780812] systemd-homed[529]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication. machine # [ 67.782704] systemd-homed[529]: test-homed-user: changing state activating → inactive sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.20 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 # [ 68.908949] systemd-homed[529]: test-homed-user: changing state inactive → activating machine # [ 68.954231] systemd-homework[1313]: Provided password unlocks user record. machine # [ 68.960955] systemd-homework[1313]: Successfully locked image file '/home/test-homed-user.home'. machine # [ 68.964982] systemd-homework[1313]: Backing file is fully allocated already. machine # [ 69.109202] loop1: detected capacity change from 0 to 557056 machine # [ 68.968712] systemd-homework[1313]: Setting up loopback device /dev/loop1 completed. machine # [ 69.834897] systemd-homework[1313]: Setting up LUKS device /dev/mapper/home-test-homed-user completed. machine # [ 69.860044] systemd-homework[1313]: Provided password unlocks user record. machine # [ 69.866142] systemd-homework[1313]: Probing file system completed (found btrfs). machine # [ 69.868270] systemd-homework[1313]: No fsck for file system btrfs installed, ignoring. machine # [ 70.012503] BTRFS: device label test-homed-user devid 1 transid 18 /dev/mapper/home-test-homed-user (254:0) scanned by systemd-homewor (1313) machine # [ 70.014764] BTRFS info (device dm-0): first mount of filesystem 06a00fbf-4222-4c55-abbd-ea41cc49c2ac machine # [ 70.016377] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm machine # [ 70.021332] BTRFS info (device dm-0): enabling free space tree machine # [ 70.022045] BTRFS info (device dm-0): use zstd compression, level 1 machine # [ 69.881451] systemd-homework[1313]: Mounting file system completed. machine # [ 69.883645] systemd-homework[1313]: Discovered used loopback device /dev/loop1. machine # [ 69.884670] systemd-homework[1313]: offset = 1048576, size = 285212672, image = 287309824 machine # [ 69.897401] systemd-homework[1313]: Ready to resize image size 274M → 758M, partition size 272M → 756M, file system size 256M → 740M. machine # [ 69.899470] systemd-homework[1313]: Growing of image file from 274M to 758M completed. machine # [ 70.043687] loop1: detected capacity change from 557056 to 1548288 machine # [ 69.903671] systemd-homework[1313]: Refreshing loop device size completed. machine # [ 69.919563] systemd-homework[1313]: Growing of partition completed. machine # [ 69.960131] systemd-homework[1313]: LUKS device growing completed. machine # [ 70.108117] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 775946240 machine # [ 69.967645] systemd-homework[1313]: File system resizing from 256M to 740M completed. machine # [ 69.969055] systemd-homework[1313]: Synchronized disk. machine # [ 69.969747] systemd-homework[1313]: Synchronized disk. machine # [ 69.971090] systemd-homework[1313]: Resizing completed. machine # [ 69.971766] systemd-homework[1313]: Image size is 758M, file system size is 740M, file system payload size is 740M, file system free is 733.9M. machine # [ 69.973334] systemd-homework[1313]: Read embedded .identity file. machine # [ 69.991986] systemd-homework[1313]: Provided password unlocks user record. machine # [ 69.993615] systemd-homework[1313]: Reconciling user identities completed (host and header version were identical). machine # [ 69.994916] systemd-homework[1313]: Reconciling embedded user identity completed (host and embedded version were identical). machine # [ 69.996912] systemd-homework[1313]: Recursive changing of ownership not necessary, skipped. machine # [ 69.997979] systemd-homework[1313]: Synchronized disk. machine # [ 69.999371] systemd-homework[1313]: Moving to final mount point /home/test-homed-user completed. machine # [ 70.022241] systemd-homework[1313]: Activation completed. machine # [ 70.023226] systemd-homework[1313]: Image size is 756M, file system size is 740M, file system payload size is 740M, file system free is 733.9M. machine # [ 70.034291] systemd-homed[529]: Home test-homed-user is signed exclusively by our key, accepting. machine # [ 70.041777] systemd-homed[529]: test-homed-user: changing state activating → active machine # [ 70.055385] systemd[1]: Starting User Manager for UID 60388... machine # [ 70.083893] (systemd)[1357]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired. machine # [ 70.127331] (systemd)[1357]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0) machine # [ 70.132959] systemd-logind[554]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'. machine # [ 70.165547] systemd[1357]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.311059] systemd[1357]: Queued start job for default target Main User Target. machine # [ 70.314693] systemd[1357]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.317215] systemd[1357]: Created slice User Application Slice. machine # [ 70.319118] systemd[1357]: Started Daily Cleanup of User's Temporary Directories. machine # [ 70.320143] systemd[1357]: Reached target Paths. machine # [ 70.320776] systemd[1357]: Reached target Timers. machine # [ 70.323749] systemd[1357]: Starting D-Bus User Message Bus Socket... machine # [ 70.325740] systemd[1357]: Starting Create User Files and Directories... machine # [ 70.358969] systemd[1357]: Finished Create User Files and Directories. machine # [ 70.361891] systemd[1357]: Listening on D-Bus User Message Bus Socket. machine # [ 70.363134] systemd[1357]: Reached target Sockets. machine # [ 70.363776] systemd[1357]: Reached target Basic System. machine # [ 70.364888] systemd[1]: Started User Manager for UID 60388. machine # [ 70.368793] systemd[1357]: Starting Run user-specific NixOS activation... machine # [ 70.410771] systemd[1357]: Finished Run user-specific NixOS activation. machine # [ 70.411968] systemd[1357]: Reached target Main User Target. machine # [ 70.412985] systemd[1357]: Startup finished in 258ms. machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.18 seconds) sshClient: sending keys 'whoami > /tmp/5\n' sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.16 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 # [ 72.329628] sshd-session[1305]: Received disconnect from 2001:db8:1::2 port 49998:11: disconnected by user sshClient: (finished: sending keys 'exit\n', in 0.05 seconds) (finished: subtest: ssh as homed user, in 6.63 seconds) (finished: run the VM test script, in 73.37 seconds) machine # [ 72.335399] sshd-session[1305]: Disconnected from user test-homed-user 2001:db8:1::2 port 49998 machine # [ 72.341632] sshd-session[1298]: pam_unix(sshd:session): session closed for user test-homed-user machine # [ 72.350568] sshd-session[1298]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used. machine # [ 72.360460] systemd[1]: session-3.scope: Deactivated successfully. machine # [ 72.367581] systemd-logind[554]: Session 3 logged out. Waiting for processes to exit. machine # [ 72.371446] systemd-logind[554]: Removed session 3. sshClient # [ 72.320575] login[700]: pam_unix(login:session): session closed for user test-normal-user sshClient # [ 72.333730] systemd[1]: getty@tty1.service: Deactivated successfully. sshClient # [ 72.354728] systemd[1]: session-1.scope: Deactivated successfully. sshClient # [ 72.358862] systemd[1]: session-1.scope: Consumed 117ms CPU time over 37.776s wall clock time, 5.9M memory peak, 12K written to disk, 21.2K incoming IP traffic, 20.4K outgoing IP traffic. sshClient # [ 72.369130] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1. sshClient # [ 72.375884] systemd-logind[543]: Session 1 logged out. Waiting for processes to exit. sshClient # [ 72.379640] systemd[1]: Started Getty on tty1. sshClient # [ 72.384249] systemd-logind[543]: Removed session 1. test script finished in 73.56s 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.32 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