vm-test-run-hermes-vm
default.ciChecks.x86_64-linux.hermes-vm
· build #4
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.01 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 machine,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10machine: starting vm11machine: QEMU running (pid 45)12machine: waiting for unit multi-user.target13machine: waiting for the VM to finish booting14machine # Disk image does not exist, creating the virtualisation disk image...15machine # Formatting '/build/vm-state-machine/tmp.SQmrTR9kp6', fmt=raw size=2576980377616machine # mke2fs 1.47.4 (6-Mar-2025)17machine # Discarding device blocks: 0/6291456 done18machine # Creating filesystem with 6291456 4k blocks and 1572864 inodes19machine # Filesystem UUID: 4181f5a0-7189-4c17-a7a4-fef738d2115120machine # Superblock backups stored on blocks:21machine # 32768, 98304, 163840, 229376, 294912, 819200, 884736, 1605632, 2654208,22machine # 409600023machine # 24machine # Allocating group tables: 0/192 done25machine # Writing inode tables: 0/192 done26machine # Creating journal (32768 blocks): done27machine # Writing superblocks and filesystem accounting information: 0/192 done28machine # 29machine # Virtualisation disk image created.30machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)31machine # 32machine # 33machine # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+BEFD1920+BEF31920 CA0034machine # Press Ctrl-B to configure iPXE (PCI 00:03.0)...35machine # 36machine # 37machine # 38machine # 39machine # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM BEFD1920 BEF31920 CB0040machine # Press Ctrl-B to configure iPXE (PCI 00:09.0)...41machine # 42machine # 43machine # Booting from ROM...44machine # Probing EDD (edd=off to disable)... ok45machine # [ 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 202646machine # [ 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=tty047machine # [ 0.000000] BIOS-provided physical RAM map:48machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable49machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdafff] usable52machine # [ 0.000000] BIOS-e820: [mem 0x00000000bffdb000-0x00000000bfffffff] reserved53machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved54machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved55machine # [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable56machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved57machine # [ 0.000000] NX (Execute Disable) protection: active58machine # [ 0.000000] APIC: Static calls initialized59machine # [ 0.000000] SMBIOS 2.8 present.60machine # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201461machine # [ 0.000000] DMI: Memory slots populated: 1/162machine # [ 0.000000] Hypervisor detected: KVM63machine # [ 0.000000] last_pfn = 0xbffdb max_arch_pfn = 0x40000000064machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0065machine # [ 0.000001] kvm-clock: using sched offset of 760200686 cycles66machine # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns67machine # [ 0.000007] tsc: Detected 4192.076 MHz processor68machine # [ 0.000927] last_pfn = 0x240000 max_arch_pfn = 0x40000000069machine # [ 0.000969] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs70machine # [ 0.000973] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT71machine # [ 0.001030] last_pfn = 0xbffdb max_arch_pfn = 0x40000000072machine # [ 0.003131] found SMP MP-table at [mem 0x000f5470-0x000f547f]73machine # [ 0.003152] Using GB pages for direct mapping74machine # [ 0.003296] RAMDISK: [mem 0xbe3b0000-0xbffcffff]75machine # [ 0.003305] ACPI: Early table checksum verification disabled76machine # [ 0.003310] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )77machine # [ 0.003315] ACPI: RSDT 0x00000000BFFE24E3 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.003321] ACPI: FACP 0x00000000BFFE237F 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.003330] ACPI: DSDT 0x00000000BFFE0040 00233F (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.003333] ACPI: FACS 0x00000000BFFE0000 00004081machine # [ 0.003336] ACPI: APIC 0x00000000BFFE23F3 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001)82machine # [ 0.003338] ACPI: HPET 0x00000000BFFE2483 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)83machine # [ 0.003340] ACPI: WAET 0x00000000BFFE24BB 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)84machine # [ 0.003342] ACPI: Reserving FACP table memory at [mem 0xbffe237f-0xbffe23f2]85machine # [ 0.003343] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe237e]86machine # [ 0.003344] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f]87machine # [ 0.003345] ACPI: Reserving APIC table memory at [mem 0xbffe23f3-0xbffe2482]88machine # [ 0.003345] ACPI: Reserving HPET table memory at [mem 0xbffe2483-0xbffe24ba]89machine # [ 0.003346] ACPI: Reserving WAET table memory at [mem 0xbffe24bb-0xbffe24e2]90machine # [ 0.003790] No NUMA configuration found91machine # [ 0.003791] Faking a node at [mem 0x0000000000000000-0x000000023fffffff]92machine # [ 0.003796] NODE_DATA(0) allocated [mem 0x23fffa780-0x23ffffcff]93machine # [ 0.003910] Zone ranges:94machine # [ 0.003911] DMA [mem 0x0000000000001000-0x0000000000ffffff]95machine # [ 0.003913] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]96machine # [ 0.003914] Normal [mem 0x0000000100000000-0x000000023fffffff]97machine # [ 0.003915] Device empty98machine # [ 0.003916] Movable zone start for each node99machine # [ 0.003917] Early memory node ranges100machine # [ 0.003917] node 0: [mem 0x0000000000001000-0x000000000009efff]101machine # [ 0.003918] node 0: [mem 0x0000000000100000-0x00000000bffdafff]102machine # [ 0.003920] node 0: [mem 0x0000000100000000-0x000000023fffffff]103machine # [ 0.003921] Initmem setup node 0 [mem 0x0000000000001000-0x000000023fffffff]104machine # [ 0.003944] On node 0, zone DMA: 1 pages in unavailable ranges105machine # [ 0.004258] On node 0, zone DMA: 97 pages in unavailable ranges106machine # [ 0.164059] On node 0, zone Normal: 37 pages in unavailable ranges107machine # [ 0.164951] ACPI: PM-Timer IO Port: 0x608108machine # [ 0.164972] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])109machine # [ 0.165012] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23110machine # [ 0.165016] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)111machine # [ 0.165018] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)112machine # [ 0.165020] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)113machine # [ 0.165021] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)114machine # [ 0.165022] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)115machine # [ 0.165025] ACPI: Using ACPI (MADT) for SMP configuration information116machine # [ 0.165027] ACPI: HPET id: 0x8086a201 base: 0xfed00000117machine # [ 0.165033] TSC deadline timer available118machine # [ 0.165038] CPU topo: Max. logical packages: 1119machine # [ 0.165039] CPU topo: Max. logical dies: 1120machine # [ 0.165039] CPU topo: Max. dies per package: 1121machine # [ 0.165044] CPU topo: Max. threads per core: 1122machine # [ 0.165045] CPU topo: Num. cores per package: 4123machine # [ 0.165045] CPU topo: Num. threads per package: 4124machine # [ 0.165046] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs125machine # [ 0.165072] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()126machine # [ 0.165090] kvm-guest: KVM setup pv remote TLB flush127machine # [ 0.165099] kvm-guest: setup PV sched yield128machine # [ 0.165121] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]129machine # [ 0.165124] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]130machine # [ 0.165125] PM: hibernation: Registered nosave memory: [mem 0xbffdb000-0xffffffff]131machine # [ 0.165128] [mem 0xc0000000-0xfeffbfff] available for PCI devices132machine # [ 0.165130] Booting paravirtualized kernel on KVM133machine # [ 0.165135] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns134machine # [ 0.169336] setup_percpu: NR_CPUS:384 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1135machine # [ 0.171847] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u524288136machine # [ 0.171908] kvm-guest: PV spinlocks enabled137machine # [ 0.171910] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)138machine # [ 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=tty0139machine # [ 0.172023] Unknown kernel command line parameters "regInfo=/nix/store/z4cn28zpywjppvs02wd5cl17x248crq6-closure-info/registration", will be passed to user space.140machine # [ 0.172225] random: crng init done141machine # [ 0.172227] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes142machine # [ 0.181994] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)143machine # [ 0.186653] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)144machine # [ 0.186742] software IO TLB: area num 4.145machine # [ 0.267831] Fallback order for Node 0: 0146machine # [ 0.267843] Built 1 zonelists, mobility grouping on. Total pages: 2097017147machine # [ 0.267845] Policy zone: Normal148machine # [ 0.270638] mem auto-init: stack:all(zero), heap alloc:on, heap free:off149machine # [ 0.299326] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1150machine # [ 0.339694] allocated 16777216 bytes of page_ext151machine # [ 0.360177] ftrace: allocating 48619 entries in 192 pages152machine # [ 0.360182] ftrace: allocated 192 pages with 2 groups153machine # [ 0.364268] Dynamic Preempt: lazy154machine # [ 0.364475] rcu: Preemptible hierarchical RCU implementation.155machine # [ 0.364476] rcu: RCU event tracing is enabled.156machine # [ 0.364477] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4.157machine # [ 0.364478] Trampoline variant of Tasks RCU enabled.158machine # [ 0.364479] Rude variant of Tasks RCU enabled.159machine # [ 0.364479] Tracing variant of Tasks RCU enabled.160machine # [ 0.364480] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.161machine # [ 0.364481] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4162machine # [ 0.364511] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.163machine # [ 0.364513] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.164machine # [ 0.364515] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.165machine # [ 0.372952] NR_IRQS: 24832, nr_irqs: 456, preallocated irqs: 16166machine # [ 0.376340] rcu: srcu_init: Setting srcu_struct sizes based on contention.167machine # [ 0.376350] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns168machine # [ 0.376554] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)169machine # [ 0.389405] Console: colour VGA+ 80x25170machine # [ 0.389410] printk: legacy console [tty0] enabled171machine # [ 0.475059] printk: legacy console [ttyS0] enabled172machine # [ 0.817180] ACPI: Core revision 20250807173machine # [ 0.818534] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns174machine # [ 0.820925] APIC: Switch to symmetric I/O mode setup175machine # [ 0.822434] x2apic enabled176machine # [ 0.823630] APIC: Switched APIC routing to: physical x2apic177machine # [ 0.825071] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask()178machine # [ 0.827001] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself()179machine # [ 0.829302] kvm-guest: setup PV IPIs180machine # [ 0.831441] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1181machine # [ 0.833059] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns182machine # [ 0.835710] Calibrating delay loop (skipped) preset value.. 8384.15 BogoMIPS (lpj=4192076)183machine # [ 0.836706] x86/cpu: User Mode Instruction Prevention (UMIP) activated184machine # [ 0.837706] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127185machine # [ 0.838706] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0186machine # [ 0.838715] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto187machine # [ 0.839710] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl188machine # [ 0.840706] Spectre V2 : Mitigation: Enhanced / Automatic IBRS189machine # [ 0.840710] Speculative Return Stack Overflow: Mitigation: Safe RET190machine # [ 0.841706] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization191machine # [ 0.842706] Spectre V2 : Enabling IBPB for BPF192machine # [ 0.842711] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier193machine # [ 0.843706] active return thunk: srso_alias_return_thunk194machine # [ 0.843740] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'195machine # [ 0.844706] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'196machine # [ 0.844709] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'197machine # [ 0.845706] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'198machine # [ 0.846706] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'199machine # [ 0.846706] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'200machine # [ 0.846706] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'201machine # [ 0.846709] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'202machine # [ 0.847706] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'203machine # [ 0.848706] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204machine # [ 0.848709] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205machine # [ 0.849706] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206machine # [ 0.849706] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207machine # [ 0.849706] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208machine # [ 0.849706] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16209machine # [ 0.849706] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24210machine # [ 0.849706] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.211machine # [ 0.860706] Freeing SMP alternatives memory: 44K212machine # [ 0.860706] pid_max: default: 32768 minimum: 301213machine # [ 0.860824] LSM: initializing lsm=capability,landlock,yama,bpf,ima214machine # [ 0.861706] landlock: Up and running.215machine # [ 0.861706] Yama: becoming mindful.216machine # [ 0.861706] LSM support for eBPF active217machine # [ 0.861706] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)218machine # [ 0.862706] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)219machine # [ 0.862706] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)220machine # [ 0.863301] Performance Events: Fam17h+ core perfctr, AMD PMU driver.221machine # [ 0.863714] ... version: 2222machine # [ 0.864711] ... bit width: 48223machine # [ 0.865766] ... generic counters: 6224machine # [ 0.866711] ... generic bitmap: 000000000000003f225machine # [ 0.867711] ... fixed-purpose counters: 0226machine # [ 0.868711] ... fixed-purpose bitmap: 0000000000000000227machine # [ 0.869710] ... value mask: 0000ffffffffffff228machine # [ 0.870711] ... max period: 00007fffffffffff229machine # [ 0.871711] ... global_ctrl mask: 000000000000003f230machine # [ 0.872911] signal: max sigframe size: 3376231machine # [ 0.873783] rcu: Hierarchical SRCU implementation.232machine # [ 0.874715] rcu: Max phase no-delay instances is 400.233machine # [ 0.875936] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level234machine # [ 0.880881] smp: Bringing up secondary CPUs ...235machine # [ 0.882214] smpboot: x86: Booting SMP configuration:236machine # [ 0.882722] .... node #0, CPUs: #1 #2 #3237machine # [ 0.885163] smp: Brought up 1 node, 4 CPUs238machine # [ 0.886716] smpboot: Total of 4 processors activated (33536.60 BogoMIPS)239machine # [ 0.889873] Memory: 8074192K/8388068K available (17162K kernel code, 2721K rwdata, 13548K rodata, 3640K init, 3012K bss, 290260K reserved, 0K cma-reserved)240machine # [ 0.895712] devtmpfs: initialized241machine # [ 0.896874] x86/mm: Memory block size: 128MB242machine # [ 0.906773] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear)243machine # [ 0.907801] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear).244machine # [ 0.908835] pinctrl core: initialized pinctrl subsystem245machine # [ 0.910246] PM: RTC time: 04:20:53, date: 2026-08-01246machine # [ 0.914742] NET: Registered PF_NETLINK/PF_ROUTE protocol family247machine # [ 0.917184] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations248machine # [ 0.917776] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations249machine # [ 0.919891] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations250machine # [ 0.920724] audit: initializing netlink subsys (disabled)251machine # [ 0.922057] thermal_sys: Registered thermal governor 'fair_share'252machine # [ 0.922060] thermal_sys: Registered thermal governor 'bang_bang'253machine # [ 0.923711] thermal_sys: Registered thermal governor 'step_wise'254machine # [ 0.923714] audit: type=2000 audit(1785558053.445:1): state=initialized audit_enabled=0 res=1255machine # [ 0.924710] thermal_sys: Registered thermal governor 'user_space'256machine # [ 0.924712] thermal_sys: Registered thermal governor 'power_allocator'257machine # [ 0.926712] cpuidle: using governor menu258machine # [ 0.939718] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5259machine # [ 0.941120] PCI: Using configuration type 1 for base access260machine # [ 0.941711] PCI: Using configuration type 1 for extended access261machine # [ 0.943785] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.262machine # [ 0.950807] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages263machine # [ 0.951706] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page264machine # [ 0.952706] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages265machine # [ 0.952711] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page266machine # [ 0.994706] ACPI: Added _OSI(Module Device)267machine # [ 0.994712] ACPI: Added _OSI(Processor Device)268machine # [ 0.995706] ACPI: Added _OSI(Processor Aggregator Device)269machine # [ 0.997138] ACPI: 1 ACPI AML tables successfully acquired and loaded270machine # [ 1.013005] ACPI: Interpreter enabled271machine # [ 1.014489] ACPI: PM: (supports S0 S3 S4 S5)272machine # [ 1.015574] ACPI: Using IOAPIC for interrupt routing273machine # [ 1.015706] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug274machine # [ 1.016711] PCI: Using E820 reservations for host bridge windows275machine # [ 1.017937] ACPI: Enabled 2 GPEs in block 00 to 0F276machine # [ 1.021706] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])277machine # [ 1.022718] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]278machine # [ 1.024226] acpiphp: Slot [3] registered279machine # [ 1.024745] acpiphp: Slot [4] registered280machine # [ 1.025747] acpiphp: Slot [5] registered281machine # [ 1.026748] acpiphp: Slot [6] registered282machine # [ 1.027706] acpiphp: Slot [7] registered283machine # [ 1.027706] acpiphp: Slot [8] registered284machine # [ 1.028747] acpiphp: Slot [9] registered285machine # [ 1.029760] acpiphp: Slot [10] registered286machine # [ 1.030754] acpiphp: Slot [11] registered287machine # [ 1.031706] acpiphp: Slot [12] registered288machine # [ 1.031706] acpiphp: Slot [13] registered289machine # [ 1.031757] acpiphp: Slot [14] registered290machine # [ 1.032706] acpiphp: Slot [15] registered291machine # [ 1.032752] acpiphp: Slot [16] registered292machine # [ 1.033706] acpiphp: Slot [17] registered293machine # [ 1.034760] acpiphp: Slot [18] registered294machine # [ 1.035706] acpiphp: Slot [19] registered295machine # [ 1.035706] acpiphp: Slot [20] registered296machine # [ 1.035746] acpiphp: Slot [21] registered297machine # [ 1.036706] acpiphp: Slot [22] registered298machine # [ 1.036750] acpiphp: Slot [23] registered299machine # [ 1.037706] acpiphp: Slot [24] registered300machine # [ 1.038754] acpiphp: Slot [25] registered301machine # [ 1.039742] acpiphp: Slot [26] registered302machine # [ 1.040743] acpiphp: Slot [27] registered303machine # [ 1.041743] acpiphp: Slot [28] registered304machine # [ 1.042772] acpiphp: Slot [29] registered305machine # [ 1.043747] acpiphp: Slot [30] registered306machine # [ 1.044746] acpiphp: Slot [31] registered307machine # [ 1.045737] PCI host bridge to bus 0000:00308machine # [ 1.047733] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]309machine # [ 1.048712] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]310machine # [ 1.050712] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]311machine # [ 1.052713] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]312machine # [ 1.054713] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window]313machine # [ 1.056714] pci_bus 0000:00: root bus resource [bus 00-ff]314machine # [ 1.059310] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint315machine # [ 1.061702] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint316machine # [ 1.062655] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint317machine # [ 1.063706] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]318machine # [ 1.064706] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk319machine # [ 1.065706] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk320machine # [ 1.065706] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk321machine # [ 1.065706] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk322machine # [ 1.067015] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 1.067706] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]324machine # [ 1.068706] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint325machine # [ 1.070285] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI326machine # [ 1.070725] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB327machine # [ 1.071706] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint328machine # [ 1.076706] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]329machine # [ 1.076706] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]330machine # [ 1.077706] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]331machine # [ 1.077706] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]332machine # [ 1.083706] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint333machine # [ 1.084731] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]334machine # [ 1.085706] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]335machine # [ 1.085760] pci 0000:00:03.0: BAR 4 [mem 0xe0000000000-0xe0000003fff 64bit pref]336machine # [ 1.086706] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]337machine # [ 1.087540] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint338machine # [ 1.089724] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]339machine # [ 1.090706] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]340machine # [ 1.090772] pci 0000:00:04.0: BAR 4 [mem 0xe0000004000-0xe0000007fff 64bit pref]341machine # [ 1.092748] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint342machine # [ 1.093725] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]343machine # [ 1.094706] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]344machine # [ 1.095760] pci 0000:00:05.0: BAR 4 [mem 0xe0000008000-0xe000000bfff 64bit pref]345machine # [ 1.096706] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint346machine # [ 1.098724] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]347machine # [ 1.099706] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]348machine # [ 1.099761] pci 0000:00:06.0: BAR 4 [mem 0xe000000c000-0xe000000ffff 64bit pref]349machine # [ 1.100706] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint350machine # [ 1.102706] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]351machine # [ 1.102706] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]352machine # [ 1.102706] pci 0000:00:07.0: BAR 4 [mem 0xe0000010000-0xe0000013fff 64bit pref]353machine # [ 1.104706] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint354machine # [ 1.105706] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]355machine # [ 1.106725] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]356machine # [ 1.107706] pci 0000:00:08.0: BAR 4 [mem 0xe0000014000-0xe0000017fff 64bit pref]357machine # [ 1.108706] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint358machine # [ 1.110706] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]359machine # [ 1.110706] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]360machine # [ 1.110706] pci 0000:00:09.0: BAR 4 [mem 0xe0000018000-0xe000001bfff 64bit pref]361machine # [ 1.111706] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]362machine # [ 1.112706] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint363machine # [ 1.114706] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]364machine # [ 1.114760] pci 0000:00:0a.0: BAR 4 [mem 0xe000001c000-0xe000001ffff 64bit pref]365machine # [ 1.116706] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint366machine # [ 1.117706] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]367machine # [ 1.117706] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]368machine # [ 1.118706] pci 0000:00:0b.0: BAR 4 [mem 0xe0000020000-0xe0000023fff 64bit pref]369machine # [ 1.119706] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint370machine # [ 1.121706] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]371machine # [ 1.122706] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]372machine # [ 1.122706] pci 0000:00:0c.0: BAR 4 [mem 0xe0000024000-0xe0000027fff 64bit pref]373machine # [ 1.125055] ACPI: PCI: Interrupt link LNKA configured for IRQ 10374machine # [ 1.125706] ACPI: PCI: Interrupt link LNKB configured for IRQ 10375machine # [ 1.126901] ACPI: PCI: Interrupt link LNKC configured for IRQ 11376machine # [ 1.127706] ACPI: PCI: Interrupt link LNKD configured for IRQ 11377machine # [ 1.127706] ACPI: PCI: Interrupt link LNKS configured for IRQ 9378machine # [ 1.130040] iommu: Default domain type: Translated379machine # [ 1.130706] iommu: DMA domain TLB invalidation policy: lazy mode380machine # [ 1.132046] ACPI: bus type USB registered381machine # [ 1.132706] usbcore: registered new interface driver usbfs382machine # [ 1.132706] usbcore: registered new interface driver hub383machine # [ 1.132724] usbcore: registered new device driver usb384machine # [ 1.136397] NetLabel: Initializing385machine # [ 1.136706] NetLabel: domain hash size = 128386machine # [ 1.136706] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO387machine # [ 1.136757] NetLabel: unlabeled traffic allowed by default388machine # [ 1.137706] PCI: Using ACPI for IRQ routing389machine # [ 1.137890] pci 0000:00:02.0: vgaarb: setting as boot VGA device390machine # [ 1.138706] pci 0000:00:02.0: vgaarb: bridge control possible391machine # [ 1.138706] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none392machine # [ 1.140715] vgaarb: loaded393machine # [ 1.141706] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0394machine # [ 1.141706] hpet0: 3 comparators, 64-bit 100.000000 MHz counter395machine # [ 1.145767] clocksource: Switched to clocksource kvm-clock396machine # [ 1.148408] VFS: Disk quotas dquot_6.6.0397machine # [ 1.155834] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)398machine # [ 1.161171] pnp: PnP ACPI init399machine # [ 1.163974] pnp: PnP ACPI: found 6 devices400machine # [ 1.193737] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns401machine # [ 1.210774] clocksource: Switched to clocksource acpi_pm402machine # [ 1.212610] NET: Registered PF_INET protocol family403machine # [ 1.218067] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)404machine # [ 1.253227] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)405machine # [ 1.255497] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)406machine # [ 1.257502] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)407machine # [ 1.267132] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)408machine # [ 1.269173] TCP: Hash tables configured (established 65536 bind 65536)409machine # [ 1.271037] MPTCP token hash table entries: 8192 (order: 6, 196608 bytes, linear)410machine # [ 1.273180] UDP hash table entries: 4096 (order: 6, 262144 bytes, linear)411machine # [ 1.277495] UDP-Lite hash table entries: 4096 (order: 6, 262144 bytes, linear)412machine # [ 1.281079] NET: Registered PF_UNIX/PF_LOCAL protocol family413machine # [ 1.282689] NET: Registered PF_XDP protocol family414machine # [ 1.284014] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]415machine # [ 1.285570] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]416machine # [ 1.290138] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]417machine # [ 1.291891] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]418machine # [ 1.296653] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window]419machine # [ 1.301749] pci 0000:00:01.0: PIIX3: Enabling Passive Release420machine # [ 1.303286] pci 0000:00:00.0: Limiting direct PCI/PCI transfers421machine # [ 1.309792] ACPI: \_SB_.LNKD: Enabled at IRQ 11422machine # [ 1.316319] PCI: CLS 0 bytes, default 64423machine # [ 1.320582] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)424machine # [ 1.320935] Trying to unpack rootfs image as initramfs...425machine # [ 1.322235] software IO TLB: mapped [mem 0x00000000ba3b0000-0x00000000be3b0000] (64MB)426machine # [ 1.322427] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d2409b60, max_idle_ns: 440795393887 ns427machine # [ 1.369610] Initialise system trusted keyrings428machine # [ 1.374878] workingset: timestamp_bits=40 max_order=21 bucket_order=0429machine # [ 1.391953] Key type asymmetric registered430machine # [ 1.405133] Asymmetric key parser 'x509' registered431machine # [ 1.406554] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)432machine # [ 1.420814] io scheduler mq-deadline registered433machine # [ 1.422051] io scheduler kyber registered434machine # [ 1.444774] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled435machine # [ 1.446814] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A436machine # [ 1.456737] Linux agpgart interface v0.103437machine # [ 1.457955] ACPI: bus type drm_connector registered438machine # [ 1.468090] usbcore: registered new interface driver usbserial_generic439machine # [ 1.469826] usbserial: USB Serial support registered for generic440machine # [ 1.471431] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.441machine # [ 1.471431] Please enable it if your BIOS has the CPPC option.442machine # [ 1.474883] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled443machine # [ 1.477042] drop_monitor: Initializing network drop monitor service444machine # [ 1.478879] NET: Registered PF_INET6 protocol family445machine # [ 1.493103] Segment Routing with IPv6446machine # [ 1.494177] In-situ OAM (IOAM) with IPv6447machine # [ 1.507944] IPI shorthand broadcast: enabled448machine # [ 1.514295] sched_clock: Marking stable (1058021112, 455356643)->(1941801980, -428424225)449machine # [ 1.528854] registered taskstats version 1450machine # [ 1.530324] Loading compiled-in X.509 certificates451machine # [ 1.554738] Demotion targets for Node 0: null452machine # [ 1.559845] Key type .fscrypt registered453machine # [ 1.560930] Key type fscrypt-provisioning registered454machine # [ 1.562398] ima: No TPM chip found, activating TPM-bypass!455machine # [ 1.563809] ima: Allocated hash algorithm: sha1456machine # [ 1.565053] ima: No architecture policies found457machine # [ 1.575733] PM: Magic number: 6:778:311458machine # [ 1.577972] RAS: Correctable Errors collector initialized.459machine # [ 1.586880] clk: Disabling unused clocks460machine # [ 1.587962] PM: genpd: Disabling unused power domains461machine # [ 1.721614] Freeing initrd memory: 28800K462machine # [ 1.727201] Freeing unused decrypted memory: 2028K463machine # [ 1.731588] Freeing unused kernel image (initmem) memory: 3640K464machine # [ 1.733330] Write protecting the kernel read-only data: 32768k465machine # [ 1.736930] Freeing unused kernel image (text/rodata gap) memory: 1268K466machine # [ 1.739623] Freeing unused kernel image (rodata/data gap) memory: 788K467machine # [ 1.787271] x86/mm: Checked W+X mappings: passed, no W+X pages found.468machine # [ 1.789162] Run /init as init process469machine # [ 1.806187] systemd[1]: Inserted module 'autofs4'470machine # [ 1.860242] fuse: init (API version 7.45)471machine # [ 1.898123] ACPI: \_SB_.LNKC: Enabled at IRQ 10472machine # [ 1.908405] ACPI: \_SB_.LNKA: Enabled at IRQ 10473machine # [ 1.912800] ACPI: \_SB_.LNKB: Enabled at IRQ 11474machine # [ 1.983559] systemd[1]: Successfully made /usr/ read-only.475machine # [ 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)476machine # [ 2.355329] systemd[1]: Detected virtualization kvm.477machine # [ 2.356866] systemd[1]: Detected architecture x86-64.478machine # [ 2.358219] systemd[1]: Running in initrd.479machine # [ 2.359906] systemd[1]: Initializing machine ID from random generator.480machine # [ 2.362080] systemd[1]: Hostname set to <machine>.481machine # [ 2.525048] systemd[1]: bpf-restrict-fs: LSM BPF program attached482machine # [ 2.606263] systemd[1]: Queued start job for default target Initrd Default Target.483machine # [ 2.655039] systemd[1]: Created slice Slice /system/modprobe.484machine # [ 2.659056] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.485machine # [ 2.663832] systemd[1]: Expecting device /dev/disk/by-label/nixos...486machine # [ 2.665562] systemd[1]: Reached target Path Units.487machine # [ 2.666994] systemd[1]: Reached target Slice Units.488machine # [ 2.668427] systemd[1]: Reached target Swaps.489machine # [ 2.669786] systemd[1]: Reached target Timer Units.490machine # [ 2.671347] systemd[1]: Listening on D-Bus System Message Bus Socket.491machine # [ 2.673303] systemd[1]: Listening on Journal Socket (/dev/log).492machine # [ 2.675106] systemd[1]: Listening on Journal Sockets.493machine # [ 2.679741] systemd[1]: Listening on udev Control Socket.494machine # [ 2.681324] systemd[1]: Listening on udev Kernel Socket.495machine # [ 2.685859] systemd[1]: Reached target Socket Units.496machine # [ 2.694821] systemd[1]: Starting Create List of Static Device Nodes...497machine # [ 2.700821] systemd[1]: Starting Load Kernel Module 9pnet_virtio...498machine # [ 2.709834] systemd[1]: Starting Load Kernel Module configfs...499machine # [ 2.736844] systemd[1]: Starting Journal Service...500machine # [ 2.797006] systemd[1]: Starting Load Kernel Modules...501machine # [ 2.798677] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os502machine # [ 2.805829] systemd[1]: Starting Coldplug All udev Devices...503machine # [ 2.812088] systemd[1]: Finished Create List of Static Device Nodes.504machine # [ 2.814636] systemd[1]: modprobe@configfs.service: Deactivated successfully.505machine # [ 2.820034] systemd[1]: Finished Load Kernel Module configfs.506machine # [ 2.838794] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config507machine # [ 2.846849] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...508machine # [ 2.852261] systemd-journald[92]: Collecting audit messages is disabled.509machine # [ 2.917027] netfs: FS-Cache loaded510machine # [ 2.926342] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.511machine # [ 2.932143] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.512machine # [ 2.955875] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev513machine # [ 2.956418] systemd[1]: Starting Create Static Device Nodes in /dev...514machine # [ 2.997675] systemd[1]: Finished Load Kernel Modules.515machine # [ 2.998912] 9pnet: Installing 9P2000 support516machine # [ 2.551829] systemd-modules-load[94]: Using 4 probe threads517machine # [ 3.006992] systemd[1]: Finished Create Static Device Nodes in /dev.518machine # [ 2.554879] systemd-modules-load[94]: Inserted module 'virtio_balloon'519machine # [ 3.011092] systemd[1]: Started Journal Service.520machine # [ 2.560786] systemd-modules-load[94]: Inserted module 'virtio_gpu'521machine # [ 2.567870] systemd-modules-load[94]: Inserted module 'dm_mod'522machine # [ 2.569648] systemd[1]: Reached target Preparation for Local File Systems.523machine # [ 2.571666] systemd[1]: Reached target Local File Systems.524machine # [ 2.575891] systemd[1]: Starting Apply Kernel Variables...525machine # [ 2.577391] systemd[1]: Starting Create System Files and Directories...526machine # [ 2.581725] systemd[1]: Starting Rule-based Manager for Device Events and Files...527machine # [ 2.602216] systemd[1]: Finished Apply Kernel Variables.528machine # [ 2.614639] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.529machine # [ 2.620195] systemd[1]: Finished Load Kernel Module 9pnet_virtio.530machine # [ 2.630155] systemd[1]: Finished Create System Files and Directories.531machine # [ 2.653626] systemd-udevd[107]: Using default interface naming scheme 'v261'.532machine # [ 2.681923] systemd[1]: Started Rule-based Manager for Device Events and Files.533machine # [ 2.694874] systemd[1]: Finished Coldplug All udev Devices.534machine # [ 2.699278] systemd[1]: Reached target System Initialization.535machine # [ 2.703601] systemd[1]: Reached target Basic System.536machine # [ 3.085788] systemd[1]: Starting Virtual Console Setup...537machine # [ 3.131831] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones.538machine # [ 3.142425] systemd[1]: Finished Virtual Console Setup.539machine # [ 3.613527] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12540machine # [ 3.616254] serio: i8042 KBD port at 0x60,0x64 irq 1541machine # [ 3.617301] serio: i8042 AUX port at 0x60,0x64 irq 12542machine # [ 3.677361] virtio_blk virtio5: 4/0/0 default/read/poll queues543machine # [ 3.682270] virtio_blk virtio5: [vda] 50331648 512-byte logical blocks (25.8 GB/24.0 GiB)544machine # [ 3.695748] uhci_hcd 0000:00:01.2: UHCI Host Controller545machine # [ 3.698594] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1546machine # [ 3.700128] uhci_hcd 0000:00:01.2: detected 2 ports547machine # [ 3.703246] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100548machine # [ 3.704677] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18549machine # [ 3.707596] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1550machine # [ 3.709615] usb usb1: Product: UHCI Host Controller551machine # [ 3.710626] usb usb1: Manufacturer: Linux 6.18.40 uhci_hcd552machine # [ 3.712347] usb usb1: SerialNumber: 0000:00:01.2553machine # [ 3.713562] hub 1-0:1.0: USB hub found554machine # [ 3.714441] hub 1-0:1.0: 2 ports detected555machine # [ 3.730438] SCSI subsystem initialized556machine # [ 3.285320] (udev-worker)[135]: Network interface NamePolicy= disabled on kernel command line.557machine # [ 3.319487] (udev-worker)[140]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.558machine # [ 3.325272] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line.559machine # [ 3.363045] systemd[1]: Found device /dev/disk/by-label/nixos.560machine # [ 3.366233] systemd[1]: Reached target Initrd Root Device.561machine # [ 3.377394] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...562machine # [ 3.443979] systemd-fsck[151]: nixos: clean, 12/1572864 files, 142772/6291456 blocks563machine # [ 3.457527] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.564machine # [ 3.466356] systemd[1]: Mounting /sysroot...565machine # [ 3.952415] usb 1-1: new full-speed USB device number 2 using uhci_hcd566machine # [ 4.017645] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0567machine # [ 4.098875] scsi host0: ata_piix568machine # [ 4.102202] scsi host1: ata_piix569machine # [ 4.103008] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0570machine # [ 4.104488] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0571machine # [ 4.141759] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00572machine # [ 4.143414] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10573machine # [ 4.145071] usb 1-1: Product: QEMU USB Tablet574machine # [ 4.146137] usb 1-1: Manufacturer: QEMU575machine # [ 4.147000] usb 1-1: SerialNumber: 28754-0000:00:01.2-1576machine # [ 4.184751] hid: raw HID events driver (C) Jiri Kosina577machine # [ 4.266953] ata2: found unknown device (class 0)578machine # [ 4.268727] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100579machine # [ 4.275080] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5580machine # [ 4.365947] usbcore: registered new interface driver usbhid581machine # [ 4.367122] usbhid: USB HID core driver582machine # [ 4.534773] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray583machine # [ 4.603642] cdrom: Uniform CD-ROM driver Revision: 3.20584machine # [ 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/input2585machine # [ 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/input0586machine # [ 4.805915] EXT4-fs (vda): mounted filesystem 4181f5a0-7189-4c17-a7a4-fef738d21151 r/w with ordered data mode. Quota mode: none.587machine # [ 4.360307] systemd[1]: Mounted /sysroot.588machine # [ 4.366629] systemd[1]: Reached target Initrd Root File System.589machine # [ 4.370674] systemd[1]: Starting Mountpoints Configured in the Real Root...590machine # [ 4.382899] systemd-sysroot-fstab-check[169]: /sysroot should be mounted in the initrd, will request daemon-reload.591machine # [ 4.389658] systemd[1]: Mounting /sysroot/nix/.ro-store...592machine # [ 4.413271] systemd[1]: Mounting /sysroot/nix/.rw-store...593machine # [ 4.420973] systemd[1]: Mounting /sysroot/run...594machine # [ 4.427275] systemd[1]: Mounting /sysroot/tmp/shared...595machine # [ 4.436538] systemd[1]: Mounting /sysroot/tmp/xchg...596machine # [ 4.893086] 9p: Installing v9fs 9p2000 file system support597machine # [ 4.446060] systemd[1]: Mounted /sysroot/nix/.rw-store.598machine # [ 4.450428] systemd[1]: Mounted /sysroot/run.599machine # [ 4.462422] systemd[1]: Starting rw-sysroot-nix-store.service...600machine # [ 4.464458] systemd[1]: Mounted /sysroot/nix/.ro-store.601machine # [ 4.465634] systemd[1]: Mounted /sysroot/tmp/xchg.602machine # [ 4.467073] systemd[1]: Reload requested from client PID 169 ('systemd-sysroot') (unit initrd-parse-etc.service)...603machine # [ 4.470524] systemd[1]: Reloading...604machine # [ 4.608497] systemd[1]: Reloading finished in 143 ms.605machine # [ 4.646450] systemd-sysroot-fstab-check[169]: Requesting initrd-fs.target/start/replace...606machine # [ 4.651414] systemd[1]: Mounted /sysroot/tmp/shared.607machine # [ 4.661615] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.608machine # [ 4.663163] systemd[1]: Finished rw-sysroot-nix-store.service.609machine # [ 4.664480] systemd-sysroot-fstab-check[169]: Requesting swap.target/start/replace...610machine # [ 4.666093] systemd[1]: Starting rw-sysroot-nix-store.service...611machine # [ 4.667475] systemd[1]: initrd-parse-etc.service: Deactivated successfully.612machine # [ 4.672487] systemd[1]: Finished Mountpoints Configured in the Real Root.613machine # [ 4.674096] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.614machine # [ 4.679507] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.615machine # [ 4.680994] systemd[1]: Finished rw-sysroot-nix-store.service.616machine # [ 4.685431] systemd[1]: Mounting /sysroot/nix/store...617machine # [ 4.726251] systemd[1]: Mounted /sysroot/nix/store.618machine # [ 4.727317] systemd[1]: Reached target Initrd File Systems.619machine # [ 4.736776] systemd[1]: Starting Find NixOS closure...620machine # [ 4.739585] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...621machine # [ 4.762483] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.622machine # [ 4.845457] systemd[1]: Finished Find NixOS closure.623machine # [ 4.849573] systemd[1]: Reached target Initrd Default Target.624machine # [ 4.853545] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...625machine # [ 4.885314] systemd[1]: Stopped target Initrd Default Target.626machine # [ 4.890600] systemd[1]: Stopped target Basic System.627machine # [ 4.894694] systemd[1]: Stopped target Initrd Root Device.628machine # [ 4.897544] systemd[1]: Stopped target Path Units.629machine # [ 4.901550] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.630machine # [ 4.908554] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.631machine # [ 4.912555] systemd[1]: Stopped target Slice Units.632machine # [ 4.919515] systemd[1]: Stopped target Socket Units.633machine # [ 4.923590] systemd[1]: Stopped target System Initialization.634machine # [ 4.930715] systemd[1]: Stopped target Swaps.635machine # [ 4.933994] systemd[1]: Stopped target Timer Units.636machine # [ 4.935169] systemd[1]: dbus.socket: Deactivated successfully.637machine # [ 4.943506] systemd[1]: Closed D-Bus System Message Bus Socket.638machine # [ 4.952456] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.639machine # [ 4.956518] systemd[1]: Stopped Find NixOS closure.640machine # [ 4.960579] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio641machine # [ 4.972576] systemd[1]: Starting rw-sysroot-nix-store.service...642machine # [ 4.973966] systemd[1]: systemd-sysctl.service: Deactivated successfully.643machine # [ 4.977676] systemd[1]: Stopped Apply Kernel Variables.644machine # [ 4.984795] systemd[1]: systemd-modules-load.service: Deactivated successfully.645machine # [ 4.986508] systemd[1]: Stopped Load Kernel Modules.646machine # [ 4.987786] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.647machine # [ 4.989505] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.648machine # [ 4.991591] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.649machine # [ 4.994521] systemd[1]: Stopped Create System Files and Directories.650machine # [ 4.998583] systemd[1]: Stopped target Local File Systems.651machine # [ 5.000163] systemd[1]: Stopped target Preparation for Local File Systems.652machine # [ 5.002258] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.653machine # [ 5.009789] systemd[1]: Stopped Coldplug All udev Devices.654machine # [ 5.014518] systemd[1]: Stopping Rule-based Manager for Device Events and Files...655machine # [ 5.016661] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.656machine # [ 5.018237] systemd[1]: Stopped Virtual Console Setup.657machine # [ 5.020398] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.658machine # [ 5.022033] systemd[1]: Finished rw-sysroot-nix-store.service.659machine # [ 5.024277] systemd[1]: initrd-cleanup.service: Deactivated successfully.660machine # [ 5.025875] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.661machine # [ 5.027472] systemd[1]: systemd-udevd.service: Deactivated successfully.662machine # [ 5.028986] systemd[1]: Stopped Rule-based Manager for Device Events and Files.663machine # [ 5.030720] systemd[1]: systemd-udevd.service: Consumed 1.238s CPU time over 2.396s wall clock time, 26.1M memory peak.664machine # [ 5.038601] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.665machine # [ 5.041050] systemd[1]: Closed udev Control Socket.666machine # [ 5.042626] systemd[1]: Starting Cleanup udev Database...667machine # [ 5.044552] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.668machine # [ 5.046280] systemd[1]: Stopped Create Static Device Nodes in /dev.669machine # [ 5.047964] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.670machine # [ 5.049742] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.671machine # [ 5.051780] systemd[1]: kmod-static-nodes.service: Deactivated successfully.672machine # [ 5.054127] systemd[1]: Stopped Create List of Static Device Nodes.673machine # [ 5.055638] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.674machine # [ 5.057298] systemd[1]: Finished Cleanup udev Database.675machine # [ 5.058567] systemd[1]: Reached target Switch Root.676machine # [ 5.059851] systemd[1]: Starting NixOS Activation...677machine # [ 8.506590] initrd-nixos-activation-start[229]: booting system configuration /nix/store/xy310nsky7qs244nfvb3fnng14vg3rjx-nixos-system-machine-test678machine # [ 9.530435] initrd-nixos-activation-start[229]: running activation script...679machine # [ 14.998821] initrd-nixos-activation-start[252]: setting up /etc...680machine # [ 16.403590] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.681machine # [ 16.405633] systemd[1]: Finished NixOS Activation.682machine # [ 16.411188] systemd[1]: Starting Switch Root...683machine # [ 16.442258] systemd[1]: Switching root.684machine # [ 17.003984] systemd-journald[92]: Received SIGTERM from PID 1 (systemd).685machine # [ 19.513004] NET: Registered PF_VSOCK protocol family686machine # [ 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)687machine # [ 20.905692] systemd[1]: Detected virtualization kvm.688machine # [ 20.913909] systemd[1]: Detected architecture x86-64.689machine # [ 20.915084] systemd[1]: Detected first boot.690machine # [ 21.017568] systemd[1]: Initializing machine ID from random generator.691machine # [ 21.739436] systemd[1]: bpf-restrict-fs: LSM BPF program attached692machine # [ 23.911862] systemd[1]: Applying preset policy.693machine # [ 28.158676] systemd[1]: Populated /etc with preset unit settings.694machine: Guest root shell did not produce any data yet...695machine: To debug, enter the VM and run 'systemctl status backdoor.service'.696machine # [ 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.697machine # [ 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.698machine # [ 32.931404] systemd[1]: initrd-switch-root.service: Deactivated successfully.699machine # [ 32.940872] systemd[1]: Stopped initrd-switch-root.service.700machine # [ 32.952408] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.701machine # [ 32.955914] systemd[1]: Created slice Slice /system/getty.702machine # [ 32.958043] systemd[1]: Created slice Slice /system/microvm.703machine # [ 32.960337] systemd[1]: Created slice Slice /system/microvm-macvtap-interfaces.704machine # [ 32.963015] systemd[1]: Created slice Slice /system/microvm-pci-devices.705machine # [ 32.965420] systemd[1]: Created slice Slice /system/microvm-set-booted.706machine # [ 32.967688] systemd[1]: Created slice Slice /system/microvm-tap-interfaces.707machine # [ 32.970197] systemd[1]: Created slice Slice /system/microvm-virtiofsd.708machine # [ 32.972873] systemd[1]: Created slice User and Session Slice.709machine # [ 32.974462] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.710machine # [ 32.976265] systemd[1]: Started Forward Password Requests to Wall Directory Watch.711machine # [ 32.980838] systemd[1]: Expecting device /dev/hvc0...712machine # [ 32.982349] systemd[1]: Expecting device /dev/ttyS0...713machine # [ 32.983505] systemd[1]: Reached target Local Encrypted Volumes.714machine # [ 32.984839] systemd[1]: Stopped target initrd-fs.target.715machine # [ 32.986875] systemd[1]: Stopped target initrd-root-fs.target.716machine # [ 32.988119] systemd[1]: Stopped target initrd-switch-root.target.717machine # [ 32.989770] systemd[1]: Reached target Virtual Machines and Containers.718machine # [ 32.991195] systemd[1]: Reached target Remote File Systems.719machine # [ 32.992437] systemd[1]: Reached target Slice Units.720machine # [ 32.994142] systemd[1]: Reached target Swaps.721machine # [ 33.077648] systemd[1]: Listening on Query the User Interactively for a Password.722machine # [ 33.098629] systemd[1]: Listening on Process Core Dump Socket.723machine # [ 33.115838] systemd[1]: Listening on Credential Encryption/Decryption.724machine # [ 33.138632] systemd[1]: Listening on Factory Reset Management.725machine # [ 33.140236] systemd[1]: Listening on Hostname Service Socket.726machine # [ 33.161470] systemd[1]: Starting Journal Log Access Socket...727machine # [ 33.173225] systemd[1]: Listening on Journal Audit Socket.728machine # [ 33.198659] systemd[1]: Listening on Console Output Muting Service Socket.729machine # [ 33.200444] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.730machine # [ 33.218249] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os731machine # [ 33.220405] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki732machine # [ 33.423336] systemd[1]: Listening on Disk Repartitioning Service Socket.733machine # [ 33.425190] systemd[1]: Listening on udev Control Socket.734machine # [ 33.434034] systemd[1]: Listening on udev Varlink Socket.735machine # [ 33.464525] systemd[1]: Mounting Huge Pages File System...736machine # [ 33.469023] systemd[1]: Mounting POSIX Message Queue File System...737machine # [ 33.475195] systemd[1]: Mounting Kernel Debug File System...738machine # [ 33.495267] systemd[1]: Mounting Kernel Trace File System...739machine # [ 33.528312] systemd[1]: Starting Create List of Static Device Nodes...740machine # [ 33.529966] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio741machine # [ 33.571466] systemd[1]: Starting Load Kernel Module configfs...742machine # [ 33.573168] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm743machine # [ 33.575767] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore744machine # [ 33.577983] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse745machine # [ 33.608378] systemd[1]: Mounting FUSE Control File System...746machine # [ 33.609690] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67747machine # [ 33.660326] systemd[1]: Starting Journal Service...748machine # [ 33.669177] systemd[1]: Starting Load Kernel Modules...749machine # [ 33.682352] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...750machine # [ 33.689589] systemd[1]: Starting Remount Root and Kernel File Systems...751machine # [ 33.691139] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os752machine # [ 33.718262] systemd[1]: Starting Coldplug All udev Devices...753machine # [ 33.725418] systemd[1]: Listening on Journal Log Access Socket.754machine # [ 33.889014] systemd-journald[322]: Collecting audit messages is enabled.755machine # [ 33.893286] systemd[1]: Mounted Kernel Debug File System.756machine # [ 33.894851] systemd[1]: Mounted Huge Pages File System.757machine # [ 33.897974] systemd[1]: Mounted POSIX Message Queue File System.758machine # [ 33.909972] systemd[1]: Mounted Kernel Trace File System.759machine # [ 33.911373] systemd[1]: Mounted FUSE Control File System.760machine # [ 33.928974] systemd[1]: Finished Create List of Static Device Nodes.761machine # [ 33.485644] systemd[1]: Queued start job for default target Multi-User System.762machine # [ 33.941238] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...763machine # [ 33.488583] systemd[1]: systemd-journald.service: Deactivated successfully.764machine # [ 33.946245] systemd[1]: Started Journal Service.765machine # [ 33.495836] systemd-modules-load[323]: Using 4 probe threads766machine # [ 33.507696] systemd[1]: modprobe@configfs.service: Deactivated successfully.767machine # [ 33.515987] systemd[1]: Finished Load Kernel Module configfs.768machine # [ 33.527745] systemd[1]: Mounting Kernel Configuration File System...769machine # [ 33.533199] systemd-oomd[324]: No swap; memory pressure usage will be degraded770machine # [ 33.999985] EXT4-fs (vda): re-mounted 4181f5a0-7189-4c17-a7a4-fef738d21151.771machine # [ 33.556611] systemd-modules-load[323]: Inserted module 'tap'772machine # [ 34.023572] loop: module loaded773machine # [ 33.569119] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.774machine # [ 33.575352] systemd-modules-load[323]: Inserted module 'loop'775machine # [ 33.576736] systemd[1]: Finished Remount Root and Kernel File Systems.776machine # [ 33.578837] systemd[1]: Listening on Disk Image Download Service Socket.777machine # [ 33.594458] systemd[1]: Starting Flush Journal to Persistent Storage...778machine # [ 33.598540] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore779machine # [ 34.064206] tun: Universal TUN/TAP device driver, 1.6780machine # [ 33.623891] systemd[1]: Starting Load/Save OS Random Seed...781machine # [ 33.625375] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os782machine # [ 33.627227] systemd-modules-load[323]: Inserted module 'vhost_vsock'783machine # [ 33.650652] systemd-modules-load[323]: Inserted module 'vhost_net'784machine # [ 33.666519] systemd[1]: Mounted Kernel Configuration File System.785machine # [ 33.700206] systemd[1]: Finished Load Kernel Modules.786machine # [ 33.708249] systemd[1]: Starting Firewall...787machine # [ 33.718310] systemd[1]: Starting Apply Kernel Variables...788machine # [ 34.176007] systemd-journald[322]: Received client request to flush runtime journal.789machine # [ 33.804219] systemd[1]: Finished Coldplug All udev Devices.790machine # [ 33.806354] systemd[1]: Finished Load/Save OS Random Seed.791machine # [ 33.808829] systemd[1]: Reached target First Boot Complete.792machine # [ 33.816803] systemd[1]: Finished Flush Journal to Persistent Storage.793machine # [ 33.944434] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.794machine # [ 33.976126] systemd[1]: Starting Create Static Device Nodes in /dev...795machine # [ 34.070896] systemd[1]: Finished Apply Kernel Variables.796machine # [ 34.169430] systemd[1]: Finished Create Static Device Nodes in /dev.797machine # [ 34.171207] systemd[1]: Reached target Preparation for Local File Systems.798machine # [ 34.184768] systemd[1]: Starting Rule-based Manager for Device Events and Files...799machine # [ 34.464611] systemd-udevd[361]: Using default interface naming scheme 'v261'.800machine # [ 34.532112] systemd[1]: Mounting /run/wrappers...801machine # [ 34.591526] systemd[1]: Mounted /run/wrappers.802machine # [ 34.593500] systemd[1]: Reached target Local File Systems.803machine # [ 34.598552] systemd[1]: Listening on Boot Loader Control Service Socket.804machine # [ 34.603052] systemd[1]: Starting register-nix-paths.service...805machine # [ 34.608543] systemd[1]: Starting Create SUID/SGID Wrappers...806machine # [ 34.610252] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.807machine # [ 34.613798] systemd[1]: Starting Save Transient machine-id to Disk...808machine # [ 34.616937] systemd[1]: Starting Create System Files and Directories...809machine # [ 34.712681] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.810machine # [ 34.714752] systemd[1]: Finished Save Transient machine-id to Disk.811machine # [ 34.718081] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:1: Duplicate line for path "/home/alice/hermes", ignoring.812machine # [ 34.720729] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:2: Duplicate line for path "/home/bob/hermes", ignoring.813machine # [ 34.723850] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:3: Duplicate line for path "/var/lib/hermes-microvm/alice/guest", ignoring.814machine # [ 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.815machine # [ 34.731275] systemd-tmpfiles[381]: /etc/tmpfiles.d/10-microvm.conf:5: Duplicate line for path "/var/lib/hermes-microvm/bob/guest", ignoring.816machine # [ 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.817machine # [ 34.759181] systemd[1]: Started Rule-based Manager for Device Events and Files.818machine # [ 34.795444] systemd[1]: Finished Create System Files and Directories.819machine # [ 34.802077] systemd[1]: Starting Rebuild Journal Catalog...820machine # [ 34.806210] systemd[1]: Starting Record System Boot/Shutdown in UTMP...821machine # [ 34.868516] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse822machine # [ 34.903909] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.823machine # [ 34.912608] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.824machine # [ 34.930114] systemd[1]: Finished Record System Boot/Shutdown in UTMP.825machine # [ 34.970476] (udev-worker)[412]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.826machine # [ 34.974342] (udev-worker)[427]: Network interface NamePolicy= disabled on kernel command line.827machine # [ 34.977591] (udev-worker)[412]: Network interface NamePolicy= disabled on kernel command line.828machine # [ 35.472857] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3829machine # [ 35.485628] ACPI: button: Power Button [PWRF]830machine # [ 35.502508] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console831machine # [ 35.503770] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5832machine # [ 35.507753] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4833machine # [ 35.514709] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0834machine # [ 35.514779] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD835machine # [ 35.536202] Console: switching to colour dummy device 80x25836machine # [ 35.543727] Floppy drive(s): fd0 is 2.88M AMI BIOS837machine # [ 35.544145] rtc_cmos 00:05: RTC can wake from S4838machine # [ 35.545242] [drm] Found bochs VGA, ID 0xb0c5.839machine # [ 35.556430] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.840machine # [ 35.558218] FDC 0 is a S82078B841machine # [ 35.558307] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input6842machine # [ 35.562282] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic843machine # [ 35.568716] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0844machine # [ 35.111715] systemd[1]: Finished Rebuild Journal Catalog.845machine # [ 35.162581] systemd[1]: Condition check resulted in Virtio network device being skipped.846machine # [ 35.603208] rtc_cmos 00:05: registered as rtc0847machine # [ 35.603328] rtc_cmos 00:05: setting system clock to 2026-08-01T04:21:28 UTC (1785558088)848machine # [ 35.168617] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore849machine # [ 35.603451] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs850machine # [ 35.171422] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.851machine # [ 35.614433] parport_pc 00:03: reported by Plug and Play ACPI852machine # [ 35.614586] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]853machine # [ 35.174409] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67854machine # [ 35.617203] Console: switching to colour frame buffer device 160x50855machine # [ 35.178824] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore856machine # [ 35.636890] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device857machine # [ 35.181514] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os858machine # [ 35.185813] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os859machine # [ 35.193660] systemd[1]: Starting Update is Completed...860machine # [ 35.669411] mousedev: PS/2 mouse device common for all mice861machine # [ 35.281186] systemd[1]: Starting Virtual Console Setup...862machine # [ 35.742714] ppdev: user-space parallel port driver863machine # [ 35.298136] systemd[1]: Finished Update is Completed.864machine # [ 35.332914] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.865machine # [ 35.336618] systemd[1]: Stopped Virtual Console Setup.866machine # [ 35.356574] systemd[1]: Starting Virtual Console Setup...867machine # [ 35.960808] kvm_amd: TSC scaling supported868machine # [ 35.961521] kvm_amd: Nested Virtualization enabled869machine # [ 35.962823] kvm_amd: Nested Paging enabled870machine # [ 35.963851] kvm_amd: LBR virtualization supported871machine # [ 35.965645] kvm_amd: Virtual GIF supported872machine # [ 35.966243] kvm_amd: Virtual NMI enabled873machine # [ 36.022133] EDAC MC: Ver: 3.0.0874machine # [ 35.689480] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.875machine # [ 35.690927] systemd[1]: Finished Create SUID/SGID Wrappers.876machine # [ 35.810620] systemd-vconsole-setup[473]: Configuration of first virtual console was skipped, ignoring remaining ones.877machine # [ 35.813547] systemd[1]: Finished Virtual Console Setup.878machine # [ 35.892228] systemd[1]: Finished Firewall.879machine # [ 35.944404] systemd[1]: Finished register-nix-paths.service.880machine # [ 35.945838] systemd[1]: Reached target System Initialization.881machine # [ 35.947367] systemd[1]: Started hermes-microvm-timezone.path.882machine # [ 35.948740] systemd[1]: Started Discard unused filesystem blocks once a week.883machine # [ 35.950301] systemd[1]: Started Daily Cleanup of Temporary Directories.884machine # [ 35.951694] systemd[1]: Reached target Path Units.885machine # [ 35.952804] systemd[1]: Reached target Timer Units.886machine # [ 35.953912] systemd[1]: Listening on D-Bus System Message Bus Socket.887machine # [ 35.965879] systemd[1]: Listening on dashboard forward socket for hermes-alice.888machine # [ 35.969406] systemd[1]: Listening on dashboard forward socket for hermes-bob.889machine # [ 35.970921] systemd[1]: Listening on spaces bridge socket for hermes-alice.890machine # [ 35.972264] systemd[1]: Listening on Nix Daemon Socket.891machine # [ 35.973529] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.892machine # [ 35.975443] systemd[1]: Reached target Socket Units.893machine # [ 35.976523] systemd[1]: Reached target Basic System.894machine # [ 35.978058] systemd[1]: Started backdoor.service.895machine # [ 35.979665] systemd[1]: Started Enable Kernel Same-Page Merging.896machine # [ 35.981655] systemd[1]: Starting Owner-match loopback firewall for hermes microvms...897machine # [ 35.984571] systemd[1]: Starting Install MicroVM 'hermes-alice'...898machine # [ 35.986427] systemd[1]: Starting Install MicroVM 'hermes-bob'...899machine # [ 35.988217] systemd[1]: Starting Import lastlog data into lastlog2 database...900machine # [ 35.991272] systemd[1]: Starting Name Service Cache Daemon (nsncd)...901machine # [ 35.995980] systemd[1]: Starting Post-Boot Actions...902machine # [ 36.000855] systemd[1]: Started Reset console on configuration changes.903machine # [ 36.006903] systemd[1]: Starting resolvconf update...904machine # [ 36.008881] systemd[1]: enable-ksm.service: Deactivated successfully.905machine # [ 36.023485] systemd[1]: Starting D-Bus System Message Bus...906machine # [ 36.029724] systemd[1]: Finished Post-Boot Actions.907machine # connecting to host...908machine # [ 36.034844] systemd[1]: install-microvm-hermes-alice.service: Deactivated successfully.909machine # [ 36.039854] systemd[1]: Finished Install MicroVM 'hermes-alice'.910machine # [ 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"911machine # [ 36.043237] systemd[1]: Started Name Service Cache Daemon (nsncd).912machine # [ 36.044188] systemd[1]: Finished Import lastlog data into lastlog2 database.913machine # [ 36.045056] systemd[1]: Reached target Host and Network Name Lookups.914machine # [ 36.046146] systemd[1]: Reached target User and Group Name Lookups.915machine # [ 36.046723] systemd[1]: Setup MicroVM 'hermes-alice' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-alice/current/bin/pci-setup916machine # [ 36.056598] systemd[1]: Starting Save MicroVM 'hermes-alice' booted configuration...917machine: Guest shell says: b'Spawning backdoor root shell...\n'918machine: connected to guest root shell919machine: (connecting took 37.68 seconds)920machine: (finished: waiting for the VM to finish booting, in 37.68 seconds)921machine # [ 36.065712] systemd[1]: Starting User Login Management...922machine # [ 36.066616] systemd[1]: install-microvm-hermes-bob.service: Deactivated successfully.923machine # [ 36.066987] systemd[1]: Finished Install MicroVM 'hermes-bob'.924machine # [ 36.068887] systemd[1]: Setup MicroVM 'hermes-bob' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/pci-setup925machine # [ 36.075143] systemd[1]: Starting Save MicroVM 'hermes-bob' booted configuration...926machine # [ 36.117511] systemd-logind[630]: New seat seat0.927machine # [ 36.119119] dbus-broker-launch[603]: Looking up NSS user entry for 'systemd-timesync'...928machine # [ 36.143591] dbus-broker-launch[603]: NSS returned no entry for 'systemd-timesync'929machine # [ 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"930machine # [ 36.151953] systemd-logind[630]: Watching system buttons on /dev/input/event2 (Power Button)931machine # [ 36.155343] systemd-logind[630]: Watching system buttons on /dev/input/event5 (QEMU Virtio Keyboard)932machine # [ 36.158498] systemd-logind[630]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)933machine # [ 36.161461] systemd[1]: Stopped target Host and Network Name Lookups.934machine # [ 36.163408] systemd[1]: Stopping Host and Network Name Lookups...935machine # [ 36.165251] systemd[1]: Stopped target User and Group Name Lookups.936machine # [ 36.167180] systemd[1]: Stopping User and Group Name Lookups...937machine # [ 36.168938] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...938machine # [ 36.171380] systemd[1]: nscd.service: Deactivated successfully.939machine # [ 36.173845] systemd[1]: Stopped Name Service Cache Daemon (nsncd).940machine # [ 36.176308] systemd[1]: Starting Name Service Cache Daemon (nsncd)...941machine # [ 36.185570] systemd[1]: Started D-Bus System Message Bus.942machine # [ 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"943machine # [ 36.201051] dbus-broker-launch[603]: Ready944machine # [ 36.202362] systemd[1]: Started Name Service Cache Daemon (nsncd).945machine # [ 36.203534] systemd[1]: Finished resolvconf update.946machine # [ 36.204537] systemd[1]: Finished Save MicroVM 'hermes-bob' booted configuration.947machine # [ 36.205882] systemd[1]: Finished Save MicroVM 'hermes-alice' booted configuration.948machine # [ 36.219463] systemd[1]: Started User Login Management.949machine # [ 36.228852] systemd[1]: Created slice Slice /user/1000.950machine # [ 36.229336] systemd[1]: Reached target Preparation for Network.951machine # [ 36.230118] systemd[1]: Reached target Host and Network Name Lookups.952machine # [ 36.230672] systemd[1]: Reached target User and Group Name Lookups.953machine # [ 36.232093] systemd[1]: Starting DHCP Client...954machine # [ 36.233670] systemd[1]: Starting linger-users.service...955machine # [ 36.234358] systemd[1]: Setup MicroVM 'hermes-alice' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-alice/current/bin/macvtap-up956machine # [ 36.234865] systemd[1]: Setup MicroVM 'hermes-bob' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/macvtap-up957machine # [ 36.237100] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-alice'...958machine # [ 36.243466] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-bob'...959machine # [ 36.246353] systemd[1]: Starting Address configuration of eth1...960machine # [ 36.247953] systemd[1]: Starting Extra networking commands....961machine # [ 36.250472] systemd[1]: Starting User Runtime Directory /run/user/1000...962machine # [ 36.269926] systemd[1]: Finished Owner-match loopback firewall for hermes microvms.963machine # [ 36.282770] systemd[1]: linger-users.service: Deactivated successfully.964machine # [ 36.285857] systemd[1]: Finished linger-users.service.965machine # [ 36.298131] systemd[1]: Finished User Runtime Directory /run/user/1000.966machine # [ 36.300380] network-addresses-eth1-start[746]: adding address 192.168.1.1/24... done967machine # [ 36.302883] systemd[1]: Starting User Manager for UID 1000...968machine # [ 36.312831] network-addresses-eth1-start[746]: adding address 2001:db8:1::1/64... done969machine # [ 36.327196] systemd[1]: Finished Address configuration of eth1.970machine # [ 36.344571] (systemd)[777]: pam_unix(systemd-user:session): session opened for user alice(uid=1000) by (uid=0)971machine # [ 36.362410] systemd-logind[630]: New session '1' of user 'alice' with class 'manager' and type 'unspecified'.972machine # [ 36.365087] systemd[1]: Finished Extra networking commands..973machine # [ 36.366422] systemd[1]: Reached target Network.974machine # [ 36.366644] systemd[1]: Setup MicroVM 'hermes-alice' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-alice/current/bin/tap-up975machine # [ 36.367375] systemd[1]: Setup MicroVM 'hermes-bob' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/tap-up976machine # [ 36.369358] systemd[1]: Starting Permit User Sessions...977machine # [ 36.380721] dhcpcd[794]: dhcpcd-10.3.2 starting978machine # [ 36.387316] dhcpcd[837]: dev: loaded udev979machine # [ 36.389857] systemd[1]: Finished Permit User Sessions.980machine # [ 36.398400] systemd[1]: Started Getty on tty1.981machine # [ 36.399535] systemd[1]: Reached target Login Prompts.982machine # [ 36.856210] 8021q: 802.1Q VLAN Support v1.8983machine # [ 36.400944] s[ 36.856964] 8021q: adding VLAN 0 to HW filter on device eth1984machine # ystemd[777]: Failed to adjust io pressure threshold, ignoring: Device or resource busy985machine # [ 36.419691] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.986machine # [ 36.952924] cfg80211: Loading compiled-in X.509 certificates for regulatory database987machine # [ 36.968352] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'988machine # [ 36.969455] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'989machine # [ 36.971968] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2990machine # [ 36.973552] cfg80211: failed to load regulatory.db991machine # [ 36.550300] systemd[777]: Queued start job for default target Main User Target.992machine # [ 37.008000] 8021q: adding VLAN 0 to HW filter on device eth0993machine # [ 36.553563] dhcpcd[837]: eth0: waiting for carrier994machine # [ 36.554060] dhcpcd[837]: eth0: carrier acquired995machine # [ 36.556937] systemd[777]: Failed to adjust io pressure threshold, ignoring: Device or resource busy996machine # [ 36.558365] systemd[777]: Created slice User Application Slice.997machine # [ 36.559463] systemd[777]: Started Daily Cleanup of User's Temporary Directories.998machine # [ 36.560374] systemd[777]: Reached target Paths.999machine # [ 36.561358] systemd[777]: Reached target Timers.1000machine # [ 36.561897] dhcpcd[837]: DUID 00:01:00:01:32:00:30:c9:52:54:00:12:34:561001machine # [ 36.563060] systemd[777]: Starting D-Bus User Message Bus Socket...1002machine # [ 36.563808] dhcpcd[837]: eth0: IAID 00:12:34:561003machine # [ 36.564746] systemd[777]: Starting Create User Files and Directories...1004machine # [ 36.565573] dhcpcd[837]: eth0: adding address fe80::5054:ff:fe12:34561005machine # [ 36.579969] systemd[777]: Finished Create User Files and Directories.1006machine # [ 36.608949] systemd[777]: Listening on D-Bus User Message Bus Socket.1007machine # [ 36.610482] systemd[777]: Reached target Sockets.1008machine # [ 36.611897] systemd[777]: Reached target Basic System.1009machine # [ 36.613143] systemd[1]: Started User Manager for UID 1000.1010machine # [ 36.614211] systemd[777]: Starting Run user-specific NixOS activation...1011machine # [ 36.638299] systemd[777]: Finished Run user-specific NixOS activation.1012machine # [ 36.638987] systemd[777]: Reached target Main User Target.1013machine # [ 36.639895] systemd[777]: Startup finished in 248ms.1014machine # [ 37.329529] dhcpcd[837]: eth0: soliciting a DHCP lease1015machine # [ 38.208326] NET: Registered PF_PACKET protocol family1016machine # [ 37.767702] dhcpcd[837]: eth0: offered 10.0.2.15 from 10.0.2.21017machine # [ 37.775231] dhcpcd[837]: eth0: probing address 10.0.2.15/241018machine # [ 38.363086] dhcpcd[837]: eth0: soliciting an IPv6 router1019machine # [ 38.368054] dhcpcd[837]: eth0: Router Advertisement from fe80::21020machine # [ 38.368253] dhcpcd[837]: eth0: adding address fec0::5054:ff:fe12:3456/641021machine # [ 38.368488] dhcpcd[837]: eth0: adding route to fec0::/641022machine # [ 38.368755] dhcpcd[837]: eth0: adding default route via fe80::21023machine # [ 41.615292] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:34,963 INFO Set uid to user 0 succeeded1024machine # [ 41.618173] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:34,969 INFO Set uid to user 0 succeeded1025machine # [ 41.725579] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:35,074 INFO supervisord started with pid 7811026machine # [ 41.725971] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:35,075 INFO supervisord started with pid 7801027machine # [ 42.649438] dhcpcd[837]: eth0: leased 10.0.2.15 for 86400 seconds1028machine # [ 42.649976] dhcpcd[837]: eth0: adding route to 10.0.2.0/241029machine # [ 42.650940] dhcpcd[837]: eth0: adding default route via 10.0.2.21030machine # [ 42.839064] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,188 INFO spawned: 'notify' with pid 9021031machine # [ 42.839561] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,190 INFO spawned: 'notify' with pid 9031032machine # [ 42.845337] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,196 INFO spawned: 'virtiofsd-hermes-exchange' with pid 9041033machine # [ 42.850402] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,202 INFO spawned: 'virtiofsd-hermes-exchange' with pid 9051034machine # [ 42.853826] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,205 INFO spawned: 'virtiofsd-hermes-state' with pid 9061035machine # [ 42.858804] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,209 INFO spawned: 'virtiofsd-host-config' with pid 9071036machine # [ 42.873783] microvm-virtiofsd@hermes-alice[781]: 2026-08-01 04:21:36,219 INFO spawned: 'virtiofsd-ro-store' with pid 9081037machine # [ 42.874836] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,219 INFO spawned: 'virtiofsd-hermes-state' with pid 9091038machine # [ 42.880366] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,231 INFO spawned: 'virtiofsd-host-config' with pid 9101039machine # [ 42.887136] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:36,239 INFO spawned: 'virtiofsd-ro-store' with pid 9111040machine # [ 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)1041machine # [ 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)1042machine # [ 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)1043machine # [ 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)1044machine # [ 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)1045machine # [ 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)1046machine # [ 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)1047machine # [ 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)1048machine # [ 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)1049machine # [ 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)1050machine # [ 44.038340] supervisord[781]: virtiofsd-hermes-state [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1051machine # [ 44.038665] supervisord[781]: virtiofsd-ro-store [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1052machine # [ 44.039196] supervisord[780]: virtiofsd-hermes-exchange [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1053machine # [ 44.039391] supervisord[780]: virtiofsd-hermes-state [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1054machine # [ 44.039615] supervisord[780]: virtiofsd-ro-store [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1055machine # [ 44.039797] supervisord[780]: virtiofsd-host-config [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1056machine # [ 44.040210] supervisord[781]: virtiofsd-hermes-exchange [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1057machine # [ 44.040587] supervisord[781]: virtiofsd-host-config [2026-08-01T04:21:37Z INFO virtiofsd] Waiting for vhost-user socket connection...1058machine # [ 44.100230] systemd[1]: Started DHCP Client.1059machine # [ 44.133937] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-alice'.1060machine # [ 44.134282] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-bob'.1061machine # [ 44.175641] systemd[1]: Starting MicroVM 'hermes-alice'...1062machine # [ 44.215453] systemd[1]: Starting MicroVM 'hermes-bob'...1063machine # [ 46.412654] microvm@hermes-bob[1096]: chmod: cannot access '/var/lib/hermes-microvm/alice/guest/tz/.localtime.tmp': No such file or directory1064machine # [ 46.430993] systemd[1]: microvm@hermes-bob.service: Control process exited, code=exited, status=1/FAILURE1065machine # [ 46.530503] systemd[1]: microvm@hermes-bob.service: Failed with result 'exit-code'.1066machine # [ 46.533050] systemd[1]: Failed to start MicroVM 'hermes-bob'.1067machine # [ 51.599340] systemd[1]: microvm@hermes-bob.service: Scheduled restart job, restart counter is at 1.1068machine # [ 51.601494] systemd[1]: Stopped MicroVM 'hermes-bob'.1069machine # [ 51.605835] systemd[1]: Setup MicroVM 'hermes-bob' devices for passthrough skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/pci-setup1070machine # [ 51.606139] systemd[1]: Stopping VirtioFS daemons for MicroVM 'hermes-bob'...1071machine # [ 52.181404] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,532 WARN received SIGTERM indicating exit request1072machine # [ 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 die1073machine # [ 52.191535] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,542 WARN stopped: virtiofsd-ro-store (exit status 1)1074machine # [ 52.212004] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,563 WARN stopped: virtiofsd-host-config (exit status 1)1075machine # [ 52.213121] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,565 WARN stopped: virtiofsd-hermes-state (exit status 1)1076machine # [ 52.228483] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:45,579 WARN stopped: virtiofsd-hermes-exchange (exit status 1)1077machine # [ 53.234864] microvm-virtiofsd@hermes-bob[780]: 2026-08-01 04:21:46,586 WARN stopped: notify (terminated by SIGTERM)1078machine # [ 53.548880] systemd[1]: microvm-virtiofsd@hermes-bob.service: Deactivated successfully.1079machine # [ 53.549229] systemd[1]: Stopped VirtioFS daemons for MicroVM 'hermes-bob'.1080machine # [ 53.580495] systemd[1]: Stopping Save MicroVM 'hermes-bob' booted configuration...1081machine # [ 53.816764] systemd[1]: microvm-set-booted@hermes-bob.service: Deactivated successfully.1082machine # [ 53.820976] systemd[1]: Stopped Save MicroVM 'hermes-bob' booted configuration.1083machine # [ 53.846210] systemd[1]: Starting Save MicroVM 'hermes-bob' booted configuration...1084machine # [ 54.994119] systemd[1]: Finished Save MicroVM 'hermes-bob' booted configuration.1085machine # [ 54.994396] systemd[1]: Setup MicroVM 'hermes-bob' MACVTAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/macvtap-up1086machine # [ 54.994573] systemd[1]: Setup MicroVM 'hermes-bob' TAP interfaces skipped, unmet condition check ConditionPathExists=/var/lib/microvms/hermes-bob/current/bin/tap-up1087machine # [ 55.017233] systemd[1]: Starting VirtioFS daemons for MicroVM 'hermes-bob'...1088machine # [ 57.549911] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:50,898 INFO Set uid to user 0 succeeded1089machine # [ 57.608660] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:50,957 INFO supervisord started with pid 11721090machine # [ 58.620744] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:51,968 INFO spawned: 'notify' with pid 11951091machine # [ 58.621108] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:51,971 INFO spawned: 'virtiofsd-hermes-exchange' with pid 11961092machine # [ 58.636716] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:51,988 INFO spawned: 'virtiofsd-hermes-state' with pid 11971093machine # [ 58.657964] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:52,009 INFO spawned: 'virtiofsd-host-config' with pid 11991094machine # [ 58.664107] microvm-virtiofsd@hermes-bob[1172]: 2026-08-01 04:21:52,015 INFO spawned: 'virtiofsd-ro-store' with pid 12001095machine # [ 59.002597] supervisord[1172]: virtiofsd-hermes-exchange [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection...1096machine # [ 59.026690] supervisord[1172]: virtiofsd-ro-store [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection...1097machine # [ 59.057455] supervisord[1172]: virtiofsd-host-config [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection...1098machine # [ 59.151138] supervisord[1172]: virtiofsd-hermes-state [2026-08-01T04:21:52Z INFO virtiofsd] Waiting for vhost-user socket connection...1099machine # [ 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)1100machine # [ 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)1101machine # [ 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)1102machine # [ 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)1103machine # [ 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)1104machine # [ 60.357638] systemd[1]: Started VirtioFS daemons for MicroVM 'hermes-bob'.1105machine # [ 60.363791] systemd[1]: Starting MicroVM 'hermes-bob'...1106machine # [ 63.122200] systemd[1]: Started MicroVM 'hermes-bob'.1107machine # [ 70.798371] supervisord[1172]: virtiofsd-hermes-exchange [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests1108machine # [ 70.799322] supervisord[1172]: virtiofsd-hermes-state [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests1109machine # [ 70.800630] supervisord[1172]: virtiofsd-ro-store [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests1110machine # [ 70.806315] supervisord[1172]: virtiofsd-host-config [2026-08-01T04:22:04Z INFO virtiofsd] Client connected, servicing requests1111machine # [ 70.864565] microvm@hermes-bob[1283]: microvm@hermes-bob: warning: Number of SMP cpus requested (8) exceeds the recommended cpus supported by KVM (4)1112machine # [ 71.101944] microvm@hermes-bob[1283]: microvm@hermes-bob: warning: Number of hotpluggable cpus requested (8) exceeds the recommended cpus supported by KVM (4)1113machine # [ 75.077992] microvm@hermes-bob[1283]: SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)1114machine # [ 76.683955] microvm@hermes-bob[1283]: Booting from ROM...1115machine # [ 76.688748] microvm@hermes-bob[1283]: PPrroobbiinngg EEDDDD ((eedddd==ooffff ttoo ddiissaabbllee))...... ook1116machine # [ 76.965525] microvm@hermes-bob[1283]: No EFI environment detected.1117machine # [ 76.969502] microvm@hermes-bob[1283]: early console in extract_kernel1118machine # [ 76.972369] microvm@hermes-bob[1283]: input_data: 0x0000000003acc2cc1119machine # [ 76.975179] microvm@hermes-bob[1283]: input_len: 0x0000000000cb3f6c1120machine # [ 76.977794] microvm@hermes-bob[1283]: output: 0x00000000010000001121machine # [ 76.980535] microvm@hermes-bob[1283]: output_len: 0x00000000037287481122machine # [ 76.983661] microvm@hermes-bob[1283]: kernel_total_size: 0x0000000002e300001123machine # [ 76.986641] microvm@hermes-bob[1283]: needed_size: 0x00000000038000001124machine # [ 76.989710] microvm@hermes-bob[1283]: trampoline_32bit: 0x00000000000000001125machine # [ 76.993111] microvm@hermes-bob[1283]: Physical KASLR using RDRAND RDTSC...1126machine # [ 76.996331] microvm@hermes-bob[1283]: Virtual KASLR using RDRAND RDTSC...1127machine # [ 76.997652] microvm@hermes-bob[1283]:1128machine # [ 77.000911] microvm@hermes-bob[1283]: Decompressing Linux... No EFI environment detected.1129machine # [ 77.766591] microvm@hermes-bob[1283]: Parsing ELF... Performing relocations... done.1130machine # [ 77.771082] microvm@hermes-bob[1283]: Booting the kernel (entry_offset: 0x0000000002788520).1131machine # [ 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 20261132machine # [ 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/registration1133machine # [ 77.820618] microvm@hermes-bob[1283]: [ 0.000000] BIOS-provided physical RAM map:1134machine # [ 77.826054] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable1135machine # [ 77.832210] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved1136machine # [ 77.838208] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved1137machine # [ 77.844275] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffdfff] usable1138machine # [ 77.850345] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000bfffe000-0x00000000bfffffff] reserved1139machine # [ 77.856311] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved1140machine # [ 77.862494] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved1141machine # [ 77.868388] microvm@hermes-bob[1283]: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable1142machine # [ 77.873527] microvm@hermes-bob[1283]: [ 0.000000] printk: legacy bootconsole [earlyser0] enabled1143machine # [ 77.882447] microvm@hermes-bob[1283]: Memory KASLR using RDRAND RDTSC...1144machine # [ 80.028686] microvm@hermes-bob[1283]: Poking KASLR using RDRAND RDTSC...1145machine # [ 80.241879] microvm@hermes-bob[1283]: [ 2.313767] PCI: Fatal: No config space access function found1146machine # [ 82.875795] microvm@hermes-bob[1283]: +q6E616D65Booting initrd of NixOS 26.11 (Zokor) (Initrd).1147machine # [ 83.165635] microvm@hermes-bob[1283]: [ OK ] Created slice Slice /system/modprobe.1148machine # [ 83.173895] microvm@hermes-bob[1283]: [ OK ] Started Dispatch Password Requests to Console Directory Watch.1149machine # [ 83.179477] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd Root Device.1150machine # [ 83.184582] microvm@hermes-bob[1283]: [ OK ] Reached target Path Units.1151machine # [ 83.189128] microvm@hermes-bob[1283]: [ OK ] Reached target Slice Units.1152machine # [ 83.193376] microvm@hermes-bob[1283]: [ OK ] Reached target Swaps.1153machine # [ 83.197832] microvm@hermes-bob[1283]: [ OK ] Reached target Timer Units.1154machine # [ 83.203152] microvm@hermes-bob[1283]: [ OK ] Listening on D-Bus System Message Bus Socket.1155machine # [ 83.206784] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Socket (/dev/log).1156machine # [ 83.211682] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Sockets.1157machine # [ 83.215268] microvm@hermes-bob[1283]: [ OK ] Listening on udev Control Socket.1158machine # [ 83.218774] microvm@hermes-bob[1283]: [ OK ] Listening on udev Kernel Socket.1159machine # [ 83.223089] microvm@hermes-bob[1283]: [ OK ] Reached target Socket Units.1160machine # [ 83.230468] microvm@hermes-bob[1283]: Starting Create List of Static Device Nodes...1161machine # [ 83.244171] microvm@hermes-bob[1283]: Starting Load Kernel Module configfs...1162machine # [ 83.253116] microvm@hermes-bob[1283]: Starting Journal Service...1163machine # [ 83.262184] microvm@hermes-bob[1283]: Starting Load Kernel Modules...1164machine # [ 83.271441] microvm@hermes-bob[1283]: Starting Coldplug All udev Devices...1165machine # [ 83.283573] microvm@hermes-bob[1283]: [ OK ] Finished Create List of Static Device Nodes.1166machine # [ 83.291779] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Module configfs.1167machine # [ 83.306324] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev gracefully...1168machine # [ 83.340354] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev gracefully.1169machine # [ 83.366551] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev...1170machine # [ 83.450216] microvm@hermes-bob[1283]: [ OK ] Finished Coldplug All udev Devices.1171machine # [ 83.478871] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev.1172machine # [ 83.484861] microvm@hermes-bob[1283]: [ OK ] Started Journal Service.1173machine # [ 83.506175] microvm@hermes-bob[1283]: [ OK ] Reached target Preparation for Local File Systems.1174machine # [ 83.510631] microvm@hermes-bob[1283]: [ OK ] Reached target Local File Systems.1175machine # [ 83.521088] microvm@hermes-bob[1283]: Mounting /sysroot...1176machine # [ 83.530951] microvm@hermes-bob[1283]: Starting Create System Files and Directories...1177machine # [ 83.603304] microvm@hermes-bob[1283]: Starting Rule-based Manager for Device Events and Files...1178machine # [ 83.612857] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot.1179machine # [ 83.621643] microvm@hermes-bob[1283]: [ OK ] Finished Create System Files and Directories.1180machine # [ 83.633358] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd Root File System.1181machine # [ 83.642378] microvm@hermes-bob[1283]: Starting Mountpoints Configured in the Real Root...1182machine # [ 83.653940] microvm@hermes-bob[1283]: Starting rw-sysroot-nix-store.service...1183machine # [ 83.844938] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Modules.1184machine # [ 83.849257] microvm@hermes-bob[1283]: [ OK ] Finished rw-sysroot-nix-store.service.1185machine # [ 83.857930] microvm@hermes-bob[1283]: Starting rw-sysroot-nix-store.service...1186machine # [ 83.864641] microvm@hermes-bob[1283]: Starting Apply Kernel Variables...1187machine # [ 83.873127] microvm@hermes-bob[1283]: [ OK ] Finished Mountpoints Configured in the Real Root.1188machine # [ 83.878230] microvm@hermes-bob[1283]: [ OK ] Finished rw-sysroot-nix-store.service.1189machine # [ 83.882625] microvm@hermes-bob[1283]: [ OK ] Finished Apply Kernel Variables.1190machine # [ 83.901407] microvm@hermes-bob[1283]: [ OK ] Started Rule-based Manager for Device Events and Files.1191machine # [ 83.908125] microvm@hermes-bob[1283]: [ OK ] Reached target System Initialization.1192machine # [ 83.911167] microvm@hermes-bob[1283]: [ OK ] Reached target Basic System.1193machine # [ 84.098547] microvm@hermes-bob[1283]: Starting Virtual Console Setup...1194machine # [ 84.136978] microvm@hermes-bob[1283]: [ OK ] Finished Virtual Console Setup.1195machine # [ 84.276218] microvm@hermes-bob[1283]: Mounting /sysroot/nix/.ro-store...1196machine # [ 84.285090] microvm@hermes-bob[1283]: Mounting /sysroot/run...1197machine # [ 84.290381] microvm@hermes-bob[1283]: Mounting /sysroot/var/lib/hermes...1198machine # [ 84.297994] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/nix/.ro-store.1199machine # [ 84.304953] microvm@hermes-bob[1283]: Mounting /sysroot/nix/store...1200machine # [ 84.310901] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/run.1201machine # [ 84.321594] microvm@hermes-bob[1283]: Mounting /sysroot/run/hermes-host...1202machine # [ 84.330988] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/var/lib/hermes.1203machine # [ 84.335061] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/nix/store.1204machine # [ 84.340306] microvm@hermes-bob[1283]: [ OK ] Mounted /sysroot/run/hermes-host.1205machine # [ 84.345860] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd File Systems.1206machine # [ 84.349845] microvm@hermes-bob[1283]: Starting Find NixOS closure...1207machine # [ 84.355467] microvm@hermes-bob[1283]: Starting Create Volatile Files and Directories in the Real Root...1208machine # [ 84.374198] microvm@hermes-bob[1283]: [ OK ] Finished Create Volatile Files and Directories in the Real Root.1209machine # [ 84.392370] microvm@hermes-bob[1283]: [ OK ] Finished Find NixOS closure.1210machine # [ 84.398771] microvm@hermes-bob[1283]: [ OK ] Reached target Initrd Default Target.1211machine # [ 84.404810] microvm@hermes-bob[1283]: Starting Cleaning Up and Shutting Down Daemons...1212machine # [ 84.431719] microvm@hermes-bob[1283]: [ OK ] Stopped target Initrd Default Target.1213machine # [ 84.436413] microvm@hermes-bob[1283]: [ OK ] Stopped target Basic System.1214machine # [ 84.441274] microvm@hermes-bob[1283]: [ OK ] Stopped target Initrd Root Device.1215machine # [ 84.446253] microvm@hermes-bob[1283]: [ OK ] Stopped target Path Units.1216machine # [ 84.453643] microvm@hermes-bob[1283]: [ OK ] Stopped Dispatch Password Requests to Console Directory Watch.1217machine # [ 84.457428] microvm@hermes-bob[1283]: [ OK ] Stopped target Slice Units.1218machine # [ 84.461109] microvm@hermes-bob[1283]: [ OK ] Stopped target Socket Units.1219machine # [ 84.464941] microvm@hermes-bob[1283]: [ OK ] Stopped target System Initialization.1220machine # [ 84.467628] microvm@hermes-bob[1283]: [ OK ] Stopped target Swaps.1221machine # [ 84.470438] microvm@hermes-bob[1283]: [ OK ] Stopped target Timer Units.1222machine # [ 84.473882] microvm@hermes-bob[1283]: [ OK ] Closed D-Bus System Message Bus Socket.1223machine # [ 84.476853] microvm@hermes-bob[1283]: [ OK ] Stopped Find NixOS closure.1224machine # [ 84.481256] microvm@hermes-bob[1283]: Starting rw-sysroot-nix-store.service...1225machine # [ 84.484495] microvm@hermes-bob[1283]: [ OK ] Stopped Apply Kernel Variables.1226machine # [ 84.490104] microvm@hermes-bob[1283]: [ OK ] Stopped Load Kernel Modules.1227machine # [ 84.496947] microvm@hermes-bob[1283]: [ OK ] Stopped Create Volatile Files and Directories in the Real Root.1228machine # [ 84.501117] microvm@hermes-bob[1283]: [ OK ] Stopped Create System Files and Directories.1229machine # [ 84.506761] microvm@hermes-bob[1283]: [ OK ] Stopped target Local File Systems.1230machine # [ 84.510960] microvm@hermes-bob[1283]: [ OK ] Stopped target Preparation for Local File Systems.1231machine # [ 84.514695] microvm@hermes-bob[1283]: [ OK ] Stopped Coldplug All udev Devices.1232machine # [ 84.520072] microvm@hermes-bob[1283]: Stopping Rule-based Manager for Device Events and Files...1233machine # [ 84.525531] microvm@hermes-bob[1283]: [ OK ] Stopped Virtual Console Setup.1234machine # [ 84.532235] microvm@hermes-bob[1283]: [ OK ] Finished rw-sysroot-nix-store.service.1235machine # [ 84.539082] microvm@hermes-bob[1283]: [ OK ] Finished Cleaning Up and Shutting Down Daemons.1236machine # [ 84.549728] microvm@hermes-bob[1283]: [ OK ] Stopped Rule-based Manager for Device Events and Files.1237machine # [ 84.554477] microvm@hermes-bob[1283]: [ OK ] Closed udev Control Socket.1238machine # [ 84.558946] microvm@hermes-bob[1283]: Starting Cleanup udev Database...1239machine # [ 84.564482] microvm@hermes-bob[1283]: [ OK ] Stopped Create Static Device Nodes in /dev.1240machine # [ 84.569805] microvm@hermes-bob[1283]: [ OK ] Stopped Create Static Device Nodes in /dev gracefully.1241machine # [ 84.573913] microvm@hermes-bob[1283]: [ OK ] Stopped Create List of Static Device Nodes.1242machine # [ 84.583548] microvm@hermes-bob[1283]: [ OK ] Finished Cleanup udev Database.1243machine # [ 84.588620] microvm@hermes-bob[1283]: [ OK ] Reached target Switch Root.1244machine # [ 84.595312] microvm@hermes-bob[1283]: Starting NixOS Activation...1245machine # [ 86.635728] microvm@hermes-bob[1283]: [* ] A start job is running for NixOS Activation (2s / no limit)1246machine # [ 87.218862] microvm@hermes-bob[1283]: [** ] A start job is running for NixOS Activation (2s / no limit)1247machine # [ 87.801482] microvm@hermes-bob[1283]: [*** ] A start job is running for NixOS Activation (3s / no limit)1248machine # [ 88.385950] microvm@hermes-bob[1283]: [ *** ] A start job is running for NixOS Activation (3s / no limit)1249machine # [ 88.969938] microvm@hermes-bob[1283]: [ *** ] A start job is running for NixOS Activation (4s / no limit)1250machine # [ 89.552544] microvm@hermes-bob[1283]: [ ***] A start job is running for NixOS Activation (5s / no limit)1251machine # [ 90.135565] microvm@hermes-bob[1283]: [ **] A start job is running for NixOS Activation (5s / no limit)1252machine # [ 90.719777] microvm@hermes-bob[1283]: [ *] A start job is running for NixOS Activation (6s / no limit)1253machine # [ 90.916662] microvm@hermes-bob[1283]: [ OK ] Finished NixOS Activation.1254machine # [ 90.920097] microvm@hermes-bob[1283]: Starting Switch Root...1255machine # [ 92.296416] microvm@hermes-bob[1283]: +q6E616D651256machine # [ 92.301706] microvm@hermes-bob[1283]: Welcome to NixOS 26.11 (Zokor)!1257machine # [ 92.303696] microvm@hermes-bob[1283]:1258machine # [ 94.100220] microvm@hermes-bob[1283]: [ OK ] Stopped initrd-switch-root.service.1259machine # [ 94.103798] microvm@hermes-bob[1283]: [ OK ] Created slice Slice /system/getty.1260machine # [ 94.107111] microvm@hermes-bob[1283]: [ OK ] Created slice Slice /system/serial-getty.1261machine # [ 94.110169] microvm@hermes-bob[1283]: [ OK ] Created slice User and Session Slice.1262machine # [ 94.113618] microvm@hermes-bob[1283]: [ OK ] Started Dispatch Password Requests to Console Directory Watch.1263machine # [ 94.116981] microvm@hermes-bob[1283]: [ OK ] Started Forward Password Requests to Wall Directory Watch.1264machine # [ 94.119065] microvm@hermes-bob[1283]: Expecting device /dev/ttyS0...1265machine # [ 94.121680] microvm@hermes-bob[1283]: [ OK ] Reached target Local Encrypted Volumes.1266machine # [ 94.124185] microvm@hermes-bob[1283]: [ OK ] Stopped target initrd-fs.target.1267machine # [ 94.126720] microvm@hermes-bob[1283]: [ OK ] Stopped target initrd-root-fs.target.1268machine # [ 94.129565] microvm@hermes-bob[1283]: [ OK ] Stopped target initrd-switch-root.target.1269machine # [ 94.132514] microvm@hermes-bob[1283]: [ OK ] Reached target Virtual Machines and Containers.1270machine # [ 94.134676] microvm@hermes-bob[1283]: [ OK ] Reached target Path Units.1271machine # [ 94.137313] microvm@hermes-bob[1283]: [ OK ] Reached target Remote File Systems.1272machine # [ 94.139482] microvm@hermes-bob[1283]: [ OK ] Reached target Slice Units.1273machine # [ 94.141529] microvm@hermes-bob[1283]: [ OK ] Reached target Swaps.1274machine # [ 94.148995] microvm@hermes-bob[1283]: [ OK ] Listening on Query the User Interactively for a Password.1275machine # [ 94.157070] microvm@hermes-bob[1283]: [ OK ] Listening on Process Core Dump Socket.1276machine # [ 94.164283] microvm@hermes-bob[1283]: [ OK ] Listening on Credential Encryption/Decryption.1277machine # [ 94.171147] microvm@hermes-bob[1283]: [ OK ] Listening on Factory Reset Management.1278machine # [ 94.173693] microvm@hermes-bob[1283]: [ OK ] Listening on Hostname Service Socket.1279machine # [ 94.179981] microvm@hermes-bob[1283]: Starting Journal Log Access Socket...1280machine # [ 94.184372] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Audit Socket.1281machine # [ 94.193152] microvm@hermes-bob[1283]: [ OK ] Listening on Console Output Muting Service Socket.1282machine # [ 94.196397] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Resolve Hook Socket.1283machine # [ 94.201530] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Metrics Varlink Socket.1284machine # [ 94.204635] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Varlink Socket.1285machine # [ 94.207796] microvm@hermes-bob[1283]: [ OK ] Listening on Network Management Netlink Socket.1286machine # [ 94.211196] microvm@hermes-bob[1283]: [ OK ] Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1287machine # [ 94.227589] microvm@hermes-bob[1283]: [ OK ] Listening on Disk Repartitioning Service Socket.1288machine # [ 94.230558] microvm@hermes-bob[1283]: [ OK ] Listening on Resolve Monitor Varlink Socket.1289machine # [ 94.233695] microvm@hermes-bob[1283]: [ OK ] Listening on Resolve Service Varlink Socket.1290machine # [ 94.236358] microvm@hermes-bob[1283]: [ OK ] Listening on udev Control Socket.1291machine # [ 94.238872] microvm@hermes-bob[1283]: [ OK ] Listening on udev Varlink Socket.1292machine # [ 94.246225] microvm@hermes-bob[1283]: Mounting Huge Pages File System...1293machine # [ 94.265254] microvm@hermes-bob[1283]: Mounting POSIX Message Queue File System...1294machine # [ 94.284920] microvm@hermes-bob[1283]: Mounting Kernel Debug File System...1295machine # [ 94.313987] microvm@hermes-bob[1283]: Mounting Kernel Trace File System...1296machine # [ 94.332112] microvm@hermes-bob[1283]: Starting Create List of Static Device Nodes...1297machine # [ 94.375465] microvm@hermes-bob[1283]: Starting Load Kernel Module configfs...1298machine # [ 94.409732] microvm@hermes-bob[1283]: Mounting FUSE Control File System...1299machine # [ 94.455888] microvm@hermes-bob[1283]: Starting Journal Service...1300machine # [ 94.474253] microvm@hermes-bob[1283]: Starting Load Kernel Modules...1301machine # [ 94.519207] microvm@hermes-bob[1283]: Starting Userspace Out-Of-Memory (OOM) Killer...1302machine # [ 94.535532] microvm@hermes-bob[1283]: Starting Remount Root and Kernel File Systems...1303machine # [ 94.572202] microvm@hermes-bob[1283]: Starting Coldplug All udev Devices...1304machine # [ 94.625594] microvm@hermes-bob[1283]: [ OK ] Listening on Journal Log Access Socket.1305machine # [ 94.633124] microvm@hermes-bob[1283]: [ OK ] Mounted Huge Pages File System.1306machine # [ 94.644254] microvm@hermes-bob[1283]: [ OK ] Mounted POSIX Message Queue File System.1307machine # [ 94.651061] microvm@hermes-bob[1283]: [ OK ] Mounted Kernel Debug File System.1308machine # [ 94.656667] microvm@hermes-bob[1283]: [ OK ] Mounted Kernel Trace File System.1309machine # [ 94.698627] microvm@hermes-bob[1283]: [ OK ] Finished Create List of Static Device Nodes.1310machine # [ 94.709697] microvm@hermes-bob[1283]: [ OK ] Started Journal Service.1311machine # [ 94.715591] microvm@hermes-bob[1283]: [ OK ] Mounted FUSE Control File System.1312machine # [ 94.794520] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev gracefully...1313machine # [ 94.806603] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Module configfs.1314machine # [ 94.851405] microvm@hermes-bob[1283]: Mounting Kernel Configuration File System...1315machine # [ 94.891062] microvm@hermes-bob[1283]: [ OK ] Started Userspace Out-Of-Memory (OOM) Killer.1316machine # [ 94.900119] microvm@hermes-bob[1283]: [ OK ] Finished Load Kernel Modules.1317machine # [ 94.904576] microvm@hermes-bob[1283]: [ OK ] Finished Remount Root and Kernel File Systems.1318machine # [ 94.912464] microvm@hermes-bob[1283]: [ OK ] Listening on Disk Image Download Service Socket.1319machine # [ 94.923193] microvm@hermes-bob[1283]: Starting Flush Journal to Persistent Storage...1320machine # [ 94.990053] microvm@hermes-bob[1283]: Starting Load/Save OS Random Seed...1321machine # [ 95.017143] microvm@hermes-bob[1283]: Starting Apply Kernel Variables...1322machine # [ 95.099291] microvm@hermes-bob[1283]: Starting Network Time Synchronization...1323machine # [ 95.119820] microvm@hermes-bob[1283]: [ OK ] Finished Coldplug All udev Devices.1324machine # [ 95.127267] microvm@hermes-bob[1283]: [ OK ] Mounted Kernel Configuration File System.1325machine # [ 95.225404] microvm@hermes-bob[1283]: [ OK ] Finished Load/Save OS Random Seed.1326machine # [ 95.256625] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev gracefully.1327machine # [ 95.264438] microvm@hermes-bob[1283]: [ OK ] Finished Flush Journal to Persistent Storage.1328machine # [ 95.276469] microvm@hermes-bob[1283]: Starting Create Static Device Nodes in /dev...1329machine # [ 95.351253] microvm@hermes-bob[1283]: [ OK ] Finished Apply Kernel Variables.1330machine # [ 95.363263] microvm@hermes-bob[1283]: Starting Network Name Resolution...1331machine # [ 95.397489] microvm@hermes-bob[1283]: [ OK ] Started Network Time Synchronization.1332machine # [ 95.406725] microvm@hermes-bob[1283]: [ OK ] Reached target System Time Set.1333machine # [ 95.499138] microvm@hermes-bob[1283]: [ OK ] Finished Create Static Device Nodes in /dev.1334machine # [ 95.504281] microvm@hermes-bob[1283]: [ OK ] Reached target Preparation for Local File Systems.1335machine # [ 95.532362] microvm@hermes-bob[1283]: Starting Rule-based Manager for Device Events and Files...1336machine # [ 95.741988] microvm@hermes-bob[1283]: [ OK ] Started Network Name Resolution.1337machine # [ 95.866604] microvm@hermes-bob[1283]: [ OK ] Started Rule-based Manager for Device Events and Files.1338machine # [ 95.875135] microvm@hermes-bob[1283]: Starting Network Management...1339machine # [ 96.194817] microvm@hermes-bob[1283]: Mounting /home/bob/hermes...1340machine # [ 96.242867] microvm@hermes-bob[1283]: Mounting /run/wrappers...1341machine # [ 96.353530] microvm@hermes-bob[1283]: Starting Virtual Console Setup...1342machine # [ 96.507469] microvm@hermes-bob[1283]: [ OK ] Mounted /home/bob/hermes.1343machine # [ 96.584631] microvm@hermes-bob[1283]: [ OK ] Mounted /run/wrappers.1344machine # [ 96.591602] microvm@hermes-bob[1283]: [ OK ] Reached target Local File Systems.1345machine # [ 96.601980] microvm@hermes-bob[1283]: [ OK ] Listening on Boot Loader Control Service Socket.1346machine # [ 96.615626] microvm@hermes-bob[1283]: Starting Create SUID/SGID Wrappers...1347machine # [ 96.690212] microvm@hermes-bob[1283]: Starting Create System Files and Directories...1348machine # [ 97.052061] microvm@hermes-bob[1283]: [ OK ] Finished Create System Files and Directories.1349machine # [ 97.064089] microvm@hermes-bob[1283]: [ OK ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1350machine # [ 97.082044] microvm@hermes-bob[1283]: Starting Rebuild Journal Catalog...1351machine # [ 97.142584] microvm@hermes-bob[1283]: Starting Record System Boot/Shutdown in UTMP...1352machine # [ 97.370488] microvm@hermes-bob[1283]: [ OK ] Finished Record System Boot/Shutdown in UTMP.1353machine # [ 97.468304] microvm@hermes-bob[1283]: [ OK ] Finished Rebuild Journal Catalog.1354machine # [ 97.495957] microvm@hermes-bob[1283]: Starting Update is Completed...1355machine # [ 97.783915] microvm@hermes-bob[1283]: [ OK ] Finished Update is Completed.1356machine # [ 98.073417] microvm@hermes-bob[1283]: [ OK ] Finished Virtual Console Setup.1357machine # [ 98.202158] microvm@hermes-bob[1283]: [ OK ] Started Network Management.1358machine # [ 98.208773] microvm@hermes-bob[1283]: [ OK ] Reached target Network.1359machine # [ 98.214772] microvm@hermes-bob[1283]: [ OK ] Reached target Network is Online.1360machine # [ 98.229297] microvm@hermes-bob[1283]: Starting Enable Persistent Storage in systemd-networkd...1361machine # [ 98.391885] microvm@hermes-bob[1283]: [ OK ] Finished Enable Persistent Storage in systemd-networkd.1362machine # [ 98.813112] microvm@hermes-bob[1283]: [ OK ] Finished Create SUID/SGID Wrappers.1363machine # [ 98.816735] microvm@hermes-bob[1283]: [ OK ] Reached target System Initialization.1364machine # [ 98.821939] microvm@hermes-bob[1283]: [ OK ] Started Discard unused filesystem blocks once a week.1365machine # [ 98.824425] microvm@hermes-bob[1283]: [ OK ] Started logrotate.timer.1366machine # [ 98.827681] microvm@hermes-bob[1283]: [ OK ] Started Daily Cleanup of Temporary Directories.1367machine # [ 98.830418] microvm@hermes-bob[1283]: [ OK ] Reached target Timer Units.1368machine # [ 98.834317] microvm@hermes-bob[1283]: [ OK ] Listening on D-Bus System Message Bus Socket.1369machine # [ 98.837111] microvm@hermes-bob[1283]: [ OK ] Listening on Nix Daemon Socket.1370machine # [ 98.846159] microvm@hermes-bob[1283]: [ OK ] Listening on OpenSSH Server Socket…temd-ssh-generator, AF_UNIX Local).1371machine # [ 98.863237] microvm@hermes-bob[1283]: [ OK ] Listening on SSH Socket.1372machine # [ 98.869207] microvm@hermes-bob[1283]: [ OK ] Listening on Virtual Machine and C…tainer Registration Service Socket.1373machine # [ 98.873633] microvm@hermes-bob[1283]: [ OK ] Reached target Socket Units.1374machine # [ 98.876307] microvm@hermes-bob[1283]: [ OK ] Reached target Basic System.1375machine # [ 98.883188] microvm@hermes-bob[1283]: Starting Hermes python venv (pip-writable)...1376machine # [ 98.905369] microvm@hermes-bob[1283]: Starting Import lastlog data into lastlog2 database...1377machine # [ 98.933255] microvm@hermes-bob[1283]: Starting Logrotate configuration check...1378machine # [ 98.961155] microvm@hermes-bob[1283]: Starting Name Service Cache Daemon (nsncd)...1379machine # [ 98.991970] microvm@hermes-bob[1283]: Starting Post-Boot Actions...1380machine # [ 99.037294] microvm@hermes-bob[1283]: [ OK ] Started Reset console on configuration changes.1381machine # [ 99.073504] microvm@hermes-bob[1283]: Starting D-Bus System Message Bus...1382machine # [ 99.178876] microvm@hermes-bob[1283]: [ OK ] Finished Logrotate configuration check.1383machine # [ 99.237284] microvm@hermes-bob[1283]: [ OK ] Finished Post-Boot Actions.1384machine # [ 99.265636] microvm@hermes-bob[1283]: [ OK ] Started Name Service Cache Daemon (nsncd).1385machine # [ 99.279699] microvm@hermes-bob[1283]: [ OK ] Reached target Host and Network Name Lookups.1386machine # [ 99.287951] microvm@hermes-bob[1283]: [ OK ] Reached target User and Group Name Lookups.1387machine # [ 99.302977] microvm@hermes-bob[1283]: Starting User Login Management...1388machine # [ 99.351639] microvm@hermes-bob[1283]: Starting Permit User Sessions...1389machine # [ 99.391627] microvm@hermes-bob[1283]: [ OK ] Finished Import lastlog data into lastlog2 database.1390machine # [ 99.490325] microvm@hermes-bob[1283]: [ OK ] Started D-Bus System Message Bus.1391machine # [ 99.528816] microvm@hermes-bob[1283]: [ OK ] Finished Permit User Sessions.1392machine # [ 99.535096] microvm@hermes-bob[1283]: [ OK ] Started User Login Management.1393machine # [ 99.553658] microvm@hermes-bob[1283]: [ OK ] Started Getty on tty1.1394machine # [ 99.559579] microvm@hermes-bob[1283]: Starting linger-users.service...1395machine # [ 99.614136] microvm@hermes-bob[1283]: [ OK ] Started Serial Getty on ttyS0.1396machine # [ 99.620563] microvm@hermes-bob[1283]: [ OK ] Reached target Login Prompts.1397machine # [ 99.704994] microvm@hermes-bob[1283]: [ OK ] Finished linger-users.service.1398machine # [ 106.689101] microvm@hermes-bob[1283]: +q6E616D651399machine # [ 106.696256] microvm@hermes-bob[1283]:1400machine # [ 106.702113] microvm@hermes-bob[1283]: <<< Welcome to NixOS 26.11pre-git (x86_64) - ttyS0 >>>1401machine # [ 106.704143] microvm@hermes-bob[1283]:1402machine # [ 106.705313] microvm@hermes-bob[1283]: Run 'nixos-help' for the NixOS manual.1403machine # [ 106.706124] microvm@hermes-bob[1283]:1404machine # [ 111.220427] systemd[1]: Started MicroVM 'hermes-alice'.1405machine # [ 111.221957] systemd[1]: Reached target microvms.target.1406machine # [ 111.223209] systemd[1]: Reached target Multi-User System.1407machine # [ 111.223782] systemd[1]: Startup finished in 1.339s (kernel) + 16.330s (initrd) + 1min 33.553s (userspace) = 1min 51.223s.1408machine # [ 111.266299] supervisord[781]: virtiofsd-ro-store [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests1409machine # [ 111.266940] microvm@hermes-alice[1713]: microvm@hermes-alice: warning: Number of SMP cpus requested (8) exceeds the recommended cpus supported by KVM (4)1410machine # [ 111.267870] microvm@hermes-alice[1713]: microvm@hermes-alice: warning: Number of hotpluggable cpus requested (8) exceeds the recommended cpus supported by KVM (4)1411machine # [ 111.273437] supervisord[781]: virtiofsd-hermes-exchange [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests1412machine # [ 111.274032] supervisord[781]: virtiofsd-hermes-state [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests1413machine # [ 111.277407] supervisord[781]: virtiofsd-host-config [2026-08-01T04:22:44Z INFO virtiofsd] Client connected, servicing requests1414machine # [ 111.366239] microvm@hermes-alice[1713]: SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)1415machine: (finished: waiting for unit multi-user.target, in 113.28 seconds)1416machine: waiting for unit microvm@hermes-alice.service1417machine: (finished: waiting for unit microvm@hermes-alice.service, in 0.09 seconds)1418machine: waiting for unit microvm@hermes-bob.service1419machine # [ 111.795212] microvm@hermes-alice[1713]: Booting from ROM...1420machine # [ 111.799311] microvm@hermes-alice[1713]: PPrroobbiinngg EEDDDD ((eedddd==ooffff ttoo ddiissaabbllee))...... ook1421machine: (finished: waiting for unit microvm@hermes-bob.service, in 0.09 seconds)1422machine: waiting for success: runuser -u alice -- hermes --version1423machine # [ 111.864441] runuser[1748]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1424machine # [ 112.039810] microvm@hermes-alice[1713]: No EFI environment detected.1425machine # [ 112.044163] microvm@hermes-alice[1713]: early console in extract_kernel1426machine # [ 112.047336] microvm@hermes-alice[1713]: input_data: 0x0000000003acc2cc1427machine # [ 112.050377] microvm@hermes-alice[1713]: input_len: 0x0000000000cb3f6c1428machine # [ 112.053424] microvm@hermes-alice[1713]: output: 0x00000000010000001429machine # [ 112.056518] microvm@hermes-alice[1713]: output_len: 0x00000000037287481430machine # [ 112.059911] microvm@hermes-alice[1713]: kernel_total_size: 0x0000000002e300001431machine # [ 112.063155] microvm@hermes-alice[1713]: needed_size: 0x00000000038000001432machine # [ 112.066576] microvm@hermes-alice[1713]: trampoline_32bit: 0x00000000000000001433machine # [ 112.069658] microvm@hermes-alice[1713]: Physical KASLR using RDRAND RDTSC...1434machine # [ 112.072662] microvm@hermes-alice[1713]: Virtual KASLR using RDRAND RDTSC...1435machine # [ 112.073811] microvm@hermes-alice[1713]:1436machine # [ 112.076648] microvm@hermes-alice[1713]: Decompressing Linux... No EFI environment detected.1437machine # [ 112.716423] microvm@hermes-alice[1713]: Parsing ELF... Performing relocations... done.1438machine # [ 112.719602] microvm@hermes-alice[1713]: Booting the kernel (entry_offset: 0x0000000002788520).1439machine # [ 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 20261440machine # [ 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/registration1441machine # [ 112.760445] microvm@hermes-alice[1713]: [ 0.000000] BIOS-provided physical RAM map:1442machine # [ 112.765070] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable1443machine # [ 112.770116] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved1444machine # [ 112.775249] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved1445machine # [ 112.780932] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffdfff] usable1446machine # [ 112.787670] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000bfffe000-0x00000000bfffffff] reserved1447machine # [ 112.794385] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved1448machine # [ 112.798236] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved1449machine # [ 112.801866] microvm@hermes-alice[1713]: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable1450machine # [ 112.804783] microvm@hermes-alice[1713]: [ 0.000000] printk: legacy bootconsole [earlyser0] enabled1451machine # [ 112.810775] microvm@hermes-alice[1713]: Memory KASLR using RDRAND RDTSC...1452machine # Failed to connect to vsock:735577804:22: Connection timed out1453machine # mm_receive_fd: recvmsg: expected received 1 got 01454machine # proxy dialer did not pass back a connection1455machine # [ 113.948529] runuser[1748]: pam_unix(runuser:session): session closed for user alice1456machine # [ 114.982812] runuser[1758]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1457machine # [ 115.030239] microvm@hermes-alice[1713]: Poking KASLR using RDRAND RDTSC...1458machine # [ 115.264873] microvm@hermes-alice[1713]: [ 2.404265] PCI: Fatal: No config space access function found1459machine # Failed to connect to vsock:735577804:22: Connection timed out1460machine # mm_receive_fd: recvmsg: expected received 1 got 01461machine # proxy dialer did not pass back a connection1462machine # [ 117.085808] runuser[1758]: pam_unix(runuser:session): session closed for user alice1463machine # [ 118.085581] microvm@hermes-alice[1713]: +q6E616D65Booting initrd of NixOS 26.11 (Zokor) (Initrd).1464machine # [ 118.124403] runuser[1768]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1465machine # [ 118.344295] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/modprobe.1466machine # [ 118.349069] microvm@hermes-alice[1713]: [ OK ] Started Dispatch Password Requests to Console Directory Watch.1467machine # [ 118.352764] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd Root Device.1468machine # [ 118.356203] microvm@hermes-alice[1713]: [ OK ] Reached target Path Units.1469machine # [ 118.358982] microvm@hermes-alice[1713]: [ OK ] Reached target Slice Units.1470machine # [ 118.361554] microvm@hermes-alice[1713]: [ OK ] Reached target Swaps.1471machine # [ 118.364218] microvm@hermes-alice[1713]: [ OK ] Reached target Timer Units.1472machine # [ 118.369759] microvm@hermes-alice[1713]: [ OK ] Listening on D-Bus System Message Bus Socket.1473machine # [ 118.374278] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Socket (/dev/log).1474machine # [ 118.377647] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Sockets.1475machine # [ 118.383707] microvm@hermes-alice[1713]: [ OK ] Listening on udev Control Socket.1476machine # [ 118.386738] microvm@hermes-alice[1713]: [ OK ] Listening on udev Kernel Socket.1477machine # [ 118.390326] microvm@hermes-alice[1713]: [ OK ] Reached target Socket Units.1478machine # [ 118.399937] microvm@hermes-alice[1713]: Starting Create List of Static Device Nodes...1479machine # [ 118.428398] microvm@hermes-alice[1713]: Starting Load Kernel Module configfs...1480machine # [ 118.450362] microvm@hermes-alice[1713]: Starting Journal Service...1481machine # [ 118.459576] microvm@hermes-alice[1713]: Starting Load Kernel Modules...1482machine # [ 118.473195] microvm@hermes-alice[1713]: Starting Coldplug All udev Devices...1483machine # [ 118.488977] microvm@hermes-alice[1713]: [ OK ] Finished Create List of Static Device Nodes.1484machine # [ 118.504078] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Module configfs.1485machine # [ 118.519994] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev gracefully...1486machine # [ 118.573644] microvm@hermes-alice[1713]: [ OK ] Started Journal Service.1487machine # [ 118.598557] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev gracefully.1488machine # [ 118.642683] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev...1489machine # [ 118.723619] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev.1490machine # [ 118.728603] microvm@hermes-alice[1713]: [ OK ] Reached target Preparation for Local File Systems.1491machine # [ 118.733179] microvm@hermes-alice[1713]: [ OK ] Reached target Local File Systems.1492machine # [ 118.739539] microvm@hermes-alice[1713]: Starting Create System Files and Directories...1493machine # [ 118.904282] microvm@hermes-alice[1713]: Starting Rule-based Manager for Device Events and Files...1494machine # [ 118.908701] microvm@hermes-alice[1713]: [ OK ] Finished Coldplug All udev Devices.1495machine # [ 118.919079] microvm@hermes-alice[1713]: [ OK ] Finished Create System Files and Directories.1496machine # [ 119.038506] microvm@hermes-alice[1713]: [ OK ] Started Rule-based Manager for Device Events and Files.1497machine # [ 119.285396] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Modules.1498machine # [ 119.288982] microvm@hermes-alice[1713]: Starting Apply Kernel Variables...1499machine # [ 119.367768] microvm@hermes-alice[1713]: Starting Virtual Console Setup...1500machine # [ 119.382331] microvm@hermes-alice[1713]: [ OK ] Finished Apply Kernel Variables.1501machine # [ 119.432182] microvm@hermes-alice[1713]: [ OK ] Finished Virtual Console Setup.1502machine # [ 119.439652] microvm@hermes-alice[1713]: [ OK ] Reached target System Initialization.1503machine # [ 119.444193] microvm@hermes-alice[1713]: [ OK ] Reached target Basic System.1504machine # [ 119.479480] microvm@hermes-alice[1713]: Mounting /sysroot...1505machine # [ 119.503691] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot.1506machine # [ 119.510947] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd Root File System.1507machine # [ 119.521112] microvm@hermes-alice[1713]: Mounting /sysroot/nix/.ro-store...1508machine # [ 119.534177] microvm@hermes-alice[1713]: Mounting /sysroot/run...1509machine # [ 119.544518] microvm@hermes-alice[1713]: Mounting /sysroot/var/lib/hermes...1510machine # [ 119.557225] microvm@hermes-alice[1713]: Starting Mountpoints Configured in the Real Root...1511machine # [ 119.564059] microvm@hermes-alice[1713]: Starting rw-sysroot-nix-store.service...1512machine # [ 119.569945] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/nix/.ro-store.1513machine # [ 119.589159] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/run.1514machine # [ 119.600583] microvm@hermes-alice[1713]: Mounting /sysroot/run/hermes-host...1515machine # [ 119.605422] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/var/lib/hermes.1516machine # [ 119.612249] microvm@hermes-alice[1713]: [ OK ] Finished rw-sysroot-nix-store.service.1517machine # [ 119.625066] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/run/hermes-host.1518machine # [ 119.728684] microvm@hermes-alice[1713]: Starting rw-sysroot-nix-store.service...1519machine # [ 119.740308] microvm@hermes-alice[1713]: [ OK ] Finished Mountpoints Configured in the Real Root.1520machine # [ 119.747553] microvm@hermes-alice[1713]: [ OK ] Finished rw-sysroot-nix-store.service.1521machine # Failed to connect to vsock:735577804:22: Connection timed out1522machine # mm_receive_fd: recvmsg: expected received 1 got 01523machine # proxy dialer did not pass back a connection1524machine # [ 120.220258] runuser[1768]: pam_unix(runuser:session): session closed for user alice1525machine # [ 120.483034] microvm@hermes-alice[1713]: Mounting /sysroot/nix/store...1526machine # [ 120.505103] microvm@hermes-alice[1713]: [ OK ] Mounted /sysroot/nix/store.1527machine # [ 120.508657] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd File Systems.1528machine # [ 120.516103] microvm@hermes-alice[1713]: Starting Find NixOS closure...1529machine # [ 120.532179] microvm@hermes-alice[1713]: Starting Create Volatile Files and Directories in the Real Root...1530machine # [ 120.541365] microvm@hermes-alice[1713]: [ OK ] Finished Create Volatile Files and Directories in the Real Root.1531machine # [ 120.558766] microvm@hermes-alice[1713]: [ OK ] Finished Find NixOS closure.1532machine # [ 120.565213] microvm@hermes-alice[1713]: [ OK ] Reached target Initrd Default Target.1533machine # [ 120.571619] microvm@hermes-alice[1713]: Starting Cleaning Up and Shutting Down Daemons...1534machine # [ 120.592758] microvm@hermes-alice[1713]: [ OK ] Stopped target Initrd Default Target.1535machine # [ 120.597233] microvm@hermes-alice[1713]: [ OK ] Stopped target Basic System.1536machine # [ 120.602455] microvm@hermes-alice[1713]: [ OK ] Stopped target Initrd Root Device.1537machine # [ 120.607700] microvm@hermes-alice[1713]: [ OK ] Stopped target Path Units.1538machine # [ 120.614116] microvm@hermes-alice[1713]: [ OK ] Stopped Dispatch Password Requests to Console Directory Watch.1539machine # [ 120.618615] microvm@hermes-alice[1713]: [ OK ] Stopped target Slice Units.1540machine # [ 120.623188] microvm@hermes-alice[1713]: [ OK ] Stopped target Socket Units.1541machine # [ 120.627556] microvm@hermes-alice[1713]: [ OK ] Stopped target System Initialization.1542machine # [ 120.630950] microvm@hermes-alice[1713]: [ OK ] Stopped target Swaps.1543machine # [ 120.636355] microvm@hermes-alice[1713]: [ OK ] Stopped target Timer Units.1544machine # [ 120.641712] microvm@hermes-alice[1713]: [ OK ] Closed D-Bus System Message Bus Socket.1545machine # [ 120.647116] microvm@hermes-alice[1713]: [ OK ] Stopped Find NixOS closure.1546machine # [ 120.651684] microvm@hermes-alice[1713]: Starting rw-sysroot-nix-store.service...1547machine # [ 120.657485] microvm@hermes-alice[1713]: [ OK ] Stopped Apply Kernel Variables.1548machine # [ 120.662724] microvm@hermes-alice[1713]: [ OK ] Stopped Load Kernel Modules.1549machine # [ 120.670098] microvm@hermes-alice[1713]: [ OK ] Stopped Create Volatile Files and Directories in the Real Root.1550machine # [ 120.675568] microvm@hermes-alice[1713]: [ OK ] Stopped Create System Files and Directories.1551machine # [ 120.680984] microvm@hermes-alice[1713]: [ OK ] Stopped target Local File Systems.1552machine # [ 120.687132] microvm@hermes-alice[1713]: [ OK ] Stopped target Preparation for Local File Systems.1553machine # [ 120.691641] microvm@hermes-alice[1713]: [ OK ] Stopped Coldplug All udev Devices.1554machine # [ 120.698850] microvm@hermes-alice[1713]: Stopping Rule-based Manager for Device Events and Files...1555machine # [ 120.704469] microvm@hermes-alice[1713]: [ OK ] Stopped Virtual Console Setup.1556machine # [ 120.711969] microvm@hermes-alice[1713]: [ OK ] Finished rw-sysroot-nix-store.service.1557machine # [ 120.725312] microvm@hermes-alice[1713]: [ OK ] Finished Cleaning Up and Shutting Down Daemons.1558machine # [ 120.735296] microvm@hermes-alice[1713]: [ OK ] Stopped Rule-based Manager for Device Events and Files.1559machine # [ 120.742389] microvm@hermes-alice[1713]: [ OK ] Closed udev Control Socket.1560machine # [ 120.747619] microvm@hermes-alice[1713]: Starting Cleanup udev Database...1561machine # [ 120.754036] microvm@hermes-alice[1713]: [ OK ] Stopped Create Static Device Nodes in /dev.1562machine # [ 120.762417] microvm@hermes-alice[1713]: [ OK ] Stopped Create Static Device Nodes in /dev gracefully.1563machine # [ 120.767707] microvm@hermes-alice[1713]: [ OK ] Stopped Create List of Static Device Nodes.1564machine # [ 120.790059] microvm@hermes-alice[1713]: [ OK ] Finished Cleanup udev Database.1565machine # [ 120.794547] microvm@hermes-alice[1713]: [ OK ] Reached target Switch Root.1566machine # [ 120.799290] microvm@hermes-alice[1713]: Starting NixOS Activation...1567machine # [ 121.250359] runuser[1778]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1568machine # [ 123.016791] microvm@hermes-alice[1713]: [* ] A start job is running for NixOS Activation (2s / no limit)1569machine # Failed to connect to vsock:735577804:22: Connection timed out1570machine # mm_receive_fd: recvmsg: expected received 1 got 01571machine # proxy dialer did not pass back a connection1572machine # [ 123.357748] runuser[1778]: pam_unix(runuser:session): session closed for user alice1573machine # [ 123.517633] microvm@hermes-alice[1713]: [** ] A start job is running for NixOS Activation (2s / no limit)1574machine # [ 124.016966] microvm@hermes-alice[1713]: [*** ] A start job is running for NixOS Activation (3s / no limit)1575machine # [ 124.393738] runuser[1788]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1576machine # [ 124.517356] microvm@hermes-alice[1713]: [ *** ] A start job is running for NixOS Activation (3s / no limit)1577machine # [ 125.017227] microvm@hermes-alice[1713]: [ *** ] A start job is running for NixOS Activation (4s / no limit)1578machine # [ 125.516375] microvm@hermes-alice[1713]: [ ***] A start job is running for NixOS Activation (4s / no limit)1579machine # [ 126.015920] microvm@hermes-alice[1713]: [ **] A start job is running for NixOS Activation (5s / no limit)1580machine # [ 126.359130] microvm@hermes-alice[1713]: [ *] A start job is running for NixOS Activation (5s / no limit)1581machine # [ 126.367399] microvm@hermes-alice[1713]: [ OK ] Finished NixOS Activation.1582machine # [ 126.374315] microvm@hermes-alice[1713]: Starting Switch Root...1583machine # Failed to connect to vsock:735577804:22: Connection timed out1584machine # mm_receive_fd: recvmsg: expected received 1 got 01585machine # proxy dialer did not pass back a connection1586machine # [ 126.497216] runuser[1788]: pam_unix(runuser:session): session closed for user alice1587machine # [ 127.531253] runuser[1798]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1588machine # Failed to connect to vsock:735577804:22: Connection reset by peer1589machine # mm_receive_fd: recvmsg: expected received 1 got 01590machine # proxy dialer did not pass back a connection1591machine # [ 127.567599] runuser[1798]: pam_unix(runuser:session): session closed for user alice1592machine # [ 127.855603] microvm@hermes-alice[1713]: +q6E616D651593machine # [ 127.860224] microvm@hermes-alice[1713]: Welcome to NixOS 26.11 (Zokor)!1594machine # [ 127.862214] microvm@hermes-alice[1713]:1595machine # [ 128.595846] runuser[1808]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1596machine # Failed to connect to vsock:735577804:22: Connection reset by peer1597machine # mm_receive_fd: recvmsg: expected received 1 got 01598machine # proxy dialer did not pass back a connection1599machine # [ 128.638891] runuser[1808]: pam_unix(runuser:session): session closed for user alice1600machine # [ 129.501282] microvm@hermes-alice[1713]: [ OK ] Stopped initrd-switch-root.service.1601machine # [ 129.506214] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/getty.1602machine # [ 129.511300] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/serial-getty.1603machine # [ 129.515970] microvm@hermes-alice[1713]: [ OK ] Created slice User and Session Slice.1604machine # [ 129.521033] microvm@hermes-alice[1713]: [ OK ] Started Dispatch Password Requests to Console Directory Watch.1605machine # [ 129.526870] microvm@hermes-alice[1713]: [ OK ] Started Forward Password Requests to Wall Directory Watch.1606machine # [ 129.529125] microvm@hermes-alice[1713]: Expecting device /dev/ttyS0...1607machine # [ 129.532069] microvm@hermes-alice[1713]: [ OK ] Reached target Local Encrypted Volumes.1608machine # [ 129.535289] microvm@hermes-alice[1713]: [ OK ] Stopped target initrd-fs.target.1609machine # [ 129.538305] microvm@hermes-alice[1713]: [ OK ] Stopped target initrd-root-fs.target.1610machine # [ 129.541422] microvm@hermes-alice[1713]: [ OK ] Stopped target initrd-switch-root.target.1611machine # [ 129.544923] microvm@hermes-alice[1713]: [ OK ] Reached target Virtual Machines and Containers.1612machine # [ 129.547351] microvm@hermes-alice[1713]: [ OK ] Reached target Path Units.1613machine # [ 129.550203] microvm@hermes-alice[1713]: [ OK ] Reached target Remote File Systems.1614machine # [ 129.552779] microvm@hermes-alice[1713]: [ OK ] Reached target Slice Units.1615machine # [ 129.555142] microvm@hermes-alice[1713]: [ OK ] Reached target Swaps.1616machine # [ 129.562771] microvm@hermes-alice[1713]: [ OK ] Listening on Query the User Interactively for a Password.1617machine # [ 129.569775] microvm@hermes-alice[1713]: [ OK ] Listening on Process Core Dump Socket.1618machine # [ 129.578762] microvm@hermes-alice[1713]: [ OK ] Listening on Credential Encryption/Decryption.1619machine # [ 129.587150] microvm@hermes-alice[1713]: [ OK ] Listening on Factory Reset Management.1620machine # [ 129.590322] microvm@hermes-alice[1713]: [ OK ] Listening on Hostname Service Socket.1621machine # [ 129.598604] microvm@hermes-alice[1713]: Starting Journal Log Access Socket...1622machine # [ 129.603216] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Audit Socket.1623machine # [ 129.614081] microvm@hermes-alice[1713]: [ OK ] Listening on Console Output Muting Service Socket.1624machine # [ 129.617343] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Resolve Hook Socket.1625machine # [ 129.621100] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Metrics Varlink Socket.1626machine # [ 129.624842] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Varlink Socket.1627machine # [ 129.628776] microvm@hermes-alice[1713]: [ OK ] Listening on Network Management Netlink Socket.1628machine # [ 129.633557] microvm@hermes-alice[1713]: [ OK ] Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1629machine # [ 129.654780] microvm@hermes-alice[1713]: [ OK ] Listening on Disk Repartitioning Service Socket.1630machine # [ 129.657734] microvm@hermes-alice[1713]: [ OK ] Listening on Resolve Monitor Varlink Socket.1631machine # [ 129.661135] microvm@hermes-alice[1713]: [ OK ] Listening on Resolve Service Varlink Socket.1632machine # [ 129.664668] microvm@hermes-alice[1713]: [ OK ] Listening on udev Control Socket.1633machine # [ 129.668355] microvm@hermes-alice[1713]: [ OK ] Listening on udev Varlink Socket.1634machine # [ 129.674913] runuser[1818]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1635machine # [ 129.676568] microvm@hermes-alice[1713]: Mounting Huge Pages File System...1636machine # Failed to connect to vsock:735577804:22: Connection reset by peer1637machine # mm_receive_fd: recvmsg: expected received 1 got 01638machine # [ 129.715685] microvm@hermes-alice[1713]: Mounting POSIX Message Queue File System...1639machine # proxy dialer did not pass back a connection1640machine # [ 129.719394] runuser[1818]: pam_unix(runuser:session): session closed for user alice1641machine # [ 129.734889] microvm@hermes-alice[1713]: Mounting Kernel Debug File System...1642machine # [ 129.771121] microvm@hermes-alice[1713]: Mounting Kernel Trace File System...1643machine # [ 129.803123] microvm@hermes-alice[1713]: Starting Create List of Static Device Nodes...1644machine # [ 129.876931] microvm@hermes-alice[1713]: Starting Load Kernel Module configfs...1645machine # [ 129.906245] microvm@hermes-alice[1713]: Mounting FUSE Control File System...1646machine # [ 130.026618] microvm@hermes-alice[1713]: Starting Journal Service...1647machine # [ 130.046093] microvm@hermes-alice[1713]: Starting Load Kernel Modules...1648machine # [ 130.175649] microvm@hermes-alice[1713]: Starting Userspace Out-Of-Memory (OOM) Killer...1649machine # [ 130.196088] microvm@hermes-alice[1713]: Starting Remount Root and Kernel File Systems...1650machine # [ 130.257954] microvm@hermes-alice[1713]: Starting Coldplug All udev Devices...1651machine # [ 130.275637] microvm@hermes-alice[1713]: [ OK ] Listening on Journal Log Access Socket.1652machine # [ 130.280302] microvm@hermes-alice[1713]: [ OK ] Mounted Huge Pages File System.1653machine # [ 130.283972] microvm@hermes-alice[1713]: [ OK ] Mounted POSIX Message Queue File System.1654machine # [ 130.314526] microvm@hermes-alice[1713]: [ OK ] Mounted Kernel Debug File System.1655machine # [ 130.323134] microvm@hermes-alice[1713]: [ OK ] Mounted Kernel Trace File System.1656machine # [ 130.330286] microvm@hermes-alice[1713]: [ OK ] Finished Create List of Static Device Nodes.1657machine # [ 130.341381] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Module configfs.1658machine # [ 130.350620] microvm@hermes-alice[1713]: [ OK ] Mounted FUSE Control File System.1659machine # [ 130.358162] microvm@hermes-alice[1713]: [ OK ] Started Journal Service.1660machine # [ 130.387982] microvm@hermes-alice[1713]: Mounting Kernel Configuration File System...1661machine # [ 130.432568] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev gracefully...1662machine # [ 130.472995] microvm@hermes-alice[1713]: [ OK ] Finished Load Kernel Modules.1663machine # [ 130.543179] microvm@hermes-alice[1713]: Starting Apply Kernel Variables...1664machine # [ 130.583858] microvm@hermes-alice[1713]: [ OK ] Started Userspace Out-Of-Memory (OOM) Killer.1665machine # [ 130.593866] microvm@hermes-alice[1713]: [ OK ] Finished Remount Root and Kernel File Systems.1666machine # [ 130.606677] microvm@hermes-alice[1713]: [ OK ] Listening on Disk Image Download Service Socket.1667machine # [ 130.619888] microvm@hermes-alice[1713]: Starting Flush Journal to Persistent Storage...1668machine # [ 130.672163] microvm@hermes-alice[1713]: Starting Load/Save OS Random Seed...1669machine # [ 130.706137] microvm@hermes-alice[1713]: Starting Network Time Synchronization...1670machine # [ 130.761197] runuser[1828]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1671machine # [ 130.783607] microvm@hermes-alice[1713]: [ OK ] Finished Coldplug All udev Devices.1672machine # [ 130.792627] microvm@hermes-alice[1713]: [ OK ] Mounted Kernel Configuration File System.1673machine # Failed to connect to vsock:735577804:22: Connection reset by peer1674machine # mm_receive_fd: recvmsg: expected received 1 got 01675machine # proxy dialer did not pass back a connection1676machine # [ 130.828292] runuser[1828]: pam_unix(runuser:session): session closed for user alice1677machine # [ 130.853687] microvm@hermes-alice[1713]: [ OK ] Finished Load/Save OS Random Seed.1678machine # [ 130.897192] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev gracefully.1679machine # [ 130.906599] microvm@hermes-alice[1713]: [ OK ] Finished Apply Kernel Variables.1680machine # [ 130.920522] microvm@hermes-alice[1713]: Starting Network Name Resolution...1681machine # [ 130.964243] microvm@hermes-alice[1713]: Starting Create Static Device Nodes in /dev...1682machine # [ 130.977726] microvm@hermes-alice[1713]: [ OK ] Finished Flush Journal to Persistent Storage.1683machine # [ 131.087177] microvm@hermes-alice[1713]: [ OK ] Started Network Time Synchronization.1684machine # [ 131.094718] microvm@hermes-alice[1713]: [ OK ] Reached target System Time Set.1685machine # [ 131.178167] microvm@hermes-alice[1713]: [ OK ] Finished Create Static Device Nodes in /dev.1686machine # [ 131.185105] microvm@hermes-alice[1713]: [ OK ] Reached target Preparation for Local File Systems.1687machine # [ 131.194992] microvm@hermes-alice[1713]: Starting Rule-based Manager for Device Events and Files...1688machine # [ 131.238326] microvm@hermes-alice[1713]: [ OK ] Started Network Name Resolution.1689machine # [ 131.492343] microvm@hermes-alice[1713]: [ OK ] Started Rule-based Manager for Device Events and Files.1690machine # [ 131.504843] microvm@hermes-alice[1713]: Starting Network Management...1691machine # [ 131.594550] microvm@hermes-alice[1713]: Mounting /home/alice/hermes...1692machine # [ 131.632938] microvm@hermes-alice[1713]: Mounting /run/wrappers...1693machine # [ 131.867133] runuser[1838]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1694machine # Failed to connect to vsock:735577804:22: Connection reset by peer1695machine # mm_receive_fd: recvmsg: expected received 1 got 01696machine # proxy dialer did not pass back a connection1697machine # [ 131.949157] runuser[1838]: pam_unix(runuser:session): session closed for user alice1698machine # [ 131.964543] microvm@hermes-alice[1713]: Starting Virtual Console Setup...1699machine # [ 132.495233] microvm@hermes-alice[1713]: [ OK ] Mounted /run/wrappers.1700machine # [ 132.499627] microvm@hermes-alice[1713]: [ OK ] Mounted /home/alice/hermes.1701machine # [ 132.505279] microvm@hermes-alice[1713]: [ OK ] Reached target Local File Systems.1702machine # [ 132.522867] microvm@hermes-alice[1713]: [ OK ] Listening on Boot Loader Control Service Socket.1703machine # [ 132.553975] microvm@hermes-alice[1713]: Starting Create SUID/SGID Wrappers...1704machine # [ 132.745789] microvm@hermes-alice[1713]: Starting Create System Files and Directories...1705machine # [ 132.986932] runuser[1848]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1706machine # Failed to connect to vsock:735577804:22: Connection reset by peer1707machine # mm_receive_fd: recvmsg: expected received 1 got 01708machine # proxy dialer did not pass back a connection1709machine # [ 133.050707] runuser[1848]: pam_unix(runuser:session): session closed for user alice1710machine # [ 133.123862] microvm@hermes-alice[1713]: [ OK ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1711machine # [ 133.268766] microvm@hermes-alice[1713]: [ OK ] Finished Create System Files and Directories.1712machine # [ 133.291505] microvm@hermes-alice[1713]: Starting Rebuild Journal Catalog...1713machine # [ 133.402510] microvm@hermes-alice[1713]: Starting Record System Boot/Shutdown in UTMP...1714machine # [ 133.582133] microvm@hermes-alice[1713]: [ OK ] Finished Record System Boot/Shutdown in UTMP.1715machine # [ 133.643566] microvm@hermes-alice[1713]: [ OK ] Finished Rebuild Journal Catalog.1716machine # [ 133.656768] microvm@hermes-alice[1713]: Starting Update is Completed...1717machine # [ 133.974473] microvm@hermes-alice[1713]: [ OK ] Finished Update is Completed.1718machine # [ 133.986445] microvm@hermes-alice[1713]: [ OK ] Finished Virtual Console Setup.1719machine # [ 134.090636] runuser[1858]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1720machine # Failed to connect to vsock:735577804:22: Connection reset by peer1721machine # mm_receive_fd: recvmsg: expected received 1 got 01722machine # proxy dialer did not pass back a connection1723machine # [ 134.135244] runuser[1858]: pam_unix(runuser:session): session closed for user alice1724machine # [ 134.455146] microvm@hermes-alice[1713]: [ OK ] Started Network Management.1725machine # [ 134.466756] microvm@hermes-alice[1713]: [ OK ] Reached target Network.1726machine # [ 134.471465] microvm@hermes-alice[1713]: [ OK ] Reached target Network is Online.1727machine # [ 134.535559] microvm@hermes-alice[1713]: Starting Enable Persistent Storage in systemd-networkd...1728machine # [ 134.800045] microvm@hermes-alice[1713]: [ OK ] Finished Enable Persistent Storage in systemd-networkd.1729machine # [ 135.165272] runuser[1868]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1730machine # Failed to connect to vsock:735577804:22: Connection reset by peer1731machine # mm_receive_fd: recvmsg: expected received 1 got 01732machine # proxy dialer did not pass back a connection1733machine # [ 135.205653] runuser[1868]: pam_unix(runuser:session): session closed for user alice1734machine # [ 135.220443] microvm@hermes-alice[1713]: [ OK ] Finished Create SUID/SGID Wrappers.1735machine # [ 135.224383] microvm@hermes-alice[1713]: [ OK ] Reached target System Initialization.1736machine # [ 135.230940] microvm@hermes-alice[1713]: [ OK ] Started Discard unused filesystem blocks once a week.1737machine # [ 135.233949] microvm@hermes-alice[1713]: [ OK ] Started logrotate.timer.1738machine # [ 135.238075] microvm@hermes-alice[1713]: [ OK ] Started Daily Cleanup of Temporary Directories.1739machine # [ 135.241264] microvm@hermes-alice[1713]: [ OK ] Reached target Timer Units.1740machine # [ 135.246334] microvm@hermes-alice[1713]: [ OK ] Listening on D-Bus System Message Bus Socket.1741machine # [ 135.251247] microvm@hermes-alice[1713]: [ OK ] Listening on Nix Daemon Socket.1742machine # [ 135.260704] microvm@hermes-alice[1713]: [ OK ] Listening on OpenSSH Server Socket…temd-ssh-generator, AF_UNIX Local).1743machine # [ 135.276903] microvm@hermes-alice[1713]: [ OK ] Listening on SSH Socket.1744machine # [ 135.283090] microvm@hermes-alice[1713]: [ OK ] Listening on Virtual Machine and C…tainer Registration Service Socket.1745machine # [ 135.286394] microvm@hermes-alice[1713]: [ OK ] Reached target Socket Units.1746machine # [ 135.289292] microvm@hermes-alice[1713]: [ OK ] Reached target Basic System.1747machine # [ 135.296983] microvm@hermes-alice[1713]: Starting Hermes python venv (pip-writable)...1748machine # [ 135.336532] microvm@hermes-alice[1713]: Starting Import lastlog data into lastlog2 database...1749machine # [ 135.373676] microvm@hermes-alice[1713]: Starting Logrotate configuration check...1750machine # [ 135.427444] microvm@hermes-alice[1713]: Starting Name Service Cache Daemon (nsncd)...1751machine # [ 135.479442] microvm@hermes-alice[1713]: Starting Post-Boot Actions...1752machine # [ 135.536301] microvm@hermes-alice[1713]: [ OK ] Started Reset console on configuration changes.1753machine # [ 135.624174] microvm@hermes-alice[1713]: Starting D-Bus System Message Bus...1754machine # [ 135.671671] microvm@hermes-alice[1713]: [ OK ] Finished Logrotate configuration check.1755machine # [ 135.774475] microvm@hermes-alice[1713]: [ OK ] Finished Post-Boot Actions.1756machine # [ 135.781139] microvm@hermes-alice[1713]: [ OK ] Started Name Service Cache Daemon (nsncd).1757machine # [ 135.795542] microvm@hermes-alice[1713]: [ OK ] Reached target Host and Network Name Lookups.1758machine # [ 135.801728] microvm@hermes-alice[1713]: [ OK ] Reached target User and Group Name Lookups.1759machine # [ 135.819987] microvm@hermes-alice[1713]: Starting User Login Management...1760machine # [ 135.854105] microvm@hermes-alice[1713]: Starting Permit User Sessions...1761machine # [ 135.876830] microvm@hermes-alice[1713]: [ OK ] Finished Import lastlog data into lastlog2 database.1762machine # [ 135.985455] microvm@hermes-alice[1713]: [ OK ] Finished Permit User Sessions.1763machine # [ 135.999400] microvm@hermes-alice[1713]: [ OK ] Started Getty on tty1.1764machine # [ 136.039461] microvm@hermes-alice[1713]: [ OK ] Started Serial Getty on ttyS0.1765machine # [ 136.045915] microvm@hermes-alice[1713]: [ OK ] Reached target Login Prompts.1766machine # [ 136.062213] microvm@hermes-alice[1713]: [ OK ] Started D-Bus System Message Bus.1767machine # [ 136.112717] microvm@hermes-alice[1713]: [ OK ] Started User Login Management.1768machine # [ 136.128160] microvm@hermes-alice[1713]: Starting linger-users.service...1769machine # [ 136.234739] runuser[1878]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1770machine # [ 136.265943] microvm@hermes-alice[1713]: [ OK ] Finished linger-users.service.1771machine # [ 136.294446] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /system/sshd.1772machine # [ 136.307255] microvm@hermes-alice[1713]: [ OK ] Started SSH per-connection Daemon (vsock:2:1418679566).1773machine # [ 136.868078] microvm@hermes-alice[1713]: [ OK ] Created slice Slice /user/1000.1774machine # [ 136.877490] microvm@hermes-alice[1713]: Starting User Runtime Directory /run/user/1000...1775machine # [ 137.033067] microvm@hermes-alice[1713]: [ OK ] Finished User Runtime Directory /run/user/1000.1776machine # [ 137.042925] microvm@hermes-alice[1713]: Starting User Manager for UID 1000...1777machine # [ 138.341120] microvm@hermes-alice[1713]: [ OK ] Started User Manager for UID 1000.1778machine # [ 138.399551] microvm@hermes-alice[1713]: [ OK ] Started Session 1 of User alice.1779machine # [ 143.194760] microvm@hermes-alice[1713]: +q6E616D651780machine # [ 143.195801] microvm@hermes-alice[1713]:1781machine # [ 143.197760] microvm@hermes-alice[1713]: <<< Welcome to NixOS 26.11pre-git (x86_64) - ttyS0 >>>1782machine # [ 143.198663] microvm@hermes-alice[1713]:1783machine # [ 143.199844] microvm@hermes-alice[1713]: Run 'nixos-help' for the NixOS manual.1784machine # [ 143.200592] microvm@hermes-alice[1713]:1785machine # [ 145.141424] runuser[1878]: pam_unix(runuser:session): session closed for user alice1786machine: (finished: waiting for success: runuser -u alice -- hermes --version, in 33.32 seconds)1787machine: must succeed: systemctl is-active hermes-dashboard-fwd-alice.socket1788machine: (finished: must succeed: systemctl is-active hermes-dashboard-fwd-alice.socket, in 0.02 seconds)1789machine: must succeed: systemctl is-active hermes-spaces-bridge-alice.socket1790machine: (finished: must succeed: systemctl is-active hermes-spaces-bridge-alice.socket, in 0.02 seconds)1791machine: must succeed: systemctl show -p Listen hermes-spaces-bridge-alice.socket1792machine: (finished: must succeed: systemctl show -p Listen hermes-spaces-bridge-alice.socket, in 0.03 seconds)1793subtest: dashboard forward is owner-gated (firewall username match)1794machine: must succeed: systemctl is-active firewall.service1795machine: (finished: must succeed: systemctl is-active firewall.service, in 0.02 seconds)1796machine: must succeed: runuser -u alice -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901'1797machine # [ 145.259981] runuser[1897]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1798machine # [ 145.271352] runuser[1897]: pam_unix(runuser:session): session closed for user alice1799machine: (finished: must succeed: runuser -u alice -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901', in 0.04 seconds)1800machine: must fail: runuser -u bob -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901'1801machine # [ 145.278703] systemd[1]: Created slice Slice /system/hermes-dashboard-fwd-alice.1802machine # [ 145.282736] systemd[1]: Started dashboard vsock forward for hermes-alice (127.0.0.1:37976).1803machine # [ 145.298458] runuser[1902]: pam_unix(runuser:session): session opened for user bob(uid=1001) by (uid=0)1804machine # bash: connect: Connection refused1805machine # bash: line 1: /dev/tcp/127.0.0.1/22901: Connection refused1806machine # [ 145.313731] runuser[1902]: pam_unix(runuser:session): session closed for user bob1807machine: (finished: must fail: runuser -u bob -- bash -c 'exec 3<>/dev/tcp/127.0.0.1/22901', in 0.04 seconds)1808(finished: subtest: dashboard forward is owner-gated (firewall username match), in 0.10 seconds)1809machine: 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'1810machine # [ 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/FAILURE1811machine # [ 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'.1812machine # [ 145.348544] runuser[1912]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1813machine # [ 146.088609] runuser[1912]: pam_unix(runuser:session): session closed for user alice1814machine: (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)1815subtest: guest python contract1816machine: 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'"'"''1817machine # [ 146.119337] runuser[1916]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1818machine # [ 146.821572] runuser[1916]: pam_unix(runuser:session): session closed for user alice1819machine: (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)1820machine: 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'"'"''1821machine # [ 146.854547] runuser[1921]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1822machine # [ 147.569497] runuser[1921]: pam_unix(runuser:session): session closed for user alice1823machine: (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)1824machine: 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'"'"''1825machine # [ 147.597258] runuser[1926]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1826machine # [ 152.971726] runuser[1926]: pam_unix(runuser:session): session closed for user alice1827machine: (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)1828(finished: subtest: guest python contract, in 6.88 seconds)1829subtest: brain: credentials only, never a model pin1830machine: 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'1831machine # [ 153.002246] runuser[1931]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1832machine # [ 153.658629] runuser[1931]: pam_unix(runuser:session): session closed for user alice1833machine: (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)1834machine: 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'"'"''1835machine # [ 153.691691] runuser[1936]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1836machine # [ 154.396088] runuser[1936]: pam_unix(runuser:session): session closed for user alice1837machine: (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)1838(finished: subtest: brain: credentials only, never a model pin, in 1.43 seconds)1839subtest: virtiofs uid translation: guest 1000 <-> runtime host uid1840machine: must succeed: id -u alice1841machine: (finished: must succeed: id -u alice, in 0.02 seconds)1842machine: 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'"'"''1843machine # [ 154.449591] runuser[1944]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1844machine # [ 155.205222] runuser[1944]: pam_unix(runuser:session): session closed for user alice1845machine: (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)1846machine: 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'"'"''1847machine # [ 155.242565] runuser[1949]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1848machine # [ 155.948544] runuser[1949]: pam_unix(runuser:session): session closed for user alice1849machine: (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)1850machine: 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'"'"''1851machine # [ 155.979096] runuser[1954]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1852machine # [ 156.721385] runuser[1954]: pam_unix(runuser:session): session closed for user alice1853machine: (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)1854machine: must succeed: stat -c %u /home/alice/hermes/xfer-marker1855machine: (finished: must succeed: stat -c %u /home/alice/hermes/xfer-marker, in 0.02 seconds)1856(finished: subtest: virtiofs uid translation: guest 1000 <-> runtime host uid, in 2.35 seconds)1857subtest: spaces bridge: own VM passes the peer-CID gate1858machine: must succeed: id -u alice1859machine: (finished: must succeed: id -u alice, in 0.02 seconds)1860machine: 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'1861machine # [ 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.1862machine # Running as unit: fake-gw.service; invocation ID: 804da68cbcaf4a81b30df793b5c34ada1863machine: (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)1864machine: waiting for success: test -S /run/user/1000/spaces-integration-gateway.sock1865machine: (finished: waiting for success: test -S /run/user/1000/spaces-integration-gateway.sock, in 1.03 seconds)1866machine: 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'"'"''1867machine # [ 157.857645] runuser[1978]: pam_unix(runuser:session): session opened for user alice(uid=1000) by (uid=0)1868machine # [ 158.626398] systemd[1]: Created slice Slice /system/hermes-spaces-bridge-alice.1869machine # [ 158.629067] systemd[1]: Started spaces gateway bridge for hermes-alice (vsock:735577804:1391501652).1870machine # [ 158.666320] systemd[1]: hermes-spaces-bridge-alice@0-4097-2:735578825-735577804:1391501652.service: Deactivated successfully.1871machine # [ 158.680547] runuser[1978]: pam_unix(runuser:session): session closed for user alice1872machine: (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)1873(finished: subtest: spaces bridge: own VM passes the peer-CID gate, in 1.94 seconds)1874subtest: spaces bridge: sibling VM is rejected at accept1875machine: 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'"'"''1876machine # [ 158.707064] runuser[1989]: pam_unix(runuser:session): session opened for user bob(uid=1001) by (uid=0)1877machine # [ 161.264089] systemd[1]: Started spaces gateway bridge for hermes-alice (vsock:2176202715:1911412638).1878machine # [ 161.288690] hermes-vsock-spaces-bridge[1994]: rejecting connection: peer cid 2176202715, expected 7355778041879machine # [ 161.291204] systemd[1]: hermes-spaces-bridge-alice@1-2-2:735578825-2176202715:1911412638.service: Main process exited, code=exited, status=1/FAILURE1880machine # [ 161.293821] systemd[1]: hermes-spaces-bridge-alice@1-2-2:735578825-2176202715:1911412638.service: Failed with result 'exit-code'.1881machine # [ 161.313605] runuser[1989]: pam_unix(runuser:session): session closed for user bob1882machine: (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)1883machine: waiting for success: journalctl -u 'hermes-spaces-bridge-alice@*' | grep -q 'rejecting connection: peer cid 2176202715'1884machine: (finished: waiting for success: journalctl -u 'hermes-spaces-bridge-alice@*' | grep -q 'rejecting connection: peer cid 2176202715', in 0.03 seconds)1885(finished: subtest: spaces bridge: sibling VM is rejected at accept, in 2.66 seconds)1886(finished: run the VM test script, in 163.29 seconds)1887test script finished in 163.34s1888cleanup1889kill QemuMachine (pid 45)1890machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/rgnappqqc5vbq60gza5fflyk84sylwl6-python3-3.14.6/bin/python3.14)1891(finished: cleanup, in 0.56 seconds)