vm-test-run-systemd-initrd-simple
checks.x86_64-linux.systemd-initrd-simple
· build #61
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7subtest: testing initrd backdoor8machine: waiting for unit initrd.target9machine: waiting for the VM to finish booting10machine: starting vm11machine: QEMU running (pid 45)12machine # Disk image does not exist, creating the virtualisation disk image...13machine # Formatting '/build/vm-state-machine/tmp.5xbexsZzgq', fmt=raw size=107374182414machine # mke2fs 1.47.4 (6-Mar-2025)15machine # Discarding device blocks: 0/262144 done16machine # Creating filesystem with 262144 4k blocks and 65536 inodes17machine # Filesystem UUID: 31782943-37d5-4f7e-9468-4636f7ea9e3d18machine # Superblock backups stored on blocks:19machine # 32768, 98304, 163840, 22937620machine # 21machine # Allocating group tables: 0/8 done22machine # Writing inode tables: 0/8 done23machine # Creating journal (8192 blocks): done24machine # Writing superblocks and filesystem accounting information: 0/8 done25machine # 26machine # Virtualisation disk image created.27machine # Starting virtiofs daemons...28machine # [2026-10-19T03:04:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)29machine # [2026-10-19T03:04:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether30machine # [2026-10-19T03:04:24Z INFO virtiofsd] Waiting for vhost-user socket connection...31machine # [2026-10-19T03:04:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32machine # [2026-10-19T03:04:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33machine # [2026-10-19T03:04:24Z INFO virtiofsd] Waiting for vhost-user socket connection...34machine # [2026-10-19T03:04:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35machine # [2026-10-19T03:04:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether36machine # [2026-10-19T03:04:24Z INFO virtiofsd] Waiting for vhost-user socket connection...37machine # [2026-10-19T03:04:24Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-19T03:04:24Z INFO virtiofsd] Client connected, servicing requests39machine # [2026-10-19T03:04:24Z INFO virtiofsd] Client connected, servicing requests40machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)41machine # 42machine # 43machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0044machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...45machine # 46machine # 47machine # 48machine # 49machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0050machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)...51machine # 52machine # 53machine # Booting from ROM...54machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @179172517255machine # [ 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/l4ximippgrsph1yhy35jc4hj818yn6ya-nixos-system-machine-test/init regInfo=/nix/.ro-store/jafvx9ylvki6nmkwwv97d26k2b80jjq1-closure-info/registration console=ttyS0,115200n8 console=tty056machine # [ 0.000000] BIOS-provided physical RAM map:57machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable58machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved59machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved60machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable61machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved62machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved63machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved64machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved65machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved66machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved67machine # [ 0.000000] NX (Execute Disable) protection: active68machine # [ 0.000000] APIC: Static calls initialized69machine # [ 0.000000] SMBIOS 2.8 present.70machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201471machine # [ 0.000000] DMI: Memory slots populated: 1/172machine # [ 0.000000] Hypervisor detected: KVM73machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000074machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0075machine # [ 0.000000] kvm-clock: using sched offset of 533909046 cycles76machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns77machine # [ 0.000005] tsc: Detected 2400.008 MHz processor78machine # [ 0.000765] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000079machine # [ 0.000792] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs80machine # [ 0.000795] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT81machine # [ 0.002687] found SMP MP-table at [mem 0x000f5450-0x000f545f]82machine # [ 0.002699] Using GB pages for direct mapping83machine # [ 0.002754] RAMDISK: [mem 0x3e2ba000-0x3ffcffff]84machine # [ 0.002763] ACPI: Early table checksum verification disabled85machine # [ 0.002766] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )86machine # [ 0.002770] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)87machine # [ 0.002774] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)88machine # [ 0.002782] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)89machine # [ 0.002784] ACPI: FACS 0x000000003FFE0000 00004090machine # [ 0.002786] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)91machine # [ 0.002787] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)92machine # [ 0.002789] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)93machine # [ 0.002790] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)94machine # [ 0.002792] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]95machine # [ 0.002792] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]96machine # [ 0.002793] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]97machine # [ 0.002794] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]98machine # [ 0.002794] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]99machine # [ 0.002794] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]100machine # [ 0.002795] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]101machine # [ 0.003022] No NUMA configuration found102machine # [ 0.003023] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]103machine # [ 0.003025] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]104machine # [ 0.005371] Zone ranges:105machine # [ 0.005373] DMA [mem 0x0000000000001000-0x0000000000ffffff]106machine # [ 0.005374] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]107machine # [ 0.005376] Normal empty108machine # [ 0.005376] Device empty109machine # [ 0.005377] Movable zone start for each node110machine # [ 0.005378] Early memory node ranges111machine # [ 0.005378] node 0: [mem 0x0000000000001000-0x000000000009efff]112machine # [ 0.005379] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]113machine # [ 0.005380] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]114machine # [ 0.005407] On node 0, zone DMA: 1 pages in unavailable ranges115machine # [ 0.005986] On node 0, zone DMA: 97 pages in unavailable ranges116machine # [ 0.025646] On node 0, zone DMA32: 40 pages in unavailable ranges117machine # [ 0.026094] ACPI: PM-Timer IO Port: 0x608118machine # [ 0.026105] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])119machine # [ 0.026134] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23120machine # [ 0.026137] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)121machine # [ 0.026139] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)122machine # [ 0.026140] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)123machine # [ 0.026141] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)124machine # [ 0.026142] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)125machine # [ 0.026144] ACPI: Using ACPI (MADT) for SMP configuration information126machine # [ 0.026145] ACPI: HPET id: 0x8086a201 base: 0xfed00000127machine # [ 0.026148] TSC deadline timer available128machine # [ 0.026152] CPU topo: Max. logical packages: 1129machine # [ 0.026153] CPU topo: Max. logical dies: 1130machine # [ 0.026153] CPU topo: Max. dies per package: 1131machine # [ 0.026157] CPU topo: Max. threads per core: 1132machine # [ 0.026157] CPU topo: Num. cores per package: 1133machine # [ 0.026158] CPU topo: Num. threads per package: 1134machine # [ 0.026158] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs135machine # [ 0.026175] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()136machine # [ 0.026206] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]137machine # [ 0.026207] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]138machine # [ 0.026209] [mem 0x40000000-0xafffffff] available for PCI devices139machine # [ 0.026210] Booting paravirtualized kernel on KVM140machine # [ 0.026213] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns141machine # [ 0.030870] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1142machine # [ 0.033062] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152143machine # [ 0.033108] kvm-guest: PV spinlocks disabled, single CPU144machine # [ 0.033109] 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/l4ximippgrsph1yhy35jc4hj818yn6ya-nixos-system-machine-test/init regInfo=/nix/.ro-store/jafvx9ylvki6nmkwwv97d26k2b80jjq1-closure-info/registration console=ttyS0,115200n8 console=tty0145machine # [ 0.033200] Unknown kernel command line parameters "regInfo=/nix/.ro-store/jafvx9ylvki6nmkwwv97d26k2b80jjq1-closure-info/registration", will be passed to user space.146machine # [ 0.033212] random: crng init done147machine # [ 0.033213] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes148machine # [ 0.033241] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)149machine # [ 0.033785] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)150machine # [ 0.033814] Fallback order for Node 0: 0151machine # [ 0.033817] Built 1 zonelists, mobility grouping on. Total pages: 262006152machine # [ 0.033818] Policy zone: DMA32153machine # [ 0.035971] mem auto-init: stack:all(zero), heap alloc:on, heap free:off154machine # [ 0.038518] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1155machine # [ 0.040753] allocated 2097152 bytes of page_ext156machine # [ 0.051222] ftrace: allocating 48842 entries in 192 pages157machine # [ 0.051224] ftrace: allocated 192 pages with 2 groups158machine # [ 0.052141] Dynamic Preempt: lazy159machine # [ 0.052328] rcu: Preemptible hierarchical RCU implementation.160machine # [ 0.052329] rcu: RCU event tracing is enabled.161machine # [ 0.052329] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.162machine # [ 0.052331] Trampoline variant of Tasks RCU enabled.163machine # [ 0.052332] Rude variant of Tasks RCU enabled.164machine # [ 0.052332] Tracing variant of Tasks RCU enabled.165machine # [ 0.052333] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.166machine # [ 0.052333] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1167machine # [ 0.052357] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168machine # [ 0.052358] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169machine # [ 0.052359] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170machine # [ 0.056756] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16171machine # [ 0.057038] rcu: srcu_init: Setting srcu_struct sizes based on contention.172machine # [ 0.057046] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns173machine # [ 0.057147] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)174machine # [ 0.060714] Console: colour VGA+ 80x25175machine # [ 0.060717] printk: legacy console [tty0] enabled176machine # [ 0.090246] printk: legacy console [ttyS0] enabled177machine # [ 0.196991] ACPI: Core revision 20250807178machine # [ 0.197948] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns179machine # [ 0.199568] APIC: Switch to symmetric I/O mode setup180machine # [ 0.200629] x2apic enabled181machine # [ 0.201379] APIC: Switched APIC routing to: physical x2apic182machine # [ 0.203460] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1183machine # [ 0.204575] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns184machine # [ 0.206512] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008)185machine # [ 0.208667] x86/cpu: User Mode Instruction Prevention (UMIP) activated186machine # [ 0.210616] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127187machine # [ 0.211508] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0188machine # [ 0.212512] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto189machine # [ 0.213509] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl190machine # [ 0.215509] Transient Scheduler Attacks: Vulnerable: No microcode191machine # [ 0.216508] Spectre V2 : Mitigation: Enhanced / Automatic IBRS192machine # [ 0.217508] Speculative Return Stack Overflow: Mitigation: Safe RET193machine # [ 0.218508] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization194machine # [ 0.220516] Spectre V2 : Enabling IBPB for BPF195machine # [ 0.221509] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier196machine # [ 0.222509] active return thunk: srso_alias_return_thunk197machine # [ 0.224222] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'198machine # [ 0.225508] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'199machine # [ 0.226508] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'200machine # [ 0.227508] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'201machine # [ 0.228508] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'202machine # [ 0.229508] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'203machine # [ 0.230508] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'204machine # [ 0.232508] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256205machine # [ 0.233509] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64206machine # [ 0.234508] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512207machine # [ 0.235509] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024208machine # [ 0.236509] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8209machine # [ 0.237508] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.210machine # [ 0.272174] Freeing SMP alternatives memory: 48K211machine # [ 0.272511] pid_max: default: 32768 minimum: 301212machine # [ 0.273613] LSM: initializing lsm=capability,landlock,yama,bpf,ima213machine # [ 0.274612] landlock: Up and running.214machine # [ 0.276008] Yama: becoming mindful.215machine # [ 0.276724] LSM support for eBPF active216machine # [ 0.277637] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217machine # [ 0.278529] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)218machine # [ 0.281458] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)219machine # [ 0.282033] Performance Events: Fam17h+ core perfctr, AMD PMU driver.220machine # [ 0.282513] ... version: 2221machine # [ 0.283270] ... bit width: 48222machine # [ 0.283512] ... generic counters: 6223machine # [ 0.284392] ... generic bitmap: 000000000000003f224machine # [ 0.285330] ... fixed-purpose counters: 0225machine # [ 0.286095] ... fixed-purpose bitmap: 0000000000000000226machine # [ 0.286511] ... value mask: 0000ffffffffffff227machine # [ 0.287470] ... max period: 00007fffffffffff228machine # [ 0.288270] ... global_ctrl mask: 000000000000003f229machine # [ 0.288642] signal: max sigframe size: 3376230machine # [ 0.289488] rcu: Hierarchical SRCU implementation.231machine # [ 0.290221] rcu: Max phase no-delay instances is 400.232machine # [ 0.295319] smp: Bringing up secondary CPUs ...233machine # [ 0.296067] smp: Brought up 1 node, 1 CPU234machine # [ 0.296513] smpboot: Total of 1 processors activated (4800.01 BogoMIPS)235machine # [ 0.297697] Memory: 940320K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 100304K reserved, 0K cma-reserved)236machine # [ 0.298817] devtmpfs: initialized237machine # [ 0.299635] x86/mm: Memory block size: 128MB238machine # [ 0.301310] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)239machine # [ 0.302479] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).240machine # [ 0.303590] pinctrl core: initialized pinctrl subsystem241machine # [ 0.304725] PM: RTC time: 03:04:24, date: 2026-10-19242machine # [ 0.308306] NET: Registered PF_NETLINK/PF_ROUTE protocol family243machine # [ 0.309659] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations244machine # [ 0.310532] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations245machine # [ 0.311661] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations246machine # [ 0.312524] audit: initializing netlink subsys (disabled)247machine # [ 0.313695] thermal_sys: Registered thermal governor 'fair_share'248machine # [ 0.313697] thermal_sys: Registered thermal governor 'bang_bang'249machine # [ 0.314515] audit: type=2000 audit(1792379065.404:1): state=initialized audit_enabled=0 res=1250machine # [ 0.316513] thermal_sys: Registered thermal governor 'step_wise'251machine # [ 0.316515] thermal_sys: Registered thermal governor 'user_space'252machine # [ 0.317512] thermal_sys: Registered thermal governor 'power_allocator'253machine # [ 0.318531] cpuidle: using governor menu254machine # [ 0.320873] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5255machine # [ 0.321776] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]256machine # [ 0.322515] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry257machine # [ 0.323524] PCI: Using configuration type 1 for base access258machine # [ 0.324687] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.259machine # [ 0.331811] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages260machine # [ 0.332512] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page261machine # [ 0.337519] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages262machine # [ 0.338511] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page263machine # [ 0.348791] ACPI: Added _OSI(Module Device)264machine # [ 0.349513] ACPI: Added _OSI(Processor Device)265machine # [ 0.350276] ACPI: Added _OSI(Processor Aggregator Device)266machine # [ 0.358071] ACPI: 1 ACPI AML tables successfully acquired and loaded267machine # [ 0.361928] ACPI: Interpreter enabled268machine # [ 0.362526] ACPI: PM: (supports S0 S3 S4 S5)269machine # [ 0.363275] ACPI: Using IOAPIC for interrupt routing270machine # [ 0.363575] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug271machine # [ 0.364511] PCI: Using E820 reservations for host bridge windows272machine # [ 0.367655] ACPI: Enabled 2 GPEs in block 00 to 3F273machine # [ 0.374404] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])274machine # [ 0.375328] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]275machine # [ 0.376587] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]276machine # [ 0.377641] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]277machine # [ 0.378848] PCI host bridge to bus 0000:00278machine # [ 0.379515] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]279machine # [ 0.380512] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]280machine # [ 0.381511] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]281machine # [ 0.382511] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]282machine # [ 0.383511] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]283machine # [ 0.384511] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]284machine # [ 0.385512] pci_bus 0000:00: root bus resource [bus 00-ff]285machine # [ 0.386552] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint286machine # [ 0.388001] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint287machine # [ 0.390591] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]288machine # [ 0.391523] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]289machine # [ 0.392532] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]290machine # [ 0.393677] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]291machine # [ 0.395233] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint292machine # [ 0.398545] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]293machine # [ 0.399441] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]294machine # [ 0.400291] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]295machine # [ 0.401453] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]296machine # [ 0.403044] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint297machine # [ 0.405547] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]298machine # [ 0.406451] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]299machine # [ 0.407396] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]300machine # [ 0.409020] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint301machine # [ 0.411545] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]302machine # [ 0.412428] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]303machine # [ 0.413306] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]304machine # [ 0.414990] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint305machine # [ 0.417395] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]306machine # [ 0.418179] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]307machine # [ 0.418532] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]308machine # [ 0.420430] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]309machine # [ 0.421900] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint310machine # [ 0.423524] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]311machine # [ 0.424540] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]312machine # [ 0.426128] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint313machine # [ 0.427524] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]314machine # [ 0.428533] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]315machine # [ 0.430057] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint316machine # [ 0.431549] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]317machine # [ 0.432533] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]318machine # [ 0.434064] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint319machine # [ 0.435804] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]320machine # [ 0.436533] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]321machine # [ 0.438046] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint322machine # [ 0.439526] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]323machine # [ 0.440439] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]324machine # [ 0.441299] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]325machine # [ 0.442962] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint326machine # [ 0.444546] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]327machine # [ 0.445435] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]328machine # [ 0.446301] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]329machine # [ 0.447964] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint330machine # [ 0.449401] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]331machine # [ 0.450415] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint332machine # [ 0.452184] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]333machine # [ 0.452709] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint334machine # [ 0.454122] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]335machine # [ 0.454799] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint336machine # [ 0.457186] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]337machine # [ 0.457753] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint338machine # [ 0.458794] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO339machine # [ 0.459763] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint340machine # [ 0.461511] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]341machine # [ 0.462405] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]342machine # [ 0.463858] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint343machine # [ 0.465454] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]344machine # [ 0.469289] ACPI: PCI: Interrupt link LNKA configured for IRQ 10345machine # [ 0.470576] ACPI: PCI: Interrupt link LNKB configured for IRQ 10346machine # [ 0.471619] ACPI: PCI: Interrupt link LNKC configured for IRQ 11347machine # [ 0.472605] ACPI: PCI: Interrupt link LNKD configured for IRQ 11348machine # [ 0.473610] ACPI: PCI: Interrupt link LNKE configured for IRQ 10349machine # [ 0.474606] ACPI: PCI: Interrupt link LNKF configured for IRQ 10350machine # [ 0.475610] ACPI: PCI: Interrupt link LNKG configured for IRQ 11351machine # [ 0.476605] ACPI: PCI: Interrupt link LNKH configured for IRQ 11352machine # [ 0.477548] ACPI: PCI: Interrupt link GSIA configured for IRQ 16353machine # [ 0.478526] ACPI: PCI: Interrupt link GSIB configured for IRQ 17354machine # [ 0.479505] ACPI: PCI: Interrupt link GSIC configured for IRQ 18355machine # [ 0.480516] ACPI: PCI: Interrupt link GSID configured for IRQ 19356machine # [ 0.481530] ACPI: PCI: Interrupt link GSIE configured for IRQ 20357machine # [ 0.482489] ACPI: PCI: Interrupt link GSIF configured for IRQ 21358machine # [ 0.483475] ACPI: PCI: Interrupt link GSIG configured for IRQ 22359machine # [ 0.484357] ACPI: PCI: Interrupt link GSIH configured for IRQ 23360machine # [ 0.486407] iommu: Default domain type: Translated361machine # [ 0.487221] iommu: DMA domain TLB invalidation policy: lazy mode362machine # [ 0.487777] ACPI: bus type USB registered363machine # [ 0.488581] usbcore: registered new interface driver usbfs364machine # [ 0.489528] usbcore: registered new interface driver hub365machine # [ 0.490452] usbcore: registered new device driver usb366machine # [ 0.492260] NetLabel: Initializing367machine # [ 0.492519] NetLabel: domain hash size = 128368machine # [ 0.493332] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO369machine # [ 0.494312] NetLabel: unlabeled traffic allowed by default370machine # [ 0.495268] PCI: Using ACPI for IRQ routing371machine # [ 0.538640] pci 0000:00:01.0: vgaarb: setting as boot VGA device372machine # [ 0.539507] pci 0000:00:01.0: vgaarb: bridge control possible373machine # [ 0.539507] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none374machine # [ 0.539515] vgaarb: loaded375machine # [ 0.540266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0376machine # [ 0.540511] hpet0: 3 comparators, 64-bit 100.000000 MHz counter377machine # [ 0.544595] clocksource: Switched to clocksource kvm-clock378machine # [ 0.546392] VFS: Disk quotas dquot_6.6.0379machine # [ 0.548925] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)380machine # [ 0.550329] pnp: PnP ACPI init381machine # [ 0.551162] ACPI: IRQ 4 override to edge(!), high(!)382machine # [ 0.552254] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved383machine # [ 0.553844] pnp: PnP ACPI: found 5 devices384machine # [ 0.561505] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns385machine # [ 0.563045] clocksource: Switched to clocksource acpi_pm386machine # [ 0.564081] NET: Registered PF_INET protocol family387machine # [ 0.565215] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)388machine # [ 0.582262] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)389machine # [ 0.583874] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)390machine # [ 0.585232] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)391machine # [ 0.586655] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)392machine # [ 0.587932] TCP: Hash tables configured (established 8192 bind 8192)393machine # [ 0.589121] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)394machine # [ 0.590499] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)395machine # [ 0.591696] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)396machine # [ 0.592960] NET: Registered PF_UNIX/PF_LOCAL protocol family397machine # [ 0.593982] NET: Registered PF_XDP protocol family398machine # [ 0.594878] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]399machine # [ 0.595935] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]400machine # [ 0.597010] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]401machine # [ 0.598168] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]402machine # [ 0.599332] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]403machine # [ 0.600511] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]404machine # [ 0.602401] ACPI: \_SB_.GSIA: Enabled at IRQ 16405machine # [ 0.604542] ACPI: \_SB_.GSIB: Enabled at IRQ 17406machine # [ 0.606521] ACPI: \_SB_.GSIC: Enabled at IRQ 18407machine # [ 0.608690] ACPI: \_SB_.GSID: Enabled at IRQ 19408machine # [ 0.610364] PCI: CLS 0 bytes, default 64409machine # [ 0.611309] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns410machine # [ 0.613110] Trying to unpack rootfs image as initramfs...411machine # [ 0.658917] Initialise system trusted keyrings412machine # [ 0.662534] workingset: timestamp_bits=40 max_order=18 bucket_order=0413machine # [ 0.683601] Key type asymmetric registered414machine # [ 0.684342] Asymmetric key parser 'x509' registered415machine # [ 0.685256] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)416machine # [ 0.690595] io scheduler mq-deadline registered417machine # [ 0.691375] io scheduler kyber registered418machine # [ 0.695142] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled419machine # [ 0.698496] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A420machine # [ 0.701800] Linux agpgart interface v0.103421machine # [ 0.702627] ACPI: bus type drm_connector registered422machine # [ 0.706007] usbcore: registered new interface driver usbserial_generic423machine # [ 0.707155] usbserial: USB Serial support registered for generic424machine # [ 0.710498] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.425machine # [ 0.710498] Please enable it if your BIOS has the CPPC option.426machine # [ 0.712878] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled427machine # [ 0.716618] drop_monitor: Initializing network drop monitor service428machine # [ 0.717828] NET: Registered PF_INET6 protocol family429machine # [ 0.722596] Segment Routing with IPv6430machine # [ 0.723242] In-situ OAM (IOAM) with IPv6431machine # [ 0.725769] IPI shorthand broadcast: enabled432machine # [ 0.734008] sched_clock: Marking stable (588016456, 145461950)->(807495702, -74017296)433machine # [ 0.739679] registered taskstats version 1434machine # [ 0.740676] Loading compiled-in X.509 certificates435machine # [ 0.759486] Demotion targets for Node 0: null436machine # [ 0.760369] Key type .fscrypt registered437machine # [ 0.761110] Key type fscrypt-provisioning registered438machine # [ 0.762108] ima: No TPM chip found, activating TPM-bypass!439machine # [ 0.766488] ima: Allocated hash algorithm: sha1440machine # [ 0.767300] ima: No architecture policies found441machine # [ 0.769489] PM: Magic number: 14:281:58442machine # [ 0.771151] RAS: Correctable Errors collector initialized.443machine # [ 0.780319] clk: Disabling unused clocks444machine # [ 0.783495] PM: genpd: Disabling unused power domains445machine # [ 0.957899] Freeing initrd memory: 29784K446machine # [ 0.961129] Freeing unused decrypted memory: 2028K447machine # [ 0.964012] Freeing unused kernel image (initmem) memory: 3660K448machine # [ 0.965188] Write protecting the kernel read-only data: 32768k449machine # [ 0.966576] Freeing unused kernel image (text/rodata gap) memory: 296K450machine # [ 0.968101] Freeing unused kernel image (rodata/data gap) memory: 684K451machine # [ 1.018824] x86/mm: Checked W+X mappings: passed, no W+X pages found.452machine # [ 1.019964] Run /init as init process453machine # [ 1.030815] systemd[1]: Inserted module 'autofs4'454machine # [ 1.050637] fuse: init (API version 7.45)455machine # [ 1.057968] ACPI: \_SB_.GSIG: Enabled at IRQ 22456machine # [ 1.060432] ACPI: \_SB_.GSIH: Enabled at IRQ 23457machine # [ 1.063292] ACPI: \_SB_.GSIE: Enabled at IRQ 20458machine # [ 1.065320] ACPI: \_SB_.GSIF: Enabled at IRQ 21459machine # [ 1.070432] virtiofs virtio5: discovered new tag: nix-store460machine # [ 1.072065] virtiofs virtio5: virtio_fs_setup_dax: No cache capability461machine # [ 1.078783] virtiofs virtio6: discovered new tag: shared462machine # [ 1.080273] virtiofs virtio6: virtio_fs_setup_dax: No cache capability463machine # [ 1.083601] virtiofs virtio7: discovered new tag: xchg464machine # [ 1.085086] virtiofs virtio7: virtio_fs_setup_dax: No cache capability465machine # [ 1.108522] systemd[1]: Successfully made /usr/ read-only.466machine # [ 1.110350] systemd[1]: systemd 263-7f4df15 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE +EXPERIMENTAL-ENVVAR)467machine # [ 1.116035] systemd[1]: Detected virtualization kvm.468machine # [ 1.117002] systemd[1]: Detected architecture x86-64.469machine # [ 1.117953] systemd[1]: Running in initrd.470machine # [ 1.119004] systemd[1]: Initializing machine ID from random generator.471machine # [ 1.120260] systemd[1]: Hostname set to <machine>.472machine # [ 1.192108] systemd[1]: bpf-restrict-fs: LSM BPF program attached473machine # [ 1.231216] systemd[1]: Queued start job for default target Initrd Default Target.474machine # [ 1.235913] systemd[1]: Created slice Slice /system/modprobe.475machine # [ 1.237219] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.476machine # [ 1.238823] systemd[1]: Expecting device /dev/disk/by-label/nixos...477machine # [ 1.239993] systemd[1]: Expecting device /dev/hvc0...478machine # [ 1.241057] systemd[1]: Expecting device /dev/ttyS0...479machine # [ 1.242092] systemd[1]: Reached target Path Units.480machine # [ 1.243105] systemd[1]: Reached target Slice Units.481machine # [ 1.244092] systemd[1]: Reached target Swaps.482machine # [ 1.245009] systemd[1]: Reached target Timer Units.483machine # [ 1.245997] systemd[1]: Listening on D-Bus System Message Bus Socket.484machine # [ 1.247292] systemd[1]: Listening on Journal Socket (/dev/log).485machine # [ 1.248576] systemd[1]: Listening on Journal Sockets.486machine # [ 1.249607] systemd[1]: Listening on udev Kernel Socket.487machine # [ 1.250611] systemd[1]: Reached target Socket Units.488machine # [ 1.252503] systemd[1]: Starting Create List of Static Device Nodes...489machine # [ 1.256390] systemd[1]: Starting Load Kernel Module configfs...490machine # [ 1.280738] systemd[1]: Starting Journal Service...491machine # [ 1.291567] systemd[1]: Starting Load Kernel Modules...492machine # [ 1.294553] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os493machine # [ 1.301543] systemd[1]: Starting Coldplug All udev Devices...494machine # [ 1.316632] systemd[1]: Finished Create List of Static Device Nodes.495machine # [ 1.322198] systemd[1]: modprobe@configfs.service: Deactivated successfully.496machine # [ 1.330918] systemd[1]: Finished Load Kernel Module configfs.497machine # [ 1.335965] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config498machine # [ 1.343660] systemd-journald[65]: Collecting audit messages is disabled.499machine # [ 1.350145] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...500machine # [ 1.360142] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.501machine # [ 1.369842] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev502machine # [ 1.395051] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.503machine # [ 1.402679] systemd[1]: Starting Create Static Device Nodes in /dev...504machine # [ 1.414552] systemd[1]: Finished Load Kernel Modules.505machine # [ 1.423692] systemd[1]: Starting Apply Kernel Variables...506machine # [ 1.445572] systemd[1]: Finished Create Static Device Nodes in /dev.507machine # [ 1.451703] systemd[1]: Reached target Preparation for Local File Systems.508machine # [ 1.456568] systemd[1]: Reached target Local File Systems.509machine # [ 1.317244] systemd-modules-load[66]: Inserted module 'dm_mod'510machine # [ 1.464790] systemd[1]: Starting Rule-based Manager for Device Events and Files...511machine # [ 1.320559] systemd-modules-load[66]: Inserted module 'virtio_balloon'512machine # [ 1.321638] systemd-modules-load[66]: Inserted module 'virtio_gpu'513machine # [ 1.473771] systemd[1]: Started Journal Service.514machine # [ 1.334543] systemd[1]: Finished Apply Kernel Variables.515machine # [ 1.347406] systemd[1]: Starting Create System Files and Directories...516machine # [ 1.378485] systemd[1]: Finished Create System Files and Directories.517machine # [ 1.383529] systemd-udevd[73]: Using default interface naming scheme 'v261'.518machine # [ 1.407068] systemd[1]: Started Rule-based Manager for Device Events and Files.519machine # [ 1.464069] systemd[1]: Finished Coldplug All udev Devices.520machine # [ 1.466338] systemd[1]: Reached target System Initialization.521machine # [ 1.468335] systemd[1]: Reached target Basic System.522machine # [ 1.485969] systemd[1]: Found device /dev/hvc0.523machine # [ 1.503350] systemd[1]: Found device /dev/ttyS0.524machine # [ 1.506444] systemd[1]: Started backdoor.service.525machine # [ 1.526147] backdoor-start[97]: /nix/store/wih4sdpik4mw459vdf955q7qq1j10agf-unit-script-backdoor-start/bin/backdoor-start: line 10: grep: command not found526machine # connecting to host...527machine: Guest shell says: b'Spawning backdoor root shell...\n'528machine: connected to guest root shell529machine: (connecting took 2.26 seconds)530machine: (finished: waiting for the VM to finish booting, in 2.50 seconds)531machine # [ 1.798160] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12532machine # [ 1.813126] serio: i8042 KBD port at 0x60,0x64 irq 1533machine # [ 1.816552] virtio_blk virtio2: 1/0/0 default/read/poll queues534machine # [ 1.819489] serio: i8042 AUX port at 0x60,0x64 irq 12535machine # [ 1.821187] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)536machine # [ 1.835098] ehci-pci 0000:00:1d.7: EHCI Host Controller537machine # [ 1.835903] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1538machine # [ 1.837637] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000539machine # [ 1.843519] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00540machine # [ 1.844409] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18541machine # [ 1.847587] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1542machine # [ 1.849013] usb usb1: Product: EHCI Host Controller543machine # [ 1.852478] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd544machine # [ 1.853182] usb usb1: SerialNumber: 0000:00:1d.7545machine # [ 1.854239] hub 1-0:1.0: USB hub found546machine # [ 1.855837] hub 1-0:1.0: 6 ports detected547machine # [ 1.861678] uhci_hcd 0000:00:1d.0: UHCI Host Controller548machine # [ 1.862372] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2549machine # [ 1.868534] uhci_hcd 0000:00:1d.0: detected 2 ports550machine # [ 1.869291] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180551machine # [ 1.879881] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18552machine # [ 1.881026] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1553machine # [ 1.898325] usb usb2: Product: UHCI Host Controller554machine # [ 1.899448] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd555machine # [ 1.900789] usb usb2: SerialNumber: 0000:00:1d.0556machine # [ 1.763439] systemd[1]: Starting Virtual Console Setup...557machine # [ 1.915964] hub 2-0:1.0: USB hub found558machine # [ 1.920446] SCSI subsystem initialized559machine # [ 1.924596] hub 2-0:1.0: 2 ports detected560machine # [ 1.800314] systemd-vconsole-setup[111]: Configuration of first virtual console was skipped, ignoring remaining ones.561machine # [ 1.807658] systemd[1]: Finished Virtual Console Setup.562machine # [ 1.809176] (udev-worker)[86]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.563machine # [ 1.811209] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line.564machine # [ 1.963909] uhci_hcd 0000:00:1d.1: UHCI Host Controller565machine # [ 1.964689] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3566machine # [ 1.970147] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0567machine # [ 1.827863] systemd[1]: Found device /dev/disk/by-label/nixos.568machine # [ 1.830500] systemd[1]: Reached target Initrd Root Device.569machine # [ 1.833417] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...570machine # [ 1.993804] uhci_hcd 0000:00:1d.1: detected 2 ports571machine # [ 2.003022] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0572machine # [ 2.008772] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18573machine # [ 2.012621] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1574machine # [ 2.016527] usb usb3: Product: UHCI Host Controller575machine # [ 2.017247] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd576machine # [ 2.019568] usb usb3: SerialNumber: 0000:00:1d.1577machine # [ 1.876353] systemd-fsck[118]: nixos: clean, 12/65536 files, 13019/262144 blocks578machine # [ 2.023084] hub 3-0:1.0: USB hub found579machine # [ 2.026495] hub 3-0:1.0: 2 ports detected580machine # [ 2.029281] uhci_hcd 0000:00:1d.2: UHCI Host Controller581machine # [ 2.031513] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4582machine # [ 2.035508] uhci_hcd 0000:00:1d.2: detected 2 ports583machine # [ 2.036303] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0584machine # [ 2.041974] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18585machine # [ 1.898483] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.586machine # [ 2.046622] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1587machine # [ 2.049336] usb usb4: Product: UHCI Host Controller588machine # [ 2.050288] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd589machine # [ 2.051271] usb usb4: SerialNumber: 0000:00:1d.2590machine # [ 2.055807] hub 4-0:1.0: USB hub found591machine # [ 2.057771] hub 4-0:1.0: 2 ports detected592machine # [ 2.089684] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode593machine # [ 2.091520] usb 1-1: new high-speed USB device number 2 using ehci-pci594machine # [ 2.095078] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)595machine # [ 2.096276] ahci 0000:00:1f.2: flags: 64bit ncq only596machine # [ 2.099100] scsi host0: ahci597machine # [ 2.100757] scsi host1: ahci598machine # [ 2.102200] scsi host2: ahci599machine # [ 2.103399] scsi host3: ahci600machine # [ 2.105006] scsi host4: ahci601machine # [ 2.106341] scsi host5: ahci602machine # [ 2.107611] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1603machine # [ 2.108787] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1604machine # [ 2.109988] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1605machine # [ 2.111129] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1606machine # [ 2.112359] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1607machine # [ 2.113502] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1608machine # [ 2.223969] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00609machine # [ 2.227049] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10610machine # [ 2.230283] usb 1-1: Product: QEMU USB Tablet611machine # [ 2.232092] usb 1-1: Manufacturer: QEMU612machine # [ 2.234091] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1613machine # [ 2.267899] hid: raw HID events driver (C) Jiri Kosina614machine # [ 2.168597] systemd[1]: Mounting /sysroot...615machine # [ 2.425028] ata6: SATA link down (SStatus 0 SControl 300)616machine # [ 2.427332] ata4: SATA link down (SStatus 0 SControl 300)617machine # [ 2.429988] ata2: SATA link down (SStatus 0 SControl 300)618machine # [ 2.432330] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)619machine # [ 2.434789] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100620machine # [ 2.436867] ata3.00: applying bridge limits621machine # [ 2.438779] ata1: SATA link down (SStatus 0 SControl 300)622machine # [ 2.440987] ata5: SATA link down (SStatus 0 SControl 300)623machine # [ 2.443274] ata3.00: configured for UDMA/100624machine # [ 2.445785] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5625machine # [ 2.520722] usbcore: registered new interface driver usbhid626machine # [ 2.521516] usbhid: USB HID core driver627machine # [ 2.530999] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2628machine # [ 2.536625] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0629machine # [ 2.540599] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray630machine # [ 2.556589] cdrom: Uniform CD-ROM driver Revision: 3.20631machine # [ 2.586340] EXT4-fs (vda): mounted filesystem 31782943-37d5-4f7e-9468-4636f7ea9e3d r/w with ordered data mode. Quota mode: none.632machine # [ 2.444727] systemd[1]: Mounted /sysroot.633machine # [ 2.446055] systemd[1]: Reached target Initrd Root File System.634machine # [ 2.448454] systemd[1]: Starting Mountpoints Configured in the Real Root...635machine # [ 2.462723] systemd-sysroot-fstab-check[152]: /sysroot should be mounted in the initrd, will request daemon-reload.636machine # [ 2.468060] systemd[1]: Reload requested from client PID 152 ('systemd-sysroot') (unit initrd-parse-etc.service)...637machine # [ 2.469453] systemd[1]: Reloading...638machine # [ 2.538362] systemd[1]: Reloading finished in 70 ms.639machine # [ 2.546088] systemd-sysroot-fstab-check[152]: Requesting initrd-fs.target/start/replace...640machine # [ 2.548225] systemd-sysroot-fstab-check[152]: Requesting swap.target/start/replace...641machine # [ 2.553295] systemd[1]: initrd-parse-etc.service: Deactivated successfully.642machine # [ 2.555213] systemd[1]: Finished Mountpoints Configured in the Real Root.643machine # [ 2.556230] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.644machine # [ 3.169135] systemd[1]: Mounting /sysroot/nix/.ro-store...645machine # [ 3.172152] systemd[1]: Mounting /sysroot/nix/.rw-store...646machine # [ 3.176806] systemd[1]: Mounting /sysroot/run...647machine # [ 3.181152] systemd[1]: Mounting /sysroot/tmp/shared...648machine # [ 3.185143] systemd[1]: Mounting /sysroot/tmp/xchg...649machine # [ 3.225812] systemd[1]: Mounted /sysroot/nix/.ro-store.650machine # [ 3.227763] systemd[1]: Mounted /sysroot/nix/.rw-store.651machine # [ 3.229259] systemd[1]: Mounted /sysroot/run.652machine # [ 3.231064] systemd[1]: Mounted /sysroot/tmp/shared.653machine # [ 3.233617] systemd[1]: Mounted /sysroot/tmp/xchg.654machine # [ 3.237372] systemd[1]: Starting rw-sysroot-nix-store.service...655machine # [ 3.248725] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.656machine # [ 3.251101] systemd[1]: Finished rw-sysroot-nix-store.service.657machine # [ 3.252262] systemd[1]: Mounting /sysroot/nix/store...658machine # [ 3.273802] systemd[1]: Mounted /sysroot/nix/store.659machine # [ 3.274911] systemd[1]: Reached target Initrd File Systems.660machine # [ 3.276582] systemd[1]: Starting Find NixOS closure...661machine # [ 3.280230] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...662machine # [ 3.297889] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.663machine # [ 3.305309] systemd[1]: Finished Find NixOS closure.664machine # [ 3.306512] systemd[1]: Reached target Initrd Default Target.665machine # [ 3.308558] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...666machine # [ 3.318805] systemd[1]: initrd-cleanup.service: Deactivated successfully.667machine # [ 3.320231] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.668machine # [ 3.324610] systemd[1]: Startup finished in 878ms (kernel) + 0 (initrd) + 2.445s (userspace) = 3.324s.669machine: (finished: waiting for unit initrd.target, in 4.58 seconds)670machine: must succeed: systemctl status initrd-fs.target671machine: (finished: must succeed: systemctl status initrd-fs.target, in 0.01 seconds)672machine: waiting for unit initrd.target673machine: (finished: waiting for unit initrd.target, in 0.01 seconds)674machine: waiting for the VM to finish booting675machine # [ 3.643783] systemd[1]: Stopped target Initrd Default Target.676machine # [ 3.645370] systemd[1]: Stopped target Basic System.677machine # [ 3.646068] systemd[1]: Stopped target Initrd Root Device.678machine # [ 3.646810] systemd[1]: Stopped target Path Units.679machine # [ 3.647859] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.680machine # [ 3.648966] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.681machine # [ 3.650273] systemd[1]: Stopped target Slice Units.682machine # [ 3.651424] systemd[1]: Stopped target Socket Units.683machine # [ 3.652219] systemd[1]: Stopped target System Initialization.684machine # [ 3.653575] systemd[1]: Stopped target Swaps.685machine # [ 3.654480] systemd[1]: Stopped target Timer Units.686machine # [ 3.655486] systemd[1]: dbus.socket: Deactivated successfully.687machine # [ 3.657137] systemd[1]: Closed D-Bus System Message Bus Socket.688machine # [ 3.657960] systemd[1]: Stopping backdoor.service...689machine # [ 3.659762] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...690machine # [ 3.661181] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.691machine # [ 3.662257] systemd[1]: Stopped Find NixOS closure.692machine # [ 3.664585] systemd[1]: Starting rw-sysroot-nix-store.service...693machine # [ 3.666160] systemd[1]: systemd-sysctl.service: Deactivated successfully.694machine # [ 3.667199] systemd[1]: Stopped Apply Kernel Variables.695machine # [ 3.668064] systemd[1]: systemd-modules-load.service: Deactivated successfully.696machine # [ 3.670121] systemd[1]: Stopped Load Kernel Modules.697machine # [ 3.670847] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.698machine # [ 3.672074] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.699machine # [ 3.673356] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.700machine # [ 3.674495] systemd[1]: Stopped Create System Files and Directories.701machine # [ 3.675508] systemd[1]: Stopped target Local File Systems.702machine # [ 3.677136] systemd[1]: Stopped target Preparation for Local File Systems.703machine # [ 3.678046] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.704machine # [ 3.679032] systemd[1]: Stopped Coldplug All udev Devices.705machine # [ 3.679828] systemd[1]: Stopping Rule-based Manager for Device Events and Files...706machine # [ 3.680820] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.707machine # [ 3.684129] systemd[1]: Stopped Virtual Console Setup.708machine # [ 3.684824] systemd[1]: backdoor.service: Deactivated successfully.709machine # [ 3.690142] systemd[1]: Stopped backdoor.service.710machine # [ 3.697815] systemd[1]: systemd-udevd.service: Deactivated successfully.711machine # [ 3.701659] systemd[1]: Stopped Rule-based Manager for Device Events and Files.712machine # [ 3.712097] systemd[1]: Starting Cleanup udev Database...713machine # [ 3.713320] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.714machine # [ 3.714399] systemd[1]: Stopped Create Static Device Nodes in /dev.715machine # [ 3.716137] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.716machine # [ 3.717261] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.717machine # [ 3.718457] systemd[1]: kmod-static-nodes.service: Deactivated successfully.718machine # [ 3.719491] systemd[1]: Stopped Create List of Static Device Nodes.719machine # [ 3.720741] systemd[1]: initrd-cleanup.service: Deactivated successfully.720machine # [ 3.723120] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.721machine # [ 3.724767] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.722machine # [ 3.726091] systemd[1]: Finished rw-sysroot-nix-store.service.723machine # [ 3.741299] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.724machine # [ 3.743132] systemd[1]: Finished Cleanup udev Database.725machine # [ 3.744384] systemd[1]: Reached target Switch Root.726machine # [ 3.746130] systemd[1]: Starting NixOS Activation...727machine # [ 3.814215] initrd-nixos-activation-start[231]: booting system configuration /nix/store/l4ximippgrsph1yhy35jc4hj818yn6ya-nixos-system-machine-test728machine # [ 3.840351] initrd-nixos-activation-start[231]: running activation script...729machine # [ 4.027560] initrd-nixos-activation-start[254]: setting up /etc...730machine # [ 4.129653] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.731machine # [ 4.132088] systemd[1]: Finished NixOS Activation.732machine # [ 4.133357] systemd[1]: Starting Switch Root...733machine # [ 4.147654] systemd[1]: Switching root.734machine # [ 4.415699] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).735machine # [ 4.500231] NET: Registered PF_VSOCK protocol family736machine # [ 4.526885] systemd[1]: systemd 263-7f4df15 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE +EXPERIMENTAL-ENVVAR)737machine # [ 4.530898] systemd[1]: Detected virtualization kvm.738machine # [ 4.531596] systemd[1]: Detected architecture x86-64.739machine # [ 4.532357] systemd[1]: Detected first boot.740machine # [ 4.534116] systemd[1]: Initializing machine ID from random generator.741machine # [ 4.623399] systemd[1]: bpf-restrict-fs: LSM BPF program attached742machine # [ 4.724716] systemd[1]: Applying preset policy.743machine # [ 4.885924] systemd[1]: Populated /etc with preset unit settings.744machine # [ 5.065405] systemd[1]: initrd-switch-root.service: Deactivated successfully.745machine # [ 5.066783] systemd[1]: Stopped initrd-switch-root.service.746machine # [ 5.068913] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.747machine # [ 5.071153] systemd[1]: Created slice Slice /system/getty.748machine # [ 5.072600] systemd[1]: Created slice User and Session Slice.749machine # [ 5.073596] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.750machine # [ 5.075063] systemd[1]: Started Forward Password Requests to Wall Directory Watch.751machine # [ 5.076318] systemd[1]: Expecting device /dev/hvc0...752machine # [ 5.077119] systemd[1]: Expecting device /dev/ttyS0...753machine # [ 5.077936] systemd[1]: Reached target Local Encrypted Volumes.754machine # [ 5.078911] systemd[1]: Stopped target initrd-fs.target.755machine # [ 5.079731] systemd[1]: Stopped target initrd-root-fs.target.756machine # [ 5.080619] systemd[1]: Stopped target initrd-switch-root.target.757machine # [ 5.081531] systemd[1]: Reached target Virtual Machines and Containers.758machine # [ 5.082486] systemd[1]: Reached target Path Units.759machine # [ 5.083226] systemd[1]: Reached target Remote File Systems.760machine # [ 5.084094] systemd[1]: Reached target Slice Units.761machine # [ 5.096759] systemd[1]: Reached target Swaps.762machine # [ 5.099698] systemd[1]: Listening on Query the User Interactively for a Password.763machine # [ 5.101974] systemd[1]: Listening on Process Core Dump Socket.764machine # [ 5.103701] systemd[1]: Listening on Credential Encryption/Decryption.765machine # [ 5.105349] systemd[1]: Listening on Factory Reset Management.766machine # [ 5.106247] systemd[1]: Listening on Hostname Service Socket.767machine # [ 5.108995] systemd[1]: Starting Journal Log Access Socket...768machine # [ 5.110890] systemd[1]: Listening on Journal Audit Socket.769machine # [ 5.113245] systemd[1]: Listening on Console Output Muting Service Socket.770machine # [ 5.114340] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.771machine # [ 5.115478] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os772machine # [ 5.116818] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki773machine # [ 5.120721] systemd[1]: Listening on Disk Repartitioning Service Socket.774machine # [ 5.121783] systemd[1]: Listening on udev Varlink Socket.775machine # [ 5.124138] systemd[1]: Mounting Huge Pages File System...776machine # [ 5.128090] systemd[1]: Mounting POSIX Message Queue File System...777machine # [ 5.134891] systemd[1]: Mounting Kernel Debug File System...778machine # [ 5.143244] systemd[1]: Mounting Kernel Trace File System...779machine # [ 5.151893] systemd[1]: Starting Create List of Static Device Nodes...780machine # [ 5.168220] systemd[1]: Starting Load Kernel Module configfs...781machine # [ 5.171797] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm782machine # [ 5.182673] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore783machine # [ 5.191968] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse784machine # [ 5.200572] systemd[1]: Mounting FUSE Control File System...785machine # [ 5.201380] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67786machine # [ 5.219712] systemd[1]: Starting Journal Service...787machine # [ 5.226953] systemd[1]: Starting Load Kernel Modules...788machine # [ 5.238514] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...789machine # [ 5.247800] systemd[1]: Starting Remount Root and Kernel File Systems...790machine # [ 5.251536] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os791machine # [ 5.260053] systemd[1]: Starting Coldplug All udev Devices...792machine # [ 5.276262] systemd-journald[325]: Collecting audit messages is enabled.793machine # [ 5.283649] systemd[1]: Listening on Journal Log Access Socket.794machine # [ 5.286883] systemd[1]: Mounted Huge Pages File System.795machine # [ 5.290626] systemd[1]: Mounted POSIX Message Queue File System.796machine # [ 5.294758] systemd[1]: Mounted Kernel Debug File System.797machine # [ 5.299887] systemd[1]: Mounted Kernel Trace File System.798machine # [ 5.158427] systemd[1]: Queued start job for default target Multi-User System.799machine # [ 5.160373] systemd[1]: Unnecessary job was removed for /sys/devices/pnp0/00:03/00:03:0/00:03:0.0/tty/ttyS0.800machine # [ 5.308114] systemd[1]: Started Journal Service.801machine # [ 5.166422] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.802machine # [ 5.169875] systemd[1]: systemd-journald.service: Deactivated successfully.803machine # [ 5.320624] loop: module loaded804machine # [ 5.179075] systemd[1]: Finished Create List of Static Device Nodes.805machine # [ 5.180223] systemd-modules-load[326]: Inserted module 'loop'806machine # [ 5.181605] systemd[1]: modprobe@configfs.service: Deactivated successfully.807machine # [ 5.183287] systemd[1]: Finished Load Kernel Module configfs.808machine # [ 5.185594] systemd[1]: Mounted FUSE Control File System.809machine # [ 5.191956] systemd[1]: Finished Load Kernel Modules.810machine # [ 5.351491] EXT4-fs (vda): re-mounted 31782943-37d5-4f7e-9468-4636f7ea9e3d.811machine # [ 5.212333] systemd[1]: Mounting Kernel Configuration File System...812machine # [ 5.218070] systemd[1]: Starting Firewall...813machine # [ 5.226995] systemd[1]: Starting Apply Kernel Variables...814machine # [ 5.240277] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...815machine # [ 5.247677] systemd[1]: Finished Remount Root and Kernel File Systems.816machine # [ 5.257434] systemd[1]: Listening on Disk Image Download Service Socket.817machine # [ 5.263782] systemd-oomd[328]: No swap; memory pressure usage will be degraded818machine # [ 5.271977] systemd[1]: Starting Grow Root File System...819machine # [ 5.281083] systemd[1]: Starting Flush Journal to Persistent Storage...820machine # [ 5.282046] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore821machine # [ 5.293153] systemd[1]: Starting Load/Save OS Random Seed...822machine # [ 5.297095] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os823machine # [ 5.306101] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.824machine # [ 5.512983] systemd-journald[325]: Received client request to flush runtime journal.825machine # [ 5.514541] EXT4-fs (vda): resizing filesystem from 262144 to 262144 blocks826machine # [ 5.463633] systemd[1]: Mounted Kernel Configuration File System.827machine # [ 5.466295] systemd-growfs[345]: Successfully resized "/" to 1G bytes.828machine # [ 5.467171] systemd[1]: Finished Apply Kernel Variables.829machine # [ 5.467852] systemd[1]: Finished Grow Root File System.830machine # [ 5.469127] systemd[1]: Finished Load/Save OS Random Seed.831machine # [ 5.469865] systemd[1]: Reached target First Boot Complete.832machine # [ 5.472441] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.833machine # [ 5.473435] systemd[1]: Starting Create Static Device Nodes in /dev...834machine # [ 5.474302] systemd[1]: Finished Flush Journal to Persistent Storage.835machine # [ 5.568316] systemd[1]: Finished Coldplug All udev Devices.836machine # [ 5.571129] systemd[1]: Finished Create Static Device Nodes in /dev.837machine # [ 5.572307] systemd[1]: Reached target Preparation for Local File Systems.838machine # [ 5.575961] systemd[1]: Starting Rule-based Manager for Device Events and Files...839machine # [ 5.620570] systemd-udevd[366]: Using default interface naming scheme 'v261'.840machine # [ 5.662667] systemd[1]: Started Rule-based Manager for Device Events and Files.841machine # [ 5.726353] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse842machine # [ 5.792059] systemd[1]: Found device /dev/hvc0.843machine # [ 5.812889] systemd[1]: Found device /dev/ttyS0.844machine # [ 5.844980] (udev-worker)[393]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.845machine # [ 5.847435] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line.846machine # [ 5.926108] systemd[1]: Mounting /run/wrappers...847machine # [ 5.949121] systemd[1]: Condition check resulted in Virtio network device being skipped.848machine # [ 5.950259] systemd[1]: Condition check resulted in Virtio network device being skipped.849machine # [ 5.951321] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore850machine # [ 5.952686] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67851machine # [ 5.955253] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore852machine # [ 5.957403] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os853machine # [ 5.958999] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os854machine # [ 5.978552] systemd[1]: Mounted /run/wrappers.855machine # [ 5.979285] systemd[1]: Reached target Local File Systems.856machine # [ 5.983084] systemd[1]: Listening on Boot Loader Control Service Socket.857machine # [ 5.988370] systemd[1]: Starting Create SUID/SGID Wrappers...858machine # [ 5.989266] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.859machine # [ 5.994723] systemd[1]: Starting Save Transient machine-id to Disk...860machine # [ 5.998094] systemd[1]: Starting Create System Files and Directories...861machine # [ 6.119102] systemd[1]: Finished Create System Files and Directories.862machine # [ 6.130178] systemd[1]: Starting Rebuild Journal Catalog...863machine # [ 6.134935] systemd[1]: Starting Record System Boot/Shutdown in UTMP...864machine # [ 6.288542] mousedev: PS/2 mouse device common for all mice865machine # [ 6.338629] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3866machine # [ 6.197366] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.867machine # [ 6.203074] systemd[1]: Finished Save Transient machine-id to Disk.868machine # [ 6.219389] systemd[1]: Finished Record System Boot/Shutdown in UTMP.869machine # [ 6.376664] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console870machine # [ 6.234370] systemd[1]: Finished Rebuild Journal Catalog.871machine # [ 6.239224] systemd[1]: Starting Update is Completed...872machine # [ 6.399607] ACPI: button: Power Button [PWRF]873machine # [ 6.293570] systemd[1]: Finished Update is Completed.874machine # [ 6.516030] systemd[1]: Finished Firewall.875machine # [ 6.522575] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.876machine # [ 6.524759] systemd[1]: Finished Create SUID/SGID Wrappers.877machine # [ 6.521805] rtc_cmos PNP0B00:00: RTC can wake from S4878machine # [ 6.527366] systemd[1]: Reached target System Initialization.879machine # [ 6.529183] systemd[1]: Started Discard unused filesystem blocks once a week.880machine # [ 6.530149] systemd[1]: Started Daily Cleanup of Temporary Directories.881machine # [ 6.531224] systemd[1]: Reached target Timer Units.882machine # [ 6.533186] systemd[1]: Listening on D-Bus System Message Bus Socket.883machine # [ 6.534121] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.884machine # [ 6.535275] systemd[1]: Reached target Socket Units.885machine # [ 6.536671] systemd[1]: Reached target Basic System.886machine # [ 6.538804] systemd[1]: Started backdoor.service.887machine # [ 6.541458] systemd[1]: Starting Import lastlog data into lastlog2 database...888machine # [ 6.544975] rtc_cmos PNP0B00:00: registered as rtc0889machine # [ 6.545045] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T03:04:31 UTC (1792379071)890machine # [ 6.554071] systemd[1]: Starting Name Service Cache Daemon (nsncd)...891machine # [ 6.563100] systemd[1]: Starting Post-Boot Actions...892machine # [ 6.571606] systemd[1]: Started Reset console on configuration changes.893machine # [ 6.590185] systemd[1]: Starting resolvconf update...894machine # [ 6.545123] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs895machine # [ 6.546012] systemd-journald[325]: Time jumped backwards, rotating.896machine # [ 6.576298] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4897machine # [ 6.617549] systemd[1]: Starting D-Bus System Message Bus...898machine # [ 6.645239] nsncd[546]: Oct 19 03:04:31.744 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"899machine # [ 6.648483] systemd[1]: Started Name Service Cache Daemon (nsncd).900machine # [ 6.655909] systemd[1]: Reached target Host and Network Name Lookups.901machine # [ 6.658435] systemd[1]: Reached target User and Group Name Lookups.902machine # connecting to host...903machine # [ 6.684903] systemd[1]: Starting User Login Management...904machine # [ 6.690081] systemd[1]: Finished Post-Boot Actions.905machine # [ 6.583328] parport_pc 00:02: reported by Plug and Play ACPI906machine # [ 6.583423] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]907machine: Guest shell says: b'Spawning backdoor root shell...\n'908machine: connected to guest root shell909machine: (connecting took 3.10 seconds)910machine: (finished: waiting for the VM to finish booting, in 3.10 seconds)911(finished: subtest: testing initrd backdoor, in 7.70 seconds)912subtest: handover to stage-2 systemd works913machine: waiting for unit multi-user.target914machine # [ 6.741404] systemd[1]: Finished Import lastlog data into lastlog2 database.915machine # [ 6.607914] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized916machine # [ 6.672206] Console: switching to colour dummy device 80x25917machine # [ 6.827467] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt918machine # [ 6.827599] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD919machine # [ 6.808705] dbus-broker-launch[551]: Looking up NSS user entry for 'systemd-timesync'...920machine # [ 6.813256] dbus-broker-launch[551]: NSS returned no entry for 'systemd-timesync'921machine # [ 6.814291] dbus-broker-launch[551]: Invalid user-name in /nix/store/48vmh84azhncwmssn2a3dnqgnjgi47xz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"922machine # [ 6.990389] [drm] Found bochs VGA, ID 0xb0c5.923machine # [ 6.990392] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.924machine # [ 6.996988] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic925machine # [ 6.997701] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0926machine # [ 6.860065] systemd-logind[567]: New seat seat0.927machine # [ 7.008058] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6928machine # [ 7.012165] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5929machine # [ 6.892944] dbus-broker-launch[551]: Ready930machine # [ 6.896112] systemd-logind[567]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)931machine # [ 6.919701] systemd-logind[567]: Watching system buttons on /dev/input/event2 (Power Button)932machine # [ 7.099628] Console: switching to colour frame buffer device 160x50933machine # [ 6.962072] systemd[1]: Started User Login Management.934machine # [ 6.962774] systemd[1]: Started D-Bus System Message Bus.935machine # [ 7.120483] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device936machine # [ 6.978560] systemd-logind[567]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)937machine # [ 6.986213] systemd[1]: Stopped target Host and Network Name Lookups.938machine # [ 6.991077] systemd[1]: Stopping Host and Network Name Lookups...939machine # [ 6.991864] systemd[1]: Stopped target User and Group Name Lookups.940machine # [ 6.992703] systemd[1]: Stopping User and Group Name Lookups...941machine # [ 6.997205] systemd[1]: Starting linger-users.service...942machine # [ 6.999940] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...943machine # [ 7.004580] systemd[1]: nscd.service: Deactivated successfully.944machine # [ 7.006388] systemd[1]: Stopped Name Service Cache Daemon (nsncd).945machine # [ 7.037140] systemd[1]: Starting Name Service Cache Daemon (nsncd)...946machine # [ 7.044875] systemd[1]: Starting Virtual Console Setup...947machine # [ 7.192711] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)948machine # [ 7.197959] ppdev: user-space parallel port driver949machine # [ 7.232146] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)950machine # [ 7.091941] systemd[1]: linger-users.service: Deactivated successfully.951machine # [ 7.095412] systemd[1]: Finished linger-users.service.952machine # [ 7.116077] systemd[1]: Finished resolvconf update.953machine # [ 7.120411] systemd[1]: Reached target Preparation for Network.954machine # [ 7.126299] systemd[1]: Starting DHCP Client...955machine # [ 7.133218] systemd[1]: Starting Address configuration of eth1...956machine # [ 7.143775] nsncd[637]: Oct 19 03:04:32.240 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"957machine # [ 7.146777] systemd[1]: Starting Extra networking commands....958machine # [ 7.149822] systemd[1]: Started Name Service Cache Daemon (nsncd).959machine # [ 7.159461] systemd[1]: Reached target Host and Network Name Lookups.960machine # [ 7.160619] systemd[1]: Reached target User and Group Name Lookups.961machine # [ 7.198120] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.962machine # [ 7.201169] systemd[1]: Stopped Virtual Console Setup.963machine # [ 7.217532] systemd[1]: Starting Virtual Console Setup...964machine # [ 7.296308] network-addresses-eth1-start[651]: adding address 192.168.1.1/24... done965machine # [ 7.347856] network-addresses-eth1-start[651]: adding address 2001:db8:1::1/64... done966machine # [ 7.390322] systemd[1]: Finished Address configuration of eth1.967machine # [ 7.422653] dhcpcd[673]: dhcpcd-10.3.2 starting968machine # [ 7.575060] kvm_amd: TSC scaling supported969machine # [ 7.575520] kvm_amd: Nested Virtualization enabled970machine # [ 7.575950] kvm_amd: Nested Paging enabled971machine # [ 7.576323] kvm_amd: LBR virtualization supported972machine # [ 7.577806] kvm_amd: Virtual VMLOAD VMSAVE supported973machine # [ 7.578292] kvm_amd: Virtual GIF supported974machine # [ 7.579038] kvm_amd: Virtual NMI enabled975machine # [ 7.462573] dhcpcd[728]: dev: loaded udev976machine # [ 7.472658] systemd[1]: Finished Extra networking commands..977machine # [ 7.475704] systemd[1]: Reached target Network.978machine # [ 7.480698] systemd[1]: Starting Permit User Sessions...979machine # [ 7.633746] 8021q: 802.1Q VLAN Support v1.8980machine # [ 7.634168] 8021q: adding VLAN 0 to HW filter on device eth1981machine # [ 7.518059] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.982machine # [ 7.666375] EDAC MC: Ver: 3.0.0983machine # [ 7.544054] systemd[1]: Finished Permit User Sessions.984machine # [ 7.550199] systemd[1]: Started Getty on tty1.985machine # [ 7.550831] systemd[1]: Reached target Login Prompts.986machine # [ 7.737082] cfg80211: Loading compiled-in X.509 certificates for regulatory database987machine # [ 7.746840] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'988machine # [ 7.747670] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'989machine # [ 7.749290] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2990machine # [ 7.750134] cfg80211: failed to load regulatory.db991machine # [ 7.776810] 8021q: adding VLAN 0 to HW filter on device eth0992machine # [ 7.632420] dhcpcd[728]: eth0: waiting for carrier993machine # [ 7.633341] dhcpcd[728]: libudev: received NULL device994machine # [ 7.634150] dhcpcd[728]: libudev: received NULL device995machine # [ 7.635086] dhcpcd[728]: eth0: carrier acquired996machine # [ 7.639788] dhcpcd[728]: DUID 00:01:00:01:32:68:45:40:52:54:00:12:34:56997machine # [ 7.640847] dhcpcd[728]: eth0: IAID 00:12:34:56998machine # [ 7.641600] dhcpcd[728]: eth0: adding address fe80::5054:ff:fe12:3456999machine # [ 7.665578] systemd-vconsole-setup[667]: Configuration of first virtual console was skipped, ignoring remaining ones.1000machine # [ 7.668822] systemd[1]: Finished Virtual Console Setup.1001machine # [ 9.356614] dhcpcd[728]: eth0: soliciting a DHCP lease1002machine # [ 9.518870] NET: Registered PF_PACKET protocol family1003machine # [ 9.378915] dhcpcd[728]: eth0: offered 10.0.2.15 from 10.0.2.21004machine # [ 9.381453] dhcpcd[728]: eth0: probing address 10.0.2.15/241005machine # [ 10.000548] dhcpcd[728]: eth0: soliciting an IPv6 router1006machine # [ 10.003234] dhcpcd[728]: eth0: Router Advertisement from fe80::21007machine # [ 10.005468] dhcpcd[728]: eth0: adding address fec0::5054:ff:fe12:3456/641008machine # [ 10.007738] dhcpcd[728]: eth0: adding route to fec0::/641009machine # [ 10.009586] dhcpcd[728]: eth0: adding default route via fe80::21010machine # [ 14.549622] dhcpcd[728]: eth0: leased 10.0.2.15 for 86400 seconds1011machine # [ 14.551807] dhcpcd[728]: eth0: adding route to 10.0.2.0/241012machine # [ 14.556323] dhcpcd[728]: eth0: adding default route via 10.0.2.21013machine # [ 14.646666] systemd[1]: Started DHCP Client.1014machine # [ 14.648728] systemd[1]: Reached target Multi-User System.1015machine # [ 14.649917] systemd[1]: Startup finished in 878ms (kernel) + 3.453s (initrd) + 10.318s (userspace) = 14.649s.1016machine: (finished: waiting for unit multi-user.target, in 8.91 seconds)1017machine: must succeed: systemd-analyze | grep -q '(initrd)'1018machine: (finished: must succeed: systemd-analyze | grep -q '(initrd)', in 0.02 seconds)1019machine: must succeed: touch /testfile1020machine: (finished: must succeed: touch /testfile, in 0.01 seconds)1021machine: must fail: touch /nix/store/testfile1022machine # touch: cannot touch '/nix/store/testfile': Read-only file system1023machine: (finished: must fail: touch /nix/store/testfile, in 0.01 seconds)1024machine: must succeed: [ -e /run/booted-system ]1025machine: (finished: must succeed: [ -e /run/booted-system ], in 0.01 seconds)1026machine: must succeed: [ -e /sys/class ]1027machine: (finished: must succeed: [ -e /sys/class ], in 0.01 seconds)1028machine: must succeed: [ -e /dev/null ]1029machine: (finished: must succeed: [ -e /dev/null ], in 0.01 seconds)1030machine: must succeed: [ -e /proc/1 ]1031machine: (finished: must succeed: [ -e /proc/1 ], in 0.01 seconds)1032machine: must succeed: [ -e /dev/shm ]1033machine: (finished: must succeed: [ -e /dev/shm ], in 0.01 seconds)1034machine: must succeed: [ -e /dev/pts/ptmx ]1035machine: (finished: must succeed: [ -e /dev/pts/ptmx ], in 0.01 seconds)1036machine: must succeed: [ -e /run/keys ]1037machine: (finished: must succeed: [ -e /run/keys ], in 0.01 seconds)1038machine: must succeed: [ ! -e /nixos-closure ]1039machine: (finished: must succeed: [ ! -e /nixos-closure ], in 0.01 seconds)1040(finished: subtest: handover to stage-2 systemd works, in 9.03 seconds)1041subtest: groups work1042machine: must fail: journalctl -b 0 | grep 'systemd-udevd.*Unknown group.*ignoring'1043machine: (finished: must fail: journalctl -b 0 | grep 'systemd-udevd.*Unknown group.*ignoring', in 0.02 seconds)1044(finished: subtest: groups work, in 0.02 seconds)1045subtest: growfs works1046machine: must succeed: df --output=avail / | sed 1d1047machine: (finished: must succeed: df --output=avail / | sed 1d, in 0.01 seconds)1048machine: waiting for the VM to power off1049additionally exposed symbols:1050 machine,1051 vlan1,1052 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_ssh1053machine # [ 15.803532] systemd-logind[567]: poweroff requested from client PID 924 ('poweroff') (unit backdoor.service)...1054machine # [ 15.806165] systemd-logind[567]: System is powering down.1055machine # [ 15.812485] systemd-logind[567]: The system will power off now!1056machine # [ 15.820690] systemd[1]: Removed slice Slice /system/modprobe.1057machine # [ 15.821970] systemd[1]: Stopped target Multi-User System.1058machine # [ 15.823402] systemd[1]: Stopped target Login Prompts.1059machine # [ 15.825144] systemd[1]: Stopped target Virtual Machines and Containers.1060machine # [ 15.826701] systemd[1]: Stopped target Host and Network Name Lookups.1061machine # [ 15.827632] systemd[1]: Stopped target Timer Units.1062machine # [ 15.828357] systemd[1]: fstrim.timer: Deactivated successfully.1063machine # [ 15.829204] systemd[1]: Stopped Discard unused filesystem blocks once a week.1064machine # [ 15.830096] systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully.1065machine # [ 15.831613] systemd[1]: Stopped Daily Cleanup of Temporary Directories.1066machine # [ 15.832540] systemd[1]: systemd-rfkill.socket: Deactivated successfully.1067machine # [ 15.833460] systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.1068machine # [ 15.835599] systemd[1]: Mounting /run/initramfs...1069machine # [ 15.837518] systemd[1]: Stopping backdoor.service...1070machine # [ 15.838640] dhcpcd[728]: received SIGTERM, stopping1071machine # [ 15.840249] dhcpcd[728]: eth0: removing interface1072machine # [ 15.840906] systemd[1]: Stopping DHCP Client...1073machine # [ 15.842948] dhcpcd[728]: eth0: deleting address fec0::5054:ff:fe12:3456/641074machine # [ 15.844337] systemd[1]: Stopping Getty on tty1...1075machine # [ 15.845956] dhcpcd[728]: eth0: deleting route to fec0::/641076machine # [ 15.846804] systemd[1]: lastlog2-import.service: Deactivated successfully.1077machine # [ 15.847883] dhcpcd[728]: eth0: deleting default route via fe80::21078machine # [ 15.848759] systemd[1]: Stopped Import lastlog data into lastlog2 database.1079machine # [ 15.849684] dhcpcd[728]: script_status: /nix/store/5bw65sv1gjzvvsrvj9l01ls66r2ibg5y-dhcpcd-10.3.2/libexec/dhcpcd-run-hooks: Terminated1080machine # [ 15.851230] dhcpcd[728]: eth0: deleting address fe80::5054:ff:fe12:34561081machine # [ 15.852108] dhcpcd[728]: eth0: deleting route to 10.0.2.0/241082machine # [ 15.852825] dhcpcd[728]: eth0: deleting default route via 10.0.2.21083machine # [ 15.858900] systemd[1]: Stopping Address configuration of eth1...1084machine # [ 15.865308] systemd[1]: Stopping Post-Boot Actions...1085machine # [ 15.866849] systemd[1]: reload-systemd-vconsole-setup.service: Deactivated successfully.1086machine # [ 15.868597] systemd[1]: Stopped Reset console on configuration changes.1087machine # [ 15.870622] systemd[1]: Stopping User Login Management...1088machine # [ 15.873137] systemd[1]: systemd-machine-id-commit.service: Deactivated successfully.1089machine # [ 15.874946] systemd[1]: Stopped Save Transient machine-id to Disk.1090machine # [ 15.877142] systemd[1]: Stopped target First Boot Complete.1091machine # [ 15.880993] systemd[1]: Stopping Userspace Out-Of-Memory (OOM) Killer...1092machine # [ 15.884178] dhcpcd[728]: dhcpcd exited1093machine # [ 15.889503] systemd[1]: Stopping Load/Save OS Random Seed...1094machine # [ 15.902776] systemd[1]: systemd-oomd.service: Deactivated successfully.1095machine # [ 15.905557] systemd[1]: Stopped Userspace Out-Of-Memory (OOM) Killer.1096machine # [ 15.911947] systemd[1]: backdoor.service: Deactivated successfully.1097machine # [ 15.915534] systemd[1]: Stopped backdoor.service.1098machine # [ 15.922416] systemd[1]: systemd-logind.service: Deactivated successfully.1099machine # [ 15.925934] systemd[1]: Stopped User Login Management.1100machine # [ 15.939113] systemd[1]: dhcpcd.service: Deactivated successfully.1101machine # [ 15.940245] systemd[1]: Stopped DHCP Client.1102machine # [ 15.942111] systemd[1]: dhcpcd.service: Consumed 158ms CPU time over 8.812s wall clock time, 5.9M memory peak, 8K written to disk, 96B incoming IP traffic, 56B outgoing IP traffic.1103machine # [ 15.944876] network-addresses-eth1-pre-stop[929]: deleting address 192.168.1.1/24... done1104machine # [ 15.948671] systemd[1]: getty@tty1.service: Deactivated successfully.1105machine # [ 15.950680] systemd[1]: Stopped Getty on tty1.1106machine # [ 15.958234] network-addresses-eth1-pre-stop[929]: deleting address 2001:db8:1::1/64... done1107machine # [ 15.962091] systemd[1]: Mounted /run/initramfs.1108machine # [ 15.962829] systemd[1]: post-boot.service: Deactivated successfully.1109machine # [ 15.964184] systemd[1]: Stopped Post-Boot Actions.1110machine # [ 15.970122] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1111machine # [ 15.971320] systemd[1]: Stopped Address configuration of eth1.1112machine # [ 15.974741] systemd[1]: Removed slice Slice /system/getty.1113machine # [ 15.978115] systemd[1]: Starting Generate shutdown ramfs...1114machine # [ 15.980607] systemd[1]: Stopping Permit User Sessions...1115machine # [ 16.011640] systemd[1]: systemd-user-sessions.service: Deactivated successfully.1116machine # [ 16.014243] systemd[1]: Stopped Permit User Sessions.1117machine # [ 16.017309] systemd[1]: Stopped target Network.1118machine # [ 16.018040] systemd[1]: Stopped target User and Group Name Lookups.1119machine # [ 16.018912] systemd[1]: Stopped target Remote File Systems.1120machine # [ 16.019802] systemd[1]: network-local-commands.service: Deactivated successfully.1121machine # [ 16.021125] systemd[1]: Stopped Extra networking commands..1122machine # [ 16.023650] systemd[1]: Stopped target Preparation for Network.1123machine # [ 16.026537] systemd[1]: Stopping Firewall...1124machine # [ 16.027334] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1125machine # [ 16.028234] systemd[1]: resolvconf.service: Deactivated successfully.1126machine # [ 16.029134] systemd[1]: Stopped resolvconf update.1127machine # [ 16.033165] systemd[1]: nscd.service: Deactivated successfully.1128machine # [ 16.034231] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1129machine # [ 16.043225] systemd[1]: Stopped target Basic System.1130machine # [ 16.043980] systemd[1]: Stopped target Path Units.1131machine # [ 16.044737] systemd[1]: Stopped target Slice Units.1132machine # [ 16.048830] systemd[1]: Removed slice User and Session Slice.1133machine # [ 16.053601] systemd[1]: Stopped target Socket Units.1134machine # [ 16.054503] systemd[1]: systemd-hostnamed.socket: Deactivated successfully.1135machine # [ 16.056255] systemd[1]: Closed Hostname Service Socket.1136machine # [ 16.056971] systemd[1]: systemd-importd.socket: Deactivated successfully.1137machine # [ 16.060270] systemd[1]: Closed Disk Image Download Service Socket.1138machine # [ 16.061214] systemd[1]: systemd-machined.socket: Deactivated successfully.1139machine # [ 16.062130] systemd[1]: Closed Virtual Machine and Container Registration Service Socket.1140machine # [ 16.063178] systemd[1]: systemd-mute-console.socket: Deactivated successfully.1141machine # [ 16.065144] systemd[1]: Closed Console Output Muting Service Socket.1142machine # [ 16.065960] systemd[1]: systemd-oomd.socket: Deactivated successfully.1143machine # [ 16.067078] systemd[1]: Closed Userspace Out-Of-Memory (OOM) Killer Socket.1144machine # [ 16.067961] systemd[1]: systemd-repart.socket: Deactivated successfully.1145machine # [ 16.068885] systemd[1]: Closed Disk Repartitioning Service Socket.1146machine # [ 16.072415] dbus-broker[618]: Dispatched 763 messages @ 2(±3)μs / message.1147machine # [ 16.073542] systemd[1]: Stopping D-Bus System Message Bus...1148machine # [ 16.075476] systemd[1]: dbus-broker.service: Deactivated successfully.1149machine # [ 16.078419] systemd[1]: Stopped D-Bus System Message Bus.1150machine # [ 16.083138] systemd[1]: systemd-random-seed.service: Deactivated successfully.1151machine # [ 16.085156] systemd[1]: Stopped Load/Save OS Random Seed.1152machine # [ 16.090146] systemd[1]: dbus.socket: Deactivated successfully.1153machine # [ 16.090941] systemd[1]: Closed D-Bus System Message Bus Socket.1154machine # [ 16.092042] systemd[1]: Stopped target System Initialization.1155machine # [ 16.092816] systemd[1]: Stopped target Local Encrypted Volumes.1156machine # [ 16.094262] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1157machine # [ 16.095365] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1158machine # [ 16.097339] systemd[1]: systemd-ask-password-wall.path: Deactivated successfully.1159machine # [ 16.100129] systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.1160machine # [ 16.101183] systemd[1]: systemd-sysctl.service: Deactivated successfully.1161machine # [ 16.102187] systemd[1]: Stopped Apply Kernel Variables.1162machine # [ 16.102988] systemd[1]: systemd-coredump.socket: Deactivated successfully.1163machine # [ 16.103940] systemd[1]: Closed Process Core Dump Socket.1164machine # [ 16.104683] systemd[1]: systemd-update-done.service: Deactivated successfully.1165machine # [ 16.105643] systemd[1]: Stopped Update is Completed.1166machine # [ 16.107224] systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully.1167machine # [ 16.108707] systemd[1]: Stopped Rebuild Journal Catalog.1168machine # [ 16.110655] make-initrd-ng[951]: /etc/initrd-release -> /nix/store/7dkvhcvi4mri514gbvzzfwpl1ra73ps9-etc-os-release1169machine # [ 16.112224] make-initrd-ng[951]: /etc/os-release -> /nix/store/7dkvhcvi4mri514gbvzzfwpl1ra73ps9-etc-os-release1170machine # [ 16.113731] make-initrd-ng[951]: /shutdown -> /nix/store/gy0n4g5lhqhhbxf2m468cxbp6qkgnx6c-systemd-263-7f4df15/lib/systemd/systemd-shutdown1171machine # [ 16.116980] systemd[1]: Stopping Record System Boot/Shutdown in UTMP...1172machine # [ 16.151216] systemd[1]: systemd-update-utmp.service: Deactivated successfully.1173machine # [ 16.154085] systemd[1]: Stopped Record System Boot/Shutdown in UTMP.1174machine # [ 16.156103] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1175machine # [ 16.157094] systemd[1]: Stopped Create System Files and Directories.1176machine # [ 16.157916] systemd[1]: Stopped target Local File Systems.1177machine # [ 16.161422] systemd[1]: Unmounting /run/keys...1178machine # [ 16.163607] systemd[1]: Unmounting /run/wrappers...1179machine # [ 16.164415] systemd[1]: systemd-growfs-root.service: Deactivated successfully.1180machine # [ 16.166271] systemd[1]: Stopped Grow Root File System.1181machine # [ 16.201239] systemd[1]: firewall.service: Deactivated successfully.1182machine # [ 16.208964] systemd[1]: Stopped Firewall.1183machine # [ 16.212291] systemd[1]: systemd-modules-load.service: Deactivated successfully.1184machine # [ 16.213346] systemd[1]: Stopped Load Kernel Modules.1185machine # [ 16.237679] systemd[1]: run-keys.mount: Deactivated successfully.1186machine # [ 16.240237] systemd[1]: Unmounted /run/keys.1187machine # [ 16.241439] systemd[1]: generate-shutdown-ramfs.service: Deactivated successfully.1188machine # [ 16.243173] systemd[1]: Finished Generate shutdown ramfs.1189machine # [ 16.244588] systemd[1]: run-wrappers.mount: Deactivated successfully.1190machine # [ 16.246062] systemd[1]: Unmounted /run/wrappers.1191machine # [ 16.247886] systemd[1]: Stopped target Preparation for Local File Systems.1192machine # [ 16.249591] systemd[1]: Stopped target Swaps.1193machine # [ 16.250501] systemd[1]: Reached target Unmount All Filesystems.1194machine # [ 16.251553] systemd[1]: systemd-remount-fs.service: Deactivated successfully.1195machine # [ 16.252705] systemd[1]: Stopped Remount Root and Kernel File Systems.1196machine # [ 16.254115] systemd[1]: systemd-fsck-root.service: Deactivated successfully.1197machine # [ 16.255118] systemd[1]: Stopped File System Check on Root Device.1198machine # [ 16.255963] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1199machine # [ 16.258154] systemd[1]: Stopped Create Static Device Nodes in /dev.1200machine # [ 16.259050] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1201machine # [ 16.260256] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1202machine # [ 16.261279] systemd[1]: Reached target System Shutdown.1203machine # [ 16.262045] systemd[1]: Reached target Late Shutdown Services.1204machine # [ 16.262879] systemd[1]: systemd-poweroff.service: Deactivated successfully.1205machine # [ 16.264113] systemd[1]: Finished System Power Off.1206machine # [ 16.265106] systemd[1]: Reached target System Power Off.1207machine # [ 16.266130] systemd[1]: Shutting down.1208machine # [ 16.549787] systemd-shutdown[1]: Syncing filesystems and block devices.1209machine # [ 16.669703] systemd-shutdown[1]: Sending SIGTERM to remaining processes...1210machine # [ 16.673674] systemd-journald[325]: Received SIGTERM from PID 1 (systemd-shutdow).1211machine # [ 16.682772] systemd-shutdown[1]: Sending SIGKILL to remaining processes...1212machine # [ 16.686194] systemd-shutdown[1]: Unmounting file systems.1213machine # [ 16.688689] (sd-umount)[983]: Unmounting '/run/credentials/systemd-journald.service'.1214machine # [ 16.689970] (sd-remount)[984]: Remounting '/nix/store' read-only with options 'lowerdir=/sysroot/nix/.ro-store,upperdir=/sysroot/nix/.rw-store/upper,workdir=/sysroot/nix/.rw-store/work,uuid=on'.1215machine # [ 16.695295] (sd-umount)[985]: Unmounting '/nix/store'.1216machine # [ 16.696879] (sd-umount)[985]: Failed to unmount /nix/store: Device or resource busy1217machine # [ 16.698817] systemd-shutdown[1]: Moving mount /nix/store to /run/shutdown/mounts/4110161563e1e75e.1218machine # [ 16.700065] (sd-remount)[986]: Remounting '/nix/store' read-only with options 'lowerdir=/sysroot/nix/.ro-store,upperdir=/sysroot/nix/.rw-store/upper,workdir=/sysroot/nix/.rw-store/work,uuid=on'.1219machine # [ 16.702152] (sd-umount)[987]: Unmounting '/nix/store'.1220machine # [ 16.703171] (sd-remount)[988]: Remounting '/tmp/xchg' read-only with options ''.1221machine # [ 16.704393] (sd-umount)[989]: Unmounting '/tmp/xchg'.1222machine # [ 16.705563] (sd-remount)[990]: Remounting '/tmp/shared' read-only with options ''.1223machine # [ 16.706737] (sd-umount)[991]: Unmounting '/tmp/shared'.1224machine # [ 16.707869] (sd-umount)[992]: Unmounting '/nix/.ro-store'.1225machine # [ 16.708859] (sd-umount)[993]: Unmounting '/nix/.rw-store'.1226machine # [ 16.709855] (sd-remount)[994]: Remounting '/' read-only with options ''.1227machine # [ 16.715211] EXT4-fs (vda): re-mounted 31782943-37d5-4f7e-9468-4636f7ea9e3d ro.1228machine # [ 16.716881] (sd-umount)[995]: Unmounting '/run/shutdown/mounts/4110161563e1e75e'.1229machine # [ 16.717687] (sd-umount)[995]: Failed to unmount /run/shutdown/mounts/4110161563e1e75e: Device or resource busy1230machine # [ 16.718868] systemd-shutdown[1]: Not all file systems unmounted, 1 left.1231machine # [ 16.719576] systemd-shutdown[1]: Deactivating swaps.1232machine # [ 16.720088] systemd-shutdown[1]: All swaps deactivated.1233machine # [ 16.720680] systemd-shutdown[1]: Detaching loop devices.1234machine # [ 16.724156] systemd-shutdown[1]: All loop devices detached.1235machine # [ 16.724797] systemd-shutdown[1]: Stopping MD devices.1236machine # [ 16.725392] systemd-shutdown[1]: All MD devices stopped.1237machine # [ 16.725981] systemd-shutdown[1]: Detaching DM devices.1238machine # [ 16.726569] systemd-shutdown[1]: All DM devices detached.1239machine # [ 16.727148] systemd-shutdown[1]: Unmounting file systems.1240machine # [ 16.728143] (sd-umount)[996]: Unmounting '/run/shutdown/mounts/4110161563e1e75e'.1241machine # [ 16.728978] (sd-umount)[996]: Failed to unmount /run/shutdown/mounts/4110161563e1e75e: Device or resource busy1242machine # [ 16.730149] systemd-shutdown[1]: Not all file systems unmounted, 1 left.1243machine # [ 16.730878] systemd-shutdown[1]: Cannot finalize remaining file systems, continuing.1244machine # [ 16.733465] systemd-shutdown[1]: Successfully changed into root pivot.1245machine # [ 16.734101] systemd-shutdown[1]: Entering exitrd...1246machine # [ 16.738848] shutdown[1]: Syncing filesystems and block devices.1247machine # [ 16.739812] shutdown[1]: Sending SIGTERM to remaining processes...1248machine # [ 16.742058] shutdown[1]: Sending SIGKILL to remaining processes...1249machine # [ 16.744252] shutdown[1]: Unmounting file systems.1250machine # [ 16.745830] (sd-umoun[998]: Unmounting '/run/credentials'.1251machine # [ 16.746877] (sd-umoun[999]: Unmounting '/oldroot/sys/kernel/config'.1252machine # [ 16.747984] (sd-umoun[1000]: Unmounting '/oldroot/sys/fs/fuse/connections'.1253machine # [ 16.749213] (sd-umoun[1001]: Unmounting '/oldroot/sys/kernel/tracing'.1254machine # [ 16.750345] (sd-umoun[1002]: Unmounting '/oldroot/sys/kernel/debug'.1255machine # [ 16.751493] (sd-umoun[1003]: Unmounting '/oldroot/dev/hugepages'.1256machine # [ 16.752990] (sd-umoun[1004]: Unmounting '/oldroot/dev/mqueue'.1257machine # [ 16.754389] (sd-umoun[1005]: Unmounting '/oldroot/sys/fs/bpf'.1258machine # [ 16.755784] (sd-umoun[1006]: Unmounting '/oldroot/sys/fs/pstore'.1259machine # [ 16.757159] (sd-umoun[1007]: Unmounting '/oldroot/sys/fs/cgroup'.1260machine # [ 16.758603] (sd-umoun[1008]: Unmounting '/oldroot/sys/kernel/security'.1261machine # [ 16.759998] (sd-umoun[1009]: Unmounting '/oldroot/run/shutdown/mounts/4110161563e1e75e'.1262machine # [ 16.778685] (sd-umoun[1010]: Unmounting '/oldroot/sys'.1263machine # [ 16.780036] (sd-umoun[1011]: Unmounting '/oldroot/proc'.1264machine # [ 16.781568] (sd-umoun[1012]: Unmounting '/oldroot/dev/shm'.1265machine # [ 16.782857] (sd-umoun[1013]: Unmounting '/oldroot/dev/pts'.1266machine # [ 16.784232] (sd-umoun[1014]: Unmounting '/oldroot/dev'.1267machine # [ 16.785600] (sd-umoun[1015]: Unmounting '/oldroot/run'.1268machine # [ 16.786907] (sd-umoun[1016]: Unmounting '/oldroot'.1269machine # [ 16.789168] EXT4-fs (vda): unmounting filesystem 31782943-37d5-4f7e-9468-4636f7ea9e3d.1270machine # [ 16.791129] shutdown[1]: All filesystems unmounted.1271machine # [ 16.792150] shutdown[1]: Deactivating swaps.1272machine # [ 16.793084] shutdown[1]: All swaps deactivated.1273machine # [ 16.794518] shutdown[1]: Detaching loop devices.1274machine # [ 16.796743] shutdown[1]: All loop devices detached.1275machine # [ 16.797814] shutdown[1]: Stopping MD devices.1276machine # [ 16.798812] shutdown[1]: All MD devices stopped.1277machine # [ 16.799628] shutdown[1]: Detaching DM devices.1278machine # [ 16.800458] shutdown[1]: All DM devices detached.1279machine # [ 16.801241] shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached.1280machine # [ 16.802679] shutdown[1]: Syncing filesystems and block devices.1281machine # [ 16.803924] shutdown[1]: Powering off.1282machine # [ 16.813808] ACPI: PM: Preparing to enter system sleep state S51283machine # [ 16.814664] kvm: exiting hardware virtualization1284machine # [ 16.815264] reboot: Power down1285machine # [2026-10-19T03:04:42Z INFO virtiofsd] Client disconnected, shutting down1286machine # [2026-10-19T03:04:42Z INFO virtiofsd] Client disconnected, shutting down1287machine # [2026-10-19T03:04:42Z INFO virtiofsd] Client disconnected, shutting down1288machine: (finished: waiting for the VM to power off, in 1.00 seconds)1289Image resized.1290machine: starting vm1291machine: QEMU running (pid 95)1292machine # Starting virtiofs daemons...1293machine: waiting for unit initrd.target1294machine # [2026-10-19T03:04:42Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)1295machine: waiting for the VM to finish booting1296machine # [2026-10-19T03:04:42Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether1297machine # [2026-10-19T03:04:42Z INFO virtiofsd] Waiting for vhost-user socket connection...1298machine # [2026-10-19T03:04:42Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)1299machine # [2026-10-19T03:04:42Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether1300machine # [2026-10-19T03:04:42Z INFO virtiofsd] Waiting for vhost-user socket connection...1301machine # [2026-10-19T03:04:42Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)1302machine # [2026-10-19T03:04:42Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether1303machine # [2026-10-19T03:04:42Z INFO virtiofsd] Waiting for vhost-user socket connection...1304machine # [2026-10-19T03:04:42Z INFO virtiofsd] Client connected, servicing requests1305machine # [2026-10-19T03:04:42Z INFO virtiofsd] Client connected, servicing requests1306machine # [2026-10-19T03:04:42Z INFO virtiofsd] Client connected, servicing requests1307machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)1308machine # 1309machine # 1310machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA001311machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...1312machine # 1313machine # 1314machine # 1315machine # 1316machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB001317machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)...1318machine # 1319machine # 1320machine # Booting from ROM...1321machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @17917251721322machine # [ 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/l4ximippgrsph1yhy35jc4hj818yn6ya-nixos-system-machine-test/init regInfo=/nix/.ro-store/jafvx9ylvki6nmkwwv97d26k2b80jjq1-closure-info/registration console=ttyS0,115200n8 console=tty01323machine # [ 0.000000] BIOS-provided physical RAM map:1324machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable1325machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved1326machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved1327machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable1328machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved1329machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved1330machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved1331machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved1332machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved1333machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved1334machine # [ 0.000000] NX (Execute Disable) protection: active1335machine # [ 0.000000] APIC: Static calls initialized1336machine # [ 0.000000] SMBIOS 2.8 present.1337machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/20141338machine # [ 0.000000] DMI: Memory slots populated: 1/11339machine # [ 0.000000] Hypervisor detected: KVM1340machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x100000000001341machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d001342machine # [ 0.000001] kvm-clock: using sched offset of 461547659 cycles1343machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns1344machine # [ 0.000005] tsc: Detected 2400.008 MHz processor1345machine # [ 0.000767] last_pfn = 0x3ffd8 max_arch_pfn = 0x100000000001346machine # [ 0.000794] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs1347machine # [ 0.000797] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT1348machine # [ 0.002691] found SMP MP-table at [mem 0x000f5450-0x000f545f]1349machine # [ 0.002703] Using GB pages for direct mapping1350machine # [ 0.002761] RAMDISK: [mem 0x3e2ba000-0x3ffcffff]1351machine # [ 0.002769] ACPI: Early table checksum verification disabled1352machine # [ 0.002772] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )1353machine # [ 0.002776] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)1354machine # [ 0.002781] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)1355machine # [ 0.002789] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)1356machine # [ 0.002791] ACPI: FACS 0x000000003FFE0000 0000401357machine # [ 0.002792] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)1358machine # [ 0.002794] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)1359machine # [ 0.002796] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)1360machine # [ 0.002797] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)1361machine # [ 0.002798] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]1362machine # [ 0.002799] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]1363machine # [ 0.002800] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]1364machine # [ 0.002801] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]1365machine # [ 0.002801] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]1366machine # [ 0.002802] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]1367machine # [ 0.002802] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]1368machine # [ 0.003038] No NUMA configuration found1369machine # [ 0.003039] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]1370machine # [ 0.003041] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]1371machine # [ 0.005308] Zone ranges:1372machine # [ 0.005309] DMA [mem 0x0000000000001000-0x0000000000ffffff]1373machine # [ 0.005311] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]1374machine # [ 0.005312] Normal empty1375machine # [ 0.005313] Device empty1376machine # [ 0.005313] Movable zone start for each node1377machine # [ 0.005314] Early memory node ranges1378machine # [ 0.005314] node 0: [mem 0x0000000000001000-0x000000000009efff]1379machine # [ 0.005315] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]1380machine # [ 0.005316] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]1381machine # [ 0.005336] On node 0, zone DMA: 1 pages in unavailable ranges1382machine # [ 0.005597] On node 0, zone DMA: 97 pages in unavailable ranges1383machine # [ 0.023123] On node 0, zone DMA32: 40 pages in unavailable ranges1384machine # [ 0.023579] ACPI: PM-Timer IO Port: 0x6081385machine # [ 0.023590] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])1386machine # [ 0.023617] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-231387machine # [ 0.023619] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)1388machine # [ 0.023621] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)1389machine # [ 0.023622] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)1390machine # [ 0.023623] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)1391machine # [ 0.023624] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)1392machine # [ 0.023626] ACPI: Using ACPI (MADT) for SMP configuration information1393machine # [ 0.023627] ACPI: HPET id: 0x8086a201 base: 0xfed000001394machine # [ 0.023630] TSC deadline timer available1395machine # [ 0.023634] CPU topo: Max. logical packages: 11396machine # [ 0.023635] CPU topo: Max. logical dies: 11397machine # [ 0.023635] CPU topo: Max. dies per package: 11398machine # [ 0.023639] CPU topo: Max. threads per core: 11399machine # [ 0.023639] CPU topo: Num. cores per package: 11400machine # [ 0.023640] CPU topo: Num. threads per package: 11401machine # [ 0.023640] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs1402machine # [ 0.023657] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()1403machine # [ 0.023685] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]1404machine # [ 0.023687] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]1405machine # [ 0.023688] [mem 0x40000000-0xafffffff] available for PCI devices1406machine # [ 0.023689] Booting paravirtualized kernel on KVM1407machine # [ 0.023691] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns1408machine # [ 0.028338] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:11409machine # [ 0.030493] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u20971521410machine # [ 0.030539] kvm-guest: PV spinlocks disabled, single CPU1411machine # [ 0.030540] 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/l4ximippgrsph1yhy35jc4hj818yn6ya-nixos-system-machine-test/init regInfo=/nix/.ro-store/jafvx9ylvki6nmkwwv97d26k2b80jjq1-closure-info/registration console=ttyS0,115200n8 console=tty01412machine # [ 0.030632] Unknown kernel command line parameters "regInfo=/nix/.ro-store/jafvx9ylvki6nmkwwv97d26k2b80jjq1-closure-info/registration", will be passed to user space.1413machine # [ 0.030644] random: crng init done1414machine # [ 0.030645] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes1415machine # [ 0.030665] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)1416machine # [ 0.031205] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)1417machine # [ 0.031234] Fallback order for Node 0: 01418machine # [ 0.031237] Built 1 zonelists, mobility grouping on. Total pages: 2620061419machine # [ 0.031238] Policy zone: DMA321420machine # [ 0.033558] mem auto-init: stack:all(zero), heap alloc:on, heap free:off1421machine # [ 0.036000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=11422machine # [ 0.038213] allocated 2097152 bytes of page_ext1423machine # [ 0.048391] ftrace: allocating 48842 entries in 192 pages1424machine # [ 0.048393] ftrace: allocated 192 pages with 2 groups1425machine # [ 0.049262] Dynamic Preempt: lazy1426machine # [ 0.049423] rcu: Preemptible hierarchical RCU implementation.1427machine # [ 0.049423] rcu: RCU event tracing is enabled.1428machine # [ 0.049424] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.1429machine # [ 0.049426] Trampoline variant of Tasks RCU enabled.1430machine # [ 0.049426] Rude variant of Tasks RCU enabled.1431machine # [ 0.049427] Tracing variant of Tasks RCU enabled.1432machine # [ 0.049428] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.1433machine # [ 0.049428] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=11434machine # [ 0.049449] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.1435machine # [ 0.049451] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.1436machine # [ 0.049451] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.1437machine # [ 0.053793] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 161438machine # [ 0.054075] rcu: srcu_init: Setting srcu_struct sizes based on contention.1439machine # [ 0.054082] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns1440machine # [ 0.054201] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)1441machine # [ 0.057682] Console: colour VGA+ 80x251442machine # [ 0.057685] printk: legacy console [tty0] enabled1443machine # [ 0.087071] printk: legacy console [ttyS0] enabled1444machine # [ 0.194276] ACPI: Core revision 202508071445machine # [ 0.195204] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns1446machine # [ 0.196887] APIC: Switch to symmetric I/O mode setup1447machine # [ 0.197902] x2apic enabled1448machine # [ 0.198664] APIC: Switched APIC routing to: physical x2apic1449machine # [ 0.214576] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-11450machine # [ 0.215642] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns1451machine # [ 0.217410] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008)1452machine # [ 0.219473] x86/cpu: User Mode Instruction Prevention (UMIP) activated1453machine # [ 0.220537] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 1271454machine # [ 0.221406] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 01455machine # [ 0.222410] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto1456machine # [ 0.223406] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl1457machine # [ 0.224406] Transient Scheduler Attacks: Vulnerable: No microcode1458machine # [ 0.225405] Spectre V2 : Mitigation: Enhanced / Automatic IBRS1459machine # [ 0.226406] Speculative Return Stack Overflow: Mitigation: Safe RET1460machine # [ 0.227406] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization1461machine # [ 0.229414] Spectre V2 : Enabling IBPB for BPF1462machine # [ 0.230407] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier1463machine # [ 0.231407] active return thunk: srso_alias_return_thunk1464machine # [ 0.232328] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'1465machine # [ 0.233406] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'1466machine # [ 0.234406] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'1467machine # [ 0.235406] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'1468machine # [ 0.236406] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'1469machine # [ 0.237406] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'1470machine # [ 0.238406] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'1471machine # [ 0.239406] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 2561472machine # [ 0.241200] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 641473machine # [ 0.242210] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 5121474machine # [ 0.243406] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 10241475machine # [ 0.244406] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 81476machine # [ 0.245406] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.1477machine # [ 0.280504] Freeing SMP alternatives memory: 48K1478machine # [ 0.281344] pid_max: default: 32768 minimum: 3011479machine # [ 0.282112] LSM: initializing lsm=capability,landlock,yama,bpf,ima1480machine # [ 0.283428] landlock: Up and running.1481machine # [ 0.284109] Yama: becoming mindful.1482machine # [ 0.284617] LSM support for eBPF active1483machine # [ 0.285522] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)1484machine # [ 0.286429] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)1485machine # [ 0.289529] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)1486machine # [ 0.290966] Performance Events: Fam17h+ core perfctr, AMD PMU driver.1487machine # [ 0.291416] ... version: 21488machine # [ 0.292165] ... bit width: 481489machine # [ 0.292408] ... generic counters: 61490machine # [ 0.293146] ... generic bitmap: 000000000000003f1491machine # [ 0.293408] ... fixed-purpose counters: 01492machine # [ 0.294162] ... fixed-purpose bitmap: 00000000000000001493machine # [ 0.294408] ... value mask: 0000ffffffffffff1494machine # [ 0.295349] ... max period: 00007fffffffffff1495machine # [ 0.296142] ... global_ctrl mask: 000000000000003f1496machine # [ 0.296522] signal: max sigframe size: 33761497machine # [ 0.297342] rcu: Hierarchical SRCU implementation.1498machine # [ 0.298041] rcu: Max phase no-delay instances is 400.1499machine # [ 0.303039] smp: Bringing up secondary CPUs ...1500machine # [ 0.303424] smp: Brought up 1 node, 1 CPU1501machine # [ 0.304130] smpboot: Total of 1 processors activated (4800.01 BogoMIPS)1502machine # [ 0.304574] Memory: 940360K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 100304K reserved, 0K cma-reserved)1503machine # [ 0.305635] devtmpfs: initialized1504machine # [ 0.306489] x86/mm: Memory block size: 128MB1505machine # [ 0.308176] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)1506machine # [ 0.309428] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).1507machine # [ 0.310490] pinctrl core: initialized pinctrl subsystem1508machine # [ 0.311757] PM: RTC time: 03:04:42, date: 2026-10-191509machine # [ 0.315350] NET: Registered PF_NETLINK/PF_ROUTE protocol family1510machine # [ 0.316588] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations1511machine # [ 0.317430] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations1512machine # [ 0.318560] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations1513machine # [ 0.319418] audit: initializing netlink subsys (disabled)1514machine # [ 0.320635] thermal_sys: Registered thermal governor 'fair_share'1515machine # [ 0.320637] thermal_sys: Registered thermal governor 'bang_bang'1516machine # [ 0.321410] thermal_sys: Registered thermal governor 'step_wise'1517machine # [ 0.322411] audit: type=2000 audit(1792379082.994:1): state=initialized audit_enabled=0 res=11518machine # [ 0.324410] thermal_sys: Registered thermal governor 'user_space'1519machine # [ 0.324411] thermal_sys: Registered thermal governor 'power_allocator'1520machine # [ 0.325424] cpuidle: using governor menu1521machine # [ 0.327711] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.51522machine # [ 0.328633] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]1523machine # [ 0.329412] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry1524machine # [ 0.330420] PCI: Using configuration type 1 for base access1525machine # [ 0.331602] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.1526machine # [ 0.336711] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages1527machine # [ 0.337410] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page1528machine # [ 0.342410] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages1529machine # [ 0.343409] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page1530machine # [ 0.353814] ACPI: Added _OSI(Module Device)1531machine # [ 0.354410] ACPI: Added _OSI(Processor Device)1532machine # [ 0.355200] ACPI: Added _OSI(Processor Aggregator Device)1533machine # [ 0.363993] ACPI: 1 ACPI AML tables successfully acquired and loaded1534machine # [ 0.367856] ACPI: Interpreter enabled1535machine # [ 0.368426] ACPI: PM: (supports S0 S3 S4 S5)1536machine # [ 0.369191] ACPI: Using IOAPIC for interrupt routing1537machine # [ 0.369463] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug1538machine # [ 0.370409] PCI: Using E820 reservations for host bridge windows1539machine # [ 0.373550] ACPI: Enabled 2 GPEs in block 00 to 3F1540machine # [ 0.381502] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])1541machine # [ 0.382417] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]1542machine # [ 0.383486] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]1543machine # [ 0.384535] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]1544machine # [ 0.385766] PCI host bridge to bus 0000:001545machine # [ 0.386413] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]1546machine # [ 0.387409] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]1547machine # [ 0.388409] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]1548machine # [ 0.389409] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]1549machine # [ 0.390408] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]1550machine # [ 0.391408] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]1551machine # [ 0.392409] pci_bus 0000:00: root bus resource [bus 00-ff]1552machine # [ 0.393505] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint1553machine # [ 0.394865] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint1554machine # [ 0.397519] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]1555machine # [ 0.398421] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]1556machine # [ 0.399430] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]1557machine # [ 0.400782] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]1558machine # [ 0.402147] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint1559machine # [ 0.405427] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]1560machine # [ 0.406310] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]1561machine # [ 0.407197] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]1562machine # [ 0.408276] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]1563machine # [ 0.410118] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint1564machine # [ 0.413377] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]1565machine # [ 0.414145] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]1566machine # [ 0.414430] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]1567machine # [ 0.415988] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint1568machine # [ 0.418453] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]1569machine # [ 0.419394] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]1570machine # [ 0.420238] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]1571machine # [ 0.421965] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint1572machine # [ 0.424481] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]1573machine # [ 0.425375] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]1574machine # [ 0.426430] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]1575machine # [ 0.427414] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]1576machine # [ 0.428962] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint1577machine # [ 0.430457] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]1578machine # [ 0.431430] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]1579machine # [ 0.432977] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint1580machine # [ 0.435085] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]1581machine # [ 0.435431] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]1582machine # [ 0.437051] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint1583machine # [ 0.438456] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]1584machine # [ 0.439431] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]1585machine # [ 0.440977] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint1586machine # [ 0.442459] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]1587machine # [ 0.443431] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]1588machine # [ 0.444983] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint1589machine # [ 0.447301] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]1590machine # [ 0.448147] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]1591machine # [ 0.448430] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]1592machine # [ 0.449965] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint1593machine # [ 0.452132] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]1594machine # [ 0.452415] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]1595machine # [ 0.453431] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]1596machine # [ 0.454975] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint1597machine # [ 0.456826] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]1598machine # [ 0.457616] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint1599machine # [ 0.459148] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]1600machine # [ 0.459606] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint1601machine # [ 0.461124] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]1602machine # [ 0.461633] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint1603machine # [ 0.464984] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]1604machine # [ 0.465699] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint1605machine # [ 0.466695] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO1606machine # [ 0.467666] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint1607machine # [ 0.470478] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]1608machine # [ 0.471390] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]1609machine # [ 0.472565] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint1610machine # [ 0.474252] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]1611machine # [ 0.478134] ACPI: PCI: Interrupt link LNKA configured for IRQ 101612machine # [ 0.478525] ACPI: PCI: Interrupt link LNKB configured for IRQ 101613machine # [ 0.479507] ACPI: PCI: Interrupt link LNKC configured for IRQ 111614machine # [ 0.480507] ACPI: PCI: Interrupt link LNKD configured for IRQ 111615machine # [ 0.481509] ACPI: PCI: Interrupt link LNKE configured for IRQ 101616machine # [ 0.482504] ACPI: PCI: Interrupt link LNKF configured for IRQ 101617machine # [ 0.483511] ACPI: PCI: Interrupt link LNKG configured for IRQ 111618machine # [ 0.484508] ACPI: PCI: Interrupt link LNKH configured for IRQ 111619machine # [ 0.485451] ACPI: PCI: Interrupt link GSIA configured for IRQ 161620machine # [ 0.486422] ACPI: PCI: Interrupt link GSIB configured for IRQ 171621machine # [ 0.487421] ACPI: PCI: Interrupt link GSIC configured for IRQ 181622machine # [ 0.488423] ACPI: PCI: Interrupt link GSID configured for IRQ 191623machine # [ 0.489421] ACPI: PCI: Interrupt link GSIE configured for IRQ 201624machine # [ 0.490423] ACPI: PCI: Interrupt link GSIF configured for IRQ 211625machine # [ 0.491424] ACPI: PCI: Interrupt link GSIG configured for IRQ 221626machine # [ 0.492423] ACPI: PCI: Interrupt link GSIH configured for IRQ 231627machine # [ 0.494427] iommu: Default domain type: Translated1628machine # [ 0.495279] iommu: DMA domain TLB invalidation policy: lazy mode1629machine # [ 0.496483] ACPI: bus type USB registered1630machine # [ 0.497278] usbcore: registered new interface driver usbfs1631machine # [ 0.498155] usbcore: registered new interface driver hub1632machine # [ 0.498416] usbcore: registered new device driver usb1633machine # [ 0.500491] NetLabel: Initializing1634machine # [ 0.501139] NetLabel: domain hash size = 1281635machine # [ 0.501409] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO1636machine # [ 0.502450] NetLabel: unlabeled traffic allowed by default1637machine # [ 0.503425] PCI: Using ACPI for IRQ routing1638machine # [ 0.547442] pci 0000:00:01.0: vgaarb: setting as boot VGA device1639machine # [ 0.548404] pci 0000:00:01.0: vgaarb: bridge control possible1640machine # [ 0.548404] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none1641machine # [ 0.548412] vgaarb: loaded1642machine # [ 0.549178] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 01643machine # [ 0.549410] hpet0: 3 comparators, 64-bit 100.000000 MHz counter1644machine # [ 0.554494] clocksource: Switched to clocksource kvm-clock1645machine # [ 0.556301] VFS: Disk quotas dquot_6.6.01646machine # [ 0.558854] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)1647machine # [ 0.560397] pnp: PnP ACPI init1648machine # [ 0.561181] ACPI: IRQ 4 override to edge(!), high(!)1649machine # [ 0.562176] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved1650machine # [ 0.563734] pnp: PnP ACPI: found 5 devices1651machine # [ 0.571439] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns1652machine # [ 0.573011] clocksource: Switched to clocksource acpi_pm1653machine # [ 0.574067] NET: Registered PF_INET protocol family1654machine # [ 0.575143] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)1655machine # [ 0.592226] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)1656machine # [ 0.593925] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)1657machine # [ 0.595313] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)1658machine # [ 0.596742] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)1659machine # [ 0.598042] TCP: Hash tables configured (established 8192 bind 8192)1660machine # [ 0.599198] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)1661machine # [ 0.600538] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)1662machine # [ 0.601712] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)1663machine # [ 0.603012] NET: Registered PF_UNIX/PF_LOCAL protocol family1664machine # [ 0.604003] NET: Registered PF_XDP protocol family1665machine # [ 0.604891] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]1666machine # [ 0.605915] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]1667machine # [ 0.606911] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]1668machine # [ 0.608073] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]1669machine # [ 0.609220] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]1670machine # [ 0.610387] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]1671machine # [ 0.612229] ACPI: \_SB_.GSIA: Enabled at IRQ 161672machine # [ 0.615081] ACPI: \_SB_.GSIB: Enabled at IRQ 171673machine # [ 0.617053] ACPI: \_SB_.GSIC: Enabled at IRQ 181674machine # [ 0.618993] ACPI: \_SB_.GSID: Enabled at IRQ 191675machine # [ 0.620667] PCI: CLS 0 bytes, default 641676machine # [ 0.621591] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns1677machine # [ 0.623375] Trying to unpack rootfs image as initramfs...1678machine # [ 0.669782] Initialise system trusted keyrings1679machine # [ 0.673400] workingset: timestamp_bits=40 max_order=18 bucket_order=01680machine # [ 0.692465] Key type asymmetric registered1681machine # [ 0.693206] Asymmetric key parser 'x509' registered1682machine # [ 0.698398] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)1683machine # [ 0.699850] io scheduler mq-deadline registered1684machine # [ 0.703355] io scheduler kyber registered1685machine # [ 0.704675] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1686machine # [ 0.705971] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A1687machine # [ 0.711218] Linux agpgart interface v0.1031688machine # [ 0.711995] ACPI: bus type drm_connector registered1689machine # [ 0.716777] usbcore: registered new interface driver usbserial_generic1690machine # [ 0.717939] usbserial: USB Serial support registered for generic1691machine # [ 0.720437] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.1692machine # [ 0.720437] Please enable it if your BIOS has the CPPC option.1693machine # [ 0.722773] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled1694machine # [ 0.727486] drop_monitor: Initializing network drop monitor service1695machine # [ 0.728959] NET: Registered PF_INET6 protocol family1696machine # [ 0.731830] Segment Routing with IPv61697machine # [ 0.734382] In-situ OAM (IOAM) with IPv61698machine # [ 0.735780] IPI shorthand broadcast: enabled1699machine # [ 0.744051] sched_clock: Marking stable (585015545, 158323127)->(821854728, -78516056)1700machine # [ 0.747525] registered taskstats version 11701machine # [ 0.748542] Loading compiled-in X.509 certificates1702machine # [ 0.768350] Demotion targets for Node 0: null1703machine # [ 0.769279] Key type .fscrypt registered1704machine # [ 0.772344] Key type fscrypt-provisioning registered1705machine # [ 0.773419] ima: No TPM chip found, activating TPM-bypass!1706machine # [ 0.774367] ima: Allocated hash algorithm: sha11707machine # [ 0.775167] ima: No architecture policies found1708machine # [ 0.779349] PM: Magic number: 14:281:581709machine # [ 0.781082] RAS: Correctable Errors collector initialized.1710machine # [ 0.792393] clk: Disabling unused clocks1711machine # [ 0.793122] PM: genpd: Disabling unused power domains1712machine # [ 0.968946] Freeing initrd memory: 29784K1713machine # [ 0.972178] Freeing unused decrypted memory: 2028K1714machine # [ 0.975059] Freeing unused kernel image (initmem) memory: 3660K1715machine # [ 0.976167] Write protecting the kernel read-only data: 32768k1716machine # [ 0.977542] Freeing unused kernel image (text/rodata gap) memory: 296K1717machine # [ 0.979105] Freeing unused kernel image (rodata/data gap) memory: 684K1718machine # [ 1.030190] x86/mm: Checked W+X mappings: passed, no W+X pages found.1719machine # [ 1.031394] Run /init as init process1720machine # [ 1.042102] systemd[1]: Inserted module 'autofs4'1721machine # [ 1.062074] fuse: init (API version 7.45)1722machine # [ 1.069432] ACPI: \_SB_.GSIG: Enabled at IRQ 221723machine # [ 1.072060] ACPI: \_SB_.GSIH: Enabled at IRQ 231724machine # [ 1.075440] ACPI: \_SB_.GSIE: Enabled at IRQ 201725machine # [ 1.077683] ACPI: \_SB_.GSIF: Enabled at IRQ 211726machine # [ 1.083639] virtiofs virtio5: discovered new tag: nix-store1727machine # [ 1.085232] virtiofs virtio5: virtio_fs_setup_dax: No cache capability1728machine # [ 1.093135] virtiofs virtio6: discovered new tag: shared1729machine # [ 1.094755] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1730machine # [ 1.099646] virtiofs virtio7: discovered new tag: xchg1731machine # [ 1.101259] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1732machine # [ 1.126181] systemd[1]: Successfully made /usr/ read-only.1733machine # [ 1.127970] systemd[1]: systemd 263-7f4df15 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE +EXPERIMENTAL-ENVVAR)1734machine # [ 1.133579] systemd[1]: Detected virtualization kvm.1735machine # [ 1.134514] systemd[1]: Detected architecture x86-64.1736machine # [ 1.135457] systemd[1]: Running in initrd.1737machine # [ 1.136457] systemd[1]: Initializing machine ID from random generator.1738machine # [ 1.137676] systemd[1]: Hostname set to <machine>.1739machine # [ 1.209628] systemd[1]: bpf-restrict-fs: LSM BPF program attached1740machine # [ 1.247014] systemd[1]: Queued start job for default target Initrd Default Target.1741machine # [ 1.251623] systemd[1]: Created slice Slice /system/modprobe.1742machine # [ 1.252859] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1743machine # [ 1.254352] systemd[1]: Expecting device /dev/disk/by-label/nixos...1744machine # [ 1.255481] systemd[1]: Expecting device /dev/hvc0...1745machine # [ 1.256406] systemd[1]: Expecting device /dev/ttyS0...1746machine # [ 1.257315] systemd[1]: Reached target Path Units.1747machine # [ 1.258207] systemd[1]: Reached target Slice Units.1748machine # [ 1.259100] systemd[1]: Reached target Swaps.1749machine # [ 1.259978] systemd[1]: Reached target Timer Units.1750machine # [ 1.261017] systemd[1]: Listening on D-Bus System Message Bus Socket.1751machine # [ 1.262283] systemd[1]: Listening on Journal Socket (/dev/log).1752machine # [ 1.263472] systemd[1]: Listening on Journal Sockets.1753machine # [ 1.264518] systemd[1]: Listening on udev Kernel Socket.1754machine # [ 1.265494] systemd[1]: Reached target Socket Units.1755machine # [ 1.267388] systemd[1]: Starting Create List of Static Device Nodes...1756machine # [ 1.271175] systemd[1]: Starting Load Kernel Module configfs...1757machine # [ 1.292012] systemd[1]: Starting Journal Service...1758machine # [ 1.303422] systemd[1]: Starting Load Kernel Modules...1759machine # [ 1.306095] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1760machine # [ 1.312030] systemd[1]: Starting Coldplug All udev Devices...1761machine # [ 1.326445] systemd[1]: Finished Create List of Static Device Nodes.1762machine # [ 1.332992] systemd[1]: modprobe@configfs.service: Deactivated successfully.1763machine # [ 1.340707] systemd[1]: Finished Load Kernel Module configfs.1764machine # [ 1.344805] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config1765machine # [ 1.350424] systemd-journald[65]: Collecting audit messages is disabled.1766machine # [ 1.358425] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1767machine # [ 1.366822] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1768machine # [ 1.380508] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1769machine # [ 1.400932] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1770machine # [ 1.409536] systemd[1]: Starting Create Static Device Nodes in /dev...1771machine # [ 1.423416] systemd[1]: Finished Load Kernel Modules.1772machine # [ 1.429571] systemd[1]: Starting Apply Kernel Variables...1773machine # [ 1.453904] systemd[1]: Finished Create Static Device Nodes in /dev.1774machine # [ 1.458565] systemd[1]: Reached target Preparation for Local File Systems.1775machine # [ 1.465418] systemd[1]: Reached target Local File Systems.1776machine # [ 1.308677] systemd-modules-load[66]: Inserted module 'dm_mod'1777machine # [ 1.313182] systemd-modules-load[66]: Inserted module 'virtio_balloon'1778machine # [ 1.314228] systemd-modules-load[66]: Inserted module 'virtio_gpu'1779machine # [ 1.474294] systemd[1]: Starting Rule-based Manager for Device Events and Files...1780machine # [ 1.478636] systemd[1]: Started Journal Service.1781machine # [ 1.328908] systemd[1]: Finished Apply Kernel Variables.1782machine # [ 1.342850] systemd[1]: Starting Create System Files and Directories...1783machine # [ 1.379481] systemd[1]: Finished Create System Files and Directories.1784machine # [ 1.384377] systemd-udevd[72]: Using default interface naming scheme 'v261'.1785machine # [ 1.407666] systemd[1]: Started Rule-based Manager for Device Events and Files.1786machine # [ 1.465172] systemd[1]: Finished Coldplug All udev Devices.1787machine # [ 1.467083] systemd[1]: Reached target System Initialization.1788machine # [ 1.467879] systemd[1]: Reached target Basic System.1789machine # [ 1.487080] systemd[1]: Found device /dev/hvc0.1790machine # [ 1.500066] systemd[1]: Found device /dev/ttyS0.1791machine # [ 1.502135] systemd[1]: Started backdoor.service.1792machine # [ 1.524722] backdoor-start[95]: /nix/store/wih4sdpik4mw459vdf955q7qq1j10agf-unit-script-backdoor-start/bin/backdoor-start: line 10: grep: command not found1793machine # connecting to host...1794machine: Guest shell says: b'Spawning backdoor root shell...\n'1795machine: connected to guest root shell1796machine: (connecting took 2.23 seconds)1797machine: (finished: waiting for the VM to finish booting, in 2.23 seconds)1798machine # [ 1.805800] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121799machine # [ 1.820021] serio: i8042 KBD port at 0x60,0x64 irq 11800machine # [ 1.823668] virtio_blk virtio2: 1/0/0 default/read/poll queues1801machine # [ 1.825214] virtio_blk virtio2: [vda] 4194304 512-byte logical blocks (2.15 GB/2.00 GiB)1802machine # [ 1.828647] serio: i8042 AUX port at 0x60,0x64 irq 121803machine # [ 1.857199] uhci_hcd 0000:00:1d.0: UHCI Host Controller1804machine # [ 1.858194] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 11805machine # [ 1.877385] uhci_hcd 0000:00:1d.0: detected 2 ports1806machine # [ 1.878241] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801807machine # [ 1.890752] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181808machine # [ 1.891906] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11809machine # [ 1.747345] systemd[1]: Starting Virtual Console Setup...1810machine # [ 1.907350] usb usb1: Product: UHCI Host Controller1811machine # [ 1.908039] usb usb1: Manufacturer: Linux 6.18.56 uhci_hcd1812machine # [ 1.915621] SCSI subsystem initialized1813machine # [ 1.924416] usb usb1: SerialNumber: 0000:00:1d.01814machine # [ 1.928521] hub 1-0:1.0: USB hub found1815machine # [ 1.781284] systemd-vconsole-setup[109]: Configuration of first virtual console was skipped, ignoring remaining ones.1816machine # [ 1.784418] systemd[1]: Finished Virtual Console Setup.1817machine # [ 1.943511] hub 1-0:1.0: 2 ports detected1818machine # [ 1.786189] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1819machine # [ 1.788957] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1820machine # [ 1.951703] ehci-pci 0000:00:1d.7: EHCI Host Controller1821machine # [ 1.952444] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 21822machine # [ 1.954143] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001823machine # [ 1.802877] systemd[1]: Found device /dev/disk/by-label/nixos.1824machine # [ 1.962708] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001825machine # [ 1.964665] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181826machine # [ 1.965804] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11827machine # [ 1.810700] systemd[1]: Reached target Initrd Root Device.1828machine # [ 1.813419] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1829machine # [ 1.973600] usb usb2: Product: EHCI Host Controller1830machine # [ 1.974252] usb usb2: Manufacturer: Linux 6.18.56 ehci_hcd1831machine # [ 1.976850] usb usb2: SerialNumber: 0000:00:1d.71832machine # [ 1.978550] hub 2-0:1.0: USB hub found1833machine # [ 1.980001] hub 2-0:1.0: 6 ports detected1834machine # [ 1.839408] systemd-fsck[117]: nixos: clean, 226/65536 files, 15454/262144 blocks1835machine # [ 2.001535] hub 1-0:1.0: USB hub found1836machine # [ 1.846977] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1837machine # [ 2.006603] hub 1-0:1.0: 2 ports detected1838machine # [ 2.009007] uhci_hcd 0000:00:1d.1: UHCI Host Controller1839machine # [ 2.009818] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31840machine # [ 2.016746] uhci_hcd 0000:00:1d.1: detected 2 ports1841machine # [ 2.018353] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01842machine # [ 2.027004] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01843machine # [ 2.032492] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181844machine # [ 2.036806] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11845machine # [ 2.040771] usb usb3: Product: UHCI Host Controller1846machine # [ 2.041479] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd1847machine # [ 2.042204] usb usb3: SerialNumber: 0000:00:1d.11848machine # [ 2.048544] hub 3-0:1.0: USB hub found1849machine # [ 2.051344] hub 3-0:1.0: 2 ports detected1850machine # [ 2.053801] uhci_hcd 0000:00:1d.2: UHCI Host Controller1851machine # [ 2.056353] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41852machine # [ 2.060391] uhci_hcd 0000:00:1d.2: detected 2 ports1853machine # [ 2.061133] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01854machine # [ 2.062644] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181855machine # [ 2.065346] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11856machine # [ 2.068337] usb usb4: Product: UHCI Host Controller1857machine # [ 2.069033] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd1858machine # [ 2.071369] usb usb4: SerialNumber: 0000:00:1d.21859machine # [ 2.072252] hub 4-0:1.0: USB hub found1860machine # [ 2.073417] hub 4-0:1.0: 2 ports detected1861machine # [ 2.101866] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1862machine # [ 2.103072] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1863machine # [ 2.103955] ahci 0000:00:1f.2: flags: 64bit ncq only1864machine # [ 2.107649] scsi host0: ahci1865machine # [ 2.109150] scsi host1: ahci1866machine # [ 2.110647] scsi host2: ahci1867machine # [ 2.112043] scsi host3: ahci1868machine # [ 2.113199] scsi host4: ahci1869machine # [ 2.114836] scsi host5: ahci1870machine # [ 2.116014] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11871machine # [ 2.117229] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11872machine # [ 2.118534] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11873machine # [ 2.119780] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11874machine # [ 2.120923] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11875machine # [ 2.122095] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11876machine # [ 2.221432] usb 2-1: new high-speed USB device number 2 using ehci-pci1877machine # [ 2.166651] systemd[1]: Mounting /sysroot...1878machine # [ 2.361431] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001879machine # [ 2.364502] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101880machine # [ 2.370003] usb 2-1: Product: QEMU USB Tablet1881machine # [ 2.374371] usb 2-1: Manufacturer: QEMU1882machine # [ 2.376265] usb 2-1: SerialNumber: 28754-0000:00:1d.7-11883machine # [ 2.397947] hid: raw HID events driver (C) Jiri Kosina1884machine # [ 2.434905] ata2: SATA link down (SStatus 0 SControl 300)1885machine # [ 2.437168] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1886machine # [ 2.439810] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001887machine # [ 2.441916] ata3.00: applying bridge limits1888machine # [ 2.443871] ata5: SATA link down (SStatus 0 SControl 300)1889machine # [ 2.446140] ata1: SATA link down (SStatus 0 SControl 300)1890machine # [ 2.448468] ata4: SATA link down (SStatus 0 SControl 300)1891machine # [ 2.450308] ata6: SATA link down (SStatus 0 SControl 300)1892machine # [ 2.452167] ata3.00: configured for UDMA/1001893machine # [ 2.454052] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51894machine # [ 2.517561] usbcore: registered new interface driver usbhid1895machine # [ 2.518314] usbhid: USB HID core driver1896machine # [ 2.530548] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input21897machine # [ 2.535471] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01898machine # [ 2.539452] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1899machine # [ 2.554456] cdrom: Uniform CD-ROM driver Revision: 3.201900machine # [ 2.594999] EXT4-fs (vda): mounted filesystem 31782943-37d5-4f7e-9468-4636f7ea9e3d r/w with ordered data mode. Quota mode: none.1901machine # [ 2.442584] systemd[1]: Mounted /sysroot.1902machine # [ 2.443857] systemd[1]: Reached target Initrd Root File System.1903machine # [ 2.447071] systemd[1]: Mounting /sysroot/nix/.ro-store...1904machine # [ 2.449827] systemd[1]: Mounting /sysroot/nix/.rw-store...1905machine # [ 2.455079] systemd[1]: Mounting /sysroot/run...1906machine # [ 2.460819] systemd[1]: Mounting /sysroot/tmp/shared...1907machine # [ 2.467130] systemd[1]: Mounting /sysroot/tmp/xchg...1908machine # [ 2.474253] systemd[1]: Starting Mountpoints Configured in the Real Root...1909machine # [ 2.513042] systemd-sysroot-fstab-check[159]: /sysroot should be mounted in the initrd, will request daemon-reload.1910machine # [ 2.515226] systemd[1]: Mounted /sysroot/nix/.ro-store.1911machine # [ 2.520840] systemd[1]: Mounted /sysroot/nix/.rw-store.1912machine # [ 2.522348] systemd[1]: Mounted /sysroot/run.1913machine # [ 2.523182] systemd[1]: Mounted /sysroot/tmp/shared.1914machine # [ 2.524082] systemd[1]: Mounted /sysroot/tmp/xchg.1915machine # [ 2.528612] systemd[1]: Reload requested from client PID 159 ('systemd-sysroot') (unit initrd-parse-etc.service)...1916machine # [ 2.530114] systemd[1]: Reloading...1917machine # [ 2.594898] systemd[1]: Reloading finished in 64 ms.1918machine # [ 2.603783] systemd-sysroot-fstab-check[159]: Requesting initrd-fs.target/start/replace...1919machine # [ 2.606247] systemd-sysroot-fstab-check[159]: Requesting swap.target/start/replace...1920machine # [ 2.609275] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1921machine # [ 2.610675] systemd[1]: Finished Mountpoints Configured in the Real Root.1922machine # [ 2.611691] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1923machine # [ 2.619626] systemd[1]: Starting rw-sysroot-nix-store.service...1924machine # [ 2.636287] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1925machine # [ 2.638055] systemd[1]: Finished rw-sysroot-nix-store.service.1926machine # [ 3.166086] systemd[1]: Mounting /sysroot/nix/store...1927machine # [ 3.187206] systemd[1]: Mounted /sysroot/nix/store.1928machine # [ 3.188464] systemd[1]: Reached target Initrd File Systems.1929machine # [ 3.190279] systemd[1]: Starting Find NixOS closure...1930machine # [ 3.192774] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1931machine # [ 3.212134] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1932machine # [ 3.213154] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1933machine # [ 3.219987] systemd[1]: Finished Find NixOS closure.1934machine # [ 3.221966] systemd[1]: Reached target Initrd Default Target.1935machine # [ 3.222987] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1936machine # [ 3.232860] systemd[1]: initrd-cleanup.service: Deactivated successfully.1937machine # [ 3.234218] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1938machine # [ 3.238617] systemd[1]: Startup finished in 876ms (kernel) + 0 (initrd) + 2.361s (userspace) = 3.238s.1939machine: (finished: waiting for unit initrd.target, in 4.34 seconds)1940machine: waiting for the VM to finish booting1941machine # [ 3.672432] systemd[1]: Stopped target Initrd Default Target.1942machine # [ 3.676328] systemd[1]: Stopped target Basic System.1943machine # [ 3.677790] systemd[1]: Stopped target Initrd Root Device.1944machine # [ 3.679337] systemd[1]: Stopped target Path Units.1945machine # [ 3.680700] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1946machine # [ 3.682677] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1947machine # [ 3.684831] systemd[1]: Stopped target Slice Units.1948machine # [ 3.686511] systemd[1]: Stopped target Socket Units.1949machine # [ 3.689130] systemd[1]: Stopped target System Initialization.1950machine # [ 3.690847] systemd[1]: Stopped target Swaps.1951machine # [ 3.692441] systemd[1]: Stopped target Timer Units.1952machine # [ 3.694211] systemd[1]: dbus.socket: Deactivated successfully.1953machine # [ 3.695715] systemd[1]: Closed D-Bus System Message Bus Socket.1954machine # [ 3.697272] systemd[1]: Stopping backdoor.service...1955machine # [ 3.700085] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1956machine # [ 3.701061] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1957machine # [ 3.702068] systemd[1]: Stopped Find NixOS closure.1958machine # [ 3.704849] systemd[1]: Starting rw-sysroot-nix-store.service...1959machine # [ 3.705768] systemd[1]: systemd-sysctl.service: Deactivated successfully.1960machine # [ 3.706748] systemd[1]: Stopped Apply Kernel Variables.1961machine # [ 3.707549] systemd[1]: systemd-modules-load.service: Deactivated successfully.1962machine # [ 3.708525] systemd[1]: Stopped Load Kernel Modules.1963machine # [ 3.709283] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1964machine # [ 3.712131] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1965machine # [ 3.713236] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1966machine # [ 3.714575] systemd[1]: Stopped Create System Files and Directories.1967machine # [ 3.715468] systemd[1]: Stopped target Local File Systems.1968machine # [ 3.716547] systemd[1]: Stopped target Preparation for Local File Systems.1969machine # [ 3.718424] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1970machine # [ 3.719483] systemd[1]: Stopped Coldplug All udev Devices.1971machine # [ 3.721139] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1972machine # [ 3.722191] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1973machine # [ 3.723472] systemd[1]: Stopped Virtual Console Setup.1974machine # [ 3.726197] systemd[1]: backdoor.service: Deactivated successfully.1975machine # [ 3.728700] systemd[1]: Stopped backdoor.service.1976machine # [ 3.732524] systemd[1]: systemd-udevd.service: Deactivated successfully.1977machine # [ 3.736074] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1978machine # [ 3.747094] systemd[1]: Starting Cleanup udev Database...1979machine # [ 3.747907] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1980machine # [ 3.749066] systemd[1]: Stopped Create Static Device Nodes in /dev.1981machine # [ 3.749883] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1982machine # [ 3.751021] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1983machine # [ 3.751952] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1984machine # [ 3.754147] systemd[1]: Stopped Create List of Static Device Nodes.1985machine # [ 3.756139] systemd[1]: initrd-cleanup.service: Deactivated successfully.1986machine # [ 3.758164] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1987machine # [ 3.759461] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1988machine # [ 3.760860] systemd[1]: Finished rw-sysroot-nix-store.service.1989machine # [ 3.775361] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1990machine # [ 3.776911] systemd[1]: Finished Cleanup udev Database.1991machine # [ 3.778126] systemd[1]: Reached target Switch Root.1992machine # [ 3.779699] systemd[1]: Starting NixOS Activation...1993machine # [ 3.852169] initrd-nixos-activation-start[224]: booting system configuration /nix/store/l4ximippgrsph1yhy35jc4hj818yn6ya-nixos-system-machine-test1994machine # [ 3.881765] initrd-nixos-activation-start[224]: running activation script...1995machine # [ 4.060844] initrd-nixos-activation-start[247]: setting up /etc...1996machine # [ 4.212857] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1997machine # [ 4.214523] systemd[1]: Finished NixOS Activation.1998machine # [ 4.216605] systemd[1]: Starting Switch Root...1999machine # [ 4.229593] systemd[1]: Switching root.2000machine # [ 4.516743] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).2001machine # [ 4.593483] NET: Registered PF_VSOCK protocol family2002machine # [ 4.620709] systemd[1]: systemd 263-7f4df15 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE +EXPERIMENTAL-ENVVAR)2003machine # [ 4.624722] systemd[1]: Detected virtualization kvm.2004machine # [ 4.625428] systemd[1]: Detected architecture x86-64.2005machine # [ 4.708438] systemd[1]: bpf-restrict-fs: LSM BPF program attached2006machine # [ 5.006163] systemd[1]: initrd-switch-root.service: Deactivated successfully.2007machine # [ 5.007534] systemd[1]: Stopped initrd-switch-root.service.2008machine # [ 5.009721] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.2009machine # [ 5.011780] systemd[1]: Created slice Slice /system/getty.2010machine # [ 5.013070] systemd[1]: Created slice User and Session Slice.2011machine # [ 5.014016] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.2012machine # [ 5.015212] systemd[1]: Started Forward Password Requests to Wall Directory Watch.2013machine # [ 5.016276] systemd[1]: Expecting device /dev/hvc0...2014machine # [ 5.017058] systemd[1]: Expecting device /dev/ttyS0...2015machine # [ 5.017829] systemd[1]: Reached target Local Encrypted Volumes.2016machine # [ 5.018724] systemd[1]: Stopped target initrd-fs.target.2017machine # [ 5.019503] systemd[1]: Stopped target initrd-root-fs.target.2018machine # [ 5.020263] systemd[1]: Stopped target initrd-switch-root.target.2019machine # [ 5.021110] systemd[1]: Reached target Virtual Machines and Containers.2020machine # [ 5.022016] systemd[1]: Reached target Path Units.2021machine # [ 5.022732] systemd[1]: Reached target Remote File Systems.2022machine # [ 5.023504] systemd[1]: Reached target Slice Units.2023machine # [ 5.024186] systemd[1]: Reached target Swaps.2024machine # [ 5.025773] systemd[1]: Listening on Query the User Interactively for a Password.2025machine # [ 5.039342] systemd[1]: Listening on Process Core Dump Socket.2026machine # [ 5.040969] systemd[1]: Listening on Credential Encryption/Decryption.2027machine # [ 5.042677] systemd[1]: Listening on Factory Reset Management.2028machine # [ 5.043639] systemd[1]: Listening on Hostname Service Socket.2029machine # [ 5.046180] systemd[1]: Starting Journal Log Access Socket...2030machine # [ 5.047539] systemd[1]: Listening on Journal Audit Socket.2031machine # [ 5.049991] systemd[1]: Listening on Console Output Muting Service Socket.2032machine # [ 5.051112] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.2033machine # [ 5.052213] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2034machine # [ 5.053498] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2035machine # [ 5.057184] systemd[1]: Listening on Disk Repartitioning Service Socket.2036machine # [ 5.058213] systemd[1]: Listening on udev Varlink Socket.2037machine # [ 5.060489] systemd[1]: Mounting Huge Pages File System...2038machine # [ 5.062767] systemd[1]: Mounting POSIX Message Queue File System...2039machine # [ 5.069410] systemd[1]: Mounting Kernel Debug File System...2040machine # [ 5.079288] systemd[1]: Mounting Kernel Trace File System...2041machine # [ 5.086535] systemd[1]: Starting Create List of Static Device Nodes...2042machine # [ 5.098585] systemd[1]: Starting Load Kernel Module configfs...2043machine # [ 5.107609] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm2044machine # [ 5.117507] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2045machine # [ 5.125033] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2046machine # [ 5.139126] systemd[1]: Mounting FUSE Control File System...2047machine # [ 5.144558] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672048machine # [ 5.159171] systemd[1]: Starting Journal Service...2049machine # [ 5.165751] systemd[1]: Starting Load Kernel Modules...2050machine # [ 5.178161] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...2051machine # [ 5.181678] systemd[1]: Starting Remount Root and Kernel File Systems...2052machine # [ 5.186537] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2053machine # [ 5.202919] systemd[1]: Starting Coldplug All udev Devices...2054machine # [ 5.218243] systemd-journald[318]: Collecting audit messages is enabled.2055machine # [ 5.225385] systemd[1]: Listening on Journal Log Access Socket.2056machine # [ 5.228512] systemd[1]: Mounted Huge Pages File System.2057machine # [ 5.230562] systemd[1]: Mounted POSIX Message Queue File System.2058machine # [ 5.232761] systemd[1]: Mounted Kernel Debug File System.2059machine # [ 5.079841] systemd[1]: Queued start job for default target Multi-User System.2060machine # [ 5.239460] systemd[1]: Started Journal Service.2061machine # [ 5.084945] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.2062machine # [ 5.088325] systemd[1]: Unnecessary job was removed for /sys/devices/pnp0/00:03/00:03:0/00:03:0.0/tty/ttyS0.2063machine # [ 5.093656] systemd[1]: systemd-journald.service: Deactivated successfully.2064machine # [ 5.094866] systemd[1]: Mounted Kernel Trace File System.2065machine # [ 5.096948] systemd[1]: Finished Create List of Static Device Nodes.2066machine # [ 5.100426] systemd[1]: modprobe@configfs.service: Deactivated successfully.2067machine # [ 5.103280] systemd[1]: Finished Load Kernel Module configfs.2068machine # [ 5.107644] systemd[1]: Mounted FUSE Control File System.2069machine # [ 5.270703] loop: module loaded2070machine # [ 5.113631] systemd-modules-load[319]: Inserted module 'loop'2071machine # [ 5.128115] systemd[1]: Mounting Kernel Configuration File System...2072machine # [ 5.134072] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2073machine # [ 5.137106] systemd[1]: Finished Load Kernel Modules.2074machine # [ 5.144107] systemd[1]: Starting Firewall...2075machine # [ 5.309393] EXT4-fs (vda): re-mounted 31782943-37d5-4f7e-9468-4636f7ea9e3d.2076machine # [ 5.164076] systemd[1]: Starting Apply Kernel Variables...2077machine # [ 5.184586] systemd[1]: Finished Remount Root and Kernel File Systems.2078machine # [ 5.193154] systemd[1]: Listening on Disk Image Download Service Socket.2079machine # [ 5.202315] systemd-oomd[321]: No swap; memory pressure usage will be degraded2080machine # [ 5.206156] systemd[1]: Starting Grow Root File System...2081machine # [ 5.223888] systemd[1]: Starting Flush Journal to Persistent Storage...2082machine # [ 5.224864] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2083machine # [ 5.235108] systemd[1]: Starting Load/Save OS Random Seed...2084machine # [ 5.235883] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2085machine # [ 5.237206] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2086machine # [ 5.455238] EXT4-fs (vda): resizing filesystem from 262144 to 524288 blocks2087machine # [ 5.474702] systemd-journald[318]: Received client request to flush runtime journal.2088machine # [ 5.489361] EXT4-fs (vda): resized filesystem to 5242882089machine # [ 5.428611] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2090machine # [ 5.432419] systemd[1]: Mounted Kernel Configuration File System.2091machine # [ 5.433410] systemd[1]: Finished Apply Kernel Variables.2092machine # [ 5.436149] systemd[1]: Starting Create Static Device Nodes in /dev...2093machine # [ 5.437143] systemd-growfs[339]: Successfully resized "/" to 2G bytes.2094machine # [ 5.437985] systemd[1]: Finished Grow Root File System.2095machine # [ 5.439873] systemd[1]: Finished Load/Save OS Random Seed.2096machine # [ 5.440706] systemd[1]: First Boot Complete skipped, unmet condition check ConditionFirstBoot=yes2097machine # [ 5.447383] systemd[1]: Finished Flush Journal to Persistent Storage.2098machine # [ 5.505121] systemd[1]: Finished Create Static Device Nodes in /dev.2099machine # [ 5.506064] systemd[1]: Reached target Preparation for Local File Systems.2100machine # [ 5.509711] systemd[1]: Starting Rule-based Manager for Device Events and Files...2101machine # [ 5.529545] systemd[1]: Finished Coldplug All udev Devices.2102machine # [ 5.563110] systemd-udevd[358]: Using default interface naming scheme 'v261'.2103machine # [ 5.605147] systemd[1]: Started Rule-based Manager for Device Events and Files.2104machine # [ 5.669700] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2105machine # [ 5.754862] systemd[1]: Found device /dev/hvc0.2106machine # [ 5.772789] systemd[1]: Found device /dev/ttyS0.2107machine # [ 5.785405] (udev-worker)[380]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.2108machine # [ 5.787820] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line.2109machine # [ 5.852416] systemd[1]: Mounting /run/wrappers...2110machine # [ 5.897394] systemd[1]: Mounted /run/wrappers.2111machine # [ 5.898155] systemd[1]: Reached target Local File Systems.2112machine # [ 5.901334] systemd[1]: Listening on Boot Loader Control Service Socket.2113machine # [ 5.905685] systemd[1]: Starting Create SUID/SGID Wrappers...2114machine # [ 5.907976] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2115machine # [ 5.909191] systemd[1]: Save Transient machine-id to Disk skipped, unmet condition check ConditionPathIsMountPoint=/etc/machine-id2116machine # [ 5.913084] systemd[1]: Starting Create System Files and Directories...2117machine # [ 5.914914] systemd[1]: Condition check resulted in Virtio network device being skipped.2118machine # [ 5.916127] systemd[1]: Condition check resulted in Virtio network device being skipped.2119machine # [ 5.917185] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2120machine # [ 5.919487] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2121machine # [ 5.920717] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672122machine # [ 5.924134] systemd[1]: Save Transient machine-id to Disk skipped, unmet condition check ConditionPathIsMountPoint=/etc/machine-id2123machine # [ 5.925611] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2124machine # [ 5.927806] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2125machine # [ 5.929156] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2126machine # [ 6.033486] systemd[1]: Finished Create System Files and Directories.2127machine # [ 6.036888] systemd[1]: Rebuild Journal Catalog skipped, unmet condition check ConditionNeedsUpdate=/var2128machine # [ 6.040760] systemd[1]: Update is Completed skipped, no trigger condition checks were met.2129machine # [ 6.044918] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2130machine # [ 6.111205] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2131machine # [ 6.297689] mousedev: PS/2 mouse device common for all mice2132machine # [ 6.303683] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input32133machine # [ 6.342399] ACPI: button: Power Button [PWRF]2134machine # [ 6.352674] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console2135machine # [ 6.386461] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2136machine # [ 6.389833] systemd[1]: Finished Create SUID/SGID Wrappers.2137machine # [ 6.391895] systemd[1]: Reached target System Initialization.2138machine # [ 6.395066] systemd[1]: Started Discard unused filesystem blocks once a week.2139machine # [ 6.396424] systemd[1]: Started Daily Cleanup of Temporary Directories.2140machine # [ 6.397361] systemd[1]: Reached target Timer Units.2141machine # [ 6.399496] systemd[1]: Listening on D-Bus System Message Bus Socket.2142machine # [ 6.401160] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2143machine # [ 6.402418] systemd[1]: Reached target Socket Units.2144machine # [ 6.403130] systemd[1]: Reached target Basic System.2145machine # [ 6.406404] systemd[1]: Started backdoor.service.2146machine # [ 6.408167] systemd[1]: Import lastlog data into lastlog2 database skipped, unmet condition check ConditionPathExists=!/var/lib/lastlog/lastlog2.db2147machine # [ 6.415095] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2148machine # [ 6.422126] systemd[1]: Starting Post-Boot Actions...2149machine # [ 6.435268] systemd[1]: Started Reset console on configuration changes.2150machine # [ 6.452868] systemd[1]: Starting resolvconf update...2151machine # [ 6.454191] rtc_cmos PNP0B00:00: RTC can wake from S42152machine # [ 6.463419] systemd[1]: Finished Firewall.2153machine # [ 6.472786] rtc_cmos PNP0B00:00: registered as rtc02154machine # [ 6.472873] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T03:04:49 UTC (1792379089)2155machine # [ 6.472959] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs2156machine # [ 6.493991] systemd[1]: Starting D-Bus System Message Bus...2157machine # [ 6.531518] systemd[1]: Finished Post-Boot Actions.2158machine # connecting to host...2159machine # [ 6.558559] systemd[1]: Started Name Service Cache Daemon (nsncd).2160machine # [ 6.563684] nsncd[529]: Oct 19 03:04:49.743 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2161machine # [ 6.566136] systemd[1]: Reached target Host and Network Name Lookups.2162machine # [ 6.567802] systemd[1]: Reached target User and Group Name Lookups.2163machine # [ 6.476862] parport_pc 00:02: reported by Plug and Play ACPI2164machine # [ 6.476963] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]2165machine # [ 6.582776] systemd[1]: Starting User Login Management...2166machine: Guest shell says: b'Spawning backdoor root shell...\n'2167machine: connected to guest root shell2168machine: (connecting took 2.94 seconds)2169machine: (finished: waiting for the VM to finish booting, in 2.94 seconds)2170machine: must succeed: df --output=avail / | sed 1d2171machine # [ 6.503577] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input42172machine # [ 6.522081] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized2173machine # [ 6.614117] Console: switching to colour dummy device 80x252174machine # [ 6.765622] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt2175machine # [ 6.765785] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD2176machine # [ 6.688927] dbus-broker-launch[539]: Looking up NSS user entry for 'systemd-timesync'...2177machine # [ 6.693506] dbus-broker-launch[539]: NSS returned no entry for 'systemd-timesync'2178machine # [ 6.694530] dbus-broker-launch[539]: Invalid user-name in /nix/store/48vmh84azhncwmssn2a3dnqgnjgi47xz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2179machine: (finished: must succeed: df --output=avail / | sed 1d, in 0.12 seconds)2180(finished: subtest: growfs works, in 8.54 seconds)2181subtest: no warnings from systemd about write permissions2182machine: must fail: journalctl -b 0 | grep 'is marked world-writable, which is a security risk as it is executed with privileges'2183machine # [ 6.890923] [drm] Found bochs VGA, ID 0xb0c5.2184machine # [ 6.890925] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.2185machine # [ 6.736980] systemd[1]: Started D-Bus System Message Bus.2186machine # [ 6.748824] systemd-logind[555]: New seat seat0.2187machine # [ 6.759336] systemd[1]: Started User Login Management.2188machine # [ 6.763267] systemd[1]: Stopped target Host and Network Name Lookups.2189machine # [ 6.764209] systemd[1]: Stopping Host and Network Name Lookups...2190machine # [ 6.765023] systemd[1]: Stopped target User and Group Name Lookups.2191machine # [ 6.765852] systemd[1]: Stopping User and Group Name Lookups...2192machine # [ 6.927437] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic2193machine # [ 6.928077] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 02194machine # [ 6.775169] systemd[1]: Starting linger-users.service...2195machine # [ 6.775914] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...2196machine # [ 6.780184] dbus-broker-launch[539]: Ready2197machine # [ 6.787619] systemd[1]: nscd.service: Deactivated successfully.2198machine # [ 6.949895] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input62199machine # [ 6.794115] systemd[1]: Stopped Name Service Cache Daemon (nsncd).2200machine # [ 6.957104] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input52201machine # [ 6.837352] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2202machine: (finished: must fail: journalctl -b 0 | grep 'is marked world-writable, which is a security risk as it is executed with privileges', in 0.15 seconds)2203(finished: subtest: no warnings from systemd about write permissions, in 0.15 seconds)2204(finished: run the VM test script, in 25.44 seconds)2205machine # [ 6.876621] systemd[1]: Starting Virtual Console Setup...2206machine # [ 6.880263] systemd[1]: linger-users.service: Deactivated successfully.2207machine # [ 6.885945] systemd[1]: Finished linger-users.service.2208machine # [ 6.907822] systemd-logind[555]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)2209machine # [ 6.913464] systemd[1]: Finished resolvconf update.2210machine # [ 6.920911] systemd[1]: Reached target Preparation for Network.2211machine # [ 6.928621] nsncd[622]: Oct 19 03:04:50.113 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2212machine # [ 6.932054] systemd[1]: Starting DHCP Client...2213machine # [ 6.934167] systemd[1]: Starting Address configuration of eth1...2214machine # [ 6.938323] systemd[1]: Starting Extra networking commands....2215machine # [ 6.942879] systemd[1]: Started Name Service Cache Daemon (nsncd).2216machine # [ 6.952169] systemd[1]: Reached target Host and Network Name Lookups.2217machine # [ 6.959154] systemd[1]: Reached target User and Group Name Lookups.2218machine # [ 7.258617] Console: switching to colour frame buffer device 160x502219machine # [ 7.259875] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device2220machine # [ 6.976225] systemd-logind[555]: Watching system buttons on /dev/input/event2 (Power Button)2221machine # [ 7.104242] network-addresses-eth1-start[646]: adding address 192.168.1.1/24... done2222machine # [ 7.113632] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2223machine # [ 7.120566] systemd[1]: Stopped Virtual Console Setup.2224machine # [ 7.130458] network-addresses-eth1-start[646]: adding address 2001:db8:1::1/64... done2225test script finished in 25.72s2226cleanup2227kill QemuMachine (pid 95)2228machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2229machine # [2026-10-19T03:04:50Z INFO virtiofsd] Client disconnected, shutting down2230machine # [2026-10-19T03:04:50Z INFO virtiofsd] Client disconnected, shutting down2231machine # [2026-10-19T03:04:50Z INFO virtiofsd] Client disconnected, shutting down2232(finished: cleanup, in 0.17 seconds)