vm-test-run-test-machine
default.ciChecks.x86_64-linux.test-machine
· build #10
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 test-machine,8 vlan1,9 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_ssh10??? 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.11 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 181312test-machine: waiting for unit multi-user.target13test-machine: waiting for the VM to finish booting14test-machine: starting vm15test-machine # Disk image does not exist, creating the virtualisation disk image...16test-machine # Formatting '/build/vm-state-test-machine/tmp.lFDG39MGnl', fmt=raw size=107374182417test-machine # mke2fs 1.47.4 (6-Mar-2025)18test-machine # Discarding device blocks: 0/262144 done19test-machine # Creating filesystem with 262144 4k blocks and 65536 inodes20test-machine # Filesystem UUID: 02021c56-502d-4ec9-acaa-e8af369360be21test-machine # Superblock backups stored on blocks:22test-machine # 32768, 98304, 163840, 22937623test-machine # 24test-machine # Allocating group tables: 0/8 done25test-machine # Writing inode tables: 0/8 done26test-machine # Creating journal (8192 blocks): done27test-machine # Writing superblocks and filesystem accounting information: 0/8 done28test-machine # 29test-machine # Virtualisation disk image created.30test-machine: QEMU running (pid 45)31test-machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)32test-machine # 33test-machine # 34test-machine # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+BEFD1850+BEF31850 CA0035test-machine # Press Ctrl-B to configure iPXE (PCI 00:03.0)...36test-machine # 37test-machine # 38test-machine # 39test-machine # 40test-machine # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM BEFD1850 BEF31850 CB0041test-machine # Press Ctrl-B to configure iPXE (PCI 00:09.0)...42test-machine # 43test-machine # 44test-machine # Booting from ROM...45test-machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.41 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Thu Jul 30 11:05:57 UTC 202646test-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/1rplylvhk66257n04kx9wdbazvcgvvw6-nixos-system-test-machine-test/init regInfo=/nix/store/i60c1qil8gbpq6rpcjkawihxw0525vqq-closure-info/registration console=ttyS0,115200n8 console=tty047test-machine # [ 0.000000] BIOS-provided physical RAM map:48test-machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable49test-machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved50test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved51test-machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffcafff] usable52test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000bffcb000-0x00000000bfffffff] reserved53test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved54test-machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved55test-machine # [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable56test-machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved57test-machine # [ 0.000000] NX (Execute Disable) protection: active58test-machine # [ 0.000000] APIC: Static calls initialized59test-machine # [ 0.000000] SMBIOS 2.8 present.60test-machine # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201461test-machine # [ 0.000000] DMI: Memory slots populated: 1/162test-machine # [ 0.000000] Hypervisor detected: KVM63test-machine # [ 0.000000] last_pfn = 0xbffcb max_arch_pfn = 0x40000000064test-machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0065test-machine # [ 0.000001] kvm-clock: using sched offset of 603711518 cycles66test-machine # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns67test-machine # [ 0.000009] tsc: Detected 4192.076 MHz processor68test-machine # [ 0.000727] last_pfn = 0x140000 max_arch_pfn = 0x40000000069test-machine # [ 0.000771] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs70test-machine # [ 0.000777] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT71test-machine # [ 0.000833] last_pfn = 0xbffcb max_arch_pfn = 0x40000000072test-machine # [ 0.002732] found SMP MP-table at [mem 0x000f5470-0x000f547f]73test-machine # [ 0.002781] Using GB pages for direct mapping74test-machine # [ 0.002985] RAMDISK: [mem 0xbe300000-0xbffbffff]75test-machine # [ 0.002997] ACPI: Early table checksum verification disabled76test-machine # [ 0.003003] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )77test-machine # [ 0.003010] ACPI: RSDT 0x00000000BFFD27A9 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78test-machine # [ 0.003016] ACPI: FACP 0x00000000BFFD25F9 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)79test-machine # [ 0.003025] ACPI: DSDT 0x00000000BFFD0040 0025B9 (v01 BOCHS BXPC 00000001 BXPC 00000001)80test-machine # [ 0.003027] ACPI: FACS 0x00000000BFFD0000 00004081test-machine # [ 0.003029] ACPI: APIC 0x00000000BFFD266D 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001)82test-machine # [ 0.003031] ACPI: HPET 0x00000000BFFD26FD 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)83test-machine # [ 0.003032] ACPI: TPM2 0x00000000BFFD2735 00004C (v04 BOCHS BXPC 00000001 BXPC 00000001)84test-machine # [ 0.003035] ACPI: WAET 0x00000000BFFD2781 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)85test-machine # [ 0.003036] ACPI: Reserving FACP table memory at [mem 0xbffd25f9-0xbffd266c]86test-machine # [ 0.003037] ACPI: Reserving DSDT table memory at [mem 0xbffd0040-0xbffd25f8]87test-machine # [ 0.003038] ACPI: Reserving FACS table memory at [mem 0xbffd0000-0xbffd003f]88test-machine # [ 0.003039] ACPI: Reserving APIC table memory at [mem 0xbffd266d-0xbffd26fc]89test-machine # [ 0.003039] ACPI: Reserving HPET table memory at [mem 0xbffd26fd-0xbffd2734]90test-machine # [ 0.003039] ACPI: Reserving TPM2 table memory at [mem 0xbffd2735-0xbffd2780]91test-machine # [ 0.003040] ACPI: Reserving WAET table memory at [mem 0xbffd2781-0xbffd27a8]92test-machine # [ 0.003534] No NUMA configuration found93test-machine # [ 0.003536] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]94test-machine # [ 0.003540] NODE_DATA(0) allocated [mem 0x13fff8780-0x13fffdcff]95test-machine # [ 0.003668] Zone ranges:96test-machine # [ 0.003670] DMA [mem 0x0000000000001000-0x0000000000ffffff]97test-machine # [ 0.003671] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]98test-machine # [ 0.003673] Normal [mem 0x0000000100000000-0x000000013fffffff]99test-machine # [ 0.003674] Device empty100test-machine # [ 0.003675] Movable zone start for each node101test-machine # [ 0.003675] Early memory node ranges102test-machine # [ 0.003676] node 0: [mem 0x0000000000001000-0x000000000009efff]103test-machine # [ 0.003677] node 0: [mem 0x0000000000100000-0x00000000bffcafff]104test-machine # [ 0.003678] node 0: [mem 0x0000000100000000-0x000000013fffffff]105test-machine # [ 0.003679] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff]106test-machine # [ 0.003703] On node 0, zone DMA: 1 pages in unavailable ranges107test-machine # [ 0.003964] On node 0, zone DMA: 97 pages in unavailable ranges108test-machine # [ 0.090005] On node 0, zone Normal: 53 pages in unavailable ranges109test-machine # [ 0.091352] ACPI: PM-Timer IO Port: 0x608110test-machine # [ 0.091374] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])111test-machine # [ 0.091407] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23112test-machine # [ 0.091410] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)113test-machine # [ 0.091412] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)114test-machine # [ 0.091413] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)115test-machine # [ 0.091415] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)116test-machine # [ 0.091415] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)117test-machine # [ 0.091418] ACPI: Using ACPI (MADT) for SMP configuration information118test-machine # [ 0.091420] ACPI: HPET id: 0x8086a201 base: 0xfed00000119test-machine # [ 0.091432] TSC deadline timer available120test-machine # [ 0.091437] CPU topo: Max. logical packages: 1121test-machine # [ 0.091438] CPU topo: Max. logical dies: 1122test-machine # [ 0.091439] CPU topo: Max. dies per package: 1123test-machine # [ 0.091443] CPU topo: Max. threads per core: 1124test-machine # [ 0.091443] CPU topo: Num. cores per package: 4125test-machine # [ 0.091444] CPU topo: Num. threads per package: 4126test-machine # [ 0.091444] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs127test-machine # [ 0.091477] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()128test-machine # [ 0.091509] kvm-guest: KVM setup pv remote TLB flush129test-machine # [ 0.091515] kvm-guest: setup PV sched yield130test-machine # [ 0.091528] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]131test-machine # [ 0.091530] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]132test-machine # [ 0.091533] PM: hibernation: Registered nosave memory: [mem 0xbffcb000-0xffffffff]133test-machine # [ 0.091535] [mem 0xc0000000-0xfeffbfff] available for PCI devices134test-machine # [ 0.091537] Booting paravirtualized kernel on KVM135test-machine # [ 0.091546] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns136test-machine # [ 0.097914] setup_percpu: NR_CPUS:384 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1137test-machine # [ 0.100459] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u524288138test-machine # [ 0.100554] kvm-guest: PV spinlocks enabled139test-machine # [ 0.100558] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)140test-machine # [ 0.100561] 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/1rplylvhk66257n04kx9wdbazvcgvvw6-nixos-system-test-machine-test/init regInfo=/nix/store/i60c1qil8gbpq6rpcjkawihxw0525vqq-closure-info/registration console=ttyS0,115200n8 console=tty0141test-machine # [ 0.100702] Unknown kernel command line parameters "regInfo=/nix/store/i60c1qil8gbpq6rpcjkawihxw0525vqq-closure-info/registration", will be passed to user space.142test-machine # [ 0.100926] random: crng init done143test-machine # [ 0.100927] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes144test-machine # [ 0.105591] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)145test-machine # [ 0.110731] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)146test-machine # [ 0.110817] software IO TLB: area num 4.147test-machine # [ 0.244307] Fallback order for Node 0: 0148test-machine # [ 0.244318] Built 1 zonelists, mobility grouping on. Total pages: 1048425149test-machine # [ 0.244321] Policy zone: Normal150test-machine # [ 0.246768] mem auto-init: stack:all(zero), heap alloc:on, heap free:off151test-machine # [ 0.256403] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1152test-machine # [ 0.265385] allocated 8388608 bytes of page_ext153test-machine # [ 0.278504] ftrace: allocating 48695 entries in 192 pages154test-machine # [ 0.278512] ftrace: allocated 192 pages with 2 groups155test-machine # [ 0.279685] Dynamic Preempt: lazy156test-machine # [ 0.279898] rcu: Preemptible hierarchical RCU implementation.157test-machine # [ 0.279899] rcu: RCU event tracing is enabled.158test-machine # [ 0.279900] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4.159test-machine # [ 0.279902] Trampoline variant of Tasks RCU enabled.160test-machine # [ 0.279902] Rude variant of Tasks RCU enabled.161test-machine # [ 0.279903] Tracing variant of Tasks RCU enabled.162test-machine # [ 0.279904] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.163test-machine # [ 0.279905] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4164test-machine # [ 0.279936] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.165test-machine # [ 0.279938] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.166test-machine # [ 0.279940] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.167test-machine # [ 0.285755] NR_IRQS: 24832, nr_irqs: 456, preallocated irqs: 16168test-machine # [ 0.286148] rcu: srcu_init: Setting srcu_struct sizes based on contention.169test-machine # [ 0.286157] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns170test-machine # [ 0.286371] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)171test-machine # [ 0.293211] Console: colour VGA+ 80x25172test-machine # [ 0.293221] printk: legacy console [tty0] enabled173test-machine # [ 0.334553] printk: legacy console [ttyS0] enabled174test-machine # [ 0.520975] ACPI: Core revision 20250807175test-machine # [ 0.522282] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns176test-machine # [ 0.524560] APIC: Switch to symmetric I/O mode setup177test-machine # [ 0.526079] x2apic enabled178test-machine # [ 0.527311] APIC: Switched APIC routing to: physical x2apic179test-machine # [ 0.528733] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask()180test-machine # [ 0.531308] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself()181test-machine # [ 0.533534] kvm-guest: setup PV IPIs182test-machine # [ 0.536123] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1183test-machine # [ 0.537644] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns184test-machine # [ 0.544977] Calibrating delay loop (skipped) preset value.. 8384.15 BogoMIPS (lpj=4192076)185test-machine # [ 0.546016] x86/cpu: User Mode Instruction Prevention (UMIP) activated186test-machine # [ 0.546971] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127187test-machine # [ 0.546971] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0188test-machine # [ 0.546980] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto189test-machine # [ 0.547971] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl190test-machine # [ 0.547971] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191test-machine # [ 0.547971] Speculative Return Stack Overflow: Mitigation: Safe RET192test-machine # [ 0.548971] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193test-machine # [ 0.548971] Spectre V2 : Enabling IBPB for BPF194test-machine # [ 0.548971] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195test-machine # [ 0.548971] active return thunk: srso_alias_return_thunk196test-machine # [ 0.548971] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197test-machine # [ 0.548971] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198test-machine # [ 0.549971] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199test-machine # [ 0.549971] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200test-machine # [ 0.549971] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201test-machine # [ 0.549971] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202test-machine # [ 0.549976] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203test-machine # [ 0.550971] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'204test-machine # [ 0.551971] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'205test-machine # [ 0.551978] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256206test-machine # [ 0.552971] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64207test-machine # [ 0.552975] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512208test-machine # [ 0.553976] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024209test-machine # [ 0.554971] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8210test-machine # [ 0.554976] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16211test-machine # [ 0.555974] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24212test-machine # [ 0.556975] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.213test-machine # [ 0.591971] Freeing SMP alternatives memory: 44K214test-machine # [ 0.591971] pid_max: default: 32768 minimum: 301215test-machine # [ 0.592080] LSM: initializing lsm=capability,landlock,yama,bpf,ima216test-machine # [ 0.592971] landlock: Up and running.217test-machine # [ 0.592974] Yama: becoming mindful.218test-machine # [ 0.593971] LSM support for eBPF active219test-machine # [ 0.594147] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)220test-machine # [ 0.594971] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)221test-machine # [ 0.595971] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)222test-machine # [ 0.596635] Performance Events: Fam17h+ core perfctr, AMD PMU driver.223test-machine # [ 0.596980] ... version: 2224test-machine # [ 0.597976] ... bit width: 48225test-machine # [ 0.598996] ... generic counters: 6226test-machine # [ 0.599976] ... generic bitmap: 000000000000003f227test-machine # [ 0.600976] ... fixed-purpose counters: 0228test-machine # [ 0.601976] ... fixed-purpose bitmap: 0000000000000000229test-machine # [ 0.602976] ... value mask: 0000ffffffffffff230test-machine # [ 0.603976] ... max period: 00007fffffffffff231test-machine # [ 0.604976] ... global_ctrl mask: 000000000000003f232test-machine # [ 0.606145] signal: max sigframe size: 3376233test-machine # [ 0.607102] rcu: Hierarchical SRCU implementation.234test-machine # [ 0.607982] rcu: Max phase no-delay instances is 400.235test-machine # [ 0.609641] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level236test-machine # [ 0.615405] smp: Bringing up secondary CPUs ...237test-machine # [ 0.616576] smpboot: x86: Booting SMP configuration:238test-machine # [ 0.616980] .... node #0, CPUs: #1 #2 #3239test-machine # [ 0.620193] smp: Brought up 1 node, 4 CPUs240test-machine # [ 0.622757] smpboot: Total of 4 processors activated (33536.60 BogoMIPS)241test-machine # [ 0.626202] Memory: 3959424K/4193700K available (17198K kernel code, 2726K rwdata, 13572K rodata, 3644K init, 2992K bss, 219224K reserved, 0K cma-reserved)242test-machine # [ 0.627978] devtmpfs: initialized243test-machine # [ 0.629217] x86/mm: Memory block size: 128MB244test-machine # [ 0.632197] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear)245test-machine # [ 0.634065] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear).246test-machine # [ 0.635135] pinctrl core: initialized pinctrl subsystem247test-machine # [ 0.636396] PM: RTC time: 16:27:18, date: 2026-08-03248test-machine # [ 0.640392] NET: Registered PF_NETLINK/PF_ROUTE protocol family249test-machine # [ 0.641957] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations250test-machine # [ 0.643013] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations251test-machine # [ 0.644556] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations252test-machine # [ 0.644999] audit: initializing netlink subsys (disabled)253test-machine # [ 0.646339] thermal_sys: Registered thermal governor 'fair_share'254test-machine # [ 0.646341] thermal_sys: Registered thermal governor 'bang_bang'255test-machine # [ 0.646979] audit: type=2000 audit(1785774438.615:1): state=initialized audit_enabled=0 res=1256test-machine # [ 0.647983] thermal_sys: Registered thermal governor 'step_wise'257test-machine # [ 0.649977] thermal_sys: Registered thermal governor 'user_space'258test-machine # [ 0.650977] thermal_sys: Registered thermal governor 'power_allocator'259test-machine # [ 0.652008] cpuidle: using governor menu260test-machine # [ 0.655578] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5261test-machine # [ 0.657341] PCI: Using configuration type 1 for base access262test-machine # [ 0.657976] PCI: Using configuration type 1 for extended access263test-machine # [ 0.659196] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.264test-machine # [ 0.660971] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages265test-machine # [ 0.660985] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page266test-machine # [ 0.661971] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages267test-machine # [ 0.661971] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page268test-machine # [ 0.675835] ACPI: Added _OSI(Module Device)269test-machine # [ 0.676822] ACPI: Added _OSI(Processor Device)270test-machine # [ 0.676971] ACPI: Added _OSI(Processor Aggregator Device)271test-machine # [ 0.679971] ACPI: 1 ACPI AML tables successfully acquired and loaded272test-machine # [ 0.681385] ACPI: Interpreter enabled273test-machine # [ 0.681971] ACPI: PM: (supports S0 S3 S4 S5)274test-machine # [ 0.683808] ACPI: Using IOAPIC for interrupt routing275test-machine # [ 0.685062] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug276test-machine # [ 0.686976] PCI: Using E820 reservations for host bridge windows277test-machine # [ 0.688169] ACPI: Enabled 2 GPEs in block 00 to 0F278test-machine # [ 0.690971] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])279test-machine # [ 0.691984] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]280test-machine # [ 0.693499] acpiphp: Slot [3] registered281test-machine # [ 0.694009] acpiphp: Slot [4] registered282test-machine # [ 0.695013] acpiphp: Slot [5] registered283test-machine # [ 0.696008] acpiphp: Slot [6] registered284test-machine # [ 0.697020] acpiphp: Slot [7] registered285test-machine # [ 0.698009] acpiphp: Slot [8] registered286test-machine # [ 0.699008] acpiphp: Slot [9] registered287test-machine # [ 0.700009] acpiphp: Slot [10] registered288test-machine # [ 0.701020] acpiphp: Slot [11] registered289test-machine # [ 0.702011] acpiphp: Slot [12] registered290test-machine # [ 0.703010] acpiphp: Slot [13] registered291test-machine # [ 0.704009] acpiphp: Slot [14] registered292test-machine # [ 0.706022] acpiphp: Slot [15] registered293test-machine # [ 0.707039] acpiphp: Slot [16] registered294test-machine # [ 0.708009] acpiphp: Slot [17] registered295test-machine # [ 0.709020] acpiphp: Slot [18] registered296test-machine # [ 0.710010] acpiphp: Slot [19] registered297test-machine # [ 0.711010] acpiphp: Slot [20] registered298test-machine # [ 0.712010] acpiphp: Slot [21] registered299test-machine # [ 0.713022] acpiphp: Slot [22] registered300test-machine # [ 0.714009] acpiphp: Slot [23] registered301test-machine # [ 0.715010] acpiphp: Slot [24] registered302test-machine # [ 0.716010] acpiphp: Slot [25] registered303test-machine # [ 0.717033] acpiphp: Slot [26] registered304test-machine # [ 0.718010] acpiphp: Slot [27] registered305test-machine # [ 0.719010] acpiphp: Slot [28] registered306test-machine # [ 0.721009] acpiphp: Slot [29] registered307test-machine # [ 0.722022] acpiphp: Slot [30] registered308test-machine # [ 0.723011] acpiphp: Slot [31] registered309test-machine # [ 0.723971] PCI host bridge to bus 0000:00310test-machine # [ 0.723971] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]311test-machine # [ 0.724978] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]312test-machine # [ 0.725971] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]313test-machine # [ 0.725971] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]314test-machine # [ 0.726971] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window]315test-machine # [ 0.727984] pci_bus 0000:00: root bus resource [mem 0xfed40000-0xfed44fff]316test-machine # [ 0.728979] pci_bus 0000:00: root bus resource [bus 00-ff]317test-machine # [ 0.730460] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint318test-machine # [ 0.733961] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint319test-machine # [ 0.735976] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint320test-machine # [ 0.740029] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]321test-machine # [ 0.742045] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk322test-machine # [ 0.743977] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk323test-machine # [ 0.745977] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk324test-machine # [ 0.746979] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk325test-machine # [ 0.747971] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint326test-machine # [ 0.748971] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]327test-machine # [ 0.748993] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint328test-machine # [ 0.750979] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI329test-machine # [ 0.751992] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB330test-machine # [ 0.754399] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint331test-machine # [ 0.778971] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]332test-machine # [ 0.781002] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]333test-machine # [ 0.782026] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]334test-machine # [ 0.783210] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]335test-machine # [ 0.784835] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint336test-machine # [ 0.788988] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]337test-machine # [ 0.789990] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]338test-machine # [ 0.791028] pci 0000:00:03.0: BAR 4 [mem 0xe0000000000-0xe0000003fff 64bit pref]339test-machine # [ 0.792992] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]340test-machine # [ 0.795971] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint341test-machine # [ 0.796989] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]342test-machine # [ 0.797971] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]343test-machine # [ 0.800024] pci 0000:00:04.0: BAR 4 [mem 0xe0000004000-0xe0000007fff 64bit pref]344test-machine # [ 0.804045] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint345test-machine # [ 0.804971] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]346test-machine # [ 0.805971] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]347test-machine # [ 0.806971] pci 0000:00:05.0: BAR 4 [mem 0xe0000008000-0xe000000bfff 64bit pref]348test-machine # [ 0.810971] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint349test-machine # [ 0.810971] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]350test-machine # [ 0.810990] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]351test-machine # [ 0.811971] pci 0000:00:06.0: BAR 4 [mem 0xe000000c000-0xe000000ffff 64bit pref]352test-machine # [ 0.816085] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint353test-machine # [ 0.817988] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]354test-machine # [ 0.819990] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]355test-machine # [ 0.821023] pci 0000:00:07.0: BAR 4 [mem 0xe0000010000-0xe0000013fff 64bit pref]356test-machine # [ 0.823836] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint357test-machine # [ 0.826988] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]358test-machine # [ 0.828004] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]359test-machine # [ 0.828971] pci 0000:00:08.0: BAR 4 [mem 0xe0000014000-0xe0000017fff 64bit pref]360test-machine # [ 0.829971] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint361test-machine # [ 0.830992] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]362test-machine # [ 0.831971] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]363test-machine # [ 0.832079] pci 0000:00:09.0: BAR 4 [mem 0xe0000018000-0xe000001bfff 64bit pref]364test-machine # [ 0.832971] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]365test-machine # [ 0.834971] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint366test-machine # [ 0.840554] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]367test-machine # [ 0.840971] pci 0000:00:0a.0: BAR 4 [mem 0xe000001c000-0xe000001ffff 64bit pref]368test-machine # [ 0.842204] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint369test-machine # [ 0.845990] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]370test-machine # [ 0.846991] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]371test-machine # [ 0.849028] pci 0000:00:0b.0: BAR 4 [mem 0xe0000020000-0xe0000023fff 64bit pref]372test-machine # [ 0.852971] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint373test-machine # [ 0.853971] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]374test-machine # [ 0.854971] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]375test-machine # [ 0.855042] pci 0000:00:0c.0: BAR 4 [mem 0xe0000024000-0xe0000027fff 64bit pref]376test-machine # [ 0.862483] ACPI: PCI: Interrupt link LNKA configured for IRQ 10377test-machine # [ 0.862971] ACPI: PCI: Interrupt link LNKB configured for IRQ 10378test-machine # [ 0.862971] ACPI: PCI: Interrupt link LNKC configured for IRQ 11379test-machine # [ 0.863971] ACPI: PCI: Interrupt link LNKD configured for IRQ 11380test-machine # [ 0.864080] ACPI: PCI: Interrupt link LNKS configured for IRQ 9381test-machine # [ 0.866036] iommu: Default domain type: Translated382test-machine # [ 0.866971] iommu: DMA domain TLB invalidation policy: lazy mode383test-machine # [ 0.869267] ACPI: bus type USB registered384test-machine # [ 0.869971] usbcore: registered new interface driver usbfs385test-machine # [ 0.871009] usbcore: registered new interface driver hub386test-machine # [ 0.872997] usbcore: registered new device driver usb387test-machine # [ 0.875961] NetLabel: Initializing388test-machine # [ 0.876671] NetLabel: domain hash size = 128389test-machine # [ 0.876976] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO390test-machine # [ 0.879102] NetLabel: unlabeled traffic allowed by default391test-machine # [ 0.879971] PCI: Using ACPI for IRQ routing392test-machine # [ 0.881168] pci 0000:00:02.0: vgaarb: setting as boot VGA device393test-machine # [ 0.881971] pci 0000:00:02.0: vgaarb: bridge control possible394test-machine # [ 0.881971] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none395test-machine # [ 0.882978] vgaarb: loaded396test-machine # [ 0.883975] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0397test-machine # [ 0.884971] hpet0: 3 comparators, 64-bit 100.000000 MHz counter398test-machine # [ 0.886974] clocksource: Switched to clocksource kvm-clock399test-machine # [ 0.889077] VFS: Disk quotas dquot_6.6.0400test-machine # [ 0.890229] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)401test-machine # [ 0.894444] pnp: PnP ACPI init402test-machine # [ 0.896116] pnp: PnP ACPI: found 6 devices403test-machine # [ 0.908374] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns404test-machine # [ 0.915169] clocksource: Switched to clocksource acpi_pm405test-machine # [ 0.916959] NET: Registered PF_INET protocol family406test-machine # [ 0.919748] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)407test-machine # [ 0.941932] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)408test-machine # [ 0.944596] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)409test-machine # [ 0.946647] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)410test-machine # [ 0.950558] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)411test-machine # [ 0.952695] TCP: Hash tables configured (established 32768 bind 32768)412test-machine # [ 0.954789] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)413test-machine # [ 0.957050] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)414test-machine # [ 0.959225] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)415test-machine # [ 0.961611] NET: Registered PF_UNIX/PF_LOCAL protocol family416test-machine # [ 0.963462] NET: Registered PF_XDP protocol family417test-machine # [ 0.964867] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]418test-machine # [ 0.966921] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]419test-machine # [ 0.968573] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]420test-machine # [ 0.970632] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]421test-machine # [ 0.972469] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window]422test-machine # [ 0.974807] pci_bus 0000:00: resource 9 [mem 0xfed40000-0xfed44fff]423test-machine # [ 0.976764] pci 0000:00:01.0: PIIX3: Enabling Passive Release424test-machine # [ 0.978561] pci 0000:00:00.0: Limiting direct PCI/PCI transfers425test-machine # [ 0.982469] ACPI: \_SB_.LNKD: Enabled at IRQ 11426test-machine # [ 0.986680] PCI: CLS 0 bytes, default 64427test-machine # [ 0.987873] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)428test-machine # [ 0.988127] Trying to unpack rootfs image as initramfs...429test-machine # [ 0.988185] software IO TLB: mapped [mem 0x00000000ba300000-0x00000000be300000] (64MB)430test-machine # [ 0.993265] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns431test-machine # [ 1.017530] Initialise system trusted keyrings432test-machine # [ 1.018897] workingset: timestamp_bits=40 max_order=20 bucket_order=0433test-machine # [ 1.032452] Key type asymmetric registered434test-machine # [ 1.033535] Asymmetric key parser 'x509' registered435test-machine # [ 1.034929] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)436test-machine # [ 1.037668] io scheduler mq-deadline registered437test-machine # [ 1.038903] io scheduler kyber registered438test-machine # [ 1.042698] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled439test-machine # [ 1.044625] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A440test-machine # [ 1.050081] Linux agpgart interface v0.103441test-machine # [ 1.058014] tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x1, rev-id 1)442test-machine # [ 1.084651] ACPI: bus type drm_connector registered443test-machine # [ 1.089809] usbcore: registered new interface driver usbserial_generic444test-machine # [ 1.091466] usbserial: USB Serial support registered for generic445test-machine # [ 1.092917] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.446test-machine # [ 1.092917] Please enable it if your BIOS has the CPPC option.447test-machine # [ 1.096342] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled448test-machine # [ 1.098367] drop_monitor: Initializing network drop monitor service449test-machine # [ 1.100196] NET: Registered PF_INET6 protocol family450test-machine # [ 1.102477] Segment Routing with IPv6451test-machine # [ 1.103563] In-situ OAM (IOAM) with IPv6452test-machine # [ 1.107313] IPI shorthand broadcast: enabled453test-machine # [ 1.112129] sched_clock: Marking stable (859153364, 252815637)->(1357005042, -245036041)454test-machine # [ 1.114921] registered taskstats version 1455test-machine # [ 1.116466] Loading compiled-in X.509 certificates456test-machine # [ 1.129170] Demotion targets for Node 0: null457test-machine # [ 1.130748] Key type .fscrypt registered458test-machine # [ 1.133574] Key type fscrypt-provisioning registered459test-machine # [ 1.152659] ima: Allocated hash algorithm: sha1460test-machine # [ 1.186293] ima: No architecture policies found461test-machine # [ 1.193085] PM: Magic number: 6:859:488462test-machine # [ 1.194405] regulator regulator.0: hash matches463test-machine # [ 1.197443] RAS: Correctable Errors collector initialized.464test-machine # [ 1.203949] clk: Disabling unused clocks465test-machine # [ 1.205414] PM: genpd: Disabling unused power domains466test-machine # [ 1.505650] Freeing initrd memory: 29440K467test-machine # [ 1.520908] Freeing unused decrypted memory: 2028K468test-machine # [ 1.526264] Freeing unused kernel image (initmem) memory: 3644K469test-machine # [ 1.528500] Write protecting the kernel read-only data: 32768k470test-machine # [ 1.533166] Freeing unused kernel image (text/rodata gap) memory: 1232K471test-machine # [ 1.536023] Freeing unused kernel image (rodata/data gap) memory: 764K472test-machine # [ 1.589887] x86/mm: Checked W+X mappings: passed, no W+X pages found.473test-machine # [ 1.622871] Run /init as init process474test-machine # [ 1.650437] systemd[1]: Inserted module 'autofs4'475test-machine # [ 1.721496] fuse: init (API version 7.45)476test-machine # [ 1.757873] ACPI: \_SB_.LNKC: Enabled at IRQ 10477test-machine # [ 1.767481] ACPI: \_SB_.LNKA: Enabled at IRQ 10478test-machine # [ 1.771541] ACPI: \_SB_.LNKB: Enabled at IRQ 11479test-machine # [ 1.827171] systemd[1]: Successfully made /usr/ read-only.480test-machine # [ 2.164184] systemd[1]: systemd 261.1 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)481test-machine # [ 2.171895] systemd[1]: Detected virtualization kvm.482test-machine # [ 2.173319] systemd[1]: Detected architecture x86-64.483test-machine # [ 2.174691] systemd[1]: Running in initrd.484test-machine # [ 2.176315] systemd[1]: Initializing machine ID from random generator.485test-machine # [ 2.178258] systemd[1]: Hostname set to <test-machine>.486test-machine # [ 2.305048] systemd[1]: bpf-restrict-fs: LSM BPF program attached487test-machine # [ 2.347111] systemd[1]: Queued start job for default target Initrd Default Target.488test-machine # [ 2.359918] systemd[1]: Created slice Slice /system/modprobe.489test-machine # [ 2.361679] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.490test-machine # [ 2.363703] systemd[1]: Expecting device /dev/disk/by-label/nixos...491test-machine # [ 2.365402] systemd[1]: Reached target Path Units.492test-machine # [ 2.366734] systemd[1]: Reached target Slice Units.493test-machine # [ 2.368122] systemd[1]: Reached target Swaps.494test-machine # [ 2.369366] systemd[1]: Reached target Timer Units.495test-machine # [ 2.370809] systemd[1]: Listening on D-Bus System Message Bus Socket.496test-machine # [ 2.372684] systemd[1]: Listening on Journal Socket (/dev/log).497test-machine # [ 2.374420] systemd[1]: Listening on Journal Sockets.498test-machine # [ 2.375950] systemd[1]: Listening on udev Control Socket.499test-machine # [ 2.377492] systemd[1]: Listening on udev Kernel Socket.500test-machine # [ 2.378934] systemd[1]: Reached target Socket Units.501test-machine # [ 2.384251] systemd[1]: Starting Create List of Static Device Nodes...502test-machine # [ 2.387074] systemd[1]: Starting Load Kernel Module 9pnet_virtio...503test-machine # [ 2.393189] systemd[1]: Starting Load Kernel Module configfs...504test-machine # [ 2.397480] systemd[1]: Starting Journal Service...505test-machine # [ 2.401096] systemd[1]: Starting Load Kernel Modules...506test-machine # [ 2.402516] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os507test-machine # [ 2.407928] systemd[1]: Starting Coldplug All udev Devices...508test-machine # [ 2.412199] systemd[1]: Finished Create List of Static Device Nodes.509test-machine # [ 2.415296] systemd[1]: modprobe@configfs.service: Deactivated successfully.510test-machine # [ 2.415715] systemd-journald[96]: Collecting audit messages is disabled.511test-machine # [ 2.452760] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.512test-machine # [ 2.456327] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev513test-machine # [ 2.456520] netfs: FS-Cache loaded514test-machine # [ 2.463373] systemd[1]: Finished Load Kernel Module configfs.515test-machine # [ 2.473953] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config516test-machine # [ 2.486575] 9pnet: Installing 9P2000 support517test-machine # [ 2.491030] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...518test-machine # [ 2.497676] systemd[1]: Finished Load Kernel Modules.519test-machine # [ 2.509967] systemd[1]: Starting Apply Kernel Variables...520test-machine # [ 2.512098] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.521test-machine # [ 2.514647] systemd[1]: Finished Load Kernel Module 9pnet_virtio.522test-machine # [ 2.263076] systemd-modules-load[97]: Using 4 probe threads[ 2.518119] systemd[1]: Started Journal Service.523test-machine # 524test-machine # [ 2.268245] systemd-modules-load[97]: Inserted module 'dm_mod'525test-machine # [ 2.270244] systemd-modules-load[97]: Inserted module 'virtio_balloon'526test-machine # [ 2.272188] systemd-modules-load[97]: Inserted module 'virtio_gpu'527test-machine # [ 2.274365] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.528test-machine # [ 2.276736] systemd[1]: Finished Apply Kernel Variables.529test-machine # [ 2.286342] systemd[1]: Starting Create Static Device Nodes in /dev...530test-machine # [ 2.319909] systemd[1]: Finished Create Static Device Nodes in /dev.531test-machine # [ 2.322709] systemd[1]: Reached target Preparation for Local File Systems.532test-machine # [ 2.325102] systemd[1]: Reached target Local File Systems.533test-machine # [ 2.335270] systemd[1]: Starting Create System Files and Directories...534test-machine # [ 2.338194] systemd[1]: Starting Rule-based Manager for Device Events and Files...535test-machine # [ 2.375346] systemd[1]: Finished Create System Files and Directories.536test-machine # [ 2.380672] systemd-udevd[119]: Using default interface naming scheme 'v261'.537test-machine # [ 2.385322] systemd[1]: Finished Coldplug All udev Devices.538test-machine # [ 2.401451] systemd[1]: Started Rule-based Manager for Device Events and Files.539test-machine # [ 2.406186] systemd[1]: Reached target System Initialization.540test-machine # [ 2.419579] systemd[1]: Reached target Basic System.541test-machine # [ 2.772065] hrtimer: interrupt took 7976432 ns542test-machine # [ 2.642712] systemd[1]: Expecting device /dev/tpm0...543test-machine # [ 2.700398] systemd[1]: Found device /dev/tpm0.544test-machine # [ 2.701830] systemd[1]: Reached target Trusted Platform Module.545test-machine # [ 2.978632] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12546test-machine # [ 2.983467] serio: i8042 KBD port at 0x60,0x64 irq 1547test-machine # [ 2.984509] serio: i8042 AUX port at 0x60,0x64 irq 12548test-machine # [ 2.989080] SCSI subsystem initialized549test-machine # [ 3.024786] uhci_hcd 0000:00:01.2: UHCI Host Controller550test-machine # [ 3.032492] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1551test-machine # [ 3.035291] uhci_hcd 0000:00:01.2: detected 2 ports552test-machine # [ 3.037191] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100553test-machine # [ 3.037329] virtio_blk virtio5: 4/0/0 default/read/poll queues554test-machine # [ 3.039774] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18555test-machine # [ 3.042687] scsi host0: ata_piix556test-machine # [ 3.044516] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1557test-machine # [ 3.044518] usb usb1: Product: UHCI Host Controller558test-machine # [ 3.046916] scsi host1: ata_piix559test-machine # [ 3.047567] usb usb1: Manufacturer: Linux 6.18.41 uhci_hcd560test-machine # [ 3.047917] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0561test-machine # [ 3.048149] usb usb1: SerialNumber: 0000:00:01.2562test-machine # [ 3.048508] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0563test-machine # [ 3.050286] hub 1-0:1.0: USB hub found564test-machine # [ 3.052810] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)565test-machine # [ 3.061895] hub 1-0:1.0: 2 ports detected566test-machine # [ 2.873138] systemd[1]: Starting Virtual Console Setup...567test-machine # [ 2.906465] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.568test-machine # [ 2.921986] systemd[1]: Finished Virtual Console Setup.569test-machine # [ 3.225636] ata2: found unknown device (class 0)570test-machine # [ 3.236982] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100571test-machine # [ 3.243877] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5572test-machine # [ 3.015136] (udev-worker)[123]: Network interface NamePolicy= disabled on kernel command line.573test-machine # [ 3.017835] (udev-worker)[131]: Network interface NamePolicy= disabled on kernel command line.574test-machine # [ 3.275579] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0575test-machine # [ 3.035431] systemd[1]: Found device /dev/disk/by-label/nixos.576test-machine # [ 3.036833] systemd[1]: Reached target Initrd Root Device.577test-machine # [ 3.038428] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...578test-machine # [ 3.309894] usb 1-1: new full-speed USB device number 2 using uhci_hcd579test-machine # [ 3.313331] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray580test-machine # [ 3.067786] systemd-fsck[163]: nixos: clean, 12/65536 files, 13019/262144 blocks581test-machine # [ 3.342074] cdrom: Uniform CD-ROM driver Revision: 3.20582test-machine # [ 3.098821] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.583test-machine # [ 3.160238] systemd[1]: Mounting /sysroot...584test-machine # [ 3.483990] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00585test-machine # [ 3.485753] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10586test-machine # [ 3.487521] usb 1-1: Product: QEMU USB Tablet587test-machine # [ 3.488718] usb 1-1: Manufacturer: QEMU588test-machine # [ 3.489676] usb 1-1: SerialNumber: 28754-0000:00:01.2-1589test-machine # [ 3.534311] hid: raw HID events driver (C) Jiri Kosina590test-machine # [ 3.547874] usbcore: registered new interface driver usbhid591test-machine # [ 3.548988] usbhid: USB HID core driver592test-machine # [ 3.557572] 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/input2593test-machine # [ 3.561395] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0594test-machine # [ 3.571465] EXT4-fs (vda): mounted filesystem 02021c56-502d-4ec9-acaa-e8af369360be r/w with ordered data mode. Quota mode: none.595test-machine # [ 3.322934] systemd[1]: Mounted /sysroot.596test-machine # [ 3.324441] systemd[1]: Reached target Initrd Root File System.597test-machine # [ 3.325672] systemd[1]: Mounting /sysroot/nix/.ro-store...598test-machine # [ 3.327051] systemd[1]: Mounting /sysroot/nix/.rw-store...599test-machine # [ 3.328248] systemd[1]: Mounting /sysroot/run...600test-machine # [ 3.329613] systemd[1]: Mounting /sysroot/tmp/shared...601test-machine # [ 3.339476] systemd[1]: Mounting /sysroot/tmp/xchg...602test-machine # [ 3.343151] systemd[1]: Starting Mountpoints Configured in the Real Root...603test-machine # [ 3.344596] systemd[1]: Mounted /sysroot/nix/.rw-store.604test-machine # [ 3.345816] systemd[1]: Mounted /sysroot/run.605test-machine # [ 3.347800] systemd[1]: Starting rw-sysroot-nix-store.service...606test-machine # [ 3.603699] 9p: Installing v9fs 9p2000 file system support607test-machine # [ 3.355401] systemd[1]: Mounted /sysroot/nix/.ro-store.608test-machine # [ 3.357401] systemd-sysroot-fstab-check[183]: /sysroot should be mounted in the initrd, will request daemon-reload.609test-machine # [ 3.359716] systemd[1]: Mounted /sysroot/tmp/shared.610test-machine # [ 3.360986] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.611test-machine # [ 3.362538] systemd[1]: Finished rw-sysroot-nix-store.service.612test-machine # [ 3.364109] systemd[1]: Mounted /sysroot/tmp/xchg.613test-machine # [ 3.365331] systemd[1]: Reload requested from client PID 183 ('systemd-sysroot') (unit initrd-parse-etc.service)...614test-machine # [ 3.367580] systemd[1]: Reloading...615test-machine # [ 3.431852] systemd[1]: Reloading finished in 67 ms.616test-machine # [ 3.457893] systemd-sysroot-fstab-check[183]: Requesting initrd-fs.target/start/replace...617test-machine # [ 3.463158] systemd-sysroot-fstab-check[183]: Requesting swap.target/start/replace...618test-machine # [ 3.465577] systemd[1]: Starting rw-sysroot-nix-store.service...619test-machine # [ 3.467061] systemd[1]: initrd-parse-etc.service: Deactivated successfully.620test-machine # [ 3.469679] systemd[1]: Finished Mountpoints Configured in the Real Root.621test-machine # [ 3.471435] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.622test-machine # [ 3.480179] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.623test-machine # [ 3.481783] systemd[1]: Finished rw-sysroot-nix-store.service.624test-machine # [ 4.162667] systemd[1]: Mounting /sysroot/nix/store...625test-machine # [ 4.185894] systemd[1]: Mounted /sysroot/nix/store.626test-machine # [ 4.188203] systemd[1]: Starting Find the path to the etc metadata image and based dir...627test-machine # [ 4.190117] systemd[1]: Starting Find NixOS closure...628test-machine # [ 4.205308] find-etc[214]: Found init: /nix/store/1rplylvhk66257n04kx9wdbazvcgvvw6-nixos-system-test-machine-test/init.629test-machine # [ 4.209731] systemd[1]: Finished Find the path to the etc metadata image and based dir.630test-machine # [ 4.213117] systemd[1]: Mounting /run/nixos-etc-metadata...631test-machine # [ 4.232202] systemd[1]: Finished Find NixOS closure.632test-machine # [ 4.527137] loop: module loaded633test-machine # [ 4.534141] loop0: detected capacity change from 0 to 88634test-machine # [ 4.566306] erofs (device loop0): mounted with root inode @ nid 36.635test-machine # [ 4.316622] systemd[1]: Mounted /run/nixos-etc-metadata.636test-machine # [ 4.326341] systemd[1]: Starting rw-etc.service...637test-machine # [ 4.383469] systemd[1]: rw-etc.service: Deactivated successfully.638test-machine # [ 4.384789] systemd[1]: Finished rw-etc.service.639test-machine # [ 4.395665] systemd[1]: Mounting /sysroot/etc...640test-machine # [ 4.439791] systemd[1]: Mounted /sysroot/etc.641test-machine # [ 4.440877] systemd[1]: Reached target Initrd File Systems.642test-machine # [ 4.445652] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...643test-machine # [ 4.463349] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.644test-machine # [ 4.467776] systemd[1]: Reached target Initrd Default Target.645test-machine # [ 4.473492] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...646test-machine # [ 4.515675] systemd[1]: Stopped target Initrd Default Target.647test-machine # [ 4.518483] systemd[1]: Stopped target Basic System.648test-machine # [ 4.519794] systemd[1]: Stopped target Initrd Root Device.649test-machine # [ 4.521173] systemd[1]: Stopped target Path Units.650test-machine # [ 4.523518] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.651test-machine # [ 4.525417] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.652test-machine # [ 4.531277] systemd[1]: Stopped target Slice Units.653test-machine # [ 4.532259] systemd[1]: Stopped target Socket Units.654test-machine # [ 4.540535] systemd[1]: Stopped target System Initialization.655test-machine # [ 4.543935] systemd[1]: Stopped target Swaps.656test-machine # [ 4.544850] systemd[1]: Stopped target Timer Units.657test-machine # [ 4.546283] systemd[1]: Stopped target Trusted Platform Module.658test-machine # [ 4.548654] systemd[1]: dbus.socket: Deactivated successfully.659test-machine # [ 4.549886] systemd[1]: Closed D-Bus System Message Bus Socket.660test-machine # [ 4.553256] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.661test-machine # [ 4.557737] systemd[1]: Stopped Find NixOS closure.662test-machine # [ 4.559207] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio663test-machine # [ 4.561529] systemd[1]: Starting rw-etc.service...664test-machine # [ 4.563322] systemd[1]: Starting rw-sysroot-nix-store.service...665test-machine # [ 4.564965] systemd[1]: systemd-sysctl.service: Deactivated successfully.666test-machine # [ 4.567084] systemd[1]: Stopped Apply Kernel Variables.667test-machine # [ 4.569898] systemd[1]: systemd-modules-load.service: Deactivated successfully.668test-machine # [ 4.572499] systemd[1]: Stopped Load Kernel Modules.669test-machine # [ 4.574056] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.670test-machine # [ 4.576226] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.671test-machine # [ 4.578106] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.672test-machine # [ 4.580202] systemd[1]: Stopped Create System Files and Directories.673test-machine # [ 4.582192] systemd[1]: Stopped target Local File Systems.674test-machine # [ 4.583681] systemd[1]: Stopped target Preparation for Local File Systems.675test-machine # [ 4.585243] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.676test-machine # [ 4.587434] systemd[1]: Stopped Coldplug All udev Devices.677test-machine # [ 4.588938] systemd[1]: Stopping Rule-based Manager for Device Events and Files...678test-machine # [ 4.591145] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.679test-machine # [ 4.593752] systemd[1]: Stopped Virtual Console Setup.680test-machine # [ 4.594909] systemd[1]: initrd-cleanup.service: Deactivated successfully.681test-machine # [ 4.597106] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.682test-machine # [ 4.598914] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.683test-machine # [ 4.600979] systemd[1]: Finished rw-sysroot-nix-store.service.684test-machine # [ 4.605812] systemd[1]: rw-etc.service: Deactivated successfully.685test-machine # [ 4.607814] systemd[1]: Finished rw-etc.service.686test-machine # [ 4.609454] systemd[1]: systemd-udevd.service: Deactivated successfully.687test-machine # [ 4.611703] systemd[1]: Stopped Rule-based Manager for Device Events and Files.688test-machine # [ 4.613564] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.689test-machine # [ 4.615174] systemd[1]: Closed udev Control Socket.690test-machine # [ 4.618260] systemd[1]: Starting Cleanup udev Database...691test-machine # [ 4.620087] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.692test-machine # [ 4.621705] systemd[1]: Stopped Create Static Device Nodes in /dev.693test-machine # [ 4.625539] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.694test-machine # [ 4.627466] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.695test-machine # [ 4.629400] systemd[1]: kmod-static-nodes.service: Deactivated successfully.696test-machine # [ 4.631198] systemd[1]: Stopped Create List of Static Device Nodes.697test-machine # [ 4.643941] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.698test-machine # [ 4.648399] systemd[1]: Finished Cleanup udev Database.699test-machine # [ 4.650810] systemd[1]: Reached target Switch Root.700test-machine # [ 4.655250] systemd[1]: Starting NixOS Activation...701test-machine # [ 5.073401] initrd-nixos-activation-start[247]: booting system configuration /nix/store/1rplylvhk66257n04kx9wdbazvcgvvw6-nixos-system-test-machine-test702test-machine # [ 5.153391] initrd-nixos-activation-start[247]: running activation script...703test-machine # [ 5.606406] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.704test-machine # [ 5.607871] systemd[1]: Finished NixOS Activation.705test-machine # [ 5.608836] systemd[1]: Starting Switch Root...706test-machine # [ 5.635177] systemd[1]: Switching root.707test-machine # [ 5.935967] systemd-journald[96]: Received SIGTERM from PID 1 (systemd).708test-machine # [ 6.091587] NET: Registered PF_VSOCK protocol family709test-machine # [ 6.486912] systemd[1]: systemd 261.1 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)710test-machine # [ 6.492266] systemd[1]: Detected virtualization kvm.711test-machine # [ 6.493274] systemd[1]: Detected architecture x86-64.712test-machine # [ 6.494288] systemd[1]: Detected first boot.713test-machine # [ 6.497702] systemd[1]: Initializing machine ID from random generator.714test-machine # [ 6.652400] systemd[1]: bpf-restrict-fs: LSM BPF program attached715test-machine # [ 6.780647] zram: Added device: zram0716test-machine # [ 6.785630] systemd[1]: Applying preset policy.717test-machine # [ 7.223548] systemd[1]: Populated /etc with preset unit settings.718test-machine # [ 7.551053] systemd[1]: microvm@hermes-test.service: Service uses a combination of Type=simple, ExecStartPost=, and credentials. This could lead to race conditions. Continuing.719test-machine # [ 7.740138] systemd[1]: initrd-switch-root.service: Deactivated successfully.720test-machine # [ 7.742173] systemd[1]: Stopped initrd-switch-root.service.721test-machine # [ 7.744955] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.722test-machine # [ 7.747473] systemd[1]: Created slice Slice /system/microvm.723test-machine # [ 7.749259] systemd[1]: Created slice Slice /system/microvm-macvtap-interfaces.724test-machine # [ 7.751419] systemd[1]: Created slice Slice /system/microvm-pci-devices.725test-machine # [ 7.753512] systemd[1]: Created slice Slice /system/microvm-set-booted.726test-machine # [ 7.755511] systemd[1]: Created slice Slice /system/microvm-tap-interfaces.727test-machine # [ 7.757464] systemd[1]: Created slice Slice /system/microvm-virtiofsd.728test-machine # [ 7.759472] systemd[1]: Created slice Slice /system/systemd-zram-setup.729test-machine # [ 7.761302] systemd[1]: Created slice User and Session Slice.730test-machine # [ 7.762574] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.731test-machine # [ 7.764194] systemd[1]: Started Forward Password Requests to Wall Directory Watch.732test-machine # [ 7.765623] systemd[1]: Expecting device /dev/hvc0...733test-machine # [ 7.766603] systemd[1]: Expecting device /dev/ttyS0...734test-machine # [ 7.767628] systemd[1]: Expecting device /dev/zram0...735test-machine # [ 7.768659] systemd[1]: Reached target Local Encrypted Volumes.736test-machine # [ 7.769784] systemd[1]: Reached target Login Prompts.737test-machine # [ 7.770690] systemd[1]: Stopped target initrd-fs.target.738test-machine # [ 7.771732] systemd[1]: Stopped target initrd-root-fs.target.739test-machine # [ 7.772905] systemd[1]: Stopped target initrd-switch-root.target.740test-machine # [ 7.774241] systemd[1]: Reached target Virtual Machines and Containers.741test-machine # [ 7.775587] systemd[1]: Reached target Remote File Systems.742test-machine # [ 7.776744] systemd[1]: Reached target Slice Units.743test-machine # [ 7.780336] systemd[1]: Listening on Query the User Interactively for a Password.744test-machine # [ 7.783818] systemd[1]: Listening on Process Core Dump Socket.745test-machine # [ 7.786564] systemd[1]: Listening on Credential Encryption/Decryption.746test-machine # [ 7.789320] systemd[1]: Listening on Factory Reset Management.747test-machine # [ 7.790680] systemd[1]: Listening on Hostname Service Socket.748test-machine # [ 7.794540] systemd[1]: Starting Journal Log Access Socket...749test-machine # [ 7.796625] systemd[1]: Listening on Journal Audit Socket.750test-machine # [ 7.799742] systemd[1]: Listening on Console Output Muting Service Socket.751test-machine # [ 7.801341] systemd[1]: Listening on Network Management Metrics Varlink Socket.752test-machine # [ 7.803054] systemd[1]: Listening on Network Management Varlink Socket.753test-machine # [ 7.804409] systemd[1]: Listening on Network Management Netlink Socket.754test-machine # [ 7.805905] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.755test-machine # [ 7.818900] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os756test-machine # [ 7.820962] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki757test-machine # [ 7.830384] systemd[1]: Listening on Disk Repartitioning Service Socket.758test-machine # [ 7.831968] systemd[1]: Listening on Resolve Monitor Varlink Socket.759test-machine # [ 7.833433] systemd[1]: Listening on Resolve Service Varlink Socket.760test-machine # [ 7.834912] systemd[1]: Listening on udev Control Socket.761test-machine # [ 7.836310] systemd[1]: Listening on udev Varlink Socket.762test-machine # [ 7.840178] systemd[1]: Mounting Huge Pages File System...763test-machine # [ 7.843605] systemd[1]: Mounting POSIX Message Queue File System...764test-machine # [ 7.859664] systemd[1]: Mounting Kernel Debug File System...765test-machine # [ 7.863561] systemd[1]: Mounting Kernel Trace File System...766test-machine # [ 7.865521] systemd[1]: initrd-find-etc.service: Deactivated successfully.767test-machine # [ 7.867714] systemd[1]: Stopped initrd-find-etc.service.768test-machine # [ 7.872373] systemd[1]: Starting Create List of Static Device Nodes...769test-machine # [ 7.874482] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio770test-machine # [ 7.881758] systemd[1]: Starting Load Kernel Module configfs...771test-machine # [ 7.883797] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm772test-machine # [ 7.885752] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore773test-machine # [ 7.887690] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse774test-machine # [ 7.899746] systemd[1]: Mounting FUSE Control File System...775test-machine # [ 7.903147] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67776test-machine # [ 7.911274] systemd[1]: Starting Journal Service...777test-machine # [ 7.915376] systemd[1]: Starting Load Kernel Modules...778test-machine # [ 7.918908] systemd[1]: Starting Remount Root and Kernel File Systems...779test-machine # [ 7.920962] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os780test-machine # [ 7.927213] systemd[1]: Starting Coldplug All udev Devices...781test-machine # [ 7.931598] systemd[1]: Listening on Journal Log Access Socket.782test-machine # [ 7.934175] systemd[1]: Mounted Huge Pages File System.783test-machine # [ 7.935949] systemd[1]: Mounted POSIX Message Queue File System.784test-machine # [ 7.938510] systemd[1]: Mounted Kernel Debug File System.785test-machine # [ 7.939794] systemd[1]: Mounted Kernel Trace File System.786test-machine # [ 7.941970] systemd[1]: Finished Create List of Static Device Nodes.787test-machine # [ 7.945164] systemd[1]: modprobe@configfs.service: Deactivated successfully.788test-machine # [ 7.948303] systemd[1]: Finished Load Kernel Module configfs.789test-machine # [ 7.951624] systemd[1]: Mounted FUSE Control File System.790test-machine # [ 7.951924] EXT4-fs (vda): re-mounted 02021c56-502d-4ec9-acaa-e8af369360be.791test-machine # [ 7.954934] systemd-journald[345]: Collecting audit messages is enabled.792test-machine # [ 7.955478] systemd[1]: Mounting Kernel Configuration File System...793test-machine # [ 7.962915] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...794test-machine # [ 7.968744] systemd[1]: Finished Remount Root and Kernel File Systems.795test-machine # [ 7.970784] systemd[1]: Listening on Disk Image Download Service Socket.796test-machine # [ 7.972573] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore797test-machine # [ 7.978528] systemd[1]: Starting Load/Save OS Random Seed...798test-machine # [ 7.979892] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os799test-machine # [ 7.983472] tun: Universal TUN/TAP device driver, 1.6800test-machine # [ 7.738978] systemd[1]: Queued start job for default target Graphical Interface.801test-machine # [ 7.991736] systemd[1]: Finished Load Kernel Modules.802test-machine # [ 7.741799] systemd[1]: systemd-journald.service: Deactivated successfully.803test-machine # [ 7.744954] systemd-modules-load[346]: Using 4 probe threads804test-machine # [ 7.999664] systemd[1]: Started Journal Service.805test-machine # [ 7.747185] systemd-modules-load[346]: Inserted module 'tap'806test-machine # [ 7.750669] systemd-modules-load[346]: Inserted module 'vhost_vsock'807test-machine # [ 7.752383] systemd-modules-load[346]: Inserted module 'vhost_net'808test-machine # [ 7.755590] systemd[1]: Mounted Kernel Configuration File System.809test-machine # [ 7.765420] systemd[1]: Starting Flush Journal to Persistent Storage...810test-machine # [ 7.770080] systemd[1]: Starting Apply Kernel Variables...811test-machine # [ 7.786366] systemd[1]: Finished Load/Save OS Random Seed.812test-machine # [ 7.788583] systemd[1]: Reached target First Boot Complete.813test-machine # [ 8.050205] systemd-journald[345]: Received client request to flush runtime journal.814test-machine # [ 7.852897] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.815test-machine # [ 7.854670] systemd[1]: Starting Create Static Device Nodes in /dev...816test-machine # [ 7.856417] systemd[1]: Finished Apply Kernel Variables.817test-machine # [ 7.857507] systemd[1]: Starting Network Name Resolution...818test-machine # [ 7.858562] systemd[1]: Finished Create Static Device Nodes in /dev.819test-machine # [ 7.859775] systemd[1]: Reached target Preparation for Local File Systems.820test-machine # [ 7.861039] systemd[1]: Starting Rule-based Manager for Device Events and Files...821test-machine # [ 7.862446] systemd[1]: Finished Flush Journal to Persistent Storage.822test-machine # [ 7.863672] systemd[1]: Finished Coldplug All udev Devices.823test-machine # [ 7.897924] systemd-resolved[374]: Positive Trust Anchors:824test-machine # [ 7.899698] systemd-resolved[374]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d825test-machine # [ 7.902492] systemd-resolved[374]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16826test-machine # [ 7.905272] systemd-resolved[374]: 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 test827test-machine # [ 7.923085] systemd-udevd[377]: Using default interface naming scheme 'v261'.828test-machine # [ 7.997288] systemd-resolved[374]: Using system hostname 'test-machine'.829test-machine # [ 8.006512] systemd[1]: Started Network Name Resolution.830test-machine # [ 8.018923] systemd[1]: Started Rule-based Manager for Device Events and Files.831test-machine # [ 8.098475] systemd[1]: Found device /dev/zram0.832test-machine # [ 8.101327] systemd[1]: Starting Create swap on /dev/zram0...833test-machine # [ 8.107535] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse834test-machine # [ 8.132805] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.835test-machine # [ 8.389935] zram0: detected capacity change from 0 to 6417616836test-machine # [ 8.154651] (udev-worker)[392]: Network interface NamePolicy= disabled on kernel command line.837test-machine # [ 8.160085] (udev-worker)[399]: Network interface NamePolicy= disabled on kernel command line.838test-machine # [ 8.164412] systemd-makefs[407]: Formatting /dev/zram0 as swap839test-machine # [ 8.168501] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.840test-machine # [ 8.189205] systemd-makefs[407]: /dev/zram0 successfully formatted as swap (label "zram0", uuid 31a8c776-9329-404c-aa58-2ed97f86e95f)841test-machine # [ 8.193841] systemd[1]: Finished Create swap on /dev/zram0.842test-machine # [ 8.198795] systemd[1]: Activating swap Compressed Swap on /dev/zram0...843test-machine # [ 8.205138] systemd[1]: Condition check resulted in /dev/tpmrm0 being skipped.844test-machine # [ 8.207699] systemd[1]: Expecting device /dev/tpm0...845test-machine # [ 8.213149] systemd[1]: Found device /dev/tpm0.846test-machine # [ 8.215212] systemd[1]: Reached target Trusted Platform Module.847test-machine # [ 8.482024] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3848test-machine # [ 8.501277] ACPI: button: Power Button [PWRF]849test-machine # [ 8.506090] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console850test-machine # [ 8.507654] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0851test-machine # [ 8.507723] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD852test-machine # [ 8.509525] Adding 3208804k swap on /dev/zram0. Priority:5 extents:1 across:3208804k SSDsc853test-machine # [ 8.520025] Console: switching to colour dummy device 80x25854test-machine # [ 8.524721] [drm] Found bochs VGA, ID 0xb0c5.855test-machine # [ 8.272322] s[ 8.524723] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.856test-machine # ystemd[1]: Activated swap Compressed Swap on /dev/zram0.857test-machine # [ 8.530567] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic858test-machine # [ 8.531587] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0859test-machine # [ 8.282294] systemd[1]: Reached target Swaps.860test-machine # [ 8.285581] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...861test-machine # [ 8.549155] rtc_cmos 00:05: RTC can wake from S4862test-machine # [ 8.556996] rtc_cmos 00:05: registered as rtc0863test-machine # [ 8.557157] rtc_cmos 00:05: setting system clock to 2026-08-03T16:27:26 UTC (1785774446)864test-machine # [ 8.557277] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs865test-machine # [ 8.557363] Floppy drive(s): fd0 is 2.88M AMI BIOS866test-machine # [ 8.557739] mousedev: PS/2 mouse device common for all mice867test-machine # [ 8.559350] Console: switching to colour frame buffer device 160x50868test-machine # [ 8.559953] parport_pc 00:03: reported by Plug and Play ACPI869test-machine # [ 8.560042] systemd-journald[345]: Time jumped backwards, rotating.870test-machine # [ 8.560107] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]871test-machine # [ 8.574183] FDC 0 is a S82078B872test-machine # [ 8.577460] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device873test-machine # [ 8.582398] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5874test-machine # [ 8.588156] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4875test-machine # [ 8.344384] systemd-resolved[374]: Clock change detected. Flushing caches.876test-machine # [ 8.606494] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input6877test-machine # [ 8.380578] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.878test-machine # [ 8.427107] systemd[1]: Starting Virtual Console Setup...879test-machine # [ 8.696270] ppdev: user-space parallel port driver880test-machine # [ 8.453548] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.881test-machine # [ 8.455237] systemd[1]: Stopped Virtual Console Setup.882test-machine # [ 8.459118] systemd[1]: Starting Virtual Console Setup...883test-machine # [ 8.730265] kvm_amd: TSC scaling supported884test-machine # [ 8.730978] kvm_amd: Nested Virtualization enabled885test-machine # [ 8.732038] kvm_amd: Nested Paging enabled886test-machine # [ 8.733260] kvm_amd: LBR virtualization supported887test-machine # [ 8.734681] kvm_amd: Virtual GIF supported888test-machine # [ 8.736014] kvm_amd: Virtual NMI enabled889test-machine # [ 8.490738] systemd[1]: Mounting /run/wrappers...890test-machine # [ 8.535593] systemd[1]: Mounted /run/wrappers.891test-machine # [ 8.536722] systemd[1]: Reached target Local File Systems.892test-machine # [ 8.538934] systemd[1]: Listening on Boot Loader Control Service Socket.893test-machine # [ 8.541183] systemd[1]: Starting register-nix-paths.service...894test-machine # [ 8.543323] systemd[1]: Starting Create SUID/SGID Wrappers...895test-machine # [ 8.545049] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.896test-machine # [ 8.547366] systemd[1]: Starting Save Transient machine-id to Disk...897test-machine # [ 8.550107] systemd[1]: Starting Create System Files and Directories...898test-machine # [ 8.585642] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.899test-machine # [ 8.589498] systemd-tmpfiles[434]: /etc/tmpfiles.d/10-microvm.conf:1: Duplicate line for path "/home/test/hermes", ignoring.900test-machine # [ 8.593367] systemd-tmpfiles[434]: /etc/tmpfiles.d/10-microvm.conf:2: Duplicate line for path "/var/lib/hermes-microvm/test/guest", ignoring.901test-machine # [ 8.596317] systemd-tmpfiles[434]: /etc/tmpfiles.d/10-microvm.conf:3: Duplicate line for path "/var/lib/hermes-microvm/test/state-vault/state", ignoring.902test-machine # [ 8.603733] s[ 8.856965] EDAC MC: Ver: 3.0.0903test-machine # ystemd[1]: Finished Save Transient machine-id to Disk.904test-machine # [ 8.607399] systemd-tmpfiles[434]: /etc/tmpfiles.d/tmp.conf:11: Duplicate line for path "/tmp", ignoring.905test-machine # [ 8.625558] systemd[1]: Finished Create System Files and Directories.906test-machine # [ 8.629406] systemd[1]: Starting Rebuild Journal Catalog...907test-machine # [ 8.632116] systemd[1]: Starting Record System Boot/Shutdown in UTMP...908test-machine # [ 8.665153] systemd[1]: Finished Record System Boot/Shutdown in UTMP.909test-machine # [ 8.690617] systemd[1]: Finished Rebuild Journal Catalog.910test-machine # [ 8.693344] systemd[1]: Starting Update is Completed...911test-machine # [ 8.720795] systemd[1]: Finished Update is Completed.912test-machine # [ 8.806329] systemd-vconsole-setup[427]: Configuration of first virtual console was skipped, ignoring remaining ones.913test-machine # [ 8.809122] systemd[1]: Finished Virtual Console Setup.914test-machine # [ 9.035392] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.915test-machine # [ 9.037264] systemd[1]: Finished Create SUID/SGID Wrappers.916test-machine # [ 9.318384] systemd[1]: Finished register-nix-paths.service.917test-machine # [ 9.319927] systemd[1]: Reached target System Initialization.918test-machine # [ 9.321357] systemd[1]: Started hermes-microvm-timezone.path.919test-machine # [ 9.322727] systemd[1]: Started Discard unused filesystem blocks once a week.920test-machine # [ 9.324308] systemd[1]: Started nix-optimise.timer.921test-machine # [ 9.325556] systemd[1]: Started Daily Cleanup of Temporary Directories.922test-machine # [ 9.326962] systemd[1]: Reached target Path Units.923test-machine # [ 9.328294] systemd[1]: Reached target Timer Units.924test-machine # [ 9.329403] systemd[1]: Listening on D-Bus System Message Bus Socket.925test-machine # [ 9.343548] systemd[1]: Listening on dashboard forward socket for hermes-test.926test-machine # [ 9.349768] systemd[1]: Listening on spaces bridge socket for hermes-test.927test-machine # [ 9.351521] systemd[1]: Listening on Nix Daemon Socket.928test-machine # [ 9.354993] systemd[1]: Listening on Authorization Manager Agent Helper.929test-machine # [ 9.357624] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).930test-machine # [ 9.359456] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.931test-machine # [ 9.361559] systemd[1]: Reached target Socket Units.932test-machine # [ 9.363198] systemd[1]: Reached target Basic System.933test-machine # [ 9.365103] systemd[1]: Started backdoor.service.934test-machine # [ 9.366470] systemd[1]: Started Enable Kernel Same-Page Merging.935test-machine # [ 9.368109] systemd[1]: Starting Install MicroVM 'hermes-test'...936test-machine # [ 9.372311] systemd[1]: Starting Import lastlog data into lastlog2 database...937test-machine # [ 9.375326] systemd[1]: Starting nftables firewall...938test-machine # [ 9.379107] systemd[1]: Starting Name Service Cache Daemon (nsncd)...939test-machine # [ 9.383933] systemd[1]: Starting Post-Boot Actions...940test-machine # [ 9.388287] systemd[1]: Started Reset console on configuration changes.941test-machine # [ 9.393178] systemd[1]: Starting Stage Nix-managed integration profiles into per-user credential trees...942test-machine # [ 9.399153] systemd[1]: Starting SSH Host Keys Generation...943test-machine # [ 9.404523] systemd[1]: Started OpenAI-compatible mock LLM for the chat round-trip test.944test-machine # [ 9.412988] systemd[1]: Starting Trigger udev change for TPM devices...945test-machine # [ 9.418370] systemd[1]: enable-ksm.service: Deactivated successfully.946test-machine # [ 9.441426] systemd[1]: Finished Post-Boot Actions.947test-machine # [ 9.449918] systemd[1]: Starting D-Bus System Message Bus...948test-machine # [ 9.455994] systemd[1]: install-microvm-hermes-test.service: Deactivated successfully.949test-machine # [ 9.458500] nsncd[531]: Aug 03 16:27:27.650 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"950test-machine # [ 9.463384] systemd[1]: Finished Install MicroVM 'hermes-test'.951test-machine # [ 9.466685] systemd[1]: Started Name Service Cache Daemon (nsncd).952test-machine # [ 9.469680] systemd[1]: Reached target Host and Network Name Lookups.953test-machine # [ 9.471476] systemd[1]: Reached target User and Group Name Lookups.954test-machine # [ 9.473607] systemd[1]: Setup MicroVM 'hermes-test' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-test/current/bin/pci-setup955test-machine # [ 9.481721] systemd[1]: Starting Save MicroVM 'hermes-test' booted configuration...956test-machine # connecting to host...957test-machine # [ 9.484521] systemd[1]: Starting User Login Management...958test-machine # [ 9.489725] systemd[1]: Finished Import lastlog data into lastlog2 database.959test-machine # [ 9.499559] blgfv7rpymlc2jx4bjmsmn2vyy0mnk9r-tpm2-udev-trigger.sh[539]: TPM udev rules changed, triggering udev960test-machine: Guest shell says: b'Spawning backdoor root shell...\n'961test-machine # [ 9.518460] systemd[1]: Finished Trigger udev change for TPM devices.962test-machine: connected to guest root shell963test-machine: (connecting took 10.62 seconds)964test-machine: (finished: waiting for the VM to finish booting, in 11.55 seconds)965test-machine # [ 9.590511] systemd-logind[574]: New seat seat0.966test-machine # [ 9.597273] dbus-broker-launch[558]: Looking up NSS user entry for 'systemd-timesync'...967test-machine # [ 9.658082] dbus-broker-launch[558]: NSS returned no entry for 'systemd-timesync'968test-machine # [ 9.659248] dbus-broker-launch[558]: Invalid user-name in /nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"969test-machine # [ 9.660776] systemd-logind[574]: Watching system buttons on /dev/input/event2 (Power Button)970test-machine # [ 9.663098] systemd-logind[574]: Watching system buttons on /dev/input/event5 (QEMU Virtio Keyboard)971test-machine # [ 9.663971] systemd-logind[574]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)972test-machine # [ 9.695297] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.UPower' in service file '/nix/store/cgmvnzdimxv58n7fbmrny61id91rmyic-upower-1.91.1/share/dbus-1/system-services/org.freedesktop.UPower.service'973test-machine # [ 9.700817] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/f6pmf8dsa55dk8nzc6jqvic1v0s7528k-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'974test-machine # [ 9.706137] systemd[1]: Started D-Bus System Message Bus.975test-machine # [ 9.731857] dbus-broker-launch[558]: Ready976test-machine # [ 9.735704] systemd[1]: Finished Stage Nix-managed integration profiles into per-user credential trees.977test-machine # [ 9.737940] systemd[1]: Finished Save MicroVM 'hermes-test' booted configuration.978test-machine # [ 9.771728] systemd[1]: Started User Login Management.979test-machine # [ 9.780141] systemd[1]: Created slice Slice /user/1000.980test-machine # [ 9.782838] systemd[1]: Starting linger-users.service...981test-machine # [ 9.783928] systemd[1]: Setup MicroVM 'hermes-test' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-test/current/bin/macvtap-up982test-machine # [ 9.805112] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-test'...983test-machine # [ 9.809098] systemd[1]: Starting User Runtime Directory /run/user/1000...984test-machine # [ 9.832191] sshd-keygen-start[592]: Generating public/private rsa key pair.985test-machine # [ 9.833313] sshd-keygen-start[592]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key986test-machine # [ 9.835580] sshd-keygen-start[592]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub987test-machine # [ 9.838740] sshd-keygen-start[592]: The key fingerprint is:988test-machine # [ 9.838882] sshd-keygen-start[592]: SHA256:BH9lNHANf/hBBdyqeValG5tMUePV3IgsVB8TsvLlQdo root@test-machine989test-machine # [ 9.839089] sshd-keygen-start[592]: The key's randomart image is:990test-machine # [ 9.841573] sshd-keygen-start[592]: +---[RSA 4096]----+991test-machine # [ 9.842653] sshd-keygen-start[592]: | . .o=X=*XB|992test-machine # [ 9.843701] sshd-keygen-start[592]: | o .+oXB+O|993test-machine # [ 9.845156] sshd-keygen-start[592]: | o o.o E*o|994test-machine # [ 9.847733] sshd-keygen-start[592]: | . . o o=+o|995test-machine # [ 9.849578] sshd-keygen-start[592]: | S .=.*.|996test-machine # [ 9.850891] sshd-keygen-start[592]: | o B |997test-machine # [ 9.852474] sshd-keygen-start[592]: | o |998test-machine # [ 9.853482] sshd-keygen-start[592]: | |999test-machine # [ 9.856620] sshd-keygen-start[592]: | |1000test-machine # [ 9.857755] sshd-keygen-start[592]: +----[SHA256]-----+1001test-machine # [ 9.888959] systemd[1]: linger-users.service: Deactivated successfully.1002test-machine # [ 9.891436] systemd[1]: Finished linger-users.service.1003test-machine # [ 9.898281] systemd[1]: Finished User Runtime Directory /run/user/1000.1004test-machine # [ 9.902412] systemd[1]: Starting User Manager for UID 1000...1005test-machine # [ 9.905773] sshd-keygen-start[645]: Generating public/private ed25519 key pair.1006test-machine # [ 9.906827] sshd-keygen-start[645]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1007test-machine # [ 9.907921] sshd-keygen-start[645]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1008test-machine # [ 9.910787] sshd-keygen-start[645]: The key fingerprint is:1009test-machine # [ 9.911760] sshd-keygen-start[645]: SHA256:Vm28u8Bke+CvyA7i7rb2JIKIt/h2rxYOhdihh50Dka8 root@test-machine1010test-machine # [ 9.912907] sshd-keygen-start[645]: The key's randomart image is:1011test-machine # [ 9.914230] sshd-keygen-start[645]: +--[ED25519 256]--+1012test-machine # [ 9.916910] sshd-keygen-start[645]: |.o |1013test-machine # [ 9.918718] sshd-keygen-start[645]: |o . o |1014test-machine # [ 9.919858] sshd-keygen-start[645]: | O + . + |1015test-machine # [ 9.920611] sshd-keygen-start[645]: |+ O . . . . |1016test-machine # [ 9.922189] sshd-keygen-start[645]: | o o S + . |1017test-machine # [ 9.923947] sshd-keygen-start[645]: |E.. . . = o . |1018test-machine # [ 9.925448] sshd-keygen-start[645]: |+ ooo.o = o |1019test-machine # [ 9.927130] sshd-keygen-start[645]: | o.+*+ o . + . |1020test-machine # [ 9.928548] sshd-keygen-start[645]: |.ooB*=o.+ ..o |1021test-machine # [ 9.930043] sshd-keygen-start[645]: +----[SHA256]-----+1022test-machine # [ 9.931635] systemd[1]: sshd-keygen.service: Deactivated successfully.1023test-machine # [ 9.933172] systemd[1]: Finished SSH Host Keys Generation.1024test-machine # [ 9.935347] systemd[1]: Finished nftables firewall.1025test-machine # [ 9.936817] systemd[1]: Reached target Preparation for Network.1026test-machine # [ 9.937991] systemd[1]: Listening on Network Management Resolve Hook Socket.1027test-machine # [ 9.939994] systemd[1]: Starting Owner-match loopback firewall for hermes microvms...1028test-machine # [ 9.942315] systemd[1]: Starting Network Management...1029test-machine # [ 9.978966] (systemd)[651]: pam_unix(systemd-user:session): session opened for user test(uid=1000) by (uid=0)1030test-machine # [ 10.002406] systemd-logind[574]: New session '1' of user 'test' with class 'manager' and type 'unspecified'.1031test-machine # [ 10.046591] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1032test-machine # [ 10.068773] systemd[651]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1033test-machine # [ 10.389498] cfg80211: Loading compiled-in X.509 certificates for regulatory database1034test-machine # [ 10.431430] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1035test-machine # [ 10.434339] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1036test-machine # [ 10.438538] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21037test-machine # [ 10.441960] cfg80211: failed to load regulatory.db1038test-machine # [ 10.223863] systemd-networkd[656]: lo: Link UP1039test-machine # [ 10.224847] systemd-networkd[656]: lo: Gained carrier1040test-machine # [ 10.229682] systemd[1]: Started Network Management.1041test-machine # [ 10.231288] systemd-networkd[656]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.1042test-machine # [ 10.235383] systemd[1]: Reached target Network.1043test-machine # [ 10.237185] systemd[1]: Setup MicroVM 'hermes-test' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-test/current/bin/tap-up1044test-machine # [ 10.241986] systemd[1]: Starting SSH Daemon...1045test-machine # [ 10.243377] systemd-networkd[656]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1046test-machine # [ 10.248660] systemd-networkd[656]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1047test-machine # [ 10.251783] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1048test-machine # [ 10.254741] python3[538]: mock-llm listening on http://127.0.0.1:80121049test-machine # [ 10.257067] systemd-networkd[656]: eth0: Link UP1050test-machine # [ 10.260287] systemd-networkd[656]: eth0: Gained carrier1051test-machine # [ 10.262990] systemd[1]: Starting Permit User Sessions...1052test-machine # [ 10.525102] NET: Registered PF_PACKET protocol family1053test-machine # [ 10.281315] systemd-networkd[656]: eth1: Link UP1054test-machine # [ 10.282977] systemd-networkd[656]: eth1: Gained carrier1055test-machine # [ 10.284968] systemd-networkd[656]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1056test-machine # [ 10.300593] systemd[1]: Finished Permit User Sessions.1057test-machine # [ 10.306557] systemd-networkd[656]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.21058test-machine # [ 10.309232] systemd[1]: Started greetd.service.1059test-machine # [ 10.323107] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1060test-machine # [ 10.350567] sshd[685]: Server listening on 0.0.0.0 port 22.1061test-machine # [ 10.351188] sshd[685]: Server listening on :: port 22.1062test-machine # [ 10.351451] systemd[1]: Started SSH Daemon.1063test-machine # [ 10.407628] systemd[1]: Finished Owner-match loopback firewall for hermes microvms.1064test-machine # [ 10.560364] systemd[651]: Queued start job for default target Main User Target.1065test-machine # [ 10.575345] systemd[651]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1066test-machine # [ 10.579568] systemd[651]: Created slice User Application Slice.1067test-machine # [ 10.580941] systemd[651]: Started Periodically update pi-chat location.1068test-machine # [ 10.581728] systemd[651]: Started Daily Cleanup of User's Temporary Directories.1069test-machine # [ 10.584144] systemd[651]: Reached target Paths.1070test-machine # [ 10.585548] systemd[651]: Reached target Timers.1071test-machine # [ 10.589386] systemd[651]: Starting D-Bus User Message Bus Socket...1072test-machine # [ 10.593680] systemd[651]: Starting GCR ssh-agent wrapper...1073test-machine # [ 10.595848] systemd[651]: Listening on PipeWire PulseAudio.1074test-machine # [ 10.597092] systemd[651]: Listening on PipeWire Multimedia System Sockets.1075test-machine # [ 10.598770] systemd[651]: Listening on Speech Dispatcher Socket.1076test-machine # [ 10.603707] systemd[651]: Starting Create User Files and Directories...1077test-machine # [ 10.631566] systemd[651]: Listening on GCR ssh-agent wrapper.1078test-machine # [ 10.637089] systemd[651]: Finished Create User Files and Directories.1079test-machine # [ 10.668964] systemd[651]: Listening on D-Bus User Message Bus Socket.1080test-machine # [ 10.669807] systemd[651]: Reached target Sockets.1081test-machine # [ 10.670638] systemd[651]: Reached target Basic System.1082test-machine # [ 10.671883] systemd[1]: Started User Manager for UID 1000.1083test-machine # [ 10.673201] systemd[651]: Started GCR ssh-agent wrapper.1084test-machine # [ 10.675481] systemd[651]: Starting Run user-specific NixOS activation...1085test-machine # [ 10.678573] systemd[651]: Starting pi-sessiond token — per-login shared secret at /run/user/1000/pi-sessiond/token...1086test-machine # [ 10.685133] systemd[651]: Starting Spaces aggregating integration MCP gateway...1087test-machine # [ 10.690363] systemd[651]: Starting Spaces integrations broker (enable + secret provisioning over /run/user/1000/spaces-integrations.sock)...1088test-machine # [ 10.722108] systemd[651]: Finished pi-sessiond token — per-login shared secret at /run/user/1000/pi-sessiond/token.1089test-machine # [ 10.727691] systemd[651]: Starting pi-sessiond — per-user pi executor (WebSocket transport + one Landlock-confined pi rpc child per session)...1090test-machine # [ 10.732909] systemd[651]: Started Spaces aggregating integration MCP gateway.1091test-machine # [ 10.739537] systemd[651]: Finished Run user-specific NixOS activation.1092test-machine # [ 10.746450] systemd[651]: Started Spaces integrations broker (enable + secret provisioning over /run/user/1000/spaces-integrations.sock).1093test-machine # [ 10.782914] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:28,976 INFO Set uid to user 0 succeeded1094test-machine # [ 10.786100] systemd[651]: Started pi-sessiond — per-user pi executor (WebSocket transport + one Landlock-confined pi rpc child per session).1095test-machine # [ 10.786755] systemd[651]: Reached target Main User Target.1096test-machine # [ 10.792141] systemd[651]: Started skill-config IPC daemon (pi-chat).1097test-machine # [ 10.794398] systemd[651]: Startup finished in 736ms.1098test-machine # [ 10.796918] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:28,992 INFO supervisord started with pid 6621099test-machine # [ 10.799329] spaces-integrationd[709]: 2026/08/03 16:27:28 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)1100test-machine # [ 10.854711] skill-config-daemon[730]: 2026/08/03 16:27:29 listening on /run/user/1000/spaces-skill-config.sock (instance=unknown)1101test-machine # [ 11.774633] systemd-networkd[656]: eth1: Gained IPv6LL1102test-machine # [ 11.801269] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:29,996 INFO spawned: 'notify' with pid 7661103test-machine # [ 11.803892] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:29,999 INFO spawned: 'virtiofsd-hermes-exchange' with pid 7671104test-machine # [ 11.806566] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:30,001 INFO spawned: 'virtiofsd-hermes-state' with pid 7681105test-machine # [ 11.810532] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:30,005 INFO spawned: 'virtiofsd-host-config' with pid 7691106test-machine # [ 11.817446] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:30,012 INFO spawned: 'virtiofsd-ro-store' with pid 7701107test-machine # [ 11.861985] supervisord[662]: virtiofsd-hermes-exchange [2026-08-03T16:27:30Z INFO virtiofsd] Waiting for vhost-user socket connection...1108test-machine # [ 11.865446] supervisord[662]: virtiofsd-hermes-state [2026-08-03T16:27:30Z INFO virtiofsd] Waiting for vhost-user socket connection...1109test-machine # [ 11.868617] supervisord[662]: virtiofsd-host-config [2026-08-03T16:27:30Z INFO virtiofsd] Waiting for vhost-user socket connection...1110test-machine # [ 11.872814] supervisord[662]: virtiofsd-ro-store [2026-08-03T16:27:30Z INFO virtiofsd] Waiting for vhost-user socket connection...1111test-machine # [ 11.903345] systemd-networkd[656]: eth0: Gained IPv6LL1112test-machine # [ 12.470796] pi-sessiond[716]: pi-sessiond: listening on 127.0.0.1:8768 (executor host); agentDir=/home/test/.local/state/pi-sessiond/pi-agent models=21113test-machine # [ 12.910483] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:31,105 INFO success: notify entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)1114test-machine # [ 12.911369] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:31,105 INFO success: virtiofsd-hermes-exchange entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)1115test-machine # [ 12.911911] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:31,106 INFO success: virtiofsd-hermes-state entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)1116test-machine # [ 12.912824] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:31,106 INFO success: virtiofsd-host-config entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)1117test-machine # [ 12.913629] microvm-virtiofsd@hermes-test[662]: 2026-08-03 16:27:31,106 INFO success: virtiofsd-ro-store entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)1118test-machine # [ 12.920470] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-test'.1119test-machine # [ 12.932214] systemd[1]: Starting MicroVM 'hermes-test'...1120test-machine # [ 13.217924] systemd[1]: Started MicroVM 'hermes-test'.1121test-machine # [ 13.219746] systemd[1]: Reached target microvms.target.1122test-machine # [ 13.221143] systemd[1]: Reached target Multi-User System.1123test-machine # [ 13.222348] systemd[1]: Reached target Graphical Interface.1124test-machine # [ 13.223993] systemd[1]: Startup finished in 1.380s (kernel) + 4.404s (initrd) + 7.438s (userspace) = 13.223s.1125test-machine # [ 13.426236] greetd[856]: pam_unix(greetd:session): session opened for user test(uid=1000) by (uid=0)1126test-machine # [ 13.487479] systemd-logind[574]: New session '2' of user 'test' with class 'greeter' and type 'tty'.1127test-machine # [ 13.495167] systemd[1]: Started Session 2 of User test.1128test-machine # [ 13.566684] greetd[856]: gkr-pam: gnome-keyring-daemon started properly1129test-machine # [ 13.679376] supervisord[662]: virtiofsd-ro-store [2026-08-03T16:27:31Z INFO virtiofsd] Client connected, servicing requests1130test-machine # [ 13.682883] supervisord[662]: virtiofsd-hermes-exchange [2026-08-03T16:27:31Z INFO virtiofsd] Client connected, servicing requests1131test-machine # [ 13.684381] supervisord[662]: virtiofsd-hermes-state [2026-08-03T16:27:31Z INFO virtiofsd] Client connected, servicing requests1132test-machine # [ 13.685556] microvm@hermes-test[853]: microvm@hermes-test: warning: Number of SMP cpus requested (8) exceeds the recommended cpus supported by KVM (4)1133test-machine # [ 13.686247] microvm@hermes-test[853]: microvm@hermes-test: warning: Number of hotpluggable cpus requested (8) exceeds the recommended cpus supported by KVM (4)1134test-machine # [ 13.695637] supervisord[662]: virtiofsd-host-config [2026-08-03T16:27:31Z INFO virtiofsd] Client connected, servicing requests1135test-machine # [ 13.872255] systemd[651]: Created slice User Core Session Slice.1136test-machine # [ 13.880999] systemd[651]: Starting D-Bus User Message Bus...1137test-machine # [ 13.938798] dbus-broker-launch[899]: 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'.1138test-machine # [ 14.017942] dbus-broker-launch[899]: Ignoring duplicate name 'ca.desrt.dconf' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/ca.desrt.dconf.service'1139test-machine # [ 14.030690] dbus-broker-launch[899]: Service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/fr.emersion.mako.service' is not named after the D-Bus name 'org.freedesktop.Notifications'.1140test-machine # [ 14.031173] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.Notifications' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/fr.emersion.mako.service'1141test-machine # [ 14.041992] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.PermissionStore' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.impl.portal.PermissionStore.service'1142test-machine # [ 14.043453] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.Secret' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.impl.portal.Secret.service'1143test-machine # [ 14.045943] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gnome' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gnome.service'1144test-machine # [ 14.048056] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gtk' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gtk.service'1145test-machine # [ 14.051922] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.import1.service'1146test-machine # [ 14.053343] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1147test-machine # [ 14.054713] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1148test-machine # [ 14.055841] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.portal.Desktop' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.portal.Desktop.service'1149test-machine # [ 14.074104] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.portal.Documents' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.portal.Documents.service'1150test-machine # [ 14.078136] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.secrets' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.secrets.service'1151test-machine # [ 14.084123] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1152test-machine # [ 14.093256] dbus-broker-launch[899]: Ignoring duplicate name 'org.gnome.keyring' in service file '/nix/store/kpi4lhzhmnhwp63qis3fwsqm6m4s384h-system-path/share/dbus-1/services/org.gnome.keyring.service'1153test-machine # [ 14.104356] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.Secret' in service file '/nix/store/0rjpw6p23i8zgqfz1c5fm7245ij904nx-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.impl.portal.Secret.service'1154test-machine # [ 14.107638] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.secrets' in service file '/nix/store/0rjpw6p23i8zgqfz1c5fm7245ij904nx-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.secrets.service'1155test-machine # [ 14.108338] dbus-broker-launch[899]: Ignoring duplicate name 'org.gnome.keyring' in service file '/nix/store/0rjpw6p23i8zgqfz1c5fm7245ij904nx-gnome-keyring-50.0/share/dbus-1/services/org.gnome.keyring.service'1156test-machine # [ 14.122147] microvm@hermes-test[853]: SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)1157test-machine # [ 14.132930] dbus-broker-launch[899]: Ignoring duplicate name 'ca.desrt.dconf' in service file '/nix/store/0yi90lx1ichmfdhvsxgr768qxbhwy277-dconf-0.49.0/share/dbus-1/services/ca.desrt.dconf.service'1158test-machine # [ 14.139359] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.PermissionStore' in service file '/nix/store/n4d4gzgydqwvnbx050p5gn1zm5wp7vm3-xdg-desktop-portal-1.20.4/share/dbus-1/services/org.freedesktop.impl.portal.PermissionStore.service'1159test-machine # [ 14.142171] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.portal.Desktop' in service file '/nix/store/n4d4gzgydqwvnbx050p5gn1zm5wp7vm3-xdg-desktop-portal-1.20.4/share/dbus-1/services/org.freedesktop.portal.Desktop.service'1160test-machine # [ 14.144385] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.portal.Documents' in service file '/nix/store/n4d4gzgydqwvnbx050p5gn1zm5wp7vm3-xdg-desktop-portal-1.20.4/share/dbus-1/services/org.freedesktop.portal.Documents.service'1161test-machine # [ 14.153211] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.Secret' in service file '/nix/store/0rjpw6p23i8zgqfz1c5fm7245ij904nx-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.impl.portal.Secret.service'1162test-machine # [ 14.154188] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.secrets' in service file '/nix/store/0rjpw6p23i8zgqfz1c5fm7245ij904nx-gnome-keyring-50.0/share/dbus-1/services/org.freedesktop.secrets.service'1163test-machine # [ 14.155554] dbus-broker-launch[899]: Ignoring duplicate name 'org.gnome.keyring' in service file '/nix/store/0rjpw6p23i8zgqfz1c5fm7245ij904nx-gnome-keyring-50.0/share/dbus-1/services/org.gnome.keyring.service'1164test-machine # [ 14.157149] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gnome' in service file '/nix/store/3prf7ck28wxv7v0xpdxgjcam8ly1jmxa-xdg-desktop-portal-gnome-50.0/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gnome.service'1165test-machine # [ 14.159065] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.impl.portal.desktop.gtk' in service file '/nix/store/lizl4r1klwfdjymb43zqczqf5dzx1rid-xdg-desktop-portal-gtk-1.15.3/share/dbus-1/services/org.freedesktop.impl.portal.desktop.gtk.service'1166test-machine # [ 14.163371] systemd[651]: Started D-Bus User Message Bus.1167test-machine # [ 14.164541] dbus-broker-launch[899]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1168test-machine # [ 14.165591] dbus-broker-launch[899]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1169test-machine # [ 14.195061] dbus-broker-launch[899]: Ready1170test-machine: (finished: waiting for unit multi-user.target, in 16.25 seconds)1171subtest: greetd autostarts the niri session1172test-machine: waiting for unit greetd.service1173test-machine # [ 14.243809] systemd[651]: Reached target Session services which should run early before the graphical session is brought up.1174test-machine # [ 14.248049] systemd[651]: Starting A scrollable-tiling Wayland compositor...1175test-machine: (finished: waiting for unit greetd.service, in 0.11 seconds)1176??? 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.1177 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131178test-machine: waiting for success: systemctl is-active user@1000.service1179test-machine: (finished: waiting for success: systemctl is-active user@1000.service, in 0.08 seconds)1180(finished: subtest: greetd autostarts the niri session, in 0.19 seconds)1181subtest: niri.service starts under the user manager1182test-machine: waiting for success: systemctl --user --machine=test@.host is-active niri.service1183test-machine # [ 14.488733] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1184test-machine # [ 14.572962] (systemd-stdio-bridge)[930]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1185test-machine # [ 14.604155] systemd[1]: Started Session 3 of User test.1186test-machine # [ 14.622849] (systemd-stdio-bridge)[930]: gkr-pam: couldn't unlock the login keyring.1187test-machine # [ 14.681573] systemd[1]: run-p929-i930.service: Deactivated successfully.1188test-machine # [ 14.685496] (sd-pam)[933]: pam_unix(login:session): session closed for user test1189test-machine # [ 14.698493] systemd[1]: session-3.scope: Deactivated successfully.1190test-machine # [ 15.131618] niri[920]: 2026-08-03T16:27:33.323335Z INFO niri: starting version 26.04 (Nixpkgs)1191test-machine # [ 15.291135] niri[920]: 2026-08-03T16:27:33.483457Z DEBUG niri_config: loaded config from "/etc/niri/config.kdl"1192test-machine # [ 15.774400] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1193test-machine # [ 15.783355] niri[920]: 2026-08-03T16:27:33.978408Z WARN niri::backend::tty: error getting the render node for the primary GPU; proceeding anyway1194test-machine # [ 15.786607] niri[920]: 2026-08-03T16:27:33.982197Z INFO niri::backend::tty: using as the render node: "/dev/dri/card0"1195test-machine # [ 15.883593] (systemd-stdio-bridge)[943]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1196test-machine # [ 15.908658] systemd[1]: Started Session 4 of User test.1197test-machine # [ 15.927180] (systemd-stdio-bridge)[943]: gkr-pam: couldn't unlock the login keyring.1198test-machine # [ 15.973519] systemd[1]: run-p942-i943.service: Deactivated successfully.1199test-machine # [ 15.976954] (sd-pam)[946]: pam_unix(login:session): session closed for user test1200test-machine # [ 15.979469] microvm@hermes-test[853]: Booting from ROM...1201test-machine # [ 15.983514] microvm@hermes-test[853]: PPrroobbiinngg EEDDDD ((eedddd==ooffff ttoo ddiissaabbllee))...... ook1202test-machine # [ 16.017210] systemd[1]: session-4.scope: Deactivated successfully.1203test-machine # [ 16.334339] microvm@hermes-test[853]: No EFI environment detected.1204test-machine # [ 16.336044] microvm@hermes-test[853]: early console in extract_kernel1205test-machine # [ 16.337089] microvm@hermes-test[853]: input_data: 0x0000000003ac72cc1206test-machine # [ 16.337556] microvm@hermes-test[853]: input_len: 0x0000000000cb9a3c1207test-machine # [ 16.339212] microvm@hermes-test[853]: output: 0x00000000010000001208test-machine # [ 16.340296] microvm@hermes-test[853]: output_len: 0x0000000003728d941209test-machine # [ 16.342644] microvm@hermes-test[853]: kernel_total_size: 0x0000000002e300001210test-machine # [ 16.344692] microvm@hermes-test[853]: needed_size: 0x00000000038000001211test-machine # [ 16.347210] microvm@hermes-test[853]: trampoline_32bit: 0x00000000000000001212test-machine # [ 16.349904] microvm@hermes-test[853]: Physical KASLR using RDRAND RDTSC...1213test-machine # [ 16.353280] microvm@hermes-test[853]: Virtual KASLR using RDRAND RDTSC...1214test-machine # [ 16.353688] microvm@hermes-test[853]:1215test-machine # [ 16.357365] microvm@hermes-test[853]: Decompressing Linux... No EFI environment detected.1216test-machine # [ 17.106870] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1217test-machine # [ 17.257651] (systemd-stdio-bridge)[958]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1218test-machine # [ 17.315140] systemd[1]: Started Session 5 of User test.1219test-machine # [ 17.339477] (systemd-stdio-bridge)[958]: gkr-pam: couldn't unlock the login keyring.1220test-machine # [ 17.361435] microvm@hermes-test[853]: Parsing ELF... Performing relocations... done.1221test-machine # [ 17.367055] microvm@hermes-test[853]: Booting the kernel (entry_offset: 0x000000000278c520).1222test-machine # [ 17.391034] microvm@hermes-test[853]: [ 0.000000] Linux version 6.18.41 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Thu Jul 30 11:05:57 UTC 20261223test-machine # [ 17.412717] microvm@hermes-test[853]: [ 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/gia9ymfphrl6m70sz7vw01axqx4a04kk-nixos-system-hermes-test-26.11pre-git/init regInfo=/nix/store/fmqdckwiwwkav8wpx1v1vf67kw2mrfi4-closure-info/registration1224test-machine # [ 17.418472] microvm@hermes-test[853]: [ 0.000000] BIOS-provided physical RAM map:1225test-machine # [ 17.420382] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable1226test-machine # [ 17.428406] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved1227test-machine # [ 17.433818] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved1228test-machine # [ 17.438812] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffdfff] usable1229test-machine # [ 17.448090] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x00000000bfffe000-0x00000000bfffffff] reserved1230test-machine # [ 17.451663] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved1231test-machine # [ 17.457836] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved1232test-machine # [ 17.463313] microvm@hermes-test[853]: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable1233test-machine # [ 17.467001] microvm@hermes-test[853]: [ 0.000000] printk: legacy bootconsole [earlyser0] enabled1234test-machine # [ 17.475157] microvm@hermes-test[853]: Memory KASLR using RDRAND RDTSC...1235test-machine # [ 17.484492] systemd[1]: run-p957-i958.service: Deactivated successfully.1236test-machine # [ 17.487981] (sd-pam)[961]: pam_unix(login:session): session closed for user test1237test-machine # [ 17.523792] systemd[1]: session-5.scope: Deactivated successfully.1238test-machine # [ 18.040960] niri[920]: 2026-08-03T16:27:36.232143Z DEBUG niri::backend::tty: adding device: 57856 "/dev/dri/card0"1239test-machine # [ 18.043773] niri[920]: 2026-08-03T16:27:36.233680Z DEBUG niri::backend::tty: this is the primary node1240test-machine # [ 18.642355] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1241test-machine # [ 18.696436] (systemd-stdio-bridge)[971]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1242test-machine # [ 18.721944] systemd[1]: Started Session 6 of User test.1243test-machine # [ 18.739892] (systemd-stdio-bridge)[971]: gkr-pam: couldn't unlock the login keyring.1244test-machine # [ 18.779673] systemd[1]: run-p970-i971.service: Deactivated successfully.1245test-machine # [ 18.783357] (sd-pam)[974]: pam_unix(login:session): session closed for user test1246test-machine # [ 18.805486] systemd[1]: session-6.scope: Deactivated successfully.1247test-machine # [ 19.938947] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1248test-machine # [ 20.103591] niri[920]: pci id for fd 31: 1234:1111, driver (null)1249test-machine # [ 20.152201] (systemd-stdio-bridge)[984]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1250test-machine # [ 20.207435] systemd[1]: Started Session 7 of User test.1251test-machine # [ 20.213045] niri[920]: pci id for fd 30: 1234:1111, driver (null)1252test-machine # [ 20.244528] (systemd-stdio-bridge)[984]: gkr-pam: couldn't unlock the login keyring.1253test-machine # [ 20.245662] niri[920]: pci id for fd 32: 1234:1111, driver (null)1254test-machine # [ 20.277265] niri[920]: pci id for fd 30: 1234:1111, driver (null)1255test-machine # [ 20.299909] niri[920]: pci id for fd 32: 1234:1111, driver (null)1256test-machine # [ 20.396642] systemd[1]: run-p983-i984.service: Deactivated successfully.1257test-machine # [ 20.409072] (sd-pam)[988]: pam_unix(login:session): session closed for user test1258test-machine # [ 20.438869] systemd[1]: session-7.scope: Deactivated successfully.1259test-machine # [ 20.568901] niri[920]: 2026-08-03T16:27:38.758935Z DEBUG niri::backend::tty: failed to initialize renderer, falling back to primary gpu: software EGL renderers are skipped1260test-machine # [ 20.583854] niri[920]: 2026-08-03T16:27:38.779486Z WARN niri::backend::tty: error adding primary node device, display-only devices may not work: no allocator available for device1261test-machine # [ 20.592580] niri[920]: 2026-08-03T16:27:38.788229Z INFO niri: listening on Wayland socket: wayland-11262test-machine # [ 20.594892] niri[920]: 2026-08-03T16:27:38.788254Z INFO niri: IPC listening on: /run/user/1000/niri.wayland-1.920.sock1263test-machine # [ 20.939927] microvm@hermes-test[853]: Poking KASLR using RDRAND RDTSC...1264test-machine # [ 21.017708] niri[920]: 2026-08-03T16:27:39.212943Z INFO niri: listening on X11 socket: :01265test-machine # [ 21.502689] microvm@hermes-test[853]: [ 3.785255] PCI: Fatal: No config space access function found1266test-machine # [ 21.648529] systemd[1]: Starting Authorization Manager...1267test-machine # [ 21.681181] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1268test-machine # [ 22.115827] (systemd-stdio-bridge)[1006]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1269test-machine # [ 22.173910] systemd[1]: Started Session 8 of User test.1270test-machine # [ 22.219224] (systemd-stdio-bridge)[1006]: gkr-pam: couldn't unlock the login keyring.1271test-machine # [ 22.405345] polkitd[1005]: Started polkitd version 1271272test-machine # [ 22.439282] systemd[1]: run-p1004-i1005.service: Deactivated successfully.1273test-machine # [ 22.447853] (sd-pam)[1009]: pam_unix(login:session): session closed for user test1274test-machine # [ 22.494446] systemd[1]: session-8.scope: Deactivated successfully.1275test-machine # [ 22.933050] systemd[1]: Started Authorization Manager.1276test-machine # [ 23.766180] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1277test-machine # [ 23.781566] systemd[651]: Started A scrollable-tiling Wayland compositor.1278test-machine # [ 23.786860] systemd[651]: Reached target Current graphical user session.1279test-machine # [ 23.802446] systemd[651]: Starting Certificate and Key Storage...1280test-machine # [ 23.818819] systemd[651]: Starting Secret Storage Service...1281test-machine # [ 23.851627] systemd[651]: Starting pi-chat...1282test-machine # [ 23.875318] systemd[651]: Started mako notification daemon.1283test-machine # [ 23.891497] niri[920]: 2026-08-03T16:27:42.081923Z WARN niri::niri: failed to set logind LockedHint: failed to call SetLockedHint1284test-machine # [ 23.891977] niri[920]: Caused by:1285test-machine # [ 23.892225] niri[920]: org.freedesktop.DBus.Error.NotSupported: Session does not support lock screen.1286test-machine # [ 23.897357] systemd[651]: Starting Noctalia Wayland desktop shell...1287test-machine # [ 23.929399] systemd[651]: Starting pi-chat Quickshell panel...1288test-machine # [ 23.935117] systemd[1]: Starting Locale Service...1289test-machine # [ 23.940095] systemd[651]: Started Forward desktop notifications to the pi-chat panel.1290test-machine # [ 23.971287] systemd[651]: Started VoxType push-to-talk voice-to-text daemon.1291test-machine # [ 24.045139] (systemd-stdio-bridge)[1038]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1292test-machine # [ 24.117923] systemd[1]: Started Session 9 of User test.1293test-machine # [ 24.165520] (systemd-stdio-bridge)[1038]: gkr-pam: couldn't unlock the login keyring.1294test-machine # [ 24.213707] systemd[651]: Started Certificate and Key Storage.1295test-machine # [ 24.219333] systemd[1]: Started Locale Service.1296test-machine # [ 24.232588] systemd[651]: Started pi-chat.1297test-machine # [ 24.237715] systemd[651]: Started Secret Storage Service.1298test-machine # [ 24.240178] systemd[651]: Reached target Startup of XDG autostart applications.1299test-machine # [ 24.276431] systemd[1]: run-p1032-i1033.service: Deactivated successfully.1300test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active niri.service, in 9.88 seconds)1301(finished: subtest: niri.service starts under the user manager, in 9.88 seconds)1302subtest: niri exposes its Wayland socket1303??? 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.1304 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131305test-machine: waiting for file '/run/user/1000/wayland-1'1306test-machine # [ 24.280174] (sd-pam)[1063]: pam_unix(login:session): session closed for user test1307test-machine # [ 24.308586] v3aqzkfmy2sjkm3rk4clsaaqjmldkc92-spaces-notify-forward[1066]: /nix/store/v3aqzkfmy2sjkm3rk4clsaaqjmldkc92-spaces-notify-forward: line 26: n: unbound variable1308test-machine # [ 24.338426] systemd[1]: session-9.scope: Deactivated successfully.1309test-machine # [ 24.507365] gnome-keyring-daemon[1080]: discover_other_daemon: 11310test-machine # [ 24.526149] gnome-keyring-daemon[1080]: discover_other_daemon: 11311test-machine # [ 24.588283] gnome-keyring-daemon[1081]: discover_other_daemon: 11312test-machine # [ 24.590308] gnome-keyring-daemon[1081]: discover_other_daemon: 11313test-machine: (finished: waiting for file '/run/user/1000/wayland-1', in 0.31 seconds)1314(finished: subtest: niri exposes its Wayland socket, in 0.31 seconds)1315subtest: shell config materialized with fresh mtimes1316test-machine: waiting for success: test -f /home/test/.config/quickshell/pi-chat/shell.qml && test ! -L /home/test/.config/quickshell/pi-chat1317test-machine # [ 25.575608] voxtype-daemon[1055]: 2026-08-03T16:27:43.767482Z INFO Using Energy VAD backend1318test-machine # [ 25.582737] voxtype-daemon[1055]: 2026-08-03T16:27:43.770651Z INFO Voice Activity Detection enabled (backend: Energy, threshold: 0.40, min_speech: 100ms)1319test-machine # [ 25.589555] voxtype-daemon[1055]: 2026-08-03T16:27:43.780163Z INFO Starting voxtype daemon1320test-machine # [ 25.694591] systemd[651]: Started pi-chat Quickshell panel.1321test-machine: (finished: waiting for success: test -f /home/test/.config/quickshell/pi-chat/shell.qml && test ! -L /home/test/.config/quickshell/pi-chat, in 1.38 seconds)1322??? 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.1323 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131324test-machine: must succeed: stat -c %Y /home/test/.config/quickshell/pi-chat/shell.qml1325test-machine: (finished: must succeed: stat -c %Y /home/test/.config/quickshell/pi-chat/shell.qml, in 0.14 seconds)1326(finished: subtest: shell config materialized with fresh mtimes, in 1.53 seconds)1327subtest: pi-chat.service is running1328test-machine: waiting for success: systemctl --user --machine=test@.host is-active pi-chat.service1329test-machine # [ 26.390508] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1330test-machine # [ 26.396985] systemd[651]: Started Noctalia Wayland desktop shell.1331test-machine # [ 26.519884] voxtype-daemon[1055]: 2026-08-03T16:27:44.710513Z INFO Audio level socket listening at "/run/user/1000/voxtype/audio.sock"1332test-machine # [ 26.521986] voxtype-daemon[1055]: 2026-08-03T16:27:44.714253Z INFO OSD audio level socket: "/run/user/1000/voxtype/audio.sock"1333test-machine # [ 26.551498] voxtype-daemon[1055]: 2026-08-03T16:27:44.744868Z INFO Output mode: Type1334test-machine # [ 26.552116] voxtype-daemon[1055]: 2026-08-03T16:27:44.747130Z INFO State file: "/run/user/1000/voxtype/state"1335test-machine # [ 26.553744] voxtype-daemon[1055]: 2026-08-03T16:27:44.747163Z INFO Built-in hotkey disabled, use 'voxtype record' commands or compositor keybindings1336test-machine # [ 26.611755] voxtype-daemon[1055]: 2026-08-03T16:27:44.806585Z INFO Loading transcription model: /nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin1337test-machine # [ 26.613566] voxtype-daemon[1055]: 2026-08-03T16:27:44.806859Z INFO Preloading primary model '/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin'1338test-machine # [ 26.621977] voxtype-daemon[1055]: 2026-08-03T16:27:44.816892Z INFO Loading model '/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin' into cache1339test-machine # [ 26.627713] voxtype-daemon[1055]: 2026-08-03T16:27:44.823255Z INFO Loading whisper model from "/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin"1340test-machine # [ 26.634508] voxtype-daemon[1055]: whisper_init_from_file_with_params_no_state: loading model from '/nix/store/cp89s185x1ykj4fi5a5mn9nlbvz1vwnn-ggml-small.bin'1341test-machine # [ 26.654779] voxtype-daemon[1055]: whisper_init_with_params_no_state: use gpu = 11342test-machine # [ 26.655792] voxtype-daemon[1055]: whisper_init_with_params_no_state: flash attn = 01343test-machine # [ 26.655948] voxtype-daemon[1055]: whisper_init_with_params_no_state: gpu_device = 01344test-machine # [ 26.656135] voxtype-daemon[1055]: whisper_init_with_params_no_state: dtw = 01345test-machine # [ 26.812556] (systemd-stdio-bridge)[1158]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1346test-machine # [ 26.875336] systemd[1]: Started Session 10 of User test.1347test-machine # [ 26.903815] (systemd-stdio-bridge)[1158]: gkr-pam: couldn't unlock the login keyring.1348test-machine # [ 27.028385] systemd[1]: run-p1156-i1157.service: Deactivated successfully.1349test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active pi-chat.service, in 0.92 seconds)1350test-machine: waiting for success: test -d /run/user/1000/quickshell1351test-machine # [ 27.032098] (sd-pam)[1164]: pam_unix(login:session): session closed for user test1352test-machine # [ 27.078456] systemd[1]: session-10.scope: Deactivated successfully.1353test-machine # [ 27.510152] microvm@hermes-test[853]: +q6E616D65Booting initrd of NixOS 26.11 (Zokor) (Initrd).1354test-machine # [ 28.211807] microvm@hermes-test[853]: [ OK ] Created slice Slice /system/modprobe.1355test-machine # [ 28.224873] microvm@hermes-test[853]: [ OK ] Started Dispatch Password Requests to Console Directory Watch.1356test-machine # [ 28.237633] microvm@hermes-test[853]: [ OK ] Reached target Initrd Root Device.1357test-machine # [ 28.252103] microvm@hermes-test[853]: [ OK ] Reached target Path Units.1358test-machine # [ 28.262473] microvm@hermes-test[853]: [ OK ] Reached target Slice Units.1359test-machine # [ 28.270281] microvm@hermes-test[853]: [ OK ] Reached target Swaps.1360test-machine # [ 28.279206] microvm@hermes-test[853]: [ OK ] Reached target Timer Units.1361test-machine # [ 28.297166] microvm@hermes-test[853]: [ OK ] Listening on D-Bus System Message Bus Socket.1362test-machine # [ 28.317148] microvm@hermes-test[853]: [ OK ] Listening on Journal Socket (/dev/log).1363test-machine # [ 28.335561] microvm@hermes-test[853]: [ OK ] Listening on Journal Sockets.1364test-machine # [ 28.345166] microvm@hermes-test[853]: [ OK ] Listening on udev Control Socket.1365test-machine # [ 28.349964] microvm@hermes-test[853]: [ OK ] Listening on udev Kernel Socket.1366test-machine # [ 28.354204] microvm@hermes-test[853]: [ OK ] Reached target Socket Units.1367test-machine # [ 28.379135] microvm@hermes-test[853]: Starting Create List of Static Device Nodes...1368test-machine # [ 28.400651] microvm@hermes-test[853]: Starting Load Kernel Module configfs...1369test-machine # [ 28.490339] microvm@hermes-test[853]: Starting Journal Service...1370test-machine # [ 28.513782] microvm@hermes-test[853]: Starting Load Kernel Modules...1371test-machine # [ 28.523143] microvm@hermes-test[853]: Starting Coldplug All udev Devices...1372test-machine # [ 28.541819] microvm@hermes-test[853]: [ OK ] Finished Create List of Static Device Nodes.1373test-machine # [ 28.558466] microvm@hermes-test[853]: [ OK ] Finished Load Kernel Module configfs.1374test-machine # [ 28.636567] microvm@hermes-test[853]: Starting Create Static Device Nodes in /dev gracefully...1375test-machine # [ 28.876971] microvm@hermes-test[853]: [ OK ] Finished Create Static Device Nodes in /dev gracefully.1376test-machine # [ 28.901066] microvm@hermes-test[853]: Starting Create Static Device Nodes in /dev...1377test-machine # [ 28.915822] microvm@hermes-test[853]: [ OK ] Started Journal Service.1378test-machine # [ 29.070727] microvm@hermes-test[853]: [ OK ] Finished Coldplug All udev Devices.1379test-machine # [ 29.131201] microvm@hermes-test[853]: [ OK ] Finished Create Static Device Nodes in /dev.1380test-machine # [ 29.138222] microvm@hermes-test[853]: [ OK ] Reached target Preparation for Local File Systems.1381test-machine # [ 29.145669] microvm@hermes-test[853]: [ OK ] Reached target Local File Systems.1382test-machine # [ 29.174209] microvm@hermes-test[853]: Starting Create System Files and Directories...1383test-machine # [ 29.251199] microvm@hermes-test[853]: Starting Rule-based Manager for Device Events and Files...1384test-machine # [ 29.315847] microvm@hermes-test[853]: [ OK ] Finished Create System Files and Directories.1385test-machine # [ 29.477432] microvm@hermes-test[853]: [ OK ] Started Rule-based Manager for Device Events and Files.1386test-machine # [ 29.545412] microvm@hermes-test[853]: Mounting /sysroot...1387test-machine # [ 29.685850] microvm@hermes-test[853]: [ OK ] Mounted /sysroot.1388test-machine # [ 29.707144] microvm@hermes-test[853]: [ OK ] Reached target Initrd Root File System.1389test-machine # [ 29.727308] microvm@hermes-test[853]: Mounting /sysroot/run...1390test-machine # [ 29.764931] microvm@hermes-test[853]: Starting Mountpoints Configured in the Real Root...1391test-machine # [ 29.800788] microvm@hermes-test[853]: Starting rw-sysroot-nix-store.service...1392test-machine # [ 30.382849] quickshell[1124]: INFO: Launching config: "/home/test/.config/quickshell/pi-chat/shell.qml"1393test-machine # [ 30.399178] quickshell[1124]: INFO: Shell ID: "3a1085cb1f6a905eb4edc7542cb4c242" Path ID "3a1085cb1f6a905eb4edc7542cb4c242"1394test-machine # [ 30.410885] noctalia-shell[1159]: No running instances for "/nix/store/lnn22vfnix3f4pv9ryl1acz788ys87jb-noctalia-shell-4.7.7/share/noctalia-shell/shell.qml"1395test-machine # [ 30.416737] noctalia-shell[1159]: INFO: Launching config: "/nix/store/lnn22vfnix3f4pv9ryl1acz788ys87jb-noctalia-shell-4.7.7/share/noctalia-shell/shell.qml"1396test-machine # [ 30.424098] noctalia-shell[1159]: INFO: Shell ID: "326a527c13f79ab48f14956d05e6eabb" Path ID "326a527c13f79ab48f14956d05e6eabb"1397test-machine # [ 30.443289] noctalia-shell[1159]: INFO: Saving logs to "/run/user/1000/quickshell/by-id/7wcec7jt/log.qslog"1398test-machine # [ 30.499629] microvm@hermes-test[853]: [ OK ] Mounted /sysroot/run.1399test-machine # [ 30.519297] quickshell[1124]: INFO: Saving logs to "/run/user/1000/quickshell/by-id/8vcec7jt/log.qslog"1400test-machine # [ 30.527956] microvm@hermes-test[853]: [ OK ] Finished rw-sysroot-nix-store.service.1401test-machine # [ 30.541378] microvm@hermes-test[853]: Starting rw-sysroot-nix-store.service...1402test-machine # [ 30.559839] microvm@hermes-test[853]: Starting Virtual Console Setup...1403test-machine # [ 30.680860] microvm@hermes-test[853]: [ OK ] Finished Mountpoints Configured in the Real Root.1404test-machine # [ 30.724470] microvm@hermes-test[853]: [ OK ] Finished rw-sysroot-nix-store.service.1405test-machine # [ 30.762095] microvm@hermes-test[853]: [ OK ] Finished Virtual Console Setup.1406test-machine: (finished: waiting for success: test -d /run/user/1000/quickshell, in 3.79 seconds)1407(finished: subtest: pi-chat.service is running, in 4.71 seconds)1408subtest: pi-chat sidecar services come up1409test-machine: waiting for success: systemctl --user --machine=test@.host is-active spaces-skill-config-daemon.service1410test-machine # [ 30.962068] systemd[651]: Starting Portal service...1411test-machine # [ 31.021321] voxtype-daemon[1055]: ggml_vulkan: No devices found.1412test-machine # [ 31.028105] voxtype-daemon[1055]: whisper_init_with_params_no_state: devices = 11413test-machine # [ 31.030671] voxtype-daemon[1055]: whisper_init_with_params_no_state: backends = 21414test-machine # [ 31.035660] voxtype-daemon[1055]: whisper_model_load: loading model1415test-machine # [ 31.037502] voxtype-daemon[1055]: whisper_model_load: n_vocab = 518651416test-machine # [ 31.042723] voxtype-daemon[1055]: whisper_model_load: n_audio_ctx = 15001417test-machine # [ 31.044694] voxtype-daemon[1055]: whisper_model_load: n_audio_state = 7681418test-machine # [ 31.046727] voxtype-daemon[1055]: whisper_model_load: n_audio_head = 121419test-machine # [ 31.054771] voxtype-daemon[1055]: whisper_model_load: n_audio_layer = 121420test-machine # [ 31.056779] voxtype-daemon[1055]: whisper_model_load: n_text_ctx = 4481421test-machine # [ 31.059321] voxtype-daemon[1055]: whisper_model_load: n_text_state = 7681422test-machine # [ 31.061274] voxtype-daemon[1055]: whisper_model_load: n_text_head = 121423test-machine # [ 31.063386] voxtype-daemon[1055]: whisper_model_load: n_text_layer = 121424test-machine # [ 31.067356] voxtype-daemon[1055]: whisper_model_load: n_mels = 801425test-machine # [ 31.070373] voxtype-daemon[1055]: whisper_model_load: ftype = 11426test-machine # [ 31.074948] voxtype-daemon[1055]: whisper_model_load: qntvr = 01427test-machine # [ 31.076884] voxtype-daemon[1055]: whisper_model_load: type = 3 (small)1428test-machine # [ 31.089378] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1429test-machine # [ 31.094769] noctalia-shell[1159]: INFO qt.qpa.wayland: There are no outputs - creating placeholder screen1430test-machine # [ 31.097744] quickshell[1124]: INFO qt.qpa.wayland: There are no outputs - creating placeholder screen1431test-machine # [ 31.261589] voxtype-daemon[1055]: whisper_model_load: adding 1608 extra tokens1432test-machine # [ 31.264277] voxtype-daemon[1055]: whisper_model_load: n_langs = 991433test-machine # [ 31.270519] voxtype-daemon[1055]: whisper_model_load: CPU total size = 487.01 MB1434test-machine # [ 31.363092] (systemd-stdio-bridge)[1196]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1435test-machine # [ 31.419678] systemd[1]: Started Session 11 of User test.1436test-machine # [ 31.456801] (systemd-stdio-bridge)[1196]: gkr-pam: couldn't unlock the login keyring.1437test-machine # [ 31.620504] systemd[1]: run-p1195-i1196.service: Deactivated successfully.1438test-machine # [ 31.629494] (sd-pam)[1203]: pam_unix(login:session): session closed for user test1439test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active spaces-skill-config-daemon.service, in 0.81 seconds)1440test-machine: waiting for file '/run/user/1000/spaces-skill-config.sock'1441test-machine # [ 31.666749] systemd[1]: session-11.scope: Deactivated successfully.1442test-machine: (finished: waiting for file '/run/user/1000/spaces-skill-config.sock', in 0.11 seconds)1443(finished: subtest: pi-chat sidecar services come up, in 0.92 seconds)1444subtest: daemon agent config is staged (settings + skills + allowlist)1445test-machine: waiting for success: test -f /home/test/.local/state/pi-sessiond/pi-agent/settings.json1446test-machine # [ 31.805987] systemd[651]: Starting sandboxed app permission store...1447test-machine # [ 31.828391] quickshell[1124]: libEGL warning: failed to get driver name for fd -11448test-machine # [ 31.833976] quickshell[1124]: libEGL warning: MESA-LOADER: failed to retrieve device information1449test-machine # [ 31.837057] quickshell[1124]: libEGL warning: failed to get driver name for fd -11450test-machine: (finished: waiting for success: test -f /home/test/.local/state/pi-sessiond/pi-agent/settings.json, in 0.12 seconds)1451test-machine: must succeed: grep -q 'skills' /home/test/.local/state/pi-sessiond/pi-agent/settings.json1452test-machine # [ 31.923129] microvm@hermes-test[853]: [ OK ] Finished Load Kernel Modules.1453test-machine # [ 31.939585] microvm@hermes-test[853]: Mounting /sysroot/nix/.ro-store...1454test-machine # [ 31.963866] microvm@hermes-test[853]: Mounting /sysroot/run/hermes-host...1455test-machine # [ 31.989457] microvm@hermes-test[853]: Mounting /sysroot/var/lib/hermes...1456test-machine # [ 32.006263] microvm@hermes-test[853]: Starting Apply Kernel Variables...1457test-machine # [ 32.022441] microvm@hermes-test[853]: [ OK ] Mounted /sysroot/run/hermes-host.1458test-machine # [ 32.038576] microvm@hermes-test[853]: [ OK ] Mounted /sysroot/var/lib/hermes.1459test-machine # [ 32.054509] microvm@hermes-test[853]: [ OK ] Mounted /sysroot/nix/.ro-store.1460test-machine: (finished: must succeed: grep -q 'skills' /home/test/.local/state/pi-sessiond/pi-agent/settings.json, in 0.20 seconds)1461test-machine: must succeed: test -f /home/test/.local/state/pi-sessiond/pi-agent/bash-confirm.json1462test-machine # [ 32.074373] microvm@hermes-test[853]: Mounting /sysroot/nix/store...1463test-machine # [ 32.083080] systemd[651]: Started sandboxed app permission store.1464test-machine # [ 32.093387] microvm@hermes-test[853]: [ OK ] Finished Apply Kernel Variables.1465test-machine # [ 32.111420] microvm@hermes-test[853]: [ OK ] Reached target System Initialization.1466test-machine # [ 32.118128] microvm@hermes-test[853]: [ OK ] Reached target Basic System.1467test-machine # [ 32.128955] microvm@hermes-test[853]: [ OK ] Mounted /sysroot/nix/store.1468test-machine # [ 32.131363] systemd[651]: Starting flatpak document portal service...1469test-machine # [ 32.138596] microvm@hermes-test[853]: [ OK ] Reached target Initrd File Systems.1470test-machine # [ 32.144074] microvm@hermes-test[853]: Starting Find NixOS closure...1471test-machine # [ 32.154487] microvm@hermes-test[853]: Starting Create Volatile Files and Directories in the Real Root...1472test-machine: (finished: must succeed: test -f /home/test/.local/state/pi-sessiond/pi-agent/bash-confirm.json, in 0.08 seconds)1473(finished: subtest: daemon agent config is staged (settings + skills + allowlist), in 0.41 seconds)1474subtest: llama-swap is up1475??? 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.1476 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131477test-machine: waiting for TCP port 8012 on localhost1478test-machine # [ 32.209512] microvm@hermes-test[853]: [ OK ] Finished Create Volatile Files and Directories in the Real Root.1479test-machine # [ 32.255620] microvm@hermes-test[853]: [ OK ] Finished Find NixOS closure.1480test-machine # [ 32.264825] microvm@hermes-test[853]: [ OK ] Reached target Initrd Default Target.1481test-machine # [ 32.279993] microvm@hermes-test[853]: Starting Cleaning Up and Shutting Down Daemons...1482test-machine # [ 32.316124] systemd[651]: Started flatpak document portal service.1483test-machine # [ 32.326493] microvm@hermes-test[853]: [ OK ] Stopped target Initrd Default Target.1484test-machine # [ 32.334329] microvm@hermes-test[853]: [ OK ] Stopped target Basic System.1485test-machine # [ 32.341348] microvm@hermes-test[853]: [ OK ] Stopped target Initrd Root Device.1486test-machine # [ 32.351397] microvm@hermes-test[853]: [ OK ] Stopped target Path Units.1487test-machine # [ 32.365718] microvm@hermes-test[853]: [ OK ] Stopped Dispatch Password Requests to Console Directory Watch.1488test-machine # [ 32.374902] microvm@hermes-test[853]: [ OK ] Stopped target Slice Units.1489test-machine # [ 32.383465] microvm@hermes-test[853]: [ OK ] Stopped target Socket Units.1490test-machine # [ 32.389562] microvm@hermes-test[853]: [ OK ] Stopped target System Initialization.1491test-machine # [ 32.397938] microvm@hermes-test[853]: [ OK ] Stopped target Swaps.1492test-machine # [ 32.407489] systemd[651]: Starting Portal service (GNOME implementation)...1493test-machine # [ 32.409042] microvm@hermes-test[853]: [ OK ] Stopped target Timer Units.1494test-machine # [ 32.419244] microvm@hermes-test[853]: [ OK ] Closed D-Bus System Message Bus Socket.1495test-machine # Connection to localhost (127.0.0.1) 8012 port [tcp/*] succeeded!1496test-machine # [ 32.431161] microvm@hermes-test[853]: [ OK ] Stopped Find NixOS closure.1497test-machine # [ 32.445916] microvm@hermes-test[853]: Starting rw-sysroot-nix-store.service...1498test-machine: (finished: waiting for TCP port 8012 on localhost, in 0.29 seconds)1499(finished: subtest: llama-swap is up, in 0.29 seconds)1500subtest: shell IPC target is registered1501??? 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.1502 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131503test-machine # [ 32.457178] microvm@hermes-test[853]: [ OK ] Stopped Apply Kernel Variables.1504test-machine # [ 32.473804] microvm@hermes-test[853]: [ OK ] Stopped Load Kernel Modules.1505test-machine # [ 32.492921] microvm@hermes-test[853]: [ OK ] Stopped Create Volatile Files and Directories in the Real Root.1506test-machine # [ 32.501986] microvm@hermes-test[853]: [ OK ] Stopped Create System Files and Directories.1507test-machine # [ 32.514445] microvm@hermes-test[853]: [ OK ] Stopped target Local File Systems.1508test-machine # [ 32.524165] microvm@hermes-test[853]: [ OK ] Stopped target Preparation for Local File Systems.1509test-machine # [ 32.537060] microvm@hermes-test[853]: [ OK ] Stopped Coldplug All udev Devices.1510test-machine # [ 32.565976] microvm@hermes-test[853]: Stopping Rule-based Manager for Device Events and Files...1511test-machine # [ 32.580924] microvm@hermes-test[853]: [ OK ] Stopped Virtual Console Setup.1512test-machine # [ 32.597977] microvm@hermes-test[853]: [ OK ] Finished rw-sysroot-nix-store.service.1513test-machine # [ 32.618228] microvm@hermes-test[853]: [ OK ] Finished Cleaning Up and Shutting Down Daemons.1514test-machine # [ 32.637906] niri[920]: 2026-08-03T16:27:50.830833Z DEBUG niri::utils::xwayland::satellite: connection to X11 abstract socket; spawning xwayland-satellite1515test-machine # [ 32.640235] microvm@hermes-test[853]: [ OK ] Stopped Rule-based Manager for Device Events and Files.1516test-machine # [ 32.656655] microvm@hermes-test[853]: [ OK ] Closed udev Control Socket.1517test-machine # [ 32.682500] microvm@hermes-test[853]: Starting Cleanup udev Database...1518test-machine # [ 32.691986] microvm@hermes-test[853]: [ OK ] Stopped Create Static Device Nodes in /dev.1519test-machine # [ 32.699641] microvm@hermes-test[853]: [ OK ] Stopped Create Static Device Nodes in /dev gracefully.1520test-machine # [ 32.710904] microvm@hermes-test[853]: [ OK ] Stopped Create List of Static Device Nodes.1521test-machine # [ 32.763941] sudo[1252]: 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 show1522test-machine # [ 32.765240] microvm@hermes-test[853]: [ OK ] Finished Cleanup udev Database.1523test-machine # [ 32.773483] microvm@hermes-test[853]: [ OK ] Reached target Switch Root.1524test-machine # [ 32.786620] microvm@hermes-test[853]: Starting NixOS Activation...1525test-machine # [ 32.809916] sudo[1252]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0)1526test-machine # [ 34.339222] quickshell[1124]: MESA: error: ZINK: failed to choose pdev1527test-machine # [ 34.362992] quickshell[1124]: libEGL warning: egl: failed to create dri2 screen1528test-machine # [ 34.853463] microvm@hermes-test[853]: [* ] A start job is running for NixOS Activation (2s / no limit)1529test-machine # [ 35.273241] microvm@hermes-test[853]: [** ] A start job is running for NixOS Activation (2s / no limit)1530test-machine # [ 35.889820] microvm@hermes-test[853]: [*** ] A start job is running for NixOS Activation (3s / no limit)1531test-machine # [ 36.306703] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (3s / no limit)1532test-machine # [ 36.821408] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (4s / no limit)1533test-machine # [ 37.437853] microvm@hermes-test[853]: [ ***] A start job is running for NixOS Activation (5s / no limit)1534test-machine # [ 38.000411] microvm@hermes-test[853]: [ **] A start job is running for NixOS Activation (5s / no limit)1535test-machine # [ 38.191628] noctalia-shell[1159]: libEGL warning: failed to get driver name for fd -11536test-machine # [ 38.195491] noctalia-shell[1159]: libEGL warning: MESA-LOADER: failed to retrieve device information1537test-machine # [ 38.197149] noctalia-shell[1159]: libEGL warning: failed to get driver name for fd -11538test-machine # [ 38.303188] quickshell[1124]: INFO: Configuration Loaded1539test-machine # [ 38.580903] microvm@hermes-test[853]: [ *] A start job is running for NixOS Activation (6s / no limit)1540test-machine # [ 38.620838] quickshell[1124]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x7189a17ff3a0)1541test-machine # [ 38.623375] quickshell[1124]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x7189b2e5f000)1542test-machine # [ 38.628134] quickshell[1124]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x7189a193e260)1543test-machine # [ 38.720808] sudo[1252]: pam_unix(sudo:session): session closed for user test1544test-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 listSessions1545test-machine # [ 38.852599] noctalia-shell[1159]: MESA: error: ZINK: failed to choose pdev1546test-machine # [ 38.854168] noctalia-shell[1159]: libEGL warning: egl: failed to create dri2 screen1547test-machine # [ 38.856304] sudo[1278]: 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 listSessions1548test-machine # [ 38.874796] sudo[1278]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0)1549test-machine # [ 39.077700] sudo[1278]: pam_unix(sudo:session): session closed for user test1550test-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.34 seconds)1551(finished: subtest: shell IPC target is registered, in 6.64 seconds)1552subtest: chat round-trip through shell IPC1553??? 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.1554 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131555test-machine: must succeed: mkdir -p /tmp/shared/tmpzm9wio9c1556test-machine # [ 39.161353] microvm@hermes-test[853]: [ **] A start job is running for NixOS Activation (6s / no limit)1557test-machine: (finished: must succeed: mkdir -p /tmp/shared/tmpzm9wio9c, in 0.09 seconds)1558test-machine: must succeed: mkdir -p /tmp1559test-machine: (finished: must succeed: mkdir -p /tmp, in 0.10 seconds)1560test-machine: must succeed: cp -r /tmp/shared/tmpzm9wio9c/65dqfr1ss7dlzzb6szkriqfn3ds1vflk-test-pi-chat.py /tmp/test-pi-chat.py1561test-machine: (finished: must succeed: cp -r /tmp/shared/tmpzm9wio9c/65dqfr1ss7dlzzb6szkriqfn3ds1vflk-test-pi-chat.py /tmp/test-pi-chat.py, in 0.33 seconds)1562test-machine # [ 39.760244] microvm@hermes-test[853]: [ ***] A start job is running for NixOS Activation (7s / no limit)1563test-machine # [ 40.101985] sudo[1300]: 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 local1564test-machine # [ 40.141469] sudo[1300]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0)1565test-machine # [ 40.374331] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (8s / no limit)1566test-machine # [ 40.435915] systemd[651]: Starting Update pi-chat location via GeoClue...1567test-machine # [ 40.817298] gz7xcfr0hhk8v47lparpymhxl5an0qnb-spaces-location-update[1304]: where-am-i failed1568test-machine # [ 40.827163] systemd[651]: spaces-location-update.service: Main process exited, code=exited, status=1/FAILURE1569test-machine # [ 40.834093] systemd[651]: spaces-location-update.service: Failed with result 'exit-code'.1570test-machine # [ 40.837977] systemd[651]: Failed to start Update pi-chat location via GeoClue.1571test-machine # [ 40.924282] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (8s / no limit)1572test-machine # [ 41.550702] microvm@hermes-test[853]: [*** ] A start job is running for NixOS Activation (9s / no limit)1573test-machine # [ 42.096974] microvm@hermes-test[853]: [** ] A start job is running for NixOS Activation (9s / no limit)1574test-machine # [ 42.677179] microvm@hermes-test[853]: [* ] A start job is running for NixOS Activation (10s / no limit)1575test-machine # [ 43.001629] systemd[651]: Starting [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/4f3219be-eb5f-4470-a872-824121a55abe/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/4f3219be-eb5f-4470-a872-824121a55abe --session-id 4f3219be-eb5f-4470-a872-824121a55abe --provider local --model qwen2.5:0.5b --name "Chat 1"...1576test-machine # [ 43.293095] microvm@hermes-test[853]: [** ] A start job is running for NixOS Activation (10s / no limit)1577test-machine # [ 43.365461] systemd[651]: Started [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/4f3219be-eb5f-4470-a872-824121a55abe/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/4f3219be-eb5f-4470-a872-824121a55abe --session-id 4f3219be-eb5f-4470-a872-824121a55abe --provider local --model qwen2.5:0.5b --name "Chat 1".1578test-machine # [ 43.409602] pi-sessiond[1323]: landlock-exec: skipped absent path /etc/static/ssl1579test-machine # [ 43.419631] pi-sessiond[1323]: landlock-exec: domain fully enforced (kernel Landlock ABI 7)1580test-machine # [ 43.680536] microvm@hermes-test[853]: [*** ] A start job is running for NixOS Activation (11s / no limit)1581test-machine # [ 43.853355] noctalia-shell[1159]: INFO qml: [20260803-162802] NiriService Service started1582test-machine # [ 43.899059] noctalia-shell[1159]: INFO qml: [20260803-162802] I18n Service started1583test-machine # [ 43.905172] noctalia-shell[1159]: INFO qml: [20260803-162802] I18n Loading "en" (locale: "en-Latn-US")1584test-machine # [ 43.913401] noctalia-shell[1159]: INFO qml: [20260803-162802] Shell ---------------------------1585test-machine # [ 43.915080] noctalia-shell[1159]: INFO qml: [20260803-162802] Shell Noctalia Hello!1586test-machine # [ 43.939192] noctalia-shell[1159]: INFO: Configuration Loaded1587test-machine # [ 43.957311] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Settings loaded1588test-machine # [ 43.959430] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings adapter.settingsVersion: 01589test-machine # [ 43.960207] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v271590test-machine # [ 43.961290] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v281591test-machine # [ 43.964158] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v321592test-machine # [ 43.995693] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v331593test-machine # [ 43.996224] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Set default wallpaperChangeMode=random1594test-machine # [ 43.996423] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v351595test-machine # [ 43.996688] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v361596test-machine # [ 43.997803] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Cleared legacy emoji usage file at: /home/test/.cache/noctalia/emoji_usage.json1597test-machine # [ 43.998823] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v37 (WiFi → Network)1598test-machine # [ 44.004980] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings No WiFi widget IDs found to migrate; leaving settings unchanged1599test-machine # [ 44.005159] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration38 Migrating bar margins from percentages to integers1600test-machine # [ 44.005302] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration39 Migrating templates from boolean format to activeTemplates array1601test-machine # [ 44.005521] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration42 Migrating randomEnabled to automationEnabled1602test-machine # [ 44.005665] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration43 Migrating recursiveSearch to viewMode1603test-machine # [ 44.005813] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration44 Updating PAM pam/password.conf1604test-machine # [ 44.089178] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration45 Migrating bar settings...1605test-machine # [ 44.089807] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration46 Removing legacy PAM configuration file1606test-machine # [ 44.132628] noctalia-shell[1159]: INFO qml: [20260803-162802] Migration47 Removing network_stats.json cache1607test-machine # [ 44.160793] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v481608test-machine # [ 44.166559] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v491609test-machine # [ 44.180094] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Removed old launcher_app_usage.json1610test-machine # [ 44.181746] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v501611test-machine # [ 44.192768] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v531612test-machine # [ 44.194991] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v541613test-machine # [ 44.195650] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v551614test-machine # [ 44.200070] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v56 (Color Scheme Migration)1615test-machine # [ 44.209605] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Running color scheme migration script: /nix/store/lnn22vfnix3f4pv9ryl1acz788ys87jb-noctalia-shell-4.7.7/share/noctalia-shell/Scripts/python/src/theming/migrate-colorschemes.py with configDir: /home/test/.config/noctalia/1616test-machine # [ 44.222875] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v57 (cavaFrameRate -> spectrumFrameRate)1617test-machine # [ 44.230924] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v58 (dock.dockType: static -> attached)1618test-machine # [ 44.234157] noctalia-shell[1159]: INFO qml: [20260803-162802] Settings Migrating settings to v59 (wallpaper.transitionType: string -> array)1619test-machine # [ 44.238704] noctalia-shell[1159]: INFO qml: [20260803-162802] PluginRegistry Loaded plugin states from: /home/test/.config/noctalia/plugins.json1620test-machine # [ 44.239581] noctalia-shell[1159]: INFO qml: [20260803-162802] PluginRegistry Migrating plugin data to v2 (adding sourceUrl)1621test-machine # [ 44.240598] noctalia-shell[1159]: INFO qml: [20260803-162802] PluginRegistry Migration complete1622test-machine # [ 44.240738] noctalia-shell[1159]: INFO qml: [20260803-162802] PluginRegistry Scanning plugin folder: /home/test/.config/noctalia/plugins1623test-machine # [ 44.262976] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (11s / no limit)1624test-machine # [ 44.266179] noctalia-shell[1159]: INFO qml: [20260803-162802] I18n Loaded translations for "en"1625test-machine # [ 44.276359] noctalia-shell[1159]: INFO qml: [20260803-162802] KeyboardLayout Service started1626test-machine # [ 44.350166] noctalia-shell[1159]: INFO qml: [20260803-162802] Icons Service started1627test-machine # [ 44.852556] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (12s / no limit)1628test-machine # [ 44.912416] noctalia-shell[1159]: INFO qml: [20260803-162803] Brightness Service started1629test-machine # [ 45.037974] systemd[651]: Started PipeWire Multimedia Service.1630test-machine # [ 45.049472] systemd[651]: Started Multimedia Service Session Manager.1631test-machine # [ 45.310468] noctalia-shell[1159]: WARN quickshell.service.notifications: Could not register notification server at org.freedesktop.Notifications, presumably because one is already registered.1632test-machine # [ 45.321101] noctalia-shell[1159]: WARN quickshell.service.notifications: Registration will be attempted again if the active service is unregistered.1633test-machine # [ 45.327960] noctalia-shell[1159]: INFO qml: [20260803-162803] DesktopWidgetR Service started1634test-machine # [ 45.419386] pipewire[1367]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown1635test-machine # [ 45.420755] pipewire[1367]: mod.rt: RTKit does not give us MaxRealtimePriority, using 11636test-machine # [ 45.421240] pipewire[1367]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown1637test-machine # [ 45.421519] pipewire[1367]: mod.rt: RTKit does not give us MinNiceLevel, using 01638test-machine # [ 45.422600] pipewire[1367]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown1639test-machine # [ 45.424770] pipewire[1367]: mod.rt: RTKit does not give us RTTimeUSecMax, using -11640test-machine # [ 45.432614] microvm@hermes-test[853]: [ ***] A start job is running for NixOS Activation (13s / no limit)1641test-machine # [ 45.449516] noctalia-shell[1159]: INFO qml: [20260803-162803] BarService Service started1642test-machine # [ 45.494111] systemd[651]: Started Portal service (GNOME implementation).1643test-machine # [ 45.617483] wireplumber[1368]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown1644test-machine # [ 45.618262] wireplumber[1368]: mod.rt: RTKit does not give us MaxRealtimePriority, using 11645test-machine # [ 45.618584] wireplumber[1368]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown1646test-machine # [ 45.618804] wireplumber[1368]: mod.rt: RTKit does not give us MinNiceLevel, using 01647test-machine # [ 45.619080] wireplumber[1368]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown1648test-machine # [ 45.619315] wireplumber[1368]: mod.rt: RTKit does not give us RTTimeUSecMax, using -11649test-machine # [ 45.793898] /nix/store/n4d4gzgydqwvnbx050p5gn1zm5wp7vm3-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1194]: Failed to load RealtimeKit property: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable1650test-machine # [ 45.797101] noctalia-shell[1159]: INFO qml: [20260803-162803] Shell ---------------------------1651test-machine # [ 45.797368] noctalia-shell[1159]: INFO qml: [20260803-162803] Wallpaper Service started1652test-machine # [ 45.798751] /nix/store/n4d4gzgydqwvnbx050p5gn1zm5wp7vm3-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1194]: Failed to load RealtimeKit property: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable1653test-machine # [ 45.800170] /nix/store/n4d4gzgydqwvnbx050p5gn1zm5wp7vm3-xdg-desktop-portal-1.20.4/libexec/xdg-desktop-portal[1194]: Failed to load RealtimeKit property: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable1654test-machine # [ 45.821147] noctalia-shell[1159]: INFO qml: [20260803-162804] ImageCache Service started1655test-machine # [ 45.841895] systemd[651]: Starting Portal service (GTK/GNOME implementation)...1656test-machine # [ 45.868104] noctalia-shell[1159]: INFO qml: [20260803-162804] AppThemeServic Service started1657test-machine # [ 45.872546] noctalia-shell[1159]: INFO qml: [20260803-162804] ColorScheme Service started1658test-machine # [ 45.904261] noctalia-shell[1159]: INFO qml: [20260803-162804] DarkModeServic Service started1659test-machine # [ 45.914367] noctalia-shell[1159]: INFO qml: [20260803-162804] SystemStat Service started (polling deferred until a consumer registers).1660test-machine # [ 45.945502] noctalia-shell[1159]: INFO qml: [20260803-162804] Location Service started1661test-machine # [ 45.969405] noctalia-shell[1159]: INFO qml: [20260803-162804] IdleInhibitor Service started1662test-machine # [ 45.973425] noctalia-shell[1159]: INFO qml: [20260803-162804] IdleService Service started1663test-machine # [ 46.014156] microvm@hermes-test[853]: [ **] A start job is running for NixOS Activation (13s / no limit)1664test-machine # [ 46.017319] noctalia-shell[1159]: INFO qml: [20260803-162804] HostService Service started1665test-machine # [ 46.019922] noctalia-shell[1159]: INFO qml: [20260803-162804] GitHub Service started1666test-machine # [ 46.022434] noctalia-shell[1159]: INFO qml: [20260803-162804] Supporter Service started1667test-machine # [ 46.040652] noctalia-shell[1159]: INFO qml: [20260803-162804] CustomButtonIP Service started1668test-machine # [ 46.043081] noctalia-shell[1159]: INFO qml: [20260803-162804] IPCService Service started1669test-machine # [ 46.045555] noctalia-shell[1159]: WARN quickshell.dbus: Could not launch service org.freedesktop.UPower.PowerProfiles: QDBusError(org.freedesktop.DBus.Error.ServiceUnknown, The name is not activatable)1670test-machine # [ 46.048995] noctalia-shell[1159]: WARN quickshell.service.powerprofiles: Could not start PowerProfilesDaemon. The PowerProfiles service will not work.1671test-machine # [ 46.080184] noctalia-shell[1159]: WARN: Layershell screen does not correspond to a real screen. Letting the compositor pick.1672test-machine # [ 46.084147] noctalia-shell[1159]: WARN qml: [20260803-162804] Location Cannot fetch weather without coordinates1673test-machine # [ 46.094675] noctalia-shell[1159]: INFO qml: [20260803-162804] HostService Detected NixOS 26.11 (Zokor)1674test-machine # [ 46.096446] noctalia-shell[1159]: INFO qml: [20260803-162804] HostService Looking for logo icon: nix-snowflake1675test-machine # [ 46.130623] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginRegistry Loaded plugin: spaces-sessions - Spaces Agent Sessions1676test-machine # [ 46.132214] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginRegistry Loaded plugin: voice-indicator - Spaces Voice Indicator1677test-machine # [ 46.132869] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginRegistry All plugin manifests loaded. Total plugins: 21678test-machine # [ 46.133775] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Initializing plugin system1679test-machine # [ 46.134717] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Found 2 enabled plugins: ["spaces-sessions","voice-indicator"]1680test-machine # [ 46.135641] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Loading plugin: spaces-sessions1681test-machine # [ 46.153758] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Loading plugin: voice-indicator1682test-machine # [ 46.162667] noctalia-shell[1159]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x72240a13d980)1683test-machine # [ 46.165058] noctalia-shell[1159]: WARN quickshell.io.fileview: got operation finished from dropped operation qs::io::FileViewOperation(0x72240a13e100)1684test-machine # [ 46.190198] niri[920]: 2026-08-03T16:28:04.385253Z WARN niri::handlers::layer_shell: no output for new layer surface, closing1685test-machine # [ 46.206098] noctalia-shell[1159]: INFO qml: [20260803-162804] ImageCache ImageMagick available1686test-machine # [ 46.219398] niri[920]: 2026-08-03T16:28:04.413103Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing1687test-machine # [ 46.231374] niri[920]: 2026-08-03T16:28:04.425929Z WARN niri::handlers::layer_shell: no output for new layer surface, closing1688test-machine # [ 46.233984] niri[920]: 2026-08-03T16:28:04.426000Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing1689test-machine # [ 46.270539] noctalia-shell[1159]: INFO qml: [20260803-162804] HostService resolved hostname test-machine1690test-machine # [ 46.278199] niri[920]: 2026-08-03T16:28:04.473607Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing1691test-machine # [ 46.286597] niri[920]: 2026-08-03T16:28:04.479218Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing1692test-machine # [ 46.297557] noctalia-shell[1159]: INFO qml: [20260803-162804] Wallpaper Starting scan for in /home/test/Pictures/Wallpapers recursive: false1693test-machine # [ 46.308670] systemd-resolved[374]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.1694test-machine # [ 46.317943] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1695test-machine # [ 46.325363] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1696test-machine # [ 46.337038] noctalia-shell[1159]: INFO qml: [20260803-162804] ColorScheme Applying color scheme: Noctalia (default)1697test-machine # [ 46.365741] noctalia-shell[1159]: WARN qml: [20260803-162804] Wallpaper Scan failed for exit code: 1 (directory might not exist)1698test-machine # [ 46.372536] noctalia-shell[1159]: WARN qml: [20260803-162804] GitHub API request failed - using cached data without updating timestamp1699test-machine # [ 46.384410] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Loaded Main.qml for plugin: spaces-sessions1700test-machine # [ 46.404155] noctalia-shell[1159]: INFO qml: [20260803-162804] BarWidgetRegis Registered plugin widget: plugin:spaces-sessions1701test-machine # [ 46.407095] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Loaded bar widget for plugin: spaces-sessions1702test-machine # [ 46.409971] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Plugin loaded: spaces-sessions1703test-machine # [ 46.448446] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Loaded Main.qml for plugin: voice-indicator1704test-machine # [ 46.460746] noctalia-shell[1159]: INFO qml: [20260803-162804] BarWidgetRegis Registered plugin widget: plugin:voice-indicator1705test-machine # [ 46.461949] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Loaded bar widget for plugin: voice-indicator1706test-machine # [ 46.464202] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Plugin loaded: voice-indicator1707test-machine # [ 46.466182] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService All plugins loaded1708test-machine # [ 46.467928] noctalia-shell[1159]: INFO qml: [20260803-162804] PluginService Refreshing available plugins1709test-machine # [ 46.601643] microvm@hermes-test[853]: [ *] A start job is running for NixOS Activation (14s / no limit)1710test-machine # [ 46.692230] noctalia-shell[1159]: WARN qml: [20260803-162804] SystemStat No supported temperature sensor found1711test-machine # [ 46.698364] noctalia-shell[1159]: ERROR qml: [20260803-162804] PluginService Empty response from Noctalia Plugins1712test-machine # [ 46.806492] wireplumber[1368]: default: Failed to get percentage from UPower: org.freedesktop.DBus.Error.NameHasNoOwner1713test-machine # [ 46.810860] wireplumber[1368]: spa.bluez5: BlueZ system service is not available1714test-machine # [ 46.990527] systemd[651]: Started Portal service (GTK/GNOME implementation).1715test-machine # [ 47.173002] systemd[651]: Started Portal service.1716test-machine # [ 47.188743] microvm@hermes-test[853]: [ **] A start job is running for NixOS Activation (14s / no limit)1717test-machine # [ 47.222908] noctalia-shell[1159]: 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'")1718test-machine # [ 47.224887] noctalia-shell[1159]: 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'")1719test-machine # [ 47.227743] quickshell[1124]: 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")1720test-machine # [ 47.250827] wireplumber[1368]: [0:00:47.250563998] [1368] INFO IPAManager ipa_manager.cpp:147 libcamera is not installed. Adding '/nix/store/src/ipa' to the IPA search path1721test-machine # [ 47.309459] wireplumber[1368]: [0:00:47.309187371] [1368] INFO Camera camera_manager.cpp:340 libcamera v0.7.01722test-machine # [ 47.446165] noctalia-shell[1159]: INFO qml: [20260803-162805] HooksService Service started1723test-machine # [ 47.449647] noctalia-shell[1159]: INFO qml: [20260803-162805] Font Service started1724test-machine # [ 47.469156] noctalia-shell[1159]: INFO qml: [20260803-162805] UpdateService Version: v4.7.71725test-machine # [ 47.470837] noctalia-shell[1159]: WARN qml: [20260803-162805] PanelService Panel not found: setupWizardPanel-1726test-machine # [ 47.767106] microvm@hermes-test[853]: [ ***] A start job is running for NixOS Activation (15s / no limit)1727test-machine # [ 47.788354] noctalia-shell[1159]: INFO qml: [20260803-162805] Font Loaded 40 fonts, 10 monospace1728test-machine # [ 47.972861] noctalia-shell[1159]: WARN qml: [20260803-162806] PanelService Panel not found: setupWizardPanel-1729test-machine # [ 48.244781] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (15s / no limit)1730test-machine # [ 48.461292] noctalia-shell[1159]: WARN: Layershell screen does not correspond to a real screen. Letting the compositor pick.1731test-machine # [ 48.462958] niri[920]: 2026-08-03T16:28:06.657177Z WARN niri::handlers::layer_shell: no output for new layer surface, closing1732test-machine # [ 48.464177] niri[920]: 2026-08-03T16:28:06.657265Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing1733test-machine # [ 48.470327] niri[920]: 2026-08-03T16:28:06.665874Z WARN niri::backend::tty: error doing early import: Error::DeviceMissing1734test-machine # [ 48.486729] noctalia-shell[1159]: WARN qml: [20260803-162806] PanelService Panel not found: setupWizardPanel-1735test-machine # [ 48.743213] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (16s / no limit)1736test-machine # [ 48.967282] noctalia-shell[1159]: WARN qml: [20260803-162807] PanelService Panel not found: setupWizardPanel-1737test-machine # [ 49.242792] microvm@hermes-test[853]: [*** ] A start job is running for NixOS Activation (16s / no limit)1738test-machine # [ 49.447549] noctalia-shell[1159]: WARN qml: [20260803-162807] PanelService Panel not found: setupWizardPanel-1739test-machine # [ 49.751665] microvm@hermes-test[853]: [** ] A start job is running for NixOS Activation (17s / no limit)1740test-machine # [ 49.950923] noctalia-shell[1159]: WARN qml: [20260803-162808] PanelService Panel not found: setupWizardPanel-1741test-machine # [ 50.244392] microvm@hermes-test[853]: [* ] A start job is running for NixOS Activation (17s / no limit)1742test-machine # [ 50.452550] noctalia-shell[1159]: WARN qml: [20260803-162808] PanelService Panel not found: setupWizardPanel-1743test-machine # [ 50.743468] microvm@hermes-test[853]: [** ] A start job is running for NixOS Activation (18s / no limit)1744test-machine # [ 50.955109] noctalia-shell[1159]: WARN qml: [20260803-162809] PanelService Panel not found: setupWizardPanel-1745test-machine # [ 51.244252] microvm@hermes-test[853]: [*** ] A start job is running for NixOS Activation (18s / no limit)1746test-machine # [ 51.456311] noctalia-shell[1159]: WARN qml: [20260803-162809] PanelService Panel not found: setupWizardPanel-1747test-machine # [ 51.752610] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (19s / no limit)1748test-machine # [ 51.959538] noctalia-shell[1159]: WARN qml: [20260803-162810] PanelService Panel not found: setupWizardPanel-1749test-machine # [ 52.242969] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (19s / no limit)1750test-machine # [ 52.461088] noctalia-shell[1159]: WARN qml: [20260803-162810] PanelService Panel not found: setupWizardPanel-1751test-machine # [ 52.743699] microvm@hermes-test[853]: [ ***] A start job is running for NixOS Activation (20s / no limit)1752test-machine # [ 52.963545] noctalia-shell[1159]: WARN qml: [20260803-162811] PanelService Panel not found: setupWizardPanel-1753test-machine # [ 53.241776] microvm@hermes-test[853]: [ **] A start job is running for NixOS Activation (20s / no limit)1754test-machine # [ 53.465912] noctalia-shell[1159]: WARN qml: [20260803-162811] PanelService Panel not found: setupWizardPanel-1755test-machine # [ 53.741678] microvm@hermes-test[853]: [ *] A start job is running for NixOS Activation (21s / no limit)1756test-machine # [ 53.967447] noctalia-shell[1159]: WARN qml: [20260803-162812] PanelService Panel not found: setupWizardPanel-1757test-machine # [ 54.242634] microvm@hermes-test[853]: [ **] A start job is running for NixOS Activation (21s / no limit)1758test-machine # [ 54.291697] systemd[1]: systemd-localed.service: Deactivated successfully.1759test-machine # [ 54.468574] noctalia-shell[1159]: WARN qml: [20260803-162812] PanelService Panel not found: setupWizardPanel-1760test-machine # [ 54.743993] microvm@hermes-test[853]: [ ***] A start job is running for NixOS Activation (22s / no limit)1761test-machine # [ 54.745481] sudo[1300]: pam_unix(sudo:session): session closed for user test1762(finished: subtest: chat round-trip through shell IPC, in 15.67 seconds)1763subtest: no per-session local pi units exist (local spawn is gone)1764test-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>&11765test-machine # [ 54.804321] sudo[1548]: 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-legend1766test-machine # [ 54.824842] sudo[1548]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0)1767test-machine # [ 54.849145] sudo[1548]: pam_unix(sudo:session): session closed for user test1768test-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.10 seconds)1769(finished: subtest: no per-session local pi units exist (local spawn is gone), in 0.10 seconds)1770subtest: memory extension stores facts and recalls them in a new session1771test-machine: must succeed: mkdir -p /tmp/shared/tmpjrg881lr1772test-machine: (finished: must succeed: mkdir -p /tmp/shared/tmpjrg881lr, in 0.03 seconds)1773test-machine: must succeed: mkdir -p /tmp1774test-machine: (finished: must succeed: mkdir -p /tmp, in 0.03 seconds)1775test-machine: must succeed: cp -r /tmp/shared/tmpjrg881lr/9qa63kz3w7d8bn33m5fylm8g7xkjqhi1-test-pi-memory.py /tmp/test-pi-memory.py1776test-machine: (finished: must succeed: cp -r /tmp/shared/tmpjrg881lr/9qa63kz3w7d8bn33m5fylm8g7xkjqhi1-test-pi-memory.py /tmp/test-pi-memory.py, in 0.03 seconds)1777test-machine # [ 54.969891] noctalia-shell[1159]: WARN qml: [20260803-162813] PanelService Panel not found: setupWizardPanel-1778test-machine # [ 55.028259] sudo[1565]: 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-chat1779test-machine # [ 55.042229] sudo[1565]: pam_unix(sudo:session): session opened for user test(uid=1000) by (uid=0)1780test-machine # [ 55.244556] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (22s / no limit)1781test-machine # [ 55.339454] voxtype-daemon[1055]: whisper_model_load: model size = 487.01 MB1782test-machine # [ 55.340872] voxtype-daemon[1055]: 2026-08-03T16:28:13.535429Z INFO Model loaded in 28.71s1783test-machine # [ 55.344578] voxtype-daemon[1055]: 2026-08-03T16:28:13.540178Z INFO Model loaded, ready for voice input1784test-machine # [ 55.470720] noctalia-shell[1159]: WARN qml: [20260803-162813] PanelService Panel not found: setupWizardPanel-1785test-machine # [ 55.602103] systemd[651]: Starting [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/5e8d0f28-d647-420e-b311-3ade23c34812/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/5e8d0f28-d647-420e-b311-3ade23c34812 --session-id 5e8d0f28-d647-420e-b311-3ade23c34812 --provider local --model qwen2.5:0.5b --name harness-2...1786test-machine # [ 55.673427] pi-sessiond[1577]: landlock-exec: skipped absent path /etc/static/ssl1787test-machine # [ 55.676125] pi-sessiond[1577]: landlock-exec: domain fully enforced (kernel Landlock ABI 7)1788test-machine # [ 55.678159] systemd[651]: Started [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/5e8d0f28-d647-420e-b311-3ade23c34812/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/5e8d0f28-d647-420e-b311-3ade23c34812 --session-id 5e8d0f28-d647-420e-b311-3ade23c34812 --provider local --model qwen2.5:0.5b --name harness-2.1789test-machine # [ 55.748708] microvm@hermes-test[853]: [ *** ] A start job is running for NixOS Activation (23s / no limit)1790test-machine # [ 55.973774] noctalia-shell[1159]: WARN qml: [20260803-162814] PanelService Panel not found: setupWizardPanel-1791test-machine # [ 56.069527] microvm@hermes-test[853]: [ OK ] Finished NixOS Activation.1792test-machine # [ 56.074514] microvm@hermes-test[853]: Starting Switch Root...1793test-machine # [ 56.475118] noctalia-shell[1159]: WARN qml: [20260803-162814] PanelService Panel not found: setupWizardPanel-1794test-machine # [ 56.978987] noctalia-shell[1159]: WARN qml: [20260803-162815] PanelService Panel not found: setupWizardPanel-1795test-machine # [ 57.479892] noctalia-shell[1159]: WARN qml: [20260803-162815] PanelService Panel not found: setupWizardPanel-1796test-machine # [ 57.667498] microvm@hermes-test[853]: +q6E616D651797test-machine # [ 57.673854] microvm@hermes-test[853]: Welcome to NixOS 26.11 (Zokor)!1798test-machine # [ 57.675792] microvm@hermes-test[853]:1799test-machine # [ 57.987149] noctalia-shell[1159]: WARN qml: [20260803-162816] PanelService Panel not found: setupWizardPanel-1800test-machine # [ 58.486716] noctalia-shell[1159]: WARN qml: [20260803-162816] PanelService Panel not found: setupWizardPanel-1801test-machine # [ 58.989847] noctalia-shell[1159]: WARN qml: [20260803-162817] PanelService Panel not found: setupWizardPanel-1802test-machine # [ 59.490482] noctalia-shell[1159]: WARN qml: [20260803-162817] PanelService Panel not found: setupWizardPanel-1803test-machine # [ 59.991804] noctalia-shell[1159]: WARN qml: [20260803-162818] PanelService Panel not found: setupWizardPanel-1804test-machine # [ 60.410855] microvm@hermes-test[853]: [ OK ] Stopped initrd-switch-root.service.1805test-machine # [ 60.423721] microvm@hermes-test[853]: [ OK ] Created slice Slice /system/getty.1806test-machine # [ 60.428733] microvm@hermes-test[853]: [ OK ] Created slice Slice /system/serial-getty.1807test-machine # [ 60.433491] microvm@hermes-test[853]: [ OK ] Created slice User and Session Slice.1808test-machine # [ 60.439106] microvm@hermes-test[853]: [ OK ] Started Dispatch Password Requests to Console Directory Watch.1809test-machine # [ 60.444395] microvm@hermes-test[853]: [ OK ] Started Forward Password Requests to Wall Directory Watch.1810test-machine # [ 60.447590] microvm@hermes-test[853]: Expecting device /dev/ttyS0...1811test-machine # [ 60.451675] microvm@hermes-test[853]: [ OK ] Reached target Local Encrypted Volumes.1812test-machine # [ 60.455863] microvm@hermes-test[853]: [ OK ] Stopped target initrd-fs.target.1813test-machine # [ 60.460447] microvm@hermes-test[853]: [ OK ] Stopped target initrd-root-fs.target.1814test-machine # [ 60.464828] microvm@hermes-test[853]: [ OK ] Stopped target initrd-switch-root.target.1815test-machine # [ 60.468895] microvm@hermes-test[853]: [ OK ] Reached target Virtual Machines and Containers.1816test-machine # [ 60.472234] microvm@hermes-test[853]: [ OK ] Reached target Path Units.1817test-machine # [ 60.475713] microvm@hermes-test[853]: [ OK ] Reached target Remote File Systems.1818test-machine # [ 60.478842] microvm@hermes-test[853]: [ OK ] Reached target Slice Units.1819test-machine # [ 60.481942] microvm@hermes-test[853]: [ OK ] Reached target Swaps.1820test-machine # [ 60.493062] noctalia-shell[1159]: WARN qml: [20260803-162818] PanelService Panel not found: setupWizardPanel-1821test-machine # [ 60.494187] microvm@hermes-test[853]: [ OK ] Listening on Query the User Interactively for a Password.1822test-machine # [ 60.505305] microvm@hermes-test[853]: [ OK ] Listening on Process Core Dump Socket.1823test-machine # [ 60.517421] microvm@hermes-test[853]: [ OK ] Listening on Credential Encryption/Decryption.1824test-machine # [ 60.526242] microvm@hermes-test[853]: [ OK ] Listening on Factory Reset Management.1825test-machine # [ 60.530920] microvm@hermes-test[853]: [ OK ] Listening on Hostname Service Socket.1826test-machine # [ 60.541371] microvm@hermes-test[853]: Starting Journal Log Access Socket...1827test-machine # [ 60.546325] microvm@hermes-test[853]: [ OK ] Listening on Journal Audit Socket.1828test-machine # [ 60.557556] microvm@hermes-test[853]: [ OK ] Listening on Console Output Muting Service Socket.1829test-machine # [ 60.565619] microvm@hermes-test[853]: [ OK ] Listening on Network Management Resolve Hook Socket.1830test-machine # [ 60.570798] microvm@hermes-test[853]: [ OK ] Listening on Network Management Metrics Varlink Socket.1831test-machine # [ 60.575880] microvm@hermes-test[853]: [ OK ] Listening on Network Management Varlink Socket.1832test-machine # [ 60.580989] microvm@hermes-test[853]: [ OK ] Listening on Network Management Netlink Socket.1833test-machine # [ 60.586506] microvm@hermes-test[853]: [ OK ] Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1834test-machine # [ 60.620453] microvm@hermes-test[853]: [ OK ] Listening on Disk Repartitioning Service Socket.1835test-machine # [ 60.628505] microvm@hermes-test[853]: [ OK ] Listening on Resolve Monitor Varlink Socket.1836test-machine # [ 60.632508] microvm@hermes-test[853]: [ OK ] Listening on Resolve Service Varlink Socket.1837test-machine # [ 60.638480] microvm@hermes-test[853]: [ OK ] Listening on udev Control Socket.1838test-machine # [ 60.644992] microvm@hermes-test[853]: [ OK ] Listening on udev Varlink Socket.1839test-machine # [ 60.655778] microvm@hermes-test[853]: Mounting Huge Pages File System...1840test-machine # [ 60.705906] microvm@hermes-test[853]: Mounting POSIX Message Queue File System...1841test-machine # [ 60.725149] microvm@hermes-test[853]: Mounting Kernel Debug File System...1842test-machine # [ 60.763600] microvm@hermes-test[853]: Mounting Kernel Trace File System...1843test-machine # [ 60.909340] microvm@hermes-test[853]: Starting Create List of Static Device Nodes...1844test-machine # [ 60.961561] microvm@hermes-test[853]: Starting Load Kernel Module configfs...1845test-machine # [ 60.995535] noctalia-shell[1159]: WARN qml: [20260803-162819] PanelService Panel not found: setupWizardPanel-1846test-machine # [ 61.089091] microvm@hermes-test[853]: Mounting FUSE Control File System...1847test-machine # [ 61.210071] microvm@hermes-test[853]: Starting Journal Service...1848test-machine # [ 61.286081] microvm@hermes-test[853]: Starting Load Kernel Modules...1849test-machine # [ 61.345842] microvm@hermes-test[853]: Starting Userspace Out-Of-Memory (OOM) Killer...1850test-machine # [ 61.390697] microvm@hermes-test[853]: Starting Remount Root and Kernel File Systems...1851test-machine # [ 61.460610] microvm@hermes-test[853]: Starting Coldplug All udev Devices...1852test-machine # [ 61.485368] microvm@hermes-test[853]: [ OK ] Listening on Journal Log Access Socket.1853test-machine # [ 61.492699] microvm@hermes-test[853]: [ OK ] Mounted Huge Pages File System.1854test-machine # [ 61.497083] noctalia-shell[1159]: WARN qml: [20260803-162819] PanelService Panel not found: setupWizardPanel-1855test-machine # [ 61.506680] microvm@hermes-test[853]: [ OK ] Mounted POSIX Message Queue File System.1856test-machine # [ 61.517888] microvm@hermes-test[853]: [ OK ] Mounted Kernel Debug File System.1857test-machine # [ 61.531125] microvm@hermes-test[853]: [ OK ] Mounted Kernel Trace File System.1858test-machine # [ 61.545819] microvm@hermes-test[853]: [ OK ] Finished Create List of Static Device Nodes.1859test-machine # [ 61.558269] microvm@hermes-test[853]: [ OK ] Finished Load Kernel Module configfs.1860test-machine # [ 61.566150] microvm@hermes-test[853]: [ OK ] Mounted FUSE Control File System.1861test-machine # [ 61.641361] microvm@hermes-test[853]: Mounting Kernel Configuration File System...1862test-machine # [ 61.713609] microvm@hermes-test[853]: Starting Create Static Device Nodes in /dev gracefully...1863test-machine # [ 61.841271] microvm@hermes-test[853]: [ OK ] Started Userspace Out-Of-Memory (OOM) Killer.1864test-machine # [ 61.889248] microvm@hermes-test[853]: [ OK ] Started Journal Service.1865test-machine # [ 62.003968] noctalia-shell[1159]: WARN qml: [20260803-162820] PanelService Panel not found: setupWizardPanel-1866test-machine # [ 62.055316] microvm@hermes-test[853]: [ OK ] Finished Remount Root and Kernel File Systems.1867test-machine # [ 62.069085] microvm@hermes-test[853]: [ OK ] Listening on Disk Image Download Service Socket.1868test-machine # [ 62.090782] microvm@hermes-test[853]: Starting Flush Journal to Persistent Storage...1869test-machine # [ 62.106610] microvm@hermes-test[853]: Starting Load/Save OS Random Seed...1870test-machine # [ 62.184301] microvm@hermes-test[853]: Starting Network Time Synchronization...1871test-machine # [ 62.252424] microvm@hermes-test[853]: [ OK ] Finished Load Kernel Modules.1872test-machine # [ 62.266639] microvm@hermes-test[853]: [ OK ] Finished Coldplug All udev Devices.1873test-machine # [ 62.275381] microvm@hermes-test[853]: [ OK ] Mounted Kernel Configuration File System.1874test-machine # [ 62.305425] microvm@hermes-test[853]: Starting Apply Kernel Variables...1875test-machine # [ 62.368154] systemd[651]: Starting [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/bffb319e-424e-442d-ad0b-0d5d79ee6b02/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/bffb319e-424e-442d-ad0b-0d5d79ee6b02 --session-id bffb319e-424e-442d-ad0b-0d5d79ee6b02 --provider local --model qwen2.5:0.5b --name memory-recall...1876test-machine # [ 62.418096] microvm@hermes-test[853]: [ OK ] Finished Load/Save OS Random Seed.1877test-machine # [ 62.427636] pi-sessiond[1655]: landlock-exec: skipped absent path /etc/static/ssl1878test-machine # [ 62.430442] pi-sessiond[1655]: landlock-exec: domain fully enforced (kernel Landlock ABI 7)1879test-machine # [ 62.434297] systemd[651]: Started [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/bffb319e-424e-442d-ad0b-0d5d79ee6b02/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/bffb319e-424e-442d-ad0b-0d5d79ee6b02 --session-id bffb319e-424e-442d-ad0b-0d5d79ee6b02 --provider local --model qwen2.5:0.5b --name memory-recall.1880test-machine # [ 62.502839] noctalia-shell[1159]: WARN qml: [20260803-162820] PanelService Panel not found: setupWizardPanel-1881test-machine # [ 62.559153] microvm@hermes-test[853]: [ OK ] Finished Flush Journal to Persistent Storage.1882test-machine # [ 62.609764] microvm@hermes-test[853]: [ OK ] Finished Create Static Device Nodes in /dev gracefully.1883test-machine # [ 62.633595] microvm@hermes-test[853]: Starting Create Static Device Nodes in /dev...1884test-machine # [ 62.707196] microvm@hermes-test[853]: [ OK ] Started Network Time Synchronization.1885test-machine # [ 62.716938] microvm@hermes-test[853]: [ OK ] Reached target System Time Set.1886test-machine # [ 62.810943] microvm@hermes-test[853]: [ OK ] Finished Apply Kernel Variables.1887test-machine # [ 62.825927] microvm@hermes-test[853]: Starting Network Name Resolution...1888test-machine # [ 62.919845] microvm@hermes-test[853]: [ OK ] Finished Create Static Device Nodes in /dev.1889test-machine # [ 62.931481] microvm@hermes-test[853]: [ OK ] Reached target Preparation for Local File Systems.1890test-machine # [ 62.946825] microvm@hermes-test[853]: Mounting /home/test/hermes...1891test-machine # [ 62.989881] microvm@hermes-test[853]: Mounting /run/wrappers...1892test-machine # [ 63.005796] noctalia-shell[1159]: WARN qml: [20260803-162821] PanelService Panel not found: setupWizardPanel-1893test-machine # [ 63.013443] microvm@hermes-test[853]: Starting Rule-based Manager for Device Events and Files...1894test-machine # [ 63.205916] microvm@hermes-test[853]: [ OK ] Mounted /home/test/hermes.1895test-machine # [ 63.212276] microvm@hermes-test[853]: [ OK ] Mounted /run/wrappers.1896test-machine # [ 63.217688] microvm@hermes-test[853]: [ OK ] Reached target Local File Systems.1897test-machine # [ 63.239477] microvm@hermes-test[853]: [ OK ] Listening on Boot Loader Control Service Socket.1898test-machine # [ 63.255592] microvm@hermes-test[853]: Starting Create SUID/SGID Wrappers...1899test-machine # [ 63.332523] microvm@hermes-test[853]: Starting Create System Files and Directories...1900test-machine # [ 63.400528] microvm@hermes-test[853]: [ OK ] Started Network Name Resolution.1901test-machine # [ 63.506256] noctalia-shell[1159]: WARN qml: [20260803-162821] PanelService Panel not found: setupWizardPanel-1902test-machine # [ 63.717667] microvm@hermes-test[853]: [ OK ] Finished Create System Files and Directories.1903test-machine # [ 63.735779] microvm@hermes-test[853]: Starting Rebuild Journal Catalog...1904test-machine # [ 63.748412] microvm@hermes-test[853]: Starting Record System Boot/Shutdown in UTMP...1905test-machine # [ 63.810976] microvm@hermes-test[853]: [ OK ] Started Rule-based Manager for Device Events and Files.1906test-machine # [ 63.834571] microvm@hermes-test[853]: Starting Network Management...1907test-machine # [ 64.024567] noctalia-shell[1159]: WARN qml: [20260803-162822] PanelService Panel not found: setupWizardPanel-1908test-machine # [ 64.527482] noctalia-shell[1159]: WARN qml: [20260803-162822] PanelService Panel not found: setupWizardPanel-1909test-machine # [ 64.607798] microvm@hermes-test[853]: [ OK ] Finished Record System Boot/Shutdown in UTMP.1910test-machine # [ 64.651680] microvm@hermes-test[853]: Starting Virtual Console Setup...1911test-machine # [ 64.746297] microvm@hermes-test[853]: [ OK ] Finished Rebuild Journal Catalog.1912test-machine # [ 64.807877] microvm@hermes-test[853]: Starting Update is Completed...1913test-machine # [ 65.031121] noctalia-shell[1159]: WARN qml: [20260803-162823] PanelService Panel not found: setupWizardPanel-1914test-machine # [ 65.159370] microvm@hermes-test[853]: [ OK ] Finished Update is Completed.1915test-machine # [ 65.534191] noctalia-shell[1159]: WARN qml: [20260803-162823] PanelService Panel not found: setupWizardPanel-1916test-machine # [ 65.910230] noctalia-shell[1159]: WARN qml: [20260803-162824] Location Cannot fetch weather without coordinates1917test-machine # [ 65.996802] sudo[1565]: pam_unix(sudo:session): session closed for user test1918(finished: subtest: memory extension stores facts and recalls them in a new session, in 11.16 seconds)1919subtest: loopback daemon runs in the user manager, not as root1920test-machine: waiting for success: systemctl --user --machine=test@.host is-active pi-sessiond.service1921test-machine # [ 66.036927] noctalia-shell[1159]: WARN qml: [20260803-162824] PanelService Panel not found: setupWizardPanel-1922test-machine # [ 66.097166] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1923test-machine # [ 66.146054] (systemd-stdio-bridge)[1752]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1924test-machine # [ 66.176075] systemd[1]: Started Session 12 of User test.1925test-machine # [ 66.196070] (systemd-stdio-bridge)[1752]: gkr-pam: couldn't unlock the login keyring.1926test-machine # [ 66.228317] systemd[1]: run-p1733-i1734.service: Deactivated successfully.1927test-machine # [ 66.230481] (sd-pam)[1756]: pam_unix(login:session): session closed for user test1928test-machine # [ 66.242589] systemd[1]: session-12.scope: Deactivated successfully.1929test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active pi-sessiond.service, in 0.23 seconds)1930test-machine: must succeed: systemctl --user --machine=test@.host show -p MainPID --value pi-sessiond.service1931test-machine # [ 66.293578] microvm@hermes-test[853]: [ OK ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1932test-machine # [ 66.303678] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.1933test-machine # [ 66.371651] (systemd-stdio-bridge)[1779]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)1934test-machine # [ 66.410215] systemd[1]: Started Session 13 of User test.1935test-machine # [ 66.440147] (systemd-stdio-bridge)[1779]: gkr-pam: couldn't unlock the login keyring.1936test-machine # [ 66.495126] systemd[1]: run-p1775-i1776.service: Deactivated successfully.1937test-machine: (finished: must succeed: systemctl --user --machine=test@.host show -p MainPID --value pi-sessiond.service, in 0.26 seconds)1938test-machine: must succeed: stat -c %u /proc/7161939test-machine # [ 66.522934] (sd-pam)[1784]: pam_unix(login:session): session closed for user test1940test-machine # [ 66.531668] systemd[1]: session-13.scope: Deactivated successfully.1941test-machine # [ 66.539164] noctalia-shell[1159]: WARN qml: [20260803-162824] PanelService Panel not found: setupWizardPanel-1942test-machine: (finished: must succeed: stat -c %u /proc/716, in 0.05 seconds)1943(finished: subtest: loopback daemon runs in the user manager, not as root, in 0.54 seconds)1944test-machine: must succeed: echo home-marker-secret > /home/test/secret-marker1945test-machine: (finished: must succeed: echo home-marker-secret > /home/test/secret-marker, in 0.02 seconds)1946test-machine: must succeed: chown test /home/test/secret-marker1947test-machine: (finished: must succeed: chown test /home/test/secret-marker, in 0.03 seconds)1948subtest: daemon mount namespace mounts the user's home read-only1949test-machine: must succeed: test -f /home/test/secret-marker1950test-machine: (finished: must succeed: test -f /home/test/secret-marker, in 0.02 seconds)1951test-machine: must succeed: nsenter -t 716 -m test -f /home/test/secret-marker1952test-machine: (finished: must succeed: nsenter -t 716 -m test -f /home/test/secret-marker, in 0.04 seconds)1953??? 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.1954 File "/nix/store/zbbcdn1y5jkph3v03bqm8sq0x3rp5l7x-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 18131955test-machine: must fail: nsenter -t 716 -m sh -c 'echo x > /home/test/write-probe'1956test-machine # sh: line 1: /home/test/write-probe: Read-only file system1957test-machine: (finished: must fail: nsenter -t 716 -m sh -c 'echo x > /home/test/write-probe', in 0.09 seconds)1958(finished: subtest: daemon mount namespace mounts the user's home read-only, in 0.15 seconds)1959subtest: daemon listens on 8768 (bun cold start can take a while)1960test-machine: waiting for success: ss -tln | grep -q ':8768 '1961test-machine # [ 67.041167] noctalia-shell[1159]: WARN qml: [20260803-162825] PanelService Panel not found: setupWizardPanel-1962test-machine: (finished: waiting for success: ss -tln | grep -q ':8768 ', in 0.65 seconds)1963(finished: subtest: daemon listens on 8768 (bun cold start can take a while), in 0.65 seconds)1964subtest: WS auth: runtime token accepted, wrong token rejected1965test-machine: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/f5cv7639fpbkhqljh8a1bph8khiskhqi-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token auth'1966test-machine # [ 67.473073] su[1838]: Successful su for test by root1967test-machine # [ 67.485818] su[1838]: pam_unix(su:session): session opened for user test(uid=1000) by (uid=0)1968test-machine # [ 67.552971] noctalia-shell[1159]: WARN qml: [20260803-162825] PanelService Panel not found: setupWizardPanel-1969test-machine # [ 68.004400] microvm@hermes-test[853]: [ OK ] Finished Virtual Console Setup.1970test-machine # [ 68.055490] noctalia-shell[1159]: WARN qml: [20260803-162826] PanelService Panel not found: setupWizardPanel-1971test-machine # [ 68.557388] noctalia-shell[1159]: WARN qml: [20260803-162826] PanelService Panel not found: setupWizardPanel-1972test-machine # [ 68.865118] microvm@hermes-test[853]: [ OK ] Started Network Management.1973test-machine # [ 68.869912] microvm@hermes-test[853]: [ OK ] Reached target Network.1974test-machine # [ 68.875056] microvm@hermes-test[853]: [ OK ] Reached target Network is Online.1975test-machine # [ 68.887745] microvm@hermes-test[853]: Starting Enable Persistent Storage in systemd-networkd...1976test-machine # [ 68.992281] su[1838]: pam_unix(su:session): session closed for user test1977test-machine: (finished: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/f5cv7639fpbkhqljh8a1bph8khiskhqi-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token auth', in 1.60 seconds)1978(finished: subtest: WS auth: runtime token accepted, wrong token rejected, in 1.60 seconds)1979subtest: bash tool runs sandboxed: HOME hidden inside the unit1980test-machine: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/f5cv7639fpbkhqljh8a1bph8khiskhqi-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token sandbox'1981test-machine # [ 69.047521] su[1847]: Successful su for test by root1982test-machine # [ 69.059121] noctalia-shell[1159]: WARN qml: [20260803-162827] PanelService Panel not found: setupWizardPanel-1983test-machine # [ 69.062754] su[1847]: pam_unix(su:session): session opened for user test(uid=1000) by (uid=0)1984test-machine # [ 69.205511] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1985test-machine # [ 69.209133] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1986test-machine # [ 69.215699] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1987test-machine # [ 69.218713] microvm@hermes-test[853]: [ OK ] Finished Enable Persistent Storage in systemd-networkd.1988test-machine # [ 69.221724] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1989test-machine # [ 69.231400] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1990test-machine # [ 69.235590] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1991test-machine # [ 69.252421] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1992test-machine # [ 69.255759] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1993test-machine # [ 69.263457] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1994test-machine # [ 69.266204] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1995test-machine # [ 69.278449] systemd-resolved[374]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.1996test-machine # [ 69.281912] systemd-resolved[374]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.1997test-machine # [ 69.298396] microvm@hermes-test[853]: [ OK ] Finished Create SUID/SGID Wrappers.1998test-machine # [ 69.306269] microvm@hermes-test[853]: [ OK ] Reached target System Initialization.1999test-machine # [ 69.314290] microvm@hermes-test[853]: [ OK ] Started Discard unused filesystem blocks once a week.2000test-machine # [ 69.319354] microvm@hermes-test[853]: [ OK ] Started logrotate.timer.2001test-machine # [ 69.327239] microvm@hermes-test[853]: [ OK ] Started Daily Cleanup of Temporary Directories.2002test-machine # [ 69.335517] microvm@hermes-test[853]: [ OK ] Reached target Timer Units.2003test-machine # [ 69.342437] microvm@hermes-test[853]: [ OK ] Listening on D-Bus System Message Bus Socket.2004test-machine # [ 69.346166] microvm@hermes-test[853]: [ OK ] Listening on Nix Daemon Socket.2005test-machine # [ 69.361200] microvm@hermes-test[853]: [ OK ] Listening on OpenSSH Server Socket…temd-ssh-generator, AF_UNIX Local).2006test-machine # [ 69.378264] microvm@hermes-test[853]: [ OK ] Listening on SSH Socket.2007test-machine # [ 69.385371] microvm@hermes-test[853]: [ OK ] Listening on Virtual Machine and C…tainer Registration Service Socket.2008test-machine # [ 69.388546] microvm@hermes-test[853]: [ OK ] Reached target Socket Units.2009test-machine # [ 69.391872] microvm@hermes-test[853]: [ OK ] Reached target Basic System.2010test-machine # [ 69.402364] microvm@hermes-test[853]: Starting Hermes python venv (pip-writable)...2011test-machine # [ 69.450486] microvm@hermes-test[853]: Starting Import lastlog data into lastlog2 database...2012test-machine # [ 69.488243] microvm@hermes-test[853]: Starting Logrotate configuration check...2013test-machine # [ 69.530322] microvm@hermes-test[853]: Starting Name Service Cache Daemon (nsncd)...2014test-machine # [ 69.560850] noctalia-shell[1159]: WARN qml: [20260803-162827] PanelService Panel not found: setupWizardPanel-2015test-machine # [ 69.659792] microvm@hermes-test[853]: Starting Post-Boot Actions...2016test-machine # [ 69.768305] microvm@hermes-test[853]: [ OK ] Started Reset console on configuration changes.2017test-machine # [ 70.047911] microvm@hermes-test[853]: Starting D-Bus System Message Bus...2018test-machine # [ 70.064595] noctalia-shell[1159]: WARN qml: [20260803-162828] PanelService Panel not found: setupWizardPanel-2019test-machine # [ 70.316070] microvm@hermes-test[853]: [ OK ] Finished Logrotate configuration check.2020test-machine # [ 70.346799] systemd[651]: Starting [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/ab87be32-5ed4-4e3c-a842-2aa0fa046913/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/ab87be32-5ed4-4e3c-a842-2aa0fa046913 --session-id ab87be32-5ed4-4e3c-a842-2aa0fa046913 --provider local --model qwen2.5:0.5b --name probe...2021test-machine # [ 70.383157] microvm@hermes-test[853]: [ OK ] Finished Post-Boot Actions.2022test-machine # [ 70.402760] pi-sessiond[1856]: landlock-exec: skipped absent path /etc/static/ssl2023test-machine # [ 70.406360] pi-sessiond[1856]: landlock-exec: domain fully enforced (kernel Landlock ABI 7)2024test-machine # [ 70.409290] systemd[651]: Started [systemd-run] /nix/store/n307icgrlq89wyjlb1jmaiw59v5p9610-landlock-exec-0.1.0/bin/landlock-exec --json /home/test/.local/state/pi-sessiond/sessions/ab87be32-5ed4-4e3c-a842-2aa0fa046913/landlock.json -- /nix/store/3sn25i7gi6wvm8jvgbg789dq85njn7w3-pi-0.78.0/bin/pi --mode rpc --session-dir /home/test/.local/state/pi-sessiond/sessions/ab87be32-5ed4-4e3c-a842-2aa0fa046913 --session-id ab87be32-5ed4-4e3c-a842-2aa0fa046913 --provider local --model qwen2.5:0.5b --name probe.2025test-machine # [ 70.419553] microvm@hermes-test[853]: [ OK ] Started Name Service Cache Daemon (nsncd).2026test-machine # [ 70.435110] microvm@hermes-test[853]: [ OK ] Reached target Host and Network Name Lookups.2027test-machine # [ 70.445838] microvm@hermes-test[853]: [ OK ] Reached target User and Group Name Lookups.2028test-machine # [ 70.469909] microvm@hermes-test[853]: Starting User Login Management...2029test-machine # [ 70.537503] microvm@hermes-test[853]: Starting Permit User Sessions...2030test-machine # [ 70.565586] noctalia-shell[1159]: WARN qml: [20260803-162828] PanelService Panel not found: setupWizardPanel-2031test-machine # [ 70.629064] microvm@hermes-test[853]: [ OK ] Finished Import lastlog data into lastlog2 database.2032test-machine # [ 70.696285] microvm@hermes-test[853]: [ OK ] Finished Permit User Sessions.2033test-machine # [ 70.719521] microvm@hermes-test[853]: [ OK ] Started Getty on tty1.2034test-machine # [ 70.779554] microvm@hermes-test[853]: [ OK ] Started Serial Getty on ttyS0.2035test-machine # [ 70.786736] microvm@hermes-test[853]: [ OK ] Reached target Login Prompts.2036test-machine # [ 70.800570] microvm@hermes-test[853]: [ OK ] Started User Login Management.2037test-machine # [ 70.816080] microvm@hermes-test[853]: Starting linger-users.service...2038test-machine # [ 70.856730] microvm@hermes-test[853]: [ OK ] Started D-Bus System Message Bus.2039test-machine # [ 71.070616] noctalia-shell[1159]: WARN qml: [20260803-162829] PanelService Panel not found: setupWizardPanel-2040test-machine # [ 71.370605] microvm@hermes-test[853]: [ OK ] Finished linger-users.service.2041test-machine # [ 71.577691] noctalia-shell[1159]: WARN qml: [20260803-162829] PanelService Panel not found: setupWizardPanel-2042test-machine # [ 72.072737] noctalia-shell[1159]: WARN qml: [20260803-162830] PanelService Panel not found: setupWizardPanel-2043test-machine # [ 72.578048] noctalia-shell[1159]: WARN qml: [20260803-162830] PanelService Panel not found: setupWizardPanel-2044test-machine # [ 73.079197] noctalia-shell[1159]: WARN qml: [20260803-162831] PanelService Panel not found: setupWizardPanel-2045test-machine # [ 73.580956] noctalia-shell[1159]: WARN qml: [20260803-162831] PanelService Panel not found: setupWizardPanel-2046test-machine # [ 74.084367] noctalia-shell[1159]: WARN qml: [20260803-162832] PanelService Panel not found: setupWizardPanel-2047test-machine # [ 74.497488] su[1847]: pam_unix(su:session): session closed for user test2048test-machine: (finished: must succeed: su - test -c 'XDG_RUNTIME_DIR=/run/user/1000 /nix/store/f5cv7639fpbkhqljh8a1bph8khiskhqi-python3-3.14.6-env/bin/python3 /nix/store/040b09w06zqradyi4c8bd9vzgpls4kgz-test-machine-ws-probe.py 8768 /run/user/1000/pi-sessiond/token sandbox', in 5.50 seconds)2049(finished: subtest: bash tool runs sandboxed: HOME hidden inside the unit, in 5.51 seconds)2050subtest: panel config defaults to the loopback executor2051test-machine: must succeed: /nix/store/4b6f9wb1qak318gk7mxlfkhril6lxpcx-jq-1.8.2-bin/bin/jq -e '.localExecutor.id == "host" and .defaultExecutor == "host"' /etc/spaces/pi-chat.json2052test-machine: (finished: must succeed: /nix/store/4b6f9wb1qak318gk7mxlfkhril6lxpcx-jq-1.8.2-bin/bin/jq -e '.localExecutor.id == "host" and .defaultExecutor == "host"' /etc/spaces/pi-chat.json, in 0.03 seconds)2053(finished: subtest: panel config defaults to the loopback executor, in 0.03 seconds)2054subtest: spaces-notify-forward unit comes up2055test-machine: waiting for success: systemctl --user --machine=test@.host is-active spaces-notify-forward.service2056test-machine # [ 74.581709] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.2057test-machine # [ 74.585408] noctalia-shell[1159]: WARN qml: [20260803-162832] PanelService Panel not found: setupWizardPanel-2058test-machine # [ 74.627970] (systemd-stdio-bridge)[1917]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)2059test-machine # [ 74.753742] systemd[1]: Started Session 14 of User test.2060test-machine # [ 74.799360] (systemd-stdio-bridge)[1917]: gkr-pam: couldn't unlock the login keyring.2061test-machine # [ 74.970951] systemd[1]: run-p1916-i1917.service: Deactivated successfully.2062test-machine # [ 74.985994] (sd-pam)[1920]: pam_unix(login:session): session closed for user test2063test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active spaces-notify-forward.service, in 0.46 seconds)2064test-machine: waiting for success: systemctl --user --machine=test@.host is-active mako.service2065test-machine # [ 75.062612] systemd[1]: session-14.scope: Deactivated successfully.2066test-machine # [ 75.088667] noctalia-shell[1159]: WARN qml: [20260803-162833] PanelService Panel not found: setupWizardPanel-2067test-machine # [ 75.200203] systemd[1]: Started [systemd-run] systemd-stdio-bridge --user --quiet.2068test-machine # [ 75.307619] (systemd-stdio-bridge)[1930]: pam_unix(login:session): session opened for user test(uid=1000) by (uid=0)2069test-machine # [ 75.363667] systemd[1]: Started Session 15 of User test.2070test-machine # [ 75.380053] (systemd-stdio-bridge)[1930]: gkr-pam: couldn't unlock the login keyring.2071test-machine # [ 75.406970] (sd-pam)[1933]: pam_unix(login:session): session closed for user test2072test-machine # [ 75.408146] systemd[1]: run-p1929-i1930.service: Deactivated successfully.2073test-machine: (finished: waiting for success: systemctl --user --machine=test@.host is-active mako.service, in 0.41 seconds)2074(finished: subtest: spaces-notify-forward unit comes up, in 0.87 seconds)2075(finished: run the VM test script, in 77.44 seconds)2076test-machine # [ 75.421486] systemd[1]: session-15.scope: Deactivated successfully.2077test script finished in 77.50s2078cleanup2079kill QemuMachine (pid 45)2080test-machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/gxzhl7aaiid7zp3y47jqqiq7zg5mqpwp-python3-3.14.6/bin/python3.14)2081(finished: cleanup, in 0.22 seconds)