this derivation will be built: /nix/store/69j6c4c2q4gb8vn4lnpvlfmkqkzrlf2k-vm-test-run-test-machine.drv building '/nix/store/69j6c4c2q4gb8vn4lnpvlfmkqkzrlf2k-vm-test-run-test-machine.drv' vm-test-run-test-machine> Machine state will be reset. To keep it, pass --keep-machine-state vm-test-run-test-machine> start all VLans vm-test-run-test-machine> (finished: start all VLans, in 0.00 seconds) vm-test-run-test-machine> Test will time out and terminate in 3600 seconds vm-test-run-test-machine> run the VM test script vm-test-run-test-machine> additionally exposed symbols: vm-test-run-test-machine> test-machine, vm-test-run-test-machine> vlan1, vm-test-run-test-machine> 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 vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.wait_for_unit' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: waiting for unit multi-user.target vm-test-run-test-machine> test-machine: waiting for the VM to finish booting vm-test-run-test-machine> test-machine: starting vm vm-test-run-test-machine> test-machine # Disk image does not exist, creating the virtualisation disk image... vm-test-run-test-machine> test-machine: QEMU running (pid 45) vm-test-run-test-machine> test-machine # Formatting '/build/vm-state-test-machine/tmp.FrC48xy49k', fmt=raw size=1073741824 vm-test-run-test-machine> test-machine # mke2fs 1.47.4 (6-Mar-2025) vm-test-run-test-machine> test-machine # Discarding device blocks: 0/262144 done vm-test-run-test-machine> test-machine # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-test-machine> test-machine # Filesystem UUID: 40bf2337-1d34-4e88-b319-06e73534fcfe vm-test-run-test-machine> test-machine # Superblock backups stored on blocks: vm-test-run-test-machine> test-machine # 32768, 98304, 163840, 229376 vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # Allocating group tables: 0/8 done vm-test-run-test-machine> test-machine # Writing inode tables: 0/8 done vm-test-run-test-machine> test-machine # Creating journal (8192 blocks): done vm-test-run-test-machine> test-machine # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # Virtualisation disk image created. vm-test-run-test-machine> test-machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+BEFD1850+BEF31850 CA00 vm-test-run-test-machine> test-machine # Press Ctrl-B to configure iPXE (PCI 00:03.0)... vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM BEFD1850 BEF31850 CB00 vm-test-run-test-machine> test-machine # Press Ctrl-B to configure iPXE (PCI 00:09.0)... vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # vm-test-run-test-machine> test-machine # Booting from ROM... vm-test-run-test-machine> test-machine # Probing EDD (edd=off to disable)... ok vm-test-run-test-machine> test-machine # [ 0.000000] Linux version 6.18.39 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Jul 18 14:53:38 UTC 2026 vm-test-run-test-machine> test-machine # [ 0.000000] Command line: console=ttyS0,115200 console=tty0 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/s3k4hpp926x7z2x3vvkjiwi6vhd6cnas-nixos-system-test-machine-test/init regInfo=/nix/store/4jarl9chgib60vd76mqhbilfi6zwfsxs-closure-info/registration console=ttyS0,115200n8 console=tty0 vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-provided physical RAM map: vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffcafff] usable vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000bffcb000-0x00000000bfffffff] reserved vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable vm-test-run-test-machine> test-machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved vm-test-run-test-machine> test-machine # [ 0.000000] NX (Execute Disable) protection: active vm-test-run-test-machine> test-machine # [ 0.000000] APIC: Static calls initialized vm-test-run-test-machine> test-machine # [ 0.000000] SMBIOS 2.8 present. vm-test-run-test-machine> test-machine # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 vm-test-run-test-machine> test-machine # [ 0.000000] DMI: Memory slots populated: 1/1 vm-test-run-test-machine> test-machine # [ 0.000000] Hypervisor detected: KVM vm-test-run-test-machine> test-machine # [ 0.000000] last_pfn = 0xbffcb max_arch_pfn = 0x400000000 vm-test-run-test-machine> test-machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 vm-test-run-test-machine> test-machine # [ 0.000000] kvm-clock: using sched offset of 411972256 cycles vm-test-run-test-machine> test-machine # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-test-machine> test-machine # [ 0.000003] tsc: Detected 4192.076 MHz processor vm-test-run-test-machine> test-machine # [ 0.000601] last_pfn = 0x140000 max_arch_pfn = 0x400000000 vm-test-run-test-machine> test-machine # [ 0.000628] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs vm-test-run-test-machine> test-machine # [ 0.000630] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT vm-test-run-test-machine> test-machine # [ 0.000667] last_pfn = 0xbffcb max_arch_pfn = 0x400000000 vm-test-run-test-machine> test-machine # [ 0.002296] found SMP MP-table at [mem 0x000f5470-0x000f547f] vm-test-run-test-machine> test-machine # [ 0.002307] Using GB pages for direct mapping vm-test-run-test-machine> test-machine # [ 0.002373] RAMDISK: [mem 0xbe38a000-0xbffbffff] vm-test-run-test-machine> test-machine # [ 0.002377] ACPI: Early table checksum verification disabled vm-test-run-test-machine> test-machine # [ 0.002379] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) vm-test-run-test-machine> test-machine # [ 0.002381] ACPI: RSDT 0x00000000BFFD27A9 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002384] ACPI: FACP 0x00000000BFFD25F9 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002388] ACPI: DSDT 0x00000000BFFD0040 0025B9 (v01 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002389] ACPI: FACS 0x00000000BFFD0000 000040 vm-test-run-test-machine> test-machine # [ 0.002391] ACPI: APIC 0x00000000BFFD266D 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002392] ACPI: HPET 0x00000000BFFD26FD 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002393] ACPI: TPM2 0x00000000BFFD2735 00004C (v04 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002394] ACPI: WAET 0x00000000BFFD2781 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) vm-test-run-test-machine> test-machine # [ 0.002395] ACPI: Reserving FACP table memory at [mem 0xbffd25f9-0xbffd266c] vm-test-run-test-machine> test-machine # [ 0.002396] ACPI: Reserving DSDT table memory at [mem 0xbffd0040-0xbffd25f8] vm-test-run-test-machine> test-machine # [ 0.002396] ACPI: Reserving FACS table memory at [mem 0xbffd0000-0xbffd003f] vm-test-run-test-machine> test-machine # [ 0.002397] ACPI: Reserving APIC table memory at [mem 0xbffd266d-0xbffd26fc] vm-test-run-test-machine> test-machine # [ 0.002397] ACPI: Reserving HPET table memory at [mem 0xbffd26fd-0xbffd2734] vm-test-run-test-machine> test-machine # [ 0.002398] ACPI: Reserving TPM2 table memory at [mem 0xbffd2735-0xbffd2780] vm-test-run-test-machine> test-machine # [ 0.002398] ACPI: Reserving WAET table memory at [mem 0xbffd2781-0xbffd27a8] vm-test-run-test-machine> test-machine # [ 0.002731] No NUMA configuration found vm-test-run-test-machine> test-machine # [ 0.002732] Faking a node at [mem 0x0000000000000000-0x000000013fffffff] vm-test-run-test-machine> test-machine # [ 0.002734] NODE_DATA(0) allocated [mem 0x13fff8780-0x13fffdcff] vm-test-run-test-machine> test-machine # [ 0.002802] Zone ranges: vm-test-run-test-machine> test-machine # [ 0.002802] DMA [mem 0x0000000000001000-0x0000000000ffffff] vm-test-run-test-machine> test-machine # [ 0.002803] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] vm-test-run-test-machine> test-machine # [ 0.002804] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-test-machine> test-machine # [ 0.002805] Device empty vm-test-run-test-machine> test-machine # [ 0.002805] Movable zone start for each node vm-test-run-test-machine> test-machine # [ 0.002806] Early memory node ranges vm-test-run-test-machine> test-machine # [ 0.002806] node 0: [mem 0x0000000000001000-0x000000000009efff] vm-test-run-test-machine> test-machine # [ 0.002807] node 0: [mem 0x0000000000100000-0x00000000bffcafff] vm-test-run-test-machine> test-machine # [ 0.002808] node 0: [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-test-machine> test-machine # [ 0.002808] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] vm-test-run-test-machine> test-machine # [ 0.002822] On node 0, zone DMA: 1 pages in unavailable ranges vm-test-run-test-machine> test-machine # [ 0.003006] On node 0, zone DMA: 97 pages in unavailable ranges vm-test-run-test-machine> test-machine # [ 0.052702] On node 0, zone Normal: 53 pages in unavailable ranges vm-test-run-test-machine> test-machine # [ 0.053407] ACPI: PM-Timer IO Port: 0x608 vm-test-run-test-machine> test-machine # [ 0.053418] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) vm-test-run-test-machine> test-machine # [ 0.053440] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 vm-test-run-test-machine> test-machine # [ 0.053442] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) vm-test-run-test-machine> test-machine # [ 0.053444] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) vm-test-run-test-machine> test-machine # [ 0.053445] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) vm-test-run-test-machine> test-machine # [ 0.053445] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) vm-test-run-test-machine> test-machine # [ 0.053446] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) vm-test-run-test-machine> test-machine # [ 0.053448] ACPI: Using ACPI (MADT) for SMP configuration information vm-test-run-test-machine> test-machine # [ 0.053449] ACPI: HPET id: 0x8086a201 base: 0xfed00000 vm-test-run-test-machine> test-machine # [ 0.053453] TSC deadline timer available vm-test-run-test-machine> test-machine # [ 0.053456] CPU topo: Max. logical packages: 1 vm-test-run-test-machine> test-machine # [ 0.053456] CPU topo: Max. logical dies: 1 vm-test-run-test-machine> test-machine # [ 0.053457] CPU topo: Max. dies per package: 1 vm-test-run-test-machine> test-machine # [ 0.053459] CPU topo: Max. threads per core: 1 vm-test-run-test-machine> test-machine # [ 0.053460] CPU topo: Num. cores per package: 4 vm-test-run-test-machine> test-machine # [ 0.053460] CPU topo: Num. threads per package: 4 vm-test-run-test-machine> test-machine # [ 0.053460] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs vm-test-run-test-machine> test-machine # [ 0.053474] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() vm-test-run-test-machine> test-machine # [ 0.053486] kvm-guest: KVM setup pv remote TLB flush vm-test-run-test-machine> test-machine # [ 0.053489] kvm-guest: setup PV sched yield vm-test-run-test-machine> test-machine # [ 0.053495] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] vm-test-run-test-machine> test-machine # [ 0.053496] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] vm-test-run-test-machine> test-machine # [ 0.053497] PM: hibernation: Registered nosave memory: [mem 0xbffcb000-0xffffffff] vm-test-run-test-machine> test-machine # [ 0.053498] [mem 0xc0000000-0xfeffbfff] available for PCI devices vm-test-run-test-machine> test-machine # [ 0.053499] Booting paravirtualized kernel on KVM vm-test-run-test-machine> test-machine # [ 0.053502] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns vm-test-run-test-machine> test-machine # [ 0.056755] setup_percpu: NR_CPUS:384 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 vm-test-run-test-machine> test-machine # [ 0.058330] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u524288 vm-test-run-test-machine> test-machine # [ 0.058367] kvm-guest: PV spinlocks enabled vm-test-run-test-machine> test-machine # [ 0.058368] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.058370] Kernel command line: console=ttyS0,115200 console=tty0 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/s3k4hpp926x7z2x3vvkjiwi6vhd6cnas-nixos-system-test-machine-test/init regInfo=/nix/store/4jarl9chgib60vd76mqhbilfi6zwfsxs-closure-info/registration console=ttyS0,115200n8 console=tty0 vm-test-run-test-machine> test-machine # [ 0.058442] Unknown kernel command line parameters "regInfo=/nix/store/4jarl9chgib60vd76mqhbilfi6zwfsxs-closure-info/registration", will be passed to user space. vm-test-run-test-machine> test-machine # [ 0.058632] random: crng init done vm-test-run-test-machine> test-machine # [ 0.058633] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes vm-test-run-test-machine> test-machine # [ 0.061664] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.063177] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.063224] software IO TLB: area num 4. vm-test-run-test-machine> test-machine # [ 0.115327] Fallback order for Node 0: 0 vm-test-run-test-machine> test-machine # [ 0.115332] Built 1 zonelists, mobility grouping on. Total pages: 1048425 vm-test-run-test-machine> test-machine # [ 0.115333] Policy zone: Normal vm-test-run-test-machine> test-machine # [ 0.117050] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-test-machine> test-machine # [ 0.122886] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 vm-test-run-test-machine> test-machine # [ 0.129043] allocated 8388608 bytes of page_ext vm-test-run-test-machine> test-machine # [ 0.137443] ftrace: allocating 48593 entries in 192 pages vm-test-run-test-machine> test-machine # [ 0.137444] ftrace: allocated 192 pages with 2 groups vm-test-run-test-machine> test-machine # [ 0.138103] Dynamic Preempt: lazy vm-test-run-test-machine> test-machine # [ 0.138241] rcu: Preemptible hierarchical RCU implementation. vm-test-run-test-machine> test-machine # [ 0.138241] rcu: RCU event tracing is enabled. vm-test-run-test-machine> test-machine # [ 0.138242] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4. vm-test-run-test-machine> test-machine # [ 0.138243] Trampoline variant of Tasks RCU enabled. vm-test-run-test-machine> test-machine # [ 0.138244] Rude variant of Tasks RCU enabled. vm-test-run-test-machine> test-machine # [ 0.138244] Tracing variant of Tasks RCU enabled. vm-test-run-test-machine> test-machine # [ 0.138245] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. vm-test-run-test-machine> test-machine # [ 0.138245] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 vm-test-run-test-machine> test-machine # [ 0.138270] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. vm-test-run-test-machine> test-machine # [ 0.138271] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. vm-test-run-test-machine> test-machine # [ 0.138272] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. vm-test-run-test-machine> test-machine # [ 0.141630] NR_IRQS: 24832, nr_irqs: 456, preallocated irqs: 16 vm-test-run-test-machine> test-machine # [ 0.141912] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-test-machine> test-machine # [ 0.141919] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns vm-test-run-test-machine> test-machine # [ 0.142079] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-test-machine> test-machine # [ 0.147266] Console: colour VGA+ 80x25 vm-test-run-test-machine> test-machine # [ 0.147269] printk: legacy console [tty0] enabled vm-test-run-test-machine> test-machine # [ 0.178788] printk: legacy console [ttyS0] enabled vm-test-run-test-machine> test-machine # [ 0.320179] ACPI: Core revision 20250807 vm-test-run-test-machine> test-machine # [ 0.321256] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns vm-test-run-test-machine> test-machine # [ 0.323196] APIC: Switch to symmetric I/O mode setup vm-test-run-test-machine> test-machine # [ 0.324373] x2apic enabled vm-test-run-test-machine> test-machine # [ 0.325191] APIC: Switched APIC routing to: physical x2apic vm-test-run-test-machine> test-machine # [ 0.326341] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() vm-test-run-test-machine> test-machine # [ 0.327820] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() vm-test-run-test-machine> test-machine # [ 0.329663] kvm-guest: setup PV IPIs vm-test-run-test-machine> test-machine # [ 0.331301] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 vm-test-run-test-machine> test-machine # [ 0.332633] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns vm-test-run-test-machine> test-machine # [ 0.338548] Calibrating delay loop (skipped) preset value.. 8384.15 BogoMIPS (lpj=4192076) vm-test-run-test-machine> test-machine # [ 0.339651] x86/cpu: User Mode Instruction Prevention (UMIP) activated vm-test-run-test-machine> test-machine # [ 0.340654] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 vm-test-run-test-machine> test-machine # [ 0.341548] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 vm-test-run-test-machine> test-machine # [ 0.342551] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto vm-test-run-test-machine> test-machine # [ 0.343548] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl vm-test-run-test-machine> test-machine # [ 0.344548] Spectre V2 : Mitigation: Enhanced / Automatic IBRS vm-test-run-test-machine> test-machine # [ 0.346548] Speculative Return Stack Overflow: Mitigation: Safe RET vm-test-run-test-machine> test-machine # [ 0.347548] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization vm-test-run-test-machine> test-machine # [ 0.348554] Spectre V2 : Enabling IBPB for BPF vm-test-run-test-machine> test-machine # [ 0.349549] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier vm-test-run-test-machine> test-machine # [ 0.350548] active return thunk: srso_alias_return_thunk vm-test-run-test-machine> test-machine # [ 0.352576] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' vm-test-run-test-machine> test-machine # [ 0.353548] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' vm-test-run-test-machine> test-machine # [ 0.354548] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' vm-test-run-test-machine> test-machine # [ 0.356548] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' vm-test-run-test-machine> test-machine # [ 0.357548] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' vm-test-run-test-machine> test-machine # [ 0.358548] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' vm-test-run-test-machine> test-machine # [ 0.359548] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' vm-test-run-test-machine> test-machine # [ 0.361548] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' vm-test-run-test-machine> test-machine # [ 0.362548] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)' vm-test-run-test-machine> test-machine # [ 0.363548] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 vm-test-run-test-machine> test-machine # [ 0.365548] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 vm-test-run-test-machine> test-machine # [ 0.366548] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 vm-test-run-test-machine> test-machine # [ 0.367548] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 vm-test-run-test-machine> test-machine # [ 0.368548] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 vm-test-run-test-machine> test-machine # [ 0.369548] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16 vm-test-run-test-machine> test-machine # [ 0.371548] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24 vm-test-run-test-machine> test-machine # [ 0.372548] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format. vm-test-run-test-machine> test-machine # [ 0.394701] Freeing SMP alternatives memory: 44K vm-test-run-test-machine> test-machine # [ 0.395553] pid_max: default: 32768 minimum: 301 vm-test-run-test-machine> test-machine # [ 0.396612] LSM: initializing lsm=capability,landlock,yama,bpf,ima vm-test-run-test-machine> test-machine # [ 0.397636] landlock: Up and running. vm-test-run-test-machine> test-machine # [ 0.398548] Yama: becoming mindful. vm-test-run-test-machine> test-machine # [ 0.399725] LSM support for eBPF active vm-test-run-test-machine> test-machine # [ 0.400657] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.401607] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.403303] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2) vm-test-run-test-machine> test-machine # [ 0.403946] Performance Events: Fam17h+ core perfctr, AMD PMU driver. vm-test-run-test-machine> test-machine # [ 0.404552] ... version: 2 vm-test-run-test-machine> test-machine # [ 0.405456] ... bit width: 48 vm-test-run-test-machine> test-machine # [ 0.406260] ... generic counters: 6 vm-test-run-test-machine> test-machine # [ 0.406561] ... generic bitmap: 000000000000003f vm-test-run-test-machine> test-machine # [ 0.407549] ... fixed-purpose counters: 0 vm-test-run-test-machine> test-machine # [ 0.408441] ... fixed-purpose bitmap: 0000000000000000 vm-test-run-test-machine> test-machine # [ 0.409441] ... value mask: 0000ffffffffffff vm-test-run-test-machine> test-machine # [ 0.410435] ... max period: 00007fffffffffff vm-test-run-test-machine> test-machine # [ 0.411442] ... global_ctrl mask: 000000000000003f vm-test-run-test-machine> test-machine # [ 0.412523] signal: max sigframe size: 3376 vm-test-run-test-machine> test-machine # [ 0.413267] rcu: Hierarchical SRCU implementation. vm-test-run-test-machine> test-machine # [ 0.413553] rcu: Max phase no-delay instances is 400. vm-test-run-test-machine> test-machine # [ 0.414690] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-test-machine> test-machine # [ 0.418450] smp: Bringing up secondary CPUs ... vm-test-run-test-machine> test-machine # [ 0.419561] smpboot: x86: Booting SMP configuration: vm-test-run-test-machine> test-machine # [ 0.420557] .... node #0, CPUs: #1 #2 #3 vm-test-run-test-machine> test-machine # [ 0.421576] smp: Brought up 1 node, 4 CPUs vm-test-run-test-machine> test-machine # [ 0.423471] smpboot: Total of 4 processors activated (33536.60 BogoMIPS) vm-test-run-test-machine> test-machine # [ 0.424836] Memory: 3959968K/4193700K available (17150K kernel code, 2721K rwdata, 13544K rodata, 3640K init, 3012K bss, 218668K reserved, 0K cma-reserved) vm-test-run-test-machine> test-machine # [ 0.425757] devtmpfs: initialized vm-test-run-test-machine> test-machine # [ 0.426709] x86/mm: Memory block size: 128MB vm-test-run-test-machine> test-machine # [ 0.428648] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.429613] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). vm-test-run-test-machine> test-machine # [ 0.430636] pinctrl core: initialized pinctrl subsystem vm-test-run-test-machine> test-machine # [ 0.431802] PM: RTC time: 16:04:05, date: 2026-07-31 vm-test-run-test-machine> test-machine # [ 0.434543] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-test-machine> test-machine # [ 0.436048] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-test-machine> test-machine # [ 0.436577] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-test-machine> test-machine # [ 0.437941] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-test-machine> test-machine # [ 0.438558] audit: initializing netlink subsys (disabled) vm-test-run-test-machine> test-machine # [ 0.439693] audit: type=2000 audit(1785513845.957:1): state=initialized audit_enabled=0 res=1 vm-test-run-test-machine> test-machine # [ 0.439741] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-test-machine> test-machine # [ 0.440550] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-test-machine> test-machine # [ 0.441550] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-test-machine> test-machine # [ 0.442550] thermal_sys: Registered thermal governor 'user_space' vm-test-run-test-machine> test-machine # [ 0.443550] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-test-machine> test-machine # [ 0.444578] cpuidle: using governor menu vm-test-run-test-machine> test-machine # [ 0.447088] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 vm-test-run-test-machine> test-machine # [ 0.447754] PCI: Using configuration type 1 for base access vm-test-run-test-machine> test-machine # [ 0.448550] PCI: Using configuration type 1 for extended access vm-test-run-test-machine> test-machine # [ 0.449662] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. vm-test-run-test-machine> test-machine # [ 0.451715] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-test-machine> test-machine # [ 0.452549] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-test-machine> test-machine # [ 0.453549] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-test-machine> test-machine # [ 0.455550] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-test-machine> test-machine # [ 0.458139] ACPI: Added _OSI(Module Device) vm-test-run-test-machine> test-machine # [ 0.459227] ACPI: Added _OSI(Processor Device) vm-test-run-test-machine> test-machine # [ 0.459549] ACPI: Added _OSI(Processor Aggregator Device) vm-test-run-test-machine> test-machine # [ 0.461959] ACPI: 1 ACPI AML tables successfully acquired and loaded vm-test-run-test-machine> test-machine # [ 0.464731] ACPI: Interpreter enabled vm-test-run-test-machine> test-machine # [ 0.465563] ACPI: PM: (supports S0 S3 S4 S5) vm-test-run-test-machine> test-machine # [ 0.466465] ACPI: Using IOAPIC for interrupt routing vm-test-run-test-machine> test-machine # [ 0.467373] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug vm-test-run-test-machine> test-machine # [ 0.468549] PCI: Using E820 reservations for host bridge windows vm-test-run-test-machine> test-machine # [ 0.469671] ACPI: Enabled 2 GPEs in block 00 to 0F vm-test-run-test-machine> test-machine # [ 0.474131] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) vm-test-run-test-machine> test-machine # [ 0.474554] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] vm-test-run-test-machine> test-machine # [ 0.475810] acpiphp: Slot [3] registered vm-test-run-test-machine> test-machine # [ 0.476578] acpiphp: Slot [4] registered vm-test-run-test-machine> test-machine # [ 0.477439] acpiphp: Slot [5] registered vm-test-run-test-machine> test-machine # [ 0.478219] acpiphp: Slot [6] registered vm-test-run-test-machine> test-machine # [ 0.478585] acpiphp: Slot [7] registered vm-test-run-test-machine> test-machine # [ 0.479436] acpiphp: Slot [8] registered vm-test-run-test-machine> test-machine # [ 0.480204] acpiphp: Slot [9] registered vm-test-run-test-machine> test-machine # [ 0.480578] acpiphp: Slot [10] registered vm-test-run-test-machine> test-machine # [ 0.481522] acpiphp: Slot [11] registered vm-test-run-test-machine> test-machine # [ 0.482296] acpiphp: Slot [12] registered vm-test-run-test-machine> test-machine # [ 0.482576] acpiphp: Slot [13] registered vm-test-run-test-machine> test-machine # [ 0.483521] acpiphp: Slot [14] registered vm-test-run-test-machine> test-machine # [ 0.484299] acpiphp: Slot [15] registered vm-test-run-test-machine> test-machine # [ 0.484576] acpiphp: Slot [16] registered vm-test-run-test-machine> test-machine # [ 0.485500] acpiphp: Slot [17] registered vm-test-run-test-machine> test-machine # [ 0.486300] acpiphp: Slot [18] registered vm-test-run-test-machine> test-machine # [ 0.486591] acpiphp: Slot [19] registered vm-test-run-test-machine> test-machine # [ 0.487573] acpiphp: Slot [20] registered vm-test-run-test-machine> test-machine # [ 0.488450] acpiphp: Slot [21] registered vm-test-run-test-machine> test-machine # [ 0.489240] acpiphp: Slot [22] registered vm-test-run-test-machine> test-machine # [ 0.489582] acpiphp: Slot [23] registered vm-test-run-test-machine> test-machine # [ 0.490445] acpiphp: Slot [24] registered vm-test-run-test-machine> test-machine # [ 0.491220] acpiphp: Slot [25] registered vm-test-run-test-machine> test-machine # [ 0.491590] acpiphp: Slot [26] registered vm-test-run-test-machine> test-machine # [ 0.492467] acpiphp: Slot [27] registered vm-test-run-test-machine> test-machine # [ 0.493233] acpiphp: Slot [28] registered vm-test-run-test-machine> test-machine # [ 0.493573] acpiphp: Slot [29] registered vm-test-run-test-machine> test-machine # [ 0.494446] acpiphp: Slot [30] registered vm-test-run-test-machine> test-machine # [ 0.495226] acpiphp: Slot [31] registered vm-test-run-test-machine> test-machine # [ 0.495563] PCI host bridge to bus 0000:00 vm-test-run-test-machine> test-machine # [ 0.496420] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] vm-test-run-test-machine> test-machine # [ 0.497558] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] vm-test-run-test-machine> test-machine # [ 0.498551] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] vm-test-run-test-machine> test-machine # [ 0.499551] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] vm-test-run-test-machine> test-machine # [ 0.500550] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] vm-test-run-test-machine> test-machine # [ 0.501554] pci_bus 0000:00: root bus resource [mem 0xfed40000-0xfed44fff] vm-test-run-test-machine> test-machine # [ 0.502551] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-test-machine> test-machine # [ 0.503795] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.505186] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.506200] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.507511] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef] vm-test-run-test-machine> test-machine # [ 0.508423] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk vm-test-run-test-machine> test-machine # [ 0.509550] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk vm-test-run-test-machine> test-machine # [ 0.510550] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk vm-test-run-test-machine> test-machine # [ 0.511550] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk vm-test-run-test-machine> test-machine # [ 0.512767] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.514514] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f] vm-test-run-test-machine> test-machine # [ 0.516349] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.518024] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI vm-test-run-test-machine> test-machine # [ 0.518562] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB vm-test-run-test-machine> test-machine # [ 0.519868] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.523448] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] vm-test-run-test-machine> test-machine # [ 0.524570] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] vm-test-run-test-machine> test-machine # [ 0.525591] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref] vm-test-run-test-machine> test-machine # [ 0.526724] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] vm-test-run-test-machine> test-machine # [ 0.528361] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.530560] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] vm-test-run-test-machine> test-machine # [ 0.531561] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] vm-test-run-test-machine> test-machine # [ 0.532595] pci 0000:00:03.0: BAR 4 [mem 0xe0000000000-0xe0000003fff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.533562] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] vm-test-run-test-machine> test-machine # [ 0.536133] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.537559] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f] vm-test-run-test-machine> test-machine # [ 0.538561] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] vm-test-run-test-machine> test-machine # [ 0.539595] pci 0000:00:04.0: BAR 4 [mem 0xe0000004000-0xe0000007fff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.541963] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.544119] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf] vm-test-run-test-machine> test-machine # [ 0.544560] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] vm-test-run-test-machine> test-machine # [ 0.545587] pci 0000:00:05.0: BAR 4 [mem 0xe0000008000-0xe000000bfff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.548090] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.549560] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f] vm-test-run-test-machine> test-machine # [ 0.550561] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] vm-test-run-test-machine> test-machine # [ 0.551587] pci 0000:00:06.0: BAR 4 [mem 0xe000000c000-0xe000000ffff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.553982] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.555560] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f] vm-test-run-test-machine> test-machine # [ 0.556562] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] vm-test-run-test-machine> test-machine # [ 0.557590] pci 0000:00:07.0: BAR 4 [mem 0xe0000010000-0xe0000013fff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.559981] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.561559] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f] vm-test-run-test-machine> test-machine # [ 0.562562] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] vm-test-run-test-machine> test-machine # [ 0.563590] pci 0000:00:08.0: BAR 4 [mem 0xe0000014000-0xe0000017fff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.566056] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.567530] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf] vm-test-run-test-machine> test-machine # [ 0.568410] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] vm-test-run-test-machine> test-machine # [ 0.569549] pci 0000:00:09.0: BAR 4 [mem 0xe0000018000-0xe000001bfff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.570562] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref] vm-test-run-test-machine> test-machine # [ 0.572995] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.574341] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] vm-test-run-test-machine> test-machine # [ 0.575528] pci 0000:00:0a.0: BAR 4 [mem 0xe000001c000-0xe000001ffff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.577979] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.579559] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff] vm-test-run-test-machine> test-machine # [ 0.580562] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] vm-test-run-test-machine> test-machine # [ 0.581592] pci 0000:00:0b.0: BAR 4 [mem 0xe0000020000-0xe0000023fff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.584012] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-test-machine> test-machine # [ 0.585504] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df] vm-test-run-test-machine> test-machine # [ 0.586473] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff] vm-test-run-test-machine> test-machine # [ 0.587593] pci 0000:00:0c.0: BAR 4 [mem 0xe0000024000-0xe0000027fff 64bit pref] vm-test-run-test-machine> test-machine # [ 0.593579] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 vm-test-run-test-machine> test-machine # [ 0.594705] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 vm-test-run-test-machine> test-machine # [ 0.595686] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 vm-test-run-test-machine> test-machine # [ 0.596690] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 vm-test-run-test-machine> test-machine # [ 0.597624] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 vm-test-run-test-machine> test-machine # [ 0.599575] iommu: Default domain type: Translated vm-test-run-test-machine> test-machine # [ 0.600549] iommu: DMA domain TLB invalidation policy: lazy mode vm-test-run-test-machine> test-machine # [ 0.601713] ACPI: bus type USB registered vm-test-run-test-machine> test-machine # [ 0.602608] usbcore: registered new interface driver usbfs vm-test-run-test-machine> test-machine # [ 0.603565] usbcore: registered new interface driver hub vm-test-run-test-machine> test-machine # [ 0.604577] usbcore: registered new device driver usb vm-test-run-test-machine> test-machine # [ 0.606108] NetLabel: Initializing vm-test-run-test-machine> test-machine # [ 0.606550] NetLabel: domain hash size = 128 vm-test-run-test-machine> test-machine # [ 0.607462] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO vm-test-run-test-machine> test-machine # [ 0.608530] NetLabel: unlabeled traffic allowed by default vm-test-run-test-machine> test-machine # [ 0.609452] PCI: Using ACPI for IRQ routing vm-test-run-test-machine> test-machine # [ 0.610649] pci 0000:00:02.0: vgaarb: setting as boot VGA device vm-test-run-test-machine> test-machine # [ 0.611545] pci 0000:00:02.0: vgaarb: bridge control possible vm-test-run-test-machine> test-machine # [ 0.611545] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none vm-test-run-test-machine> test-machine # [ 0.611552] vgaarb: loaded vm-test-run-test-machine> test-machine # [ 0.612357] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 vm-test-run-test-machine> test-machine # [ 0.613330] hpet0: 3 comparators, 64-bit 100.000000 MHz counter vm-test-run-test-machine> test-machine # [ 0.615622] clocksource: Switched to clocksource kvm-clock vm-test-run-test-machine> test-machine # [ 0.618439] VFS: Disk quotas dquot_6.6.0 vm-test-run-test-machine> test-machine # [ 0.619440] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-test-machine> test-machine # [ 0.621068] pnp: PnP ACPI init vm-test-run-test-machine> test-machine # [ 0.622179] pnp: PnP ACPI: found 6 devices vm-test-run-test-machine> test-machine # [ 0.629693] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns vm-test-run-test-machine> test-machine # [ 0.631449] clocksource: Switched to clocksource acpi_pm vm-test-run-test-machine> test-machine # [ 0.632620] NET: Registered PF_INET protocol family vm-test-run-test-machine> test-machine # [ 0.634098] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.647224] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.648929] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.650536] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.652229] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.653741] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-test-machine> test-machine # [ 0.655196] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.656818] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.658288] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) vm-test-run-test-machine> test-machine # [ 0.659827] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-test-machine> test-machine # [ 0.661014] NET: Registered PF_XDP protocol family vm-test-run-test-machine> test-machine # [ 0.662050] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] vm-test-run-test-machine> test-machine # [ 0.663289] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] vm-test-run-test-machine> test-machine # [ 0.664528] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] vm-test-run-test-machine> test-machine # [ 0.665923] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] vm-test-run-test-machine> test-machine # [ 0.667275] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] vm-test-run-test-machine> test-machine # [ 0.668758] pci_bus 0000:00: resource 9 [mem 0xfed40000-0xfed44fff] vm-test-run-test-machine> test-machine # [ 0.670066] pci 0000:00:01.0: PIIX3: Enabling Passive Release vm-test-run-test-machine> test-machine # [ 0.671247] pci 0000:00:00.0: Limiting direct PCI/PCI transfers vm-test-run-test-machine> test-machine # [ 0.673528] ACPI: \_SB_.LNKD: Enabled at IRQ 11 vm-test-run-test-machine> test-machine # [ 0.675806] PCI: CLS 0 bytes, default 64 vm-test-run-test-machine> test-machine # [ 0.676720] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) vm-test-run-test-machine> test-machine # [ 0.676880] Trying to unpack rootfs image as initramfs... vm-test-run-test-machine> test-machine # [ 0.676949] software IO TLB: mapped [mem 0x00000000ba38a000-0x00000000be38a000] (64MB) vm-test-run-test-machine> test-machine # [ 0.680907] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns vm-test-run-test-machine> test-machine # [ 0.696786] Initialise system trusted keyrings vm-test-run-test-machine> test-machine # [ 0.698014] workingset: timestamp_bits=40 max_order=20 bucket_order=0 vm-test-run-test-machine> test-machine # [ 0.707143] Key type asymmetric registered vm-test-run-test-machine> test-machine # [ 0.708062] Asymmetric key parser 'x509' registered vm-test-run-test-machine> test-machine # [ 0.709130] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) vm-test-run-test-machine> test-machine # [ 0.710989] io scheduler mq-deadline registered vm-test-run-test-machine> test-machine # [ 0.712114] io scheduler kyber registered vm-test-run-test-machine> test-machine # [ 0.717328] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-test-machine> test-machine # [ 0.718901] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A vm-test-run-test-machine> test-machine # [ 0.721537] Linux agpgart interface v0.103 vm-test-run-test-machine> test-machine # [ 0.725893] tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x1, rev-id 1) vm-test-run-test-machine> test-machine # [ 0.740471] ACPI: bus type drm_connector registered vm-test-run-test-machine> test-machine # [ 0.743338] usbcore: registered new interface driver usbserial_generic vm-test-run-test-machine> test-machine # [ 0.744673] usbserial: USB Serial support registered for generic vm-test-run-test-machine> test-machine # [ 0.745885] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. vm-test-run-test-machine> test-machine # [ 0.745885] Please enable it if your BIOS has the CPPC option. vm-test-run-test-machine> test-machine # [ 0.748575] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled vm-test-run-test-machine> test-machine # [ 0.750259] drop_monitor: Initializing network drop monitor service vm-test-run-test-machine> test-machine # [ 0.751676] NET: Registered PF_INET6 protocol family vm-test-run-test-machine> test-machine # [ 0.753473] Segment Routing with IPv6 vm-test-run-test-machine> test-machine # [ 0.754335] In-situ OAM (IOAM) with IPv6 vm-test-run-test-machine> test-machine # [ 0.756059] IPI shorthand broadcast: enabled vm-test-run-test-machine> test-machine # [ 0.759666] sched_clock: Marking stable (572018131, 187627254)->(847939387, -88294002) vm-test-run-test-machine> test-machine # [ 0.761512] registered taskstats version 1 vm-test-run-test-machine> test-machine # [ 0.762576] Loading compiled-in X.509 certificates vm-test-run-test-machine> test-machine # [ 0.770022] Demotion targets for Node 0: null vm-test-run-test-machine> test-machine # [ 0.771173] Key type .fscrypt registered vm-test-run-test-machine> test-machine # [ 0.772025] Key type fscrypt-provisioning registered vm-test-run-test-machine> test-machine # [ 0.773138] ima: Allocated hash algorithm: sha1 vm-test-run-test-machine> test-machine # [ 0.788835] ima: No architecture policies found vm-test-run-test-machine> test-machine # [ 0.790006] PM: Magic number: 2:547:86 vm-test-run-test-machine> test-machine # [ 0.791462] RAS: Correctable Errors collector initialized. vm-test-run-test-machine> test-machine # [ 0.795220] clk: Disabling unused clocks vm-test-run-test-machine> test-machine # [ 0.796078] PM: genpd: Disabling unused power domains vm-test-run-test-machine> test-machine # [ 0.863503] Freeing initrd memory: 28888K vm-test-run-test-machine> test-machine # [ 0.866381] Freeing unused decrypted memory: 2028K vm-test-run-test-machine> test-machine # [ 0.868869] Freeing unused kernel image (initmem) memory: 3640K vm-test-run-test-machine> test-machine # [ 0.870142] Write protecting the kernel read-only data: 32768k vm-test-run-test-machine> test-machine # [ 0.872228] Freeing unused kernel image (text/rodata gap) memory: 1280K vm-test-run-test-machine> test-machine # [ 0.873984] Freeing unused kernel image (rodata/data gap) memory: 792K vm-test-run-test-machine> test-machine # [ 0.907132] x86/mm: Checked W+X mappings: passed, no W+X pages found. vm-test-run-test-machine> test-machine # [ 0.908449] Run /init as init process vm-test-run-test-machine> test-machine # [ 0.925993] systemd[1]: Inserted module 'autofs4' vm-test-run-test-machine> test-machine # [ 0.942717] fuse: init (API version 7.45) vm-test-run-test-machine> test-machine # [ 0.948922] ACPI: \_SB_.LNKC: Enabled at IRQ 10 vm-test-run-test-machine> test-machine # [ 0.954908] ACPI: \_SB_.LNKA: Enabled at IRQ 10 vm-test-run-test-machine> test-machine # [ 0.958068] ACPI: \_SB_.LNKB: Enabled at IRQ 11 vm-test-run-test-machine> test-machine # [ 0.988513] systemd[1]: Successfully made /usr/ read-only. vm-test-run-test-machine> test-machine # [ 1.324927] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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) vm-test-run-test-machine> test-machine # [ 1.330675] systemd[1]: Detected virtualization kvm. vm-test-run-test-machine> test-machine # [ 1.331719] systemd[1]: Detected architecture x86-64. vm-test-run-test-machine> test-machine # [ 1.332770] systemd[1]: Running in initrd. vm-test-run-test-machine> test-machine # [ 1.333822] systemd[1]: Initializing machine ID from random generator. vm-test-run-test-machine> test-machine # [ 1.335186] systemd[1]: Hostname set to . vm-test-run-test-machine> test-machine # [ 1.414509] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-test-machine> test-machine # [ 1.439110] systemd[1]: Queued start job for default target Initrd Default Target. vm-test-run-test-machine> test-machine # [ 1.457883] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-test-machine> test-machine # [ 1.459250] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-test-machine> test-machine # [ 1.460891] systemd[1]: Expecting device /dev/disk/by-label/nixos... vm-test-run-test-machine> test-machine # [ 1.462217] systemd[1]: Reached target Path Units. vm-test-run-test-machine> test-machine # [ 1.463283] systemd[1]: Reached target Slice Units. vm-test-run-test-machine> test-machine # [ 1.464364] systemd[1]: Reached target Swaps. vm-test-run-test-machine> test-machine # [ 1.465360] systemd[1]: Reached target Timer Units. vm-test-run-test-machine> test-machine # [ 1.466538] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-test-machine> test-machine # [ 1.467998] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-test-machine> test-machine # [ 1.469356] systemd[1]: Listening on Journal Sockets. vm-test-run-test-machine> test-machine # [ 1.470547] systemd[1]: Listening on udev Control Socket. vm-test-run-test-machine> test-machine # [ 1.471789] systemd[1]: Listening on udev Kernel Socket. vm-test-run-test-machine> test-machine # [ 1.472939] systemd[1]: Reached target Socket Units. vm-test-run-test-machine> test-machine # [ 1.474694] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-test-machine> test-machine # [ 1.476680] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-test-machine> test-machine # [ 1.480391] systemd[1]: Starting Load Kernel Module configfs... vm-test-run-test-machine> test-machine # [ 1.483126] systemd[1]: Starting Journal Service... vm-test-run-test-machine> test-machine # [ 1.485612] systemd[1]: Starting Load Kernel Modules... vm-test-run-test-machine> test-machine # [ 1.486849] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os vm-test-run-test-machine> test-machine # [ 1.490751] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-test-machine> test-machine # [ 1.495665] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-test-machine> test-machine # [ 1.499198] systemd-journald[94]: Collecting audit messages is disabled. vm-test-run-test-machine> test-machine # [ 1.500165] systemd[1]: modprobe@configfs.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 1.502500] systemd[1]: Finished Load Kernel Module configfs. vm-test-run-test-machine> test-machine # [ 1.504221] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config vm-test-run-test-machine> test-machine # [ 1.504387] netfs: FS-Cache loaded vm-test-run-test-machine> test-machine # [ 1.509786] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. vm-test-run-test-machine> test-machine # [ 1.512174] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-test-machine> test-machine # [ 1.514714] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev vm-test-run-test-machine> test-machine # [ 1.519496] systemd[1]: Finished Load Kernel Modules. vm-test-run-test-machine> test-machine # [ 1.519714] 9pnet: Installing 9P2000 support vm-test-run-test-machine> test-machine # [ 1.522467] systemd[1]: Starting Apply Kernel Variables... vm-test-run-test-machine> test-machine # [ 1.524464] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-test-machine> test-machine # [ 1.528046] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-test-machine> test-machine # [ 1.529820] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 1.531846] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-test-machine> test-machine # [ 1.533879] systemd[1]: Finished Apply Kernel Variables. vm-test-run-test-machine> test-machine # [ 1.536906] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-test-machine> test-machine # [ 1.538412] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-test-machine> test-machine # [ 1.539890] systemd[1]: Reached target Local File Systems. vm-test-run-test-machine> test-machine # [ 1.541763] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-test-machine> test-machine # [ 1.555845] systemd[1]: Started Journal Service. vm-test-run-test-machine> test-machine # [ 1.368314] systemd-modules-load[95]: Using 4 probe threads vm-test-run-test-machine> test-machine # [ 1.370596] systemd-modules-load[95]: Inserted module 'virtio_balloon' vm-test-run-test-machine> test-machine # [ 1.371806] systemd-modules-load[95]: Inserted module 'dm_mod' vm-test-run-test-machine> test-machine # [ 1.373460] systemd-modules-load[95]: Inserted module 'virtio_gpu' vm-test-run-test-machine> test-machine # [ 1.374594] systemd[1]: Starting Create System Files and Directories... vm-test-run-test-machine> test-machine # [ 1.378867] systemd-udevd[105]: Using default interface naming scheme 'v261'. vm-test-run-test-machine> test-machine # [ 1.380865] systemd[1]: Finished Create System Files and Directories. vm-test-run-test-machine> test-machine # [ 1.390768] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-test-machine> test-machine # [ 1.405528] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-test-machine> test-machine # [ 1.406423] systemd[1]: Reached target System Initialization. vm-test-run-test-machine> test-machine # [ 1.407332] systemd[1]: Reached target Basic System. vm-test-run-test-machine> test-machine # [ 1.479488] systemd[1]: Condition check resulted in /dev/tpmrm0 being skipped. vm-test-run-test-machine> test-machine # [ 1.480637] systemd[1]: Expecting device /dev/tpm0... vm-test-run-test-machine> test-machine # [ 1.485359] systemd[1]: Found device /dev/tpm0. vm-test-run-test-machine> test-machine # [ 1.486497] systemd[1]: Reached target Trusted Platform Module. vm-test-run-test-machine> test-machine # [ 1.678150] uhci_hcd 0000:00:01.2: UHCI Host Controller vm-test-run-test-machine> test-machine # [ 1.679016] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 vm-test-run-test-machine> test-machine # [ 1.680246] uhci_hcd 0000:00:01.2: detected 2 ports vm-test-run-test-machine> test-machine # [ 1.681193] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100 vm-test-run-test-machine> test-machine # [ 1.682431] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 vm-test-run-test-machine> test-machine # [ 1.683712] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 vm-test-run-test-machine> test-machine # [ 1.684871] usb usb1: Product: UHCI Host Controller vm-test-run-test-machine> test-machine # [ 1.685696] usb usb1: Manufacturer: Linux 6.18.39 uhci_hcd vm-test-run-test-machine> test-machine # [ 1.686566] usb usb1: SerialNumber: 0000:00:01.2 vm-test-run-test-machine> test-machine # [ 1.693499] SCSI subsystem initialized vm-test-run-test-machine> test-machine # [ 1.700108] hub 1-0:1.0: USB hub found vm-test-run-test-machine> test-machine # [ 1.700769] hub 1-0:1.0: 2 ports detected vm-test-run-test-machine> test-machine # [ 1.710609] virtio_blk virtio5: 4/0/0 default/read/poll queues vm-test-run-test-machine> test-machine # [ 1.710756] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 vm-test-run-test-machine> test-machine # [ 1.715006] serio: i8042 KBD port at 0x60,0x64 irq 1 vm-test-run-test-machine> test-machine # [ 1.715819] serio: i8042 AUX port at 0x60,0x64 irq 12 vm-test-run-test-machine> test-machine # [ 1.717778] scsi host0: ata_piix vm-test-run-test-machine> test-machine # [ 1.720615] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-test-machine> test-machine # [ 1.724018] scsi host1: ata_piix vm-test-run-test-machine> test-machine # [ 1.724778] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0 vm-test-run-test-machine> test-machine # [ 1.726084] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0 vm-test-run-test-machine> test-machine # [ 1.556141] systemd[1]: Starting Virtual Console Setup... vm-test-run-test-machine> test-machine # [ 1.568366] systemd[1]: Found device /dev/disk/by-label/nixos. vm-test-run-test-machine> test-machine # [ 1.569409] systemd[1]: Reached target Initrd Root Device. vm-test-run-test-machine> test-machine # [ 1.570378] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... vm-test-run-test-machine> test-machine # [ 1.571826] systemd-vconsole-setup[149]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-test-machine> test-machine # [ 1.587070] systemd[1]: Finished Virtual Console Setup. vm-test-run-test-machine> test-machine # [ 1.595326] systemd-fsck[154]: nixos: clean, 12/65536 files, 13019/262144 blocks vm-test-run-test-machine> test-machine # [ 1.597366] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. vm-test-run-test-machine> test-machine # [ 1.881210] ata2: found unknown device (class 0) vm-test-run-test-machine> test-machine # [ 1.882802] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 vm-test-run-test-machine> test-machine # [ 1.884969] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 vm-test-run-test-machine> test-machine # [ 1.716571] (udev-worker)[136]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-test-machine> test-machine # [ 1.720045] (udev-worker)[125]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-test-machine> test-machine # [ 1.909242] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 vm-test-run-test-machine> test-machine # [ 1.932322] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray vm-test-run-test-machine> test-machine # [ 1.936700] usb 1-1: new full-speed USB device number 2 using uhci_hcd vm-test-run-test-machine> test-machine # [ 1.968133] cdrom: Uniform CD-ROM driver Revision: 3.20 vm-test-run-test-machine> test-machine # [ 2.105000] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 vm-test-run-test-machine> test-machine # [ 2.106685] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 vm-test-run-test-machine> test-machine # [ 2.107820] usb 1-1: Product: QEMU USB Tablet vm-test-run-test-machine> test-machine # [ 2.108536] usb 1-1: Manufacturer: QEMU vm-test-run-test-machine> test-machine # [ 2.109213] usb 1-1: SerialNumber: 28754-0000:00:01.2-1 vm-test-run-test-machine> test-machine # [ 2.122158] hid: raw HID events driver (C) Jiri Kosina vm-test-run-test-machine> test-machine # [ 2.129307] usbcore: registered new interface driver usbhid vm-test-run-test-machine> test-machine # [ 2.130182] usbhid: USB HID core driver vm-test-run-test-machine> test-machine # [ 2.133634] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 vm-test-run-test-machine> test-machine # [ 2.135392] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 vm-test-run-test-machine> test-machine # [ 2.306376] systemd[1]: Mounting /sysroot... vm-test-run-test-machine> test-machine # [ 2.571452] EXT4-fs (vda): mounted filesystem 40bf2337-1d34-4e88-b319-06e73534fcfe r/w with ordered data mode. Quota mode: none. vm-test-run-test-machine> test-machine # [ 2.386835] systemd[1]: Mounted /sysroot. vm-test-run-test-machine> test-machine # [ 2.387745] systemd[1]: Reached target Initrd Root File System. vm-test-run-test-machine> test-machine # [ 2.389680] systemd[1]: Mounting /sysroot/nix/.ro-store... vm-test-run-test-machine> test-machine # [ 2.391073] systemd[1]: Mounting /sysroot/nix/.rw-store... vm-test-run-test-machine> test-machine # [ 2.401120] systemd[1]: Mounting /sysroot/run... vm-test-run-test-machine> test-machine # [ 2.402120] systemd[1]: Mounting /sysroot/tmp/shared... vm-test-run-test-machine> test-machine # [ 2.405112] systemd[1]: Mounting /sysroot/tmp/xchg... vm-test-run-test-machine> test-machine # [ 2.406585] systemd[1]: Starting Mountpoints Configured in the Real Root... vm-test-run-test-machine> test-machine # [ 2.409102] systemd[1]: Mount[ 2.596692] 9p: Installing v9fs 9p2000 file system support vm-test-run-test-machine> test-machine # ed /sysroot/nix/.rw-store. vm-test-run-test-machine> test-machine # [ 2.410655] systemd[1]: Mounted /sysroot/run. vm-test-run-test-machine> test-machine # [ 2.413365] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-test-machine> test-machine # [ 2.415253] systemd[1]: Mounted /sysroot/nix/.ro-store. vm-test-run-test-machine> test-machine # [ 2.416359] systemd-sysroot-fstab-check[179]: /sysroot should be mounted in the initrd, will request daemon-reload. vm-test-run-test-machine> test-machine # [ 2.417933] systemd[1]: Mounted /sysroot/tmp/shared. vm-test-run-test-machine> test-machine # [ 2.418781] systemd[1]: Mounted /sysroot/tmp/xchg. vm-test-run-test-machine> test-machine # [ 2.419581] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 2.420961] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-test-machine> test-machine # [ 2.424532] systemd[1]: Reload requested from client PID 179 ('systemd-sysroot') (unit initrd-parse-etc.service)... vm-test-run-test-machine> test-machine # [ 2.426202] systemd[1]: Reloading... vm-test-run-test-machine> test-machine # [ 2.464542] systemd[1]: Reloading finished in 37 ms. vm-test-run-test-machine> test-machine # [ 2.490132] systemd-sysroot-fstab-check[179]: Requesting initrd-fs.target/start/replace... vm-test-run-test-machine> test-machine # [ 2.491672] systemd-sysroot-fstab-check[179]: Requesting swap.target/start/replace... vm-test-run-test-machine> test-machine # [ 2.492934] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-test-machine> test-machine # [ 2.494067] systemd[1]: initrd-parse-etc.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 2.495248] systemd[1]: Finished Mountpoints Configured in the Real Root. vm-test-run-test-machine> test-machine # [ 2.496376] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. vm-test-run-test-machine> test-machine # [ 2.499458] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 2.500691] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-test-machine> test-machine # [ 3.306822] systemd[1]: Mounting /sysroot/nix/store... vm-test-run-test-machine> test-machine # [ 3.333055] systemd[1]: Mounted /sysroot/nix/store. vm-test-run-test-machine> test-machine # [ 3.334903] systemd[1]: Starting Find the path to the etc metadata image and based dir... vm-test-run-test-machine> test-machine # [ 3.336728] systemd[1]: Starting Find NixOS closure... vm-test-run-test-machine> test-machine # [ 3.353663] find-etc[207]: Found init: /nix/store/s3k4hpp926x7z2x3vvkjiwi6vhd6cnas-nixos-system-test-machine-test/init. vm-test-run-test-machine> test-machine # [ 3.356800] systemd[1]: Finished Find the path to the etc metadata image and based dir. vm-test-run-test-machine> test-machine # [ 3.359173] systemd[1]: Mounting /run/nixos-etc-metadata... vm-test-run-test-machine> test-machine # [ 3.361201] systemd[1]: Finished Find NixOS closure. vm-test-run-test-machine> test-machine # [ 3.557161] loop: module loaded vm-test-run-test-machine> test-machine # [ 3.558298] loop0: detected capacity change from 0 to 88 vm-test-run-test-machine> test-machine # [ 3.567435] erofs (device loop0): mounted with root inode @ nid 36. vm-test-run-test-machine> test-machine # [ 3.381937] systemd[1]: Mounted /run/nixos-etc-metadata. vm-test-run-test-machine> test-machine # [ 3.383639] systemd[1]: Starting rw-etc.service... vm-test-run-test-machine> test-machine # [ 3.410776] systemd[1]: rw-etc.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.411832] systemd[1]: Finished rw-etc.service. vm-test-run-test-machine> test-machine # [ 3.412749] systemd[1]: Mounting /sysroot/etc... vm-test-run-test-machine> test-machine # [ 3.418621] systemd[1]: Mounted /sysroot/etc. vm-test-run-test-machine> test-machine # [ 3.419491] systemd[1]: Reached target Initrd File Systems. vm-test-run-test-machine> test-machine # [ 3.420514] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... vm-test-run-test-machine> test-machine # [ 3.426944] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. vm-test-run-test-machine> test-machine # [ 3.428285] systemd[1]: Reached target Initrd Default Target. vm-test-run-test-machine> test-machine # [ 3.429358] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... vm-test-run-test-machine> test-machine # [ 3.436131] systemd[1]: Stopped target Initrd Default Target. vm-test-run-test-machine> test-machine # [ 3.437202] systemd[1]: Stopped target Basic System. vm-test-run-test-machine> test-machine # [ 3.438110] systemd[1]: Stopped target Initrd Root Device. vm-test-run-test-machine> test-machine # [ 3.439070] systemd[1]: Stopped target Path Units. vm-test-run-test-machine> test-machine # [ 3.439986] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.441420] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. vm-test-run-test-machine> test-machine # [ 3.442785] systemd[1]: Stopped target Slice Units. vm-test-run-test-machine> test-machine # [ 3.443738] systemd[1]: Stopped target Socket Units. vm-test-run-test-machine> test-machine # [ 3.444692] systemd[1]: Stopped target System Initialization. vm-test-run-test-machine> test-machine # [ 3.446415] systemd[1]: Stopped target Swaps. vm-test-run-test-machine> test-machine # [ 3.447288] systemd[1]: Stopped target Timer Units. vm-test-run-test-machine> test-machine # [ 3.448315] systemd[1]: Stopped target Trusted Platform Module. vm-test-run-test-machine> test-machine # [ 3.449420] systemd[1]: dbus.socket: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.450440] systemd[1]: Closed D-Bus System Message Bus Socket. vm-test-run-test-machine> test-machine # [ 3.451484] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.452956] systemd[1]: Stopped Find NixOS closure. vm-test-run-test-machine> test-machine # [ 3.453789] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio vm-test-run-test-machine> test-machine # [ 3.455643] systemd[1]: Starting rw-etc.service... vm-test-run-test-machine> test-machine # [ 3.456484] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-test-machine> test-machine # [ 3.457638] systemd[1]: systemd-sysctl.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.458774] systemd[1]: Stopped Apply Kernel Variables. vm-test-run-test-machine> test-machine # [ 3.459902] systemd[1]: systemd-modules-load.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.461118] systemd[1]: Stopped Load Kernel Modules. vm-test-run-test-machine> test-machine # [ 3.462152] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.463480] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. vm-test-run-test-machine> test-machine # [ 3.464746] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.465944] systemd[1]: Stopped Create System Files and Directories. vm-test-run-test-machine> test-machine # [ 3.467038] systemd[1]: Stopped target Local File Systems. vm-test-run-test-machine> test-machine # [ 3.467959] systemd[1]: Stopped target Preparation for Local File Systems. vm-test-run-test-machine> test-machine # [ 3.469109] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.470292] systemd[1]: Stopped Coldplug All udev Devices. vm-test-run-test-machine> test-machine # [ 3.471220] systemd[1]: Stopping Rule-based Manager for Device Events and Files... vm-test-run-test-machine> test-machine # [ 3.472453] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.473652] systemd[1]: Stopped Virtual Console Setup. vm-test-run-test-machine> test-machine # [ 3.474536] systemd[1]: initrd-cleanup.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.475671] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. vm-test-run-test-machine> test-machine # [ 3.476780] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.477977] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-test-machine> test-machine # [ 3.479055] systemd[1]: rw-etc.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.480177] systemd[1]: Finished rw-etc.service. vm-test-run-test-machine> test-machine # [ 3.481037] systemd[1]: systemd-udevd.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.483108] systemd[1]: Stopped Rule-based Manager for Device Events and Files. vm-test-run-test-machine> test-machine # [ 3.484453] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.485656] systemd[1]: Closed udev Control Socket. vm-test-run-test-machine> test-machine # [ 3.486543] systemd[1]: Starting Cleanup udev Database... vm-test-run-test-machine> test-machine # [ 3.487534] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.488946] systemd[1]: Stopped Create Static Device Nodes in /dev. vm-test-run-test-machine> test-machine # [ 3.490057] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.491396] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. vm-test-run-test-machine> test-machine # [ 3.492608] systemd[1]: kmod-static-nodes.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.493780] systemd[1]: Stopped Create List of Static Device Nodes. vm-test-run-test-machine> test-machine # [ 3.499306] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.500570] systemd[1]: Finished Cleanup udev Database. vm-test-run-test-machine> test-machine # [ 3.501486] systemd[1]: Reached target Switch Root. vm-test-run-test-machine> test-machine # [ 3.502403] systemd[1]: Starting NixOS Activation... vm-test-run-test-machine> test-machine # [ 3.622508] initrd-nixos-activation-start[240]: booting system configuration /nix/store/s3k4hpp926x7z2x3vvkjiwi6vhd6cnas-nixos-system-test-machine-test vm-test-run-test-machine> test-machine # [ 3.660686] initrd-nixos-activation-start[240]: running activation script... vm-test-run-test-machine> test-machine # [ 3.942930] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 3.944322] systemd[1]: Finished NixOS Activation. vm-test-run-test-machine> test-machine # [ 3.945293] systemd[1]: Starting Switch Root... vm-test-run-test-machine> test-machine # [ 3.972575] systemd[1]: Switching root. vm-test-run-test-machine> test-machine # [ 4.206215] systemd-journald[94]: Received SIGTERM from PID 1 (systemd). vm-test-run-test-machine> test-machine # [ 4.301016] NET: Registered PF_VSOCK protocol family vm-test-run-test-machine> test-machine # [ 4.681839] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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) vm-test-run-test-machine> test-machine # [ 4.686289] systemd[1]: Detected virtualization kvm. vm-test-run-test-machine> test-machine # [ 4.687128] systemd[1]: Detected architecture x86-64. vm-test-run-test-machine> test-machine # [ 4.688032] systemd[1]: Detected first boot. vm-test-run-test-machine> test-machine # [ 4.691060] systemd[1]: Initializing machine ID from random generator. vm-test-run-test-machine> test-machine # [ 4.792829] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-test-machine> test-machine # [ 4.852395] zram: Added device: zram0 vm-test-run-test-machine> test-machine # [ 4.858592] systemd[1]: Applying preset policy. vm-test-run-test-machine> test-machine # [ 5.099050] systemd[1]: Populated /etc with preset unit settings. vm-test-run-test-machine> test-machine # [ 5.267474] systemd[1]: microvm@hermes-test.service: Service uses a combination of Type=simple, ExecStartPost=, and credentials. This could lead to race conditions. Continuing. vm-test-run-test-machine> test-machine # [ 5.388373] systemd[1]: initrd-switch-root.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 5.389982] systemd[1]: Stopped initrd-switch-root.service. vm-test-run-test-machine> test-machine # [ 5.392248] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. vm-test-run-test-machine> test-machine # [ 5.394574] systemd[1]: Created slice Slice /system/microvm. vm-test-run-test-machine> test-machine # [ 5.396145] systemd[1]: Created slice Slice /system/microvm-macvtap-interfaces. vm-test-run-test-machine> test-machine # [ 5.397945] systemd[1]: Created slice Slice /system/microvm-pci-devices. vm-test-run-test-machine> test-machine # [ 5.399570] systemd[1]: Created slice Slice /system/microvm-set-booted. vm-test-run-test-machine> test-machine # [ 5.401225] systemd[1]: Created slice Slice /system/microvm-tap-interfaces. vm-test-run-test-machine> test-machine # [ 5.402894] systemd[1]: Created slice Slice /system/microvm-virtiofsd. vm-test-run-test-machine> test-machine # [ 5.404500] systemd[1]: Created slice Slice /system/systemd-zram-setup. vm-test-run-test-machine> test-machine # [ 5.406163] systemd[1]: Created slice User and Session Slice. vm-test-run-test-machine> test-machine # [ 5.407258] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-test-machine> test-machine # [ 5.408744] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-test-machine> test-machine # [ 5.410083] systemd[1]: Expecting device /dev/hvc0... vm-test-run-test-machine> test-machine # [ 5.411007] systemd[1]: Expecting device /dev/ttyS0... vm-test-run-test-machine> test-machine # [ 5.411950] systemd[1]: Expecting device /dev/zram0... vm-test-run-test-machine> test-machine # [ 5.412913] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-test-machine> test-machine # [ 5.413987] systemd[1]: Reached target Login Prompts. vm-test-run-test-machine> test-machine # [ 5.414912] systemd[1]: Stopped target initrd-fs.target. vm-test-run-test-machine> test-machine # [ 5.415888] systemd[1]: Stopped target initrd-root-fs.target. vm-test-run-test-machine> test-machine # [ 5.416919] systemd[1]: Stopped target initrd-switch-root.target. vm-test-run-test-machine> test-machine # [ 5.418025] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-test-machine> test-machine # [ 5.419198] systemd[1]: Reached target Remote File Systems. vm-test-run-test-machine> test-machine # [ 5.420224] systemd[1]: Reached target Slice Units. vm-test-run-test-machine> test-machine # [ 5.422447] systemd[1]: Listening on Query the User Interactively for a Password. vm-test-run-test-machine> test-machine # [ 5.424707] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-test-machine> test-machine # [ 5.426492] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-test-machine> test-machine # [ 5.428448] systemd[1]: Listening on Factory Reset Management. vm-test-run-test-machine> test-machine # [ 5.429517] systemd[1]: Listening on Hostname Service Socket. vm-test-run-test-machine> test-machine # [ 5.431979] systemd[1]: Starting Journal Log Access Socket... vm-test-run-test-machine> test-machine # [ 5.441759] systemd[1]: Listening on Journal Audit Socket. vm-test-run-test-machine> test-machine # [ 5.443756] systemd[1]: Listening on Console Output Muting Service Socket. vm-test-run-test-machine> test-machine # [ 5.444988] systemd[1]: Listening on Network Management Metrics Varlink Socket. vm-test-run-test-machine> test-machine # [ 5.446338] systemd[1]: Listening on Network Management Varlink Socket. vm-test-run-test-machine> test-machine # [ 5.447497] systemd[1]: Listening on Network Management Netlink Socket. vm-test-run-test-machine> test-machine # [ 5.448756] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-test-machine> test-machine # [ 5.450213] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os vm-test-run-test-machine> test-machine # [ 5.451724] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-test-machine> test-machine # [ 5.456572] systemd[1]: Listening on Disk Repartitioning Service Socket. vm-test-run-test-machine> test-machine # [ 5.457797] systemd[1]: Listening on Resolve Monitor Varlink Socket. vm-test-run-test-machine> test-machine # [ 5.458913] systemd[1]: Listening on Resolve Service Varlink Socket. vm-test-run-test-machine> test-machine # [ 5.460028] systemd[1]: Listening on udev Control Socket. vm-test-run-test-machine> test-machine # [ 5.461041] systemd[1]: Listening on udev Varlink Socket. vm-test-run-test-machine> test-machine # [ 5.464012] systemd[1]: Mounting Huge Pages File System... vm-test-run-test-machine> test-machine # [ 5.466426] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-test-machine> test-machine # [ 5.479938] systemd[1]: Mounting Kernel Debug File System... vm-test-run-test-machine> test-machine # [ 5.482703] systemd[1]: Mounting Kernel Trace File System... vm-test-run-test-machine> test-machine # [ 5.484332] systemd[1]: initrd-find-etc.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 5.486325] systemd[1]: Stopped initrd-find-etc.service. vm-test-run-test-machine> test-machine # [ 5.489243] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-test-machine> test-machine # [ 5.491002] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio vm-test-run-test-machine> test-machine # [ 5.494191] systemd[1]: Starting Load Kernel Module configfs... vm-test-run-test-machine> test-machine # [ 5.495396] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm vm-test-run-test-machine> test-machine # [ 5.497002] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-test-machine> test-machine # [ 5.498733] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse vm-test-run-test-machine> test-machine # [ 5.502219] systemd[1]: Mounting FUSE Control File System... vm-test-run-test-machine> test-machine # [ 5.504156] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-test-machine> test-machine # [ 5.518450] systemd[1]: Starting Journal Service... vm-test-run-test-machine> test-machine # [ 5.521202] systemd[1]: Starting Load Kernel Modules... vm-test-run-test-machine> test-machine # [ 5.523582] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-test-machine> test-machine # [ 5.525320] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os vm-test-run-test-machine> test-machine # [ 5.529415] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-test-machine> test-machine # [ 5.532449] systemd[1]: Listening on Journal Log Access Socket. vm-test-run-test-machine> test-machine # [ 5.534501] systemd[1]: Mounted Huge Pages File System. vm-test-run-test-machine> test-machine # [ 5.535720] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-test-machine> test-machine # [ 5.536982] systemd[1]: Mounted Kernel Debug File System. vm-test-run-test-machine> test-machine # [ 5.538196] systemd[1]: Mounted Kernel Trace File System. vm-test-run-test-machine> test-machine # [ 5.539911] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-test-machine> test-machine # [ 5.541702] systemd[1]: Mounted FUSE Control File System. vm-test-run-test-machine> test-machine # [ 5.544693] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-test-machine> test-machine # [ 5.546830] systemd-journald[336]: Collecting audit messages is enabled. vm-test-run-test-machine> test-machine # [ 5.548368] systemd[1]: modprobe@configfs.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 5.552128] systemd[1]: Finished Load Kernel Module configfs. vm-test-run-test-machine> test-machine # [ 5.556274] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-test-machine> test-machine # [ 5.560714] EXT4-fs (vda): re-mounted 40bf2337-1d34-4e88-b319-06e73534fcfe. vm-test-run-test-machine> test-machine # [ 5.562909] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-test-machine> test-machine # [ 5.376832] systemd[1]: Queued start job for [ 5.564597] systemd[1]: Started Journal Service. vm-test-run-test-machine> test-machine # default target Graphical Interface. vm-test-run-test-machine> test-machine # [ 5.379460] systemd[1]: systemd-journald.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 5.381476] systemd-modules-load[337]: Using 4 probe threads vm-test-run-test-machine> test-machine # [ 5.383138] systemd-modules-load[337]: Inserted module 'tap' vm-test-run-test-machine> test-machine # [ 5.384678] systemd-modules-load[337]: Inserted module 'vhost_vsock' vm-test-run-test-machine> test-machine # [ 5.386300] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-test-machine> test-machine # [ 5.388091] systemd[1]: Listening on Disk Image Download Service Socket. vm-test-run-test-machine> test-machine # [ 5.389931] systemd-modules-load[337]: Inserted module 'vhost_net' vm-test-run-test-machine> test-machine # [ 5.392049] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-test-machine> test-machine # [ 5.393720] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-test-machine> test-machine # [ 5.396341] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-test-machine> test-machine # [ 5.397906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os vm-test-run-test-machine> test-machine # [ 5.400656] systemd[1]: Finished Load Kernel Modules. vm-test-run-test-machine> test-machine # [ 5.402099] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-test-machine> test-machine # [ 5.404148] systemd[1]: Starting Apply Kernel Variables... vm-test-run-test-machine> test-machine # [ 5.593737] systemd-journald[336]: Received client request to flush runtime journal. vm-test-run-test-machine> test-machine # [ 5.452358] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-test-machine> test-machine # [ 5.453575] systemd[1]: Reached target First Boot Complete. vm-test-run-test-machine> test-machine # [ 5.454683] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-test-machine> test-machine # [ 5.455890] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-test-machine> test-machine # [ 5.456971] systemd[1]: Finished Apply Kernel Variables. vm-test-run-test-machine> test-machine # [ 5.457863] systemd[1]: Starting Network Name Resolution... vm-test-run-test-machine> test-machine # [ 5.458821] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-test-machine> test-machine # [ 5.459872] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-test-machine> test-machine # [ 5.461180] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-test-machine> test-machine # [ 5.462539] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-test-machine> test-machine # [ 5.471972] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-test-machine> test-machine # [ 5.485162] systemd-resolved[365]: Positive Trust Anchors: vm-test-run-test-machine> test-machine # [ 5.486368] systemd-resolved[365]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d vm-test-run-test-machine> test-machine # [ 5.488211] systemd-resolved[365]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 vm-test-run-test-machine> test-machine # [ 5.490113] systemd-resolved[365]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test vm-test-run-test-machine> test-machine # [ 5.504706] systemd-udevd[368]: Using default interface naming scheme 'v261'. vm-test-run-test-machine> test-machine # [ 5.548330] systemd-resolved[365]: Using system hostname 'test-machine'. vm-test-run-test-machine> test-machine # [ 5.553893] systemd[1]: Started Network Name Resolution. vm-test-run-test-machine> test-machine # [ 5.558906] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-test-machine> test-machine # [ 5.626429] systemd[1]: Found device /dev/zram0. vm-test-run-test-machine> test-machine # [ 5.629603] systemd[1]: Starting Create swap on /dev/zram0... vm-test-run-test-machine> test-machine # [ 5.632139] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse vm-test-run-test-machine> test-machine # [ 5.649720] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. vm-test-run-test-machine> test-machine # [ 5.864929] zram0: detected capacity change from 0 to 6417616 vm-test-run-test-machine> test-machine # [ 5.686855] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. vm-test-run-test-machine> test-machine # [ 5.692425] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-test-machine> test-machine # [ 5.695620] systemd-makefs[398]: Formatting /dev/zram0 as swap vm-test-run-test-machine> test-machine # [ 5.701677] (udev-worker)[388]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-test-machine> test-machine # [ 5.703401] systemd[1]: Condition check resulted in /dev/tpmrm0 being skipped. vm-test-run-test-machine> test-machine # [ 5.705416] systemd[1]: Expecting device /dev/tpm0... vm-test-run-test-machine> test-machine # [ 5.709402] systemd-makefs[398]: /dev/zram0 successfully formatted as swap (label "zram0", uuid 12cedced-2520-438a-a8ea-d721dd0bbfc6) vm-test-run-test-machine> test-machine # [ 5.713039] systemd[1]: Finished Create swap on /dev/zram0. vm-test-run-test-machine> test-machine # [ 5.720697] systemd[1]: Activating swap Compressed Swap on /dev/zram0... vm-test-run-test-machine> test-machine # [ 5.722436] systemd[1]: Found device /dev/tpm0. vm-test-run-test-machine> test-machine # [ 5.723229] systemd[1]: Reached target Trusted Platform Module. vm-test-run-test-machine> test-machine # [ 5.912825] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console vm-test-run-test-machine> test-machine # [ 5.924988] Console: switching to colour dummy device 80x25 vm-test-run-test-machine> test-machine # [ 5.926042] [drm] Found bochs VGA, ID 0xb0c5. vm-test-run-test-machine> test-machine # [ 5.926044] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. vm-test-run-test-machine> test-machine # [ 5.928569] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 vm-test-run-test-machine> test-machine # [ 5.929715] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic vm-test-run-test-machine> test-machine # [ 5.931487] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0 vm-test-run-test-machine> test-machine # [ 5.931539] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD vm-test-run-test-machine> test-machine # [ 5.936067] fbcon: bochs-drmdrmfb (fb0) is primary device vm-test-run-test-machine> test-machine # [ 5.936511] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 vm-test-run-test-machine> test-machine # [ 5.941742] ACPI: button: Power Button [PWRF] vm-test-run-test-machine> test-machine # [ 5.943896] Console: switching to colour frame buffer device 160x50 vm-test-run-test-machine> test-machine # [ 5.947590] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device vm-test-run-test-machine> test-machine # [ 5.954314] Adding 3208804k swap on /dev/zram0. Priority:5 extents:1 across:3208804k SSDsc vm-test-run-test-machine> test-machine # [ 5.954534] rtc_cmos 00:05: RTC can wake from S4 vm-test-run-test-machine> test-machine # [ 5.959544] parport_pc 00:03: reported by Plug and Play ACPI vm-test-run-test-machine> test-machine # [ 5.961220] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] vm-test-run-test-machine> test-machine # [ 5.963097] rtc_cmos 00:05: registered as rtc0 vm-test-run-test-machine> test-machine # [ 5.963961] rtc_cmos 00:05: setting system clock to 2026-07-31T16:04:11 UTC (1785513851) vm-test-run-test-machine> test-machine # [ 5.965170] Floppy drive(s): fd0 is 2.88M AMI BIOS vm-test-run-test-machine> test-machine # [ 5.965292] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs vm-test-run-test-machine> test-machine # [ 5.782167] systemd-resolved[365]: Clock change detected. Flushing caches. vm-test-run-test-machine> test-machine # [ 5.785985] systemd[1]: Activated swap Compressed Swap on /dev/zram0. vm-test-run-test-machine> test-machine # [ 5.790557] systemd[1]: Reached target Swaps. vm-test-run-test-machine> test-machine # [ 5.978694] FDC 0 is a S82078B vm-test-run-test-machine> test-machine # [ 5.998008] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4 vm-test-run-test-machine> test-machine # [ 5.998846] mousedev: PS/2 mouse device common for all mice vm-test-run-test-machine> test-machine # [ 5.813079] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-test-machine> test-machine # [ 6.013767] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 vm-test-run-test-machine> test-machine # [ 6.029392] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 vm-test-run-test-machine> test-machine # [ 5.859364] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-test-machine> test-machine # [ 5.872159] systemd[1]: Starting Virtual Console Setup... vm-test-run-test-machine> test-machine # [ 5.895291] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 5.897564] systemd[1]: Stopped Virtual Console Setup. vm-test-run-test-machine> test-machine # [ 5.901705] systemd[1]: Starting Virtual Console Setup... vm-test-run-test-machine> test-machine # [ 6.122442] ppdev: user-space parallel port driver vm-test-run-test-machine> test-machine # [ 6.145021] kvm_amd: TSC scaling supported vm-test-run-test-machine> test-machine # [ 6.145598] kvm_amd: Nested Virtualization enabled vm-test-run-test-machine> test-machine # [ 6.146390] kvm_amd: Nested Paging enabled vm-test-run-test-machine> test-machine # [ 6.147088] kvm_amd: LBR virtualization supported vm-test-run-test-machine> test-machine # [ 6.147836] kvm_amd: Virtual GIF supported vm-test-run-test-machine> test-machine # [ 6.148411] kvm_amd: Virtual NMI enabled vm-test-run-test-machine> test-machine # [ 6.248914] EDAC MC: Ver: 3.0.0 vm-test-run-test-machine> test-machine # [ 6.100633] systemd-vconsole-setup[417]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-test-machine> test-machine # [ 6.102781] systemd[1]: Finished Virtual Console Setup. vm-test-run-test-machine> test-machine # [ 6.202829] systemd[1]: Mounting /run/wrappers... vm-test-run-test-machine> test-machine # [ 6.226125] systemd[1]: Mounted /run/wrappers. vm-test-run-test-machine> test-machine # [ 6.226912] systemd[1]: Reached target Local File Systems. vm-test-run-test-machine> test-machine # [ 6.227877] systemd[1]: Listening on Boot Loader Control Service Socket. vm-test-run-test-machine> test-machine # [ 6.229123] systemd[1]: Starting register-nix-paths.service... vm-test-run-test-machine> test-machine # [ 6.230665] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-test-machine> test-machine # [ 6.231679] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-test-machine> test-machine # [ 6.233293] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-test-machine> test-machine # [ 6.236152] systemd[1]: Starting Create System Files and Directories... vm-test-run-test-machine> test-machine # [ 6.251569] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 6.253633] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-test-machine> test-machine # [ 6.255925] systemd-tmpfiles[427]: /etc/tmpfiles.d/10-microvm.conf:1: Duplicate line for path "/home/test/hermes", ignoring. vm-test-run-test-machine> test-machine # [ 6.258873] systemd-tmpfiles[427]: /etc/tmpfiles.d/10-microvm.conf:2: Duplicate line for path "/var/lib/hermes-microvm/test/guest", ignoring. vm-test-run-test-machine> test-machine # [ 6.261742] systemd-tmpfiles[427]: /etc/tmpfiles.d/10-microvm.conf:3: Duplicate line for path "/var/lib/hermes-microvm/test/state-vault/state", ignoring. vm-test-run-test-machine> test-machine # [ 6.264513] systemd-tmpfiles[427]: /etc/tmpfiles.d/tmp.conf:11: Duplicate line for path "/tmp", ignoring. vm-test-run-test-machine> test-machine # [ 6.271162] systemd[1]: Finished Create System Files and Directories. vm-test-run-test-machine> test-machine # [ 6.273209] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-test-machine> test-machine # [ 6.274569] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-test-machine> test-machine # [ 6.293974] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-test-machine> test-machine # [ 6.310430] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-test-machine> test-machine # [ 6.312188] systemd[1]: Starting Update is Completed... vm-test-run-test-machine> test-machine # [ 6.329620] systemd[1]: Finished Update is Completed. vm-test-run-test-machine> test-machine # [ 6.463759] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 6.465362] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-test-machine> test-machine # [ 6.706370] systemd[1]: Finished register-nix-paths.service. vm-test-run-test-machine> test-machine # [ 6.707499] systemd[1]: Reached target System Initialization. vm-test-run-test-machine> test-machine # [ 6.708599] systemd[1]: Started hermes-microvm-timezone.path. vm-test-run-test-machine> test-machine # [ 6.709662] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-test-machine> test-machine # [ 6.710887] systemd[1]: Started nix-optimise.timer. vm-test-run-test-machine> test-machine # [ 6.711841] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-test-machine> test-machine # [ 6.713048] systemd[1]: Reached target Path Units. vm-test-run-test-machine> test-machine # [ 6.714100] systemd[1]: Reached target Timer Units. vm-test-run-test-machine> test-machine # [ 6.715048] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-test-machine> test-machine # [ 6.716270] systemd[1]: Listening on dashboard forward socket for hermes-test. vm-test-run-test-machine> test-machine # [ 6.717692] systemd[1]: Listening on spaces bridge socket for hermes-test. vm-test-run-test-machine> test-machine # [ 6.718930] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-test-machine> test-machine # [ 6.719905] systemd[1]: Listening on Authorization Manager Agent Helper. vm-test-run-test-machine> test-machine # [ 6.721226] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). vm-test-run-test-machine> test-machine # [ 6.723048] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. vm-test-run-test-machine> test-machine # [ 6.724726] systemd[1]: Reached target Socket Units. vm-test-run-test-machine> test-machine # [ 6.725715] systemd[1]: Reached target Basic System. vm-test-run-test-machine> test-machine # [ 6.726668] systemd[1]: Started backdoor.service. vm-test-run-test-machine> test-machine # [ 6.727589] systemd[1]: Started Enable Kernel Same-Page Merging. vm-test-run-test-machine> test-machine # [ 6.728662] systemd[1]: Starting Install MicroVM 'hermes-test'... vm-test-run-test-machine> test-machine # [ 6.731249] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-test-machine> test-machine # [ 6.732889] systemd[1]: Starting nftables firewall... vm-test-run-test-machine> test-machine # [ 6.735128] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-test-machine> test-machine # [ 6.738972] systemd[1]: Starting Post-Boot Actions... vm-test-run-test-machine> test-machine # [ 6.742041] systemd[1]: Started Reset console on configuration changes. vm-test-run-test-machine> test-machine # [ 6.745328] systemd[1]: Starting Stage Nix-managed integration profiles into per-user credential trees... vm-test-run-test-machine> test-machine # [ 6.749839] systemd[1]: Starting SSH Host Keys Generation... vm-test-run-test-machine> test-machine # [ 6.753745] systemd[1]: Started OpenAI-compatible mock LLM for the chat round-trip test. vm-test-run-test-machine> test-machine # [ 6.757885] systemd[1]: Starting Trigger udev change for TPM devices... vm-test-run-test-machine> test-machine # [ 6.763608] systemd[1]: enable-ksm.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 6.768703] systemd[1]: Finished Post-Boot Actions. vm-test-run-test-machine> test-machine # [ 6.779448] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-test-machine> test-machine # connecting to host... vm-test-run-test-machine> test-machine # [ 6.784718] systemd[1]: install-microvm-hermes-test.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 6.787259] agdfzqr05r7ys0idawh75qdaz9rx54zp-tpm2-udev-trigger.sh[525]: TPM udev rules changed, triggering udev vm-test-run-test-machine> test-machine # [ 6.790411] nsncd[515]: Jul 31 16:04:12.511 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-test-machine> test-machine # [ 6.794222] systemd[1]: Finished Install MicroVM 'hermes-test'. vm-test-run-test-machine> test-machine # [ 6.795470] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-test-machine> test-machine # [ 6.796410] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-test-machine> test-machine # [ 6.797157] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-test-machine> test-machine # [ 6.797851] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-test-machine> test-machine # [ 6.798700] systemd[1]: Setup MicroVM 'hermes-test' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-test/current/bin/pci-setup vm-test-run-test-machine> test-machine # [ 6.802060] systemd[1]: Starting Save MicroVM 'hermes-test' booted configuration... vm-test-run-test-machine> test-machine # [ 6.804856] systemd[1]: Starting User Login Management... vm-test-run-test-machine> test-machine # [ 6.807933] systemd[1]: Finished Trigger udev change for TPM devices. vm-test-run-test-machine> test-machine: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-test-machine> test-machine: connected to guest root shell vm-test-run-test-machine> test-machine: (connecting took 7.50 seconds) vm-test-run-test-machine> test-machine: (finished: waiting for the VM to finish booting, in 7.64 seconds) vm-test-run-test-machine> test-machine # [ 6.858148] systemd-logind[567]: New seat seat0. vm-test-run-test-machine> test-machine # [ 6.870173] dbus-broker-launch[545]: Looking up NSS user entry for 'systemd-timesync'... vm-test-run-test-machine> test-machine # [ 6.888231] systemd[1]: Finished Stage Nix-managed integration profiles into per-user credential trees. vm-test-run-test-machine> test-machine # [ 6.891704] dbus-broker-launch[545]: NSS returned no entry for 'systemd-timesync' vm-test-run-test-machine> test-machine # [ 6.893431] dbus-broker-launch[545]: Invalid user-name in /nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" vm-test-run-test-machine> test-machine # [ 6.896769] systemd-logind[567]: Watching system buttons on /dev/input/event2 (Power Button) vm-test-run-test-machine> test-machine # [ 6.898628] systemd-logind[567]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) vm-test-run-test-machine> test-machine # [ 6.901042] systemd-logind[567]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) vm-test-run-test-machine> test-machine # [ 6.915660] dbus-broker-launch[545]: Ignoring duplicate name 'org.freedesktop.UPower' in service file '/nix/store/i19avbz7x3jl3fjzqx7l6j5syp4frxzy-upower-1.91.1/share/dbus-1/system-services/org.freedesktop.UPower.service' vm-test-run-test-machine> test-machine # [ 6.917829] dbus-broker-launch[545]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/rsy2zjr37dllqkaw4q8v3fnfswqpg1vh-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' vm-test-run-test-machine> test-machine # [ 6.920088] systemd[1]: Started D-Bus System Message Bus. vm-test-run-test-machine> test-machine # [ 6.936628] dbus-broker-launch[545]: Ready vm-test-run-test-machine> test-machine # [ 6.939083] systemd[1]: Finished Save MicroVM 'hermes-test' booted configuration. vm-test-run-test-machine> test-machine # [ 6.949334] systemd[1]: Started User Login Management. vm-test-run-test-machine> test-machine # [ 6.960891] systemd[1]: Created slice Slice /user/1000. vm-test-run-test-machine> test-machine # [ 6.963122] systemd[1]: Starting linger-users.service... vm-test-run-test-machine> test-machine # [ 6.965756] systemd[1]: Setup MicroVM 'hermes-test' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-test/current/bin/macvtap-up vm-test-run-test-machine> test-machine # [ 6.968586] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-test'... vm-test-run-test-machine> test-machine # [ 6.970251] systemd[1]: Starting User Runtime Directory /run/user/1000... vm-test-run-test-machine> test-machine # [ 7.003495] systemd[1]: Finished User Runtime Directory /run/user/1000. vm-test-run-test-machine> test-machine # [ 7.006631] systemd[1]: Starting User Manager for UID 1000... vm-test-run-test-machine> test-machine # [ 7.015907] systemd[1]: Finished nftables firewall. vm-test-run-test-machine> test-machine # [ 7.019563] systemd[1]: Reached target Preparation for Network. vm-test-run-test-machine> test-machine # [ 7.020780] systemd[1]: Listening on Network Management Resolve Hook Socket. vm-test-run-test-machine> test-machine # [ 7.022749] systemd[1]: Starting Owner-match loopback firewall for hermes microvms... vm-test-run-test-machine> test-machine # [ 7.025252] systemd[1]: Starting Network Management... vm-test-run-test-machine> test-machine # [ 7.033143] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 7.035751] systemd[1]: Finished linger-users.service. vm-test-run-test-machine> test-machine # [ 7.045390] (systemd)[628]: pam_unix(systemd-user:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 7.061789] systemd-logind[567]: New session '1' of user 'test' with class 'manager' and type 'unspecified'. vm-test-run-test-machine> test-machine # [ 7.096870] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy vm-test-run-test-machine> test-machine # [ 7.112578] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. vm-test-run-test-machine> test-machine # [ 7.367382] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-test-machine> test-machine # [ 7.381126] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-test-machine> test-machine # [ 7.383266] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-test-machine> test-machine # [ 7.386182] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 vm-test-run-test-machine> test-machine # [ 7.388247] cfg80211: failed to load regulatory.db vm-test-run-test-machine> test-machine # [ 7.205728] sshd-keygen-start[570]: Generating public/private rsa key pair. vm-test-run-test-machine> test-machine # [ 7.206800] sshd-keygen-start[570]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key vm-test-run-test-machine> test-machine # [ 7.207738] sshd-keygen-start[570]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub vm-test-run-test-machine> test-machine # [ 7.209784] sshd-keygen-start[570]: The key fingerprint is: vm-test-run-test-machine> test-machine # [ 7.210988] sshd-keygen-start[570]: SHA256:qpvJMxV54veqAOvFsbjE2vmj1gBfLMc57Idc5bATHIQ root@test-machine vm-test-run-test-machine> test-machine # [ 7.211674] sshd-keygen-start[570]: The key's randomart image is: vm-test-run-test-machine> test-machine # [ 7.213278] sshd-keygen-start[570]: +---[RSA 4096]----+ vm-test-run-test-machine> test-machine # [ 7.213982] sshd-keygen-start[570]: | +o. | vm-test-run-test-machine> test-machine # [ 7.214648] sshd-keygen-start[570]: | E + . | vm-test-run-test-machine> test-machine # [ 7.214774] sshd-keygen-start[570]: | + . B | vm-test-run-test-machine> test-machine # [ 7.214878] sshd-keygen-start[570]: |. . O B o | vm-test-run-test-machine> test-machine # [ 7.214987] sshd-keygen-start[570]: | o.*.= =S | vm-test-run-test-machine> test-machine # [ 7.215122] sshd-keygen-start[570]: | .o=+o+.. | vm-test-run-test-machine> test-machine # [ 7.215230] sshd-keygen-start[570]: | =o=o.. . | vm-test-run-test-machine> test-machine # [ 7.215397] sshd-keygen-start[570]: | =.=== . | vm-test-run-test-machine> test-machine # [ 7.215502] sshd-keygen-start[570]: |..*oB=.... | vm-test-run-test-machine> test-machine # [ 7.215604] sshd-keygen-start[570]: +----[SHA256]-----+ vm-test-run-test-machine> test-machine # [ 7.231355] systemd-networkd[634]: lo: Link UP vm-test-run-test-machine> test-machine # [ 7.232420] systemd-networkd[634]: lo: Gained carrier vm-test-run-test-machine> test-machine # [ 7.235765] sshd-keygen-start[666]: Generating public/private ed25519 key pair. vm-test-run-test-machine> test-machine # [ 7.236789] sshd-keygen-start[666]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key vm-test-run-test-machine> test-machine # [ 7.238388] sshd-keygen-start[666]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub vm-test-run-test-machine> test-machine # [ 7.241672] sshd-keygen-start[666]: The key fingerprint is: vm-test-run-test-machine> test-machine # [ 7.242726] sshd-keygen-start[666]: SHA256:n8dOMOov3haVY2nOvXiOBa6arzKGfyOrW/zOc5eLhMA root@test-machine vm-test-run-test-machine> test-machine # [ 7.243738] sshd-keygen-start[666]: The key's randomart image is: vm-test-run-test-machine> test-machine # [ 7.244866] sshd-keygen-start[666]: +--[ED25519 256]--+ vm-test-run-test-machine> test-machine # [ 7.245783] sshd-keygen-start[666]: | | vm-test-run-test-machine> test-machine # [ 7.248236] sshd-keygen-start[666]: | | vm-test-run-test-machine> test-machine # [ 7.248897] sshd-keygen-start[666]: | o | vm-test-run-test-machine> test-machine # [ 7.249833] sshd-keygen-start[666]: | . B | vm-test-run-test-machine> test-machine # [ 7.250553] sshd-keygen-start[666]: | E S o*.o | vm-test-run-test-machine> test-machine # [ 7.251466] sshd-keygen-start[666]: | . . +.*o.. | vm-test-run-test-machine> test-machine # [ 7.252627] sshd-keygen-start[666]: | .o o +.=o.. | vm-test-run-test-machine> test-machine # [ 7.253547] sshd-keygen-start[666]: | ..=+=+o*+oo | vm-test-run-test-machine> test-machine # [ 7.254304] sshd-keygen-start[666]: | o=oOX@*.++. | vm-test-run-test-machine> test-machine # [ 7.255369] sshd-keygen-start[666]: +----[SHA256]-----+ vm-test-run-test-machine> test-machine # [ 7.256600] systemd[1]: Started Network Management. vm-test-run-test-machine> test-machine # [ 7.258074] systemd[1]: sshd-keygen.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 7.259380] systemd[1]: Finished SSH Host Keys Generation. vm-test-run-test-machine> test-machine # [ 7.260257] systemd[1]: Reached target Network. vm-test-run-test-machine> test-machine # [ 7.261449] systemd[1]: Setup MicroVM 'hermes-test' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-test/current/bin/tap-up vm-test-run-test-machine> test-machine # [ 7.262636] systemd-networkd[634]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. vm-test-run-test-machine> test-machine # [ 7.263840] systemd[1]: Starting SSH Daemon... vm-test-run-test-machine> test-machine # [ 7.264805] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... vm-test-run-test-machine> test-machine # [ 7.266883] systemd[1]: Starting Permit User Sessions... vm-test-run-test-machine> test-machine # [ 7.267858] systemd-networkd[634]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network vm-test-run-test-machine> test-machine # [ 7.268580] systemd-networkd[634]: eth1: Configuring with /etc/systemd/network/40-eth1.network. vm-test-run-test-machine> test-machine # [ 7.269369] systemd-networkd[634]: eth0: Link UP vm-test-run-test-machine> test-machine # [ 7.270210] systemd-networkd[634]: eth0: Gained carrier vm-test-run-test-machine> test-machine # [ 7.276969] python3[524]: mock-llm listening on http://127.0.0.1:8012 vm-test-run-test-machine> test-machine # [ 7.468137] NET: Registered PF_PACKET protocol family vm-test-run-test-machine> test-machine # [ 7.287091] systemd-networkd[634]: eth1: Link UP vm-test-run-test-machine> test-machine # [ 7.288322] systemd[1]: Finished Permit User Sessions. vm-test-run-test-machine> test-machine # [ 7.289714] systemd[1]: Started greetd.service. vm-test-run-test-machine> test-machine # [ 7.290764] systemd-networkd[634]: eth1: Gained carrier vm-test-run-test-machine> test-machine # [ 7.291585] systemd-networkd[634]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network vm-test-run-test-machine> test-machine # [ 7.304427] sshd[669]: Server listening on 0.0.0.0 port 22. vm-test-run-test-machine> test-machine # [ 7.305256] sshd[669]: Server listening on :: port 22. vm-test-run-test-machine> test-machine # [ 7.306040] systemd[1]: Started SSH Daemon. vm-test-run-test-machine> test-machine # [ 7.306777] systemd[1]: Finished Owner-match loopback firewall for hermes microvms. vm-test-run-test-machine> test-machine # [ 7.309121] systemd-networkd[634]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 vm-test-run-test-machine> test-machine # [ 7.320596] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. vm-test-run-test-machine> test-machine # [ 7.371232] systemd[628]: Queued start job for default target Main User Target. vm-test-run-test-machine> test-machine # [ 7.385767] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy vm-test-run-test-machine> test-machine # [ 7.386985] systemd[628]: Created slice User Application Slice. vm-test-run-test-machine> test-machine # [ 7.387902] systemd[628]: Started Periodically update pi-chat location. vm-test-run-test-machine> test-machine # [ 7.388983] systemd[628]: Started Daily Cleanup of User's Temporary Directories. vm-test-run-test-machine> test-machine # [ 7.389734] systemd[628]: Reached target Paths. vm-test-run-test-machine> test-machine # [ 7.390864] systemd[628]: Reached target Timers. vm-test-run-test-machine> test-machine # [ 7.391692] systemd[628]: Starting D-Bus User Message Bus Socket... vm-test-run-test-machine> test-machine # [ 7.393411] systemd[628]: Starting GCR ssh-agent wrapper... vm-test-run-test-machine> test-machine # [ 7.394850] systemd[628]: Listening on PipeWire PulseAudio. vm-test-run-test-machine> test-machine # [ 7.395806] systemd[628]: Listening on PipeWire Multimedia System Sockets. vm-test-run-test-machine> test-machine # [ 7.396738] systemd[628]: Listening on Speech Dispatcher Socket. vm-test-run-test-machine> test-machine # [ 7.397837] systemd[628]: Starting Create User Files and Directories... vm-test-run-test-machine> test-machine # [ 7.416413] systemd[628]: Finished Create User Files and Directories. vm-test-run-test-machine> test-machine # [ 7.417213] systemd[628]: Listening on GCR ssh-agent wrapper. vm-test-run-test-machine> test-machine # [ 7.440361] systemd[628]: Listening on D-Bus User Message Bus Socket. vm-test-run-test-machine> test-machine # [ 7.441499] systemd[628]: Reached target Sockets. vm-test-run-test-machine> test-machine # [ 7.442534] systemd[628]: Reached target Basic System. vm-test-run-test-machine> test-machine # [ 7.443354] systemd[1]: Started User Manager for UID 1000. vm-test-run-test-machine> test-machine # [ 7.444484] systemd[628]: Started GCR ssh-agent wrapper. vm-test-run-test-machine> test-machine # [ 7.445947] systemd[628]: Starting Run user-specific NixOS activation... vm-test-run-test-machine> test-machine # [ 7.447658] systemd[628]: Starting pi-sessiond token — per-login shared secret at /run/user/1000/pi-sessiond/token... vm-test-run-test-machine> test-machine # [ 7.457869] systemd[628]: Starting Spaces aggregating integration MCP gateway... vm-test-run-test-machine> test-machine # [ 7.460942] systemd[628]: Starting Spaces integrations broker (enable + secret provisioning over /run/user/1000/spaces-integrations.sock)... vm-test-run-test-machine> test-machine # [ 7.480233] systemd[628]: Finished pi-sessiond token — per-login shared secret at /run/user/1000/pi-sessiond/token. vm-test-run-test-machine> test-machine # [ 7.485095] systemd[628]: Starting pi-sessiond — per-user pi executor (WebSocket transport + one Landlock-confined pi rpc child per session)... vm-test-run-test-machine> test-machine # [ 7.488434] systemd[628]: Started Spaces aggregating integration MCP gateway. vm-test-run-test-machine> test-machine # [ 7.492301] systemd[628]: Started Spaces integrations broker (enable + secret provisioning over /run/user/1000/spaces-integrations.sock). vm-test-run-test-machine> test-machine # [ 7.496403] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:13,219 INFO Set uid to user 0 succeeded vm-test-run-test-machine> test-machine # [ 7.499311] systemd[628]: Finished Run user-specific NixOS activation. vm-test-run-test-machine> test-machine # [ 7.504549] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:13,228 INFO supervisord started with pid 636 vm-test-run-test-machine> test-machine # [ 7.525601] spaces-integrationd[693]: 2026/07/31 16:04:13 listening on /run/user/1000/spaces-integrations.sock (defs=/etc/spaces-integrations state=/home/test/.local/state/spaces-integrationd managed=/run/spaces-integrations-managed/test) vm-test-run-test-machine> test-machine # [ 7.526834] systemd[628]: Started pi-sessiond — per-user pi executor (WebSocket transport + one Landlock-confined pi rpc child per session). vm-test-run-test-machine> test-machine # [ 7.527986] systemd[628]: Reached target Main User Target. vm-test-run-test-machine> test-machine # [ 7.529487] systemd[628]: Started skill-config IPC daemon (pi-chat). vm-test-run-test-machine> test-machine # [ 7.530268] systemd[628]: Startup finished in 444ms. vm-test-run-test-machine> test-machine # [ 7.569808] skill-config-daemon[709]: 2026/07/31 16:04:13 listening on /run/user/1000/spaces-skill-config.sock (instance=unknown) vm-test-run-test-machine> test-machine # [ 8.429704] systemd-networkd[634]: eth1: Gained IPv6LL vm-test-run-test-machine> test-machine # [ 8.509546] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:14,232 INFO spawned: 'notify' with pid 747 vm-test-run-test-machine> test-machine # [ 8.512449] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:14,235 INFO spawned: 'virtiofsd-hermes-exchange' with pid 748 vm-test-run-test-machine> test-machine # [ 8.514909] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:14,238 INFO spawned: 'virtiofsd-hermes-state' with pid 749 vm-test-run-test-machine> test-machine # [ 8.521072] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:14,242 INFO spawned: 'virtiofsd-host-config' with pid 750 vm-test-run-test-machine> test-machine # [ 8.522932] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:14,246 INFO spawned: 'virtiofsd-ro-store' with pid 752 vm-test-run-test-machine> test-machine # [ 8.555428] supervisord[636]: virtiofsd-hermes-exchange [2026-07-31T16:04:14Z INFO virtiofsd] Waiting for vhost-user socket connection... vm-test-run-test-machine> test-machine # [ 8.556787] supervisord[636]: virtiofsd-hermes-state [2026-07-31T16:04:14Z INFO virtiofsd] Waiting for vhost-user socket connection... vm-test-run-test-machine> test-machine # [ 8.557718] supervisord[636]: virtiofsd-host-config [2026-07-31T16:04:14Z INFO virtiofsd] Waiting for vhost-user socket connection... vm-test-run-test-machine> test-machine # [ 8.558325] supervisord[636]: virtiofsd-ro-store [2026-07-31T16:04:14Z INFO virtiofsd] Waiting for vhost-user socket connection... vm-test-run-test-machine> test-machine # [ 8.661533] pi-sessiond[700]: pi-sessiond: listening on 127.0.0.1:8768 (executor host); agentDir=/home/test/.local/state/pi-sessiond/pi-agent models=2 vm-test-run-test-machine> test-machine # [ 8.685469] systemd-networkd[634]: eth0: Gained IPv6LL vm-test-run-test-machine> test-machine # [ 9.572635] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:15,295 INFO success: notify entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) vm-test-run-test-machine> test-machine # [ 9.573764] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:15,296 INFO success: virtiofsd-hermes-exchange entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) vm-test-run-test-machine> test-machine # [ 9.574600] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:15,296 INFO success: virtiofsd-hermes-state entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) vm-test-run-test-machine> test-machine # [ 9.575331] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:15,296 INFO success: virtiofsd-host-config entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) vm-test-run-test-machine> test-machine # [ 9.576096] microvm-virtiofsd@hermes-test[636]: 2026-07-31 16:04:15,296 INFO success: virtiofsd-ro-store entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) vm-test-run-test-machine> test-machine # [ 9.581251] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-test'. vm-test-run-test-machine> test-machine # [ 9.587818] systemd[1]: Starting MicroVM 'hermes-test'... vm-test-run-test-machine> test-machine # [ 9.739469] systemd[1]: Started MicroVM 'hermes-test'. vm-test-run-test-machine> test-machine # [ 9.740412] systemd[1]: Reached target microvms.target. vm-test-run-test-machine> test-machine # [ 9.741246] systemd[1]: Reached target Multi-User System. vm-test-run-test-machine> test-machine # [ 9.742275] systemd[1]: Reached target Graphical Interface. vm-test-run-test-machine> test-machine # [ 9.743172] systemd[1]: Startup finished in 732ms (kernel) + 3.340s (initrd) + 5.667s (userspace) = 9.740s. vm-test-run-test-machine> test-machine # [ 9.837958] greetd[828]: pam_unix(greetd:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 9.860694] systemd-logind[567]: New session '2' of user 'test' with class 'greeter' and type 'tty'. vm-test-run-test-machine> test-machine # [ 9.864267] systemd[1]: Started Session 2 of User test. vm-test-run-test-machine> test-machine # [ 9.908075] greetd[828]: gkr-pam: gnome-keyring-daemon started properly vm-test-run-test-machine> test-machine # [ 9.996908] supervisord[636]: virtiofsd-ro-store [2026-07-31T16:04:15Z INFO virtiofsd] Client connected, servicing requests vm-test-run-test-machine> test-machine # [ 9.998147] supervisord[636]: virtiofsd-hermes-exchange [2026-07-31T16:04:15Z INFO virtiofsd] Client connected, servicing requests vm-test-run-test-machine> test-machine # [ 9.998871] supervisord[636]: virtiofsd-hermes-state [2026-07-31T16:04:15Z INFO virtiofsd] Client connected, servicing requests vm-test-run-test-machine> test-machine # [ 9.999621] supervisord[636]: virtiofsd-host-config [2026-07-31T16:04:15Z INFO virtiofsd] Client connected, servicing requests vm-test-run-test-machine> test-machine # [ 10.005222] microvm@hermes-test[825]: microvm@hermes-test: warning: Number of SMP cpus requested (8) exceeds the recommended cpus supported by KVM (4) vm-test-run-test-machine> test-machine # [ 10.006564] microvm@hermes-test[825]: microvm@hermes-test: warning: Number of hotpluggable cpus requested (8) exceeds the recommended cpus supported by KVM (4) vm-test-run-test-machine> test-machine: (finished: waiting for unit multi-user.target, in 10.84 seconds) vm-test-run-test-machine> subtest: greetd autostarts the niri session vm-test-run-test-machine> test-machine: waiting for unit greetd.service vm-test-run-test-machine> test-machine: (finished: waiting for unit greetd.service, in 0.04 seconds) vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.wait_until_succeeds' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: waiting for success: systemctl is-active user@1000.service vm-test-run-test-machine> test-machine # [ 10.067307] systemd[628]: Created slice User Core Session Slice. vm-test-run-test-machine> test-machine # [ 10.069595] systemd[628]: Starting D-Bus User Message Bus... vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl is-active user@1000.service, in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: greetd autostarts the niri session, in 0.06 seconds) vm-test-run-test-machine> subtest: niri.service starts under the user manager vm-test-run-test-machine> test-machine: waiting for success: systemctl --user --machine=test@.host is-active niri.service vm-test-run-test-machine> test-machine # [ 10.103677] dbus-broker-launch[882]: Service file '/run/current-system/sw/share/dbus-1/services/fr.emersion.mako.service' is not named after the D-Bus name 'org.freedesktop.Notifications'. vm-test-run-test-machine> test-machine # [ 10.109494] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 10.118608] dbus-broker-launch[882]: Ignoring duplicate name 'ca.desrt.dconf' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/ca.desrt.dconf.service' vm-test-run-test-machine> test-machine # [ 10.122436] dbus-broker-launch[882]: Service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/fr.emersion.mako.service' is not named after the D-Bus name 'org.freedesktop.Notifications'. vm-test-run-test-machine> test-machine # [ 10.126465] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.Notifications' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/fr.emersion.mako.service' vm-test-run-test-machine> test-machine # [ 10.129554] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.PermissionStore' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.impl.portal.PermissionStore.service' vm-test-run-test-machine> test-machine # [ 10.134462] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.Secret' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.impl.portal.Secret.service' vm-test-run-test-machine> test-machine # [ 10.139786] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gnome' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gnome.service' vm-test-run-test-machine> test-machine # [ 10.144828] dbus-broker-launch[882]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored vm-test-run-test-machine> test-machine # [ 10.147682] dbus-broker-launch[882]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored vm-test-run-test-machine> test-machine # [ 10.150909] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gtk' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gtk.service' vm-test-run-test-machine> test-machine # [ 10.155471] microvm@hermes-test[825]: SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) vm-test-run-test-machine> test-machine # [ 10.157801] (systemd-stdio-bridge)[897]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 10.159845] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.import1.service' vm-test-run-test-machine> test-machine # [ 10.164575] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.machine1.service' vm-test-run-test-machine> test-machine # [ 10.168751] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.portable1.service' vm-test-run-test-machine> test-machine # [ 10.172890] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.portal.Desktop' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.portal.Desktop.service' vm-test-run-test-machine> test-machine # [ 10.176761] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.portal.Documents' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.portal.Documents.service' vm-test-run-test-machine> test-machine # [ 10.180613] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.secrets' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.secrets.service' vm-test-run-test-machine> test-machine # [ 10.184147] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' vm-test-run-test-machine> test-machine # [ 10.184847] dbus-broker-launch[882]: Ignoring duplicate name 'org.gnome.keyring' in service file '/nix/store/r98m1iw8p0l5mim1yq2fmb1gm3nk6pz9-system-path/share/dbus-1/services/org.gnome.keyring.service' vm-test-run-test-machine> test-machine # [ 10.185613] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.Secret' in service file '/nix/store/l5yyrpih8lsv7mqrqa33sy7ayg7bnh70-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.impl.portal.Secret.service' vm-test-run-test-machine> test-machine # [ 10.186193] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.secrets' in service file '/nix/store/l5yyrpih8lsv7mqrqa33sy7ayg7bnh70-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.secrets.service' vm-test-run-test-machine> test-machine # [ 10.186738] dbus-broker-launch[882]: Ignoring duplicate name 'org.gnome.keyring' in service file '/nix/store/l5yyrpih8lsv7mqrqa33sy7ayg7bnh70-gnome-keyring-50.0/share/dbus-1/services/org.gnome.keyring.service' vm-test-run-test-machine> test-machine # [ 10.187511] dbus-broker-launch[882]: Ignoring duplicate name 'ca.desrt.dconf' in service file '/nix/store/1q99v6wrhldqfwmv3gyvjxx3084ia9d9-dconf-0.49.0/share/dbus-1/services/ca.desrt.dconf.service' vm-test-run-test-machine> test-machine # [ 10.188104] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.PermissionStore' in service file '/nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/share/dbus-1/services/org.freedesktop.impl.portal.PermissionStore.service' vm-test-run-test-machine> test-machine # [ 10.188641] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.portal.Desktop' in service file '/nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/share/dbus-1/services/org.freedesktop.portal.Desktop.service' vm-test-run-test-machine> test-machine # [ 10.189228] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.portal.Documents' in service file '/nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/share/dbus-1/services/org.freedesktop.portal.Documents.service' vm-test-run-test-machine> test-machine # [ 10.189782] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.Secret' in service file '/nix/store/l5yyrpih8lsv7mqrqa33sy7ayg7bnh70-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.impl.portal.Secret.service' vm-test-run-test-machine> test-machine # [ 10.190519] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.secrets' in service file '/nix/store/l5yyrpih8lsv7mqrqa33sy7ayg7bnh70-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.secrets.service' vm-test-run-test-machine> test-machine # [ 10.191116] dbus-broker-launch[882]: Ignoring duplicate name 'org.gnome.keyring' in service file '/nix/store/l5yyrpih8lsv7mqrqa33sy7ayg7bnh70-gnome-keyring-50.0/share/dbus-1/services/org.gnome.keyring.service' vm-test-run-test-machine> test-machine # [ 10.191658] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gnome' in service file '/nix/store/a7qyl6ps2w3dx7jb786i08sxp01hn8vd-xdg-desktop-portal-gnome-50.0/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gnome.service' vm-test-run-test-machine> test-machine # [ 10.192254] dbus-broker-launch[882]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gtk' in service file '/nix/store/4qlmv2k6qahg6mbpcaa7gdrrw98w1852-xdg-desktop-portal-gtk-1.15.3/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gtk.service' vm-test-run-test-machine> test-machine # [ 10.192808] systemd[628]: Started D-Bus User Message Bus. vm-test-run-test-machine> test-machine # [ 10.193569] dbus-broker-launch[882]: Ready vm-test-run-test-machine> test-machine # [ 10.194158] systemd[628]: Reached target Session services which should run early before the graphical session is brought up. vm-test-run-test-machine> test-machine # [ 10.228661] systemd[1]: Started Session 3 of User test. vm-test-run-test-machine> test-machine # [ 10.230416] systemd[628]: Starting A scrollable-tiling Wayland compositor... vm-test-run-test-machine> test-machine # [ 10.240111] (systemd-stdio-bridge)[897]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 10.259183] systemd[1]: run-p896-i897.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 10.260567] (sd-pam)[905]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine # [ 10.267199] systemd[1]: session-3.scope: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 10.398583] niri[903]: 2026-07-31T16:04:16.120854Z INFO niri: starting version 26.04 (Nixpkgs) vm-test-run-test-machine> test-machine # [ 10.443343] niri[903]: 2026-07-31T16:04:16.166788Z DEBUG niri_config: loaded config from "/etc/niri/config.kdl" vm-test-run-test-machine> test-machine # [ 10.545040] microvm@hermes-test[825]: Booting from ROM... vm-test-run-test-machine> test-machine # [ 10.549110] microvm@hermes-test[825]: PPrroobbiinngg EEDDDD ((eedddd==ooffff ttoo ddiissaabbllee))...... ook vm-test-run-test-machine> test-machine # [ 10.644631] niri[903]: 2026-07-31T16:04:16.368179Z WARN niri::backend::tty: error getting the render node for the primary GPU; proceeding anyway vm-test-run-test-machine> test-machine # [ 10.645707] niri[903]: 2026-07-31T16:04:16.368499Z INFO niri::backend::tty: using as the render node: "/dev/dri/card0" vm-test-run-test-machine> test-machine # [ 10.772149] microvm@hermes-test[825]: No EFI environment detected. vm-test-run-test-machine> test-machine # [ 10.775763] microvm@hermes-test[825]: early console in extract_kernel vm-test-run-test-machine> test-machine # [ 10.778561] microvm@hermes-test[825]: input_data: 0x0000000003acf2cc vm-test-run-test-machine> test-machine # [ 10.781235] microvm@hermes-test[825]: input_len: 0x0000000000cb13c8 vm-test-run-test-machine> test-machine # [ 10.783696] microvm@hermes-test[825]: output: 0x0000000001000000 vm-test-run-test-machine> test-machine # [ 10.786403] microvm@hermes-test[825]: output_len: 0x000000000372858c vm-test-run-test-machine> test-machine # [ 10.789398] microvm@hermes-test[825]: kernel_total_size: 0x0000000002e30000 vm-test-run-test-machine> test-machine # [ 10.792188] microvm@hermes-test[825]: needed_size: 0x0000000003800000 vm-test-run-test-machine> test-machine # [ 10.795164] microvm@hermes-test[825]: trampoline_32bit: 0x0000000000000000 vm-test-run-test-machine> test-machine # [ 10.798353] microvm@hermes-test[825]: Physical KASLR using RDRAND RDTSC... vm-test-run-test-machine> test-machine # [ 10.801398] microvm@hermes-test[825]: Virtual KASLR using RDRAND RDTSC... vm-test-run-test-machine> test-machine # [ 10.802740] microvm@hermes-test[825]: vm-test-run-test-machine> test-machine # [ 10.805988] microvm@hermes-test[825]: Decompressing Linux... No EFI environment detected. vm-test-run-test-machine> test-machine # [ 10.871115] niri[903]: 2026-07-31T16:04:16.594588Z DEBUG niri::backend::tty: adding device: 57856 "/dev/dri/card0" vm-test-run-test-machine> test-machine # [ 10.871890] niri[903]: 2026-07-31T16:04:16.594893Z DEBUG niri::backend::tty: this is the primary node vm-test-run-test-machine> test-machine # [ 11.241722] niri[903]: pci id for fd 31: 1234:1111, driver (null) vm-test-run-test-machine> test-machine # [ 11.248061] niri[903]: pci id for fd 30: 1234:1111, driver (null) vm-test-run-test-machine> test-machine # [ 11.251211] niri[903]: pci id for fd 32: 1234:1111, driver (null) vm-test-run-test-machine> test-machine # [ 11.255284] niri[903]: pci id for fd 30: 1234:1111, driver (null) vm-test-run-test-machine> test-machine # [ 11.256992] niri[903]: pci id for fd 32: 1234:1111, driver (null) vm-test-run-test-machine> test-machine # [ 11.282085] niri[903]: 2026-07-31T16:04:17.005320Z DEBUG niri::backend::tty: failed to initialize renderer, falling back to primary gpu: software EGL renderers are skipped vm-test-run-test-machine> test-machine # [ 11.283826] niri[903]: 2026-07-31T16:04:17.007408Z WARN niri::backend::tty: error adding primary node device, display-only devices may not work: no allocator available for device vm-test-run-test-machine> test-machine # [ 11.285091] niri[903]: 2026-07-31T16:04:17.008704Z INFO niri: listening on Wayland socket: wayland-1 vm-test-run-test-machine> test-machine # [ 11.286431] niri[903]: 2026-07-31T16:04:17.008719Z INFO niri: IPC listening on: /run/user/1000/niri.wayland-1.903.sock vm-test-run-test-machine> test-machine # [ 11.293322] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 11.315236] niri[903]: 2026-07-31T16:04:17.038850Z INFO niri: listening on X11 socket: :0 vm-test-run-test-machine> test-machine # [ 11.323733] (systemd-stdio-bridge)[917]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 11.341323] systemd[1]: Started Session 4 of User test. vm-test-run-test-machine> test-machine # [ 11.356698] (systemd-stdio-bridge)[917]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 11.359218] systemd[1]: Starting Authorization Manager... vm-test-run-test-machine> test-machine # [ 11.373368] systemd[1]: run-p915-i916.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 11.374643] (sd-pam)[927]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine # [ 11.379746] systemd[1]: session-4.scope: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 11.425514] polkitd[926]: Started polkitd version 127 vm-test-run-test-machine> test-machine # [ 11.426711] microvm@hermes-test[825]: Parsing ELF... Performing relocations... done. vm-test-run-test-machine> test-machine # [ 11.430709] microvm@hermes-test[825]: Booting the kernel (entry_offset: 0x0000000002788520). vm-test-run-test-machine> test-machine # [ 11.444693] systemd[1]: Started Authorization Manager. vm-test-run-test-machine> test-machine # [ 11.448217] microvm@hermes-test[825]: [ 0.000000] Linux version 6.18.39 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Jul 18 14:53:38 UTC 2026 vm-test-run-test-machine> test-machine # [ 11.464900] microvm@hermes-test[825]: [ 0.000000] Command line: earlyprintk=ttyS0 console=ttyS0 reboot=t panic=-1 8250.nr_uarts=1 8250.nr_uarts=1 root=fstab loglevel=4 lsm=landlock,yama,bpf init=/nix/store/k6v0hian2hf69nzczs4slgyjyll1garx-nixos-system-hermes-test-26.11pre-git/init regInfo=/nix/store/k07vbby8477jf2nppfx3b1wvnh672zx8-closure-info/registration vm-test-run-test-machine> test-machine # [ 11.472252] microvm@hermes-test[825]: [ 0.000000] BIOS-provided physical RAM map: vm-test-run-test-machine> test-machine # [ 11.477045] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable vm-test-run-test-machine> test-machine # [ 11.482287] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved vm-test-run-test-machine> test-machine # [ 11.487434] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved vm-test-run-test-machine> test-machine # [ 11.492255] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffdfff] usable vm-test-run-test-machine> test-machine # [ 11.497322] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x00000000bfffe000-0x00000000bfffffff] reserved vm-test-run-test-machine> test-machine # [ 11.502430] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved vm-test-run-test-machine> test-machine # [ 11.505571] systemd[628]: Started A scrollable-tiling Wayland compositor. vm-test-run-test-machine> test-machine # [ 11.507789] systemd[628]: Reached target Current graphical user session. vm-test-run-test-machine> test-machine # [ 11.510147] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved vm-test-run-test-machine> test-machine # [ 11.510943] systemd[628]: Starting Certificate and Key Storage... vm-test-run-test-machine> test-machine # [ 11.513200] systemd[628]: Starting Secret Storage Service... vm-test-run-test-machine> test-machine # [ 11.516828] niri[903]: 2026-07-31T16:04:17.239914Z WARN niri::niri: failed to set logind LockedHint: failed to call SetLockedHint vm-test-run-test-machine> test-machine # [ 11.517547] niri[903]: Caused by: vm-test-run-test-machine> test-machine # [ 11.518267] niri[903]: org.freedesktop.DBus.Error.NotSupported: Session does not support lock screen. vm-test-run-test-machine> test-machine # [ 11.519787] systemd[1]: Starting Locale Service... vm-test-run-test-machine> test-machine # [ 11.524087] microvm@hermes-test[825]: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable vm-test-run-test-machine> test-machine # [ 11.526413] microvm@hermes-test[825]: [ 0.000000] printk: legacy bootconsole [earlyser0] enabled vm-test-run-test-machine> test-machine # [ 11.532185] systemd[628]: Starting pi-chat... vm-test-run-test-machine> test-machine # [ 11.533836] microvm@hermes-test[825]: Memory KASLR using RDRAND RDTSC... vm-test-run-test-machine> test-machine # [ 11.534763] systemd[628]: Started mako notification daemon. vm-test-run-test-machine> test-machine # [ 11.540747] systemd[628]: Starting Noctalia Wayland desktop shell... vm-test-run-test-machine> test-machine # [ 11.543417] systemd[628]: Starting pi-chat Quickshell panel... vm-test-run-test-machine> test-machine # [ 11.553069] systemd[628]: Started Forward desktop notifications to the pi-chat panel. vm-test-run-test-machine> test-machine # [ 11.556118] systemd[628]: Started VoxType push-to-talk voice-to-text daemon. vm-test-run-test-machine> test-machine # [ 11.571458] systemd[628]: Started Certificate and Key Storage. vm-test-run-test-machine> test-machine # [ 11.577513] systemd[628]: Started Secret Storage Service. vm-test-run-test-machine> test-machine # [ 11.591070] systemd[1]: Started Locale Service. vm-test-run-test-machine> test-machine # [ 11.599864] jrj7hk4n9vv33b5f15q5gq7c88b3i2vi-spaces-notify-forward[973]: /nix/store/jrj7hk4n9vv33b5f15q5gq7c88b3i2vi-spaces-notify-forward: line 26: n: unbound variable vm-test-run-test-machine> test-machine # [ 11.603240] gnome-keyring-daemon[975]: discover_other_daemon: 1 vm-test-run-test-machine> test-machine # [ 11.605799] gnome-keyring-daemon[975]: discover_other_daemon: 1 vm-test-run-test-machine> test-machine # [ 11.607871] systemd[628]: Started pi-chat. vm-test-run-test-machine> test-machine # [ 11.610222] systemd[628]: Reached target Startup of XDG autostart applications. vm-test-run-test-machine> test-machine # [ 11.612699] gnome-keyring-daemon[977]: discover_other_daemon: 1 vm-test-run-test-machine> test-machine # [ 11.614741] gnome-keyring-daemon[977]: discover_other_daemon: 1 vm-test-run-test-machine> test-machine # [ 11.701258] systemd[628]: Started pi-chat Quickshell panel. vm-test-run-test-machine> test-machine # [ 11.727979] voxtype-daemon[965]: 2026-07-31T16:04:17.451097Z INFO Using Energy VAD backend vm-test-run-test-machine> test-machine # [ 11.730557] voxtype-daemon[965]: 2026-07-31T16:04:17.451462Z INFO Voice Activity Detection enabled (backend: Energy, threshold: 0.40, min_speech: 100ms) vm-test-run-test-machine> test-machine # [ 11.734789] voxtype-daemon[965]: 2026-07-31T16:04:17.452641Z INFO Starting voxtype daemon vm-test-run-test-machine> test-machine # [ 11.761187] systemd[628]: Started Noctalia Wayland desktop shell. vm-test-run-test-machine> test-machine # [ 11.810053] voxtype-daemon[965]: 2026-07-31T16:04:17.533679Z INFO Audio level socket listening at "/run/user/1000/voxtype/audio.sock" vm-test-run-test-machine> test-machine # [ 11.812879] voxtype-daemon[965]: 2026-07-31T16:04:17.533702Z INFO OSD audio level socket: "/run/user/1000/voxtype/audio.sock" vm-test-run-test-machine> test-machine # [ 11.815218] voxtype-daemon[965]: 2026-07-31T16:04:17.534193Z INFO Output mode: Type vm-test-run-test-machine> test-machine # [ 11.817052] voxtype-daemon[965]: 2026-07-31T16:04:17.534207Z INFO State file: "/run/user/1000/voxtype/state" vm-test-run-test-machine> test-machine # [ 11.819533] voxtype-daemon[965]: 2026-07-31T16:04:17.534318Z INFO Built-in hotkey disabled, use 'voxtype record' commands or compositor keybindings vm-test-run-test-machine> test-machine # [ 11.822911] voxtype-daemon[965]: 2026-07-31T16:04:17.537233Z INFO Loading transcription model: /nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin vm-test-run-test-machine> test-machine # [ 11.826869] voxtype-daemon[965]: 2026-07-31T16:04:17.537341Z INFO Preloading primary model '/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin' vm-test-run-test-machine> test-machine # [ 11.830125] voxtype-daemon[965]: 2026-07-31T16:04:17.537468Z INFO Loading model '/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin' into cache vm-test-run-test-machine> test-machine # [ 11.833721] voxtype-daemon[965]: 2026-07-31T16:04:17.537689Z INFO Loading whisper model from "/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin" vm-test-run-test-machine> test-machine # [ 11.836882] voxtype-daemon[965]: whisper_init_from_file_with_params_no_state: loading model from '/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin' vm-test-run-test-machine> test-machine # [ 11.839734] voxtype-daemon[965]: whisper_init_with_params_no_state: use gpu = 1 vm-test-run-test-machine> test-machine # [ 11.841225] voxtype-daemon[965]: whisper_init_with_params_no_state: flash attn = 0 vm-test-run-test-machine> test-machine # [ 11.842625] voxtype-daemon[965]: whisper_init_with_params_no_state: gpu_device = 0 vm-test-run-test-machine> test-machine # [ 11.843981] voxtype-daemon[965]: whisper_init_with_params_no_state: dtw = 0 vm-test-run-test-machine> test-machine # [ 12.056194] quickshell[1015]: INFO: Launching config: "/home/test/.config/quickshell/pi-chat/shell.qml" vm-test-run-test-machine> test-machine # [ 12.058375] quickshell[1015]: INFO: Shell ID: "3a1085cb1f6a905eb4edc7542cb4c242" Path ID "3a1085cb1f6a905eb4edc7542cb4c242" vm-test-run-test-machine> test-machine # [ 12.060838] noctalia-shell[1041]: No running instances for "/nix/store/ac1l8ds1kdam7r1dngbkr66sbz60bfnm-noctalia-shell-4.7.7/share/noctalia-shell/shell.qml" vm-test-run-test-machine> test-machine # [ 12.064543] noctalia-shell[1041]: INFO: Launching config: "/nix/store/ac1l8ds1kdam7r1dngbkr66sbz60bfnm-noctalia-shell-4.7.7/share/noctalia-shell/shell.qml" vm-test-run-test-machine> test-machine # [ 12.068340] noctalia-shell[1041]: INFO: Shell ID: "18bad6197e59691d10c2121ce5971e8c" Path ID "18bad6197e59691d10c2121ce5971e8c" vm-test-run-test-machine> test-machine # [ 12.071934] noctalia-shell[1041]: INFO: Saving logs to "/run/user/1000/quickshell/by-id/xs5br1jt/log.qslog" vm-test-run-test-machine> test-machine # [ 12.074257] quickshell[1015]: INFO: Saving logs to "/run/user/1000/quickshell/by-id/7s5br1jt/log.qslog" vm-test-run-test-machine> test-machine # [ 12.101769] systemd[628]: Starting Portal service... vm-test-run-test-machine> test-machine # [ 12.116105] noctalia-shell[1041]: INFO qt.qpa.wayland: There are no outputs - creating placeholder screen vm-test-run-test-machine> test-machine # [ 12.118852] quickshell[1015]: INFO qt.qpa.wayland: There are no outputs - creating placeholder screen vm-test-run-test-machine> test-machine # [ 12.176094] voxtype-daemon[965]: ggml_vulkan: No devices found. vm-test-run-test-machine> test-machine # [ 12.179983] voxtype-daemon[965]: whisper_init_with_params_no_state: devices = 1 vm-test-run-test-machine> test-machine # [ 12.181963] voxtype-daemon[965]: whisper_init_with_params_no_state: backends = 2 vm-test-run-test-machine> test-machine # [ 12.183657] voxtype-daemon[965]: whisper_model_load: loading model vm-test-run-test-machine> test-machine # [ 12.185157] voxtype-daemon[965]: whisper_model_load: n_vocab = 51865 vm-test-run-test-machine> test-machine # [ 12.187051] voxtype-daemon[965]: whisper_model_load: n_audio_ctx = 1500 vm-test-run-test-machine> test-machine # [ 12.188406] voxtype-daemon[965]: whisper_model_load: n_audio_state = 768 vm-test-run-test-machine> test-machine # [ 12.189895] voxtype-daemon[965]: whisper_model_load: n_audio_head = 12 vm-test-run-test-machine> test-machine # [ 12.191087] voxtype-daemon[965]: whisper_model_load: n_audio_layer = 12 vm-test-run-test-machine> test-machine # [ 12.192482] voxtype-daemon[965]: whisper_model_load: n_text_ctx = 448 vm-test-run-test-machine> test-machine # [ 12.194781] voxtype-daemon[965]: whisper_model_load: n_text_state = 768 vm-test-run-test-machine> test-machine # [ 12.196223] voxtype-daemon[965]: whisper_model_load: n_text_head = 12 vm-test-run-test-machine> test-machine # [ 12.197490] voxtype-daemon[965]: whisper_model_load: n_text_layer = 12 vm-test-run-test-machine> test-machine # [ 12.198625] voxtype-daemon[965]: whisper_model_load: n_mels = 80 vm-test-run-test-machine> test-machine # [ 12.199875] voxtype-daemon[965]: whisper_model_load: ftype = 1 vm-test-run-test-machine> test-machine # [ 12.201163] voxtype-daemon[965]: whisper_model_load: qntvr = 0 vm-test-run-test-machine> test-machine # [ 12.202418] voxtype-daemon[965]: whisper_model_load: type = 3 (small) vm-test-run-test-machine> test-machine # [ 12.220159] systemd[628]: Starting sandboxed app permission store... vm-test-run-test-machine> test-machine # [ 12.227857] voxtype-daemon[965]: whisper_model_load: adding 1608 extra tokens vm-test-run-test-machine> test-machine # [ 12.229241] voxtype-daemon[965]: whisper_model_load: n_langs = 99 vm-test-run-test-machine> test-machine # [ 12.230737] voxtype-daemon[965]: whisper_model_load: CPU total size = 487.01 MB vm-test-run-test-machine> test-machine # [ 12.237060] quickshell[1015]: libEGL warning: failed to get driver name for fd -1 vm-test-run-test-machine> test-machine # [ 12.239117] quickshell[1015]: libEGL warning: MESA-LOADER: failed to retrieve device information vm-test-run-test-machine> test-machine # [ 12.240503] quickshell[1015]: libEGL warning: failed to get driver name for fd -1 vm-test-run-test-machine> test-machine # [ 12.271476] systemd[628]: Started sandboxed app permission store. vm-test-run-test-machine> test-machine # [ 12.291451] systemd[628]: Starting flatpak document portal service... vm-test-run-test-machine> test-machine # [ 12.354404] systemd[628]: Started flatpak document portal service. vm-test-run-test-machine> test-machine # [ 12.356201] niri[903]: 2026-07-31T16:04:18.079534Z DEBUG niri::utils::xwayland::satellite: connection to X11 abstract socket; spawning xwayland-satellite vm-test-run-test-machine> test-machine # [ 12.359693] xdg-document-portal[1061]: Failed to call 'fusermount3': No such file or directory vm-test-run-test-machine> test-machine # [ 12.361212] xdg-document-portal[1061]: posix_spawn(p)() for fusermount3 failed: No such file or directoryFailed to call 'fusermount3': No such file or directory vm-test-run-test-machine> test-machine # [ 12.362165] xdg-document-portal[1061]: posix_spawn(p)() for fusermount3 failed: No such file or directoryerror: fuse init failed: Can't mount path /run/user/1000/doc vm-test-run-test-machine> test-machine # [ 12.369377] /nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1047]: Document portal fuse mount point unknown: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable vm-test-run-test-machine> test-machine # [ 12.374693] systemd[628]: xdg-document-portal.service: Main process exited, code=exited, status=6/NOTCONFIGURED vm-test-run-test-machine> test-machine # [ 12.378213] systemd[628]: xdg-document-portal.service: Failed with result 'exit-code'. vm-test-run-test-machine> test-machine # [ 12.385874] systemd[628]: Starting Portal service (GNOME implementation)... vm-test-run-test-machine> test-machine # [ 12.408751] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 12.445349] (systemd-stdio-bridge)[1082]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 12.461904] systemd[1]: Started Session 5 of User test. vm-test-run-test-machine> test-machine # [ 12.477659] (systemd-stdio-bridge)[1082]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 12.499408] systemd[1]: run-p1081-i1082.service: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active niri.service, in 2.42 seconds) vm-test-run-test-machine> test-machine # [ 12.503093] (sd-pam)[1085]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> (finished: subtest: niri.service starts under the user manager, in 2.42 seconds) vm-test-run-test-machine> subtest: niri exposes its Wayland socket vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.wait_for_file' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: waiting for file '/run/user/1000/wayland-1' vm-test-run-test-machine> test-machine # [ 12.512574] systemd[1]: session-5.scope: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: waiting for file '/run/user/1000/wayland-1', in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: niri exposes its Wayland socket, in 0.02 seconds) vm-test-run-test-machine> subtest: shell config materialized with fresh mtimes vm-test-run-test-machine> test-machine: waiting for success: test -f /home/test/.config/quickshell/pi-chat/shell.qml && test ! -L /home/test/.config/quickshell/pi-chat vm-test-run-test-machine> test-machine: (finished: waiting for success: test -f /home/test/.config/quickshell/pi-chat/shell.qml && test ! -L /home/test/.config/quickshell/pi-chat, in 0.02 seconds) vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.succeed' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: must succeed: stat -c %Y /home/test/.config/quickshell/pi-chat/shell.qml vm-test-run-test-machine> test-machine: (finished: must succeed: stat -c %Y /home/test/.config/quickshell/pi-chat/shell.qml, in 0.01 seconds) vm-test-run-test-machine> (finished: subtest: shell config materialized with fresh mtimes, in 0.03 seconds) vm-test-run-test-machine> subtest: pi-chat.service is running vm-test-run-test-machine> test-machine: waiting for success: systemctl --user --machine=test@.host is-active pi-chat.service vm-test-run-test-machine> test-machine # [ 12.579109] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 12.616565] (systemd-stdio-bridge)[1106]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 12.628463] systemd[1]: Started Session 6 of User test. vm-test-run-test-machine> test-machine # [ 12.642517] (systemd-stdio-bridge)[1106]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active pi-chat.service, in 0.11 seconds) vm-test-run-test-machine> test-machine: waiting for success: test -d /run/user/1000/quickshell vm-test-run-test-machine> test-machine # [ 12.661518] (sd-pam)[1110]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine # [ 12.663555] systemd[1]: run-p1105-i1106.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 12.675479] systemd[1]: session-6.scope: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: waiting for success: test -d /run/user/1000/quickshell, in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: pi-chat.service is running, in 0.13 seconds) vm-test-run-test-machine> subtest: pi-chat sidecar services come up vm-test-run-test-machine> test-machine: waiting for success: systemctl --user --machine=test@.host is-active spaces-skill-config-daemon.service vm-test-run-test-machine> test-machine # [ 12.710911] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 12.732950] quickshell[1015]: MESA: error: ZINK: failed to choose pdev vm-test-run-test-machine> test-machine # [ 12.737343] quickshell[1015]: libEGL warning: egl: failed to create dri2 screen vm-test-run-test-machine> test-machine # [ 12.746578] (systemd-stdio-bridge)[1128]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 12.761325] systemd[1]: Started Session 7 of User test. vm-test-run-test-machine> test-machine # [ 12.776918] (systemd-stdio-bridge)[1128]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active spaces-skill-config-daemon.service, in 0.11 seconds) vm-test-run-test-machine> test-machine: waiting for file '/run/user/1000/spaces-skill-config.sock' vm-test-run-test-machine> test-machine # [ 12.805591] (sd-pam)[1134]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine # [ 12.808351] systemd[1]: run-p1126-i1127.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 12.810896] systemd[1]: session-7.scope: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: waiting for file '/run/user/1000/spaces-skill-config.sock', in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: pi-chat sidecar services come up, in 0.13 seconds) vm-test-run-test-machine> subtest: daemon agent config is staged (settings + skills + allowlist) vm-test-run-test-machine> test-machine: waiting for success: test -f /home/test/.local/state/pi-sessiond/pi-agent/settings.json vm-test-run-test-machine> test-machine: (finished: waiting for success: test -f /home/test/.local/state/pi-sessiond/pi-agent/settings.json, in 0.02 seconds) vm-test-run-test-machine> test-machine: must succeed: grep -q 'skills' /home/test/.local/state/pi-sessiond/pi-agent/settings.json vm-test-run-test-machine> test-machine: (finished: must succeed: grep -q 'skills' /home/test/.local/state/pi-sessiond/pi-agent/settings.json, in 0.02 seconds) vm-test-run-test-machine> test-machine: must succeed: test -f /home/test/.local/state/pi-sessiond/pi-agent/bash-confirm.json vm-test-run-test-machine> test-machine: (finished: must succeed: test -f /home/test/.local/state/pi-sessiond/pi-agent/bash-confirm.json, in 0.01 seconds) vm-test-run-test-machine> (finished: subtest: daemon agent config is staged (settings + skills + allowlist), in 0.05 seconds) vm-test-run-test-machine> subtest: llama-swap is up vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.wait_for_open_port' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: waiting for TCP port 8012 on localhost vm-test-run-test-machine> test-machine # Connection to localhost (127.0.0.1) 8012 port [tcp/*] succeeded! vm-test-run-test-machine> test-machine: (finished: waiting for TCP port 8012 on localhost, in 0.05 seconds) vm-test-run-test-machine> (finished: subtest: llama-swap is up, in 0.05 seconds) vm-test-run-test-machine> subtest: shell IPC target is registered vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.execute' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine # [ 12.967357] sudo[1163]: root : TTY=hvc0 ; PWD=/tmp ; USER=test ; ENV=HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 ; COMMAND=/run/current-system/sw/bin/quickshell ipc -c pi-chat show vm-test-run-test-machine> test-machine # [ 12.974359] sudo[1163]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 13.380887] quickshell[1015]: INFO: Configuration Loaded vm-test-run-test-machine> test-machine # [ 13.461087] quickshell[1015]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x71c5da20a620) vm-test-run-test-machine> test-machine # [ 13.464387] quickshell[1015]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x71c5eb89a280) vm-test-run-test-machine> test-machine # [ 13.467351] quickshell[1015]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x71c5da336440) vm-test-run-test-machine> test-machine # [ 13.486211] sudo[1163]: pam_unix(sudo:session): session closed for user test vm-test-run-test-machine> test-machine: must succeed: sudo -u test HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 quickshell ipc -c pi-chat call pi-chat listSessions vm-test-run-test-machine> test-machine # [ 13.517953] sudo[1173]: root : TTY=hvc0 ; PWD=/tmp ; USER=test ; ENV=HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 ; COMMAND=/run/current-system/sw/bin/quickshell ipc -c pi-chat call pi-chat listSessions vm-test-run-test-machine> test-machine # [ 13.526064] sudo[1173]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 13.619888] sudo[1173]: pam_unix(sudo:session): session closed for user test vm-test-run-test-machine> test-machine: (finished: must succeed: sudo -u test HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 quickshell ipc -c pi-chat call pi-chat listSessions, in 0.13 seconds) vm-test-run-test-machine> (finished: subtest: shell IPC target is registered, in 0.70 seconds) vm-test-run-test-machine> subtest: chat round-trip through shell IPC vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.copy_from_host' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: must succeed: mkdir -p /tmp/shared/tmp35had_54 vm-test-run-test-machine> test-machine: (finished: must succeed: mkdir -p /tmp/shared/tmp35had_54, in 0.01 seconds) vm-test-run-test-machine> test-machine: must succeed: mkdir -p /tmp vm-test-run-test-machine> test-machine: (finished: must succeed: mkdir -p /tmp, in 0.01 seconds) vm-test-run-test-machine> test-machine: must succeed: cp -r /tmp/shared/tmp35had_54/65dqfr1ss7dlzzb6szkriqfn3ds1vflk-test-pi-chat.py /tmp/test-pi-chat.py vm-test-run-test-machine> test-machine: (finished: must succeed: cp -r /tmp/shared/tmp35had_54/65dqfr1ss7dlzzb6szkriqfn3ds1vflk-test-pi-chat.py /tmp/test-pi-chat.py, in 0.02 seconds) vm-test-run-test-machine> test-machine # [ 13.700781] sudo[1194]: root : TTY=hvc0 ; PWD=/tmp ; USER=test ; ENV=HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 ; COMMAND=/run/current-system/sw/bin/python3 /tmp/test-pi-chat.py quickshell pi-chat pi-chat local vm-test-run-test-machine> test-machine # [ 13.711472] sudo[1194]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 13.754221] microvm@hermes-test[825]: Poking KASLR using RDRAND RDTSC... vm-test-run-test-machine> test-machine # [ 13.845269] systemd[628]: Started Portal service (GNOME implementation). vm-test-run-test-machine> test-machine # [ 13.913265] /nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1047]: Failed to load RealtimeKit property: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable vm-test-run-test-machine> test-machine # [ 13.918447] /nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1047]: Failed to load RealtimeKit property: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable vm-test-run-test-machine> test-machine # [ 13.923058] /nix/store/1sjg4vq0h07y6gadmah0xs2jgf1p6qm4-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1047]: Failed to load RealtimeKit property: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable vm-test-run-test-machine> test-machine # [ 13.936402] systemd[628]: Starting Portal service (GTK/GNOME implementation)... vm-test-run-test-machine> test-machine # [ 13.952925] noctalia-shell[1041]: libEGL warning: failed to get driver name for fd -1 vm-test-run-test-machine> test-machine # [ 13.955317] noctalia-shell[1041]: libEGL warning: MESA-LOADER: failed to retrieve device information vm-test-run-test-machine> test-machine # [ 13.957456] noctalia-shell[1041]: libEGL warning: failed to get driver name for fd -1 vm-test-run-test-machine> test-machine # [ 14.035299] microvm@hermes-test[825]: [ 2.436433] PCI: Fatal: No config space access function found vm-test-run-test-machine> test-machine # [ 14.110988] noctalia-shell[1041]: MESA: error: ZINK: failed to choose pdev vm-test-run-test-machine> test-machine # [ 14.113071] noctalia-shell[1041]: libEGL warning: egl: failed to create dri2 screen vm-test-run-test-machine> test-machine # [ 14.182694] systemd[628]: Starting [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/02c78d84-e7e2-4a6c-9206-d5af207d94a2/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/02c78d84-e7e2-4a6c-9206-d5af207d94a2 --session-id 02c78d84-e7e2-4a6c-9206-d5af207d94a2 --provider local --model qwen2.5:0.5b --name "Chat 1"... vm-test-run-test-machine> test-machine # [ 14.239127] systemd[628]: Started Portal service (GTK/GNOME implementation). vm-test-run-test-machine> test-machine # [ 14.256553] systemd[628]: Started [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/02c78d84-e7e2-4a6c-9206-d5af207d94a2/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/02c78d84-e7e2-4a6c-9206-d5af207d94a2 --session-id 02c78d84-e7e2-4a6c-9206-d5af207d94a2 --provider local --model qwen2.5:0.5b --name "Chat 1". vm-test-run-test-machine> test-machine # [ 14.261055] pi-sessiond[1219]: landlock-exec: skipped absent path /etc/static/ssl vm-test-run-test-machine> test-machine # [ 14.261664] pi-sessiond[1219]: landlock-exec: domain fully enforced (kernel Landlock ABI 7) vm-test-run-test-machine> test-machine # [ 14.312181] systemd[628]: Started PipeWire Multimedia Service. vm-test-run-test-machine> test-machine # [ 14.314137] systemd[628]: Started Multimedia Service Session Manager. vm-test-run-test-machine> test-machine # [ 14.383142] pipewire[1223]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown vm-test-run-test-machine> test-machine # [ 14.386724] pipewire[1223]: mod.rt: RTKit does not give us MaxRealtimePriority, using 1 vm-test-run-test-machine> test-machine # [ 14.395390] pipewire[1223]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown vm-test-run-test-machine> test-machine # [ 14.397941] pipewire[1223]: mod.rt: RTKit does not give us MinNiceLevel, using 0 vm-test-run-test-machine> test-machine # [ 14.400277] pipewire[1223]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown vm-test-run-test-machine> test-machine # [ 14.402718] pipewire[1223]: mod.rt: RTKit does not give us RTTimeUSecMax, using -1 vm-test-run-test-machine> test-machine # [ 14.435326] wireplumber[1224]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown vm-test-run-test-machine> test-machine # [ 14.438228] wireplumber[1224]: mod.rt: RTKit does not give us MaxRealtimePriority, using 1 vm-test-run-test-machine> test-machine # [ 14.440739] wireplumber[1224]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown vm-test-run-test-machine> test-machine # [ 14.442929] wireplumber[1224]: mod.rt: RTKit does not give us MinNiceLevel, using 0 vm-test-run-test-machine> test-machine # [ 14.445108] wireplumber[1224]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown vm-test-run-test-machine> test-machine # [ 14.447224] wireplumber[1224]: mod.rt: RTKit does not give us RTTimeUSecMax, using -1 vm-test-run-test-machine> test-machine # [ 14.542899] systemd[628]: Started Portal service. vm-test-run-test-machine> test-machine # [ 14.574884] quickshell[1015]: WARN qt.qpa.services: Failed to register with host portal QDBusError("org.freedesktop.portal.Error.Failed", "Could not register app ID: Connection already associated with an application ID") vm-test-run-test-machine> test-machine # [ 14.801097] wireplumber[1224]: default: Failed to get percentage from UPower: org.freedesktop.DBus.Error.NameHasNoOwner vm-test-run-test-machine> test-machine # [ 14.805332] wireplumber[1224]: spa.bluez5: BlueZ system service is not available vm-test-run-test-machine> test-machine # [ 14.950860] wireplumber[1224]: [0:00:14.950687485] [1224] INFO IPAManager ipa_manager.cpp:147 libcamera is not installed. Adding '/nix/store/src/ipa' to the IPA search path vm-test-run-test-machine> test-machine # [ 14.962676] wireplumber[1224]: [0:00:14.962585925] [1224] INFO Camera camera_manager.cpp:340 libcamera v0.7.0 vm-test-run-test-machine> test-machine # [ 15.352286] noctalia-shell[1041]: INFO qml: [20260731-160421] NiriService Service started vm-test-run-test-machine> test-machine # [ 15.372850] noctalia-shell[1041]: INFO qml: [20260731-160421] I18n Service started vm-test-run-test-machine> test-machine # [ 15.375262] noctalia-shell[1041]: INFO qml: [20260731-160421] I18n Loading "en" (locale: "en-Latn-US") vm-test-run-test-machine> test-machine # [ 15.379139] noctalia-shell[1041]: INFO qml: [20260731-160421] Shell --------------------------- vm-test-run-test-machine> test-machine # [ 15.381521] noctalia-shell[1041]: INFO qml: [20260731-160421] Shell Noctalia Hello! vm-test-run-test-machine> test-machine # [ 15.386795] noctalia-shell[1041]: INFO: Configuration Loaded vm-test-run-test-machine> test-machine # [ 15.403747] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Settings loaded vm-test-run-test-machine> test-machine # [ 15.404766] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings adapter.settingsVersion: 0 vm-test-run-test-machine> test-machine # [ 15.405572] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v27 vm-test-run-test-machine> test-machine # [ 15.406443] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v28 vm-test-run-test-machine> test-machine # [ 15.407853] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v32 vm-test-run-test-machine> test-machine # [ 15.408692] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v33 vm-test-run-test-machine> test-machine # [ 15.409426] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Set default wallpaperChangeMode=random vm-test-run-test-machine> test-machine # [ 15.410708] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v35 vm-test-run-test-machine> test-machine # [ 15.411477] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v36 vm-test-run-test-machine> test-machine # [ 15.412111] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Cleared legacy emoji usage file at: /home/test/.cache/noctalia/emoji_usage.json vm-test-run-test-machine> test-machine # [ 15.412922] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v37 (WiFi → Network) vm-test-run-test-machine> test-machine # [ 15.413896] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings No WiFi widget IDs found to migrate; leaving settings unchanged vm-test-run-test-machine> test-machine # [ 15.414721] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration38 Migrating bar margins from percentages to integers vm-test-run-test-machine> test-machine # [ 15.415605] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration39 Migrating templates from boolean format to activeTemplates array vm-test-run-test-machine> test-machine # [ 15.416273] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration42 Migrating randomEnabled to automationEnabled vm-test-run-test-machine> test-machine # [ 15.417125] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration43 Migrating recursiveSearch to viewMode vm-test-run-test-machine> test-machine # [ 15.417586] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration44 Updating PAM pam/password.conf vm-test-run-test-machine> test-machine # [ 15.428671] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration45 Migrating bar settings... vm-test-run-test-machine> test-machine # [ 15.429237] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration46 Removing legacy PAM configuration file vm-test-run-test-machine> test-machine # [ 15.446829] noctalia-shell[1041]: INFO qml: [20260731-160421] Migration47 Removing network_stats.json cache vm-test-run-test-machine> test-machine # [ 15.449682] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v48 vm-test-run-test-machine> test-machine # [ 15.451075] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v49 vm-test-run-test-machine> test-machine # [ 15.457756] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Removed old launcher_app_usage.json vm-test-run-test-machine> test-machine # [ 15.459616] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v50 vm-test-run-test-machine> test-machine # [ 15.460753] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v53 vm-test-run-test-machine> test-machine # [ 15.461864] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v54 vm-test-run-test-machine> test-machine # [ 15.463585] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v55 vm-test-run-test-machine> test-machine # [ 15.464651] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v56 (Color Scheme Migration) vm-test-run-test-machine> test-machine # [ 15.465513] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Running color scheme migration script: /nix/store/ac1l8ds1kdam7r1dngbkr66sbz60bfnm-noctalia-shell-4.7.7/share/noctalia-shell/Scripts/python/src/theming/migrate-colorschemes.py with configDir: /home/test/.config/noctalia/ vm-test-run-test-machine> test-machine # [ 15.469178] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v57 (cavaFrameRate -> spectrumFrameRate) vm-test-run-test-machine> test-machine # [ 15.470621] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v58 (dock.dockType: static -> attached) vm-test-run-test-machine> test-machine # [ 15.471950] noctalia-shell[1041]: INFO qml: [20260731-160421] Settings Migrating settings to v59 (wallpaper.transitionType: string -> array) vm-test-run-test-machine> test-machine # [ 15.484953] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry Loaded plugin states from: /home/test/.config/noctalia/plugins.json vm-test-run-test-machine> test-machine # [ 15.486101] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry Migrating plugin data to v2 (adding sourceUrl) vm-test-run-test-machine> test-machine # [ 15.486908] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry Migration complete vm-test-run-test-machine> test-machine # [ 15.487945] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry Scanning plugin folder: /home/test/.config/noctalia/plugins vm-test-run-test-machine> test-machine # [ 15.493082] noctalia-shell[1041]: INFO qml: [20260731-160421] I18n Loaded translations for "en" vm-test-run-test-machine> test-machine # [ 15.496123] noctalia-shell[1041]: INFO qml: [20260731-160421] KeyboardLayout Service started vm-test-run-test-machine> test-machine # [ 15.539765] noctalia-shell[1041]: INFO qml: [20260731-160421] Icons Service started vm-test-run-test-machine> test-machine # [ 15.644865] noctalia-shell[1041]: INFO qml: [20260731-160421] Brightness Service started vm-test-run-test-machine> test-machine # [ 15.760111] noctalia-shell[1041]: WARN quickshell.service.notifications: Could not register notification server at org.freedesktop.Notifications, presumably because one is already registered. vm-test-run-test-machine> test-machine # [ 15.763810] noctalia-shell[1041]: WARN quickshell.service.notifications: Registration will be attempted again if the active service is unregistered. vm-test-run-test-machine> test-machine # [ 15.768308] noctalia-shell[1041]: INFO qml: [20260731-160421] DesktopWidgetR Service started vm-test-run-test-machine> test-machine # [ 15.775952] noctalia-shell[1041]: INFO qml: [20260731-160421] BarService Service started vm-test-run-test-machine> test-machine # [ 15.795956] noctalia-shell[1041]: INFO qml: [20260731-160421] Shell --------------------------- vm-test-run-test-machine> test-machine # [ 15.798474] noctalia-shell[1041]: INFO qml: [20260731-160421] Wallpaper Service started vm-test-run-test-machine> test-machine # [ 15.800633] noctalia-shell[1041]: INFO qml: [20260731-160421] ImageCache Service started vm-test-run-test-machine> test-machine # [ 15.820734] noctalia-shell[1041]: INFO qml: [20260731-160421] AppThemeServic Service started vm-test-run-test-machine> test-machine # [ 15.825901] noctalia-shell[1041]: INFO qml: [20260731-160421] ColorScheme Service started vm-test-run-test-machine> test-machine # [ 15.828503] noctalia-shell[1041]: INFO qml: [20260731-160421] DarkModeServic Service started vm-test-run-test-machine> test-machine # [ 15.832284] noctalia-shell[1041]: INFO qml: [20260731-160421] SystemStat Service started (polling deferred until a consumer registers). vm-test-run-test-machine> test-machine # [ 15.841895] noctalia-shell[1041]: WARN qt.qpa.services: Failed to register with host portal QDBusError("org.freedesktop.portal.Error.Failed", "Could not register app ID: App info not found for 'dev.noctalia.noctalia-qs'") vm-test-run-test-machine> test-machine # [ 15.846749] noctalia-shell[1041]: INFO qml: [20260731-160421] Location Service started vm-test-run-test-machine> test-machine # [ 15.848984] noctalia-shell[1041]: INFO qml: [20260731-160421] IdleInhibitor Service started vm-test-run-test-machine> test-machine # [ 15.851555] noctalia-shell[1041]: INFO qml: [20260731-160421] IdleService Service started vm-test-run-test-machine> test-machine # [ 15.855809] noctalia-shell[1041]: INFO qml: [20260731-160421] HostService Service started vm-test-run-test-machine> test-machine # [ 15.858262] noctalia-shell[1041]: INFO qml: [20260731-160421] GitHub Service started vm-test-run-test-machine> test-machine # [ 15.860526] noctalia-shell[1041]: INFO qml: [20260731-160421] Supporter Service started vm-test-run-test-machine> test-machine # [ 15.862835] noctalia-shell[1041]: INFO qml: [20260731-160421] CustomButtonIP Service started vm-test-run-test-machine> test-machine # [ 15.865173] noctalia-shell[1041]: INFO qml: [20260731-160421] IPCService Service started vm-test-run-test-machine> test-machine # [ 15.867963] noctalia-shell[1041]: WARN qt.qpa.services: Failed to register with host portal QDBusError("org.freedesktop.portal.Error.Failed", "Could not register app ID: App info not found for 'dev.noctalia.noctalia-qs'") vm-test-run-test-machine> test-machine # [ 15.873277] noctalia-shell[1041]: WARN quickshell.dbus: Could not launch service org.freedesktop.UPower.PowerProfiles: QDBusError(org.freedesktop.DBus.Error.ServiceUnknown, The name is not activatable) vm-test-run-test-machine> test-machine # [ 15.879124] noctalia-shell[1041]: WARN quickshell.service.powerprofiles: Could not start PowerProfilesDaemon. The PowerProfiles service will not work. vm-test-run-test-machine> test-machine # [ 15.881787] noctalia-shell[1041]: WARN: Layershell screen does not correspond to a real screen. Letting the compositor pick. vm-test-run-test-machine> test-machine # [ 15.885181] noctalia-shell[1041]: WARN qml: [20260731-160421] Location Cannot fetch weather without coordinates vm-test-run-test-machine> test-machine # [ 15.891496] noctalia-shell[1041]: INFO qml: [20260731-160421] HostService Detected NixOS 26.11 (Zokor) vm-test-run-test-machine> test-machine # [ 15.895324] noctalia-shell[1041]: INFO qml: [20260731-160421] HostService Looking for logo icon: nix-snowflake vm-test-run-test-machine> test-machine # [ 15.899664] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry Loaded plugin: spaces-sessions - Spaces Agent Sessions vm-test-run-test-machine> test-machine # [ 15.903909] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry Loaded plugin: voice-indicator - Spaces Voice Indicator vm-test-run-test-machine> test-machine # [ 15.907471] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginRegistry All plugin manifests loaded. Total plugins: 2 vm-test-run-test-machine> test-machine # [ 15.910602] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Initializing plugin system vm-test-run-test-machine> test-machine # [ 15.913868] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Found 2 enabled plugins: ["spaces-sessions","voice-indicator"] vm-test-run-test-machine> test-machine # [ 15.917847] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Loading plugin: spaces-sessions vm-test-run-test-machine> test-machine # [ 15.922596] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Loading plugin: voice-indicator vm-test-run-test-machine> test-machine # [ 15.926061] noctalia-shell[1041]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x7c5fd133dca0) vm-test-run-test-machine> test-machine # [ 15.931068] noctalia-shell[1041]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x7c5fd133e420) vm-test-run-test-machine> test-machine # [ 15.935223] niri[903]: 2026-07-31T16:04:21.642442Z WARN niri::handlers::layer_shell: no output for new layer surface, closing vm-test-run-test-machine> test-machine # [ 15.939263] niri[903]: 2026-07-31T16:04:21.644356Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing vm-test-run-test-machine> test-machine # [ 15.942509] niri[903]: 2026-07-31T16:04:21.644882Z WARN niri::handlers::layer_shell: no output for new layer surface, closing vm-test-run-test-machine> test-machine # [ 15.945702] niri[903]: 2026-07-31T16:04:21.644913Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing vm-test-run-test-machine> test-machine # [ 15.947170] niri[903]: 2026-07-31T16:04:21.655257Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing vm-test-run-test-machine> test-machine # [ 15.947846] niri[903]: 2026-07-31T16:04:21.655516Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing vm-test-run-test-machine> test-machine # [ 15.948977] noctalia-shell[1041]: INFO qml: [20260731-160421] ImageCache ImageMagick available vm-test-run-test-machine> test-machine # [ 15.949226] noctalia-shell[1041]: INFO qml: [20260731-160421] HostService resolved hostname test-machine vm-test-run-test-machine> test-machine # [ 15.950106] noctalia-shell[1041]: INFO qml: [20260731-160421] Wallpaper Starting scan for in /home/test/Pictures/Wallpapers recursive: false vm-test-run-test-machine> test-machine # [ 15.950626] noctalia-shell[1041]: INFO qml: [20260731-160421] ColorScheme Applying color scheme: Noctalia (default) vm-test-run-test-machine> test-machine # [ 15.957064] noctalia-shell[1041]: WARN qml: [20260731-160421] GitHub API request failed - using cached data without updating timestamp vm-test-run-test-machine> test-machine # [ 15.957618] noctalia-shell[1041]: WARN qml: [20260731-160421] Wallpaper Scan failed for exit code: 1 (directory might not exist) vm-test-run-test-machine> test-machine # [ 15.964312] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Loaded Main.qml for plugin: spaces-sessions vm-test-run-test-machine> test-machine # [ 15.966391] noctalia-shell[1041]: INFO qml: [20260731-160421] BarWidgetRegis Registered plugin widget: plugin:spaces-sessions vm-test-run-test-machine> test-machine # [ 15.968046] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Loaded bar widget for plugin: spaces-sessions vm-test-run-test-machine> test-machine # [ 15.969180] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Plugin loaded: spaces-sessions vm-test-run-test-machine> test-machine # [ 15.973296] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Loaded Main.qml for plugin: voice-indicator vm-test-run-test-machine> test-machine # [ 15.974932] noctalia-shell[1041]: INFO qml: [20260731-160421] BarWidgetRegis Registered plugin widget: plugin:voice-indicator vm-test-run-test-machine> test-machine # [ 15.975613] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Loaded bar widget for plugin: voice-indicator vm-test-run-test-machine> test-machine # [ 15.976223] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Plugin loaded: voice-indicator vm-test-run-test-machine> test-machine # [ 15.976905] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService All plugins loaded vm-test-run-test-machine> test-machine # [ 15.977945] noctalia-shell[1041]: INFO qml: [20260731-160421] PluginService Refreshing available plugins vm-test-run-test-machine> test-machine # [ 16.040078] noctalia-shell[1041]: ERROR qml: [20260731-160421] PluginService Empty response from Noctalia Plugins vm-test-run-test-machine> test-machine # [ 16.042465] noctalia-shell[1041]: WARN qml: [20260731-160421] SystemStat No supported temperature sensor found vm-test-run-test-machine> test-machine # [ 16.703705] noctalia-shell[1041]: WARN: Layershell screen does not correspond to a real screen. Letting the compositor pick. vm-test-run-test-machine> test-machine # [ 16.704967] niri[903]: 2026-07-31T16:04:22.427836Z WARN niri::handlers::layer_shell: no output for new layer surface, closing vm-test-run-test-machine> test-machine # [ 16.705769] niri[903]: 2026-07-31T16:04:22.427965Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing vm-test-run-test-machine> test-machine # [ 16.706538] niri[903]: 2026-07-31T16:04:22.428236Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing vm-test-run-test-machine> test-machine # [ 17.067572] microvm@hermes-test[825]: +q6E616D65Booting initrd of NixOS 26.11 (Zokor) (Initrd). vm-test-run-test-machine> test-machine # [ 17.185689] voxtype-daemon[965]: whisper_model_load: model size = 487.01 MB vm-test-run-test-machine> test-machine # [ 17.187119] voxtype-daemon[965]: 2026-07-31T16:04:22.909365Z INFO Model loaded in 5.37s vm-test-run-test-machine> test-machine # [ 17.189551] voxtype-daemon[965]: 2026-07-31T16:04:22.909706Z INFO Model loaded, ready for voice input vm-test-run-test-machine> test-machine # [ 17.326653] microvm@hermes-test[825]: [ OK ] Created slice Slice /system/modprobe. vm-test-run-test-machine> test-machine # [ 17.330907] microvm@hermes-test[825]: [ OK ] Started Dispatch Password Requests to Console Directory Watch. vm-test-run-test-machine> test-machine # [ 17.332930] noctalia-shell[1041]: INFO qml: [20260731-160423] HooksService Service started vm-test-run-test-machine> test-machine # [ 17.334840] noctalia-shell[1041]: INFO qml: [20260731-160423] Font Service started vm-test-run-test-machine> test-machine # [ 17.336924] microvm@hermes-test[825]: [ OK ] Reached target Initrd Root Device. vm-test-run-test-machine> test-machine # [ 17.338476] microvm@hermes-test[825]: [ OK ] Reached target Path Units. vm-test-run-test-machine> test-machine # [ 17.340524] microvm@hermes-test[825]: [ OK ] Reached target Slice Units. vm-test-run-test-machine> test-machine # [ 17.342150] noctalia-shell[1041]: INFO qml: [20260731-160423] UpdateService Version: v4.7.7 vm-test-run-test-machine> test-machine # [ 17.344361] noctalia-shell[1041]: WARN qml: [20260731-160423] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 17.346596] microvm@hermes-test[825]: [ OK ] Reached target Swaps. vm-test-run-test-machine> test-machine # [ 17.348207] microvm@hermes-test[825]: [ OK ] Reached target Timer Units. vm-test-run-test-machine> test-machine # [ 17.349933] microvm@hermes-test[825]: [ OK ] Listening on D-Bus System Message Bus Socket. vm-test-run-test-machine> test-machine # [ 17.352544] microvm@hermes-test[825]: [ OK ] Listening on Journal Socket (/dev/log). vm-test-run-test-machine> test-machine # [ 17.357285] microvm@hermes-test[825]: [ OK ] Listening on Journal Sockets. vm-test-run-test-machine> test-machine # [ 17.360718] microvm@hermes-test[825]: [ OK ] Listening on udev Control Socket. vm-test-run-test-machine> test-machine # [ 17.364265] microvm@hermes-test[825]: [ OK ] Listening on udev Kernel Socket. vm-test-run-test-machine> test-machine # [ 17.366662] microvm@hermes-test[825]: [ OK ] Reached target Socket Units. vm-test-run-test-machine> test-machine # [ 17.375305] microvm@hermes-test[825]: Starting Create List of Static Device Nodes... vm-test-run-test-machine> test-machine # [ 17.382382] microvm@hermes-test[825]: Starting Load Kernel Module configfs... vm-test-run-test-machine> test-machine # [ 17.398637] microvm@hermes-test[825]: Starting Journal Service... vm-test-run-test-machine> test-machine # [ 17.416436] microvm@hermes-test[825]: Starting Load Kernel Modules... vm-test-run-test-machine> test-machine # [ 17.436119] microvm@hermes-test[825]: Starting Coldplug All udev Devices... vm-test-run-test-machine> test-machine # [ 17.449032] noctalia-shell[1041]: INFO qml: [20260731-160423] Font Loaded 40 fonts, 10 monospace vm-test-run-test-machine> test-machine # [ 17.457030] microvm@hermes-test[825]: [ OK ] Finished Load Kernel Module configfs. vm-test-run-test-machine> test-machine # [ 17.466786] microvm@hermes-test[825]: [ OK ] Finished Create List of Static Device Nodes. vm-test-run-test-machine> test-machine # [ 17.473832] microvm@hermes-test[825]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-test-machine> test-machine # [ 17.630485] microvm@hermes-test[825]: [ OK ] Started Journal Service. vm-test-run-test-machine> test-machine # [ 17.650515] microvm@hermes-test[825]: [ OK ] Finished Coldplug All udev Devices. vm-test-run-test-machine> test-machine # [ 17.657084] microvm@hermes-test[825]: [ OK ] Finished Create Static Device Nodes in /dev gracefully. vm-test-run-test-machine> test-machine # [ 17.664887] microvm@hermes-test[825]: Starting Create Static Device Nodes in /dev... vm-test-run-test-machine> test-machine # [ 17.704970] microvm@hermes-test[825]: [ OK ] Finished Create Static Device Nodes in /dev. vm-test-run-test-machine> test-machine # [ 17.711085] microvm@hermes-test[825]: [ OK ] Reached target Preparation for Local File Systems. vm-test-run-test-machine> test-machine # [ 17.716676] microvm@hermes-test[825]: [ OK ] Reached target Local File Systems. vm-test-run-test-machine> test-machine # [ 17.722687] microvm@hermes-test[825]: Starting Create System Files and Directories... vm-test-run-test-machine> test-machine # [ 17.733093] microvm@hermes-test[825]: Starting Rule-based Manager for Device Events and Files... vm-test-run-test-machine> test-machine # [ 17.746901] sudo[1194]: pam_unix(sudo:session): session closed for user test vm-test-run-test-machine> (finished: subtest: chat round-trip through shell IPC, in 4.13 seconds) vm-test-run-test-machine> subtest: no per-session local pi units exist (local spawn is gone) vm-test-run-test-machine> test-machine: must succeed: sudo -u test HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 systemctl --user list-units 'pi-chat-*.service' --all --no-legend 2>&1 vm-test-run-test-machine> test-machine # [ 17.778352] sudo[1407]: root : TTY=hvc0 ; PWD=/tmp ; USER=test ; ENV=HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 ; COMMAND=/run/current-system/sw/bin/systemctl --user list-units pi-chat-*.service --all --no-legend vm-test-run-test-machine> test-machine # [ 17.786435] sudo[1407]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 17.800396] sudo[1407]: pam_unix(sudo:session): session closed for user test vm-test-run-test-machine> test-machine: (finished: must succeed: sudo -u test HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 systemctl --user list-units 'pi-chat-*.service' --all --no-legend 2>&1, in 0.05 seconds) vm-test-run-test-machine> (finished: subtest: no per-session local pi units exist (local spawn is gone), in 0.05 seconds) vm-test-run-test-machine> subtest: memory extension stores facts and recalls them in a new session vm-test-run-test-machine> test-machine: must succeed: mkdir -p /tmp/shared/tmp_jmcngx5 vm-test-run-test-machine> test-machine # [ 17.810817] noctalia-shell[1041]: WARN qml: [20260731-160423] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine: (finished: must succeed: mkdir -p /tmp/shared/tmp_jmcngx5, in 0.02 seconds) vm-test-run-test-machine> test-machine: must succeed: mkdir -p /tmp vm-test-run-test-machine> test-machine: (finished: must succeed: mkdir -p /tmp, in 0.02 seconds) vm-test-run-test-machine> test-machine: must succeed: cp -r /tmp/shared/tmp_jmcngx5/9qa63kz3w7d8bn33m5fylm8g7xkjqhi1-test-pi-memory.py /tmp/test-pi-memory.py vm-test-run-test-machine> test-machine # [ 17.861836] microvm@hermes-test[825]: [ OK ] Finished Create System Files and Directories. vm-test-run-test-machine> test-machine: (finished: must succeed: cp -r /tmp/shared/tmp_jmcngx5/9qa63kz3w7d8bn33m5fylm8g7xkjqhi1-test-pi-memory.py /tmp/test-pi-memory.py, in 0.03 seconds) vm-test-run-test-machine> test-machine # [ 17.901580] sudo[1424]: root : TTY=hvc0 ; PWD=/tmp ; USER=test ; ENV=HOME=/home/test XDG_RUNTIME_DIR=/run/user/1000 DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus WAYLAND_DISPLAY=wayland-1 ; COMMAND=/run/current-system/sw/bin/python3 /tmp/test-pi-memory.py quickshell pi-chat pi-chat vm-test-run-test-machine> test-machine # [ 17.913591] microvm@hermes-test[825]: [ OK ] Started Rule-based Manager for Device Events and Files. vm-test-run-test-machine> test-machine # [ 17.917416] sudo[1424]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 18.099163] microvm@hermes-test[825]: [ OK ] Finished Load Kernel Modules. vm-test-run-test-machine> test-machine # [ 18.105855] microvm@hermes-test[825]: Starting Apply Kernel Variables... vm-test-run-test-machine> test-machine # [ 18.134204] microvm@hermes-test[825]: [ OK ] Finished Apply Kernel Variables. vm-test-run-test-machine> test-machine # [ 18.137544] microvm@hermes-test[825]: [ OK ] Reached target System Initialization. vm-test-run-test-machine> test-machine # [ 18.142119] microvm@hermes-test[825]: [ OK ] Reached target Basic System. vm-test-run-test-machine> test-machine # [ 18.235179] microvm@hermes-test[825]: Starting Virtual Console Setup... vm-test-run-test-machine> test-machine # [ 18.282899] microvm@hermes-test[825]: [ OK ] Finished Virtual Console Setup. vm-test-run-test-machine> test-machine # [ 18.308147] systemd[628]: Starting [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/613458f8-487c-4a86-a98a-2f4273fc660c/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/613458f8-487c-4a86-a98a-2f4273fc660c --session-id 613458f8-487c-4a86-a98a-2f4273fc660c --provider local --model qwen2.5:0.5b --name harness-2... vm-test-run-test-machine> test-machine # [ 18.347238] pi-sessiond[1436]: landlock-exec: skipped absent path /etc/static/ssl vm-test-run-test-machine> test-machine # [ 18.348137] pi-sessiond[1436]: landlock-exec: domain fully enforced (kernel Landlock ABI 7) vm-test-run-test-machine> test-machine # [ 18.351472] systemd[628]: Started [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/613458f8-487c-4a86-a98a-2f4273fc660c/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/613458f8-487c-4a86-a98a-2f4273fc660c --session-id 613458f8-487c-4a86-a98a-2f4273fc660c --provider local --model qwen2.5:0.5b --name harness-2. vm-test-run-test-machine> test-machine # [ 18.354499] noctalia-shell[1041]: WARN qml: [20260731-160424] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 18.443036] microvm@hermes-test[825]: Mounting /sysroot... vm-test-run-test-machine> test-machine # [ 18.459642] microvm@hermes-test[825]: [ OK ] Mounted /sysroot. vm-test-run-test-machine> test-machine # [ 18.462978] microvm@hermes-test[825]: [ OK ] Reached target Initrd Root File System. vm-test-run-test-machine> test-machine # [ 18.466409] microvm@hermes-test[825]: Mounting /sysroot/nix/.ro-store... vm-test-run-test-machine> test-machine # [ 18.476202] microvm@hermes-test[825]: Mounting /sysroot/run... vm-test-run-test-machine> test-machine # [ 18.490764] microvm@hermes-test[825]: Mounting /sysroot/var/lib/hermes... vm-test-run-test-machine> test-machine # [ 18.503822] microvm@hermes-test[825]: Starting Mountpoints Configured in the Real Root... vm-test-run-test-machine> test-machine # [ 18.512445] microvm@hermes-test[825]: Starting rw-sysroot-nix-store.service... vm-test-run-test-machine> test-machine # [ 18.520538] microvm@hermes-test[825]: [ OK ] Mounted /sysroot/nix/.ro-store. vm-test-run-test-machine> test-machine # [ 18.527200] microvm@hermes-test[825]: [ OK ] Mounted /sysroot/run. vm-test-run-test-machine> test-machine # [ 18.532985] microvm@hermes-test[825]: [ OK ] Mounted /sysroot/var/lib/hermes. vm-test-run-test-machine> test-machine # [ 18.542832] microvm@hermes-test[825]: Mounting /sysroot/run/hermes-host... vm-test-run-test-machine> test-machine # [ 18.636642] microvm@hermes-test[825]: [ OK ] Finished rw-sysroot-nix-store.service. vm-test-run-test-machine> test-machine # [ 18.639826] microvm@hermes-test[825]: [ OK ] Mounted /sysroot/run/hermes-host. vm-test-run-test-machine> test-machine # [ 18.648308] microvm@hermes-test[825]: Starting rw-sysroot-nix-store.service... vm-test-run-test-machine> test-machine # [ 18.657118] microvm@hermes-test[825]: [ OK ] Finished Mountpoints Configured in the Real Root. vm-test-run-test-machine> test-machine # [ 18.660985] microvm@hermes-test[825]: [ OK ] Finished rw-sysroot-nix-store.service. vm-test-run-test-machine> test-machine # [ 18.669378] microvm@hermes-test[825]: Mounting /sysroot/nix/store... vm-test-run-test-machine> test-machine # [ 18.676651] microvm@hermes-test[825]: [ OK ] Mounted /sysroot/nix/store. vm-test-run-test-machine> test-machine # [ 18.681586] microvm@hermes-test[825]: [ OK ] Reached target Initrd File Systems. vm-test-run-test-machine> test-machine # [ 18.685450] microvm@hermes-test[825]: Starting Find NixOS closure... vm-test-run-test-machine> test-machine # [ 18.701289] microvm@hermes-test[825]: Starting Create Volatile Files and Directories in the Real Root... vm-test-run-test-machine> test-machine # [ 18.715035] microvm@hermes-test[825]: [ OK ] Finished Create Volatile Files and Directories in the Real Root. vm-test-run-test-machine> test-machine # [ 18.729274] microvm@hermes-test[825]: [ OK ] Finished Find NixOS closure. vm-test-run-test-machine> test-machine # [ 18.732859] microvm@hermes-test[825]: [ OK ] Reached target Initrd Default Target. vm-test-run-test-machine> test-machine # [ 18.736924] microvm@hermes-test[825]: Starting Cleaning Up and Shutting Down Daemons... vm-test-run-test-machine> test-machine # [ 18.754527] microvm@hermes-test[825]: [ OK ] Stopped target Initrd Default Target. vm-test-run-test-machine> test-machine # [ 18.757388] microvm@hermes-test[825]: [ OK ] Stopped target Basic System. vm-test-run-test-machine> test-machine # [ 18.760263] microvm@hermes-test[825]: [ OK ] Stopped target Initrd Root Device. vm-test-run-test-machine> test-machine # [ 18.764137] microvm@hermes-test[825]: [ OK ] Stopped target Path Units. vm-test-run-test-machine> test-machine # [ 18.769243] microvm@hermes-test[825]: [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. vm-test-run-test-machine> test-machine # [ 18.771784] microvm@hermes-test[825]: [ OK ] Stopped target Slice Units. vm-test-run-test-machine> test-machine # [ 18.774585] microvm@hermes-test[825]: [ OK ] Stopped target Socket Units. vm-test-run-test-machine> test-machine # [ 18.778201] microvm@hermes-test[825]: [ OK ] Stopped target System Initialization. vm-test-run-test-machine> test-machine # [ 18.782250] microvm@hermes-test[825]: [ OK ] Stopped target Swaps. vm-test-run-test-machine> test-machine # [ 18.786310] microvm@hermes-test[825]: [ OK ] Stopped target Timer Units. vm-test-run-test-machine> test-machine # [ 18.789536] microvm@hermes-test[825]: [ OK ] Closed D-Bus System Message Bus Socket. vm-test-run-test-machine> test-machine # [ 18.792968] microvm@hermes-test[825]: [ OK ] Stopped Find NixOS closure. vm-test-run-test-machine> test-machine # [ 18.798452] microvm@hermes-test[825]: Starting rw-sysroot-nix-store.service... vm-test-run-test-machine> test-machine # [ 18.801785] microvm@hermes-test[825]: [ OK ] Stopped Apply Kernel Variables. vm-test-run-test-machine> test-machine # [ 18.806582] microvm@hermes-test[825]: [ OK ] Stopped Load Kernel Modules. vm-test-run-test-machine> test-machine # [ 18.811254] microvm@hermes-test[825]: [ OK ] Stopped Create Volatile Files and Directories in the Real Root. vm-test-run-test-machine> test-machine # [ 18.815523] microvm@hermes-test[825]: [ OK ] Stopped Create System Files and Directories. vm-test-run-test-machine> test-machine # [ 18.819218] microvm@hermes-test[825]: [ OK ] Stopped target Local File Systems. vm-test-run-test-machine> test-machine # [ 18.822969] microvm@hermes-test[825]: [ OK ] Stopped target Preparation for Local File Systems. vm-test-run-test-machine> test-machine # [ 18.826391] microvm@hermes-test[825]: [ OK ] Stopped Coldplug All udev Devices. vm-test-run-test-machine> test-machine # [ 18.832612] microvm@hermes-test[825]: Stopping Rule-based Manager for Device Events and Files... vm-test-run-test-machine> test-machine # [ 18.835974] microvm@hermes-test[825]: [ OK ] Stopped Virtual Console Setup. vm-test-run-test-machine> test-machine # [ 18.845194] microvm@hermes-test[825]: [ OK ] Finished rw-sysroot-nix-store.service. vm-test-run-test-machine> test-machine # [ 18.851096] microvm@hermes-test[825]: [ OK ] Finished Cleaning Up and Shutting Down Daemons. vm-test-run-test-machine> test-machine # [ 18.855862] noctalia-shell[1041]: WARN qml: [20260731-160424] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 18.861954] microvm@hermes-test[825]: [ OK ] Stopped Rule-based Manager for Device Events and Files. vm-test-run-test-machine> test-machine # [ 18.867976] microvm@hermes-test[825]: [ OK ] Closed udev Control Socket. vm-test-run-test-machine> test-machine # [ 18.874644] microvm@hermes-test[825]: Starting Cleanup udev Database... vm-test-run-test-machine> test-machine # [ 18.878688] microvm@hermes-test[825]: [ OK ] Stopped Create Static Device Nodes in /dev. vm-test-run-test-machine> test-machine # [ 18.882577] microvm@hermes-test[825]: [ OK ] Stopped Create Static Device Nodes in /dev gracefully. vm-test-run-test-machine> test-machine # [ 18.885968] microvm@hermes-test[825]: [ OK ] Stopped Create List of Static Device Nodes. vm-test-run-test-machine> test-machine # [ 18.897852] microvm@hermes-test[825]: [ OK ] Finished Cleanup udev Database. vm-test-run-test-machine> test-machine # [ 18.902660] microvm@hermes-test[825]: [ OK ] Reached target Switch Root. vm-test-run-test-machine> test-machine # [ 18.905721] microvm@hermes-test[825]: Starting NixOS Activation... vm-test-run-test-machine> test-machine # [ 19.356413] noctalia-shell[1041]: WARN qml: [20260731-160425] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 19.857862] noctalia-shell[1041]: WARN qml: [20260731-160425] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 20.357821] noctalia-shell[1041]: WARN qml: [20260731-160426] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 20.859670] noctalia-shell[1041]: WARN qml: [20260731-160426] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 21.164485] microvm@hermes-test[825]: [* ] A start job is running for NixOS Activation (2s / no limit) vm-test-run-test-machine> test-machine # [ 21.360595] noctalia-shell[1041]: WARN qml: [20260731-160427] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 21.567836] systemd[628]: Starting [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/92d0130e-a842-4e28-ad29-12f93cd7918c/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/92d0130e-a842-4e28-ad29-12f93cd7918c --session-id 92d0130e-a842-4e28-ad29-12f93cd7918c --provider local --model qwen2.5:0.5b --name memory-recall... vm-test-run-test-machine> test-machine # [ 21.605171] pi-sessiond[1513]: landlock-exec: skipped absent path /etc/static/ssl vm-test-run-test-machine> test-machine # [ 21.605806] pi-sessiond[1513]: landlock-exec: domain fully enforced (kernel Landlock ABI 7) vm-test-run-test-machine> test-machine # [ 21.608041] systemd[628]: Started [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/92d0130e-a842-4e28-ad29-12f93cd7918c/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/92d0130e-a842-4e28-ad29-12f93cd7918c --session-id 92d0130e-a842-4e28-ad29-12f93cd7918c --provider local --model qwen2.5:0.5b --name memory-recall. vm-test-run-test-machine> test-machine # [ 21.744370] microvm@hermes-test[825]: [** ] A start job is running for NixOS Activation (2s / no limit) vm-test-run-test-machine> test-machine # [ 21.861517] noctalia-shell[1041]: WARN qml: [20260731-160427] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 22.326896] microvm@hermes-test[825]: [*** ] A start job is running for NixOS Activation (3s / no limit) vm-test-run-test-machine> test-machine # [ 22.363045] noctalia-shell[1041]: WARN qml: [20260731-160428] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 22.864048] noctalia-shell[1041]: WARN qml: [20260731-160428] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 22.914920] microvm@hermes-test[825]: [ *** ] A start job is running for NixOS Activation (4s / no limit) vm-test-run-test-machine> test-machine # [ 23.363879] noctalia-shell[1041]: WARN qml: [20260731-160429] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 23.483392] microvm@hermes-test[825]: [ *** ] A start job is running for NixOS Activation (4s / no limit) vm-test-run-test-machine> test-machine # [ 23.487391] microvm@hermes-test[825]: [ OK ] Finished NixOS Activation. vm-test-run-test-machine> test-machine # [ 23.490841] microvm@hermes-test[825]: Starting Switch Root... vm-test-run-test-machine> test-machine # [ 23.865758] noctalia-shell[1041]: WARN qml: [20260731-160429] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 23.908970] sudo[1424]: pam_unix(sudo:session): session closed for user test vm-test-run-test-machine> (finished: subtest: memory extension stores facts and recalls them in a new session, in 6.11 seconds) vm-test-run-test-machine> subtest: loopback daemon runs in the user manager, not as root vm-test-run-test-machine> test-machine: waiting for success: systemctl --user --machine=test@.host is-active pi-sessiond.service vm-test-run-test-machine> test-machine # [ 23.939194] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 23.968115] (systemd-stdio-bridge)[1620]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 23.977482] systemd[1]: Started Session 8 of User test. vm-test-run-test-machine> test-machine # [ 23.988073] (systemd-stdio-bridge)[1620]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 24.001653] systemd[1]: run-p1615-i1616.service: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active pi-sessiond.service, in 0.09 seconds) vm-test-run-test-machine> test-machine: must succeed: systemctl --user --machine=test@.host show -p MainPID --value pi-sessiond.service vm-test-run-test-machine> test-machine # [ 24.005594] (sd-pam)[1623]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine # [ 24.011191] systemd[1]: session-8.scope: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 24.028134] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 24.056824] (systemd-stdio-bridge)[1633]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 24.068817] systemd[1]: Started Session 9 of User test. vm-test-run-test-machine> test-machine # [ 24.080943] (systemd-stdio-bridge)[1633]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 24.107438] systemd[1]: run-p1632-i1633.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 24.108911] (sd-pam)[1636]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine: (finished: must succeed: systemctl --user --machine=test@.host show -p MainPID --value pi-sessiond.service, in 0.11 seconds) vm-test-run-test-machine> test-machine: must succeed: stat -c %u /proc/700 vm-test-run-test-machine> test-machine # [ 24.117828] systemd[1]: session-9.scope: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: must succeed: stat -c %u /proc/700, in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: loopback daemon runs in the user manager, not as root, in 0.21 seconds) vm-test-run-test-machine> test-machine: must succeed: echo home-marker-secret > /home/test/secret-marker vm-test-run-test-machine> test-machine: (finished: must succeed: echo home-marker-secret > /home/test/secret-marker, in 0.01 seconds) vm-test-run-test-machine> test-machine: must succeed: chown test /home/test/secret-marker vm-test-run-test-machine> test-machine: (finished: must succeed: chown test /home/test/secret-marker, in 0.01 seconds) vm-test-run-test-machine> subtest: daemon mount namespace mounts the user's home read-only vm-test-run-test-machine> test-machine: must succeed: test -f /home/test/secret-marker vm-test-run-test-machine> test-machine: (finished: must succeed: test -f /home/test/secret-marker, in 0.01 seconds) vm-test-run-test-machine> test-machine: must succeed: nsenter -t 700 -m test -f /home/test/secret-marker vm-test-run-test-machine> test-machine: (finished: must succeed: nsenter -t 700 -m test -f /home/test/secret-marker, in 0.02 seconds) vm-test-run-test-machine> ??? Warning (UserWarning): invoking 'QemuMachine.fail' is deprecated: It's deprecated to use the `machine` variable when the only machine is called test_machine. This behavior will no longer work in NixOS 27.05. vm-test-run-test-machine> File "/nix/store/m9q8ny4djkgmj4g5mbrs894laz3cixmx-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1816 vm-test-run-test-machine> test-machine: must fail: nsenter -t 700 -m sh -c 'echo x > /home/test/write-probe' vm-test-run-test-machine> test-machine # sh: line 1: /home/test/write-probe: Read-only file system vm-test-run-test-machine> test-machine: (finished: must fail: nsenter -t 700 -m sh -c 'echo x > /home/test/write-probe', in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: daemon mount namespace mounts the user's home read-only, in 0.05 seconds) vm-test-run-test-machine> subtest: daemon listens on 8768 (bun cold start can take a while) vm-test-run-test-machine> test-machine: waiting for success: ss -tln | grep -q ':8768 ' vm-test-run-test-machine> test-machine: (finished: waiting for success: ss -tln | grep -q ':8768 ', in 0.04 seconds) vm-test-run-test-machine> (finished: subtest: daemon listens on 8768 (bun cold start can take a while), in 0.04 seconds) vm-test-run-test-machine> subtest: WS auth: runtime token accepted, wrong token rejected vm-test-run-test-machine> test-machine: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/b0y47m60p7y38741fi6asvkj6gpbqxj2-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token auth' vm-test-run-test-machine> test-machine # [ 24.263597] su[1690]: Successful su for test by root vm-test-run-test-machine> test-machine # [ 24.269257] su[1690]: pam_unix(su:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 24.365801] noctalia-shell[1041]: WARN qml: [20260731-160430] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 24.671866] su[1690]: pam_unix(su:session): session closed for user test vm-test-run-test-machine> test-machine: (finished: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/b0y47m60p7y38741fi6asvkj6gpbqxj2-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token auth', in 0.44 seconds) vm-test-run-test-machine> (finished: subtest: WS auth: runtime token accepted, wrong token rejected, in 0.44 seconds) vm-test-run-test-machine> subtest: bash tool runs sandboxed: HOME hidden inside the unit vm-test-run-test-machine> test-machine: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/b0y47m60p7y38741fi6asvkj6gpbqxj2-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token sandbox' vm-test-run-test-machine> test-machine # [ 24.693198] su[1697]: Successful su for test by root vm-test-run-test-machine> test-machine # [ 24.697663] su[1697]: pam_unix(su:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 24.866530] noctalia-shell[1041]: WARN qml: [20260731-160430] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 24.909986] microvm@hermes-test[825]: +q6E616D65 vm-test-run-test-machine> test-machine # [ 24.914389] microvm@hermes-test[825]: Welcome to NixOS 26.11 (Zokor)! vm-test-run-test-machine> test-machine # [ 24.915290] microvm@hermes-test[825]: vm-test-run-test-machine> test-machine # [ 25.035689] systemd[628]: Starting [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/298ae626-87b4-4cf2-a4dc-bc4ffa77abab/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/298ae626-87b4-4cf2-a4dc-bc4ffa77abab --session-id 298ae626-87b4-4cf2-a4dc-bc4ffa77abab --provider local --model qwen2.5:0.5b --name probe... vm-test-run-test-machine> test-machine # [ 25.071465] pi-sessiond[1706]: landlock-exec: skipped absent path /etc/static/ssl vm-test-run-test-machine> test-machine # [ 25.073512] pi-sessiond[1706]: landlock-exec: domain fully enforced (kernel Landlock ABI 7) vm-test-run-test-machine> test-machine # [ 25.075084] systemd[628]: Started [systemd-run] /nix/store/631bh04pb7zsrmyr7ssg84jj1q2iys6z-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/298ae626-87b4-4cf2-a4dc-bc4ffa77abab/landlock.json -- /nix/store/iq5jw41kc6nxcwybwlgcmypc1jarb4cx-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/298ae626-87b4-4cf2-a4dc-bc4ffa77abab --session-id 298ae626-87b4-4cf2-a4dc-bc4ffa77abab --provider local --model qwen2.5:0.5b --name probe. vm-test-run-test-machine> test-machine # [ 25.367466] noctalia-shell[1041]: WARN qml: [20260731-160431] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 25.868251] noctalia-shell[1041]: WARN qml: [20260731-160431] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 26.174845] microvm@hermes-test[825]: [ OK ] Stopped initrd-switch-root.service. vm-test-run-test-machine> test-machine # [ 26.183327] microvm@hermes-test[825]: [ OK ] Created slice Slice /system/getty. vm-test-run-test-machine> test-machine # [ 26.187095] microvm@hermes-test[825]: [ OK ] Created slice Slice /system/serial-getty. vm-test-run-test-machine> test-machine # [ 26.190950] microvm@hermes-test[825]: [ OK ] Created slice User and Session Slice. vm-test-run-test-machine> test-machine # [ 26.195770] microvm@hermes-test[825]: [ OK ] Started Dispatch Password Requests to Console Directory Watch. vm-test-run-test-machine> test-machine # [ 26.200377] microvm@hermes-test[825]: [ OK ] Started Forward Password Requests to Wall Directory Watch. vm-test-run-test-machine> test-machine # [ 26.204657] microvm@hermes-test[825]: Expecting device /dev/ttyS0... vm-test-run-test-machine> test-machine # [ 26.209372] microvm@hermes-test[825]: [ OK ] Reached target Local Encrypted Volumes. vm-test-run-test-machine> test-machine # [ 26.212690] microvm@hermes-test[825]: [ OK ] Stopped target initrd-fs.target. vm-test-run-test-machine> test-machine # [ 26.218081] microvm@hermes-test[825]: [ OK ] Stopped target initrd-root-fs.target. vm-test-run-test-machine> test-machine # [ 26.221923] microvm@hermes-test[825]: [ OK ] Stopped target initrd-switch-root.target. vm-test-run-test-machine> test-machine # [ 26.223731] microvm@hermes-test[825]: [ OK ] Reached target Virtual Machines and Containers. vm-test-run-test-machine> test-machine # [ 26.225970] microvm@hermes-test[825]: [ OK ] Reached target Path Units. vm-test-run-test-machine> test-machine # [ 26.229555] microvm@hermes-test[825]: [ OK ] Reached target Remote File Systems. vm-test-run-test-machine> test-machine # [ 26.232242] microvm@hermes-test[825]: [ OK ] Reached target Slice Units. vm-test-run-test-machine> test-machine # [ 26.236401] microvm@hermes-test[825]: [ OK ] Reached target Swaps. vm-test-run-test-machine> test-machine # [ 26.244679] microvm@hermes-test[825]: [ OK ] Listening on Query the User Interactively for a Password. vm-test-run-test-machine> test-machine # [ 26.256514] microvm@hermes-test[825]: [ OK ] Listening on Process Core Dump Socket. vm-test-run-test-machine> test-machine # [ 26.264162] microvm@hermes-test[825]: [ OK ] Listening on Credential Encryption/Decryption. vm-test-run-test-machine> test-machine # [ 26.272423] microvm@hermes-test[825]: [ OK ] Listening on Factory Reset Management. vm-test-run-test-machine> test-machine # [ 26.275028] microvm@hermes-test[825]: [ OK ] Listening on Hostname Service Socket. vm-test-run-test-machine> test-machine # [ 26.284793] microvm@hermes-test[825]: Starting Journal Log Access Socket... vm-test-run-test-machine> test-machine # [ 26.288933] microvm@hermes-test[825]: [ OK ] Listening on Journal Audit Socket. vm-test-run-test-machine> test-machine # [ 26.296534] microvm@hermes-test[825]: [ OK ] Listening on Console Output Muting Service Socket. vm-test-run-test-machine> test-machine # [ 26.303925] microvm@hermes-test[825]: [ OK ] Listening on Network Management Resolve Hook Socket. vm-test-run-test-machine> test-machine # [ 26.311097] microvm@hermes-test[825]: [ OK ] Listening on Network Management Metrics Varlink Socket. vm-test-run-test-machine> test-machine # [ 26.317943] microvm@hermes-test[825]: [ OK ] Listening on Network Management Varlink Socket. vm-test-run-test-machine> test-machine # [ 26.325342] microvm@hermes-test[825]: [ OK ] Listening on Network Management Netlink Socket. vm-test-run-test-machine> test-machine # [ 26.331630] microvm@hermes-test[825]: [ OK ] Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-test-machine> test-machine # [ 26.350004] microvm@hermes-test[825]: [ OK ] Listening on Disk Repartitioning Service Socket. vm-test-run-test-machine> test-machine # [ 26.357057] microvm@hermes-test[825]: [ OK ] Listening on Resolve Monitor Varlink Socket. vm-test-run-test-machine> test-machine # [ 26.359185] microvm@hermes-test[825]: [ OK ] Listening on Resolve Service Varlink Socket. vm-test-run-test-machine> test-machine # [ 26.363688] microvm@hermes-test[825]: [ OK ] Listening on udev Control Socket. vm-test-run-test-machine> test-machine # [ 26.365481] microvm@hermes-test[825]: [ OK ] Listening on udev Varlink Socket. vm-test-run-test-machine> test-machine # [ 26.368927] noctalia-shell[1041]: WARN qml: [20260731-160432] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine # [ 26.379528] microvm@hermes-test[825]: Mounting Huge Pages File System... vm-test-run-test-machine> test-machine # [ 26.389108] microvm@hermes-test[825]: Mounting POSIX Message Queue File System... vm-test-run-test-machine> test-machine # [ 26.395636] microvm@hermes-test[825]: Mounting Kernel Debug File System... vm-test-run-test-machine> test-machine # [ 26.458393] microvm@hermes-test[825]: Mounting Kernel Trace File System... vm-test-run-test-machine> test-machine # [ 26.520804] microvm@hermes-test[825]: Starting Create List of Static Device Nodes... vm-test-run-test-machine> test-machine # [ 26.561385] microvm@hermes-test[825]: Starting Load Kernel Module configfs... vm-test-run-test-machine> test-machine # [ 26.653074] microvm@hermes-test[825]: Mounting FUSE Control File System... vm-test-run-test-machine> test-machine # [ 26.700378] microvm@hermes-test[825]: Starting Journal Service... vm-test-run-test-machine> test-machine # [ 26.745458] microvm@hermes-test[825]: Starting Load Kernel Modules... vm-test-run-test-machine> test-machine # [ 26.780968] microvm@hermes-test[825]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-test-machine> test-machine # [ 26.823931] microvm@hermes-test[825]: Starting Remount Root and Kernel File Systems... vm-test-run-test-machine> test-machine # [ 26.858930] su[1697]: pam_unix(su:session): session closed for user test vm-test-run-test-machine> test-machine: (finished: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/b0y47m60p7y38741fi6asvkj6gpbqxj2-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token sandbox', in 2.19 seconds) vm-test-run-test-machine> (finished: subtest: bash tool runs sandboxed: HOME hidden inside the unit, in 2.19 seconds) vm-test-run-test-machine> subtest: panel config defaults to the loopback executor vm-test-run-test-machine> test-machine: must succeed: /nix/store/qqd37kj25f68wxmr9rg05850y9daxbrp-jq-1.8.2-bin/bin/jq -e '.localExecutor.id == "host" and .defaultExecutor == "host"' /etc/spaces/pi-chat.json vm-test-run-test-machine> test-machine # [ 26.870276] noctalia-shell[1041]: WARN qml: [20260731-160432] PanelService Panel not found: setupWizardPanel- vm-test-run-test-machine> test-machine: (finished: must succeed: /nix/store/qqd37kj25f68wxmr9rg05850y9daxbrp-jq-1.8.2-bin/bin/jq -e '.localExecutor.id == "host" and .defaultExecutor == "host"' /etc/spaces/pi-chat.json, in 0.02 seconds) vm-test-run-test-machine> (finished: subtest: panel config defaults to the loopback executor, in 0.02 seconds) vm-test-run-test-machine> subtest: spaces-notify-forward unit comes up vm-test-run-test-machine> test-machine: waiting for success: systemctl --user --machine=test@.host is-active spaces-notify-forward.service vm-test-run-test-machine> test-machine # [ 26.906222] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 26.922682] microvm@hermes-test[825]: Starting Coldplug All udev Devices... vm-test-run-test-machine> test-machine # [ 26.938425] (systemd-stdio-bridge)[1762]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 26.954277] systemd[1]: Started Session 10 of User test. vm-test-run-test-machine> test-machine # [ 26.966378] (systemd-stdio-bridge)[1762]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 26.973837] microvm@hermes-test[825]: [ OK ] Listening on Journal Log Access Socket. vm-test-run-test-machine> test-machine # [ 26.982868] microvm@hermes-test[825]: [ OK ] Mounted Huge Pages File System. vm-test-run-test-machine> test-machine # [ 26.987046] systemd[1]: run-p1761-i1762.service: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 26.988452] (sd-pam)[1765]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active spaces-notify-forward.service, in 0.11 seconds) vm-test-run-test-machine> test-machine: waiting for success: systemctl --user --machine=test@.host is-active mako.service vm-test-run-test-machine> test-machine # [ 26.996724] systemd[1]: session-10.scope: Deactivated successfully. vm-test-run-test-machine> test-machine # [ 26.999270] microvm@hermes-test[825]: [ OK ] Mounted POSIX Message Queue File System. vm-test-run-test-machine> test-machine # [ 27.008329] microvm@hermes-test[825]: [ OK ] Mounted Kernel Debug File System. vm-test-run-test-machine> test-machine # [ 27.017483] microvm@hermes-test[825]: [ OK ] Mounted Kernel Trace File System. vm-test-run-test-machine> test-machine # [ 27.028631] microvm@hermes-test[825]: [ OK ] Finished Create List of Static Device Nodes. vm-test-run-test-machine> test-machine # [ 27.034202] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet. vm-test-run-test-machine> test-machine # [ 27.070928] (systemd-stdio-bridge)[1775]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0) vm-test-run-test-machine> test-machine # [ 27.076364] microvm@hermes-test[825]: [ OK ] Finished Load Kernel Module configfs. vm-test-run-test-machine> test-machine # [ 27.082714] microvm@hermes-test[825]: [ OK ] Mounted FUSE Control File System. vm-test-run-test-machine> test-machine # [ 27.085632] systemd[1]: Started Session 11 of User test. vm-test-run-test-machine> test-machine # [ 27.091890] microvm@hermes-test[825]: [ OK ] Started Journal Service. vm-test-run-test-machine> test-machine # [ 27.101165] (systemd-stdio-bridge)[1775]: gkr-pam: couldn't unlock the login keyring. vm-test-run-test-machine> test-machine # [ 27.117173] microvm@hermes-test[825]: Mounting Kernel Configuration File System... vm-test-run-test-machine> test-machine # [ 27.120526] systemd[1]: run-p1774-i1775.service: Deactivated successfully. vm-test-run-test-machine> test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active mako.service, in 0.13 seconds) vm-test-run-test-machine> (finished: subtest: spaces-notify-forward unit comes up, in 0.24 seconds) vm-test-run-test-machine> (finished: run the VM test script, in 27.94 seconds) vm-test-run-test-machine> test-machine # [ 27.122862] (sd-pam)[1778]: pam_unix(login:session): session closed for user test vm-test-run-test-machine> test-machine # [ 27.132779] systemd[1]: session-11.scope: Deactivated successfully. vm-test-run-test-machine> test script finished in 27.97s vm-test-run-test-machine> cleanup vm-test-run-test-machine> kill QemuMachine (pid 45) vm-test-run-test-machine> test-machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/rgnappqqc5vbq60gza5fflyk84sylwl6-python3-3.14.6/bin/python3.14) vm-test-run-test-machine> (finished: cleanup, in 0.14 seconds)