Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.01 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: machine, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh machine: starting vm machine: QEMU running (pid 45) machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.SQmrTR9kp6', fmt=raw size=25769803776 machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/6291456 done machine # Creating filesystem with 6291456 4k blocks and 1572864 inodes machine # Filesystem UUID: 4181f5a0-7189-4c17-a7a4-fef738d21151 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376, 294912, 819200, 884736, 1605632, 2654208, machine # 4096000 machine # machine # Allocating group tables: 0/192 done machine # Writing inode tables: 0/192 done machine # Creating journal (32768 blocks): done machine # Writing superblocks and filesystem accounting information: 0/192 done machine # machine # Virtualisation disk image created. machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+BEFD1920+BEF31920 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:03.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM BEFD1920 BEF31920 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:09.0)... machine # machine # machine # Booting from ROM... machine # Probing EDD (edd=off to disable)... ok machine # [ 0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Jul 24 14:17:26 UTC 2026 machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/xy310nsky7qs244nfvb3fnng14vg3rjx-nixos-system-machine-test/init regInfo=/nix/store/z4cn28zpywjppvs02wd5cl17x248crq6-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdafff] usable machine # [ 0.000000] BIOS-e820: [mem 0x00000000bffdb000-0x00000000bfffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000000] APIC: Static calls initialized machine # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM machine # [ 0.000000] last_pfn = 0xbffdb max_arch_pfn = 0x400000000 machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.000001] kvm-clock: using sched offset of 760200686 cycles machine # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000007] tsc: Detected 4192.076 MHz processor machine # [ 0.000927] last_pfn = 0x240000 max_arch_pfn = 0x400000000 machine # [ 0.000969] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000973] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.001030] last_pfn = 0xbffdb max_arch_pfn = 0x400000000 machine # [ 0.003131] found SMP MP-table at [mem 0x000f5470-0x000f547f] machine # [ 0.003152] Using GB pages for direct mapping machine # [ 0.003296] RAMDISK: [mem 0xbe3b0000-0xbffcffff] machine # [ 0.003305] ACPI: Early table checksum verification disabled machine # [ 0.003310] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.003315] ACPI: RSDT 0x00000000BFFE24E3 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003321] ACPI: FACP 0x00000000BFFE237F 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003330] ACPI: DSDT 0x00000000BFFE0040 00233F (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003333] ACPI: FACS 0x00000000BFFE0000 000040 machine # [ 0.003336] ACPI: APIC 0x00000000BFFE23F3 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003338] ACPI: HPET 0x00000000BFFE2483 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003340] ACPI: WAET 0x00000000BFFE24BB 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.003342] ACPI: Reserving FACP table memory at [mem 0xbffe237f-0xbffe23f2] machine # [ 0.003343] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe237e] machine # [ 0.003344] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] machine # [ 0.003345] ACPI: Reserving APIC table memory at [mem 0xbffe23f3-0xbffe2482] machine # [ 0.003345] ACPI: Reserving HPET table memory at [mem 0xbffe2483-0xbffe24ba] machine # [ 0.003346] ACPI: Reserving WAET table memory at [mem 0xbffe24bb-0xbffe24e2] machine # [ 0.003790] No NUMA configuration found machine # [ 0.003791] Faking a node at [mem 0x0000000000000000-0x000000023fffffff] machine # [ 0.003796] NODE_DATA(0) allocated [mem 0x23fffa780-0x23ffffcff] machine # [ 0.003910] Zone ranges: machine # [ 0.003911] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.003913] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] machine # [ 0.003914] Normal [mem 0x0000000100000000-0x000000023fffffff] machine # [ 0.003915] Device empty machine # [ 0.003916] Movable zone start for each node machine # [ 0.003917] Early memory node ranges machine # [ 0.003917] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.003918] node 0: [mem 0x0000000000100000-0x00000000bffdafff] machine # [ 0.003920] node 0: [mem 0x0000000100000000-0x000000023fffffff] machine # [ 0.003921] Initmem setup node 0 [mem 0x0000000000001000-0x000000023fffffff] machine # [ 0.003944] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.004258] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.164059] On node 0, zone Normal: 37 pages in unavailable ranges machine # [ 0.164951] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.164972] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.165012] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.165016] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.165018] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.165020] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.165021] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.165022] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.165025] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.165027] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.165033] TSC deadline timer available machine # [ 0.165038] CPU topo: Max. logical packages: 1 machine # [ 0.165039] CPU topo: Max. logical dies: 1 machine # [ 0.165039] CPU topo: Max. dies per package: 1 machine # [ 0.165044] CPU topo: Max. threads per core: 1 machine # [ 0.165045] CPU topo: Num. cores per package: 4 machine # [ 0.165045] CPU topo: Num. threads per package: 4 machine # [ 0.165046] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs machine # [ 0.165072] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.165090] kvm-guest: KVM setup pv remote TLB flush machine # [ 0.165099] kvm-guest: setup PV sched yield machine # [ 0.165121] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.165124] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.165125] PM: hibernation: Registered nosave memory: [mem 0xbffdb000-0xffffffff] machine # [ 0.165128] [mem 0xc0000000-0xfeffbfff] available for PCI devices machine # [ 0.165130] Booting paravirtualized kernel on KVM machine # [ 0.165135] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.169336] setup_percpu: NR_CPUS:384 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 machine # [ 0.171847] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u524288 machine # [ 0.171908] kvm-guest: PV spinlocks enabled machine # [ 0.171910] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) machine # [ 0.171913] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/xy310nsky7qs244nfvb3fnng14vg3rjx-nixos-system-machine-test/init regInfo=/nix/store/z4cn28zpywjppvs02wd5cl17x248crq6-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.172023] Unknown kernel command line parameters "regInfo=/nix/store/z4cn28zpywjppvs02wd5cl17x248crq6-closure-info/registration", will be passed to user space. machine # [ 0.172225] random: crng init done machine # [ 0.172227] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.181994] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) machine # [ 0.186653] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) machine # [ 0.186742] software IO TLB: area num 4. machine # [ 0.267831] Fallback order for Node 0: 0 machine # [ 0.267843] Built 1 zonelists, mobility grouping on. Total pages: 2097017 machine # [ 0.267845] Policy zone: Normal machine # [ 0.270638] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.299326] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 machine # [ 0.339694] allocated 16777216 bytes of page_ext machine # [ 0.360177] ftrace: allocating 48619 entries in 192 pages machine # [ 0.360182] ftrace: allocated 192 pages with 2 groups machine # [ 0.364268] Dynamic Preempt: lazy machine # [ 0.364475] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.364476] rcu: RCU event tracing is enabled. machine # [ 0.364477] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4. machine # [ 0.364478] Trampoline variant of Tasks RCU enabled. machine # [ 0.364479] Rude variant of Tasks RCU enabled. machine # [ 0.364479] Tracing variant of Tasks RCU enabled. machine # [ 0.364480] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.364481] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 machine # [ 0.364511] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.364513] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.364515] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.372952] NR_IRQS: 24832, nr_irqs: 456, preallocated irqs: 16 machine # [ 0.376340] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.376350] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.376554] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.389405] Console: colour VGA+ 80x25 machine # [ 0.389410] printk: legacy console [tty0] enabled machine # [ 0.475059] printk: legacy console [ttyS0] enabled machine # [ 0.817180] ACPI: Core revision 20250807 machine # [ 0.818534] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.820925] APIC: Switch to symmetric I/O mode setup machine # [ 0.822434] x2apic enabled machine # [ 0.823630] APIC: Switched APIC routing to: physical x2apic machine # [ 0.825071] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() machine # [ 0.827001] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() machine # [ 0.829302] kvm-guest: setup PV IPIs machine # [ 0.831441] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.833059] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns machine # [ 0.835710] Calibrating delay loop (skipped) preset value.. 8384.15 BogoMIPS (lpj=4192076) machine # [ 0.836706] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.837706] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.838706] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.838715] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.839710] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.840706] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.840710] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.841706] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.842706] Spectre V2 : Enabling IBPB for BPF machine # [ 0.842711] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.843706] active return thunk: srso_alias_return_thunk machine # [ 0.843740] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.844706] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.844709] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.845706] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.846706] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.846706] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.846706] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.846709] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' machine # [ 0.847706] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)' machine # [ 0.848706] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.848709] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.849706] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.849706] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.849706] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.849706] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16 machine # [ 0.849706] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24 machine # [ 0.849706] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format. machine # [ 0.860706] Freeing SMP alternatives memory: 44K machine # [ 0.860706] pid_max: default: 32768 minimum: 301 machine # [ 0.860824] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.861706] landlock: Up and running. machine # [ 0.861706] Yama: becoming mindful. machine # [ 0.861706] LSM support for eBPF active machine # [ 0.861706] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.862706] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.862706] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2) machine # [ 0.863301] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.863714] ... version: 2 machine # [ 0.864711] ... bit width: 48 machine # [ 0.865766] ... generic counters: 6 machine # [ 0.866711] ... generic bitmap: 000000000000003f machine # [ 0.867711] ... fixed-purpose counters: 0 machine # [ 0.868711] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.869710] ... value mask: 0000ffffffffffff machine # [ 0.870711] ... max period: 00007fffffffffff machine # [ 0.871711] ... global_ctrl mask: 000000000000003f machine # [ 0.872911] signal: max sigframe size: 3376 machine # [ 0.873783] rcu: Hierarchical SRCU implementation. machine # [ 0.874715] rcu: Max phase no-delay instances is 400. machine # [ 0.875936] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level machine # [ 0.880881] smp: Bringing up secondary CPUs ... machine # [ 0.882214] smpboot: x86: Booting SMP configuration: machine # [ 0.882722] .... node #0, CPUs: #1 #2 #3 machine # [ 0.885163] smp: Brought up 1 node, 4 CPUs machine # [ 0.886716] smpboot: Total of 4 processors activated (33536.60 BogoMIPS) machine # [ 0.889873] Memory: 8074192K/8388068K available (17162K kernel code, 2721K rwdata, 13548K rodata, 3640K init, 3012K bss, 290260K reserved, 0K cma-reserved) machine # [ 0.895712] devtmpfs: initialized machine # [ 0.896874] x86/mm: Memory block size: 128MB machine # [ 0.906773] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.907801] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). machine # [ 0.908835] pinctrl core: initialized pinctrl subsystem machine # [ 0.910246] PM: RTC time: 04:20:53, date: 2026-08-01 machine # [ 0.914742] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.917184] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.917776] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.919891] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.920724] audit: initializing netlink subsys (disabled) machine # [ 0.922057] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.922060] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.923711] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.923714] audit: type=2000 audit(1785558053.445:1): state=initialized audit_enabled=0 res=1 machine # [ 0.924710] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.924712] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.926712] cpuidle: using governor menu machine # [ 0.939718] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.941120] PCI: Using configuration type 1 for base access machine # [ 0.941711] PCI: Using configuration type 1 for extended access machine # [ 0.943785] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.950807] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.951706] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.952706] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.952711] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.994706] ACPI: Added _OSI(Module Device) machine # [ 0.994712] ACPI: Added _OSI(Processor Device) machine # [ 0.995706] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.997138] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 1.013005] ACPI: Interpreter enabled machine # [ 1.014489] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 1.015574] ACPI: Using IOAPIC for interrupt routing machine # [ 1.015706] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 1.016711] PCI: Using E820 reservations for host bridge windows machine # [ 1.017937] ACPI: Enabled 2 GPEs in block 00 to 0F machine # [ 1.021706] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 1.022718] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 1.024226] acpiphp: Slot [3] registered machine # [ 1.024745] acpiphp: Slot [4] registered machine # [ 1.025747] acpiphp: Slot [5] registered machine # [ 1.026748] acpiphp: Slot [6] registered machine # [ 1.027706] acpiphp: Slot [7] registered machine # [ 1.027706] acpiphp: Slot [8] registered machine # [ 1.028747] acpiphp: Slot [9] registered machine # [ 1.029760] acpiphp: Slot [10] registered machine # [ 1.030754] acpiphp: Slot [11] registered machine # [ 1.031706] acpiphp: Slot [12] registered machine # [ 1.031706] acpiphp: Slot [13] registered machine # [ 1.031757] acpiphp: Slot [14] registered machine # [ 1.032706] acpiphp: Slot [15] registered machine # [ 1.032752] acpiphp: Slot [16] registered machine # [ 1.033706] acpiphp: Slot [17] registered machine # [ 1.034760] acpiphp: Slot [18] registered machine # [ 1.035706] acpiphp: Slot [19] registered machine # [ 1.035706] acpiphp: Slot [20] registered machine # [ 1.035746] acpiphp: Slot [21] registered machine # [ 1.036706] acpiphp: Slot [22] registered machine # [ 1.036750] acpiphp: Slot [23] registered machine # [ 1.037706] acpiphp: Slot [24] registered machine # [ 1.038754] acpiphp: Slot [25] registered machine # [ 1.039742] acpiphp: Slot [26] registered machine # [ 1.040743] acpiphp: Slot [27] registered machine # [ 1.041743] acpiphp: Slot [28] registered machine # [ 1.042772] acpiphp: Slot [29] registered machine # [ 1.043747] acpiphp: Slot [30] registered machine # [ 1.044746] acpiphp: Slot [31] registered machine # [ 1.045737] PCI host bridge to bus 0000:00 machine # [ 1.047733] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 1.048712] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 1.050712] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 1.052713] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 1.054713] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] machine # [ 1.056714] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 1.059310] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint machine # [ 1.061702] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint machine # [ 1.062655] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint machine # [ 1.063706] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef] machine # [ 1.064706] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk machine # [ 1.065706] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk machine # [ 1.065706] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk machine # [ 1.065706] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk machine # [ 1.067015] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 1.067706] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f] machine # [ 1.068706] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint machine # [ 1.070285] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI machine # [ 1.070725] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB machine # [ 1.071706] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 1.076706] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 1.076706] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 1.077706] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 1.077706] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 1.083706] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 1.084731] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 1.085706] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 1.085760] pci 0000:00:03.0: BAR 4 [mem 0xe0000000000-0xe0000003fff 64bit pref] machine # [ 1.086706] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 1.087540] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 1.089724] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f] machine # [ 1.090706] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 1.090772] pci 0000:00:04.0: BAR 4 [mem 0xe0000004000-0xe0000007fff 64bit pref] machine # [ 1.092748] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 1.093725] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 1.094706] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 1.095760] pci 0000:00:05.0: BAR 4 [mem 0xe0000008000-0xe000000bfff 64bit pref] machine # [ 1.096706] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 1.098724] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f] machine # [ 1.099706] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 1.099761] pci 0000:00:06.0: BAR 4 [mem 0xe000000c000-0xe000000ffff 64bit pref] machine # [ 1.100706] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 1.102706] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f] machine # [ 1.102706] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 1.102706] pci 0000:00:07.0: BAR 4 [mem 0xe0000010000-0xe0000013fff 64bit pref] machine # [ 1.104706] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 1.105706] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f] machine # [ 1.106725] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 1.107706] pci 0000:00:08.0: BAR 4 [mem 0xe0000014000-0xe0000017fff 64bit pref] machine # [ 1.108706] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 1.110706] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf] machine # [ 1.110706] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 1.110706] pci 0000:00:09.0: BAR 4 [mem 0xe0000018000-0xe000001bfff 64bit pref] machine # [ 1.111706] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 1.112706] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 1.114706] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 1.114760] pci 0000:00:0a.0: BAR 4 [mem 0xe000001c000-0xe000001ffff 64bit pref] machine # [ 1.116706] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 1.117706] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff] machine # [ 1.117706] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 1.118706] pci 0000:00:0b.0: BAR 4 [mem 0xe0000020000-0xe0000023fff 64bit pref] machine # [ 1.119706] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 1.121706] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df] machine # [ 1.122706] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 1.122706] pci 0000:00:0c.0: BAR 4 [mem 0xe0000024000-0xe0000027fff 64bit pref] machine # [ 1.125055] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 1.125706] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 1.126901] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 1.127706] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 1.127706] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 machine # [ 1.130040] iommu: Default domain type: Translated machine # [ 1.130706] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 1.132046] ACPI: bus type USB registered machine # [ 1.132706] usbcore: registered new interface driver usbfs machine # [ 1.132706] usbcore: registered new interface driver hub machine # [ 1.132724] usbcore: registered new device driver usb machine # [ 1.136397] NetLabel: Initializing machine # [ 1.136706] NetLabel: domain hash size = 128 machine # [ 1.136706] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 1.136757] NetLabel: unlabeled traffic allowed by default machine # [ 1.137706] PCI: Using ACPI for IRQ routing machine # [ 1.137890] pci 0000:00:02.0: vgaarb: setting as boot VGA device machine # [ 1.138706] pci 0000:00:02.0: vgaarb: bridge control possible machine # [ 1.138706] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 1.140715] vgaarb: loaded machine # [ 1.141706] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 1.141706] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 1.145767] clocksource: Switched to clocksource kvm-clock machine # [ 1.148408] VFS: Disk quotas dquot_6.6.0 machine # [ 1.155834] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 1.161171] pnp: PnP ACPI init machine # [ 1.163974] pnp: PnP ACPI: found 6 devices machine # [ 1.193737] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 1.210774] clocksource: Switched to clocksource acpi_pm machine # [ 1.212610] NET: Registered PF_INET protocol family machine # [ 1.218067] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 1.253227] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) machine # [ 1.255497] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 1.257502] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 1.267132] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) machine # [ 1.269173] TCP: Hash tables configured (established 65536 bind 65536) machine # [ 1.271037] MPTCP token hash table entries: 8192 (order: 6, 196608 bytes, linear) machine # [ 1.273180] UDP hash table entries: 4096 (order: 6, 262144 bytes, linear) machine # [ 1.277495] UDP-Lite hash table entries: 4096 (order: 6, 262144 bytes, linear) machine # [ 1.281079] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 1.282689] NET: Registered PF_XDP protocol family machine # [ 1.284014] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 1.285570] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 1.290138] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 1.291891] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] machine # [ 1.296653] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] machine # [ 1.301749] pci 0000:00:01.0: PIIX3: Enabling Passive Release machine # [ 1.303286] pci 0000:00:00.0: Limiting direct PCI/PCI transfers machine # [ 1.309792] ACPI: \_SB_.LNKD: Enabled at IRQ 11 machine # [ 1.316319] PCI: CLS 0 bytes, default 64 machine # [ 1.320582] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) machine # [ 1.320935] Trying to unpack rootfs image as initramfs... machine # [ 1.322235] software IO TLB: mapped [mem 0x00000000ba3b0000-0x00000000be3b0000] (64MB) machine # [ 1.322427] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns machine # [ 1.369610] Initialise system trusted keyrings machine # [ 1.374878] workingset: timestamp_bits=40 max_order=21 bucket_order=0 machine # [ 1.391953] Key type asymmetric registered machine # [ 1.405133] Asymmetric key parser 'x509' registered machine # [ 1.406554] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 1.420814] io scheduler mq-deadline registered machine # [ 1.422051] io scheduler kyber registered machine # [ 1.444774] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 1.446814] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 1.456737] Linux agpgart interface v0.103 machine # [ 1.457955] ACPI: bus type drm_connector registered machine # [ 1.468090] usbcore: registered new interface driver usbserial_generic machine # [ 1.469826] usbserial: USB Serial support registered for generic machine # [ 1.471431] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 1.471431] Please enable it if your BIOS has the CPPC option. machine # [ 1.474883] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 1.477042] drop_monitor: Initializing network drop monitor service machine # [ 1.478879] NET: Registered PF_INET6 protocol family machine # [ 1.493103] Segment Routing with IPv6 machine # [ 1.494177] In-situ OAM (IOAM) with IPv6 machine # [ 1.507944] IPI shorthand broadcast: enabled machine # [ 1.514295] sched_clock: Marking stable (1058021112, 455356643)->(1941801980, -428424225) machine # [ 1.528854] registered taskstats version 1 machine # [ 1.530324] Loading compiled-in X.509 certificates machine # [ 1.554738] Demotion targets for Node 0: null machine # [ 1.559845] Key type .fscrypt registered machine # [ 1.560930] Key type fscrypt-provisioning registered machine # [ 1.562398] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.563809] ima: Allocated hash algorithm: sha1 machine # [ 1.565053] ima: No architecture policies found machine # [ 1.575733] PM: Magic number: 6:778:311 machine # [ 1.577972] RAS: Correctable Errors collector initialized. machine # [ 1.586880] clk: Disabling unused clocks machine # [ 1.587962] PM: genpd: Disabling unused power domains machine # [ 1.721614] Freeing initrd memory: 28800K machine # [ 1.727201] Freeing unused decrypted memory: 2028K machine # [ 1.731588] Freeing unused kernel image (initmem) memory: 3640K machine # [ 1.733330] Write protecting the kernel read-only data: 32768k machine # [ 1.736930] Freeing unused kernel image (text/rodata gap) memory: 1268K machine # [ 1.739623] Freeing unused kernel image (rodata/data gap) memory: 788K machine # [ 1.787271] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 1.789162] Run /init as init process machine # [ 1.806187] systemd[1]: Inserted module 'autofs4' machine # [ 1.860242] fuse: init (API version 7.45) machine # [ 1.898123] ACPI: \_SB_.LNKC: Enabled at IRQ 10 machine # [ 1.908405] ACPI: \_SB_.LNKA: Enabled at IRQ 10 machine # [ 1.912800] ACPI: \_SB_.LNKB: Enabled at IRQ 11 machine # [ 1.983559] systemd[1]: Successfully made /usr/ read-only. machine # [ 2.347760] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 2.355329] systemd[1]: Detected virtualization kvm. machine # [ 2.356866] systemd[1]: Detected architecture x86-64. machine # [ 2.358219] systemd[1]: Running in initrd. machine # [ 2.359906] systemd[1]: Initializing machine ID from random generator. machine # [ 2.362080] systemd[1]: Hostname set to . machine # [ 2.525048] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 2.606263] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 2.655039] systemd[1]: Created slice Slice /system/modprobe. machine # [ 2.659056] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 2.663832] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 2.665562] systemd[1]: Reached target Path Units. machine # [ 2.666994] systemd[1]: Reached target Slice Units. machine # [ 2.668427] systemd[1]: Reached target Swaps. machine # [ 2.669786] systemd[1]: Reached target Timer Units. machine # [ 2.671347] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 2.673303] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 2.675106] systemd[1]: Listening on Journal Sockets. machine # [ 2.679741] systemd[1]: Listening on udev Control Socket. machine # [ 2.681324] systemd[1]: Listening on udev Kernel Socket. machine # [ 2.685859] systemd[1]: Reached target Socket Units. machine # [ 2.694821] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 2.700821] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 2.709834] systemd[1]: Starting Load Kernel Module configfs... machine # [ 2.736844] systemd[1]: Starting Journal Service... machine # [ 2.797006] systemd[1]: Starting Load Kernel Modules... machine # [ 2.798677] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 2.805829] systemd[1]: Starting Coldplug All udev Devices... machine # [ 2.812088] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 2.814636] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 2.820034] systemd[1]: Finished Load Kernel Module configfs. machine # [ 2.838794] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 2.846849] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 2.852261] systemd-journald[92]: Collecting audit messages is disabled. machine # [ 2.917027] netfs: FS-Cache loaded machine # [ 2.926342] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 2.932143] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 2.955875] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 2.956418] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 2.997675] systemd[1]: Finished Load Kernel Modules. machine # [ 2.998912] 9pnet: Installing 9P2000 support machine # [ 2.551829] systemd-modules-load[94]: Using 4 probe threads machine # [ 3.006992] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 2.554879] systemd-modules-load[94]: Inserted module 'virtio_balloon' machine # [ 3.011092] systemd[1]: Started Journal Service. machine # [ 2.560786] systemd-modules-load[94]: Inserted module 'virtio_gpu' machine # [ 2.567870] systemd-modules-load[94]: Inserted module 'dm_mod' machine # [ 2.569648] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 2.571666] systemd[1]: Reached target Local File Systems. machine # [ 2.575891] systemd[1]: Starting Apply Kernel Variables... machine # [ 2.577391] systemd[1]: Starting Create System Files and Directories... machine # [ 2.581725] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 2.602216] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.614639] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 2.620195] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 2.630155] systemd[1]: Finished Create System Files and Directories. machine # [ 2.653626] systemd-udevd[107]: Using default interface naming scheme 'v261'. machine # [ 2.681923] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.694874] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.699278] systemd[1]: Reached target System Initialization. machine # [ 2.703601] systemd[1]: Reached target Basic System. machine # [ 3.085788] systemd[1]: Starting Virtual Console Setup... machine # [ 3.131831] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 3.142425] systemd[1]: Finished Virtual Console Setup. machine # [ 3.613527] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 3.616254] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 3.617301] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 3.677361] virtio_blk virtio5: 4/0/0 default/read/poll queues machine # [ 3.682270] virtio_blk virtio5: [vda] 50331648 512-byte logical blocks (25.8 GB/24.0 GiB) machine # [ 3.695748] uhci_hcd 0000:00:01.2: UHCI Host Controller machine # [ 3.698594] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 machine # [ 3.700128] uhci_hcd 0000:00:01.2: detected 2 ports machine # [ 3.703246] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100 machine # [ 3.704677] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 3.707596] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 3.709615] usb usb1: Product: UHCI Host Controller machine # [ 3.710626] usb usb1: Manufacturer: Linux 6.18.40 uhci_hcd machine # [ 3.712347] usb usb1: SerialNumber: 0000:00:01.2 machine # [ 3.713562] hub 1-0:1.0: USB hub found machine # [ 3.714441] hub 1-0:1.0: 2 ports detected machine # [ 3.730438] SCSI subsystem initialized machine # [ 3.285320] (udev-worker)[135]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.319487] (udev-worker)[140]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 3.325272] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.363045] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.366233] systemd[1]: Reached target Initrd Root Device. machine # [ 3.377394] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.443979] systemd-fsck[151]: nixos: clean, 12/1572864 files, 142772/6291456 blocks machine # [ 3.457527] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.466356] systemd[1]: Mounting /sysroot... machine # [ 3.952415] usb 1-1: new full-speed USB device number 2 using uhci_hcd machine # [ 4.017645] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 4.098875] scsi host0: ata_piix machine # [ 4.102202] scsi host1: ata_piix machine # [ 4.103008] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0 machine # [ 4.104488] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0 machine # [ 4.141759] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 4.143414] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 4.145071] usb 1-1: Product: QEMU USB Tablet machine # [ 4.146137] usb 1-1: Manufacturer: QEMU machine # [ 4.147000] usb 1-1: SerialNumber: 28754-0000:00:01.2-1 machine # [ 4.184751] hid: raw HID events driver (C) Jiri Kosina machine # [ 4.266953] ata2: found unknown device (class 0) machine # [ 4.268727] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 4.275080] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 4.365947] usbcore: registered new interface driver usbhid machine # [ 4.367122] usbhid: USB HID core driver machine # [ 4.534773] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 4.603642] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 4.754191] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 machine # [ 4.758529] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 machine # [ 4.805915] EXT4-fs (vda): mounted filesystem 4181f5a0-7189-4c17-a7a4-fef738d21151 r/w with ordered data mode. Quota mode: none. machine # [ 4.360307] systemd[1]: Mounted /sysroot. machine # [ 4.366629] systemd[1]: Reached target Initrd Root File System. machine # [ 4.370674] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 4.382899] systemd-sysroot-fstab-check[169]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 4.389658] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 4.413271] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 4.420973] systemd[1]: Mounting /sysroot/run... machine # [ 4.427275] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 4.436538] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 4.893086] 9p: Installing v9fs 9p2000 file system support machine # [ 4.446060] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 4.450428] systemd[1]: Mounted /sysroot/run. machine # [ 4.462422] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.464458] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 4.465634] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 4.467073] systemd[1]: Reload requested from client PID 169 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 4.470524] systemd[1]: Reloading... machine # [ 4.608497] systemd[1]: Reloading finished in 143 ms. machine # [ 4.646450] systemd-sysroot-fstab-check[169]: Requesting initrd-fs.target/start/replace... machine # [ 4.651414] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 4.661615] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.663163] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.664480] systemd-sysroot-fstab-check[169]: Requesting swap.target/start/replace... machine # [ 4.666093] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.667475] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 4.672487] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 4.674096] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 4.679507] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.680994] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.685431] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.726251] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.727317] systemd[1]: Reached target Initrd File Systems. machine # [ 4.736776] systemd[1]: Starting Find NixOS closure... machine # [ 4.739585] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.762483] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.845457] systemd[1]: Finished Find NixOS closure. machine # [ 4.849573] systemd[1]: Reached target Initrd Default Target. machine # [ 4.853545] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.885314] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.890600] systemd[1]: Stopped target Basic System. machine # [ 4.894694] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.897544] systemd[1]: Stopped target Path Units. machine # [ 4.901550] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.908554] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.912555] systemd[1]: Stopped target Slice Units. machine # [ 4.919515] systemd[1]: Stopped target Socket Units. machine # [ 4.923590] systemd[1]: Stopped target System Initialization. machine # [ 4.930715] systemd[1]: Stopped target Swaps. machine # [ 4.933994] systemd[1]: Stopped target Timer Units. machine # [ 4.935169] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.943506] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.952456] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.956518] systemd[1]: Stopped Find NixOS closure. machine # [ 4.960579] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 4.972576] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.973966] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.977676] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.984795] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.986508] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.987786] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.989505] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.991591] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.994521] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.998583] systemd[1]: Stopped target Local File Systems. machine # [ 5.000163] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 5.002258] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 5.009789] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 5.014518] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 5.016661] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 5.018237] systemd[1]: Stopped Virtual Console Setup. machine # [ 5.020398] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 5.022033] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 5.024277] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 5.025875] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 5.027472] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 5.028986] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 5.030720] systemd[1]: systemd-udevd.service: Consumed 1.238s CPU time over 2.396s wall clock time, 26.1M memory peak. machine # [ 5.038601] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. machine # [ 5.041050] systemd[1]: Closed udev Control Socket. machine # [ 5.042626] systemd[1]: Starting Cleanup udev Database... machine # [ 5.044552] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 5.046280] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 5.047964] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 5.049742] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 5.051780] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 5.054127] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.055638] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.057298] systemd[1]: Finished Cleanup udev Database. machine # [ 5.058567] systemd[1]: Reached target Switch Root. machine # [ 5.059851] systemd[1]: Starting NixOS Activation... machine # [ 8.506590] initrd-nixos-activation-start[229]: booting system configuration /nix/store/xy310nsky7qs244nfvb3fnng14vg3rjx-nixos-system-machine-test machine # [ 9.530435] initrd-nixos-activation-start[229]: running activation script... machine # [ 14.998821] initrd-nixos-activation-start[252]: setting up /etc... machine # [ 16.403590] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 16.405633] systemd[1]: Finished NixOS Activation. machine # [ 16.411188] systemd[1]: Starting Switch Root... machine # [ 16.442258] systemd[1]: Switching root. machine # [ 17.003984] systemd-journald[92]: Received SIGTERM from PID 1 (systemd). machine # [ 19.513004] NET: Registered PF_VSOCK protocol family machine # [ 20.896276] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 20.905692] systemd[1]: Detected virtualization kvm. machine # [ 20.913909] systemd[1]: Detected architecture x86-64. machine # [ 20.915084] systemd[1]: Detected first boot. machine # [ 21.017568] systemd[1]: Initializing machine ID from random generator. machine # [ 21.739436] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 23.911862] systemd[1]: Applying preset policy. machine # [ 28.158676] systemd[1]: Populated /etc with preset unit settings. machine: Guest root shell did not produce any data yet... machine: To debug, enter the VM and run 'systemctl status backdoor.service'. machine # [ 31.148151] systemd[1]: microvm@hermes-bob.service: Service uses a combination of Type=simple, ExecStartPost=, and credentials. This could lead to race conditions. Continuing. machine # [ 31.274103] systemd[1]: microvm@hermes-alice.service: Service uses a combination of Type=simple, ExecStartPost=, and credentials. This could lead to race conditions. Continuing. machine # [ 32.931404] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 32.940872] systemd[1]: Stopped initrd-switch-root.service. machine # [ 32.952408] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 32.955914] systemd[1]: Created slice Slice /system/getty. machine # [ 32.958043] systemd[1]: Created slice Slice /system/microvm. machine # [ 32.960337] systemd[1]: Created slice Slice /system/microvm-macvtap-interfaces. machine # [ 32.963015] systemd[1]: Created slice Slice /system/microvm-pci-devices. machine # [ 32.965420] systemd[1]: Created slice Slice /system/microvm-set-booted. machine # [ 32.967688] systemd[1]: Created slice Slice /system/microvm-tap-interfaces. machine # [ 32.970197] systemd[1]: Created slice Slice /system/microvm-virtiofsd. machine # [ 32.972873] systemd[1]: Created slice User and Session Slice. machine # [ 32.974462] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 32.976265] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 32.980838] systemd[1]: Expecting device /dev/hvc0... machine # [ 32.982349] systemd[1]: Expecting device /dev/ttyS0... machine # [ 32.983505] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 32.984839] systemd[1]: Stopped target initrd-fs.target. machine # [ 32.986875] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 32.988119] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 32.989770] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 32.991195] systemd[1]: Reached target Remote File Systems. machine # [ 32.992437] systemd[1]: Reached target Slice Units. machine # [ 32.994142] systemd[1]: Reached target Swaps. machine # [ 33.077648] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 33.098629] systemd[1]: Listening on Process Core Dump Socket. machine # [ 33.115838] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 33.138632] systemd[1]: Listening on Factory Reset Management. machine # [ 33.140236] systemd[1]: Listening on Hostname Service Socket. machine # [ 33.161470] systemd[1]: Starting Journal Log Access Socket... machine # [ 33.173225] systemd[1]: Listening on Journal Audit Socket. machine # [ 33.198659] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 33.200444] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 33.218249] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.220405] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.423336] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 33.425190] systemd[1]: Listening on udev Control Socket. machine # [ 33.434034] systemd[1]: Listening on udev Varlink Socket. machine # [ 33.464525] systemd[1]: Mounting Huge Pages File System... machine # [ 33.469023] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 33.475195] systemd[1]: Mounting Kernel Debug File System... machine # [ 33.495267] systemd[1]: Mounting Kernel Trace File System... machine # [ 33.528312] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 33.529966] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 33.571466] systemd[1]: Starting Load Kernel Module configfs... machine # [ 33.573168] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 33.575767] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.577983] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 33.608378] systemd[1]: Mounting FUSE Control File System... machine # [ 33.609690] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.660326] systemd[1]: Starting Journal Service... machine # [ 33.669177] systemd[1]: Starting Load Kernel Modules... machine # [ 33.682352] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 33.689589] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 33.691139] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.718262] systemd[1]: Starting Coldplug All udev Devices... machine # [ 33.725418] systemd[1]: Listening on Journal Log Access Socket. machine # [ 33.889014] systemd-journald[322]: Collecting audit messages is enabled. machine # [ 33.893286] systemd[1]: Mounted Kernel Debug File System. machine # [ 33.894851] systemd[1]: Mounted Huge Pages File System. machine # [ 33.897974] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 33.909972] systemd[1]: Mounted Kernel Trace File System. machine # [ 33.911373] systemd[1]: Mounted FUSE Control File System. machine # [ 33.928974] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 33.485644] systemd[1]: Queued start job for default target Multi-User System. machine # [ 33.941238] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 33.488583] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 33.946245] systemd[1]: Started Journal Service. machine # [ 33.495836] systemd-modules-load[323]: Using 4 probe threads machine # [ 33.507696] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 33.515987] systemd[1]: Finished Load Kernel Module configfs. machine # [ 33.527745] systemd[1]: Mounting Kernel Configuration File System... machine # [ 33.533199] systemd-oomd[324]: No swap; memory pressure usage will be degraded machine # [ 33.999985] EXT4-fs (vda): re-mounted 4181f5a0-7189-4c17-a7a4-fef738d21151. machine # [ 33.556611] systemd-modules-load[323]: Inserted module 'tap' machine # [ 34.023572] loop: module loaded machine # [ 33.569119] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 33.575352] systemd-modules-load[323]: Inserted module 'loop' machine # [ 33.576736] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 33.578837] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 33.594458] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 33.598540] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.064206] tun: Universal TUN/TAP device driver, 1.6 machine # [ 33.623891] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 33.625375] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.627227] systemd-modules-load[323]: Inserted module 'vhost_vsock' machine # [ 33.650652] systemd-modules-load[323]: Inserted module 'vhost_net' machine # [ 33.666519] systemd[1]: Mounted Kernel Configuration File System. machine # [ 33.700206] systemd[1]: Finished Load Kernel Modules. machine # [ 33.708249] systemd[1]: Starting Firewall... machine # [ 33.718310] systemd[1]: Starting Apply Kernel Variables... machine # [ 34.176007] systemd-journald[322]: Received client request to flush runtime journal. machine # [ 33.804219] systemd[1]: Finished Coldplug All udev Devices. machine # [ 33.806354] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 33.808829] systemd[1]: Reached target First Boot Complete. machine # [ 33.816803] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 33.944434] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 33.976126] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 34.070896] systemd[1]: Finished Apply Kernel Variables. machine # [ 34.169430] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 34.171207] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 34.184768] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 34.464611] systemd-udevd[361]: Using default interface naming scheme 'v261'. machine # [ 34.532112] systemd[1]: Mounting /run/wrappers... machine # [ 34.591526] systemd[1]: Mounted /run/wrappers. machine # [ 34.593500] systemd[1]: Reached target Local File Systems. machine # [ 34.598552] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 34.603052] systemd[1]: Starting register-nix-paths.service... machine # [ 34.608543] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.610252] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.613798] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 34.616937] systemd[1]: Starting Create System Files and Directories... machine # [ 34.712681] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 34.714752] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 34.718081] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:1: Duplicate line for path "/home/alice/hermes", ignoring. machine # [ 34.720729] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:2: Duplicate line for path "/home/bob/hermes", ignoring. machine # [ 34.723850] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:3: Duplicate line for path "/var/lib/hermes-microvm/alice/guest", ignoring. machine # [ 34.727161] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:4: Duplicate line for path "/var/lib/hermes-microvm/alice/state-vault/state", ignoring. machine # [ 34.731275] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:5: Duplicate line for path "/var/lib/hermes-microvm/bob/guest", ignoring. machine # [ 34.734285] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:6: Duplicate line for path "/var/lib/hermes-microvm/bob/state-vault/state", ignoring. machine # [ 34.759181] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 34.795444] systemd[1]: Finished Create System Files and Directories. machine # [ 34.802077] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 34.806210] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 34.868516] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 34.903909] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 34.912608] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 34.930114] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 34.970476] (udev-worker)[412]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 34.974342] (udev-worker)[427]: Network interface NamePolicy= disabled on kernel command line. machine # [ 34.977591] (udev-worker)[412]: Network interface NamePolicy= disabled on kernel command line. machine # [ 35.472857] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 35.485628] ACPI: button: Power Button [PWRF] machine # [ 35.502508] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console machine # [ 35.503770] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 35.507753] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 machine # [ 35.514709] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 machine # [ 35.514779] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 35.536202] Console: switching to colour dummy device 80x25 machine # [ 35.543727] Floppy drive(s): fd0 is 2.88M AMI BIOS machine # [ 35.544145] rtc_cmos 00:05: RTC can wake from S4 machine # [ 35.545242] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 35.556430] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 35.558218] FDC 0 is a S82078B machine # [ 35.558307] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input6 machine # [ 35.562282] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic machine # [ 35.568716] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0 machine # [ 35.111715] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 35.162581] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 35.603208] rtc_cmos 00:05: registered as rtc0 machine # [ 35.603328] rtc_cmos 00:05: setting system clock to 2026-08-01T04:21:28 UTC (1785558088) machine # [ 35.168617] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 35.603451] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 35.171422] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 35.614433] parport_pc 00:03: reported by Plug and Play ACPI machine # [ 35.614586] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 35.174409] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 35.617203] Console: switching to colour frame buffer device 160x50 machine # [ 35.178824] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 35.636890] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 35.181514] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.185813] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.193660] systemd[1]: Starting Update is Completed... machine # [ 35.669411] mousedev: PS/2 mouse device common for all mice machine # [ 35.281186] systemd[1]: Starting Virtual Console Setup... machine # [ 35.742714] ppdev: user-space parallel port driver machine # [ 35.298136] systemd[1]: Finished Update is Completed. machine # [ 35.332914] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 35.336618] systemd[1]: Stopped Virtual Console Setup. machine # [ 35.356574] systemd[1]: Starting Virtual Console Setup... machine # [ 35.960808] kvm_amd: TSC scaling supported machine # [ 35.961521] kvm_amd: Nested Virtualization enabled machine # [ 35.962823] kvm_amd: Nested Paging enabled machine # [ 35.963851] kvm_amd: LBR virtualization supported machine # [ 35.965645] kvm_amd: Virtual GIF supported machine # [ 35.966243] kvm_amd: Virtual NMI enabled machine # [ 36.022133] EDAC MC: Ver: 3.0.0 machine # [ 35.689480] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 35.690927] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.810620] systemd-vconsole-setup[473]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 35.813547] systemd[1]: Finished Virtual Console Setup. machine # [ 35.892228] systemd[1]: Finished Firewall. machine # [ 35.944404] systemd[1]: Finished register-nix-paths.service. machine # [ 35.945838] systemd[1]: Reached target System Initialization. machine # [ 35.947367] systemd[1]: Started hermes-microvm-timezone.path. machine # [ 35.948740] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 35.950301] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 35.951694] systemd[1]: Reached target Path Units. machine # [ 35.952804] systemd[1]: Reached target Timer Units. machine # [ 35.953912] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 35.965879] systemd[1]: Listening on dashboard forward socket for hermes-alice. machine # [ 35.969406] systemd[1]: Listening on dashboard forward socket for hermes-bob. machine # [ 35.970921] systemd[1]: Listening on spaces bridge socket for hermes-alice. machine # [ 35.972264] systemd[1]: Listening on Nix Daemon Socket. machine # [ 35.973529] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 35.975443] systemd[1]: Reached target Socket Units. machine # [ 35.976523] systemd[1]: Reached target Basic System. machine # [ 35.978058] systemd[1]: Started backdoor.service. machine # [ 35.979665] systemd[1]: Started Enable Kernel Same-Page Merging. machine # [ 35.981655] systemd[1]: Starting Owner-match loopback firewall for hermes microvms... machine # [ 35.984571] systemd[1]: Starting Install MicroVM 'hermes-alice'... machine # [ 35.986427] systemd[1]: Starting Install MicroVM 'hermes-bob'... machine # [ 35.988217] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 35.991272] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 35.995980] systemd[1]: Starting Post-Boot Actions... machine # [ 36.000855] systemd[1]: Started Reset console on configuration changes. machine # [ 36.006903] systemd[1]: Starting resolvconf update... machine # [ 36.008881] systemd[1]: enable-ksm.service: Deactivated successfully. machine # [ 36.023485] systemd[1]: Starting D-Bus System Message Bus... machine # [ 36.029724] systemd[1]: Finished Post-Boot Actions. machine # connecting to host... machine # [ 36.034844] systemd[1]: install-microvm-hermes-alice.service: Deactivated successfully. machine # [ 36.039854] systemd[1]: Finished Install MicroVM 'hermes-alice'. machine # [ 36.042296] nsncd[588]: Aug 01 04:21:29.394 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 36.043237] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 36.044188] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 36.045056] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 36.046146] systemd[1]: Reached target User and Group Name Lookups. machine # [ 36.046723] systemd[1]: Setup MicroVM 'hermes-alice' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-alice/current/bin/pci-setup machine # [ 36.056598] systemd[1]: Starting Save MicroVM 'hermes-alice' booted configuration... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 37.68 seconds) machine: (finished: waiting for the VM to finish booting, in 37.68 seconds) machine # [ 36.065712] systemd[1]: Starting User Login Management... machine # [ 36.066616] systemd[1]: install-microvm-hermes-bob.service: Deactivated successfully. machine # [ 36.066987] systemd[1]: Finished Install MicroVM 'hermes-bob'. machine # [ 36.068887] systemd[1]: Setup MicroVM 'hermes-bob' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/pci-setup machine # [ 36.075143] systemd[1]: Starting Save MicroVM 'hermes-bob' booted configuration... machine # [ 36.117511] systemd-logind[630]: New seat seat0. machine # [ 36.119119] dbus-broker-launch[603]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 36.143591] dbus-broker-launch[603]: NSS returned no entry for 'systemd-timesync' machine # [ 36.146863] dbus-broker-launch[603]: Invalid user-name in /nix/store/z72lzlwliv3ih7l9c2lxb5dba84qsgqg-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 36.151953] systemd-logind[630]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 36.155343] systemd-logind[630]: Watching system buttons on /dev/input/event5 (QEMU Virtio Keyboard) machine # [ 36.158498] systemd-logind[630]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 36.161461] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 36.163408] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 36.165251] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 36.167180] systemd[1]: Stopping User and Group Name Lookups... machine # [ 36.168938] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 36.171380] systemd[1]: nscd.service: Deactivated successfully. machine # [ 36.173845] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 36.176308] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 36.185570] systemd[1]: Started D-Bus System Message Bus. machine # [ 36.192759] nsncd[702]: Aug 01 04:21:29.544 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 36.201051] dbus-broker-launch[603]: Ready machine # [ 36.202362] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 36.203534] systemd[1]: Finished resolvconf update. machine # [ 36.204537] systemd[1]: Finished Save MicroVM 'hermes-bob' booted configuration. machine # [ 36.205882] systemd[1]: Finished Save MicroVM 'hermes-alice' booted configuration. machine # [ 36.219463] systemd[1]: Started User Login Management. machine # [ 36.228852] systemd[1]: Created slice Slice /user/1000. machine # [ 36.229336] systemd[1]: Reached target Preparation for Network. machine # [ 36.230118] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 36.230672] systemd[1]: Reached target User and Group Name Lookups. machine # [ 36.232093] systemd[1]: Starting DHCP Client... machine # [ 36.233670] systemd[1]: Starting linger-users.service... machine # [ 36.234358] systemd[1]: Setup MicroVM 'hermes-alice' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-alice/current/bin/macvtap-up machine # [ 36.234865] systemd[1]: Setup MicroVM 'hermes-bob' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/macvtap-up machine # [ 36.237100] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-alice'... machine # [ 36.243466] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-bob'... machine # [ 36.246353] systemd[1]: Starting Address configuration of eth1... machine # [ 36.247953] systemd[1]: Starting Extra networking commands.... machine # [ 36.250472] systemd[1]: Starting User Runtime Directory /run/user/1000... machine # [ 36.269926] systemd[1]: Finished Owner-match loopback firewall for hermes microvms. machine # [ 36.282770] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.285857] systemd[1]: Finished linger-users.service. machine # [ 36.298131] systemd[1]: Finished User Runtime Directory /run/user/1000. machine # [ 36.300380] network-addresses-eth1-start[746]: adding address 192.168.1.1/24... done machine # [ 36.302883] systemd[1]: Starting User Manager for UID 1000... machine # [ 36.312831] network-addresses-eth1-start[746]: adding address 2001:db8:1::1/64... done machine # [ 36.327196] systemd[1]: Finished Address configuration of eth1. machine # [ 36.344571] (systemd)[777]: pam_unix(systemd-user:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 36.362410] systemd-logind[630]: New session '1' of user 'alice' with class 'manager' and type 'unspecified'. machine # [ 36.365087] systemd[1]: Finished Extra networking commands.. machine # [ 36.366422] systemd[1]: Reached target Network. machine # [ 36.366644] systemd[1]: Setup MicroVM 'hermes-alice' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-alice/current/bin/tap-up machine # [ 36.367375] systemd[1]: Setup MicroVM 'hermes-bob' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/tap-up machine # [ 36.369358] systemd[1]: Starting Permit User Sessions... machine # [ 36.380721] dhcpcd[794]: dhcpcd-10.3.2 starting machine # [ 36.387316] dhcpcd[837]: dev: loaded udev machine # [ 36.389857] systemd[1]: Finished Permit User Sessions. machine # [ 36.398400] systemd[1]: Started Getty on tty1. machine # [ 36.399535] systemd[1]: Reached target Login Prompts. machine # [ 36.856210] 8021q: 802.1Q VLAN Support v1.8 machine # [ 36.400944] s[ 36.856964] 8021q: adding VLAN 0 to HW filter on device eth1 machine # ystemd[777]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.419691] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 36.952924] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 36.968352] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 36.969455] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 36.971968] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 36.973552] cfg80211: failed to load regulatory.db machine # [ 36.550300] systemd[777]: Queued start job for default target Main User Target. machine # [ 37.008000] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 36.553563] dhcpcd[837]: eth0: waiting for carrier machine # [ 36.554060] dhcpcd[837]: eth0: carrier acquired machine # [ 36.556937] systemd[777]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.558365] systemd[777]: Created slice User Application Slice. machine # [ 36.559463] systemd[777]: Started Daily Cleanup of User's Temporary Directories. machine # [ 36.560374] systemd[777]: Reached target Paths. machine # [ 36.561358] systemd[777]: Reached target Timers. machine # [ 36.561897] dhcpcd[837]: DUID 00:01:00:01:32:00:30:c9:52:54:00:12:34:56 machine # [ 36.563060] systemd[777]: Starting D-Bus User Message Bus Socket... machine # [ 36.563808] dhcpcd[837]: eth0: IAID 00:12:34:56 machine # [ 36.564746] systemd[777]: Starting Create User Files and Directories... machine # [ 36.565573] dhcpcd[837]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 36.579969] systemd[777]: Finished Create User Files and Directories. machine # [ 36.608949] systemd[777]: Listening on D-Bus User Message Bus Socket. machine # [ 36.610482] systemd[777]: Reached target Sockets. machine # [ 36.611897] systemd[777]: Reached target Basic System. machine # [ 36.613143] systemd[1]: Started User Manager for UID 1000. machine # [ 36.614211] systemd[777]: Starting Run user-specific NixOS activation... machine # [ 36.638299] systemd[777]: Finished Run user-specific NixOS activation. machine # [ 36.638987] systemd[777]: Reached target Main User Target. machine # [ 36.639895] systemd[777]: Startup finished in 248ms. machine # [ 37.329529] dhcpcd[837]: eth0: soliciting a DHCP lease machine # [ 38.208326] NET: Registered PF_PACKET protocol family machine # [ 37.767702] dhcpcd[837]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 37.775231] dhcpcd[837]: eth0: probing address 10.0.2.15/24 machine # [ 38.363086] dhcpcd[837]: eth0: soliciting an IPv6 router machine # [ 38.368054] dhcpcd[837]: eth0: Router Advertisement from fe80::2 machine # [ 38.368253] dhcpcd[837]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 38.368488] dhcpcd[837]: eth0: adding route to fec0::/64 machine # [ 38.368755] dhcpcd[837]: eth0: adding default route via fe80::2 machine # [ 41.615292] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:34,963 INFO Set uid to user 0 succeeded machine # [ 41.618173] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:34,969 INFO Set uid to user 0 succeeded machine # [ 41.725579] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:35,074 INFO supervisord started with pid 781 machine # [ 41.725971] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:35,075 INFO supervisord started with pid 780 machine # [ 42.649438] dhcpcd[837]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 42.649976] dhcpcd[837]: eth0: adding route to 10.0.2.0/24 machine # [ 42.650940] dhcpcd[837]: eth0: adding default route via 10.0.2.2 machine # [ 42.839064] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,188 INFO spawned: 'notify' with pid 902 machine # [ 42.839561] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,190 INFO spawned: 'notify' with pid 903 machine # [ 42.845337] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,196 INFO spawned: 'virtiofsd-hermes-exchange' with pid 904 machine # [ 42.850402] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,202 INFO spawned: 'virtiofsd-hermes-exchange' with pid 905 machine # [ 42.853826] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,205 INFO spawned: 'virtiofsd-hermes-state' with pid 906 machine # [ 42.858804] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,209 INFO spawned: 'virtiofsd-host-config' with pid 907 machine # [ 42.873783] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,219 INFO spawned: 'virtiofsd-ro-store' with pid 908 machine # [ 42.874836] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,219 INFO spawned: 'virtiofsd-hermes-state' with pid 909 machine # [ 42.880366] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,231 INFO spawned: 'virtiofsd-host-config' with pid 910 machine # [ 42.887136] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,239 INFO spawned: 'virtiofsd-ro-store' with pid 911 machine # [ 43.875818] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:37,226 INFO success: notify entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.881736] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:37,227 INFO success: virtiofsd-hermes-exchange entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.884880] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:37,227 INFO success: virtiofsd-hermes-state entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.891547] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:37,227 INFO success: virtiofsd-host-config entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.891727] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:37,227 INFO success: virtiofsd-ro-store entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.898802] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:37,249 INFO success: notify entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.901241] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:37,250 INFO success: virtiofsd-hermes-exchange entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.901908] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:37,250 INFO success: virtiofsd-hermes-state entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.902827] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:37,250 INFO success: virtiofsd-host-config entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 43.906805] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:37,250 INFO success: virtiofsd-ro-store entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 44.038340] supervisord[781]: virtiofsd-hermes-state [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.038665] supervisord[781]: virtiofsd-ro-store [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.039196] supervisord[780]: virtiofsd-hermes-exchange [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.039391] supervisord[780]: virtiofsd-hermes-state [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.039615] supervisord[780]: virtiofsd-ro-store [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.039797] supervisord[780]: virtiofsd-host-config [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.040210] supervisord[781]: virtiofsd-hermes-exchange [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.040587] supervisord[781]: virtiofsd-host-config [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 44.100230] systemd[1]: Started DHCP Client. machine # [ 44.133937] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-alice'. machine # [ 44.134282] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-bob'. machine # [ 44.175641] systemd[1]: Starting MicroVM 'hermes-alice'... machine # [ 44.215453] systemd[1]: Starting MicroVM 'hermes-bob'... machine # [ 46.412654] microvm@hermes-bob[1096]: chmod: cannot access '/var/lib/hermes-microvm/alice/guest/tz/.localtime.tmp': No such file or directory machine # [ 46.430993] systemd[1]: microvm@hermes-bob.service: Control process exited, code=exited, status=1/FAILURE machine # [ 46.530503] systemd[1]: microvm@hermes-bob.service: Failed with result 'exit-code'. machine # [ 46.533050] systemd[1]: Failed to start MicroVM 'hermes-bob'. machine # [ 51.599340] systemd[1]: microvm@hermes-bob.service: Scheduled restart job, restart counter is at 1. machine # [ 51.601494] systemd[1]: Stopped MicroVM 'hermes-bob'. machine # [ 51.605835] systemd[1]: Setup MicroVM 'hermes-bob' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/pci-setup machine # [ 51.606139] systemd[1]: Stopping VirtioFS daemons for MicroVM 'hermes-bob'... machine # [ 52.181404] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,532 WARN received SIGTERM indicating exit request machine # [ 52.181644] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,532 INFO waiting for notify, virtiofsd-hermes-exchange, virtiofsd-hermes-state, virtiofsd-host-config, virtiofsd-ro-store to die machine # [ 52.191535] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,542 WARN stopped: virtiofsd-ro-store (exit status 1) machine # [ 52.212004] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,563 WARN stopped: virtiofsd-host-config (exit status 1) machine # [ 52.213121] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,565 WARN stopped: virtiofsd-hermes-state (exit status 1) machine # [ 52.228483] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,579 WARN stopped: virtiofsd-hermes-exchange (exit status 1) machine # [ 53.234864] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:46,586 WARN stopped: notify (terminated by SIGTERM) machine # [ 53.548880] systemd[1]: microvm-virtiofsd@hermes-bob.service: Deactivated successfully. machine # [ 53.549229] systemd[1]: Stopped VirtioFS daemons for MicroVM 'hermes-bob'. machine # [ 53.580495] systemd[1]: Stopping Save MicroVM 'hermes-bob' booted configuration... machine # [ 53.816764] systemd[1]: microvm-set-booted@hermes-bob.service: Deactivated successfully. machine # [ 53.820976] systemd[1]: Stopped Save MicroVM 'hermes-bob' booted configuration. machine # [ 53.846210] systemd[1]: Starting Save MicroVM 'hermes-bob' booted configuration... machine # [ 54.994119] systemd[1]: Finished Save MicroVM 'hermes-bob' booted configuration. machine # [ 54.994396] systemd[1]: Setup MicroVM 'hermes-bob' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/macvtap-up machine # [ 54.994573] systemd[1]: Setup MicroVM 'hermes-bob' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/tap-up machine # [ 55.017233] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-bob'... machine # [ 57.549911] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:50,898 INFO Set uid to user 0 succeeded machine # [ 57.608660] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:50,957 INFO supervisord started with pid 1172 machine # [ 58.620744] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:51,968 INFO spawned: 'notify' with pid 1195 machine # [ 58.621108] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:51,971 INFO spawned: 'virtiofsd-hermes-exchange' with pid 1196 machine # [ 58.636716] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:51,988 INFO spawned: 'virtiofsd-hermes-state' with pid 1197 machine # [ 58.657964] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:52,009 INFO spawned: 'virtiofsd-host-config' with pid 1199 machine # [ 58.664107] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:52,015 INFO spawned: 'virtiofsd-ro-store' with pid 1200 machine # [ 59.002597] supervisord[1172]: virtiofsd-hermes-exchange [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 59.026690] supervisord[1172]: virtiofsd-ro-store [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 59.057455] supervisord[1172]: virtiofsd-host-config [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 59.151138] supervisord[1172]: virtiofsd-hermes-state [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [ 60.309571] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:53,659 INFO success: notify entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 60.309872] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:53,660 INFO success: virtiofsd-hermes-exchange entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 60.310102] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:53,660 INFO success: virtiofsd-hermes-state entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 60.310250] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:53,660 INFO success: virtiofsd-host-config entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 60.310378] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:53,660 INFO success: virtiofsd-ro-store entered RUNNING state, process has stayed up for > than 1 seconds (startsecs) machine # [ 60.357638] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-bob'. machine # [ 60.363791] systemd[1]: Starting MicroVM 'hermes-bob'... machine # [ 63.122200] systemd[1]: Started MicroVM 'hermes-bob'. machine # [ 70.798371] supervisord[1172]: virtiofsd-hermes-exchange [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests machine # [ 70.799322] supervisord[1172]: virtiofsd-hermes-state [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests machine # [ 70.800630] supervisord[1172]: virtiofsd-ro-store [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests machine # [ 70.806315] supervisord[1172]: virtiofsd-host-config [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests machine # [ 70.864565] microvm@hermes-bob[1283]: microvm@hermes-bob: warning: Number of SMP cpus requested (8) exceeds the recommended cpus supported by KVM (4) machine # [ 71.101944] microvm@hermes-bob[1283]: microvm@hermes-bob: warning: Number of hotpluggable cpus requested (8) exceeds the recommended cpus supported by KVM (4) machine # [ 75.077992] microvm@hermes-bob[1283]: SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # [ 76.683955] microvm@hermes-bob[1283]: Booting from ROM... machine # [ 76.688748] microvm@hermes-bob[1283]: PPrroobbiinngg EEDDDD ((eedddd==ooffff ttoo ddiissaabbllee))...... ook machine # [ 76.965525] microvm@hermes-bob[1283]: No EFI environment detected. machine # [ 76.969502] microvm@hermes-bob[1283]: early console in extract_kernel machine # [ 76.972369] microvm@hermes-bob[1283]: input_data: 0x0000000003acc2cc machine # [ 76.975179] microvm@hermes-bob[1283]: input_len: 0x0000000000cb3f6c machine # [ 76.977794] microvm@hermes-bob[1283]: output: 0x0000000001000000 machine # [ 76.980535] microvm@hermes-bob[1283]: output_len: 0x0000000003728748 machine # [ 76.983661] microvm@hermes-bob[1283]: kernel_total_size: 0x0000000002e30000 machine # [ 76.986641] microvm@hermes-bob[1283]: needed_size: 0x0000000003800000 machine # [ 76.989710] microvm@hermes-bob[1283]: trampoline_32bit: 0x0000000000000000 machine # [ 76.993111] microvm@hermes-bob[1283]: Physical KASLR using RDRAND RDTSC... machine # [ 76.996331] microvm@hermes-bob[1283]: Virtual KASLR using RDRAND RDTSC... machine # [ 76.997652] microvm@hermes-bob[1283]: machine # [ 77.000911] microvm@hermes-bob[1283]: Decompressing Linux... No EFI environment detected. machine # [ 77.766591] microvm@hermes-bob[1283]: Parsing ELF... Performing relocations... done. machine # [ 77.771082] microvm@hermes-bob[1283]: Booting the kernel (entry_offset: 0x0000000002788520). machine # [ 77.792889] microvm@hermes-bob[1283]: [ 0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Jul 24 14:17:26 UTC 2026 machine # [ 77.812815] microvm@hermes-bob[1283]: [ 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/fa1al3d25y8qlpsxb5mmzi37mhik68vf-nixos-system-hermes-bob-26.11pre-git/init regInfo=/nix/store/hnz54hql4szcfdr42c0yrrzxzf8bfb25-closure-info/registration machine # [ 77.820618] microvm@hermes-bob[1283]: [ 0.000000] BIOS-provided physical RAM map: machine # [ 77.826054] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 77.832210] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 77.838208] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 77.844275] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffdfff] usable machine # [ 77.850345] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000bfffe000-0x00000000bfffffff] reserved machine # [ 77.856311] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 77.862494] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 77.868388] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable machine # [ 77.873527] microvm@hermes-bob[1283]: [ 0.000000] printk: legacy bootconsole [earlyser0] enabled machine # [ 77.882447] microvm@hermes-bob[1283]: Memory KASLR using RDRAND RDTSC... machine # [ 80.028686] microvm@hermes-bob[1283]: Poking KASLR using RDRAND RDTSC... machine # [ 80.241879] microvm@hermes-bob[1283]: [ 2.313767] PCI: Fatal: No config space access function found machine # [ 82.875795] microvm@hermes-bob[1283]: +q6E616D65Booting initrd of NixOS 26.11 (Zokor) (Initrd). machine # [ 83.165635] microvm@hermes-bob[1283]: [ OK ] Created slice Slice /system/modprobe. machine # [ 83.173895] microvm@hermes-bob[1283]: [ OK ] Started Dispatch Password Requests to Console Directory Watch. machine # [ 83.179477] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd Root Device. machine # [ 83.184582] microvm@hermes-bob[1283]: [ OK ] Reached target Path Units. machine # [ 83.189128] microvm@hermes-bob[1283]: [ OK ] Reached target Slice Units. machine # [ 83.193376] microvm@hermes-bob[1283]: [ OK ] Reached target Swaps. machine # [ 83.197832] microvm@hermes-bob[1283]: [ OK ] Reached target Timer Units. machine # [ 83.203152] microvm@hermes-bob[1283]: [ OK ] Listening on D-Bus System Message Bus Socket. machine # [ 83.206784] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Socket (/dev/log). machine # [ 83.211682] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Sockets. machine # [ 83.215268] microvm@hermes-bob[1283]: [ OK ] Listening on udev Control Socket. machine # [ 83.218774] microvm@hermes-bob[1283]: [ OK ] Listening on udev Kernel Socket. machine # [ 83.223089] microvm@hermes-bob[1283]: [ OK ] Reached target Socket Units. machine # [ 83.230468] microvm@hermes-bob[1283]: Starting Create List of Static Device Nodes... machine # [ 83.244171] microvm@hermes-bob[1283]: Starting Load Kernel Module configfs... machine # [ 83.253116] microvm@hermes-bob[1283]: Starting Journal Service... machine # [ 83.262184] microvm@hermes-bob[1283]: Starting Load Kernel Modules... machine # [ 83.271441] microvm@hermes-bob[1283]: Starting Coldplug All udev Devices... machine # [ 83.283573] microvm@hermes-bob[1283]: [ OK ] Finished Create List of Static Device Nodes. machine # [ 83.291779] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Module configfs. machine # [ 83.306324] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 83.340354] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev gracefully. machine # [ 83.366551] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev... machine # [ 83.450216] microvm@hermes-bob[1283]: [ OK ] Finished Coldplug All udev Devices. machine # [ 83.478871] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev. machine # [ 83.484861] microvm@hermes-bob[1283]: [ OK ] Started Journal Service. machine # [ 83.506175] microvm@hermes-bob[1283]: [ OK ] Reached target Preparation for Local File Systems. machine # [ 83.510631] microvm@hermes-bob[1283]: [ OK ] Reached target Local File Systems. machine # [ 83.521088] microvm@hermes-bob[1283]: Mounting /sysroot... machine # [ 83.530951] microvm@hermes-bob[1283]: Starting Create System Files and Directories... machine # [ 83.603304] microvm@hermes-bob[1283]: Starting Rule-based Manager for Device Events and Files... machine # [ 83.612857] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot. machine # [ 83.621643] microvm@hermes-bob[1283]: [ OK ] Finished Create System Files and Directories. machine # [ 83.633358] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd Root File System. machine # [ 83.642378] microvm@hermes-bob[1283]: Starting Mountpoints Configured in the Real Root... machine # [ 83.653940] microvm@hermes-bob[1283]: Starting rw-sysroot-nix-store.service... machine # [ 83.844938] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Modules. machine # [ 83.849257] microvm@hermes-bob[1283]: [ OK ] Finished rw-sysroot-nix-store.service. machine # [ 83.857930] microvm@hermes-bob[1283]: Starting rw-sysroot-nix-store.service... machine # [ 83.864641] microvm@hermes-bob[1283]: Starting Apply Kernel Variables... machine # [ 83.873127] microvm@hermes-bob[1283]: [ OK ] Finished Mountpoints Configured in the Real Root. machine # [ 83.878230] microvm@hermes-bob[1283]: [ OK ] Finished rw-sysroot-nix-store.service. machine # [ 83.882625] microvm@hermes-bob[1283]: [ OK ] Finished Apply Kernel Variables. machine # [ 83.901407] microvm@hermes-bob[1283]: [ OK ] Started Rule-based Manager for Device Events and Files. machine # [ 83.908125] microvm@hermes-bob[1283]: [ OK ] Reached target System Initialization. machine # [ 83.911167] microvm@hermes-bob[1283]: [ OK ] Reached target Basic System. machine # [ 84.098547] microvm@hermes-bob[1283]: Starting Virtual Console Setup... machine # [ 84.136978] microvm@hermes-bob[1283]: [ OK ] Finished Virtual Console Setup. machine # [ 84.276218] microvm@hermes-bob[1283]: Mounting /sysroot/nix/.ro-store... machine # [ 84.285090] microvm@hermes-bob[1283]: Mounting /sysroot/run... machine # [ 84.290381] microvm@hermes-bob[1283]: Mounting /sysroot/var/lib/hermes... machine # [ 84.297994] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/nix/.ro-store. machine # [ 84.304953] microvm@hermes-bob[1283]: Mounting /sysroot/nix/store... machine # [ 84.310901] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/run. machine # [ 84.321594] microvm@hermes-bob[1283]: Mounting /sysroot/run/hermes-host... machine # [ 84.330988] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/var/lib/hermes. machine # [ 84.335061] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/nix/store. machine # [ 84.340306] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/run/hermes-host. machine # [ 84.345860] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd File Systems. machine # [ 84.349845] microvm@hermes-bob[1283]: Starting Find NixOS closure... machine # [ 84.355467] microvm@hermes-bob[1283]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 84.374198] microvm@hermes-bob[1283]: [ OK ] Finished Create Volatile Files and Directories in the Real Root. machine # [ 84.392370] microvm@hermes-bob[1283]: [ OK ] Finished Find NixOS closure. machine # [ 84.398771] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd Default Target. machine # [ 84.404810] microvm@hermes-bob[1283]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 84.431719] microvm@hermes-bob[1283]: [ OK ] Stopped target Initrd Default Target. machine # [ 84.436413] microvm@hermes-bob[1283]: [ OK ] Stopped target Basic System. machine # [ 84.441274] microvm@hermes-bob[1283]: [ OK ] Stopped target Initrd Root Device. machine # [ 84.446253] microvm@hermes-bob[1283]: [ OK ] Stopped target Path Units. machine # [ 84.453643] microvm@hermes-bob[1283]: [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 84.457428] microvm@hermes-bob[1283]: [ OK ] Stopped target Slice Units. machine # [ 84.461109] microvm@hermes-bob[1283]: [ OK ] Stopped target Socket Units. machine # [ 84.464941] microvm@hermes-bob[1283]: [ OK ] Stopped target System Initialization. machine # [ 84.467628] microvm@hermes-bob[1283]: [ OK ] Stopped target Swaps. machine # [ 84.470438] microvm@hermes-bob[1283]: [ OK ] Stopped target Timer Units. machine # [ 84.473882] microvm@hermes-bob[1283]: [ OK ] Closed D-Bus System Message Bus Socket. machine # [ 84.476853] microvm@hermes-bob[1283]: [ OK ] Stopped Find NixOS closure. machine # [ 84.481256] microvm@hermes-bob[1283]: Starting rw-sysroot-nix-store.service... machine # [ 84.484495] microvm@hermes-bob[1283]: [ OK ] Stopped Apply Kernel Variables. machine # [ 84.490104] microvm@hermes-bob[1283]: [ OK ] Stopped Load Kernel Modules. machine # [ 84.496947] microvm@hermes-bob[1283]: [ OK ] Stopped Create Volatile Files and Directories in the Real Root. machine # [ 84.501117] microvm@hermes-bob[1283]: [ OK ] Stopped Create System Files and Directories. machine # [ 84.506761] microvm@hermes-bob[1283]: [ OK ] Stopped target Local File Systems. machine # [ 84.510960] microvm@hermes-bob[1283]: [ OK ] Stopped target Preparation for Local File Systems. machine # [ 84.514695] microvm@hermes-bob[1283]: [ OK ] Stopped Coldplug All udev Devices. machine # [ 84.520072] microvm@hermes-bob[1283]: Stopping Rule-based Manager for Device Events and Files... machine # [ 84.525531] microvm@hermes-bob[1283]: [ OK ] Stopped Virtual Console Setup. machine # [ 84.532235] microvm@hermes-bob[1283]: [ OK ] Finished rw-sysroot-nix-store.service. machine # [ 84.539082] microvm@hermes-bob[1283]: [ OK ] Finished Cleaning Up and Shutting Down Daemons. machine # [ 84.549728] microvm@hermes-bob[1283]: [ OK ] Stopped Rule-based Manager for Device Events and Files. machine # [ 84.554477] microvm@hermes-bob[1283]: [ OK ] Closed udev Control Socket. machine # [ 84.558946] microvm@hermes-bob[1283]: Starting Cleanup udev Database... machine # [ 84.564482] microvm@hermes-bob[1283]: [ OK ] Stopped Create Static Device Nodes in /dev. machine # [ 84.569805] microvm@hermes-bob[1283]: [ OK ] Stopped Create Static Device Nodes in /dev gracefully. machine # [ 84.573913] microvm@hermes-bob[1283]: [ OK ] Stopped Create List of Static Device Nodes. machine # [ 84.583548] microvm@hermes-bob[1283]: [ OK ] Finished Cleanup udev Database. machine # [ 84.588620] microvm@hermes-bob[1283]: [ OK ] Reached target Switch Root. machine # [ 84.595312] microvm@hermes-bob[1283]: Starting NixOS Activation... machine # [ 86.635728] microvm@hermes-bob[1283]: [* ] A start job is running for NixOS Activation (2s / no limit) machine # [ 87.218862] microvm@hermes-bob[1283]: [** ] A start job is running for NixOS Activation (2s / no limit) machine # [ 87.801482] microvm@hermes-bob[1283]: [*** ] A start job is running for NixOS Activation (3s / no limit) machine # [ 88.385950] microvm@hermes-bob[1283]: [ *** ] A start job is running for NixOS Activation (3s / no limit) machine # [ 88.969938] microvm@hermes-bob[1283]: [ *** ] A start job is running for NixOS Activation (4s / no limit) machine # [ 89.552544] microvm@hermes-bob[1283]: [ ***] A start job is running for NixOS Activation (5s / no limit) machine # [ 90.135565] microvm@hermes-bob[1283]: [ **] A start job is running for NixOS Activation (5s / no limit) machine # [ 90.719777] microvm@hermes-bob[1283]: [ *] A start job is running for NixOS Activation (6s / no limit) machine # [ 90.916662] microvm@hermes-bob[1283]: [ OK ] Finished NixOS Activation. machine # [ 90.920097] microvm@hermes-bob[1283]: Starting Switch Root... machine # [ 92.296416] microvm@hermes-bob[1283]: +q6E616D65 machine # [ 92.301706] microvm@hermes-bob[1283]: Welcome to NixOS 26.11 (Zokor)! machine # [ 92.303696] microvm@hermes-bob[1283]: machine # [ 94.100220] microvm@hermes-bob[1283]: [ OK ] Stopped initrd-switch-root.service. machine # [ 94.103798] microvm@hermes-bob[1283]: [ OK ] Created slice Slice /system/getty. machine # [ 94.107111] microvm@hermes-bob[1283]: [ OK ] Created slice Slice /system/serial-getty. machine # [ 94.110169] microvm@hermes-bob[1283]: [ OK ] Created slice User and Session Slice. machine # [ 94.113618] microvm@hermes-bob[1283]: [ OK ] Started Dispatch Password Requests to Console Directory Watch. machine # [ 94.116981] microvm@hermes-bob[1283]: [ OK ] Started Forward Password Requests to Wall Directory Watch. machine # [ 94.119065] microvm@hermes-bob[1283]: Expecting device /dev/ttyS0... machine # [ 94.121680] microvm@hermes-bob[1283]: [ OK ] Reached target Local Encrypted Volumes. machine # [ 94.124185] microvm@hermes-bob[1283]: [ OK ] Stopped target initrd-fs.target. machine # [ 94.126720] microvm@hermes-bob[1283]: [ OK ] Stopped target initrd-root-fs.target. machine # [ 94.129565] microvm@hermes-bob[1283]: [ OK ] Stopped target initrd-switch-root.target. machine # [ 94.132514] microvm@hermes-bob[1283]: [ OK ] Reached target Virtual Machines and Containers. machine # [ 94.134676] microvm@hermes-bob[1283]: [ OK ] Reached target Path Units. machine # [ 94.137313] microvm@hermes-bob[1283]: [ OK ] Reached target Remote File Systems. machine # [ 94.139482] microvm@hermes-bob[1283]: [ OK ] Reached target Slice Units. machine # [ 94.141529] microvm@hermes-bob[1283]: [ OK ] Reached target Swaps. machine # [ 94.148995] microvm@hermes-bob[1283]: [ OK ] Listening on Query the User Interactively for a Password. machine # [ 94.157070] microvm@hermes-bob[1283]: [ OK ] Listening on Process Core Dump Socket. machine # [ 94.164283] microvm@hermes-bob[1283]: [ OK ] Listening on Credential Encryption/Decryption. machine # [ 94.171147] microvm@hermes-bob[1283]: [ OK ] Listening on Factory Reset Management. machine # [ 94.173693] microvm@hermes-bob[1283]: [ OK ] Listening on Hostname Service Socket. machine # [ 94.179981] microvm@hermes-bob[1283]: Starting Journal Log Access Socket... machine # [ 94.184372] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Audit Socket. machine # [ 94.193152] microvm@hermes-bob[1283]: [ OK ] Listening on Console Output Muting Service Socket. machine # [ 94.196397] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Resolve Hook Socket. machine # [ 94.201530] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Metrics Varlink Socket. machine # [ 94.204635] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Varlink Socket. machine # [ 94.207796] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Netlink Socket. machine # [ 94.211196] microvm@hermes-bob[1283]: [ OK ] Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 94.227589] microvm@hermes-bob[1283]: [ OK ] Listening on Disk Repartitioning Service Socket. machine # [ 94.230558] microvm@hermes-bob[1283]: [ OK ] Listening on Resolve Monitor Varlink Socket. machine # [ 94.233695] microvm@hermes-bob[1283]: [ OK ] Listening on Resolve Service Varlink Socket. machine # [ 94.236358] microvm@hermes-bob[1283]: [ OK ] Listening on udev Control Socket. machine # [ 94.238872] microvm@hermes-bob[1283]: [ OK ] Listening on udev Varlink Socket. machine # [ 94.246225] microvm@hermes-bob[1283]: Mounting Huge Pages File System... machine # [ 94.265254] microvm@hermes-bob[1283]: Mounting POSIX Message Queue File System... machine # [ 94.284920] microvm@hermes-bob[1283]: Mounting Kernel Debug File System... machine # [ 94.313987] microvm@hermes-bob[1283]: Mounting Kernel Trace File System... machine # [ 94.332112] microvm@hermes-bob[1283]: Starting Create List of Static Device Nodes... machine # [ 94.375465] microvm@hermes-bob[1283]: Starting Load Kernel Module configfs... machine # [ 94.409732] microvm@hermes-bob[1283]: Mounting FUSE Control File System... machine # [ 94.455888] microvm@hermes-bob[1283]: Starting Journal Service... machine # [ 94.474253] microvm@hermes-bob[1283]: Starting Load Kernel Modules... machine # [ 94.519207] microvm@hermes-bob[1283]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 94.535532] microvm@hermes-bob[1283]: Starting Remount Root and Kernel File Systems... machine # [ 94.572202] microvm@hermes-bob[1283]: Starting Coldplug All udev Devices... machine # [ 94.625594] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Log Access Socket. machine # [ 94.633124] microvm@hermes-bob[1283]: [ OK ] Mounted Huge Pages File System. machine # [ 94.644254] microvm@hermes-bob[1283]: [ OK ] Mounted POSIX Message Queue File System. machine # [ 94.651061] microvm@hermes-bob[1283]: [ OK ] Mounted Kernel Debug File System. machine # [ 94.656667] microvm@hermes-bob[1283]: [ OK ] Mounted Kernel Trace File System. machine # [ 94.698627] microvm@hermes-bob[1283]: [ OK ] Finished Create List of Static Device Nodes. machine # [ 94.709697] microvm@hermes-bob[1283]: [ OK ] Started Journal Service. machine # [ 94.715591] microvm@hermes-bob[1283]: [ OK ] Mounted FUSE Control File System. machine # [ 94.794520] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 94.806603] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Module configfs. machine # [ 94.851405] microvm@hermes-bob[1283]: Mounting Kernel Configuration File System... machine # [ 94.891062] microvm@hermes-bob[1283]: [ OK ] Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 94.900119] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Modules. machine # [ 94.904576] microvm@hermes-bob[1283]: [ OK ] Finished Remount Root and Kernel File Systems. machine # [ 94.912464] microvm@hermes-bob[1283]: [ OK ] Listening on Disk Image Download Service Socket. machine # [ 94.923193] microvm@hermes-bob[1283]: Starting Flush Journal to Persistent Storage... machine # [ 94.990053] microvm@hermes-bob[1283]: Starting Load/Save OS Random Seed... machine # [ 95.017143] microvm@hermes-bob[1283]: Starting Apply Kernel Variables... machine # [ 95.099291] microvm@hermes-bob[1283]: Starting Network Time Synchronization... machine # [ 95.119820] microvm@hermes-bob[1283]: [ OK ] Finished Coldplug All udev Devices. machine # [ 95.127267] microvm@hermes-bob[1283]: [ OK ] Mounted Kernel Configuration File System. machine # [ 95.225404] microvm@hermes-bob[1283]: [ OK ] Finished Load/Save OS Random Seed. machine # [ 95.256625] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev gracefully. machine # [ 95.264438] microvm@hermes-bob[1283]: [ OK ] Finished Flush Journal to Persistent Storage. machine # [ 95.276469] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev... machine # [ 95.351253] microvm@hermes-bob[1283]: [ OK ] Finished Apply Kernel Variables. machine # [ 95.363263] microvm@hermes-bob[1283]: Starting Network Name Resolution... machine # [ 95.397489] microvm@hermes-bob[1283]: [ OK ] Started Network Time Synchronization. machine # [ 95.406725] microvm@hermes-bob[1283]: [ OK ] Reached target System Time Set. machine # [ 95.499138] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev. machine # [ 95.504281] microvm@hermes-bob[1283]: [ OK ] Reached target Preparation for Local File Systems. machine # [ 95.532362] microvm@hermes-bob[1283]: Starting Rule-based Manager for Device Events and Files... machine # [ 95.741988] microvm@hermes-bob[1283]: [ OK ] Started Network Name Resolution. machine # [ 95.866604] microvm@hermes-bob[1283]: [ OK ] Started Rule-based Manager for Device Events and Files. machine # [ 95.875135] microvm@hermes-bob[1283]: Starting Network Management... machine # [ 96.194817] microvm@hermes-bob[1283]: Mounting /home/bob/hermes... machine # [ 96.242867] microvm@hermes-bob[1283]: Mounting /run/wrappers... machine # [ 96.353530] microvm@hermes-bob[1283]: Starting Virtual Console Setup... machine # [ 96.507469] microvm@hermes-bob[1283]: [ OK ] Mounted /home/bob/hermes. machine # [ 96.584631] microvm@hermes-bob[1283]: [ OK ] Mounted /run/wrappers. machine # [ 96.591602] microvm@hermes-bob[1283]: [ OK ] Reached target Local File Systems. machine # [ 96.601980] microvm@hermes-bob[1283]: [ OK ] Listening on Boot Loader Control Service Socket. machine # [ 96.615626] microvm@hermes-bob[1283]: Starting Create SUID/SGID Wrappers... machine # [ 96.690212] microvm@hermes-bob[1283]: Starting Create System Files and Directories... machine # [ 97.052061] microvm@hermes-bob[1283]: [ OK ] Finished Create System Files and Directories. machine # [ 97.064089] microvm@hermes-bob[1283]: [ OK ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 97.082044] microvm@hermes-bob[1283]: Starting Rebuild Journal Catalog... machine # [ 97.142584] microvm@hermes-bob[1283]: Starting Record System Boot/Shutdown in UTMP... machine # [ 97.370488] microvm@hermes-bob[1283]: [ OK ] Finished Record System Boot/Shutdown in UTMP. machine # [ 97.468304] microvm@hermes-bob[1283]: [ OK ] Finished Rebuild Journal Catalog. machine # [ 97.495957] microvm@hermes-bob[1283]: Starting Update is Completed... machine # [ 97.783915] microvm@hermes-bob[1283]: [ OK ] Finished Update is Completed. machine # [ 98.073417] microvm@hermes-bob[1283]: [ OK ] Finished Virtual Console Setup. machine # [ 98.202158] microvm@hermes-bob[1283]: [ OK ] Started Network Management. machine # [ 98.208773] microvm@hermes-bob[1283]: [ OK ] Reached target Network. machine # [ 98.214772] microvm@hermes-bob[1283]: [ OK ] Reached target Network is Online. machine # [ 98.229297] microvm@hermes-bob[1283]: Starting Enable Persistent Storage in systemd-networkd... machine # [ 98.391885] microvm@hermes-bob[1283]: [ OK ] Finished Enable Persistent Storage in systemd-networkd. machine # [ 98.813112] microvm@hermes-bob[1283]: [ OK ] Finished Create SUID/SGID Wrappers. machine # [ 98.816735] microvm@hermes-bob[1283]: [ OK ] Reached target System Initialization. machine # [ 98.821939] microvm@hermes-bob[1283]: [ OK ] Started Discard unused filesystem blocks once a week. machine # [ 98.824425] microvm@hermes-bob[1283]: [ OK ] Started logrotate.timer. machine # [ 98.827681] microvm@hermes-bob[1283]: [ OK ] Started Daily Cleanup of Temporary Directories. machine # [ 98.830418] microvm@hermes-bob[1283]: [ OK ] Reached target Timer Units. machine # [ 98.834317] microvm@hermes-bob[1283]: [ OK ] Listening on D-Bus System Message Bus Socket. machine # [ 98.837111] microvm@hermes-bob[1283]: [ OK ] Listening on Nix Daemon Socket. machine # [ 98.846159] microvm@hermes-bob[1283]: [ OK ] Listening on OpenSSH Server Socket…temd-ssh-generator, AF_UNIX Local). machine # [ 98.863237] microvm@hermes-bob[1283]: [ OK ] Listening on SSH Socket. machine # [ 98.869207] microvm@hermes-bob[1283]: [ OK ] Listening on Virtual Machine and C…tainer Registration Service Socket. machine # [ 98.873633] microvm@hermes-bob[1283]: [ OK ] Reached target Socket Units. machine # [ 98.876307] microvm@hermes-bob[1283]: [ OK ] Reached target Basic System. machine # [ 98.883188] microvm@hermes-bob[1283]: Starting Hermes python venv (pip-writable)... machine # [ 98.905369] microvm@hermes-bob[1283]: Starting Import lastlog data into lastlog2 database... machine # [ 98.933255] microvm@hermes-bob[1283]: Starting Logrotate configuration check... machine # [ 98.961155] microvm@hermes-bob[1283]: Starting Name Service Cache Daemon (nsncd)... machine # [ 98.991970] microvm@hermes-bob[1283]: Starting Post-Boot Actions... machine # [ 99.037294] microvm@hermes-bob[1283]: [ OK ] Started Reset console on configuration changes. machine # [ 99.073504] microvm@hermes-bob[1283]: Starting D-Bus System Message Bus... machine # [ 99.178876] microvm@hermes-bob[1283]: [ OK ] Finished Logrotate configuration check. machine # [ 99.237284] microvm@hermes-bob[1283]: [ OK ] Finished Post-Boot Actions. machine # [ 99.265636] microvm@hermes-bob[1283]: [ OK ] Started Name Service Cache Daemon (nsncd). machine # [ 99.279699] microvm@hermes-bob[1283]: [ OK ] Reached target Host and Network Name Lookups. machine # [ 99.287951] microvm@hermes-bob[1283]: [ OK ] Reached target User and Group Name Lookups. machine # [ 99.302977] microvm@hermes-bob[1283]: Starting User Login Management... machine # [ 99.351639] microvm@hermes-bob[1283]: Starting Permit User Sessions... machine # [ 99.391627] microvm@hermes-bob[1283]: [ OK ] Finished Import lastlog data into lastlog2 database. machine # [ 99.490325] microvm@hermes-bob[1283]: [ OK ] Started D-Bus System Message Bus. machine # [ 99.528816] microvm@hermes-bob[1283]: [ OK ] Finished Permit User Sessions. machine # [ 99.535096] microvm@hermes-bob[1283]: [ OK ] Started User Login Management. machine # [ 99.553658] microvm@hermes-bob[1283]: [ OK ] Started Getty on tty1. machine # [ 99.559579] microvm@hermes-bob[1283]: Starting linger-users.service... machine # [ 99.614136] microvm@hermes-bob[1283]: [ OK ] Started Serial Getty on ttyS0. machine # [ 99.620563] microvm@hermes-bob[1283]: [ OK ] Reached target Login Prompts. machine # [ 99.704994] microvm@hermes-bob[1283]: [ OK ] Finished linger-users.service. machine # [ 106.689101] microvm@hermes-bob[1283]: +q6E616D65 machine # [ 106.696256] microvm@hermes-bob[1283]: machine # [ 106.702113] microvm@hermes-bob[1283]: <<< Welcome to NixOS 26.11pre-git (x86_64) - ttyS0 >>> machine # [ 106.704143] microvm@hermes-bob[1283]: machine # [ 106.705313] microvm@hermes-bob[1283]: Run 'nixos-help' for the NixOS manual. machine # [ 106.706124] microvm@hermes-bob[1283]: machine # [ 111.220427] systemd[1]: Started MicroVM 'hermes-alice'. machine # [ 111.221957] systemd[1]: Reached target microvms.target. machine # [ 111.223209] systemd[1]: Reached target Multi-User System. machine # [ 111.223782] systemd[1]: Startup finished in 1.339s (kernel) + 16.330s (initrd) + 1min 33.553s (userspace) = 1min 51.223s. machine # [ 111.266299] supervisord[781]: virtiofsd-ro-store [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests machine # [ 111.266940] microvm@hermes-alice[1713]: microvm@hermes-alice: warning: Number of SMP cpus requested (8) exceeds the recommended cpus supported by KVM (4) machine # [ 111.267870] microvm@hermes-alice[1713]: microvm@hermes-alice: warning: Number of hotpluggable cpus requested (8) exceeds the recommended cpus supported by KVM (4) machine # [ 111.273437] supervisord[781]: virtiofsd-hermes-exchange [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests machine # [ 111.274032] supervisord[781]: virtiofsd-hermes-state [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests machine # [ 111.277407] supervisord[781]: virtiofsd-host-config [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests machine # [ 111.366239] microvm@hermes-alice[1713]: SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine: (finished: waiting for unit multi-user.target, in 113.28 seconds) machine: waiting for unit microvm@hermes-alice.service machine: (finished: waiting for unit microvm@hermes-alice.service, in 0.09 seconds) machine: waiting for unit microvm@hermes-bob.service machine # [ 111.795212] microvm@hermes-alice[1713]: Booting from ROM... machine # [ 111.799311] microvm@hermes-alice[1713]: PPrroobbiinngg EEDDDD ((eedddd==ooffff ttoo ddiissaabbllee))...... ook machine: (finished: waiting for unit microvm@hermes-bob.service, in 0.09 seconds) machine: waiting for success: runuser -u alice -- hermes --version machine # [ 111.864441] runuser[1748]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 112.039810] microvm@hermes-alice[1713]: No EFI environment detected. machine # [ 112.044163] microvm@hermes-alice[1713]: early console in extract_kernel machine # [ 112.047336] microvm@hermes-alice[1713]: input_data: 0x0000000003acc2cc machine # [ 112.050377] microvm@hermes-alice[1713]: input_len: 0x0000000000cb3f6c machine # [ 112.053424] microvm@hermes-alice[1713]: output: 0x0000000001000000 machine # [ 112.056518] microvm@hermes-alice[1713]: output_len: 0x0000000003728748 machine # [ 112.059911] microvm@hermes-alice[1713]: kernel_total_size: 0x0000000002e30000 machine # [ 112.063155] microvm@hermes-alice[1713]: needed_size: 0x0000000003800000 machine # [ 112.066576] microvm@hermes-alice[1713]: trampoline_32bit: 0x0000000000000000 machine # [ 112.069658] microvm@hermes-alice[1713]: Physical KASLR using RDRAND RDTSC... machine # [ 112.072662] microvm@hermes-alice[1713]: Virtual KASLR using RDRAND RDTSC... machine # [ 112.073811] microvm@hermes-alice[1713]: machine # [ 112.076648] microvm@hermes-alice[1713]: Decompressing Linux... No EFI environment detected. machine # [ 112.716423] microvm@hermes-alice[1713]: Parsing ELF... Performing relocations... done. machine # [ 112.719602] microvm@hermes-alice[1713]: Booting the kernel (entry_offset: 0x0000000002788520). machine # [ 112.736414] microvm@hermes-alice[1713]: [ 0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Jul 24 14:17:26 UTC 2026 machine # [ 112.752656] microvm@hermes-alice[1713]: [ 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/qxqjj1yq46v6y0gclm2zc15kfika13db-nixos-system-hermes-alice-26.11pre-git/init regInfo=/nix/store/i58h0rmg3sy8mmrzd2hcf7ln8a7d89km-closure-info/registration machine # [ 112.760445] microvm@hermes-alice[1713]: [ 0.000000] BIOS-provided physical RAM map: machine # [ 112.765070] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 112.770116] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 112.775249] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 112.780932] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffdfff] usable machine # [ 112.787670] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000bfffe000-0x00000000bfffffff] reserved machine # [ 112.794385] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 112.798236] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 112.801866] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable machine # [ 112.804783] microvm@hermes-alice[1713]: [ 0.000000] printk: legacy bootconsole [earlyser0] enabled machine # [ 112.810775] microvm@hermes-alice[1713]: Memory KASLR using RDRAND RDTSC... machine # Failed to connect to vsock:735577804:22: Connection timed out machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 113.948529] runuser[1748]: pam_unix(runuser:session): session closed for user alice machine # [ 114.982812] runuser[1758]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 115.030239] microvm@hermes-alice[1713]: Poking KASLR using RDRAND RDTSC... machine # [ 115.264873] microvm@hermes-alice[1713]: [ 2.404265] PCI: Fatal: No config space access function found machine # Failed to connect to vsock:735577804:22: Connection timed out machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 117.085808] runuser[1758]: pam_unix(runuser:session): session closed for user alice machine # [ 118.085581] microvm@hermes-alice[1713]: +q6E616D65Booting initrd of NixOS 26.11 (Zokor) (Initrd). machine # [ 118.124403] runuser[1768]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 118.344295] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/modprobe. machine # [ 118.349069] microvm@hermes-alice[1713]: [ OK ] Started Dispatch Password Requests to Console Directory Watch. machine # [ 118.352764] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd Root Device. machine # [ 118.356203] microvm@hermes-alice[1713]: [ OK ] Reached target Path Units. machine # [ 118.358982] microvm@hermes-alice[1713]: [ OK ] Reached target Slice Units. machine # [ 118.361554] microvm@hermes-alice[1713]: [ OK ] Reached target Swaps. machine # [ 118.364218] microvm@hermes-alice[1713]: [ OK ] Reached target Timer Units. machine # [ 118.369759] microvm@hermes-alice[1713]: [ OK ] Listening on D-Bus System Message Bus Socket. machine # [ 118.374278] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Socket (/dev/log). machine # [ 118.377647] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Sockets. machine # [ 118.383707] microvm@hermes-alice[1713]: [ OK ] Listening on udev Control Socket. machine # [ 118.386738] microvm@hermes-alice[1713]: [ OK ] Listening on udev Kernel Socket. machine # [ 118.390326] microvm@hermes-alice[1713]: [ OK ] Reached target Socket Units. machine # [ 118.399937] microvm@hermes-alice[1713]: Starting Create List of Static Device Nodes... machine # [ 118.428398] microvm@hermes-alice[1713]: Starting Load Kernel Module configfs... machine # [ 118.450362] microvm@hermes-alice[1713]: Starting Journal Service... machine # [ 118.459576] microvm@hermes-alice[1713]: Starting Load Kernel Modules... machine # [ 118.473195] microvm@hermes-alice[1713]: Starting Coldplug All udev Devices... machine # [ 118.488977] microvm@hermes-alice[1713]: [ OK ] Finished Create List of Static Device Nodes. machine # [ 118.504078] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Module configfs. machine # [ 118.519994] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 118.573644] microvm@hermes-alice[1713]: [ OK ] Started Journal Service. machine # [ 118.598557] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev gracefully. machine # [ 118.642683] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev... machine # [ 118.723619] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev. machine # [ 118.728603] microvm@hermes-alice[1713]: [ OK ] Reached target Preparation for Local File Systems. machine # [ 118.733179] microvm@hermes-alice[1713]: [ OK ] Reached target Local File Systems. machine # [ 118.739539] microvm@hermes-alice[1713]: Starting Create System Files and Directories... machine # [ 118.904282] microvm@hermes-alice[1713]: Starting Rule-based Manager for Device Events and Files... machine # [ 118.908701] microvm@hermes-alice[1713]: [ OK ] Finished Coldplug All udev Devices. machine # [ 118.919079] microvm@hermes-alice[1713]: [ OK ] Finished Create System Files and Directories. machine # [ 119.038506] microvm@hermes-alice[1713]: [ OK ] Started Rule-based Manager for Device Events and Files. machine # [ 119.285396] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Modules. machine # [ 119.288982] microvm@hermes-alice[1713]: Starting Apply Kernel Variables... machine # [ 119.367768] microvm@hermes-alice[1713]: Starting Virtual Console Setup... machine # [ 119.382331] microvm@hermes-alice[1713]: [ OK ] Finished Apply Kernel Variables. machine # [ 119.432182] microvm@hermes-alice[1713]: [ OK ] Finished Virtual Console Setup. machine # [ 119.439652] microvm@hermes-alice[1713]: [ OK ] Reached target System Initialization. machine # [ 119.444193] microvm@hermes-alice[1713]: [ OK ] Reached target Basic System. machine # [ 119.479480] microvm@hermes-alice[1713]: Mounting /sysroot... machine # [ 119.503691] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot. machine # [ 119.510947] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd Root File System. machine # [ 119.521112] microvm@hermes-alice[1713]: Mounting /sysroot/nix/.ro-store... machine # [ 119.534177] microvm@hermes-alice[1713]: Mounting /sysroot/run... machine # [ 119.544518] microvm@hermes-alice[1713]: Mounting /sysroot/var/lib/hermes... machine # [ 119.557225] microvm@hermes-alice[1713]: Starting Mountpoints Configured in the Real Root... machine # [ 119.564059] microvm@hermes-alice[1713]: Starting rw-sysroot-nix-store.service... machine # [ 119.569945] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/nix/.ro-store. machine # [ 119.589159] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/run. machine # [ 119.600583] microvm@hermes-alice[1713]: Mounting /sysroot/run/hermes-host... machine # [ 119.605422] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/var/lib/hermes. machine # [ 119.612249] microvm@hermes-alice[1713]: [ OK ] Finished rw-sysroot-nix-store.service. machine # [ 119.625066] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/run/hermes-host. machine # [ 119.728684] microvm@hermes-alice[1713]: Starting rw-sysroot-nix-store.service... machine # [ 119.740308] microvm@hermes-alice[1713]: [ OK ] Finished Mountpoints Configured in the Real Root. machine # [ 119.747553] microvm@hermes-alice[1713]: [ OK ] Finished rw-sysroot-nix-store.service. machine # Failed to connect to vsock:735577804:22: Connection timed out machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 120.220258] runuser[1768]: pam_unix(runuser:session): session closed for user alice machine # [ 120.483034] microvm@hermes-alice[1713]: Mounting /sysroot/nix/store... machine # [ 120.505103] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/nix/store. machine # [ 120.508657] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd File Systems. machine # [ 120.516103] microvm@hermes-alice[1713]: Starting Find NixOS closure... machine # [ 120.532179] microvm@hermes-alice[1713]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 120.541365] microvm@hermes-alice[1713]: [ OK ] Finished Create Volatile Files and Directories in the Real Root. machine # [ 120.558766] microvm@hermes-alice[1713]: [ OK ] Finished Find NixOS closure. machine # [ 120.565213] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd Default Target. machine # [ 120.571619] microvm@hermes-alice[1713]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 120.592758] microvm@hermes-alice[1713]: [ OK ] Stopped target Initrd Default Target. machine # [ 120.597233] microvm@hermes-alice[1713]: [ OK ] Stopped target Basic System. machine # [ 120.602455] microvm@hermes-alice[1713]: [ OK ] Stopped target Initrd Root Device. machine # [ 120.607700] microvm@hermes-alice[1713]: [ OK ] Stopped target Path Units. machine # [ 120.614116] microvm@hermes-alice[1713]: [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 120.618615] microvm@hermes-alice[1713]: [ OK ] Stopped target Slice Units. machine # [ 120.623188] microvm@hermes-alice[1713]: [ OK ] Stopped target Socket Units. machine # [ 120.627556] microvm@hermes-alice[1713]: [ OK ] Stopped target System Initialization. machine # [ 120.630950] microvm@hermes-alice[1713]: [ OK ] Stopped target Swaps. machine # [ 120.636355] microvm@hermes-alice[1713]: [ OK ] Stopped target Timer Units. machine # [ 120.641712] microvm@hermes-alice[1713]: [ OK ] Closed D-Bus System Message Bus Socket. machine # [ 120.647116] microvm@hermes-alice[1713]: [ OK ] Stopped Find NixOS closure. machine # [ 120.651684] microvm@hermes-alice[1713]: Starting rw-sysroot-nix-store.service... machine # [ 120.657485] microvm@hermes-alice[1713]: [ OK ] Stopped Apply Kernel Variables. machine # [ 120.662724] microvm@hermes-alice[1713]: [ OK ] Stopped Load Kernel Modules. machine # [ 120.670098] microvm@hermes-alice[1713]: [ OK ] Stopped Create Volatile Files and Directories in the Real Root. machine # [ 120.675568] microvm@hermes-alice[1713]: [ OK ] Stopped Create System Files and Directories. machine # [ 120.680984] microvm@hermes-alice[1713]: [ OK ] Stopped target Local File Systems. machine # [ 120.687132] microvm@hermes-alice[1713]: [ OK ] Stopped target Preparation for Local File Systems. machine # [ 120.691641] microvm@hermes-alice[1713]: [ OK ] Stopped Coldplug All udev Devices. machine # [ 120.698850] microvm@hermes-alice[1713]: Stopping Rule-based Manager for Device Events and Files... machine # [ 120.704469] microvm@hermes-alice[1713]: [ OK ] Stopped Virtual Console Setup. machine # [ 120.711969] microvm@hermes-alice[1713]: [ OK ] Finished rw-sysroot-nix-store.service. machine # [ 120.725312] microvm@hermes-alice[1713]: [ OK ] Finished Cleaning Up and Shutting Down Daemons. machine # [ 120.735296] microvm@hermes-alice[1713]: [ OK ] Stopped Rule-based Manager for Device Events and Files. machine # [ 120.742389] microvm@hermes-alice[1713]: [ OK ] Closed udev Control Socket. machine # [ 120.747619] microvm@hermes-alice[1713]: Starting Cleanup udev Database... machine # [ 120.754036] microvm@hermes-alice[1713]: [ OK ] Stopped Create Static Device Nodes in /dev. machine # [ 120.762417] microvm@hermes-alice[1713]: [ OK ] Stopped Create Static Device Nodes in /dev gracefully. machine # [ 120.767707] microvm@hermes-alice[1713]: [ OK ] Stopped Create List of Static Device Nodes. machine # [ 120.790059] microvm@hermes-alice[1713]: [ OK ] Finished Cleanup udev Database. machine # [ 120.794547] microvm@hermes-alice[1713]: [ OK ] Reached target Switch Root. machine # [ 120.799290] microvm@hermes-alice[1713]: Starting NixOS Activation... machine # [ 121.250359] runuser[1778]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 123.016791] microvm@hermes-alice[1713]: [* ] A start job is running for NixOS Activation (2s / no limit) machine # Failed to connect to vsock:735577804:22: Connection timed out machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 123.357748] runuser[1778]: pam_unix(runuser:session): session closed for user alice machine # [ 123.517633] microvm@hermes-alice[1713]: [** ] A start job is running for NixOS Activation (2s / no limit) machine # [ 124.016966] microvm@hermes-alice[1713]: [*** ] A start job is running for NixOS Activation (3s / no limit) machine # [ 124.393738] runuser[1788]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 124.517356] microvm@hermes-alice[1713]: [ *** ] A start job is running for NixOS Activation (3s / no limit) machine # [ 125.017227] microvm@hermes-alice[1713]: [ *** ] A start job is running for NixOS Activation (4s / no limit) machine # [ 125.516375] microvm@hermes-alice[1713]: [ ***] A start job is running for NixOS Activation (4s / no limit) machine # [ 126.015920] microvm@hermes-alice[1713]: [ **] A start job is running for NixOS Activation (5s / no limit) machine # [ 126.359130] microvm@hermes-alice[1713]: [ *] A start job is running for NixOS Activation (5s / no limit) machine # [ 126.367399] microvm@hermes-alice[1713]: [ OK ] Finished NixOS Activation. machine # [ 126.374315] microvm@hermes-alice[1713]: Starting Switch Root... machine # Failed to connect to vsock:735577804:22: Connection timed out machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 126.497216] runuser[1788]: pam_unix(runuser:session): session closed for user alice machine # [ 127.531253] runuser[1798]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 127.567599] runuser[1798]: pam_unix(runuser:session): session closed for user alice machine # [ 127.855603] microvm@hermes-alice[1713]: +q6E616D65 machine # [ 127.860224] microvm@hermes-alice[1713]: Welcome to NixOS 26.11 (Zokor)! machine # [ 127.862214] microvm@hermes-alice[1713]: machine # [ 128.595846] runuser[1808]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 128.638891] runuser[1808]: pam_unix(runuser:session): session closed for user alice machine # [ 129.501282] microvm@hermes-alice[1713]: [ OK ] Stopped initrd-switch-root.service. machine # [ 129.506214] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/getty. machine # [ 129.511300] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/serial-getty. machine # [ 129.515970] microvm@hermes-alice[1713]: [ OK ] Created slice User and Session Slice. machine # [ 129.521033] microvm@hermes-alice[1713]: [ OK ] Started Dispatch Password Requests to Console Directory Watch. machine # [ 129.526870] microvm@hermes-alice[1713]: [ OK ] Started Forward Password Requests to Wall Directory Watch. machine # [ 129.529125] microvm@hermes-alice[1713]: Expecting device /dev/ttyS0... machine # [ 129.532069] microvm@hermes-alice[1713]: [ OK ] Reached target Local Encrypted Volumes. machine # [ 129.535289] microvm@hermes-alice[1713]: [ OK ] Stopped target initrd-fs.target. machine # [ 129.538305] microvm@hermes-alice[1713]: [ OK ] Stopped target initrd-root-fs.target. machine # [ 129.541422] microvm@hermes-alice[1713]: [ OK ] Stopped target initrd-switch-root.target. machine # [ 129.544923] microvm@hermes-alice[1713]: [ OK ] Reached target Virtual Machines and Containers. machine # [ 129.547351] microvm@hermes-alice[1713]: [ OK ] Reached target Path Units. machine # [ 129.550203] microvm@hermes-alice[1713]: [ OK ] Reached target Remote File Systems. machine # [ 129.552779] microvm@hermes-alice[1713]: [ OK ] Reached target Slice Units. machine # [ 129.555142] microvm@hermes-alice[1713]: [ OK ] Reached target Swaps. machine # [ 129.562771] microvm@hermes-alice[1713]: [ OK ] Listening on Query the User Interactively for a Password. machine # [ 129.569775] microvm@hermes-alice[1713]: [ OK ] Listening on Process Core Dump Socket. machine # [ 129.578762] microvm@hermes-alice[1713]: [ OK ] Listening on Credential Encryption/Decryption. machine # [ 129.587150] microvm@hermes-alice[1713]: [ OK ] Listening on Factory Reset Management. machine # [ 129.590322] microvm@hermes-alice[1713]: [ OK ] Listening on Hostname Service Socket. machine # [ 129.598604] microvm@hermes-alice[1713]: Starting Journal Log Access Socket... machine # [ 129.603216] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Audit Socket. machine # [ 129.614081] microvm@hermes-alice[1713]: [ OK ] Listening on Console Output Muting Service Socket. machine # [ 129.617343] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Resolve Hook Socket. machine # [ 129.621100] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Metrics Varlink Socket. machine # [ 129.624842] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Varlink Socket. machine # [ 129.628776] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Netlink Socket. machine # [ 129.633557] microvm@hermes-alice[1713]: [ OK ] Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 129.654780] microvm@hermes-alice[1713]: [ OK ] Listening on Disk Repartitioning Service Socket. machine # [ 129.657734] microvm@hermes-alice[1713]: [ OK ] Listening on Resolve Monitor Varlink Socket. machine # [ 129.661135] microvm@hermes-alice[1713]: [ OK ] Listening on Resolve Service Varlink Socket. machine # [ 129.664668] microvm@hermes-alice[1713]: [ OK ] Listening on udev Control Socket. machine # [ 129.668355] microvm@hermes-alice[1713]: [ OK ] Listening on udev Varlink Socket. machine # [ 129.674913] runuser[1818]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 129.676568] microvm@hermes-alice[1713]: Mounting Huge Pages File System... machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # [ 129.715685] microvm@hermes-alice[1713]: Mounting POSIX Message Queue File System... machine # proxy dialer did not pass back a connection machine # [ 129.719394] runuser[1818]: pam_unix(runuser:session): session closed for user alice machine # [ 129.734889] microvm@hermes-alice[1713]: Mounting Kernel Debug File System... machine # [ 129.771121] microvm@hermes-alice[1713]: Mounting Kernel Trace File System... machine # [ 129.803123] microvm@hermes-alice[1713]: Starting Create List of Static Device Nodes... machine # [ 129.876931] microvm@hermes-alice[1713]: Starting Load Kernel Module configfs... machine # [ 129.906245] microvm@hermes-alice[1713]: Mounting FUSE Control File System... machine # [ 130.026618] microvm@hermes-alice[1713]: Starting Journal Service... machine # [ 130.046093] microvm@hermes-alice[1713]: Starting Load Kernel Modules... machine # [ 130.175649] microvm@hermes-alice[1713]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 130.196088] microvm@hermes-alice[1713]: Starting Remount Root and Kernel File Systems... machine # [ 130.257954] microvm@hermes-alice[1713]: Starting Coldplug All udev Devices... machine # [ 130.275637] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Log Access Socket. machine # [ 130.280302] microvm@hermes-alice[1713]: [ OK ] Mounted Huge Pages File System. machine # [ 130.283972] microvm@hermes-alice[1713]: [ OK ] Mounted POSIX Message Queue File System. machine # [ 130.314526] microvm@hermes-alice[1713]: [ OK ] Mounted Kernel Debug File System. machine # [ 130.323134] microvm@hermes-alice[1713]: [ OK ] Mounted Kernel Trace File System. machine # [ 130.330286] microvm@hermes-alice[1713]: [ OK ] Finished Create List of Static Device Nodes. machine # [ 130.341381] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Module configfs. machine # [ 130.350620] microvm@hermes-alice[1713]: [ OK ] Mounted FUSE Control File System. machine # [ 130.358162] microvm@hermes-alice[1713]: [ OK ] Started Journal Service. machine # [ 130.387982] microvm@hermes-alice[1713]: Mounting Kernel Configuration File System... machine # [ 130.432568] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 130.472995] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Modules. machine # [ 130.543179] microvm@hermes-alice[1713]: Starting Apply Kernel Variables... machine # [ 130.583858] microvm@hermes-alice[1713]: [ OK ] Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 130.593866] microvm@hermes-alice[1713]: [ OK ] Finished Remount Root and Kernel File Systems. machine # [ 130.606677] microvm@hermes-alice[1713]: [ OK ] Listening on Disk Image Download Service Socket. machine # [ 130.619888] microvm@hermes-alice[1713]: Starting Flush Journal to Persistent Storage... machine # [ 130.672163] microvm@hermes-alice[1713]: Starting Load/Save OS Random Seed... machine # [ 130.706137] microvm@hermes-alice[1713]: Starting Network Time Synchronization... machine # [ 130.761197] runuser[1828]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 130.783607] microvm@hermes-alice[1713]: [ OK ] Finished Coldplug All udev Devices. machine # [ 130.792627] microvm@hermes-alice[1713]: [ OK ] Mounted Kernel Configuration File System. machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 130.828292] runuser[1828]: pam_unix(runuser:session): session closed for user alice machine # [ 130.853687] microvm@hermes-alice[1713]: [ OK ] Finished Load/Save OS Random Seed. machine # [ 130.897192] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev gracefully. machine # [ 130.906599] microvm@hermes-alice[1713]: [ OK ] Finished Apply Kernel Variables. machine # [ 130.920522] microvm@hermes-alice[1713]: Starting Network Name Resolution... machine # [ 130.964243] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev... machine # [ 130.977726] microvm@hermes-alice[1713]: [ OK ] Finished Flush Journal to Persistent Storage. machine # [ 131.087177] microvm@hermes-alice[1713]: [ OK ] Started Network Time Synchronization. machine # [ 131.094718] microvm@hermes-alice[1713]: [ OK ] Reached target System Time Set. machine # [ 131.178167] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev. machine # [ 131.185105] microvm@hermes-alice[1713]: [ OK ] Reached target Preparation for Local File Systems. machine # [ 131.194992] microvm@hermes-alice[1713]: Starting Rule-based Manager for Device Events and Files... machine # [ 131.238326] microvm@hermes-alice[1713]: [ OK ] Started Network Name Resolution. machine # [ 131.492343] microvm@hermes-alice[1713]: [ OK ] Started Rule-based Manager for Device Events and Files. machine # [ 131.504843] microvm@hermes-alice[1713]: Starting Network Management... machine # [ 131.594550] microvm@hermes-alice[1713]: Mounting /home/alice/hermes... machine # [ 131.632938] microvm@hermes-alice[1713]: Mounting /run/wrappers... machine # [ 131.867133] runuser[1838]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 131.949157] runuser[1838]: pam_unix(runuser:session): session closed for user alice machine # [ 131.964543] microvm@hermes-alice[1713]: Starting Virtual Console Setup... machine # [ 132.495233] microvm@hermes-alice[1713]: [ OK ] Mounted /run/wrappers. machine # [ 132.499627] microvm@hermes-alice[1713]: [ OK ] Mounted /home/alice/hermes. machine # [ 132.505279] microvm@hermes-alice[1713]: [ OK ] Reached target Local File Systems. machine # [ 132.522867] microvm@hermes-alice[1713]: [ OK ] Listening on Boot Loader Control Service Socket. machine # [ 132.553975] microvm@hermes-alice[1713]: Starting Create SUID/SGID Wrappers... machine # [ 132.745789] microvm@hermes-alice[1713]: Starting Create System Files and Directories... machine # [ 132.986932] runuser[1848]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 133.050707] runuser[1848]: pam_unix(runuser:session): session closed for user alice machine # [ 133.123862] microvm@hermes-alice[1713]: [ OK ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 133.268766] microvm@hermes-alice[1713]: [ OK ] Finished Create System Files and Directories. machine # [ 133.291505] microvm@hermes-alice[1713]: Starting Rebuild Journal Catalog... machine # [ 133.402510] microvm@hermes-alice[1713]: Starting Record System Boot/Shutdown in UTMP... machine # [ 133.582133] microvm@hermes-alice[1713]: [ OK ] Finished Record System Boot/Shutdown in UTMP. machine # [ 133.643566] microvm@hermes-alice[1713]: [ OK ] Finished Rebuild Journal Catalog. machine # [ 133.656768] microvm@hermes-alice[1713]: Starting Update is Completed... machine # [ 133.974473] microvm@hermes-alice[1713]: [ OK ] Finished Update is Completed. machine # [ 133.986445] microvm@hermes-alice[1713]: [ OK ] Finished Virtual Console Setup. machine # [ 134.090636] runuser[1858]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 134.135244] runuser[1858]: pam_unix(runuser:session): session closed for user alice machine # [ 134.455146] microvm@hermes-alice[1713]: [ OK ] Started Network Management. machine # [ 134.466756] microvm@hermes-alice[1713]: [ OK ] Reached target Network. machine # [ 134.471465] microvm@hermes-alice[1713]: [ OK ] Reached target Network is Online. machine # [ 134.535559] microvm@hermes-alice[1713]: Starting Enable Persistent Storage in systemd-networkd... machine # [ 134.800045] microvm@hermes-alice[1713]: [ OK ] Finished Enable Persistent Storage in systemd-networkd. machine # [ 135.165272] runuser[1868]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # Failed to connect to vsock:735577804:22: Connection reset by peer machine # mm_receive_fd: recvmsg: expected received 1 got 0 machine # proxy dialer did not pass back a connection machine # [ 135.205653] runuser[1868]: pam_unix(runuser:session): session closed for user alice machine # [ 135.220443] microvm@hermes-alice[1713]: [ OK ] Finished Create SUID/SGID Wrappers. machine # [ 135.224383] microvm@hermes-alice[1713]: [ OK ] Reached target System Initialization. machine # [ 135.230940] microvm@hermes-alice[1713]: [ OK ] Started Discard unused filesystem blocks once a week. machine # [ 135.233949] microvm@hermes-alice[1713]: [ OK ] Started logrotate.timer. machine # [ 135.238075] microvm@hermes-alice[1713]: [ OK ] Started Daily Cleanup of Temporary Directories. machine # [ 135.241264] microvm@hermes-alice[1713]: [ OK ] Reached target Timer Units. machine # [ 135.246334] microvm@hermes-alice[1713]: [ OK ] Listening on D-Bus System Message Bus Socket. machine # [ 135.251247] microvm@hermes-alice[1713]: [ OK ] Listening on Nix Daemon Socket. machine # [ 135.260704] microvm@hermes-alice[1713]: [ OK ] Listening on OpenSSH Server Socket…temd-ssh-generator, AF_UNIX Local). machine # [ 135.276903] microvm@hermes-alice[1713]: [ OK ] Listening on SSH Socket. machine # [ 135.283090] microvm@hermes-alice[1713]: [ OK ] Listening on Virtual Machine and C…tainer Registration Service Socket. machine # [ 135.286394] microvm@hermes-alice[1713]: [ OK ] Reached target Socket Units. machine # [ 135.289292] microvm@hermes-alice[1713]: [ OK ] Reached target Basic System. machine # [ 135.296983] microvm@hermes-alice[1713]: Starting Hermes python venv (pip-writable)... machine # [ 135.336532] microvm@hermes-alice[1713]: Starting Import lastlog data into lastlog2 database... machine # [ 135.373676] microvm@hermes-alice[1713]: Starting Logrotate configuration check... machine # [ 135.427444] microvm@hermes-alice[1713]: Starting Name Service Cache Daemon (nsncd)... machine # [ 135.479442] microvm@hermes-alice[1713]: Starting Post-Boot Actions... machine # [ 135.536301] microvm@hermes-alice[1713]: [ OK ] Started Reset console on configuration changes. machine # [ 135.624174] microvm@hermes-alice[1713]: Starting D-Bus System Message Bus... machine # [ 135.671671] microvm@hermes-alice[1713]: [ OK ] Finished Logrotate configuration check. machine # [ 135.774475] microvm@hermes-alice[1713]: [ OK ] Finished Post-Boot Actions. machine # [ 135.781139] microvm@hermes-alice[1713]: [ OK ] Started Name Service Cache Daemon (nsncd). machine # [ 135.795542] microvm@hermes-alice[1713]: [ OK ] Reached target Host and Network Name Lookups. machine # [ 135.801728] microvm@hermes-alice[1713]: [ OK ] Reached target User and Group Name Lookups. machine # [ 135.819987] microvm@hermes-alice[1713]: Starting User Login Management... machine # [ 135.854105] microvm@hermes-alice[1713]: Starting Permit User Sessions... machine # [ 135.876830] microvm@hermes-alice[1713]: [ OK ] Finished Import lastlog data into lastlog2 database. machine # [ 135.985455] microvm@hermes-alice[1713]: [ OK ] Finished Permit User Sessions. machine # [ 135.999400] microvm@hermes-alice[1713]: [ OK ] Started Getty on tty1. machine # [ 136.039461] microvm@hermes-alice[1713]: [ OK ] Started Serial Getty on ttyS0. machine # [ 136.045915] microvm@hermes-alice[1713]: [ OK ] Reached target Login Prompts. machine # [ 136.062213] microvm@hermes-alice[1713]: [ OK ] Started D-Bus System Message Bus. machine # [ 136.112717] microvm@hermes-alice[1713]: [ OK ] Started User Login Management. machine # [ 136.128160] microvm@hermes-alice[1713]: Starting linger-users.service... machine # [ 136.234739] runuser[1878]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 136.265943] microvm@hermes-alice[1713]: [ OK ] Finished linger-users.service. machine # [ 136.294446] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/sshd. machine # [ 136.307255] microvm@hermes-alice[1713]: [ OK ] Started SSH per-connection Daemon (vsock:2:1418679566). machine # [ 136.868078] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /user/1000. machine # [ 136.877490] microvm@hermes-alice[1713]: Starting User Runtime Directory /run/user/1000... machine # [ 137.033067] microvm@hermes-alice[1713]: [ OK ] Finished User Runtime Directory /run/user/1000. machine # [ 137.042925] microvm@hermes-alice[1713]: Starting User Manager for UID 1000... machine # [ 138.341120] microvm@hermes-alice[1713]: [ OK ] Started User Manager for UID 1000. machine # [ 138.399551] microvm@hermes-alice[1713]: [ OK ] Started Session 1 of User alice. machine # [ 143.194760] microvm@hermes-alice[1713]: +q6E616D65 machine # [ 143.195801] microvm@hermes-alice[1713]: machine # [ 143.197760] microvm@hermes-alice[1713]: <<< Welcome to NixOS 26.11pre-git (x86_64) - ttyS0 >>> machine # [ 143.198663] microvm@hermes-alice[1713]: machine # [ 143.199844] microvm@hermes-alice[1713]: Run 'nixos-help' for the NixOS manual. machine # [ 143.200592] microvm@hermes-alice[1713]: machine # [ 145.141424] runuser[1878]: pam_unix(runuser:session): session closed for user alice machine: (finished: waiting for success: runuser -u alice -- hermes --version, in 33.32 seconds) machine: must succeed: systemctl is-active hermes-dashboard-fwd-alice.socket machine: (finished: must succeed: systemctl is-active hermes-dashboard-fwd-alice.socket, in 0.02 seconds) machine: must succeed: systemctl is-active hermes-spaces-bridge-alice.socket machine: (finished: must succeed: systemctl is-active hermes-spaces-bridge-alice.socket, in 0.02 seconds) machine: must succeed: systemctl show -p Listen hermes-spaces-bridge-alice.socket machine: (finished: must succeed: systemctl show -p Listen hermes-spaces-bridge-alice.socket, in 0.03 seconds) subtest: dashboard forward is owner-gated (firewall username match) machine: must succeed: systemctl is-active firewall.service machine: (finished: must succeed: systemctl is-active firewall.service, in 0.02 seconds) machine: must succeed: runuser -u alice -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901' machine # [ 145.259981] runuser[1897]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 145.271352] runuser[1897]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901', in 0.04 seconds) machine: must fail: runuser -u bob -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901' machine # [ 145.278703] systemd[1]: Created slice Slice /system/hermes-dashboard-fwd-alice. machine # [ 145.282736] systemd[1]: Started dashboard vsock forward for hermes-alice (127.0.0.1:37976). machine # [ 145.298458] runuser[1902]: pam_unix(runuser:session): session opened for user bob(uid=1001) by (uid=0) machine # bash: connect: Connection refused machine # bash: line 1: /dev/tcp/127.0.0.1/22901: Connection refused machine # [ 145.313731] runuser[1902]: pam_unix(runuser:session): session closed for user bob machine: (finished: must fail: runuser -u bob -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901', in 0.04 seconds) (finished: subtest: dashboard forward is owner-gated (firewall username match), in 0.10 seconds) machine: waiting for success: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'systemctl is-active --quiet hermes-python-venv.service' machine # [ 145.325251] systemd[1]: hermes-dashboard-fwd-alice@0-1-127.0.0.1:22901-127.0.0.1:37976.service: Main process exited, code=exited, status=1/FAILURE machine # [ 145.326035] systemd[1]: hermes-dashboard-fwd-alice@0-1-127.0.0.1:22901-127.0.0.1:37976.service: Failed with result 'exit-code'. machine # [ 145.348544] runuser[1912]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 146.088609] runuser[1912]: pam_unix(runuser:session): session closed for user alice machine: (finished: waiting for success: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'systemctl is-active --quiet hermes-python-venv.service', in 0.77 seconds) subtest: guest python contract machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'command -v python3'"'"'' machine # [ 146.119337] runuser[1916]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 146.821572] runuser[1916]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'command -v python3'"'"'', in 0.73 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'command -v pip'"'"'' machine # [ 146.854547] runuser[1921]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 147.569497] runuser[1921]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'command -v pip'"'"'', in 0.75 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'pip install --no-index --no-deps --quiet --dry-run pip'"'"'' machine # [ 147.597258] runuser[1926]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 152.971726] runuser[1926]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'pip install --no-index --no-deps --quiet --dry-run pip'"'"'', in 5.40 seconds) (finished: subtest: guest python contract, in 6.88 seconds) subtest: brain: credentials only, never a model pin machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- '! test -e /var/lib/hermes/.hermes/.model-seeded' machine # [ 153.002246] runuser[1931]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 153.658629] runuser[1931]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- '! test -e /var/lib/hermes/.hermes/.model-seeded', in 0.69 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'grep -q OPENROUTER_API_KEY /var/lib/hermes/.hermes/.env'"'"'' machine # [ 153.691691] runuser[1936]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 154.396088] runuser[1936]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'grep -q OPENROUTER_API_KEY /var/lib/hermes/.hermes/.env'"'"'', in 0.74 seconds) (finished: subtest: brain: credentials only, never a model pin, in 1.43 seconds) subtest: virtiofs uid translation: guest 1000 <-> runtime host uid machine: must succeed: id -u alice machine: (finished: must succeed: id -u alice, in 0.02 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'id -u'"'"'' machine # [ 154.449591] runuser[1944]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 155.205222] runuser[1944]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'id -u'"'"'', in 0.79 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'touch ~/xfer-marker'"'"'' machine # [ 155.242565] runuser[1949]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 155.948544] runuser[1949]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'touch ~/xfer-marker'"'"'', in 0.74 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'stat -c %u ~/xfer-marker'"'"'' machine # [ 155.979096] runuser[1954]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 156.721385] runuser[1954]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'stat -c %u ~/xfer-marker'"'"'', in 0.77 seconds) machine: must succeed: stat -c %u /home/alice/hermes/xfer-marker machine: (finished: must succeed: stat -c %u /home/alice/hermes/xfer-marker, in 0.02 seconds) (finished: subtest: virtiofs uid translation: guest 1000 <-> runtime host uid, in 2.35 seconds) subtest: spaces bridge: own VM passes the peer-CID gate machine: must succeed: id -u alice machine: (finished: must succeed: id -u alice, in 0.02 seconds) machine: must succeed: systemd-run --uid=alice --unit=fake-gw -- /nix/store/fyamkshpr5rrm5drs7m20ny1k403hqs9-socat-1.8.1.3/bin/socat UNIX-LISTEN:/run/user/1000/spaces-integration-gateway.sock,fork EXEC:'/nix/store/mp8s10fwm685azvvv1qq7zyf7iajjlj8-coreutils-9.11/bin/cat' machine # [ 156.792216] systemd[1]: Started [systemd-run] /nix/store/fyamkshpr5rrm5drs7m20ny1k403hqs9-socat-1.8.1.3/bin/socat UNIX-LISTEN:/run/user/1000/spaces-integration-gateway.sock,fork EXEC:/nix/store/mp8s10fwm685azvvv1qq7zyf7iajjlj8-coreutils-9.11/bin/cat. machine # Running as unit: fake-gw.service; invocation ID: 804da68cbcaf4a81b30df793b5c34ada machine: (finished: must succeed: systemd-run --uid=alice --unit=fake-gw -- /nix/store/fyamkshpr5rrm5drs7m20ny1k403hqs9-socat-1.8.1.3/bin/socat UNIX-LISTEN:/run/user/1000/spaces-integration-gateway.sock,fork EXEC:'/nix/store/mp8s10fwm685azvvv1qq7zyf7iajjlj8-coreutils-9.11/bin/cat', in 0.03 seconds) machine: waiting for success: test -S /run/user/1000/spaces-integration-gateway.sock machine: (finished: waiting for success: test -S /run/user/1000/spaces-integration-gateway.sock, in 1.03 seconds) machine: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'printf ping | socat -T 10 STDIO VSOCK-CONNECT:2:735578825'"'"'' machine # [ 157.857645] runuser[1978]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0) machine # [ 158.626398] systemd[1]: Created slice Slice /system/hermes-spaces-bridge-alice. machine # [ 158.629067] systemd[1]: Started spaces gateway bridge for hermes-alice (vsock:735577804:1391501652). machine # [ 158.666320] systemd[1]: hermes-spaces-bridge-alice@0-4097-2:735578825-735577804:1391501652.service: Deactivated successfully. machine # [ 158.680547] runuser[1978]: pam_unix(runuser:session): session closed for user alice machine: (finished: must succeed: runuser -u alice -- ssh -q -i /var/lib/hermes-microvm/alice/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/alice/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-alice -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/735577804 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none alice@hermes-alice -- 'bash -lc '"'"'printf ping | socat -T 10 STDIO VSOCK-CONNECT:2:735578825'"'"'', in 0.85 seconds) (finished: subtest: spaces bridge: own VM passes the peer-CID gate, in 1.94 seconds) subtest: spaces bridge: sibling VM is rejected at accept machine: must succeed: runuser -u bob -- ssh -q -i /var/lib/hermes-microvm/bob/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/bob/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-bob -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/2176202715 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none bob@hermes-bob -- 'bash -lc '"'"'printf ping | socat -T 10 STDIO VSOCK-CONNECT:2:735578825 2>&1 || true'"'"'' machine # [ 158.707064] runuser[1989]: pam_unix(runuser:session): session opened for user bob(uid=1001) by (uid=0) machine # [ 161.264089] systemd[1]: Started spaces gateway bridge for hermes-alice (vsock:2176202715:1911412638). machine # [ 161.288690] hermes-vsock-spaces-bridge[1994]: rejecting connection: peer cid 2176202715, expected 735577804 machine # [ 161.291204] systemd[1]: hermes-spaces-bridge-alice@1-2-2:735578825-2176202715:1911412638.service: Main process exited, code=exited, status=1/FAILURE machine # [ 161.293821] systemd[1]: hermes-spaces-bridge-alice@1-2-2:735578825-2176202715:1911412638.service: Failed with result 'exit-code'. machine # [ 161.313605] runuser[1989]: pam_unix(runuser:session): session closed for user bob machine: (finished: must succeed: runuser -u bob -- ssh -q -i /var/lib/hermes-microvm/bob/ssh/client_ed25519 -o UserKnownHostsFile=/var/lib/hermes-microvm/bob/ssh/known_hosts -o StrictHostKeyChecking=yes -o HostKeyAlias=hermes-bob -o ProxyCommand='/nix/store/axx9bvf0dmah41f39ds9xdkds1lsz6z9-systemd-261/lib/systemd/systemd-ssh-proxy vsock/2176202715 22' -o ProxyUseFdpass=yes -o ControlMaster=no -o ControlPath=none bob@hermes-bob -- 'bash -lc '"'"'printf ping | socat -T 10 STDIO VSOCK-CONNECT:2:735578825 2>&1 || true'"'"'', in 2.63 seconds) machine: waiting for success: journalctl -u 'hermes-spaces-bridge-alice@*' | grep -q 'rejecting connection: peer cid 2176202715' machine: (finished: waiting for success: journalctl -u 'hermes-spaces-bridge-alice@*' | grep -q 'rejecting connection: peer cid 2176202715', in 0.03 seconds) (finished: subtest: spaces bridge: sibling VM is rejected at accept, in 2.66 seconds) (finished: run the VM test script, in 163.29 seconds) test script finished in 163.34s cleanup kill QemuMachine (pid 45) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/rgnappqqc5vbq60gza5fflyk84sylwl6-python3-3.14.6/bin/python3.14) (finished: cleanup, in 0.56 seconds)